"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix)$'" - Starting (try 3/5) 2026/09/08 01:28:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xafaciz8woro" 2026/09/08 01:28:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/08 01:28:09 DEBUG : Starting OpenDrive session with ID: 59341992a121ed012376c8a0d78a0c3db2f1a1446a68483e0d0c4d4cc4f71695 2026/09/08 01:28:10 DEBUG : Creating backend with remote "/tmp/rclone28864147" === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:198: Remote "OpenDrive root 'rclone-test-xafaciz8woro'", Local "Local file system at /tmp/rclone28864147", Modify Window "1s" 2026/09/08 01:28:11 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/09/08 01:28:13 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/09/08 01:28:15 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/09/08 01:28:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xafaciz8woro/dst" 2026/09/08 01:28:17 DEBUG : Starting OpenDrive session with ID: d66f21f31c4e12a60c54f2de84af79ded5d2fc634888dc6ef2f1b511e5fef8bf 2026/09/08 01:28:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xafaciz8woro/backup" 2026/09/08 01:28:18 DEBUG : Starting OpenDrive session with ID: 6ce86b6885cc0810d4524cf6cb2b34add6adce4940c1976ab69fca4e749b40c3 2026/09/08 01:28:19 DEBUG : one: size = 4 (Local file system at /tmp/rclone28864147) 2026/09/08 01:28:19 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-xafaciz8woro/dst') 2026/09/08 01:28:19 DEBUG : OpenDrive root 'rclone-test-xafaciz8woro/dst': Waiting for checks to finish 2026/09/08 01:28:19 DEBUG : two: size = 3 OK 2026/09/08 01:28:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:28:19 DEBUG : two: Unchanged skipping 2026/09/08 01:28:19 DEBUG : one: Sizes differ 2026/09/08 01:28:20 INFO : one: Moved (server-side) to: one-2019-01-01 2026/09/08 01:28:20 DEBUG : OpenDrive root 'rclone-test-xafaciz8woro/dst': Waiting for transfers to finish 2026/09/08 01:28:20 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/09/08 01:28:21 DEBUG : one: size = 4 OK 2026/09/08 01:28:21 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/09/08 01:28:21 INFO : one: Copied (new) 2026/09/08 01:28:21 DEBUG : Waiting for deletions to finish 2026/09/08 01:28:22 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/09/08 01:28:22 INFO : three.txt: Moved into backup dir 2026/09/08 01:28:23 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/09/08 01:28:25 DEBUG : one: size = 5 (Local file system at /tmp/rclone28864147) 2026/09/08 01:28:25 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-xafaciz8woro/dst') 2026/09/08 01:28:25 DEBUG : one: Sizes differ 2026/09/08 01:28:25 DEBUG : two: size = 3 OK 2026/09/08 01:28:25 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:28:25 DEBUG : two: Unchanged skipping 2026/09/08 01:28:25 DEBUG : OpenDrive root 'rclone-test-xafaciz8woro/dst': Waiting for checks to finish 2026/09/08 01:28:26 INFO : one-2019-01-01: Deleted 2026/09/08 01:28:26 INFO : one: Moved (server-side) to: one-2019-01-01 2026/09/08 01:28:26 DEBUG : OpenDrive root 'rclone-test-xafaciz8woro/dst': Waiting for transfers to finish 2026/09/08 01:28:27 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/09/08 01:28:28 DEBUG : one: size = 5 OK 2026/09/08 01:28:28 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/09/08 01:28:28 INFO : one: Copied (new) 2026/09/08 01:28:28 DEBUG : Waiting for deletions to finish 2026/09/08 01:28:29 INFO : three-2019-01-01.txt: Deleted 2026/09/08 01:28:29 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/09/08 01:28:29 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (21.76s) === RUN TestSyncSuffix run.go:198: Remote "OpenDrive root 'rclone-test-xafaciz8woro'", Local "Local file system at /tmp/rclone28864147", Modify Window "1s" 2026/09/08 01:28:32 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/09/08 01:28:34 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/09/08 01:28:36 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/09/08 01:28:37 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xafaciz8woro/dst" 2026/09/08 01:28:38 DEBUG : Starting OpenDrive session with ID: 1cd7946dbbdc9544386a4f2d5906447c387831c95b146ffefca9d716a7ef26e6 2026/09/08 01:28:38 DEBUG : one: size = 4 (Local file system at /tmp/rclone28864147) 2026/09/08 01:28:38 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-xafaciz8woro/dst') 2026/09/08 01:28:38 DEBUG : one: Sizes differ 2026/09/08 01:28:39 DEBUG : one: same parent dir (ODFfMTMyNDE2MTRfRjY0SGM) - using file/rename instead of move_copy for one.bak 2026/09/08 01:28:39 INFO : one: Moved (server-side) to: one.bak 2026/09/08 01:28:40 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/09/08 01:28:41 DEBUG : one: size = 4 OK 2026/09/08 01:28:41 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/09/08 01:28:41 INFO : one: Copied (new) 2026/09/08 01:28:42 DEBUG : two: size = 3 OK 2026/09/08 01:28:42 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:28:42 DEBUG : two: Unchanged skipping 2026/09/08 01:28:42 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone28864147) 2026/09/08 01:28:42 DEBUG : three.txt: size = 5 (OpenDrive root 'rclone-test-xafaciz8woro/dst') 2026/09/08 01:28:42 DEBUG : three.txt: Sizes differ 2026/09/08 01:28:42 DEBUG : three.txt: same parent dir (ODFfMTMyNDE2MTRfRjY0SGM) - using file/rename instead of move_copy for three.txt.bak 2026/09/08 01:28:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/08 01:28:43 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2026/09/08 01:28:44 DEBUG : three.txt: size = 6 OK 2026/09/08 01:28:44 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2026/09/08 01:28:44 INFO : three.txt: Copied (new) 2026/09/08 01:28:45 DEBUG : one: size = 5 (Local file system at /tmp/rclone28864147) 2026/09/08 01:28:45 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-xafaciz8woro/dst') 2026/09/08 01:28:45 DEBUG : one: Sizes differ 2026/09/08 01:28:46 INFO : one.bak: Deleted 2026/09/08 01:28:46 DEBUG : one: same parent dir (ODFfMTMyNDE2MTRfRjY0SGM) - using file/rename instead of move_copy for one.bak 2026/09/08 01:28:47 INFO : one: Moved (server-side) to: one.bak 2026/09/08 01:28:47 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/09/08 01:28:48 DEBUG : one: size = 5 OK 2026/09/08 01:28:48 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/09/08 01:28:48 INFO : one: Copied (new) 2026/09/08 01:28:49 DEBUG : two: size = 3 OK 2026/09/08 01:28:49 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:28:49 DEBUG : two: Unchanged skipping 2026/09/08 01:28:49 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone28864147) 2026/09/08 01:28:49 DEBUG : three.txt: size = 6 (OpenDrive root 'rclone-test-xafaciz8woro/dst') 2026/09/08 01:28:49 DEBUG : three.txt: Sizes differ 2026/09/08 01:28:50 INFO : three.txt.bak: Deleted 2026/09/08 01:28:50 DEBUG : three.txt: same parent dir (ODFfMTMyNDE2MTRfRjY0SGM) - using file/rename instead of move_copy for three.txt.bak 2026/09/08 01:28:51 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/08 01:28:51 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2026/09/08 01:28:52 DEBUG : three.txt: size = 19 OK 2026/09/08 01:28:52 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2026/09/08 01:28:52 INFO : three.txt: Copied (new) fstest.go:128: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:128 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:133 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:152 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2552 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffix Messages: dst/three.txt: Modification time difference too big |-128772h28m53s| > 1s (want 2011-12-30 12:59:59 +0000 UTC vs got 2026-09-08 01:28:52 +0000 UTC) (precision 1s) --- FAIL: TestSyncSuffix (23.07s) FAIL 2026/09/08 01:28:54 DEBUG : OpenDrive root 'rclone-test-xafaciz8woro': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix)$'" - Finished ERROR in 46.13442567s (try 3/5): exit status 1: Failed [TestSyncSuffix]