"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestCopyDeleteBefore|TestError|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSizeOnly|TestSyncSuffix)$'" - Starting (try 2/5) 2025/08/09 04:33:58 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yobagaf8babo" 2025/08/09 04:33:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/09 04:33:59 DEBUG : Starting OpenDrive session with ID: c57dc17958c3205efd4939e3ba1ba8a4cb71551d8e003e77416712a73eac987e 2025/08/09 04:33:59 DEBUG : Creating backend with remote "/tmp/rclone3690372506" === RUN TestSyncSizeOnly run.go:180: Remote "OpenDrive root 'rclone-test-yobagaf8babo'", Local "Local file system at /tmp/rclone3690372506", Modify Window "1s" 2025/08/09 04:33:59 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/08/09 04:33:59 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for checks to finish 2025/08/09 04:33:59 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for transfers to finish 2025/08/09 04:34:00 DEBUG : sizeonly: Uploading chunk 0, size=6, remain=0 2025/08/09 04:34:01 DEBUG : sizeonly: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/08/09 04:34:01 INFO : sizeonly: Copied (new) 2025/08/09 04:34:01 DEBUG : Waiting for deletions to finish 2025/08/09 04:34:02 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for checks to finish 2025/08/09 04:34:02 DEBUG : sizeonly: Sizes identical 2025/08/09 04:34:02 DEBUG : sizeonly: Unchanged skipping 2025/08/09 04:34:02 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for transfers to finish 2025/08/09 04:34:02 DEBUG : Waiting for deletions to finish 2025/08/09 04:34:02 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (3.56s) === RUN TestCopyDeleteBefore run.go:180: Remote "OpenDrive root 'rclone-test-yobagaf8babo'", Local "Local file system at /tmp/rclone3690372506", Modify Window "1s" 2025/08/09 04:34:03 DEBUG : potato: Uploading chunk 0, size=21, remain=0 2025/08/09 04:34:05 DEBUG : potato2: Need to transfer - File not found at Destination 2025/08/09 04:34:05 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for checks to finish 2025/08/09 04:34:05 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for transfers to finish 2025/08/09 04:34:06 DEBUG : potato2: Uploading chunk 0, size=19, remain=0 2025/08/09 04:34:07 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK 2025/08/09 04:34:07 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (5.30s) === RUN TestSyncSuffix run.go:180: Remote "OpenDrive root 'rclone-test-yobagaf8babo'", Local "Local file system at /tmp/rclone3690372506", Modify Window "1s" 2025/08/09 04:34:09 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/08/09 04:34:11 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/08/09 04:34:12 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/08/09 04:34:14 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-yobagaf8babo/dst" 2025/08/09 04:34:14 DEBUG : Starting OpenDrive session with ID: 1491af81db7c1f11b063164bf1d500edae6c3238b619af3587fccc1553cdcc06 2025/08/09 04:34:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/09 04:34:15 DEBUG : one: same parent dir (ODFfOTM3OTQ0M19TR3VjWg) - using file/rename instead of move_copy for one.bak 2025/08/09 04:34:15 INFO : one: Moved (server-side) to: one.bak 2025/08/09 04:34:16 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/08/09 04:34:17 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/08/09 04:34:17 INFO : one: Copied (new) 2025/08/09 04:34:17 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/09 04:34:17 DEBUG : two: Unchanged skipping 2025/08/09 04:34:18 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/08/09 04:34:18 DEBUG : three.txt: same parent dir (ODFfOTM3OTQ0M19TR3VjWg) - using file/rename instead of move_copy for three.txt.bak 2025/08/09 04:34:18 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/08/09 04:34:19 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/08/09 04:34:20 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/08/09 04:34:20 INFO : three.txt: Copied (new) 2025/08/09 04:34:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/09 04:34:21 INFO : one.bak: Deleted 2025/08/09 04:34:21 DEBUG : one: same parent dir (ODFfOTM3OTQ0M19TR3VjWg) - using file/rename instead of move_copy for one.bak 2025/08/09 04:34:21 INFO : one: Moved (server-side) to: one.bak 2025/08/09 04:34:22 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/08/09 04:34:23 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/08/09 04:34:23 INFO : one: Copied (new) 2025/08/09 04:34:23 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/09 04:34:23 DEBUG : two: Unchanged skipping 2025/08/09 04:34:23 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/08/09 04:34:24 INFO : three.txt.bak: Deleted 2025/08/09 04:34:24 DEBUG : three.txt: same parent dir (ODFfOTM3OTQ0M19TR3VjWg) - using file/rename instead of move_copy for three.txt.bak 2025/08/09 04:34:24 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/08/09 04:34:25 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/08/09 04:34:26 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/08/09 04:34:26 INFO : three.txt: Copied (new) run.go:125: removing dir "dst" failed - try 1/3: folder not empty --- PASS: TestSyncSuffix (21.56s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-yobagaf8babo'", Local "Local file system at /tmp/rclone3690372506", Modify Window "1s" 2025/08/09 04:34:29 DEBUG : empty_dir: Making directory with metadata 2025/08/09 04:34:29 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/09 04:34:29 DEBUG : empty_on_remote: Making directory with metadata 2025/08/09 04:34:29 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/08/09 04:34:29 INFO : empty_on_remote: Making directory 2025/08/09 04:34:30 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/08/09 04:34:34 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/08/09 04:34:34 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/08/09 04:34:34 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/08/09 04:34:34 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/08/09 04:34:34 INFO : empty_dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (8.26s) === RUN TestBase64 run.go:180: Remote "OpenDrive root 'rclone-test-yobagaf8babo'", Local "Local file system at /tmp/rclone3690372506", Modify Window "1s" 2025/08/09 04:34:38 DEBUG : toe: transformed to: dG9l 2025/08/09 04:34:38 DEBUG : toe: transformed to: dG9l 2025/08/09 04:34:38 INFO : dG9l: Making directory 2025/08/09 04:34:38 DEBUG : toe: transformed to: dG9l 2025/08/09 04:34:38 DEBUG : toe: transformed to: dG9l 2025/08/09 04:34:38 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/08/09 04:34:38 INFO : dG9l/dG9l: Making directory 2025/08/09 04:34:38 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/08/09 04:34:38 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/08/09 04:34:38 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/08/09 04:34:38 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/08/09 04:34:38 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/08/09 04:34:38 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for checks to finish 2025/08/09 04:34:38 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for transfers to finish 2025/08/09 04:34:39 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0 2025/08/09 04:34:40 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/08/09 04:34:40 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/08/09 04:34:40 DEBUG : Waiting for deletions to finish 2025/08/09 04:34:41 DEBUG : dG9l: transformed to: toe 2025/08/09 04:34:41 DEBUG : dG9l: transformed to: toe 2025/08/09 04:34:41 DEBUG : toe: Directory modification time the same (differ by 35.097977ms, within tolerance 1s) 2025/08/09 04:34:41 DEBUG : Added delayed dir = "toe", newDst=toe 2025/08/09 04:34:41 DEBUG : dG9l: transformed to: toe 2025/08/09 04:34:41 DEBUG : dG9l/dG9l: transformed to: toe/toe 2025/08/09 04:34:41 INFO : toe/toe: Set directory modification time (using SetModTime) 2025/08/09 04:34:41 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe 2025/08/09 04:34:41 DEBUG : dG9lLnR4dA==: transformed to: toe.txt 2025/08/09 04:34:41 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s) 2025/08/09 04:34:41 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt 2025/08/09 04:34:41 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping 2025/08/09 04:34:41 DEBUG : Local file system at /tmp/rclone3690372506: Waiting for checks to finish 2025/08/09 04:34:41 DEBUG : Local file system at /tmp/rclone3690372506: Waiting for transfers to finish 2025/08/09 04:34:41 DEBUG : Waiting for deletions to finish --- PASS: TestBase64 (5.77s) === RUN TestError run.go:180: Remote "OpenDrive root 'rclone-test-yobagaf8babo'", Local "Local file system at /tmp/rclone3690372506", Modify Window "1s" 2025/08/09 04:34:43 ERROR : Failed to transform: transform cannot add path separators: ta/ctoe 2025/08/09 04:34:43 DEBUG : toe: transformed to: 2025/08/09 04:34:43 ERROR : Failed to transform: transform cannot add path separators: ta/ctoe 2025/08/09 04:34:43 DEBUG : toe: transformed to: 2025/08/09 04:34:43 INFO : OpenDrive root 'rclone-test-yobagaf8babo': Making directory 2025/08/09 04:34:43 ERROR : Failed to transform: transform cannot add path separators: ta/ctoe 2025/08/09 04:34:43 DEBUG : toe: transformed to: 2025/08/09 04:34:43 ERROR : Failed to transform: transform cannot add path separators: ta/ctoe 2025/08/09 04:34:43 DEBUG : toe: transformed to: 2025/08/09 04:34:43 ERROR : Failed to transform: transform cannot add path separators: ta/ctoe 2025/08/09 04:34:43 DEBUG : toe/toe: transformed to: 2025/08/09 04:34:43 ERROR : toe/toe: number of path segments must match: toe/toe (1), (0) 2025/08/09 04:34:43 INFO : toe/toe: Making directory 2025/08/09 04:34:44 ERROR : Failed to transform: transform cannot add path separators: ta/ctoe 2025/08/09 04:34:44 DEBUG : toe/toe: transformed to: 2025/08/09 04:34:44 ERROR : toe/toe: number of path segments must match: toe/toe (1), (0) 2025/08/09 04:34:44 ERROR : Failed to transform: transform cannot add path separators: ta/ctoe 2025/08/09 04:34:44 DEBUG : toe: transformed to: 2025/08/09 04:34:44 DEBUG : toe/toe/toe: Need to transfer - File not found at Destination 2025/08/09 04:34:44 ERROR : Failed to transform: transform cannot add path separators: ta/ctoe 2025/08/09 04:34:44 DEBUG : toe/toe/toe: transformed to: 2025/08/09 04:34:44 ERROR : toe/toe/toe: number of path segments must match: toe/toe/toe (2), (0) 2025/08/09 04:34:44 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for checks to finish 2025/08/09 04:34:44 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Waiting for transfers to finish 2025/08/09 04:34:45 DEBUG : toe/toe/toe: Uploading chunk 0, size=11, remain=0 2025/08/09 04:34:46 DEBUG : toe/toe/toe: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/08/09 04:34:46 INFO : toe/toe/toe: Copied (new) 2025/08/09 04:34:46 ERROR : OpenDrive root 'rclone-test-yobagaf8babo': not deleting files as there were IO errors 2025/08/09 04:34:46 ERROR : OpenDrive root 'rclone-test-yobagaf8babo': not deleting directories as there were IO errors --- PASS: TestError (4.93s) PASS 2025/08/09 04:34:48 DEBUG : OpenDrive root 'rclone-test-yobagaf8babo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestCopyDeleteBefore|TestError|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSizeOnly|TestSyncSuffix)$'" - Finished OK in 50.728204522s (try 2/5)