"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension)$'" - Starting (try 3/5) 2026/02/19 04:12:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hidokaw2yelu" 2026/02/19 04:12:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/19 04:12:37 DEBUG : Starting OpenDrive session with ID: 8063bce5ab5dd73f42be8983a2d7550d18dc17578dc6432adc8094845a2ee352 2026/02/19 04:12:37 DEBUG : Creating backend with remote "/tmp/rclone422140093" === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "OpenDrive root 'rclone-test-hidokaw2yelu'", Local "Local file system at /tmp/rclone422140093", Modify Window "1s" 2026/02/19 04:12:39 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/02/19 04:12:40 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/02/19 04:12:42 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/02/19 04:12:44 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hidokaw2yelu/dst" 2026/02/19 04:12:44 DEBUG : Starting OpenDrive session with ID: 77555c042d0fd0efb1a9bc11a015f2211fd0756655851c525b1733714515e05c 2026/02/19 04:12:44 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hidokaw2yelu/backup" 2026/02/19 04:12:45 DEBUG : Starting OpenDrive session with ID: aa010f63e3ee56c8932eb508b778bfb93cc5a3cc5444e573ebdb4e99da0c8172 2026/02/19 04:12:46 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu/dst': Waiting for checks to finish 2026/02/19 04:12:46 DEBUG : two: size = 3 OK 2026/02/19 04:12:46 DEBUG : one: size = 4 (Local file system at /tmp/rclone422140093) 2026/02/19 04:12:46 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:12:46 DEBUG : two: Unchanged skipping 2026/02/19 04:12:46 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-hidokaw2yelu/dst') 2026/02/19 04:12:46 DEBUG : one: Sizes differ 2026/02/19 04:12:47 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/19 04:12:47 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu/dst': Waiting for transfers to finish 2026/02/19 04:12:47 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/02/19 04:12:48 DEBUG : one: size = 4 OK 2026/02/19 04:12:48 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/02/19 04:12:48 INFO : one: Copied (new) 2026/02/19 04:12:48 DEBUG : Waiting for deletions to finish 2026/02/19 04:12:49 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/19 04:12:49 INFO : three.txt: Moved into backup dir 2026/02/19 04:12:50 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/02/19 04:12:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone422140093) 2026/02/19 04:12:52 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-hidokaw2yelu/dst') 2026/02/19 04:12:52 DEBUG : one: Sizes differ 2026/02/19 04:12:52 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu/dst': Waiting for checks to finish 2026/02/19 04:12:52 DEBUG : two: size = 3 OK 2026/02/19 04:12:52 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:12:52 DEBUG : two: Unchanged skipping 2026/02/19 04:12:52 INFO : one-2019-01-01: Deleted 2026/02/19 04:12:52 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/19 04:12:52 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu/dst': Waiting for transfers to finish 2026/02/19 04:12:53 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/02/19 04:12:54 DEBUG : one: size = 5 OK 2026/02/19 04:12:54 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/02/19 04:12:54 INFO : one: Copied (new) 2026/02/19 04:12:54 DEBUG : Waiting for deletions to finish 2026/02/19 04:12:54 INFO : three-2019-01-01.txt: Deleted 2026/02/19 04:12:54 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/19 04:12:54 INFO : three.txt: Moved into backup dir fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/asm_amd64.s:1693 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (6), dst/one (5), dst/two (3) got backup/one-2019-01-01 (5), backup/three-2019-01-01.txt (6), dst/two (3) fstest.go:143: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:149 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: OpenDrive root 'rclone-test-hidokaw2yelu'/backup/one-2019-01-01: md5 hash incorrect - expecting "c7957179c41f69d44f217a108c7915d8" got "0f93e81041f0cab37c37a05ae998b219" fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Not equal: expected: 4 actual : 5 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: size incorrect file=4 vs obj=5 fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:132 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/one-2019-01-01: Modification time difference too big |-119h59m59.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2011-12-30 12:59:59 +0000 UTC) (precision 1s) fstest.go:203: Not found "dst/one" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 1 objects not found fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (35.36s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "OpenDrive root 'rclone-test-hidokaw2yelu'", Local "Local file system at /tmp/rclone422140093", Modify Window "1s" 2026/02/19 04:13:14 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/02/19 04:13:15 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/02/19 04:13:17 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/02/19 04:13:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hidokaw2yelu/dst" 2026/02/19 04:13:19 DEBUG : Starting OpenDrive session with ID: c4ce40c9a5a602e7dbde1d4693d72ed24ca8b2e6e75c0ca32223c5ea0d2c4727 2026/02/19 04:13:20 DEBUG : one: size = 4 (Local file system at /tmp/rclone422140093) 2026/02/19 04:13:20 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-hidokaw2yelu/dst') 2026/02/19 04:13:20 DEBUG : one: Sizes differ 2026/02/19 04:13:20 DEBUG : two: size = 3 OK 2026/02/19 04:13:20 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:13:20 DEBUG : two: Unchanged skipping 2026/02/19 04:13:20 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu/dst': Waiting for checks to finish 2026/02/19 04:13:20 DEBUG : one: same parent dir (ODFfMTAyMzg4NzJfVjdCekM) - using file/rename instead of move_copy for one.bak 2026/02/19 04:13:20 INFO : one: Moved (server-side) to: one.bak 2026/02/19 04:13:20 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu/dst': Waiting for transfers to finish 2026/02/19 04:13:21 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/02/19 04:13:22 DEBUG : one: size = 4 OK 2026/02/19 04:13:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/02/19 04:13:22 INFO : one: Copied (new) 2026/02/19 04:13:22 DEBUG : Waiting for deletions to finish 2026/02/19 04:13:22 DEBUG : three.txt: same parent dir (ODFfMTAyMzg4NzJfVjdCekM) - using file/rename instead of move_copy for three.txt.bak 2026/02/19 04:13:22 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/19 04:13:22 INFO : three.txt: Moved into backup dir 2026/02/19 04:13:23 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/02/19 04:13:25 DEBUG : one.bak: Excluded (Path Filter) 2026/02/19 04:13:25 DEBUG : one.bak: Excluded 2026/02/19 04:13:25 DEBUG : three.txt.bak: Excluded (Path Filter) 2026/02/19 04:13:25 DEBUG : three.txt.bak: Excluded 2026/02/19 04:13:25 DEBUG : one: size = 5 (Local file system at /tmp/rclone422140093) 2026/02/19 04:13:25 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-hidokaw2yelu/dst') 2026/02/19 04:13:25 DEBUG : one: Sizes differ 2026/02/19 04:13:25 DEBUG : two: size = 3 OK 2026/02/19 04:13:25 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:13:25 DEBUG : two: Unchanged skipping 2026/02/19 04:13:25 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu/dst': Waiting for checks to finish 2026/02/19 04:13:25 INFO : one.bak: Deleted 2026/02/19 04:13:25 DEBUG : one: same parent dir (ODFfMTAyMzg4NzJfVjdCekM) - using file/rename instead of move_copy for one.bak 2026/02/19 04:13:26 INFO : one: Moved (server-side) to: one.bak 2026/02/19 04:13:26 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu/dst': Waiting for transfers to finish 2026/02/19 04:13:26 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/02/19 04:13:27 DEBUG : one: size = 5 OK 2026/02/19 04:13:27 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/02/19 04:13:27 INFO : one: Copied (new) 2026/02/19 04:13:27 DEBUG : Waiting for deletions to finish 2026/02/19 04:13:28 INFO : three.txt.bak: Deleted 2026/02/19 04:13:28 DEBUG : three.txt: same parent dir (ODFfMTAyMzg4NzJfVjdCekM) - using file/rename instead of move_copy for three.txt.bak 2026/02/19 04:13:28 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/19 04:13:28 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (17.58s) FAIL 2026/02/19 04:13:30 DEBUG : OpenDrive root 'rclone-test-hidokaw2yelu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension)$'" - Finished ERROR in 54.280785958s (try 3/5): exit status 1: Failed [TestSyncBackupDirWithSuffixKeepExtension]