"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirSuffixOnly|TestSyncSuffix|TestSyncWithUpdateOlder)$'" - Starting (try 2/5) 2025/08/25 03:02:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vaxotex0zelo" 2025/08/25 03:02:19 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/25 03:02:20 DEBUG : Starting OpenDrive session with ID: 39efdefc0617b979809a876edf7299423261cd71f9d66f79dd33d2386bcd03b7 2025/08/25 03:02:20 DEBUG : Creating backend with remote "/tmp/rclone3321367674" === RUN TestSyncWithUpdateOlder run.go:180: Remote "OpenDrive root 'rclone-test-vaxotex0zelo'", Local "Local file system at /tmp/rclone3321367674", Modify Window "1s" 2025/08/25 03:02:22 DEBUG : one: Uploading chunk 0, size=3, remain=0 2025/08/25 03:02:23 DEBUG : two: Uploading chunk 0, size=3, remain=0 2025/08/25 03:02:25 DEBUG : three: Uploading chunk 0, size=5, remain=0 2025/08/25 03:02:27 DEBUG : four: Uploading chunk 0, size=8, remain=0 2025/08/25 03:02:29 DEBUG : five: Need to transfer - File not found at Destination 2025/08/25 03:02:29 DEBUG : one: Destination is newer than source, skipping 2025/08/25 03:02:29 DEBUG : three: Sizes identical 2025/08/25 03:02:29 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/08/25 03:02:29 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/08/25 03:02:29 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Waiting for checks to finish 2025/08/25 03:02:29 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone3321367674) 2025/08/25 03:02:29 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/08/25 03:02:29 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (OpenDrive root 'rclone-test-vaxotex0zelo') 2025/08/25 03:02:29 DEBUG : two: md5 differ 2025/08/25 03:02:29 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Waiting for transfers to finish 2025/08/25 03:02:29 DEBUG : four: Uploading chunk 0, size=4, remain=0 2025/08/25 03:02:29 DEBUG : two: Uploading chunk 0, size=3, remain=0 2025/08/25 03:02:30 DEBUG : five: Uploading chunk 0, size=4, remain=0 2025/08/25 03:02:30 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2025/08/25 03:02:30 INFO : four: Copied (replaced existing) 2025/08/25 03:02:30 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/08/25 03:02:30 INFO : two: Copied (replaced existing) 2025/08/25 03:02:31 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2025/08/25 03:02:31 INFO : five: Copied (new) 2025/08/25 03:02:31 DEBUG : Waiting for deletions to finish 2025/08/25 03:02:31 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Waiting for checks to finish 2025/08/25 03:02:31 DEBUG : one: Destination is newer than source, skipping 2025/08/25 03:02:31 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/08/25 03:02:31 DEBUG : two: Size and md5 of src and dst objects identical 2025/08/25 03:02:31 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2025/08/25 03:02:31 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2025/08/25 03:02:31 DEBUG : four: Size and md5 of src and dst objects identical 2025/08/25 03:02:31 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2025/08/25 03:02:31 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone3321367674) 2025/08/25 03:02:31 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (OpenDrive root 'rclone-test-vaxotex0zelo') 2025/08/25 03:02:31 DEBUG : three: md5 differ 2025/08/25 03:02:31 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2025/08/25 03:02:31 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2025/08/25 03:02:31 DEBUG : five: Size and md5 of src and dst objects identical 2025/08/25 03:02:31 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2025/08/25 03:02:31 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Waiting for transfers to finish 2025/08/25 03:02:32 DEBUG : three: Uploading chunk 0, size=5, remain=0 2025/08/25 03:02:33 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2025/08/25 03:02:33 INFO : three: Copied (replaced existing) 2025/08/25 03:02:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (13.65s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "OpenDrive root 'rclone-test-vaxotex0zelo'", Local "Local file system at /tmp/rclone3321367674", Modify Window "1s" 2025/08/25 03:02:35 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/08/25 03:02:37 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/08/25 03:02:39 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/08/25 03:02:41 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vaxotex0zelo/dst" 2025/08/25 03:02:41 DEBUG : Starting OpenDrive session with ID: 4644fef482feb17bc92ae8089204d880f1905e40e6054605d4d4c2c1e7d33e85 2025/08/25 03:02:42 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/25 03:02:42 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo/dst': Waiting for checks to finish 2025/08/25 03:02:42 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/25 03:02:42 DEBUG : two: Unchanged skipping 2025/08/25 03:02:42 DEBUG : one: same parent dir (ODFfOTQyMjQxOV9jWVAxSQ) - using file/rename instead of move_copy for one.bak 2025/08/25 03:02:42 INFO : one: Moved (server-side) to: one.bak 2025/08/25 03:02:42 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo/dst': Waiting for transfers to finish 2025/08/25 03:02:43 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/08/25 03:02:44 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/08/25 03:02:44 INFO : one: Copied (new) 2025/08/25 03:02:44 DEBUG : Waiting for deletions to finish 2025/08/25 03:02:44 DEBUG : three.txt: same parent dir (ODFfOTQyMjQxOV9jWVAxSQ) - using file/rename instead of move_copy for three.txt.bak 2025/08/25 03:02:45 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/08/25 03:02:45 INFO : three.txt: Moved into backup dir 2025/08/25 03:02:46 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/08/25 03:02:48 DEBUG : one.bak: Excluded (Path Filter) 2025/08/25 03:02:48 DEBUG : one.bak: Excluded 2025/08/25 03:02:48 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/08/25 03:02:48 DEBUG : three.txt.bak: Excluded 2025/08/25 03:02:48 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo/dst': Waiting for checks to finish 2025/08/25 03:02:48 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/25 03:02:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/25 03:02:48 DEBUG : two: Unchanged skipping 2025/08/25 03:02:48 INFO : one.bak: Deleted 2025/08/25 03:02:48 DEBUG : one: same parent dir (ODFfOTQyMjQxOV9jWVAxSQ) - using file/rename instead of move_copy for one.bak 2025/08/25 03:02:48 INFO : one: Moved (server-side) to: one.bak 2025/08/25 03:02:48 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo/dst': Waiting for transfers to finish 2025/08/25 03:02:49 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/08/25 03:02:50 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/08/25 03:02:50 INFO : one: Copied (new) 2025/08/25 03:02:50 DEBUG : Waiting for deletions to finish 2025/08/25 03:02:51 INFO : three.txt.bak: Deleted 2025/08/25 03:02:51 DEBUG : three.txt: same parent dir (ODFfOTQyMjQxOV9jWVAxSQ) - using file/rename instead of move_copy for three.txt.bak 2025/08/25 03:02:51 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/08/25 03:02:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (18.97s) === RUN TestSyncSuffix run.go:180: Remote "OpenDrive root 'rclone-test-vaxotex0zelo'", Local "Local file system at /tmp/rclone3321367674", Modify Window "1s" 2025/08/25 03:02:54 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/08/25 03:02:56 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/08/25 03:02:58 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/08/25 03:02:59 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vaxotex0zelo/dst" 2025/08/25 03:03:00 DEBUG : Starting OpenDrive session with ID: 7053242e8c55418c7849375e20b7cb4797831cd71e9a30cb2f9f3bb0012b4cd4 2025/08/25 03:03:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/08/25 03:03:01 DEBUG : one: same parent dir (ODFfOTQyMjQyMF9DbUNzMA) - using file/rename instead of move_copy for one.bak 2025/08/25 03:03:01 INFO : one: Moved (server-side) to: one.bak 2025/08/25 03:03:02 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/08/25 03:03:03 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/08/25 03:03:03 INFO : one: Copied (new) 2025/08/25 03:03:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/25 03:03:03 DEBUG : two: Unchanged skipping 2025/08/25 03:03:03 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/08/25 03:03:03 DEBUG : three.txt: same parent dir (ODFfOTQyMjQyMF9DbUNzMA) - using file/rename instead of move_copy for three.txt.bak 2025/08/25 03:03:04 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/08/25 03:03:04 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/08/25 03:03:05 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/08/25 03:03:05 INFO : three.txt: Copied (new) 2025/08/25 03:03:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/08/25 03:03:07 INFO : one.bak: Deleted 2025/08/25 03:03:07 DEBUG : one: same parent dir (ODFfOTQyMjQyMF9DbUNzMA) - using file/rename instead of move_copy for one.bak 2025/08/25 03:03:07 INFO : one: Moved (server-side) to: one.bak 2025/08/25 03:03:08 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/08/25 03:03:09 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/08/25 03:03:09 INFO : one: Copied (new) 2025/08/25 03:03:09 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/08/25 03:03:09 DEBUG : two: Unchanged skipping 2025/08/25 03:03:09 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/08/25 03:03:10 INFO : three.txt.bak: Deleted 2025/08/25 03:03:10 DEBUG : three.txt: same parent dir (ODFfOTQyMjQyMF9DbUNzMA) - using file/rename instead of move_copy for three.txt.bak 2025/08/25 03:03:10 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/08/25 03:03:11 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/08/25 03:03:12 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/08/25 03:03:12 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (20.94s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-vaxotex0zelo'", Local "Local file system at /tmp/rclone3321367674", Modify Window "1s" 2025/08/25 03:03:14 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/08/25 03:03:14 INFO : sub dir: Making directory 2025/08/25 03:03:14 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC sync_test.go:2898: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2898 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:22 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2896 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2976 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestNothingToTransferWithEmptyDirs run.go:125: removing dir "sub dir2/very/very/very/very/very" failed - try 1/3: Directory doesn't exist (Error 404) --- FAIL: TestNothingToTransferWithEmptyDirs (9.00s) === RUN TestManualTransformFile run.go:180: Remote "OpenDrive root 'rclone-test-vaxotex0zelo'", Local "Local file system at /tmp/rclone3321367674", Modify Window "1s" 2025/08/25 03:03:23 DEBUG : Reset feature "Copy" 2025/08/25 03:03:23 DEBUG : Reset feature "Move" 2025/08/25 03:03:23 DEBUG : Reset feature "Copy" 2025/08/25 03:03:23 DEBUG : Reset feature "Move" 2025/08/25 03:03:23 DEBUG : empty_dir: Making directory with metadata 2025/08/25 03:03:23 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/08/25 03:03:23 DEBUG : toe: transformed to: tictactoe 2025/08/25 03:03:23 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/08/25 03:03:23 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/08/25 03:03:23 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/08/25 03:03:23 INFO : tictacempty_dir: Making directory 2025/08/25 03:03:23 DEBUG : empty_dir: transformed to: tictacempty_dir 2025/08/25 03:03:23 DEBUG : toe: transformed to: tictactoe 2025/08/25 03:03:23 DEBUG : toe: transformed to: tictactoe 2025/08/25 03:03:23 INFO : tictactoe: Making directory 2025/08/25 03:03:24 DEBUG : toe: transformed to: tictactoe 2025/08/25 03:03:24 DEBUG : toe: transformed to: tictactoe 2025/08/25 03:03:24 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/08/25 03:03:24 INFO : tictactoe/tictactoe: Making directory 2025/08/25 03:03:24 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2025/08/25 03:03:24 DEBUG : toe.txt: transformed to: tictactoe.txt 2025/08/25 03:03:24 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/08/25 03:03:24 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/08/25 03:03:24 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/08/25 03:03:24 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2025/08/25 03:03:24 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Waiting for checks to finish 2025/08/25 03:03:24 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Waiting for transfers to finish 2025/08/25 03:03:25 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2025/08/25 03:03:26 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/08/25 03:03:26 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2025/08/25 03:03:26 INFO : toe/toe/toe.txt: Deleted 2025/08/25 03:03:26 ERROR : Local file system at /tmp/rclone3321367674: not deleting directories as there were IO errors sync_transform_test.go:457: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_transform_test.go:457 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestManualTransformFile --- FAIL: TestManualTransformFile (5.10s) === RUN TestBase64 run.go:180: Remote "OpenDrive root 'rclone-test-vaxotex0zelo'", Local "Local file system at /tmp/rclone3321367674", Modify Window "1s" 2025/08/25 03:03:28 DEBUG : toe: transformed to: dG9l 2025/08/25 03:03:28 DEBUG : toe: transformed to: dG9l 2025/08/25 03:03:28 INFO : dG9l: Making directory 2025/08/25 03:03:29 DEBUG : toe: transformed to: dG9l 2025/08/25 03:03:29 DEBUG : toe: transformed to: dG9l 2025/08/25 03:03:29 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/08/25 03:03:29 INFO : dG9l/dG9l: Making directory 2025/08/25 03:03:29 DEBUG : toe/toe: transformed to: dG9l/dG9l 2025/08/25 03:03:29 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2025/08/25 03:03:29 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/08/25 03:03:29 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2025/08/25 03:03:29 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Waiting for checks to finish 2025/08/25 03:03:29 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Waiting for transfers to finish 2025/08/25 03:03:29 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2025/08/25 03:03:30 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0 2025/08/25 03:03:31 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/08/25 03:03:31 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2025/08/25 03:03:31 ERROR : OpenDrive root 'rclone-test-vaxotex0zelo': not deleting files as there were IO errors 2025/08/25 03:03:31 ERROR : OpenDrive root 'rclone-test-vaxotex0zelo': 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.41s) FAIL 2025/08/25 03:03:32 DEBUG : OpenDrive root 'rclone-test-vaxotex0zelo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirSuffixOnly|TestSyncSuffix|TestSyncWithUpdateOlder)$'" - Finished ERROR in 1m13.615922975s (try 2/5): exit status 1: Failed [TestNothingToTransferWithEmptyDirs TestManualTransformFile TestBase64]