"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDir$'" - Starting (try 2/5) 2024/11/10 04:35:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-tehenow6meja" 2024/11/10 04:35:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/11/10 04:35:10 DEBUG : Starting OpenDrive session with ID: 0cc80b85e7614c1ddb040ef3653ca80181c34a927aa91dfe8e5bbf6117960968 2024/11/10 04:35:11 DEBUG : Creating backend with remote "/tmp/rclone2829551261" === RUN TestSyncBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-tehenow6meja'", Local "Local file system at /tmp/rclone2829551261", Modify Window "1s" 2024/11/10 04:35:12 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2024/11/10 04:35:14 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2024/11/10 04:35:15 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2024/11/10 04:35:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-tehenow6meja/dst" 2024/11/10 04:35:17 DEBUG : Starting OpenDrive session with ID: 7bba66438e3f42fe8157c4c58b05bad4180fa32551f7a047f890ce3408f48e4f 2024/11/10 04:35:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-tehenow6meja/backup" 2024/11/10 04:35:18 DEBUG : Starting OpenDrive session with ID: a16524a9c4b168ba5ea8f1d20a73c117562652ecb70731b0f09803071534d149 2024/11/10 04:35:19 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/11/10 04:35:19 DEBUG : OpenDrive root 'rclone-test-tehenow6meja/dst': Waiting for checks to finish 2024/11/10 04:35:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/11/10 04:35:19 DEBUG : two: Unchanged skipping 2024/11/10 04:35:20 INFO : one: Moved (server-side) 2024/11/10 04:35:20 DEBUG : OpenDrive root 'rclone-test-tehenow6meja/dst': Waiting for transfers to finish 2024/11/10 04:35:21 DEBUG : one: Uploading chunk 0, size=4, remain=0 2024/11/10 04:35:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2024/11/10 04:35:22 INFO : one: Copied (new) 2024/11/10 04:35:22 DEBUG : Waiting for deletions to finish 2024/11/10 04:35:22 INFO : three.txt: Moved (server-side) 2024/11/10 04:35:22 INFO : three.txt: Moved into backup dir 2024/11/10 04:35:23 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2024/11/10 04:35:25 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/11/10 04:35:25 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/11/10 04:35:25 DEBUG : two: Unchanged skipping 2024/11/10 04:35:25 DEBUG : OpenDrive root 'rclone-test-tehenow6meja/dst': Waiting for checks to finish 2024/11/10 04:35:25 INFO : one: Deleted 2024/11/10 04:35:25 INFO : one: Moved (server-side) 2024/11/10 04:35:25 DEBUG : OpenDrive root 'rclone-test-tehenow6meja/dst': Waiting for transfers to finish 2024/11/10 04:35:26 DEBUG : one: Uploading chunk 0, size=5, remain=0 2024/11/10 04:35:27 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2024/11/10 04:35:27 INFO : one: Copied (new) 2024/11/10 04:35:27 DEBUG : Waiting for deletions to finish 2024/11/10 04:35:27 INFO : three.txt: Deleted 2024/11/10 04:35:27 INFO : three.txt: Moved (server-side) 2024/11/10 04:35:27 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:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2423 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2427 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (4), backup/three.txt (6), dst/one (5), dst/two (3) got backup/one (5), backup/three.txt (6), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2423 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2427 Error: Should be true Test: TestSyncBackupDir Messages: OpenDrive root 'rclone-test-tehenow6meja'/backup/one: md5 hash incorrect - expecting "c7957179c41f69d44f217a108c7915d8" got "0f93e81041f0cab37c37a05ae998b219" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2423 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2427 Error: Not equal: expected: 4 actual : 5 Test: TestSyncBackupDir Messages: backup/one: size incorrect file=4 vs obj=5 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2423 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2427 Error: Should be true Test: TestSyncBackupDir Messages: backup/one: 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:197: Not found "dst/one" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2423 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2427 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found --- FAIL: TestSyncBackupDir (27.07s) FAIL 2024/11/10 04:35:38 DEBUG : OpenDrive root 'rclone-test-tehenow6meja': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDir$'" - Finished ERROR in 28.298962075s (try 2/5): exit status 1: Failed [TestSyncBackupDir]