"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyWithDryRun|TestNothingToTransferWithEmptyDirs|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncReplaceDirModTime|TestSyncSuffix)$'" - Starting (try 2/5) 2025/05/01 04:02:26 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jadagof0saze" 2025/05/01 04:02:26 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/05/01 04:02:26 DEBUG : Starting OpenDrive session with ID: 4a12c0a8a3aeafffecec10c6567608816b0a3084cd229e652efe46657ec07117 2025/05/01 04:02:26 DEBUG : Creating backend with remote "/tmp/rclone12548983" === RUN TestCopyWithDryRun run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" 2025/05/01 04:02:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/05/01 04:02:27 DEBUG : OpenDrive root 'rclone-test-jadagof0saze': Waiting for checks to finish 2025/05/01 04:02:27 DEBUG : OpenDrive root 'rclone-test-jadagof0saze': Waiting for transfers to finish 2025/05/01 04:02:27 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" --- PASS: TestCopyWithDryRun (1.45s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" 2025/05/01 04:02:29 DEBUG : potato: Uploading chunk 0, size=21, remain=0 2025/05/01 04:02:31 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/05/01 04:02:32 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/05/01 04:02:32 DEBUG : potato2: Need to transfer - File not found at Destination 2025/05/01 04:02:32 DEBUG : empty space: Unchanged skipping 2025/05/01 04:02:32 DEBUG : OpenDrive root 'rclone-test-jadagof0saze': Waiting for checks to finish 2025/05/01 04:02:32 DEBUG : OpenDrive root 'rclone-test-jadagof0saze': Waiting for transfers to finish 2025/05/01 04:02:33 DEBUG : potato2: Uploading chunk 0, size=60, remain=0 2025/05/01 04:02:34 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/05/01 04:02:34 INFO : potato2: Copied (new) 2025/05/01 04:02:34 DEBUG : Waiting for deletions to finish 2025/05/01 04:02:34 INFO : potato: Deleted run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (7.97s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" 2025/05/01 04:02:37 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/05/01 04:02:39 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/05/01 04:02:41 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/05/01 04:02:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jadagof0saze/dst" 2025/05/01 04:02:43 DEBUG : Starting OpenDrive session with ID: 93d4e8e802b86bc913cd751397c71f319da27bdf5cf79d6882038d8c131d6b24 2025/05/01 04:02:43 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jadagof0saze/backup" 2025/05/01 04:02:43 DEBUG : Starting OpenDrive session with ID: 79aa312c02e486cc38e73878410793f22e227e1c809fd86e54c53433d4dcb119 2025/05/01 04:02:44 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/01 04:02:44 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/05/01 04:02:44 DEBUG : two: Unchanged skipping 2025/05/01 04:02:44 DEBUG : OpenDrive root 'rclone-test-jadagof0saze/dst': Waiting for checks to finish 2025/05/01 04:02:45 INFO : one: Moved (server-side) to: one-2019-01-01 2025/05/01 04:02:45 DEBUG : OpenDrive root 'rclone-test-jadagof0saze/dst': Waiting for transfers to finish 2025/05/01 04:02:46 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/05/01 04:02:47 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/05/01 04:02:47 INFO : one: Copied (new) 2025/05/01 04:02:47 DEBUG : Waiting for deletions to finish 2025/05/01 04:02:48 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/05/01 04:02:48 INFO : three.txt: Moved into backup dir 2025/05/01 04:02:49 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/05/01 04:02:51 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/01 04:02:51 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/05/01 04:02:51 DEBUG : two: Unchanged skipping 2025/05/01 04:02:51 DEBUG : OpenDrive root 'rclone-test-jadagof0saze/dst': Waiting for checks to finish 2025/05/01 04:02:51 INFO : one-2019-01-01: Deleted 2025/05/01 04:02:51 INFO : one: Moved (server-side) to: one-2019-01-01 2025/05/01 04:02:51 DEBUG : OpenDrive root 'rclone-test-jadagof0saze/dst': Waiting for transfers to finish 2025/05/01 04:02:52 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/05/01 04:02:53 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/05/01 04:02:53 INFO : one: Copied (new) 2025/05/01 04:02:53 DEBUG : Waiting for deletions to finish 2025/05/01 04:02:54 INFO : three-2019-01-01.txt: Deleted 2025/05/01 04:02:54 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/05/01 04:02:54 INFO : three.txt: Moved into backup dir 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 --- PASS: TestSyncBackupDirWithSuffixKeepExtension (27.81s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" 2025/05/01 04:03:05 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/05/01 04:03:07 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/05/01 04:03:09 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/05/01 04:03:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jadagof0saze/dst" 2025/05/01 04:03:11 DEBUG : Starting OpenDrive session with ID: c75e8b9a0e86bb690a71153ab1ff0ea84f6494de42b11704d3e3dbc8804302bb 2025/05/01 04:03:11 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/01 04:03:11 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/05/01 04:03:11 DEBUG : two: Unchanged skipping 2025/05/01 04:03:11 DEBUG : OpenDrive root 'rclone-test-jadagof0saze/dst': Waiting for checks to finish 2025/05/01 04:03:12 DEBUG : one: same parent dir (ODFfODQ4ODg4N19RdGQ2RA) - using file/rename instead of move_copy for one.bak 2025/05/01 04:03:12 INFO : one: Moved (server-side) to: one.bak 2025/05/01 04:03:12 DEBUG : OpenDrive root 'rclone-test-jadagof0saze/dst': Waiting for transfers to finish 2025/05/01 04:03:13 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/05/01 04:03:14 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/05/01 04:03:14 INFO : one: Copied (new) 2025/05/01 04:03:14 DEBUG : Waiting for deletions to finish 2025/05/01 04:03:14 DEBUG : three.txt: same parent dir (ODFfODQ4ODg4N19RdGQ2RA) - using file/rename instead of move_copy for three.txt.bak 2025/05/01 04:03:14 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/05/01 04:03:14 INFO : three.txt: Moved into backup dir 2025/05/01 04:03:15 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/05/01 04:03:17 DEBUG : one.bak: Excluded (Path Filter) 2025/05/01 04:03:17 DEBUG : one.bak: Excluded 2025/05/01 04:03:17 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/05/01 04:03:17 DEBUG : three.txt.bak: Excluded 2025/05/01 04:03:17 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/01 04:03:17 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/05/01 04:03:17 DEBUG : two: Unchanged skipping 2025/05/01 04:03:17 DEBUG : OpenDrive root 'rclone-test-jadagof0saze/dst': Waiting for checks to finish 2025/05/01 04:03:17 INFO : one.bak: Deleted 2025/05/01 04:03:17 DEBUG : one: same parent dir (ODFfODQ4ODg4N19RdGQ2RA) - using file/rename instead of move_copy for one.bak 2025/05/01 04:03:17 INFO : one: Moved (server-side) to: one.bak 2025/05/01 04:03:17 DEBUG : OpenDrive root 'rclone-test-jadagof0saze/dst': Waiting for transfers to finish 2025/05/01 04:03:18 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/05/01 04:03:19 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/05/01 04:03:19 INFO : one: Copied (new) 2025/05/01 04:03:19 DEBUG : Waiting for deletions to finish 2025/05/01 04:03:20 INFO : three.txt.bak: Deleted 2025/05/01 04:03:20 DEBUG : three.txt: same parent dir (ODFfODQ4ODg4N19RdGQ2RA) - using file/rename instead of move_copy for three.txt.bak 2025/05/01 04:03:20 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/05/01 04:03:20 INFO : three.txt: Moved into backup dir run.go:125: removing dir "dst" failed - try 1/3: folder not empty --- PASS: TestSyncBackupDirSuffixOnly (19.43s) === RUN TestSyncSuffix run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" 2025/05/01 04:03:24 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/05/01 04:03:26 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/05/01 04:03:28 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/05/01 04:03:30 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jadagof0saze/dst" 2025/05/01 04:03:30 DEBUG : Starting OpenDrive session with ID: e93bf3894ea77b40c2cb31a4d5de738fbcb18359d32506e47204de03aa46365c 2025/05/01 04:03:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/05/01 04:03:31 DEBUG : one: same parent dir (ODFfODQ4ODg4OF9xa1BVWg) - using file/rename instead of move_copy for one.bak 2025/05/01 04:03:31 INFO : one: Moved (server-side) to: one.bak 2025/05/01 04:03:32 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/05/01 04:03:33 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/05/01 04:03:33 INFO : one: Copied (new) 2025/05/01 04:03:33 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/05/01 04:03:33 DEBUG : two: Unchanged skipping 2025/05/01 04:03:34 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/05/01 04:03:34 DEBUG : three.txt: same parent dir (ODFfODQ4ODg4OF9xa1BVWg) - using file/rename instead of move_copy for three.txt.bak 2025/05/01 04:03:34 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/05/01 04:03:35 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/05/01 04:03:36 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/05/01 04:03:36 INFO : three.txt: Copied (new) 2025/05/01 04:03:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/05/01 04:03:38 INFO : one.bak: Deleted 2025/05/01 04:03:38 DEBUG : one: same parent dir (ODFfODQ4ODg4OF9xa1BVWg) - using file/rename instead of move_copy for one.bak 2025/05/01 04:03:38 INFO : one: Moved (server-side) to: one.bak 2025/05/01 04:03:39 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/05/01 04:03:40 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/05/01 04:03:40 INFO : one: Copied (new) 2025/05/01 04:03:40 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/05/01 04:03:40 DEBUG : two: Unchanged skipping 2025/05/01 04:03:40 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/05/01 04:03:40 INFO : three.txt.bak: Deleted 2025/05/01 04:03:40 DEBUG : three.txt: same parent dir (ODFfODQ4ODg4OF9xa1BVWg) - using file/rename instead of move_copy for three.txt.bak 2025/05/01 04:03:41 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/05/01 04:03:42 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/05/01 04:03:42 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/05/01 04:03:42 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (21.38s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" 2025/05/01 04:03:44 DEBUG : empty_dir: Making directory with metadata 2025/05/01 04:03:44 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/01 04:03:44 DEBUG : empty_on_remote: Making directory with metadata 2025/05/01 04:03:44 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/05/01 04:03:44 INFO : empty_on_remote: Making directory 2025/05/01 04:03:45 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" 2025/05/01 04:03:48 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/05/01 04:03:48 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/05/01 04:03:48 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/05/01 04:03:48 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" --- PASS: TestSyncReplaceDirModTime (8.13s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-jadagof0saze'", Local "Local file system at /tmp/rclone12548983", Modify Window "1s" 2025/05/01 04:03:53 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/05/01 04:03:53 INFO : sub dir: Making directory 2025/05/01 04:03:53 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC sync_test.go:2868: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2868 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:18 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2866 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2946 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestNothingToTransferWithEmptyDirs 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 --- FAIL: TestNothingToTransferWithEmptyDirs (18.09s) FAIL "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyWithDryRun|TestNothingToTransferWithEmptyDirs|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncReplaceDirModTime|TestSyncSuffix)$'" - Finished ERROR in 1m45.791982161s (try 2/5): exit status 1: Failed [TestNothingToTransferWithEmptyDirs]