"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerMailru: -verbose -fast-list -test.run '^(TestCopy|TestCopyAfterDelete|TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyMetadata|TestCopyMissingDirectory|TestCopyNoTraverse|TestCopyRedownload|TestCopyWithDepth|TestCopyWithDryRun|TestCopyWithFilesFrom|TestCopyWithFilesFromAndNoTraverse|TestMoveEmptyDirectories|TestServerSideCopy|TestSyncBasedOnCheckSum|TestSyncEmptyDirectories|TestSyncIgnoreSize|TestSyncNoTraverse|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes|TestSyncSizeOnly)$'" - Starting (try 4/5)
2024/04/25 02:11:02 DEBUG : Creating backend with remote "TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0"
2024/04/25 02:11:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/25 02:11:02 DEBUG : Creating backend with remote "TestMailru:rclone-test-xunuqod8bubozij8homibev0"
2024/04/25 02:11:03 DEBUG : Reset feature "ListR"
2024/04/25 02:11:03 DEBUG : Creating backend with remote "/tmp/rclone3339164220"
=== RUN   TestCopyWithDryRun
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:03 DEBUG : [rclone-test-xunuqod8bubozij8homibev0]: new meta server: https://cld-extapi.datacloudmail.ru/meta/
2024/04/25 02:11:03 NOTICE: sub dir: Skipped make directory as --dry-run is set
2024/04/25 02:11:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:03 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2024/04/25 02:11:03 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:03 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyWithDryRun (0.83s)
=== RUN   TestCopy
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:03 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/04/25 02:11:03 DEBUG : sub dir: Making directory
2024/04/25 02:11:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:04 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:04 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:04 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:04 INFO  : sub dir/hello world.rclone_chunk.001_hcws19: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:04 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:04 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopy (1.73s)
=== RUN   TestCopyMetadata
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
    sync_test.go:97: Skipping as metadata not supported
--- SKIP: TestCopyMetadata (0.15s)
=== RUN   TestCopyMissingDirectory
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:05 DEBUG : Creating backend with remote "/non-existing"
2024/04/25 02:11:05 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2024/04/25 02:11:05 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:05 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyMissingDirectory (0.41s)
=== RUN   TestCopyNoTraverse
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:06 DEBUG : sub dir: Making directory
2024/04/25 02:11:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:06 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:06 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:06 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:07 INFO  : sub dir/hello world.rclone_chunk.001_hcwuoa: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:07 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyNoTraverse (1.70s)
=== RUN   TestCopyCheckFirst
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:07 INFO  : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Running all checks before starting transfers
2024/04/25 02:11:07 DEBUG : sub dir: Making directory
2024/04/25 02:11:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:08 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:08 INFO  : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Checks finished, now starting transfers
2024/04/25 02:11:08 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:08 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:08 INFO  : sub dir/hello world.rclone_chunk.001_hcww9a: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:08 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyCheckFirst (2.02s)
=== RUN   TestSyncNoTraverse
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:09 ERROR : Ignoring --no-traverse with sync
2024/04/25 02:11:10 DEBUG : sub dir: Making directory
2024/04/25 02:11:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:10 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:10 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:10 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:10 INFO  : sub dir/hello world.rclone_chunk.001_hcwy81: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:10 INFO  : sub dir/hello world: Copied (new)
2024/04/25 02:11:10 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestSyncNoTraverse (2.28s)
=== RUN   TestCopyWithDepth
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:12 DEBUG : hello world2: Need to transfer - File not found at Destination
2024/04/25 02:11:12 DEBUG : sub dir: Making directory
2024/04/25 02:11:12 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:12 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:12 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:13 INFO  : hello world2.rclone_chunk.001_hcx0a7: Moved (server-side) to: hello world2
2024/04/25 02:11:13 INFO  : hello world2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyWithDepth (1.74s)
=== RUN   TestCopyWithFilesFrom
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:13 DEBUG : potato2: Need to transfer - File not found at Destination
2024/04/25 02:11:13 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:13 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:14 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:14 INFO  : potato2.rclone_chunk.001_hcx2q9: Moved (server-side) to: potato2
2024/04/25 02:11:14 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyWithFilesFrom (1.27s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:15 DEBUG : potato2: Need to transfer - File not found at Destination
2024/04/25 02:11:15 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:15 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:15 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:16 INFO  : potato2.rclone_chunk.001_hcx3ih: Moved (server-side) to: potato2
2024/04/25 02:11:16 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyWithFilesFromAndNoTraverse (1.44s)
=== RUN   TestCopyEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:16 DEBUG : sub dir2: Making directory with metadata
2024/04/25 02:11:16 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/04/25 02:11:16 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/04/25 02:11:16 DEBUG : sub dir: Making directory
2024/04/25 02:11:16 DEBUG : sub dir2: Making directory
2024/04/25 02:11:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:16 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:16 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:16 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:17 INFO  : sub dir/hello world.rclone_chunk.001_hcx4b9: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:17 INFO  : sub dir/hello world: Copied (new)
2024/04/25 02:11:17 DEBUG : sub dir2: Making directory
2024/04/25 02:11:17 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': copied 1 directories
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:17 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopyEmptyDirectories (2.10s)
=== RUN   TestMoveEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:18 DEBUG : sub dir2: Making directory with metadata
2024/04/25 02:11:18 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/04/25 02:11:18 DEBUG : sub dir: Making directory
2024/04/25 02:11:18 DEBUG : sub dir2: Making directory
2024/04/25 02:11:18 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:18 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:18 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:19 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:19 INFO  : sub dir/hello world.rclone_chunk.001_hcx70h: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:19 INFO  : sub dir/hello world: Copied (new)
2024/04/25 02:11:19 INFO  : sub dir/hello world: Deleted
2024/04/25 02:11:19 DEBUG : sub dir: Making directory
2024/04/25 02:11:19 DEBUG : sub dir2: Making directory
2024/04/25 02:11:19 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': copied 2 directories
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:20 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
2024/04/25 02:11:20 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestMoveEmptyDirectories (2.37s)
=== RUN   TestSyncNoUpdateDirModtime
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
    sync_test.go:351: Skipping test as backend does not support DirSetModTime
--- SKIP: TestSyncNoUpdateDirModtime (0.14s)
=== RUN   TestSyncEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:21 DEBUG : sub dir2: Making directory with metadata
2024/04/25 02:11:21 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2024/04/25 02:11:21 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2024/04/25 02:11:21 DEBUG : sub dir: Making directory
2024/04/25 02:11:21 DEBUG : sub dir2: Making directory
2024/04/25 02:11:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:21 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:21 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:21 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:22 INFO  : sub dir/hello world.rclone_chunk.001_hcx9ns: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:22 INFO  : sub dir/hello world: Copied (new)
2024/04/25 02:11:22 DEBUG : sub dir2: Making directory
2024/04/25 02:11:22 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': copied 1 directories
2024/04/25 02:11:22 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:22 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (2.24s)
=== RUN   TestSyncSetDelayedModTimes
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
    sync_test.go:427: Backend doesn't have DirModTimeUpdatesOnWrite set
--- SKIP: TestSyncSetDelayedModTimes (0.17s)
=== RUN   TestServerSideCopy
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:24 INFO  : sub dir/hello world.rclone_chunk.001_hcxbgp: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:24 DEBUG : Creating backend with remote "TestChunkerMailru:rclone-test-yetimub2sotezoc9suxodiy0"
2024/04/25 02:11:24 DEBUG : Creating backend with remote "TestMailru:rclone-test-yetimub2sotezoc9suxodiy0"
2024/04/25 02:11:24 DEBUG : Reset feature "ListR"
    sync_test.go:487: Server side copy (if possible) Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0' -> Chunked 'TestChunkerMailru:rclone-test-yetimub2sotezoc9suxodiy0'
2024/04/25 02:11:24 DEBUG : sub dir: Making directory
2024/04/25 02:11:24 DEBUG : [rclone-test-yetimub2sotezoc9suxodiy0]: new meta server: https://cld-extapi.datacloudmail.ru/meta/
2024/04/25 02:11:24 DEBUG : [rclone-test-yetimub2sotezoc9suxodiy0]: mkDirs by part "/rclone-test-yetimub2sotezoc9suxodiy0/sub dir"
2024/04/25 02:11:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:25 DEBUG : sub dir/hello world: copy non-chunked object...
2024/04/25 02:11:25 DEBUG : Chunked 'TestChunkerMailru:rclone-test-yetimub2sotezoc9suxodiy0': Waiting for checks to finish
2024/04/25 02:11:25 DEBUG : Chunked 'TestChunkerMailru:rclone-test-yetimub2sotezoc9suxodiy0': Waiting for transfers to finish
2024/04/25 02:11:25 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:25 DEBUG : Chunked 'TestChunkerMailru:rclone-test-yetimub2sotezoc9suxodiy0': Purge remote
--- PASS: TestServerSideCopy (2.70s)
=== RUN   TestCopyAfterDelete
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:27 INFO  : sub dir/hello world.rclone_chunk.001_hcxel4: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:27 ERROR : : error listing: directory not found
2024/04/25 02:11:27 DEBUG : Local file system at /tmp/rclone3339164220: Making directory
2024/04/25 02:11:27 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:27 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyAfterDelete (1.85s)
=== RUN   TestCopyRedownload
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:28 INFO  : sub dir/hello world.rclone_chunk.001_hcxg5v: Moved (server-side) to: sub dir/hello world
2024/04/25 02:11:29 DEBUG : sub dir: Making directory with metadata
2024/04/25 02:11:29 INFO  : sub dir: Made directory with metadata (mtime=1970-01-01T00:00:00Z)
2024/04/25 02:11:29 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/04/25 02:11:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/04/25 02:11:29 DEBUG : Local file system at /tmp/rclone3339164220: Waiting for checks to finish
2024/04/25 02:11:29 DEBUG : Local file system at /tmp/rclone3339164220: Waiting for transfers to finish
2024/04/25 02:11:29 DEBUG : [rclone-test-xunuqod8bubozij8homibev0]: Switch file server: locks 1, url https://cloclo62.datacloudmail.ru/oauth-get/, expiry "2024-04-25T02:14:29.19222169Z"
2024/04/25 02:11:29 DEBUG : [rclone-test-xunuqod8bubozij8homibev0]: Unlock file server: locks 0, url https://cloclo62.datacloudmail.ru/oauth-get/
2024/04/25 02:11:29 DEBUG : sub dir/hello world.tipesem3.partial: renamed to: sub dir/hello world
2024/04/25 02:11:29 INFO  : sub dir/hello world: Copied (new)
2024/04/25 02:11:29 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestCopyRedownload (1.87s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:30 DEBUG : check sum: Need to transfer - File not found at Destination
2024/04/25 02:11:30 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:30 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:30 DEBUG : check sum: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:30 INFO  : check sum.rclone_chunk.001_hcxism: Moved (server-side) to: check sum
2024/04/25 02:11:30 INFO  : check sum: Copied (new)
2024/04/25 02:11:30 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:31 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:31 NOTICE: Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2024/04/25 02:11:31 DEBUG : check sum: Size of src and dst objects identical
2024/04/25 02:11:31 DEBUG : check sum: Unchanged skipping
2024/04/25 02:11:31 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:31 DEBUG : Waiting for deletions to finish
2024/04/25 02:11:31 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestSyncBasedOnCheckSum (1.67s)
=== RUN   TestSyncSizeOnly
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:31 DEBUG : sizeonly: Need to transfer - File not found at Destination
2024/04/25 02:11:31 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:31 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:31 DEBUG : sizeonly: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:32 INFO  : sizeonly.rclone_chunk.001_hcxjcn: Moved (server-side) to: sizeonly
2024/04/25 02:11:32 INFO  : sizeonly: Copied (new)
2024/04/25 02:11:32 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:32 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:32 DEBUG : sizeonly: Sizes identical
2024/04/25 02:11:32 DEBUG : sizeonly: Unchanged skipping
2024/04/25 02:11:32 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:32 DEBUG : Waiting for deletions to finish
2024/04/25 02:11:32 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestSyncSizeOnly (1.71s)
=== RUN   TestSyncIgnoreSize
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:33 DEBUG : ignore-size: Need to transfer - File not found at Destination
2024/04/25 02:11:33 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:33 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:33 DEBUG : ignore-size: skip slow MD5 on source file, hashing in-transit
2024/04/25 02:11:34 INFO  : ignore-size.rclone_chunk.001_hcxlwg: Moved (server-side) to: ignore-size
2024/04/25 02:11:34 INFO  : ignore-size: Copied (new)
2024/04/25 02:11:34 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
2024/04/25 02:11:35 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for checks to finish
2024/04/25 02:11:35 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2024/04/25 02:11:35 DEBUG : ignore-size: Unchanged skipping
2024/04/25 02:11:35 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Waiting for transfers to finish
2024/04/25 02:11:35 DEBUG : Waiting for deletions to finish
2024/04/25 02:11:35 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0'", Local "Local file system at /tmp/rclone3339164220", Modify Window "1s"
--- PASS: TestSyncIgnoreSize (2.38s)
PASS
2024/04/25 02:11:35 DEBUG : Chunked 'TestChunkerMailru:rclone-test-xunuqod8bubozij8homibev0': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerMailru: -verbose -fast-list -test.run '^(TestCopy|TestCopyAfterDelete|TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyMetadata|TestCopyMissingDirectory|TestCopyNoTraverse|TestCopyRedownload|TestCopyWithDepth|TestCopyWithDryRun|TestCopyWithFilesFrom|TestCopyWithFilesFromAndNoTraverse|TestMoveEmptyDirectories|TestServerSideCopy|TestSyncBasedOnCheckSum|TestSyncEmptyDirectories|TestSyncIgnoreSize|TestSyncNoTraverse|TestSyncNoUpdateDirModtime|TestSyncSetDelayedModTimes|TestSyncSizeOnly)$'" - Finished OK in 33.184476494s (try 4/5)