"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestSyncAfterChangingContentsOnly|TestSyncBackupDirSuffixOnly|TestSyncCompareDest|TestSyncSuffix|TestSyncSuffixKeepExtension)$'" - Starting (try 2/5)
2024/12/25 04:05:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nonenes2yufe"
2024/12/25 04:05:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/25 04:05:12 DEBUG : Starting OpenDrive session with ID: 0decfb5cf5fa3ccb3a250718766859f3c4ac9c2d48a760a3fa239678e4f6fbc0
2024/12/25 04:05:12 DEBUG : Creating backend with remote "/tmp/rclone2113711242"
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
2024/12/25 04:05:13 DEBUG : potato: Uploading chunk 0, size=21, remain=0
2024/12/25 04:05:15 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe': Waiting for checks to finish
2024/12/25 04:05:15 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC
2024/12/25 04:05:15 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone2113711242)
2024/12/25 04:05:15 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 (OpenDrive root 'rclone-test-nonenes2yufe')
2024/12/25 04:05:15 DEBUG : potato: md5 differ
2024/12/25 04:05:15 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe': Waiting for transfers to finish
2024/12/25 04:05:15 DEBUG : potato: Uploading chunk 0, size=21, remain=0
2024/12/25 04:05:16 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 OK
2024/12/25 04:05:16 INFO  : potato: Copied (replaced existing)
2024/12/25 04:05:16 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
--- PASS: TestSyncAfterChangingContentsOnly (4.79s)
=== RUN   TestSyncCompareDest
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
2024/12/25 04:05:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nonenes2yufe/dst"
2024/12/25 04:05:18 DEBUG : Starting OpenDrive session with ID: f83723f501b78c0b20aa47ada29f10ee4eb0b03858d69bd6d0ef22f96c548b9b
2024/12/25 04:05:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nonenes2yufe/CompareDest"
2024/12/25 04:05:19 DEBUG : Starting OpenDrive session with ID: 9b2b9a5efc1d8a93915253d69d5ab9c1dcef27d116645688602b03817b2caba3
2024/12/25 04:05:20 DEBUG : one: Need to transfer - File not found at Destination
2024/12/25 04:05:20 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for checks to finish
2024/12/25 04:05:20 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for transfers to finish
2024/12/25 04:05:21 DEBUG : one: Uploading chunk 0, size=3, remain=0
2024/12/25 04:05:22 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2024/12/25 04:05:22 INFO  : one: Copied (new)
2024/12/25 04:05:22 DEBUG : Waiting for deletions to finish
    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:2094
        	Error:      	Should be true
        	Test:       	TestSyncCompareDest
        	Messages:   	dst/one: Modification time difference too big |-209424h0m15.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2024-12-25 04:05:22 +0000 UTC) (precision 1s)
2024/12/25 04:05:23 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/25 04:05:23 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for checks to finish
2024/12/25 04:05:24 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for transfers to finish
2024/12/25 04:05:24 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/12/25 04:05:25 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2024/12/25 04:05:25 INFO  : one: Copied (replaced existing)
2024/12/25 04:05:25 DEBUG : Waiting for deletions to finish
2024/12/25 04:05:26 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/12/25 04:05:28 DEBUG : CompareDest/one: Uploading chunk 0, size=5, remain=0
2024/12/25 04:05:30 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/25 04:05:30 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for checks to finish
2024/12/25 04:05:30 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/12/25 04:05:30 DEBUG : one: Destination found in --compare-dest, skipping
2024/12/25 04:05:30 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for transfers to finish
2024/12/25 04:05:30 DEBUG : Waiting for deletions to finish
2024/12/25 04:05:30 INFO  : There was nothing to transfer
2024/12/25 04:05:31 DEBUG : CompareDest/two: Uploading chunk 0, size=3, remain=0
2024/12/25 04:05:33 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/12/25 04:05:33 DEBUG : two: Destination found in --compare-dest, skipping
2024/12/25 04:05:33 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/25 04:05:33 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for checks to finish
2024/12/25 04:05:34 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/12/25 04:05:34 DEBUG : one: Destination found in --compare-dest, skipping
2024/12/25 04:05:34 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for transfers to finish
2024/12/25 04:05:34 DEBUG : Waiting for deletions to finish
2024/12/25 04:05:34 INFO  : There was nothing to transfer
2024/12/25 04:05:35 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/12/25 04:05:35 DEBUG : two: Destination found in --compare-dest, skipping
2024/12/25 04:05:35 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/25 04:05:35 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for checks to finish
2024/12/25 04:05:35 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/12/25 04:05:35 DEBUG : one: Destination found in --compare-dest, skipping
2024/12/25 04:05:35 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for transfers to finish
2024/12/25 04:05:35 DEBUG : Waiting for deletions to finish
2024/12/25 04:05:35 INFO  : There was nothing to transfer
    sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test
2024/12/25 04:05:36 DEBUG : two: Sizes differ (src 5 vs dst 3)
2024/12/25 04:05:36 DEBUG : two: Need to transfer - File not found at Destination
2024/12/25 04:05:36 DEBUG : one: Sizes differ (src 5 vs dst 3)
2024/12/25 04:05:36 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for checks to finish
2024/12/25 04:05:36 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2024/12/25 04:05:36 DEBUG : one: Destination found in --compare-dest, skipping
2024/12/25 04:05:36 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for transfers to finish
2024/12/25 04:05:37 DEBUG : two: Uploading chunk 0, size=5, remain=0
2024/12/25 04:05:38 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK
2024/12/25 04:05:38 INFO  : two: Copied (new)
2024/12/25 04:05:38 DEBUG : Waiting for deletions to finish
--- FAIL: TestSyncCompareDest (23.14s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
2024/12/25 04:05:42 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/12/25 04:05:44 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2024/12/25 04:05:46 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2024/12/25 04:05:47 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nonenes2yufe/dst"
2024/12/25 04:05:47 DEBUG : Starting OpenDrive session with ID: 2af45d992f94881de4f5f38a751333c436128b012b0d8d78330dacc0cf883f79
2024/12/25 04:05:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/25 04:05:48 DEBUG : two: Unchanged skipping
2024/12/25 04:05:48 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/25 04:05:48 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for checks to finish
2024/12/25 04:05:48 DEBUG : one: same parent dir (ODFfNzg1MDY0OV91UXJKbw) - using file/rename instead of move_copy for one.bak
2024/12/25 04:05:49 INFO  : one: Moved (server-side) to: one.bak
2024/12/25 04:05:49 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for transfers to finish
2024/12/25 04:05:50 DEBUG : one: Uploading chunk 0, size=4, remain=0
2024/12/25 04:05:51 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/12/25 04:05:51 INFO  : one: Copied (new)
2024/12/25 04:05:51 DEBUG : Waiting for deletions to finish
2024/12/25 04:05:51 DEBUG : three.txt: same parent dir (ODFfNzg1MDY0OV91UXJKbw) - using file/rename instead of move_copy for three.txt.bak
2024/12/25 04:05:51 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/25 04:05:51 INFO  : three.txt: Moved into backup dir
2024/12/25 04:05:52 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2024/12/25 04:05:54 DEBUG : one.bak: Excluded (Path Filter)
2024/12/25 04:05:54 DEBUG : one.bak: Excluded
2024/12/25 04:05:54 DEBUG : three.txt.bak: Excluded (Path Filter)
2024/12/25 04:05:54 DEBUG : three.txt.bak: Excluded
2024/12/25 04:05:54 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/25 04:05:54 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/25 04:05:54 DEBUG : two: Unchanged skipping
2024/12/25 04:05:54 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for checks to finish
2024/12/25 04:05:54 INFO  : one.bak: Deleted
2024/12/25 04:05:55 DEBUG : one: same parent dir (ODFfNzg1MDY0OV91UXJKbw) - using file/rename instead of move_copy for one.bak
2024/12/25 04:05:55 INFO  : one: Moved (server-side) to: one.bak
2024/12/25 04:05:55 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe/dst': Waiting for transfers to finish
2024/12/25 04:05:56 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/12/25 04:05:57 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/12/25 04:05:57 INFO  : one: Copied (new)
2024/12/25 04:05:57 DEBUG : Waiting for deletions to finish
2024/12/25 04:05:57 INFO  : three.txt.bak: Deleted
2024/12/25 04:05:57 DEBUG : three.txt: same parent dir (ODFfNzg1MDY0OV91UXJKbw) - using file/rename instead of move_copy for three.txt.bak
2024/12/25 04:05:57 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/25 04:05:57 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (18.97s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
2024/12/25 04:06:00 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/12/25 04:06:02 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2024/12/25 04:06:04 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2024/12/25 04:06:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nonenes2yufe/dst"
2024/12/25 04:06:06 DEBUG : Starting OpenDrive session with ID: 8286fd8d8f45e75249d22152765fbb395aea2e82a40c616b09f09f0c8dc18b55
2024/12/25 04:06:06 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/25 04:06:07 DEBUG : one: same parent dir (ODFfNzg1MDY1Ml8yMGxOSA) - using file/rename instead of move_copy for one.bak
2024/12/25 04:06:07 INFO  : one: Moved (server-side) to: one.bak
2024/12/25 04:06:08 DEBUG : one: Uploading chunk 0, size=4, remain=0
2024/12/25 04:06:09 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/12/25 04:06:09 INFO  : one: Copied (new)
2024/12/25 04:06:09 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/25 04:06:09 DEBUG : two: Unchanged skipping
2024/12/25 04:06:09 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2024/12/25 04:06:09 DEBUG : three.txt: same parent dir (ODFfNzg1MDY1Ml8yMGxOSA) - using file/rename instead of move_copy for three.txt.bak
2024/12/25 04:06:09 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/25 04:06:10 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2024/12/25 04:06:12 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2024/12/25 04:06:12 INFO  : three.txt: Copied (new)
2024/12/25 04:06:13 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/25 04:06:13 INFO  : one.bak: Deleted
2024/12/25 04:06:13 DEBUG : one: same parent dir (ODFfNzg1MDY1Ml8yMGxOSA) - using file/rename instead of move_copy for one.bak
2024/12/25 04:06:14 INFO  : one: Moved (server-side) to: one.bak
2024/12/25 04:06:14 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/12/25 04:06:15 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/12/25 04:06:15 INFO  : one: Copied (new)
2024/12/25 04:06:16 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/25 04:06:16 DEBUG : two: Unchanged skipping
2024/12/25 04:06:16 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2024/12/25 04:06:16 INFO  : three.txt.bak: Deleted
2024/12/25 04:06:16 DEBUG : three.txt: same parent dir (ODFfNzg1MDY1Ml8yMGxOSA) - using file/rename instead of move_copy for three.txt.bak
2024/12/25 04:06:17 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2024/12/25 04:06:18 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2024/12/25 04:06:19 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2024/12/25 04:06:19 INFO  : three.txt: Copied (new)
    run.go:125: removing dir "dst" failed - try 1/3: folder not empty
--- PASS: TestSyncSuffix (22.81s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
2024/12/25 04:06:23 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2024/12/25 04:06:25 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2024/12/25 04:06:27 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2024/12/25 04:06:28 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nonenes2yufe/dst"
2024/12/25 04:06:28 DEBUG : Starting OpenDrive session with ID: 53d58ce3c00ec8d3ae56073fb4d8448b9c6a39bf9ff5532f35ab5fcaf3611ccb
2024/12/25 04:06:29 DEBUG : one: Sizes differ (src 4 vs dst 3)
2024/12/25 04:06:29 DEBUG : one: same parent dir (ODFfNzg1MDY1NV9FT3JnSA) - using file/rename instead of move_copy for one-2019-01-01
2024/12/25 04:06:29 INFO  : one: Moved (server-side) to: one-2019-01-01
2024/12/25 04:06:30 DEBUG : one: Uploading chunk 0, size=4, remain=0
2024/12/25 04:06:31 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2024/12/25 04:06:31 INFO  : one: Copied (new)
2024/12/25 04:06:31 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/25 04:06:31 DEBUG : two: Unchanged skipping
2024/12/25 04:06:31 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2024/12/25 04:06:32 DEBUG : three.txt: same parent dir (ODFfNzg1MDY1NV9FT3JnSA) - using file/rename instead of move_copy for three-2019-01-01.txt
2024/12/25 04:06:32 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2024/12/25 04:06:33 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2024/12/25 04:06:34 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2024/12/25 04:06:34 INFO  : three.txt: Copied (new)
2024/12/25 04:06:34 DEBUG : one: Sizes differ (src 5 vs dst 4)
2024/12/25 04:06:35 INFO  : one-2019-01-01: Deleted
2024/12/25 04:06:35 DEBUG : one: same parent dir (ODFfNzg1MDY1NV9FT3JnSA) - using file/rename instead of move_copy for one-2019-01-01
2024/12/25 04:06:35 INFO  : one: Moved (server-side) to: one-2019-01-01
2024/12/25 04:06:36 DEBUG : one: Uploading chunk 0, size=5, remain=0
2024/12/25 04:06:37 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2024/12/25 04:06:37 INFO  : one: Copied (new)
2024/12/25 04:06:37 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/12/25 04:06:37 DEBUG : two: Unchanged skipping
2024/12/25 04:06:37 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2024/12/25 04:06:38 INFO  : three-2019-01-01.txt: Deleted
2024/12/25 04:06:38 DEBUG : three.txt: same parent dir (ODFfNzg1MDY1NV9FT3JnSA) - using file/rename instead of move_copy for three-2019-01-01.txt
2024/12/25 04:06:38 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2024/12/25 04:06:39 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2024/12/25 04:06:40 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2024/12/25 04:06:40 INFO  : three.txt: Copied (new)
    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:2521
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524
        	Error:      	Should be true
        	Test:       	TestSyncSuffixKeepExtension
        	Messages:   	listing wrong, want
        	            	  dst/one (5), dst/one-2019-01-01 (4), dst/three-2019-01-01.txt (6), dst/three.txt (19), dst/two (3) got
        	            	  dst/one (5), dst/one-2019-01-01 (4), dst/three-2019-01-01.txt (19), 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:2521
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524
        	Error:      	Should be true
        	Test:       	TestSyncSuffixKeepExtension
        	Messages:   	OpenDrive root 'rclone-test-nonenes2yufe'/dst/three-2019-01-01.txt: md5 hash incorrect - expecting "91341eed84691a83caea73aa785736d5" got "423e01c5e84a30d129b97aedb6e219a9"
    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:2521
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524
        	Error:      	Not equal: 
        	            	expected: 6
        	            	actual  : 19
        	Test:       	TestSyncSuffixKeepExtension
        	Messages:   	dst/three-2019-01-01.txt: size incorrect file=6 vs obj=19
    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:2521
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524
        	Error:      	Should be true
        	Test:       	TestSyncSuffixKeepExtension
        	Messages:   	dst/three-2019-01-01.txt: Modification time difference too big |-95576h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-30 12:59:59 +0000 UTC) (precision 1s)
    fstest.go:197: Not found "dst/three.txt"
    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:2521
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2524
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestSyncSuffixKeepExtension
        	Messages:   	1 objects not found
--- FAIL: TestSyncSuffixKeepExtension (27.88s)
=== RUN   TestFixCase
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
2024/12/25 04:06:51 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0
2024/12/25 04:06:53 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0
    run.go:285: Failed to put "SUBDIRA/subdirb/SUBDIRC/HELLO" to "OpenDrive root 'rclone-test-nonenes2yufe'": failed to get folder list: Directory doesn't exist (Error 404)
--- FAIL: TestFixCase (6.10s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
2024/12/25 04:06:56 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/25 04:06:56 DEBUG : sub dir: Making directory
2024/12/25 04:06:56 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
--- PASS: TestNothingToTransferWithEmptyDirs (16.45s)
=== RUN   TestNothingToTransferWithoutEmptyDirs
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
2024/12/25 04:07:12 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/12/25 04:07:12 DEBUG : sub dir: Making directory
2024/12/25 04:07:13 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nonenes2yufe'", Local "Local file system at /tmp/rclone2113711242", Modify Window "1s"
    run.go:125: removing dir "sub dir2/very/very/very/very/very/nested/subdir" failed - try 1/3: folder not empty
    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: TestNothingToTransferWithoutEmptyDirs (25.95s)
FAIL
2024/12/25 04:07:38 DEBUG : OpenDrive root 'rclone-test-nonenes2yufe': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestNothingToTransferWithEmptyDirs|TestNothingToTransferWithoutEmptyDirs|TestSyncAfterChangingContentsOnly|TestSyncBackupDirSuffixOnly|TestSyncCompareDest|TestSyncSuffix|TestSyncSuffixKeepExtension)$'" - Finished ERROR in 2m27.47692892s (try 2/5): exit status 1: Failed [TestSyncCompareDest TestSyncSuffixKeepExtension TestFixCase]