"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestServerSideCopy|TestServerSideMoveOverSelf|TestSyncBackupDirSuffixOnly|TestSyncCompareDest|TestSyncOverlapWithFilter|TestSyncReplaceDirModTimeWithEmptyDirs)$'" - Starting (try 2/5)
2025/07/30 04:44:02 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-boqemop4xaha"
2025/07/30 04:44:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/30 04:44:03 DEBUG : Starting OpenDrive session with ID: 4326751741628cd3601bb71c8e50d9f9e9fd89771593fb7de550057abbab35a5
2025/07/30 04:44:03 DEBUG : Creating backend with remote "/tmp/rclone796062517"
=== RUN   TestServerSideCopy
    run.go:180: Remote "OpenDrive root 'rclone-test-boqemop4xaha'", Local "Local file system at /tmp/rclone796062517", Modify Window "1s"
2025/07/30 04:44:04 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
2025/07/30 04:44:06 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wijulij5moxe"
2025/07/30 04:44:06 DEBUG : Starting OpenDrive session with ID: 3ca9385eb9eb976ce43cc8749391408c434c15a0f951af8f04aaf301beded81a
    sync_test.go:620: Server side copy (if possible) OpenDrive root 'rclone-test-boqemop4xaha' -> OpenDrive root 'rclone-test-wijulij5moxe'
2025/07/30 04:44:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/07/30 04:44:07 DEBUG : OpenDrive root 'rclone-test-wijulij5moxe': Waiting for checks to finish
2025/07/30 04:44:07 DEBUG : OpenDrive root 'rclone-test-wijulij5moxe': Waiting for transfers to finish
2025/07/30 04:44:08 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/07/30 04:44:08 INFO  : sub dir/hello world: Copied (server-side copy)
2025/07/30 04:44:08 DEBUG : OpenDrive root 'rclone-test-wijulij5moxe': Purge remote
    run.go:125: removing dir "sub dir" failed - try 1/3: folder not empty
--- PASS: TestServerSideCopy (8.14s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "OpenDrive root 'rclone-test-boqemop4xaha'", Local "Local file system at /tmp/rclone796062517", Modify Window "1s"
    run.go:285: Failed to put "sub dir/hello world" to "OpenDrive root 'rclone-test-boqemop4xaha'": failed to create file: Parent folder is trashed (Error 403)
--- FAIL: TestServerSideMoveOverSelf (0.87s)
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "OpenDrive root 'rclone-test-boqemop4xaha'", Local "Local file system at /tmp/rclone796062517", Modify Window "1s"
2025/07/30 04:44:12 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-boqemop4xaha/rclone-sync-test"
2025/07/30 04:44:12 DEBUG : Starting OpenDrive session with ID: bcdf7c49eeeedd4acdd92ad8d2f88b9aa83432a9ea7890aed22210e9d5df3efa
2025/07/30 04:44:13 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-boqemop4xaha/rclone-sync-test-include/layer2"
2025/07/30 04:44:14 DEBUG : Starting OpenDrive session with ID: 0076264400e99e03c5219cefc908bc32e0e9adead2373a35b30ddc985e4ef806
2025/07/30 04:44:15 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-boqemop4xaha/rclone-sync-test-ignore-file"
2025/07/30 04:44:16 DEBUG : Starting OpenDrive session with ID: d2e84223bce90f532aa749159a079e218d0d5fea24df94cbde37dac86f3305d8
2025/07/30 04:44:18 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0
2025/07/30 04:44:19 DEBUG : rclone-sync-test: Excluded
2025/07/30 04:44:20 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/07/30 04:44:20 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/07/30 04:44:20 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/rclone-sync-test': Waiting for checks to finish
2025/07/30 04:44:20 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/rclone-sync-test': Waiting for transfers to finish
2025/07/30 04:44:20 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:20 INFO  : There was nothing to transfer
2025/07/30 04:44:21 DEBUG : rclone-sync-test: Excluded
2025/07/30 04:44:21 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/07/30 04:44:21 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/07/30 04:44:21 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha': Waiting for checks to finish
2025/07/30 04:44:21 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha': Waiting for transfers to finish
2025/07/30 04:44:21 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:21 INFO  : rclone-sync-test-include: Removing directory
2025/07/30 04:44:22 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha': deleted 1 directories
2025/07/30 04:44:22 INFO  : There was nothing to transfer
2025/07/30 04:44:22 DEBUG : rclone-sync-test: Excluded
2025/07/30 04:44:22 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/07/30 04:44:22 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/07/30 04:44:22 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/07/30 04:44:22 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:22 INFO  : There was nothing to transfer
2025/07/30 04:44:23 DEBUG : rclone-sync-test: Excluded
2025/07/30 04:44:23 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/07/30 04:44:23 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha': Waiting for checks to finish
2025/07/30 04:44:23 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha': Waiting for transfers to finish
2025/07/30 04:44:23 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:23 INFO  : There was nothing to transfer
2025/07/30 04:44:23 DEBUG : Excluded
2025/07/30 04:44:24 DEBUG : rclone-sync-test: Excluded
2025/07/30 04:44:24 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/07/30 04:44:24 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/07/30 04:44:24 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/07/30 04:44:24 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:24 INFO  : There was nothing to transfer
--- PASS: TestSyncOverlapWithFilter (13.85s)
=== RUN   TestSyncCompareDest
    run.go:180: Remote "OpenDrive root 'rclone-test-boqemop4xaha'", Local "Local file system at /tmp/rclone796062517", Modify Window "1s"
2025/07/30 04:44:26 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-boqemop4xaha/dst"
2025/07/30 04:44:26 DEBUG : Starting OpenDrive session with ID: 169a973b32c03565cb9ac365b68e70ec9892954522f927314f26e00123ea28ec
2025/07/30 04:44:27 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-boqemop4xaha/CompareDest"
2025/07/30 04:44:27 DEBUG : Starting OpenDrive session with ID: 77233627e4d59886ecca19687d83b24c0954e12f336bb2d621895d36daebb81a
2025/07/30 04:44:28 DEBUG : one: Need to transfer - File not found at Destination
2025/07/30 04:44:28 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for checks to finish
2025/07/30 04:44:28 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for transfers to finish
2025/07/30 04:44:30 DEBUG : one: Uploading chunk 0, size=3, remain=0
2025/07/30 04:44:31 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/07/30 04:44:31 INFO  : one: Copied (new)
2025/07/30 04:44:31 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:31 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for checks to finish
2025/07/30 04:44:31 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/07/30 04:44:32 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for transfers to finish
2025/07/30 04:44:32 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/07/30 04:44:33 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/07/30 04:44:33 INFO  : one: Copied (replaced existing)
2025/07/30 04:44:33 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:34 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/07/30 04:44:36 DEBUG : CompareDest/one: Uploading chunk 0, size=5, remain=0
2025/07/30 04:44:38 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/07/30 04:44:38 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for checks to finish
2025/07/30 04:44:38 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/30 04:44:38 DEBUG : one: Destination found in --compare-dest, skipping
2025/07/30 04:44:38 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for transfers to finish
2025/07/30 04:44:38 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:38 INFO  : There was nothing to transfer
2025/07/30 04:44:39 DEBUG : CompareDest/two: Uploading chunk 0, size=3, remain=0
2025/07/30 04:44:41 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/07/30 04:44:41 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/30 04:44:41 DEBUG : two: Destination found in --compare-dest, skipping
2025/07/30 04:44:41 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for checks to finish
2025/07/30 04:44:41 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/30 04:44:41 DEBUG : one: Destination found in --compare-dest, skipping
2025/07/30 04:44:41 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for transfers to finish
2025/07/30 04:44:41 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:41 INFO  : There was nothing to transfer
2025/07/30 04:44:41 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/07/30 04:44:42 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/30 04:44:42 DEBUG : two: Destination found in --compare-dest, skipping
2025/07/30 04:44:42 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for checks to finish
2025/07/30 04:44:42 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/30 04:44:42 DEBUG : one: Destination found in --compare-dest, skipping
2025/07/30 04:44:42 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for transfers to finish
2025/07/30 04:44:42 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:42 INFO  : There was nothing to transfer
    sync_test.go:2205: No hash on uploaded file so skipping compare timestamp test
2025/07/30 04:44:43 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/07/30 04:44:43 DEBUG : two: Sizes differ (src 5 vs dst 3)
2025/07/30 04:44:43 DEBUG : two: Need to transfer - File not found at Destination
2025/07/30 04:44:43 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for checks to finish
2025/07/30 04:44:43 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2025/07/30 04:44:43 DEBUG : one: Destination found in --compare-dest, skipping
2025/07/30 04:44:43 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for transfers to finish
2025/07/30 04:44:44 DEBUG : two: Uploading chunk 0, size=5, remain=0
2025/07/30 04:44:45 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK
2025/07/30 04:44:45 INFO  : two: Copied (new)
2025/07/30 04:44:45 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (21.23s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-boqemop4xaha'", Local "Local file system at /tmp/rclone796062517", Modify Window "1s"
2025/07/30 04:44:48 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/07/30 04:44:50 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/07/30 04:44:51 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/07/30 04:44:53 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-boqemop4xaha/dst"
2025/07/30 04:44:53 DEBUG : Starting OpenDrive session with ID: efdf886df4e1c0c4810aaa1cd7ccc78cd9c7ea7b5d2156b3d5f1cd6fa7ebf64d
2025/07/30 04:44:54 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/07/30 04:44:54 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for checks to finish
2025/07/30 04:44:54 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/07/30 04:44:54 DEBUG : two: Unchanged skipping
2025/07/30 04:44:54 DEBUG : one: same parent dir (ODFfOTM1MTA3MV83amtzTA) - using file/rename instead of move_copy for one.bak
2025/07/30 04:44:54 INFO  : one: Moved (server-side) to: one.bak
2025/07/30 04:44:54 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for transfers to finish
2025/07/30 04:44:55 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/07/30 04:44:56 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/07/30 04:44:56 INFO  : one: Copied (new)
2025/07/30 04:44:56 DEBUG : Waiting for deletions to finish
2025/07/30 04:44:57 DEBUG : three.txt: same parent dir (ODFfOTM1MTA3MV83amtzTA) - using file/rename instead of move_copy for three.txt.bak
2025/07/30 04:44:57 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/07/30 04:44:57 INFO  : three.txt: Moved into backup dir
2025/07/30 04:44:58 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
    fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:298: Flushing the directory cache
    fstest.go:289: Sleeping for 6s just to make sure
2025/07/30 04:45:07 DEBUG : one.bak: Excluded (Path Filter)
2025/07/30 04:45:07 DEBUG : one.bak: Excluded
2025/07/30 04:45:07 DEBUG : three.txt.bak: Excluded (Path Filter)
2025/07/30 04:45:07 DEBUG : three.txt.bak: Excluded
2025/07/30 04:45:07 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/07/30 04:45:07 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/07/30 04:45:07 DEBUG : two: Unchanged skipping
2025/07/30 04:45:07 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for checks to finish
2025/07/30 04:45:07 INFO  : one.bak: Deleted
2025/07/30 04:45:07 DEBUG : one: same parent dir (ODFfOTM1MTA3MV83amtzTA) - using file/rename instead of move_copy for one.bak
2025/07/30 04:45:08 INFO  : one: Moved (server-side) to: one.bak
2025/07/30 04:45:08 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha/dst': Waiting for transfers to finish
2025/07/30 04:45:08 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/07/30 04:45:09 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/07/30 04:45:09 INFO  : one: Copied (new)
2025/07/30 04:45:09 DEBUG : Waiting for deletions to finish
2025/07/30 04:45:10 INFO  : three.txt.bak: Deleted
2025/07/30 04:45:10 DEBUG : three.txt: same parent dir (ODFfOTM1MTA3MV83amtzTA) - using file/rename instead of move_copy for three.txt.bak
2025/07/30 04:45:10 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/07/30 04:45:10 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (25.04s)
=== RUN   TestSyncReplaceDirModTimeWithEmptyDirs
    run.go:180: Remote "OpenDrive root 'rclone-test-boqemop4xaha'", Local "Local file system at /tmp/rclone796062517", Modify Window "1s"
2025/07/30 04:45:12 DEBUG : empty_dir: Making directory with metadata
2025/07/30 04:45:12 INFO  : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/07/30 04:45:12 DEBUG : empty_on_remote: Making directory with metadata
2025/07/30 04:45:12 INFO  : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/07/30 04:45:12 INFO  : empty_on_remote: Making directory
2025/07/30 04:45:12 INFO  : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
2025/07/30 04:45:16 INFO  : test_dir1: Set directory modification time (using DirSetModTime)
2025/07/30 04:45:16 INFO  : test_dir2: Set directory modification time (using DirSetModTime)
2025/07/30 04:45:16 INFO  : test_dir2/sub_dir: Set directory modification time (using DirSetModTime)
2025/07/30 04:45:16 INFO  : empty_on_remote: Set directory modification time (using DirSetModTime)
2025/07/30 04:45:16 INFO  : empty_dir: Set directory modification time (using DirSetModTime)
--- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (7.66s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:180: Remote "OpenDrive root 'rclone-test-boqemop4xaha'", Local "Local file system at /tmp/rclone796062517", Modify Window "1s"
2025/07/30 04:45:20 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/07/30 04:45:20 INFO  : sub dir: Making directory
2025/07/30 04:45:20 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
--- PASS: TestNothingToTransferWithEmptyDirs (15.36s)
FAIL
2025/07/30 04:45:35 DEBUG : OpenDrive root 'rclone-test-boqemop4xaha': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestServerSideCopy|TestServerSideMoveOverSelf|TestSyncBackupDirSuffixOnly|TestSyncCompareDest|TestSyncOverlapWithFilter|TestSyncReplaceDirModTimeWithEmptyDirs)$'" - Finished ERROR in 1m33.605009578s (try 2/5): exit status 1: Failed [TestServerSideMoveOverSelf]