"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024" - Starting (try 1/5)
2025/05/08 02:45:57 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june"
2025/05/08 02:45:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/08 02:45:57 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june"
2025/05/08 02:45:57 DEBUG : Creating backend with remote "/tmp/rclone3392324280"
=== RUN   TestPipe
--- PASS: TestPipe (0.00s)
=== RUN   TestPipeConcurrent
--- PASS: TestPipeConcurrent (0.04s)
=== RUN   TestPipeOrderBy
=== RUN   TestPipeOrderBy/#00
=== RUN   TestPipeOrderBy/size
=== RUN   TestPipeOrderBy/name
=== RUN   TestPipeOrderBy/modtime
=== RUN   TestPipeOrderBy/size,ascending
=== RUN   TestPipeOrderBy/name,asc
=== RUN   TestPipeOrderBy/modtime,ascending
=== RUN   TestPipeOrderBy/size,descending
=== RUN   TestPipeOrderBy/name,desc
=== RUN   TestPipeOrderBy/modtime,descending
=== RUN   TestPipeOrderBy/size,mixed,50
=== RUN   TestPipeOrderBy/size,mixed,51
--- PASS: TestPipeOrderBy (0.00s)
    --- PASS: TestPipeOrderBy/#00 (0.00s)
    --- PASS: TestPipeOrderBy/size (0.00s)
    --- PASS: TestPipeOrderBy/name (0.00s)
    --- PASS: TestPipeOrderBy/modtime (0.00s)
    --- PASS: TestPipeOrderBy/size,ascending (0.00s)
    --- PASS: TestPipeOrderBy/name,asc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,ascending (0.00s)
    --- PASS: TestPipeOrderBy/size,descending (0.00s)
    --- PASS: TestPipeOrderBy/name,desc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,descending (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s)
=== RUN   TestNewLess
=== RUN   TestNewLess/blankOK
=== RUN   TestNewLess/tooManyParts
=== RUN   TestNewLess/tooManyParts2
=== RUN   TestNewLess/badMixed
=== RUN   TestNewLess/unknownComparison
=== RUN   TestNewLess/unknownSortDirection
=== RUN   TestNewLess/size
=== RUN   TestNewLess/name
=== RUN   TestNewLess/modtime
=== RUN   TestNewLess/size,ascending
=== RUN   TestNewLess/name,asc
=== RUN   TestNewLess/modtime,ascending
=== RUN   TestNewLess/size,descending
=== RUN   TestNewLess/name,desc
=== RUN   TestNewLess/modtime,descending
=== RUN   TestNewLess/modtime,mixed
=== RUN   TestNewLess/modtime,mixed,30
--- PASS: TestNewLess (0.00s)
    --- PASS: TestNewLess/blankOK (0.00s)
    --- PASS: TestNewLess/tooManyParts (0.00s)
    --- PASS: TestNewLess/tooManyParts2 (0.00s)
    --- PASS: TestNewLess/badMixed (0.00s)
    --- PASS: TestNewLess/unknownComparison (0.00s)
    --- PASS: TestNewLess/unknownSortDirection (0.00s)
    --- PASS: TestNewLess/size (0.00s)
    --- PASS: TestNewLess/name (0.00s)
    --- PASS: TestNewLess/modtime (0.00s)
    --- PASS: TestNewLess/size,ascending (0.00s)
    --- PASS: TestNewLess/name,asc (0.00s)
    --- PASS: TestNewLess/modtime,ascending (0.00s)
    --- PASS: TestNewLess/size,descending (0.00s)
    --- PASS: TestNewLess/name,desc (0.00s)
    --- PASS: TestNewLess/modtime,descending (0.00s)
    --- PASS: TestNewLess/modtime,mixed (0.00s)
    --- PASS: TestNewLess/modtime,mixed,30 (0.00s)
=== RUN   TestRcCopy
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcCopy (0.00s)
=== RUN   TestRcMove
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcMove (0.00s)
=== RUN   TestRcSync
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcSync (0.00s)
=== RUN   TestCopyWithDryRun
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:45:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:45:58 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2025/05/08 02:45:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:45:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyWithDryRun (2.42s)
=== RUN   TestCopy
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:46:00 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/05/08 02:46:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:46:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:46:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:46:01 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:46:05 INFO  : sub dir/hello world.rclone_chunk.001_xemqdk: Moved (server-side) to: sub dir/hello world
2025/05/08 02:46:05 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:46:05 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:46:06 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopy (18.20s)
=== RUN   TestCopyMetadata
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:97: Skipping as metadata not supported
--- SKIP: TestCopyMetadata (0.77s)
=== RUN   TestCopyMetadataNoEmptyDirs
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:97: Skipping as metadata not supported
--- SKIP: TestCopyMetadataNoEmptyDirs (0.63s)
=== RUN   TestCopyMissingDirectory
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:46:19 DEBUG : Creating backend with remote "/non-existing"
2025/05/08 02:46:20 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2025/05/08 02:46:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:46:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyMissingDirectory (2.49s)
=== RUN   TestCopyNoTraverse
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:46:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:46:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:46:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:46:22 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:46:27 INFO  : sub dir/hello world.rclone_chunk.001_xenbjk: Moved (server-side) to: sub dir/hello world
2025/05/08 02:46:27 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:46:27 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyNoTraverse (18.32s)
=== RUN   TestCopyCheckFirst
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:46:40 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Running all checks before starting transfers
2025/05/08 02:46:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:46:40 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:46:40 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Checks finished, now starting transfers
2025/05/08 02:46:40 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:46:41 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:46:46 INFO  : sub dir/hello world.rclone_chunk.001_xenuoe: Moved (server-side) to: sub dir/hello world
2025/05/08 02:46:46 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:46:46 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyCheckFirst (18.84s)
=== RUN   TestSyncNoTraverse
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:46:59 ERROR : Ignoring --no-traverse with sync
2025/05/08 02:46:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:46:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:46:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:47:00 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:47:04 INFO  : sub dir/hello world.rclone_chunk.001_xeodl9: Moved (server-side) to: sub dir/hello world
2025/05/08 02:47:04 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:47:04 INFO  : sub dir/hello world: Copied (new)
2025/05/08 02:47:04 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncNoTraverse (17.40s)
=== RUN   TestCopyWithDepth
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:47:17 DEBUG : hello world2: Need to transfer - File not found at Destination
2025/05/08 02:47:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:47:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:47:17 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:47:22 INFO  : hello world2.rclone_chunk.001_xeouxs: Moved (server-side) to: hello world2
2025/05/08 02:47:22 DEBUG : hello world2: md5 = cc2c857f89648dbd139d7b2a6665957d OK
2025/05/08 02:47:22 INFO  : hello world2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyWithDepth (15.23s)
=== RUN   TestCopyWithFilesFrom
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:47:32 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 02:47:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:47:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:47:32 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:47:37 INFO  : potato2.rclone_chunk.001_xep9yg: Moved (server-side) to: potato2
2025/05/08 02:47:37 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:47:37 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyWithFilesFrom (11.15s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:47:43 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 02:47:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:47:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:47:43 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:47:48 INFO  : potato2.rclone_chunk.001_xepk61: Moved (server-side) to: potato2
2025/05/08 02:47:48 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:47:48 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyWithFilesFromAndNoTraverse (12.40s)
=== RUN   TestCopyEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:47:55 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2025/05/08 02:47:55 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/05/08 02:47:55 INFO  : sub dir2: Set directory modification time (using DirSetModTime)
2025/05/08 02:47:55 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/05/08 02:47:56 INFO  : sub dir: Making directory
2025/05/08 02:47:56 INFO  : sub dir2: Making directory
2025/05/08 02:47:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:47:56 INFO  : sub dir2/sub sub dir2: Making directory
2025/05/08 02:47:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:47:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:47:57 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:48:01 INFO  : sub dir/hello world.rclone_chunk.001_xepyz8: Moved (server-side) to: sub dir/hello world
2025/05/08 02:48:01 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:48:01 INFO  : sub dir/hello world: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:48:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestCopyEmptyDirectories (30.69s)
=== RUN   TestCopyNoEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:48:26 INFO  : sub dir2: Making directory
2025/05/08 02:48:26 DEBUG : sub dir2/sub sub dir2: Making directory with metadata
2025/05/08 02:48:26 INFO  : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/05/08 02:48:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:48:26 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:48:26 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:48:26 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:48:31 INFO  : sub dir/hello world.rclone_chunk.001_xeqr7i: Moved (server-side) to: sub dir/hello world
2025/05/08 02:48:31 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:48:31 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoEmptyDirectories (16.11s)
=== RUN   TestMoveEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:48:42 DEBUG : sub dir2: Making directory with metadata
2025/05/08 02:48:42 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/05/08 02:48:42 INFO  : sub dir: Making directory
2025/05/08 02:48:43 INFO  : sub dir2: Making directory
2025/05/08 02:48:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:48:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:48:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:48:44 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:48:51 INFO  : sub dir/hello world.rclone_chunk.001_xer9wl: Moved (server-side) to: sub dir/hello world
2025/05/08 02:48:51 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:48:51 INFO  : sub dir/hello world: Copied (new)
2025/05/08 02:48:51 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:48:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
2025/05/08 02:48:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestMoveEmptyDirectories (30.06s)
=== RUN   TestSyncNoUpdateDirModtime
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:408: Skipping test as backend does not support DirSetModTime
--- SKIP: TestSyncNoUpdateDirModtime (0.86s)
=== RUN   TestMoveNoEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:49:13 INFO  : sub dir2: Making directory
2025/05/08 02:49:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:49:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:49:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:49:14 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:49:19 INFO  : sub dir/hello world.rclone_chunk.001_xes3bv: Moved (server-side) to: sub dir/hello world
2025/05/08 02:49:19 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:49:19 INFO  : sub dir/hello world: Copied (new)
2025/05/08 02:49:19 INFO  : sub dir/hello world: Deleted
--- PASS: TestMoveNoEmptyDirectories (15.73s)
=== RUN   TestSyncEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:49:29 DEBUG : sub dir2: Making directory with metadata
2025/05/08 02:49:29 INFO  : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/05/08 02:49:29 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/05/08 02:49:29 INFO  : sub dir: Making directory
2025/05/08 02:49:29 INFO  : sub dir2: Making directory
2025/05/08 02:49:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:49:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:49:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:49:30 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:49:35 INFO  : sub dir/hello world.rclone_chunk.001_xesjk7: Moved (server-side) to: sub dir/hello world
2025/05/08 02:49:35 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:49:35 INFO  : sub dir/hello world: Copied (new)
2025/05/08 02:49:35 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:49:36 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata
--- PASS: TestSyncEmptyDirectories (23.65s)
=== RUN   TestSyncSetDelayedModTimes
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set
--- SKIP: TestSyncSetDelayedModTimes (0.67s)
=== RUN   TestSyncNoEmptyDirectories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:49:53 INFO  : sub dir2: Making directory
2025/05/08 02:49:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:49:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:49:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:49:54 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:49:58 INFO  : sub dir/hello world.rclone_chunk.001_xet7nf: Moved (server-side) to: sub dir/hello world
2025/05/08 02:49:58 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:49:58 INFO  : sub dir/hello world: Copied (new)
2025/05/08 02:49:58 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoEmptyDirectories (16.67s)
=== RUN   TestServerSideCopy
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:50:15 INFO  : sub dir/hello world.rclone_chunk.001_xetn32: Moved (server-side) to: sub dir/hello world
2025/05/08 02:50:16 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-favumel4bugi"
2025/05/08 02:50:16 DEBUG : Config file has changed externally - reloading
2025/05/08 02:50:16 DEBUG : Creating backend with remote "TestYandex:rclone-test-favumel4bugi"
    sync_test.go:590: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-favumel4bugi'
2025/05/08 02:50:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:50:17 DEBUG : sub dir/hello world: copy non-chunked object...
2025/05/08 02:50:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-favumel4bugi': Waiting for checks to finish
2025/05/08 02:50:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-favumel4bugi': Waiting for transfers to finish
2025/05/08 02:50:19 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:50:19 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:50:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-favumel4bugi': Purge remote
--- PASS: TestServerSideCopy (29.74s)
=== RUN   TestCopyOverSelf
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:50:44 INFO  : sub dir/hello world.rclone_chunk.001_xeuhbg: Moved (server-side) to: sub dir/hello world
2025/05/08 02:50:46 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/05/08 02:50:46 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:50:46 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:50:46 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:50:54 INFO  : sub dir/hello world: Deleted
2025/05/08 02:50:55 INFO  : sub dir/hello world.rclone_chunk.001_xeun7h: Moved (server-side) to: sub dir/hello world
2025/05/08 02:50:55 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK
2025/05/08 02:50:55 INFO  : sub dir/hello world: Copied (replaced existing)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyOverSelf (28.84s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:51:13 INFO  : sub dir/hello world.rclone_chunk.001_xevamp: Moved (server-side) to: sub dir/hello world
2025/05/08 02:51:13 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-fefigir6cusu"
2025/05/08 02:51:13 DEBUG : Creating backend with remote "TestYandex:rclone-test-fefigir6cusu"
    sync_test.go:626: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-fefigir6cusu'
2025/05/08 02:51:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:51:14 DEBUG : sub dir/hello world: copy non-chunked object...
2025/05/08 02:51:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fefigir6cusu': Waiting for checks to finish
2025/05/08 02:51:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fefigir6cusu': Waiting for transfers to finish
2025/05/08 02:51:16 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:51:16 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:51:26 INFO  : sub dir/hello world: Deleted
2025/05/08 02:51:27 INFO  : sub dir/hello world.rclone_chunk.001_xevjo9: Moved (server-side) to: sub dir/hello world
2025/05/08 02:51:28 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/05/08 02:51:28 DEBUG : sub dir/hello world: copy non-chunked object...
2025/05/08 02:51:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fefigir6cusu': Waiting for checks to finish
2025/05/08 02:51:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fefigir6cusu': Waiting for transfers to finish
2025/05/08 02:51:29 DEBUG : sub dir/hello world: server side copy: renaming existing object to "sub dir/hello world.xivisuh4" before starting
2025/05/08 02:51:31 DEBUG : sub dir/hello world.xivisuh4: server side copy: removing renamed existing file after operation
2025/05/08 02:51:36 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK
2025/05/08 02:51:36 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:51:38 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fefigir6cusu': Purge remote
--- PASS: TestServerSideCopyOverSelf (49.25s)
=== RUN   TestMoveOverSelf
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:52:04 INFO  : sub dir/hello world.rclone_chunk.001_xewnaz: Moved (server-side) to: sub dir/hello world
2025/05/08 02:52:05 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:52:05 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/05/08 02:52:05 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:52:05 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:52:12 INFO  : sub dir/hello world: Deleted
2025/05/08 02:52:14 INFO  : sub dir/hello world.rclone_chunk.001_xewuor: Moved (server-side) to: sub dir/hello world
2025/05/08 02:52:14 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK
2025/05/08 02:52:14 INFO  : sub dir/hello world: Copied (replaced existing)
2025/05/08 02:52:14 INFO  : sub dir/hello world: Deleted
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestMoveOverSelf (30.03s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:52:32 INFO  : sub dir/hello world.rclone_chunk.001_xexhed: Moved (server-side) to: sub dir/hello world
2025/05/08 02:52:35 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-janafeh8toju"
2025/05/08 02:52:35 DEBUG : Creating backend with remote "TestYandex:rclone-test-janafeh8toju"
    sync_test.go:671: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju'
2025/05/08 02:52:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:52:36 DEBUG : sub dir/hello world: copy non-chunked object...
2025/05/08 02:52:36 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Waiting for checks to finish
2025/05/08 02:52:36 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Waiting for transfers to finish
2025/05/08 02:52:38 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:52:38 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:52:49 INFO  : sub dir/hello world: Deleted
2025/05/08 02:52:51 INFO  : sub dir/hello world.rclone_chunk.001_xextp0: Moved (server-side) to: sub dir/hello world
2025/05/08 02:52:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Using server-side directory move
2025/05/08 02:52:52 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/05/08 02:52:53 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/05/08 02:52:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Waiting for checks to finish
2025/05/08 02:52:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Waiting for transfers to finish
2025/05/08 02:52:57 INFO  : sub dir/hello world: Deleted
2025/05/08 02:52:57 DEBUG : sub dir/hello world: move non-chunked object...
2025/05/08 02:52:59 INFO  : sub dir/hello world: Moved (server-side)
2025/05/08 02:52:59 INFO  : sub dir/hello world: Moved (server-side)
2025/05/08 02:53:05 INFO  : sub dir/hello world.rclone_chunk.001_xeyd7s: Moved (server-side) to: sub dir/hello world
2025/05/08 02:53:05 DEBUG : testing file moves
2025/05/08 02:53:06 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/05/08 02:53:06 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Waiting for checks to finish
2025/05/08 02:53:06 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Waiting for transfers to finish
2025/05/08 02:53:10 INFO  : sub dir/hello world: Deleted
2025/05/08 02:53:10 DEBUG : sub dir/hello world: move non-chunked object...
2025/05/08 02:53:12 INFO  : sub dir/hello world: Moved (server-side)
2025/05/08 02:53:12 INFO  : sub dir/hello world: Moved (server-side)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:53:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-janafeh8toju': Purge remote
--- PASS: TestServerSideMoveOverSelf (61.19s)
=== RUN   TestCopyAfterDelete
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:53:34 INFO  : sub dir/hello world.rclone_chunk.001_xez651: Moved (server-side) to: sub dir/hello world
2025/05/08 02:53:34 ERROR : : error listing: directory not found
2025/05/08 02:53:34 INFO  : Local file system at /tmp/rclone3392324280: Making directory
2025/05/08 02:53:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:53:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyAfterDelete (19.44s)
=== RUN   TestCopyRedownload
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:53:52 INFO  : sub dir/hello world.rclone_chunk.001_xezpcc: Moved (server-side) to: sub dir/hello world
2025/05/08 02:53:54 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2025/05/08 02:53:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 02:53:54 DEBUG : Local file system at /tmp/rclone3392324280: Waiting for checks to finish
2025/05/08 02:53:54 DEBUG : Local file system at /tmp/rclone3392324280: Waiting for transfers to finish
2025/05/08 02:53:55 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 02:53:55 DEBUG : sub dir/hello world.7ddbfcba.partial: renamed to: sub dir/hello world
2025/05/08 02:53:55 INFO  : sub dir/hello world: Copied (new)
2025/05/08 02:53:55 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyRedownload (18.49s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:54:07 DEBUG : check sum: Need to transfer - File not found at Destination
2025/05/08 02:54:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:54:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:54:07 DEBUG : check sum: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:54:12 INFO  : check sum.rclone_chunk.001_xf0817: Moved (server-side) to: check sum
2025/05/08 02:54:12 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/05/08 02:54:12 INFO  : check sum: Copied (new)
2025/05/08 02:54:12 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:54:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:54:13 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/05/08 02:54:13 DEBUG : check sum: Size and md5 of src and dst objects identical
2025/05/08 02:54:13 DEBUG : check sum: Unchanged skipping
2025/05/08 02:54:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:54:13 DEBUG : Waiting for deletions to finish
2025/05/08 02:54:13 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncBasedOnCheckSum (13.74s)
=== RUN   TestSyncSizeOnly
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:54:21 DEBUG : sizeonly: Need to transfer - File not found at Destination
2025/05/08 02:54:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:54:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:54:22 DEBUG : sizeonly: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:54:27 INFO  : sizeonly.rclone_chunk.001_xf0nf9: Moved (server-side) to: sizeonly
2025/05/08 02:54:27 DEBUG : sizeonly: md5 = 8ee2027983915ec78acc45027d874316 OK
2025/05/08 02:54:27 INFO  : sizeonly: Copied (new)
2025/05/08 02:54:27 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:54:28 DEBUG : sizeonly: Sizes identical
2025/05/08 02:54:28 DEBUG : sizeonly: Unchanged skipping
2025/05/08 02:54:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:54:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:54:28 DEBUG : Waiting for deletions to finish
2025/05/08 02:54:28 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncSizeOnly (14.49s)
=== RUN   TestSyncIgnoreSize
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:54:35 DEBUG : ignore-size: Need to transfer - File not found at Destination
2025/05/08 02:54:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:54:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:54:36 DEBUG : ignore-size: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:54:40 INFO  : ignore-size.rclone_chunk.001_xf11fo: Moved (server-side) to: ignore-size
2025/05/08 02:54:40 DEBUG : ignore-size: md5 = 98bf7d8c15784f0a3d63204441e1e2aa OK
2025/05/08 02:54:40 INFO  : ignore-size: Copied (new)
2025/05/08 02:54:40 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:54:41 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 02:54:41 DEBUG : ignore-size: Unchanged skipping
2025/05/08 02:54:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:54:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:54:41 DEBUG : Waiting for deletions to finish
2025/05/08 02:54:41 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncIgnoreSize (13.41s)
=== RUN   TestSyncIgnoreTimes
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:54:53 INFO  : existing.rclone_chunk.001_xf1egc: Moved (server-side) to: existing
2025/05/08 02:54:54 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 02:54:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:54:54 DEBUG : existing: Unchanged skipping
2025/05/08 02:54:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:54:54 DEBUG : Waiting for deletions to finish
2025/05/08 02:54:54 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:54:54 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2025/05/08 02:54:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:54:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:54:54 DEBUG : existing: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:55:03 INFO  : existing: Deleted
2025/05/08 02:55:04 INFO  : existing.rclone_chunk.001_xf1jj8: Moved (server-side) to: existing
2025/05/08 02:55:04 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK
2025/05/08 02:55:04 INFO  : existing: Copied (replaced existing)
2025/05/08 02:55:04 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncIgnoreTimes (23.46s)
=== RUN   TestSyncIgnoreExisting
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:55:12 DEBUG : existing: Need to transfer - File not found at Destination
2025/05/08 02:55:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:55:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:55:12 DEBUG : existing: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:55:17 INFO  : existing.rclone_chunk.001_xf216t: Moved (server-side) to: existing
2025/05/08 02:55:17 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK
2025/05/08 02:55:17 INFO  : existing: Copied (new)
2025/05/08 02:55:17 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:55:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:55:18 DEBUG : existing: Destination exists, skipping
2025/05/08 02:55:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:55:18 DEBUG : Waiting for deletions to finish
2025/05/08 02:55:18 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncIgnoreExisting (13.33s)
=== RUN   TestSyncIgnoreErrors
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:55:30 INFO  : b/potato.rclone_chunk.001_xf2ejo: Moved (server-side) to: b/potato
2025/05/08 02:55:35 INFO  : c/non empty space.rclone_chunk.001_xf2j2t: Moved (server-side) to: c/non empty space
2025/05/08 02:55:35 INFO  : d: Making directory
2025/05/08 02:55:36 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/05/08 02:55:37 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 02:55:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:55:37 DEBUG : c/non empty space: Unchanged skipping
2025/05/08 02:55:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:55:37 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:55:42 INFO  : a/potato2.rclone_chunk.001_xf2qi6: Moved (server-side) to: a/potato2.rclone_chunk.001
2025/05/08 02:55:44 INFO  : a/potato2.rclone_chunk.002_xf2qi6: Moved (server-side) to: a/potato2.rclone_chunk.002
2025/05/08 02:55:47 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/08 02:55:47 INFO  : a/potato2: Copied (new)
2025/05/08 02:55:47 DEBUG : Waiting for deletions to finish
2025/05/08 02:55:51 INFO  : b/potato: Deleted
2025/05/08 02:55:51 INFO  : d: Removing directory
2025/05/08 02:55:57 INFO  : b: Removing directory
2025/05/08 02:56:03 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': deleted 2 directories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncIgnoreErrors (71.14s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:56:41 INFO  : empty space.rclone_chunk.001_xf4ds1: Moved (server-side) to: empty space
2025/05/08 02:56:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:56:41 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC
2025/05/08 02:56:41 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/05/08 02:56:41 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1)
2025/05/08 02:56:41 DEBUG : empty space: Unchanged skipping
2025/05/08 02:56:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:56:41 DEBUG : Waiting for deletions to finish
2025/05/08 02:56:41 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:56:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:56:42 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC
2025/05/08 02:56:42 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/05/08 02:56:43 INFO  : empty space: Updated modification time in destination
2025/05/08 02:56:43 DEBUG : empty space: Unchanged skipping
2025/05/08 02:56:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:56:43 DEBUG : Waiting for deletions to finish
2025/05/08 02:56:43 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterChangingModtimeOnly (14.56s)
=== RUN   TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:56:56 INFO  : empty space.rclone_chunk.001_xf4sug: Moved (server-side) to: empty space
2025/05/08 02:56:57 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC
2025/05/08 02:56:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:56:57 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK
2025/05/08 02:56:57 DEBUG : empty space: Unchanged skipping
2025/05/08 02:56:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:56:57 DEBUG : Waiting for deletions to finish
2025/05/08 02:56:57 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (12.93s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:57:09 INFO  : foo.rclone_chunk.001_xf55nr: Moved (server-side) to: foo
2025/05/08 02:57:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:57:09 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC
2025/05/08 02:57:09 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone3392324280)
2025/05/08 02:57:09 DEBUG : foo: md5 = 37b51d194a7513e45b56f6524f2d51f2 (Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june')
2025/05/08 02:57:09 DEBUG : foo: md5 differ
2025/05/08 02:57:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:57:09 DEBUG : foo: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:57:17 INFO  : foo: Deleted
2025/05/08 02:57:18 INFO  : foo.rclone_chunk.001_xf5a5z: Moved (server-side) to: foo
2025/05/08 02:57:18 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 OK
2025/05/08 02:57:18 INFO  : foo: Copied (replaced existing)
2025/05/08 02:57:18 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncDoesntUpdateModtime (20.82s)
=== RUN   TestSyncAfterAddingAFile
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:57:27 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/05/08 02:57:27 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/05/08 02:57:27 DEBUG : pacer: Reducing sleep to 15ms
2025/05/08 02:57:27 DEBUG : pacer: Reducing sleep to 11.25ms
2025/05/08 02:57:27 DEBUG : pacer: Reducing sleep to 10ms
2025/05/08 02:57:29 INFO  : empty space.rclone_chunk.001_xf5q6h: Moved (server-side) to: empty space
2025/05/08 02:57:30 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 02:57:30 DEBUG : potato: Need to transfer - File not found at Destination
2025/05/08 02:57:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:57:30 DEBUG : empty space: Unchanged skipping
2025/05/08 02:57:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:57:30 DEBUG : potato: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:57:37 INFO  : potato.rclone_chunk.001_xf5vsa: Moved (server-side) to: potato.rclone_chunk.001
2025/05/08 02:57:39 INFO  : potato.rclone_chunk.002_xf5vsa: Moved (server-side) to: potato.rclone_chunk.002
2025/05/08 02:57:43 DEBUG : potato: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/08 02:57:43 INFO  : potato: Copied (new)
2025/05/08 02:57:43 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterAddingAFile (40.26s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:58:11 INFO  : potato.rclone_chunk.001_xf6uuu: Moved (server-side) to: potato.rclone_chunk.001
2025/05/08 02:58:12 INFO  : potato.rclone_chunk.002_xf6uuu: Moved (server-side) to: potato.rclone_chunk.002
2025/05/08 02:58:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:58:16 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2025/05/08 02:58:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:58:16 DEBUG : potato: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:58:32 INFO  : potato: Deleted
2025/05/08 02:58:34 INFO  : potato.rclone_chunk.001_xf75hf: Moved (server-side) to: potato
2025/05/08 02:58:34 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 OK
2025/05/08 02:58:34 INFO  : potato: Copied (replaced existing)
2025/05/08 02:58:34 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterChangingFilesSizeOnly (35.62s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:58:45 INFO  : potato.rclone_chunk.001_xf7uc5: Moved (server-side) to: potato
2025/05/08 02:58:45 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
2025/05/08 02:58:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:58:45 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone3392324280)
2025/05/08 02:58:45 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 (Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june')
2025/05/08 02:58:45 DEBUG : potato: md5 differ
2025/05/08 02:58:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:58:45 DEBUG : potato: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:58:53 INFO  : potato: Deleted
2025/05/08 02:58:55 INFO  : potato.rclone_chunk.001_xf7yin: Moved (server-side) to: potato
2025/05/08 02:58:55 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 OK
2025/05/08 02:58:55 INFO  : potato: Copied (replaced existing)
2025/05/08 02:58:55 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterChangingContentsOnly (21.08s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:59:06 INFO  : potato.rclone_chunk.001_xf8ftt: Moved (server-side) to: potato
2025/05/08 02:59:11 INFO  : empty space.rclone_chunk.001_xf8kcc: Moved (server-side) to: empty space
2025/05/08 02:59:11 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 02:59:11 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2025/05/08 02:59:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:59:11 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 02:59:11 DEBUG : empty space: Unchanged skipping
2025/05/08 02:59:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:59:11 DEBUG : Waiting for deletions to finish
2025/05/08 02:59:11 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (21.41s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 02:59:28 INFO  : potato.rclone_chunk.001_xf900n: Moved (server-side) to: potato
2025/05/08 02:59:33 INFO  : empty space.rclone_chunk.001_xf959y: Moved (server-side) to: empty space
2025/05/08 02:59:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 02:59:34 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 02:59:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 02:59:34 DEBUG : empty space: Unchanged skipping
2025/05/08 02:59:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 02:59:34 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 02:59:42 INFO  : potato2.rclone_chunk.001_xf9bro: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/08 02:59:43 INFO  : potato2.rclone_chunk.002_xf9bro: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/08 02:59:46 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/08 02:59:46 INFO  : potato2: Copied (new)
2025/05/08 02:59:46 DEBUG : Waiting for deletions to finish
2025/05/08 02:59:51 INFO  : potato: Deleted
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (51.48s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:00:20 INFO  : b/potato.rclone_chunk.001_xfag0e: Moved (server-side) to: b/potato
2025/05/08 03:00:25 INFO  : c/non empty space.rclone_chunk.001_xfalaw: Moved (server-side) to: c/non empty space
2025/05/08 03:00:25 INFO  : d: Making directory
2025/05/08 03:00:25 INFO  : d/e: Making directory
2025/05/08 03:00:27 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/05/08 03:00:28 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:00:28 DEBUG : c/non empty space: Unchanged skipping
2025/05/08 03:00:28 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:00:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:00:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:00:35 INFO  : a/potato2.rclone_chunk.001_xfat1f: Moved (server-side) to: a/potato2.rclone_chunk.001
2025/05/08 03:00:36 INFO  : a/potato2.rclone_chunk.002_xfat1f: Moved (server-side) to: a/potato2.rclone_chunk.002
2025/05/08 03:00:40 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/08 03:00:40 INFO  : a/potato2: Copied (new)
2025/05/08 03:00:40 DEBUG : Waiting for deletions to finish
2025/05/08 03:00:45 INFO  : b/potato: Deleted
2025/05/08 03:00:45 INFO  : d/e: Removing directory
2025/05/08 03:00:50 INFO  : d: Removing directory
2025/05/08 03:00:56 INFO  : b: Removing directory
2025/05/08 03:01:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': deleted 3 directories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (78.04s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:01:37 INFO  : b/potato.rclone_chunk.001_xfcmjq: Moved (server-side) to: b/potato
2025/05/08 03:01:42 INFO  : c/non empty space.rclone_chunk.001_xfcrgq: Moved (server-side) to: c/non empty space
2025/05/08 03:01:42 INFO  : d: Making directory
2025/05/08 03:01:44 DEBUG : a/potato2: Need to transfer - File not found at Destination
2025/05/08 03:01:44 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:01:44 DEBUG : c/non empty space: Unchanged skipping
2025/05/08 03:01:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:01:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:01:45 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:01:50 INFO  : a/potato2.rclone_chunk.001_xfcy2h: Moved (server-side) to: a/potato2.rclone_chunk.001
2025/05/08 03:01:52 INFO  : a/potato2.rclone_chunk.002_xfcy2h: Moved (server-side) to: a/potato2.rclone_chunk.002
2025/05/08 03:01:54 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/08 03:01:54 INFO  : a/potato2: Copied (new)
2025/05/08 03:01:54 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': not deleting files as there were IO errors
2025/05/08 03:01:54 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': not deleting directories as there were IO errors
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (70.54s)
=== RUN   TestSyncDeleteAfter
--- PASS: TestSyncDeleteAfter (0.00s)
=== RUN   TestSyncDeleteDuring
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:02:48 INFO  : potato.rclone_chunk.001_xfek71: Moved (server-side) to: potato
2025/05/08 03:02:53 INFO  : empty space.rclone_chunk.001_xfep8t: Moved (server-side) to: empty space
2025/05/08 03:02:53 DEBUG : Waiting for deletions to finish
2025/05/08 03:02:54 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:02:54 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 03:02:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:02:54 DEBUG : empty space: Unchanged skipping
2025/05/08 03:02:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:02:54 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:02:58 INFO  : potato: Deleted
2025/05/08 03:03:00 INFO  : potato2.rclone_chunk.001_xfev2v: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/08 03:03:01 INFO  : potato2.rclone_chunk.002_xfev2v: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/08 03:03:04 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/08 03:03:04 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncDeleteDuring (41.29s)
=== RUN   TestSyncDeleteBefore
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:03:29 INFO  : potato.rclone_chunk.001_xffqzw: Moved (server-side) to: potato
2025/05/08 03:03:33 INFO  : empty space.rclone_chunk.001_xffusi: Moved (server-side) to: empty space
2025/05/08 03:03:34 DEBUG : Waiting for deletions to finish
2025/05/08 03:03:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:03:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:03:38 INFO  : potato: Deleted
2025/05/08 03:03:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:03:39 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 03:03:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:03:39 DEBUG : empty space: Unchanged skipping
2025/05/08 03:03:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:03:39 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:03:46 INFO  : potato2.rclone_chunk.001_xfg4px: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/08 03:03:48 INFO  : potato2.rclone_chunk.002_xfg4px: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/08 03:03:51 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2025/05/08 03:03:51 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncDeleteBefore (47.80s)
=== RUN   TestCopyDeleteBefore
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:04:17 INFO  : potato.rclone_chunk.001_xfh116: Moved (server-side) to: potato
2025/05/08 03:04:17 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 03:04:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:04:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:04:18 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:04:21 INFO  : potato2.rclone_chunk.001_xfh7fd: Moved (server-side) to: potato2
2025/05/08 03:04:21 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK
2025/05/08 03:04:21 INFO  : potato2: Copied (new)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestCopyDeleteBefore (20.62s)
=== RUN   TestSyncWithExclude
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:04:39 INFO  : potato2.rclone_chunk.001_xfhm21: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/08 03:04:41 INFO  : potato2.rclone_chunk.002_xfhm21: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/08 03:04:49 INFO  : empty space.rclone_chunk.001_xfhx13: Moved (server-side) to: empty space
2025/05/08 03:04:50 DEBUG : enormous: Excluded (Size Filter)
2025/05/08 03:04:50 DEBUG : enormous: Excluded
2025/05/08 03:04:50 DEBUG : potato2: Excluded (Size Filter)
2025/05/08 03:04:50 DEBUG : potato2: Excluded
2025/05/08 03:04:51 DEBUG : potato2: Excluded (Size Filter)
2025/05/08 03:04:51 DEBUG : potato2: Excluded
2025/05/08 03:04:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:04:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:04:51 DEBUG : empty space: Unchanged skipping
2025/05/08 03:04:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:04:51 DEBUG : Waiting for deletions to finish
2025/05/08 03:04:51 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:04:51 DEBUG : potato2: Excluded (Size Filter)
2025/05/08 03:04:51 DEBUG : potato2: Excluded
2025/05/08 03:04:52 DEBUG : enormous: Excluded (Size Filter)
2025/05/08 03:04:52 DEBUG : enormous: Excluded
2025/05/08 03:04:52 DEBUG : potato2: Excluded (Size Filter)
2025/05/08 03:04:52 DEBUG : potato2: Excluded
2025/05/08 03:04:52 DEBUG : potato2: Excluded (Size Filter)
2025/05/08 03:04:52 DEBUG : potato2: Excluded
2025/05/08 03:04:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:04:52 DEBUG : Local file system at /tmp/rclone3392324280: Waiting for checks to finish
2025/05/08 03:04:52 DEBUG : empty space: Unchanged skipping
2025/05/08 03:04:52 DEBUG : Local file system at /tmp/rclone3392324280: Waiting for transfers to finish
2025/05/08 03:04:52 DEBUG : Waiting for deletions to finish
2025/05/08 03:04:52 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:04:53 DEBUG : potato2: Excluded (Size Filter)
2025/05/08 03:04:53 DEBUG : potato2: Excluded
--- PASS: TestSyncWithExclude (40.36s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:05:20 INFO  : potato2.rclone_chunk.001_xfiqm9: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/08 03:05:21 INFO  : potato2.rclone_chunk.002_xfiqm9: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/08 03:05:28 INFO  : empty space.rclone_chunk.001_xfj0ga: Moved (server-side) to: empty space
2025/05/08 03:05:34 INFO  : enormous.rclone_chunk.001_xfj5kz: Moved (server-side) to: enormous.rclone_chunk.001
2025/05/08 03:05:36 INFO  : enormous.rclone_chunk.002_xfj5kz: Moved (server-side) to: enormous.rclone_chunk.002
2025/05/08 03:05:40 DEBUG : enormous: Excluded (Size Filter)
2025/05/08 03:05:40 DEBUG : enormous: Excluded
2025/05/08 03:05:40 DEBUG : potato2: Excluded (Size Filter)
2025/05/08 03:05:40 DEBUG : potato2: Excluded
2025/05/08 03:05:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:05:40 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:05:40 DEBUG : empty space: Unchanged skipping
2025/05/08 03:05:40 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:05:40 DEBUG : Waiting for deletions to finish
2025/05/08 03:05:53 INFO  : enormous: Deleted
2025/05/08 03:05:54 INFO  : potato2: Deleted
2025/05/08 03:05:54 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:05:55 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:05:55 DEBUG : empty space: Unchanged skipping
2025/05/08 03:05:55 DEBUG : Local file system at /tmp/rclone3392324280: Waiting for checks to finish
2025/05/08 03:05:55 DEBUG : Local file system at /tmp/rclone3392324280: Waiting for transfers to finish
2025/05/08 03:05:55 DEBUG : Waiting for deletions to finish
2025/05/08 03:05:55 INFO  : potato2: Deleted
2025/05/08 03:05:55 INFO  : enormous: Deleted
2025/05/08 03:05:55 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncWithExcludeAndDeleteExcluded (49.37s)
=== RUN   TestSyncWithUpdateOlder
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:06:07 INFO  : one.rclone_chunk.001_xfk4rn: Moved (server-side) to: one
2025/05/08 03:06:12 INFO  : two.rclone_chunk.001_xfk8px: Moved (server-side) to: two
2025/05/08 03:06:18 INFO  : three.rclone_chunk.001_xfkdaf: Moved (server-side) to: three
2025/05/08 03:06:22 INFO  : four.rclone_chunk.001_xfkjnf: Moved (server-side) to: four
2025/05/08 03:06:23 DEBUG : five: Need to transfer - File not found at Destination
2025/05/08 03:06:23 DEBUG : four: Sizes differ (src 4 vs dst 8)
2025/05/08 03:06:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:06:23 DEBUG : one: Destination is newer than source, skipping
2025/05/08 03:06:23 DEBUG : three: Sizes identical
2025/05/08 03:06:23 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2025/05/08 03:06:23 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2025/05/08 03:06:23 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone3392324280)
2025/05/08 03:06:23 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june')
2025/05/08 03:06:23 DEBUG : two: md5 differ
2025/05/08 03:06:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:06:23 DEBUG : four: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:06:23 DEBUG : two: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:06:23 DEBUG : five: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:06:28 INFO  : five.rclone_chunk.001_xfkor9: Moved (server-side) to: five
2025/05/08 03:06:28 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK
2025/05/08 03:06:28 INFO  : five: Copied (new)
2025/05/08 03:06:31 INFO  : four: Deleted
2025/05/08 03:06:31 INFO  : two: Deleted
2025/05/08 03:06:33 INFO  : two.rclone_chunk.001_xfkok3: Moved (server-side) to: two
2025/05/08 03:06:33 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2025/05/08 03:06:33 INFO  : two: Copied (replaced existing)
2025/05/08 03:06:33 INFO  : four.rclone_chunk.001_xfkows: Moved (server-side) to: four
2025/05/08 03:06:33 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK
2025/05/08 03:06:33 INFO  : four: Copied (replaced existing)
2025/05/08 03:06:33 DEBUG : Waiting for deletions to finish
2025/05/08 03:06:34 DEBUG : one: Destination is newer than source, skipping
2025/05/08 03:06:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:06:34 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK
2025/05/08 03:06:34 DEBUG : five: Size and md5 of src and dst objects identical
2025/05/08 03:06:34 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping
2025/05/08 03:06:34 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2025/05/08 03:06:34 DEBUG : two: Size and md5 of src and dst objects identical
2025/05/08 03:06:34 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping
2025/05/08 03:06:34 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone3392324280)
2025/05/08 03:06:34 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june')
2025/05/08 03:06:34 DEBUG : three: md5 differ
2025/05/08 03:06:34 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring
2025/05/08 03:06:34 DEBUG : three: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:06:34 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK
2025/05/08 03:06:34 DEBUG : four: Size and md5 of src and dst objects identical
2025/05/08 03:06:34 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping
2025/05/08 03:06:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:06:42 INFO  : three: Deleted
2025/05/08 03:06:43 INFO  : three.rclone_chunk.001_xfkzfe: Moved (server-side) to: three
2025/05/08 03:06:43 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK
2025/05/08 03:06:43 INFO  : three: Copied (replaced existing)
2025/05/08 03:06:43 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithUpdateOlder (66.21s)
=== RUN   TestSyncWithMaxDuration
=== RUN   TestSyncWithMaxDuration/Hard
    sync_test.go:1466: Skipping test on non local remote
=== RUN   TestSyncWithMaxDuration/Soft
    sync_test.go:1466: Skipping test on non local remote
--- PASS: TestSyncWithMaxDuration (0.00s)
    --- SKIP: TestSyncWithMaxDuration/Hard (0.00s)
    --- SKIP: TestSyncWithMaxDuration/Soft (0.00s)
=== RUN   TestSyncWithTrackRenames
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:1538: Can track renames: true
2025/05/08 03:07:09 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Making map for --track-renames
2025/05/08 03:07:09 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Finished making map for --track-renames
2025/05/08 03:07:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:07:09 DEBUG : potato: Need to transfer - No matching file found at Destination
2025/05/08 03:07:09 DEBUG : yam: Need to transfer - No matching file found at Destination
2025/05/08 03:07:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for renames to finish
2025/05/08 03:07:09 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:07:09 DEBUG : potato: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:07:09 DEBUG : yam: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:07:13 INFO  : yam.rclone_chunk.001_xfly2x: Moved (server-side) to: yam
2025/05/08 03:07:13 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK
2025/05/08 03:07:13 INFO  : yam: Copied (new)
2025/05/08 03:07:14 INFO  : potato.rclone_chunk.001_xfly6s: Moved (server-side) to: potato
2025/05/08 03:07:14 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK
2025/05/08 03:07:14 INFO  : potato: Copied (new)
2025/05/08 03:07:14 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:07:15 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:07:15 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Making map for --track-renames
2025/05/08 03:07:15 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Finished making map for --track-renames
2025/05/08 03:07:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:07:15 DEBUG : potato: Unchanged skipping
2025/05/08 03:07:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for renames to finish
2025/05/08 03:07:15 DEBUG : yam: move non-chunked object...
2025/05/08 03:07:21 INFO  : yam: Moved (server-side) to: yaml
2025/05/08 03:07:21 INFO  : yam: Moved (server-side) to: yaml
2025/05/08 03:07:21 INFO  : yaml: Renamed from "yam"
2025/05/08 03:07:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:07:21 DEBUG : Waiting for deletions to finish
2025/05/08 03:07:21 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncWithTrackRenames (24.25s)
=== RUN   TestParseRenamesStrategyModtime
--- PASS: TestParseRenamesStrategyModtime (0.00s)
=== RUN   TestRenamesStrategyModtime
--- PASS: TestRenamesStrategyModtime (0.00s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:1610: Can track renames: true
2025/05/08 03:07:33 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Making map for --track-renames
2025/05/08 03:07:33 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Finished making map for --track-renames
2025/05/08 03:07:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:07:33 DEBUG : potato: Need to transfer - No matching file found at Destination
2025/05/08 03:07:33 DEBUG : yam: Need to transfer - No matching file found at Destination
2025/05/08 03:07:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for renames to finish
2025/05/08 03:07:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:07:33 DEBUG : yam: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:07:33 DEBUG : potato: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:07:38 INFO  : yam.rclone_chunk.001_xfmmo4: Moved (server-side) to: yam
2025/05/08 03:07:38 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK
2025/05/08 03:07:38 INFO  : yam: Copied (new)
2025/05/08 03:07:38 INFO  : potato.rclone_chunk.001_xfmmgo: Moved (server-side) to: potato
2025/05/08 03:07:38 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK
2025/05/08 03:07:38 INFO  : potato: Copied (new)
2025/05/08 03:07:38 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:07:39 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:07:39 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Making map for --track-renames
2025/05/08 03:07:39 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Finished making map for --track-renames
2025/05/08 03:07:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:07:39 DEBUG : potato: Unchanged skipping
2025/05/08 03:07:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for renames to finish
2025/05/08 03:07:39 DEBUG : yam: move non-chunked object...
2025/05/08 03:07:42 INFO  : yam: Moved (server-side) to: yaml
2025/05/08 03:07:42 INFO  : yam: Moved (server-side) to: yaml
2025/05/08 03:07:42 INFO  : yaml: Renamed from "yam"
2025/05/08 03:07:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:07:42 DEBUG : Waiting for deletions to finish
2025/05/08 03:07:42 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncWithTrackRenamesStrategyModtime (20.48s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:1649: Can track renames: true
2025/05/08 03:07:54 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Making map for --track-renames
2025/05/08 03:07:54 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Finished making map for --track-renames
2025/05/08 03:07:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:07:54 DEBUG : potato: Need to transfer - No matching file found at Destination
2025/05/08 03:07:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for renames to finish
2025/05/08 03:07:54 DEBUG : sub/yam: Need to transfer - No matching file found at Destination
2025/05/08 03:07:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:07:54 DEBUG : potato: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:07:54 DEBUG : sub/yam: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:07:58 INFO  : potato.rclone_chunk.001_xfn7qo: Moved (server-side) to: potato
2025/05/08 03:07:58 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK
2025/05/08 03:07:58 INFO  : potato: Copied (new)
2025/05/08 03:07:59 INFO  : sub/yam.rclone_chunk.001_xfn73o: Moved (server-side) to: sub/yam
2025/05/08 03:07:59 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK
2025/05/08 03:07:59 INFO  : sub/yam: Copied (new)
2025/05/08 03:07:59 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:08:00 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:08:00 DEBUG : potato: Unchanged skipping
2025/05/08 03:08:01 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Making map for --track-renames
2025/05/08 03:08:01 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Finished making map for --track-renames
2025/05/08 03:08:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:08:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for renames to finish
2025/05/08 03:08:01 DEBUG : sub/yam: move non-chunked object...
2025/05/08 03:08:03 INFO  : sub/yam: Moved (server-side) to: yam
2025/05/08 03:08:03 INFO  : sub/yam: Moved (server-side) to: yam
2025/05/08 03:08:03 INFO  : yam: Renamed from "sub/yam"
2025/05/08 03:08:03 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:08:03 DEBUG : Waiting for deletions to finish
2025/05/08 03:08:03 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (26.89s)
=== RUN   TestServerSideMoveLocal
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:08:20 DEBUG : Creating backend with remote "/tmp/rclone3392324280/dir1"
2025/05/08 03:08:20 DEBUG : Creating backend with remote "/tmp/rclone3392324280/dir2"
2025/05/08 03:08:20 DEBUG : Local file system at /tmp/rclone3392324280/dir2: Using server-side directory move
2025/05/08 03:08:20 INFO  : Local file system at /tmp/rclone3392324280/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/05/08 03:08:20 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/05/08 03:08:20 DEBUG : Local file system at /tmp/rclone3392324280/dir2: Waiting for checks to finish
2025/05/08 03:08:20 DEBUG : Local file system at /tmp/rclone3392324280/dir2: Waiting for transfers to finish
2025/05/08 03:08:20 INFO  : file1.txt: Moved (server-side)
--- PASS: TestServerSideMoveLocal (0.64s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:08:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 03:08:21 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2025/05/08 03:08:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:08:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:08:21 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:08:21 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:08:26 INFO  : sub dir/hello world.rclone_chunk.001_xfnyz6: Moved (server-side) to: sub dir/hello world
2025/05/08 03:08:26 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 03:08:26 INFO  : sub dir/hello world: Copied (new)
2025/05/08 03:08:26 INFO  : sub dir/hello world: Deleted
2025/05/08 03:08:27 INFO  : nested/sub dir/file.rclone_chunk.001_xfnyy8: Moved (server-side) to: nested/sub dir/file
2025/05/08 03:08:27 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK
2025/05/08 03:08:27 INFO  : nested/sub dir/file: Copied (new)
2025/05/08 03:08:27 INFO  : nested/sub dir/file: Deleted
2025/05/08 03:08:27 INFO  : sub dir: Removing directory
2025/05/08 03:08:27 INFO  : nested/sub dir: Removing directory
2025/05/08 03:08:27 INFO  : nested: Removing directory
2025/05/08 03:08:27 DEBUG : Local file system at /tmp/rclone3392324280: deleted 3 directories
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestMoveWithDeleteEmptySrcDirs (37.22s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:08:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/05/08 03:08:58 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination
2025/05/08 03:08:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:08:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:08:59 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:08:59 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:09:03 INFO  : sub dir/hello world.rclone_chunk.001_xfp02t: Moved (server-side) to: sub dir/hello world
2025/05/08 03:09:03 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK
2025/05/08 03:09:03 INFO  : sub dir/hello world: Copied (new)
2025/05/08 03:09:03 INFO  : sub dir/hello world: Deleted
2025/05/08 03:09:05 INFO  : nested/sub dir/file.rclone_chunk.001_xfp0qf: Moved (server-side) to: nested/sub dir/file
2025/05/08 03:09:05 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK
2025/05/08 03:09:05 INFO  : nested/sub dir/file: Copied (new)
2025/05/08 03:09:05 INFO  : nested/sub dir/file: Deleted
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (36.09s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:09:34 DEBUG : existing: Need to transfer - File not found at Destination
2025/05/08 03:09:34 DEBUG : existing-b: Need to transfer - File not found at Destination
2025/05/08 03:09:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:09:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:09:35 DEBUG : existing: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:09:35 DEBUG : existing-b: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:09:39 INFO  : existing-b.rclone_chunk.001_xfq05a: Moved (server-side) to: existing-b
2025/05/08 03:09:39 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK
2025/05/08 03:09:39 INFO  : existing-b: Copied (new)
2025/05/08 03:09:39 INFO  : existing-b: Deleted
2025/05/08 03:09:43 INFO  : existing.rclone_chunk.001_xfq0ln: Moved (server-side) to: existing
2025/05/08 03:09:43 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK
2025/05/08 03:09:43 INFO  : existing: Copied (new)
2025/05/08 03:09:43 INFO  : existing: Deleted
2025/05/08 03:09:43 DEBUG : existing: Destination exists, skipping
2025/05/08 03:09:43 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2025/05/08 03:09:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:09:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:09:43 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestMoveWithIgnoreExisting (21.36s)
=== RUN   TestServerSideMove
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:09:56 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-zimulir6jeci"
2025/05/08 03:09:56 DEBUG : Creating backend with remote "TestYandex:rclone-test-zimulir6jeci"
2025/05/08 03:10:03 INFO  : potato2.rclone_chunk.001_xfqlhs: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/08 03:10:04 INFO  : potato2.rclone_chunk.002_xfqlhs: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/08 03:10:11 INFO  : empty space.rclone_chunk.001_xfqwp3: Moved (server-side) to: empty space
2025/05/08 03:10:18 INFO  : potato3.rclone_chunk.001_xfr15t: Moved (server-side) to: potato3.rclone_chunk.001
2025/05/08 03:10:19 INFO  : potato3.rclone_chunk.002_xfr15t: Moved (server-side) to: potato3.rclone_chunk.002
    sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-zimulir6jeci'
2025/05/08 03:10:32 INFO  : empty space.rclone_chunk.001_xfrd56: Moved (server-side) to: empty space
2025/05/08 03:10:39 INFO  : potato3.rclone_chunk.001_xfrlrm: Moved (server-side) to: potato3.rclone_chunk.001
2025/05/08 03:10:40 INFO  : potato3.rclone_chunk.002_xfrlrm: Moved (server-side) to: potato3.rclone_chunk.002
2025/05/08 03:10:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-zimulir6jeci': Using server-side directory move
2025/05/08 03:10:43 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-zimulir6jeci': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/05/08 03:10:44 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 03:10:44 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:10:44 DEBUG : empty space: Unchanged skipping
2025/05/08 03:10:44 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/05/08 03:10:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-zimulir6jeci': Waiting for checks to finish
2025/05/08 03:10:44 DEBUG : potato2: move 2 data chunks...
2025/05/08 03:10:46 INFO  : potato2.rclone_chunk.001: Moved (server-side)
2025/05/08 03:10:47 INFO  : potato2.rclone_chunk.002: Moved (server-side)
2025/05/08 03:10:48 INFO  : empty space: Deleted
2025/05/08 03:10:48 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-zimulir6jeci': Waiting for transfers to finish
2025/05/08 03:10:49 INFO  : potato2: Moved (server-side)
2025/05/08 03:10:51 INFO  : potato2: Moved (server-side)
2025/05/08 03:10:58 INFO  : potato3: Deleted
2025/05/08 03:10:59 DEBUG : potato3: move 2 data chunks...
2025/05/08 03:11:00 INFO  : potato3.rclone_chunk.001: Moved (server-side)
2025/05/08 03:11:02 INFO  : potato3.rclone_chunk.002: Moved (server-side)
2025/05/08 03:11:03 INFO  : potato3: Moved (server-side)
2025/05/08 03:11:06 INFO  : potato3: Moved (server-side)
2025/05/08 03:11:07 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-leworec7xeca"
2025/05/08 03:11:07 DEBUG : Creating backend with remote "TestYandex:rclone-test-leworec7xeca"
2025/05/08 03:11:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-leworec7xeca': Using server-side directory move
2025/05/08 03:11:10 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-leworec7xeca': Server side directory move succeeded
2025/05/08 03:11:12 ERROR : : error listing: directory not found
2025/05/08 03:11:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-leworec7xeca': Purge remote
2025/05/08 03:11:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-zimulir6jeci': Purge remote
2025/05/08 03:11:25 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.)
--- PASS: TestServerSideMove (90.12s)
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:11:26 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-venakot8cufa"
2025/05/08 03:11:26 DEBUG : Creating backend with remote "TestYandex:rclone-test-venakot8cufa"
2025/05/08 03:11:32 INFO  : potato2.rclone_chunk.001_xft3zu: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/08 03:11:34 INFO  : potato2.rclone_chunk.002_xft3zu: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/08 03:11:41 INFO  : empty space.rclone_chunk.001_xftd8a: Moved (server-side) to: empty space
2025/05/08 03:11:47 INFO  : potato3.rclone_chunk.001_xfti4n: Moved (server-side) to: potato3.rclone_chunk.001
2025/05/08 03:11:49 INFO  : potato3.rclone_chunk.002_xfti4n: Moved (server-side) to: potato3.rclone_chunk.002
    sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-venakot8cufa'
2025/05/08 03:11:58 INFO  : empty space.rclone_chunk.001_xftvid: Moved (server-side) to: empty space
2025/05/08 03:12:05 INFO  : potato3.rclone_chunk.001_xfu0t2: Moved (server-side) to: potato3.rclone_chunk.001
2025/05/08 03:12:07 INFO  : potato3.rclone_chunk.002_xfu0t2: Moved (server-side) to: potato3.rclone_chunk.002
2025/05/08 03:12:10 DEBUG : empty space: Excluded (Size Filter)
2025/05/08 03:12:10 DEBUG : empty space: Excluded
2025/05/08 03:12:10 DEBUG : empty space: Excluded (Size Filter)
2025/05/08 03:12:10 DEBUG : empty space: Excluded
2025/05/08 03:12:10 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 03:12:10 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/05/08 03:12:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-venakot8cufa': Waiting for checks to finish
2025/05/08 03:12:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-venakot8cufa': Waiting for transfers to finish
2025/05/08 03:12:11 DEBUG : potato2: move 2 data chunks...
2025/05/08 03:12:12 INFO  : potato2.rclone_chunk.001: Moved (server-side)
2025/05/08 03:12:14 INFO  : potato2.rclone_chunk.002: Moved (server-side)
2025/05/08 03:12:16 INFO  : potato2: Moved (server-side)
2025/05/08 03:12:18 INFO  : potato2: Moved (server-side)
2025/05/08 03:12:24 INFO  : potato3: Deleted
2025/05/08 03:12:24 DEBUG : potato3: move 2 data chunks...
2025/05/08 03:12:26 INFO  : potato3.rclone_chunk.001: Moved (server-side)
2025/05/08 03:12:27 INFO  : potato3.rclone_chunk.002: Moved (server-side)
2025/05/08 03:12:28 INFO  : potato3: Moved (server-side)
2025/05/08 03:12:31 INFO  : potato3: Moved (server-side)
2025/05/08 03:12:32 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-zefatuy5maze"
2025/05/08 03:12:32 DEBUG : Creating backend with remote "TestYandex:rclone-test-zefatuy5maze"
2025/05/08 03:12:33 DEBUG : empty space: Excluded (Size Filter)
2025/05/08 03:12:33 DEBUG : empty space: Excluded
2025/05/08 03:12:33 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 03:12:33 DEBUG : potato3: Need to transfer - File not found at Destination
2025/05/08 03:12:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-zefatuy5maze': Waiting for checks to finish
2025/05/08 03:12:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-zefatuy5maze': Waiting for transfers to finish
2025/05/08 03:12:33 DEBUG : potato2: move 2 data chunks...
2025/05/08 03:12:33 DEBUG : potato3: move 2 data chunks...
2025/05/08 03:12:35 INFO  : potato3.rclone_chunk.001: Moved (server-side)
2025/05/08 03:12:35 INFO  : potato2.rclone_chunk.001: Moved (server-side)
2025/05/08 03:12:36 INFO  : potato2.rclone_chunk.002: Moved (server-side)
2025/05/08 03:12:37 INFO  : potato3.rclone_chunk.002: Moved (server-side)
2025/05/08 03:12:38 INFO  : potato2: Moved (server-side)
2025/05/08 03:12:38 INFO  : potato3: Moved (server-side)
2025/05/08 03:12:40 INFO  : potato2: Moved (server-side)
2025/05/08 03:12:40 INFO  : potato3: Moved (server-side)
2025/05/08 03:12:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-zefatuy5maze': Purge remote
2025/05/08 03:12:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-venakot8cufa': Purge remote
--- PASS: TestServerSideMoveWithFilter (101.38s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:13:07 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-yuxogom3xigu"
2025/05/08 03:13:07 DEBUG : Creating backend with remote "TestYandex:rclone-test-yuxogom3xigu"
2025/05/08 03:13:14 INFO  : potato2.rclone_chunk.001_xfvxj8: Moved (server-side) to: potato2.rclone_chunk.001
2025/05/08 03:13:16 INFO  : potato2.rclone_chunk.002_xfvxj8: Moved (server-side) to: potato2.rclone_chunk.002
2025/05/08 03:13:22 INFO  : empty space.rclone_chunk.001_xfw72n: Moved (server-side) to: empty space
2025/05/08 03:13:28 INFO  : potato3.rclone_chunk.001_xfwcuy: Moved (server-side) to: potato3.rclone_chunk.001
2025/05/08 03:13:29 INFO  : potato3.rclone_chunk.002_xfwcuy: Moved (server-side) to: potato3.rclone_chunk.002
2025/05/08 03:13:31 INFO  : tomatoDir: Making directory
    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:1703
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1901
        	Error:      	Should be true
        	Test:       	TestServerSideMoveDeleteEmptySourceDirs
        	Messages:   	potato2: Modification time difference too big |-212639h8m10.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-08 03:13:17 +0000 UTC) (precision 1ns)
    sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-yuxogom3xigu'
2025/05/08 03:13:39 INFO  : empty space.rclone_chunk.001_xfwnk3: Moved (server-side) to: empty space
2025/05/08 03:13:47 INFO  : potato3.rclone_chunk.001_xfws4c: Moved (server-side) to: potato3.rclone_chunk.001
2025/05/08 03:13:48 INFO  : potato3.rclone_chunk.002_xfws4c: Moved (server-side) to: potato3.rclone_chunk.002
2025/05/08 03:13:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-yuxogom3xigu': Using server-side directory move
2025/05/08 03:13:52 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-yuxogom3xigu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/05/08 03:13:52 DEBUG : potato2: Need to transfer - File not found at Destination
2025/05/08 03:13:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:13:52 DEBUG : empty space: Unchanged skipping
2025/05/08 03:13:52 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2025/05/08 03:13:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-yuxogom3xigu': Waiting for checks to finish
2025/05/08 03:13:53 DEBUG : potato2: move 2 data chunks...
2025/05/08 03:13:54 INFO  : potato2.rclone_chunk.001: Moved (server-side)
2025/05/08 03:13:56 INFO  : potato2.rclone_chunk.002: Moved (server-side)
2025/05/08 03:13:56 INFO  : empty space: Deleted
2025/05/08 03:13:56 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-yuxogom3xigu': Waiting for transfers to finish
2025/05/08 03:13:57 INFO  : potato2: Moved (server-side)
2025/05/08 03:13:59 INFO  : potato2: Moved (server-side)
2025/05/08 03:14:05 INFO  : potato3: Deleted
2025/05/08 03:14:05 DEBUG : potato3: move 2 data chunks...
2025/05/08 03:14:07 INFO  : potato3.rclone_chunk.001: Moved (server-side)
2025/05/08 03:14:09 INFO  : potato3.rclone_chunk.002: Moved (server-side)
2025/05/08 03:14:10 INFO  : potato3: Moved (server-side)
2025/05/08 03:14:12 INFO  : potato3: Moved (server-side)
2025/05/08 03:14:12 INFO  : tomatoDir: Removing directory
2025/05/08 03:14:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': deleted 1 directories
    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:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1729
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1901
        	Error:      	Should be true
        	Test:       	TestServerSideMoveDeleteEmptySourceDirs
        	Messages:   	potato2: Modification time difference too big |-212639h8m50.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-08 03:13:57 +0000 UTC) (precision 1ns)
2025/05/08 03:14:20 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-fixelik2hali"
2025/05/08 03:14:20 DEBUG : Creating backend with remote "TestYandex:rclone-test-fixelik2hali"
2025/05/08 03:14:20 INFO  : tomatoDir: Making directory
2025/05/08 03:14:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fixelik2hali': Using server-side directory move
2025/05/08 03:14:22 INFO  : Chunked 'TestChunkerChunk50bYandex:rclone-test-fixelik2hali': Server side directory move succeeded
    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:350
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1752
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1901
        	Error:      	Should be true
        	Test:       	TestServerSideMoveDeleteEmptySourceDirs
        	Messages:   	potato2: Modification time difference too big |-212639h8m50.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-05-08 03:13:57 +0000 UTC) (precision 1ns)
2025/05/08 03:14:24 ERROR : : error listing: directory not found
2025/05/08 03:14:25 ERROR : : error listing: directory not found
2025/05/08 03:14:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fixelik2hali': Purge remote
2025/05/08 03:14:38 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-yuxogom3xigu': Purge remote
2025/05/08 03:14:38 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.)
--- FAIL: TestServerSideMoveDeleteEmptySourceDirs (91.91s)
=== RUN   TestServerSideMoveOverlap
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:1910: Skipping test as remote supports DirMove
--- SKIP: TestServerSideMoveOverlap (0.54s)
=== RUN   TestSyncOverlap
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:14:39 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test"
2025/05/08 03:14:39 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/rclone-sync-test"
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncOverlap (4.85s)
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:14:44 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test"
2025/05/08 03:14:44 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/rclone-sync-test"
2025/05/08 03:14:45 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test-include/layer2"
2025/05/08 03:14:45 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/rclone-sync-test-include/layer2"
2025/05/08 03:14:47 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test-ignore-file"
2025/05/08 03:14:47 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/rclone-sync-test-ignore-file"
2025/05/08 03:14:53 INFO  : rclone-sync-test-ignore-file/.ignore.rclone_chunk.001_xfypp4: Moved (server-side) to: rclone-sync-test-ignore-file/.ignore
2025/05/08 03:14:54 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:14:55 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:14:56 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/05/08 03:14:56 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test': Waiting for checks to finish
2025/05/08 03:14:56 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test': Waiting for transfers to finish
2025/05/08 03:14:56 DEBUG : Waiting for deletions to finish
2025/05/08 03:14:56 INFO  : There was nothing to transfer
2025/05/08 03:14:57 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:14:57 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:14:58 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/05/08 03:14:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:14:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:14:58 DEBUG : Waiting for deletions to finish
2025/05/08 03:14:58 INFO  : rclone-sync-test-include: Removing directory
2025/05/08 03:14:58 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2025/05/08 03:14:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': failed to delete 1 directories
2025/05/08 03:14:58 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:14:59 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:00 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:01 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:02 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:02 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:03 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:04 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:05 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:06 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:06 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:07 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:08 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:09 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:10 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:11 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:11 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:12 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:13 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/05/08 03:15:14 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:14 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:15 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/05/08 03:15:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/05/08 03:15:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/05/08 03:15:15 DEBUG : Waiting for deletions to finish
2025/05/08 03:15:15 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:16 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:16 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:17 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:18 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:19 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:19 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/05/08 03:15:20 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:21 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:22 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/05/08 03:15:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:15:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:15:22 DEBUG : Waiting for deletions to finish
2025/05/08 03:15:22 INFO  : rclone-sync-test-include: Removing directory
2025/05/08 03:15:22 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty
2025/05/08 03:15:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': failed to delete 1 directories
2025/05/08 03:15:22 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:22 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:23 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:24 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:25 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:25 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:26 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:27 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:28 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:29 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:29 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:30 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:31 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/05/08 03:15:32 DEBUG : : Excluded
2025/05/08 03:15:32 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:33 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:34 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/05/08 03:15:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/05/08 03:15:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/05/08 03:15:34 DEBUG : Waiting for deletions to finish
2025/05/08 03:15:34 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:34 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:35 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:36 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:37 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:37 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:38 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:39 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:39 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:41 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:42 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:42 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:43 DEBUG : rclone-sync-test-include/layer2: Excluded
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:15:44 DEBUG : rclone-sync-test: Excluded
2025/05/08 03:15:44 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/05/08 03:15:45 DEBUG : rclone-sync-test-include/layer2: Excluded
--- PASS: TestSyncOverlapWithFilter (99.26s)
=== RUN   TestSyncCompareDest
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:16:24 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:16:24 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:16:24 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/CompareDest"
2025/05/08 03:16:24 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/CompareDest"
2025/05/08 03:16:26 DEBUG : one: Need to transfer - File not found at Destination
2025/05/08 03:16:26 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:16:26 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:16:26 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:16:31 INFO  : one.rclone_chunk.001_xg1fd0: Moved (server-side) to: one
2025/05/08 03:16:31 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/05/08 03:16:31 INFO  : one: Copied (new)
2025/05/08 03:16:31 DEBUG : Waiting for deletions to finish
2025/05/08 03:16:34 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:16:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:16:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:16:34 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:16:42 INFO  : one: Deleted
2025/05/08 03:16:44 INFO  : one.rclone_chunk.001_xg1nrg: Moved (server-side) to: one
2025/05/08 03:16:44 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/05/08 03:16:44 INFO  : one: Copied (replaced existing)
2025/05/08 03:16:44 DEBUG : Waiting for deletions to finish
2025/05/08 03:16:53 INFO  : dst/one: Deleted
2025/05/08 03:16:55 INFO  : dst/one.rclone_chunk.001_xg1yg2: Moved (server-side) to: dst/one
2025/05/08 03:16:59 INFO  : CompareDest/one.rclone_chunk.001_xg299n: Moved (server-side) to: CompareDest/one
2025/05/08 03:17:00 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:17:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:17:01 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:17:01 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/08 03:17:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:17:01 DEBUG : Waiting for deletions to finish
2025/05/08 03:17:01 INFO  : There was nothing to transfer
2025/05/08 03:17:07 INFO  : CompareDest/two.rclone_chunk.001_xg2f69: Moved (server-side) to: CompareDest/two
2025/05/08 03:17:08 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:17:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:17:08 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/08 03:17:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:17:08 DEBUG : two: Destination found in --compare-dest, skipping
2025/05/08 03:17:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:17:08 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:17:08 DEBUG : Waiting for deletions to finish
2025/05/08 03:17:08 INFO  : There was nothing to transfer
2025/05/08 03:17:09 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:17:10 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:17:10 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/08 03:17:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:17:10 DEBUG : two: Destination found in --compare-dest, skipping
2025/05/08 03:17:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:17:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:17:10 DEBUG : Waiting for deletions to finish
2025/05/08 03:17:10 INFO  : There was nothing to transfer
2025/05/08 03:17:12 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:17:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:17:13 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/08 03:17:13 DEBUG : two: Modification times differ by -119h59m59.876543211s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123456789 +0000 UTC
2025/05/08 03:17:13 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2025/05/08 03:17:13 DEBUG : two: Destination found in --compare-dest, skipping
2025/05/08 03:17:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:17:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:17:13 DEBUG : Waiting for deletions to finish
2025/05/08 03:17:13 INFO  : There was nothing to transfer
2025/05/08 03:17:14 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:17:15 DEBUG : two: Sizes differ (src 5 vs dst 3)
2025/05/08 03:17:15 DEBUG : two: Need to transfer - File not found at Destination
2025/05/08 03:17:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:17:15 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:17:15 DEBUG : one: Destination found in --compare-dest, skipping
2025/05/08 03:17:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:17:15 DEBUG : two: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:17:20 INFO  : two.rclone_chunk.001_xg2s8y: Moved (server-side) to: two
2025/05/08 03:17:20 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK
2025/05/08 03:17:20 INFO  : two: Copied (new)
2025/05/08 03:17:20 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (87.32s)
=== RUN   TestSyncMultipleCompareDest
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:17:56 INFO  : pre-dest1/1.rclone_chunk.001_xg3sdd: Moved (server-side) to: pre-dest1/1
2025/05/08 03:18:02 INFO  : pre-dest2/2.rclone_chunk.001_xg3yjb: Moved (server-side) to: pre-dest2/2
2025/05/08 03:18:03 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dest"
2025/05/08 03:18:03 DEBUG : Config file has changed externally - reloading
2025/05/08 03:18:03 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dest"
2025/05/08 03:18:03 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/pre-dest1"
2025/05/08 03:18:03 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/pre-dest1"
2025/05/08 03:18:04 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/pre-dest2"
2025/05/08 03:18:04 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/pre-dest2"
2025/05/08 03:18:05 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:18:05 DEBUG : 1: Destination found in --compare-dest, skipping
2025/05/08 03:18:06 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:18:06 DEBUG : 2: Destination found in --compare-dest, skipping
2025/05/08 03:18:07 DEBUG : 3: Need to transfer - File not found at Destination
2025/05/08 03:18:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dest': Waiting for checks to finish
2025/05/08 03:18:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dest': Waiting for transfers to finish
2025/05/08 03:18:07 DEBUG : 3: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:18:12 INFO  : 3.rclone_chunk.001_xg483v: Moved (server-side) to: 3
2025/05/08 03:18:12 DEBUG : 3: md5 = eccbc87e4b5ce2fe28308fd9f2a7baf3 OK
2025/05/08 03:18:12 INFO  : 3: Copied (new)
2025/05/08 03:18:12 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncMultipleCompareDest (53.15s)
=== RUN   TestSyncCopyDest
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:18:44 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:18:44 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:18:44 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/CopyDest"
2025/05/08 03:18:44 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/CopyDest"
2025/05/08 03:18:45 DEBUG : one: Need to transfer - File not found at Destination
2025/05/08 03:18:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:18:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:18:45 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:18:50 INFO  : one.rclone_chunk.001_xg5aah: Moved (server-side) to: one
2025/05/08 03:18:50 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2025/05/08 03:18:50 INFO  : one: Copied (new)
2025/05/08 03:18:50 DEBUG : Waiting for deletions to finish
2025/05/08 03:18:52 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:18:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:18:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:18:52 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:19:02 INFO  : one: Deleted
2025/05/08 03:19:03 INFO  : one.rclone_chunk.001_xg5hdj: Moved (server-side) to: one
2025/05/08 03:19:03 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/05/08 03:19:03 INFO  : one: Copied (replaced existing)
2025/05/08 03:19:03 DEBUG : Waiting for deletions to finish
2025/05/08 03:19:12 INFO  : dst/one: Deleted
2025/05/08 03:19:14 INFO  : dst/one.rclone_chunk.001_xg5to0: Moved (server-side) to: dst/one
2025/05/08 03:19:19 INFO  : CopyDest/one.rclone_chunk.001_xg637b: Moved (server-side) to: CopyDest/one
2025/05/08 03:19:19 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/BackupDir"
2025/05/08 03:19:19 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/BackupDir"
2025/05/08 03:19:20 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:19:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:19:21 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:19:21 DEBUG : one: Sizes differ (src 5 vs dst 3)
2025/05/08 03:19:21 DEBUG : one: move non-chunked object...
2025/05/08 03:19:23 INFO  : one: Moved (server-side)
2025/05/08 03:19:23 INFO  : one: Moved (server-side)
2025/05/08 03:19:23 DEBUG : one: copy non-chunked object...
2025/05/08 03:19:24 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2025/05/08 03:19:24 INFO  : one: Copied (server-side copy)
2025/05/08 03:19:24 DEBUG : one: Destination found in --copy-dest, using server-side copy
2025/05/08 03:19:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:19:24 DEBUG : Waiting for deletions to finish
2025/05/08 03:19:29 INFO  : CopyDest/two.rclone_chunk.001_xg6ebe: Moved (server-side) to: CopyDest/two
2025/05/08 03:19:31 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:19:31 DEBUG : one: Unchanged skipping
2025/05/08 03:19:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:19:32 DEBUG : two: copy non-chunked object...
2025/05/08 03:19:33 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2025/05/08 03:19:33 INFO  : two: Copied (server-side copy)
2025/05/08 03:19:33 DEBUG : two: Destination found in --copy-dest, using server-side copy
2025/05/08 03:19:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:19:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:19:33 DEBUG : Waiting for deletions to finish
2025/05/08 03:19:34 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:19:34 DEBUG : one: Unchanged skipping
2025/05/08 03:19:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:19:34 DEBUG : two: Unchanged skipping
2025/05/08 03:19:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:19:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:19:34 DEBUG : Waiting for deletions to finish
2025/05/08 03:19:34 INFO  : There was nothing to transfer
2025/05/08 03:19:40 INFO  : CopyDest/three.rclone_chunk.001_xg6onf: Moved (server-side) to: CopyDest/three
2025/05/08 03:19:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:19:41 DEBUG : one: Unchanged skipping
2025/05/08 03:19:42 DEBUG : three: Sizes differ (src 7 vs dst 5)
2025/05/08 03:19:42 DEBUG : three: Destination not found in --copy-dest
2025/05/08 03:19:42 DEBUG : three: Need to transfer - File not found at Destination
2025/05/08 03:19:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:19:42 DEBUG : two: Unchanged skipping
2025/05/08 03:19:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:19:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:19:42 DEBUG : three: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:19:46 INFO  : three.rclone_chunk.001_xg6vcd: Moved (server-side) to: three
2025/05/08 03:19:46 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK
2025/05/08 03:19:46 INFO  : three: Copied (new)
2025/05/08 03:19:46 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (114.66s)
=== RUN   TestSyncBackupDir
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:20:44 INFO  : dst/one.rclone_chunk.001_xg8go9: Moved (server-side) to: dst/one
2025/05/08 03:20:50 INFO  : dst/two.rclone_chunk.001_xg8mg0: Moved (server-side) to: dst/two
2025/05/08 03:20:54 INFO  : dst/three.txt.rclone_chunk.001_xg8rbz: Moved (server-side) to: dst/three.txt
2025/05/08 03:20:55 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:20:55 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:20:55 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/backup"
2025/05/08 03:20:55 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/backup"
2025/05/08 03:20:56 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/08 03:20:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:20:56 DEBUG : two: Unchanged skipping
2025/05/08 03:20:56 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:20:56 DEBUG : one: move non-chunked object...
2025/05/08 03:20:58 INFO  : one: Moved (server-side)
2025/05/08 03:20:58 INFO  : one: Moved (server-side)
2025/05/08 03:20:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:20:58 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:21:04 INFO  : one.rclone_chunk.001_xg8zap: Moved (server-side) to: one
2025/05/08 03:21:04 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/08 03:21:04 INFO  : one: Copied (new)
2025/05/08 03:21:04 DEBUG : Waiting for deletions to finish
2025/05/08 03:21:04 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:21:06 INFO  : three.txt: Moved (server-side)
2025/05/08 03:21:06 INFO  : three.txt: Moved (server-side)
2025/05/08 03:21:06 INFO  : three.txt: Moved into backup dir
2025/05/08 03:21:12 INFO  : dst/three.txt.rclone_chunk.001_xg9882: Moved (server-side) to: dst/three.txt
2025/05/08 03:21:13 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/08 03:21:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:21:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:21:13 DEBUG : two: Unchanged skipping
2025/05/08 03:21:19 INFO  : one: Deleted
2025/05/08 03:21:19 DEBUG : one: move non-chunked object...
2025/05/08 03:21:20 INFO  : one: Moved (server-side)
2025/05/08 03:21:20 INFO  : one: Moved (server-side)
2025/05/08 03:21:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:21:20 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:21:25 INFO  : one.rclone_chunk.001_xg9ljx: Moved (server-side) to: one
2025/05/08 03:21:25 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/08 03:21:25 INFO  : one: Copied (new)
2025/05/08 03:21:25 DEBUG : Waiting for deletions to finish
2025/05/08 03:21:29 INFO  : three.txt: Deleted
2025/05/08 03:21:29 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:21:31 INFO  : three.txt: Moved (server-side)
2025/05/08 03:21:31 INFO  : three.txt: Moved (server-side)
2025/05/08 03:21:31 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (82.63s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:22:06 INFO  : dst/one.rclone_chunk.001_xgarmp: Moved (server-side) to: dst/one
2025/05/08 03:22:11 INFO  : dst/two.rclone_chunk.001_xgav5c: Moved (server-side) to: dst/two
2025/05/08 03:22:16 INFO  : dst/three.txt.rclone_chunk.001_xgb0kk: Moved (server-side) to: dst/three.txt
2025/05/08 03:22:17 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:22:17 DEBUG : Config file has changed externally - reloading
2025/05/08 03:22:17 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:22:18 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/backup"
2025/05/08 03:22:18 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/backup"
2025/05/08 03:22:18 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/08 03:22:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:22:18 DEBUG : two: Unchanged skipping
2025/05/08 03:22:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:22:18 DEBUG : one: move non-chunked object...
2025/05/08 03:22:20 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:22:20 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:22:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:22:20 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:22:24 INFO  : one.rclone_chunk.001_xgb91r: Moved (server-side) to: one
2025/05/08 03:22:24 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/08 03:22:24 INFO  : one: Copied (new)
2025/05/08 03:22:24 DEBUG : Waiting for deletions to finish
2025/05/08 03:22:24 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:22:27 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:22:27 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:22:27 INFO  : three.txt: Moved into backup dir
2025/05/08 03:22:32 INFO  : dst/three.txt.rclone_chunk.001_xgbh5l: Moved (server-side) to: dst/three.txt
2025/05/08 03:22:33 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/08 03:22:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:22:33 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:22:33 DEBUG : two: Unchanged skipping
2025/05/08 03:22:38 INFO  : one.bak: Deleted
2025/05/08 03:22:38 DEBUG : one: move non-chunked object...
2025/05/08 03:22:40 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:22:40 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:22:40 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:22:40 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:22:45 INFO  : one.rclone_chunk.001_xgbtj9: Moved (server-side) to: one
2025/05/08 03:22:45 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/08 03:22:45 INFO  : one: Copied (new)
2025/05/08 03:22:45 DEBUG : Waiting for deletions to finish
2025/05/08 03:22:50 INFO  : three.txt.bak: Deleted
2025/05/08 03:22:50 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:22:51 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:22:51 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:22:51 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (79.48s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:23:24 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/05/08 03:23:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/05/08 03:23:24 DEBUG : pacer: Reducing sleep to 15ms
2025/05/08 03:23:24 DEBUG : pacer: Reducing sleep to 11.25ms
2025/05/08 03:23:25 DEBUG : pacer: Reducing sleep to 10ms
2025/05/08 03:23:26 INFO  : dst/one.rclone_chunk.001_xgcy6m: Moved (server-side) to: dst/one
2025/05/08 03:23:31 INFO  : dst/two.rclone_chunk.001_xgd3pz: Moved (server-side) to: dst/two
2025/05/08 03:23:36 INFO  : dst/three.txt.rclone_chunk.001_xgd85j: Moved (server-side) to: dst/three.txt
2025/05/08 03:23:37 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:23:37 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:23:37 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/backup"
2025/05/08 03:23:37 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/backup"
2025/05/08 03:23:38 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/08 03:23:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:23:38 DEBUG : two: Unchanged skipping
2025/05/08 03:23:38 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:23:38 DEBUG : one: move non-chunked object...
2025/05/08 03:23:39 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/08 03:23:39 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/08 03:23:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:23:40 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:23:44 INFO  : one.rclone_chunk.001_xgdhb4: Moved (server-side) to: one
2025/05/08 03:23:44 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/08 03:23:44 INFO  : one: Copied (new)
2025/05/08 03:23:44 DEBUG : Waiting for deletions to finish
2025/05/08 03:23:44 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:23:46 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/08 03:23:46 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/08 03:23:46 INFO  : three.txt: Moved into backup dir
    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:2398
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffixKeepExtension
        	Messages:   	dst/one: Modification time difference too big |-117182h23m43.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-05-08 03:23:43 +0000 UTC) (precision 1ns)
2025/05/08 03:23:51 INFO  : dst/three.txt.rclone_chunk.001_xgdomw: Moved (server-side) to: dst/three.txt
    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:2404
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2435
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffixKeepExtension
        	Messages:   	dst/one: Modification time difference too big |-117182h23m43.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-05-08 03:23:43 +0000 UTC) (precision 1ns)
2025/05/08 03:23:53 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/08 03:23:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:23:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:23:53 DEBUG : two: Unchanged skipping
2025/05/08 03:23:58 INFO  : one-2019-01-01: Deleted
2025/05/08 03:23:58 DEBUG : one: move non-chunked object...
2025/05/08 03:23:59 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/08 03:23:59 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/08 03:23:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:23:59 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:24:05 INFO  : one.rclone_chunk.001_xge0eh: Moved (server-side) to: one
2025/05/08 03:24:05 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/08 03:24:05 INFO  : one: Copied (new)
2025/05/08 03:24:05 DEBUG : Waiting for deletions to finish
2025/05/08 03:24:09 INFO  : three-2019-01-01.txt: Deleted
2025/05/08 03:24:09 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:24:11 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/08 03:24:11 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/08 03:24:11 INFO  : three.txt: Moved into backup dir
    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:2435
        	Error:      	Should be true
        	Test:       	TestSyncBackupDirWithSuffixKeepExtension
        	Messages:   	backup/one-2019-01-01: Modification time difference too big |-117182h23m58.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-05-08 03:23:58 +0000 UTC) (precision 1ns)
--- FAIL: TestSyncBackupDirWithSuffixKeepExtension (79.50s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:24:45 INFO  : dst/one.rclone_chunk.001_xgf6wc: Moved (server-side) to: dst/one
2025/05/08 03:24:50 INFO  : dst/two.rclone_chunk.001_xgfazi: Moved (server-side) to: dst/two
2025/05/08 03:24:55 INFO  : dst/three.txt.rclone_chunk.001_xgfgm7: Moved (server-side) to: dst/three.txt
2025/05/08 03:24:56 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:24:56 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:24:57 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/08 03:24:57 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:24:57 DEBUG : two: Unchanged skipping
2025/05/08 03:24:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:24:57 DEBUG : one: move non-chunked object...
2025/05/08 03:24:59 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:24:59 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:24:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:24:59 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:25:07 INFO  : one.rclone_chunk.001_xgfoh1: Moved (server-side) to: one
2025/05/08 03:25:07 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/08 03:25:07 INFO  : one: Copied (new)
2025/05/08 03:25:07 DEBUG : Waiting for deletions to finish
2025/05/08 03:25:08 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:25:09 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:25:09 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:25:09 INFO  : three.txt: Moved into backup dir
2025/05/08 03:25:14 INFO  : dst/three.txt.rclone_chunk.001_xgfze2: Moved (server-side) to: dst/three.txt
2025/05/08 03:25:16 DEBUG : one.bak: Excluded (Path Filter)
2025/05/08 03:25:16 DEBUG : one.bak: Excluded
2025/05/08 03:25:16 DEBUG : three.txt.bak: Excluded (Path Filter)
2025/05/08 03:25:16 DEBUG : three.txt.bak: Excluded
2025/05/08 03:25:16 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/08 03:25:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:25:16 DEBUG : two: Unchanged skipping
2025/05/08 03:25:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for checks to finish
2025/05/08 03:25:21 INFO  : one.bak: Deleted
2025/05/08 03:25:21 DEBUG : one: move non-chunked object...
2025/05/08 03:25:23 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:25:23 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:25:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst': Waiting for transfers to finish
2025/05/08 03:25:23 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:25:27 INFO  : one.rclone_chunk.001_xggcox: Moved (server-side) to: one
2025/05/08 03:25:27 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/08 03:25:27 INFO  : one: Copied (new)
2025/05/08 03:25:27 DEBUG : Waiting for deletions to finish
2025/05/08 03:25:32 INFO  : three.txt.bak: Deleted
2025/05/08 03:25:32 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:25:34 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:25:34 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:25:34 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (76.80s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:26:02 INFO  : dst/one.rclone_chunk.001_xghafz: Moved (server-side) to: dst/one
2025/05/08 03:26:06 INFO  : dst/two.rclone_chunk.001_xghfpe: Moved (server-side) to: dst/two
2025/05/08 03:26:11 INFO  : dst/three.txt.rclone_chunk.001_xghkuh: Moved (server-side) to: dst/three.txt
2025/05/08 03:26:12 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:26:12 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:26:13 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/08 03:26:13 DEBUG : one: move non-chunked object...
2025/05/08 03:26:16 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:26:16 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:26:16 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:26:20 INFO  : one.rclone_chunk.001_xghtoo: Moved (server-side) to: one
2025/05/08 03:26:20 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/08 03:26:20 INFO  : one: Copied (new)
2025/05/08 03:26:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:26:20 DEBUG : two: Unchanged skipping
2025/05/08 03:26:21 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/05/08 03:26:21 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:26:23 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:26:23 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:26:23 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:26:27 INFO  : three.txt.rclone_chunk.001_xgi0oy: Moved (server-side) to: three.txt
2025/05/08 03:26:27 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/05/08 03:26:27 INFO  : three.txt: Copied (new)
2025/05/08 03:26:31 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/08 03:26:39 INFO  : one.bak: Deleted
2025/05/08 03:26:39 DEBUG : one: move non-chunked object...
2025/05/08 03:26:41 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:26:41 INFO  : one: Moved (server-side) to: one.bak
2025/05/08 03:26:41 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:26:46 INFO  : one.rclone_chunk.001_xgiigg: Moved (server-side) to: one
2025/05/08 03:26:46 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/08 03:26:46 INFO  : one: Copied (new)
2025/05/08 03:26:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:26:46 DEBUG : two: Unchanged skipping
2025/05/08 03:26:47 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/05/08 03:26:52 INFO  : three.txt.bak: Deleted
2025/05/08 03:26:52 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:26:53 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:26:53 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/05/08 03:26:53 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:26:58 INFO  : three.txt.rclone_chunk.001_xgiu5q: Moved (server-side) to: three.txt
2025/05/08 03:26:58 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/05/08 03:26:58 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (88.97s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:27:31 INFO  : dst/one.rclone_chunk.001_xgjr4i: Moved (server-side) to: dst/one
2025/05/08 03:27:35 INFO  : dst/two.rclone_chunk.001_xgjxrp: Moved (server-side) to: dst/two
2025/05/08 03:27:40 INFO  : dst/three.txt.rclone_chunk.001_xgk1p3: Moved (server-side) to: dst/three.txt
2025/05/08 03:27:40 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:27:40 DEBUG : Config file has changed externally - reloading
2025/05/08 03:27:40 DEBUG : Creating backend with remote "TestYandex:rclone-test-vewexes2june/dst"
2025/05/08 03:27:41 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/05/08 03:27:42 DEBUG : one: move non-chunked object...
2025/05/08 03:27:43 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/08 03:27:43 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/08 03:27:43 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:27:49 INFO  : one.rclone_chunk.001_xgk8b6: Moved (server-side) to: one
2025/05/08 03:27:49 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/05/08 03:27:49 INFO  : one: Copied (new)
2025/05/08 03:27:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:27:49 DEBUG : two: Unchanged skipping
2025/05/08 03:27:50 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/05/08 03:27:50 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:27:52 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/08 03:27:52 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/08 03:27:52 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:27:56 INFO  : three.txt.rclone_chunk.001_xgkhgf: Moved (server-side) to: three.txt
2025/05/08 03:27:56 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/05/08 03:27:56 INFO  : three.txt: Copied (new)
2025/05/08 03:27:58 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/05/08 03:28:03 INFO  : one-2019-01-01: Deleted
2025/05/08 03:28:03 DEBUG : one: move non-chunked object...
2025/05/08 03:28:05 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/08 03:28:05 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/05/08 03:28:05 DEBUG : one: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:28:09 INFO  : one.rclone_chunk.001_xgkurg: Moved (server-side) to: one
2025/05/08 03:28:09 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/05/08 03:28:09 INFO  : one: Copied (new)
2025/05/08 03:28:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:28:10 DEBUG : two: Unchanged skipping
2025/05/08 03:28:11 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/05/08 03:28:15 INFO  : three-2019-01-01.txt: Deleted
2025/05/08 03:28:15 DEBUG : three.txt: move non-chunked object...
2025/05/08 03:28:17 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/08 03:28:17 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/05/08 03:28:18 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:28:22 INFO  : three.txt.rclone_chunk.001_xgl7bi: Moved (server-side) to: three.txt
2025/05/08 03:28:22 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/05/08 03:28:22 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (85.62s)
=== RUN   TestSyncUTFNorm
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:28:56 INFO  : Testêé.rclone_chunk.001_xgm6ba: Moved (server-side) to: Testêé
2025/05/08 03:28:58 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2025/05/08 03:28:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:28:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:29:05 INFO  : Testêé: Deleted
2025/05/08 03:29:07 INFO  : Testêé.rclone_chunk.001_xgmbrk: Moved (server-side) to: Testêé
2025/05/08 03:29:07 DEBUG : Testêé: md5 = ce114e4501d2f4e2dcea3e17b546f339 OK
2025/05/08 03:29:07 INFO  : Testêé: Copied (replaced existing) to: Testêé
2025/05/08 03:29:07 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (19.99s)
=== RUN   TestSyncImmutable
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:29:12 DEBUG : existing: Need to transfer - File not found at Destination
2025/05/08 03:29:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:29:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:29:13 DEBUG : existing: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:29:16 INFO  : existing.rclone_chunk.001_xgmq5i: Moved (server-side) to: existing
2025/05/08 03:29:16 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK
2025/05/08 03:29:16 INFO  : existing: Copied (new)
2025/05/08 03:29:16 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:29:18 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2025/05/08 03:29:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:29:18 ERROR : existing: Source and destination exist but do not match: immutable file modified
2025/05/08 03:29:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:29:18 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': not deleting files as there were IO errors
2025/05/08 03:29:18 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': not deleting directories as there were IO errors
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncImmutable (13.00s)
=== RUN   TestSyncIgnoreCase
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:29:29 INFO  : EXISTING.rclone_chunk.001_xgn2fi: Moved (server-side) to: EXISTING
2025/05/08 03:29:29 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:29:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:29:29 DEBUG : existing: Unchanged skipping
2025/05/08 03:29:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:29:29 DEBUG : Waiting for deletions to finish
2025/05/08 03:29:29 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (10.25s)
=== RUN   TestFixCase
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:2635: Skipping test as local or remote are case-sensitive
--- SKIP: TestFixCase (0.71s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:2674: This test only runs on local
=== RUN   TestMaxTransfer/Soft
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:2674: This test only runs on local
=== RUN   TestMaxTransfer/Cautious
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    sync_test.go:2674: This test only runs on local
--- PASS: TestMaxTransfer (1.53s)
    --- SKIP: TestMaxTransfer/Hard (0.35s)
    --- SKIP: TestMaxTransfer/Soft (0.72s)
    --- SKIP: TestMaxTransfer/Cautious (0.46s)
=== RUN   TestSyncConcurrentDelete
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:29:42 INFO  : both0.rclone_chunk.001_xgnf5f: Moved (server-side) to: both0
2025/05/08 03:29:48 INFO  : only0.rclone_chunk.001_xgnjww: Moved (server-side) to: only0
2025/05/08 03:29:52 INFO  : both1.rclone_chunk.001_xgnpi2: Moved (server-side) to: both1
2025/05/08 03:29:57 INFO  : only1.rclone_chunk.001_xgnt1g: Moved (server-side) to: only1
2025/05/08 03:30:02 INFO  : both2.rclone_chunk.001_xgnzu1: Moved (server-side) to: both2
2025/05/08 03:30:07 INFO  : only2.rclone_chunk.001_xgo4w8: Moved (server-side) to: only2
2025/05/08 03:30:14 INFO  : both3.rclone_chunk.001_xgo9ir: Moved (server-side) to: both3
2025/05/08 03:30:19 INFO  : only3.rclone_chunk.001_xgof0s: Moved (server-side) to: only3
2025/05/08 03:30:24 INFO  : both4.rclone_chunk.001_xgolte: Moved (server-side) to: both4
2025/05/08 03:30:29 INFO  : only4.rclone_chunk.001_xgoqu3: Moved (server-side) to: only4
2025/05/08 03:30:36 INFO  : both5.rclone_chunk.001_xgou29: Moved (server-side) to: both5
2025/05/08 03:30:41 INFO  : only5.rclone_chunk.001_xgp2si: Moved (server-side) to: only5
2025/05/08 03:30:46 INFO  : both6.rclone_chunk.001_xgp6ns: Moved (server-side) to: both6
2025/05/08 03:30:50 INFO  : only6.rclone_chunk.001_xgpbi3: Moved (server-side) to: only6
2025/05/08 03:30:56 INFO  : both7.rclone_chunk.001_xgpfku: Moved (server-side) to: both7
2025/05/08 03:31:02 INFO  : only7.rclone_chunk.001_xgplj8: Moved (server-side) to: only7
2025/05/08 03:31:07 INFO  : both8.rclone_chunk.001_xgprc4: Moved (server-side) to: both8
2025/05/08 03:31:11 INFO  : only8.rclone_chunk.001_xgpwrd: Moved (server-side) to: only8
2025/05/08 03:31:16 INFO  : both9.rclone_chunk.001_xgq0nj: Moved (server-side) to: both9
2025/05/08 03:31:21 INFO  : only9.rclone_chunk.001_xgq506: Moved (server-side) to: only9
2025/05/08 03:31:25 INFO  : both10.rclone_chunk.001_xgqa22: Moved (server-side) to: both10
2025/05/08 03:31:30 INFO  : only10.rclone_chunk.001_xgqe6c: Moved (server-side) to: only10
2025/05/08 03:31:35 INFO  : both11.rclone_chunk.001_xgqks7: Moved (server-side) to: both11
2025/05/08 03:31:37 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/05/08 03:31:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/05/08 03:31:38 DEBUG : pacer: Reducing sleep to 15ms
2025/05/08 03:31:38 DEBUG : pacer: Reducing sleep to 11.25ms
2025/05/08 03:31:38 DEBUG : pacer: Reducing sleep to 10ms
2025/05/08 03:31:40 INFO  : only11.rclone_chunk.001_xgqp3p: Moved (server-side) to: only11
2025/05/08 03:31:44 INFO  : both12.rclone_chunk.001_xgqtbg: Moved (server-side) to: both12
2025/05/08 03:31:49 INFO  : only12.rclone_chunk.001_xgqyp0: Moved (server-side) to: only12
2025/05/08 03:31:54 INFO  : both13.rclone_chunk.001_xgr3eq: Moved (server-side) to: both13
2025/05/08 03:31:59 INFO  : only13.rclone_chunk.001_xgr7ea: Moved (server-side) to: only13
2025/05/08 03:32:04 INFO  : both14.rclone_chunk.001_xgrcj9: Moved (server-side) to: both14
2025/05/08 03:32:08 INFO  : only14.rclone_chunk.001_xgrhos: Moved (server-side) to: only14
2025/05/08 03:32:13 INFO  : both15.rclone_chunk.001_xgrmjy: Moved (server-side) to: both15
2025/05/08 03:32:17 INFO  : only15.rclone_chunk.001_xgrq4d: Moved (server-side) to: only15
2025/05/08 03:32:25 INFO  : both16.rclone_chunk.001_xgrv4n: Moved (server-side) to: both16
2025/05/08 03:32:30 INFO  : only16.rclone_chunk.001_xgs2xz: Moved (server-side) to: only16
2025/05/08 03:32:34 INFO  : both17.rclone_chunk.001_xgs7b5: Moved (server-side) to: both17
2025/05/08 03:32:39 INFO  : only17.rclone_chunk.001_xgsbgo: Moved (server-side) to: only17
2025/05/08 03:32:43 INFO  : both18.rclone_chunk.001_xgsgs2: Moved (server-side) to: both18
2025/05/08 03:32:48 INFO  : only18.rclone_chunk.001_xgskfk: Moved (server-side) to: only18
2025/05/08 03:32:52 INFO  : both19.rclone_chunk.001_xgspe0: Moved (server-side) to: both19
2025/05/08 03:32:57 INFO  : only19.rclone_chunk.001_xgsule: Moved (server-side) to: only19
2025/05/08 03:32:57 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:32:57 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both0: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both12: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both13: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both14: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both11: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both16: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both17: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both18: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both1: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both15: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both10: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both2: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both5: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both6: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both7: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both19: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:32:57 DEBUG : both3: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both9: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both4: Unchanged skipping
2025/05/08 03:32:57 DEBUG : both8: Unchanged skipping
2025/05/08 03:32:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:32:57 DEBUG : Waiting for deletions to finish
2025/05/08 03:33:02 INFO  : only1: Deleted
2025/05/08 03:33:02 INFO  : only4: Deleted
2025/05/08 03:33:02 INFO  : only19: Deleted
2025/05/08 03:33:02 INFO  : only10: Deleted
2025/05/08 03:33:06 INFO  : only11: Deleted
2025/05/08 03:33:06 INFO  : only17: Deleted
2025/05/08 03:33:06 INFO  : only0: Deleted
2025/05/08 03:33:07 INFO  : only6: Deleted
2025/05/08 03:33:10 INFO  : only14: Deleted
2025/05/08 03:33:10 INFO  : only16: Deleted
2025/05/08 03:33:11 INFO  : only3: Deleted
2025/05/08 03:33:11 INFO  : only8: Deleted
2025/05/08 03:33:15 INFO  : only9: Deleted
2025/05/08 03:33:15 INFO  : only12: Deleted
2025/05/08 03:33:15 INFO  : only18: Deleted
2025/05/08 03:33:15 INFO  : only15: Deleted
2025/05/08 03:33:19 INFO  : only13: Deleted
2025/05/08 03:33:19 INFO  : only7: Deleted
2025/05/08 03:33:20 INFO  : only2: Deleted
2025/05/08 03:33:20 INFO  : only5: Deleted
2025/05/08 03:33:20 INFO  : There was nothing to transfer
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncConcurrentDelete (315.06s)
=== RUN   TestSyncConcurrentTruncate
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:34:57 INFO  : both0.rclone_chunk.001_xgw52f: Moved (server-side) to: both0
2025/05/08 03:35:02 INFO  : only0.rclone_chunk.001_xgwbic: Moved (server-side) to: only0
2025/05/08 03:35:07 INFO  : both1.rclone_chunk.001_xgwf7w: Moved (server-side) to: both1
2025/05/08 03:35:12 INFO  : only1.rclone_chunk.001_xgwleh: Moved (server-side) to: only1
2025/05/08 03:35:17 INFO  : both2.rclone_chunk.001_xgwp0o: Moved (server-side) to: both2
2025/05/08 03:35:22 INFO  : only2.rclone_chunk.001_xgwuqx: Moved (server-side) to: only2
2025/05/08 03:35:27 INFO  : both3.rclone_chunk.001_xgx0hf: Moved (server-side) to: both3
2025/05/08 03:35:32 INFO  : only3.rclone_chunk.001_xgx51b: Moved (server-side) to: only3
2025/05/08 03:35:37 INFO  : both4.rclone_chunk.001_xgx95p: Moved (server-side) to: both4
2025/05/08 03:35:42 INFO  : only4.rclone_chunk.001_xgxekv: Moved (server-side) to: only4
2025/05/08 03:35:51 INFO  : both5.rclone_chunk.001_xgxjx2: Moved (server-side) to: both5
2025/05/08 03:35:56 INFO  : only5.rclone_chunk.001_xgxsd6: Moved (server-side) to: only5
2025/05/08 03:36:00 INFO  : both6.rclone_chunk.001_xgxxzm: Moved (server-side) to: both6
2025/05/08 03:36:05 INFO  : only6.rclone_chunk.001_xgy2pp: Moved (server-side) to: only6
2025/05/08 03:36:10 INFO  : both7.rclone_chunk.001_xgy71p: Moved (server-side) to: both7
2025/05/08 03:36:15 INFO  : only7.rclone_chunk.001_xgybnb: Moved (server-side) to: only7
2025/05/08 03:36:19 INFO  : both8.rclone_chunk.001_xgygm0: Moved (server-side) to: both8
2025/05/08 03:36:27 INFO  : only8.rclone_chunk.001_xgylun: Moved (server-side) to: only8
2025/05/08 03:36:29 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.))
2025/05/08 03:36:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/05/08 03:36:30 DEBUG : pacer: Reducing sleep to 15ms
2025/05/08 03:36:31 DEBUG : pacer: Reducing sleep to 11.25ms
2025/05/08 03:36:31 DEBUG : pacer: Reducing sleep to 10ms
2025/05/08 03:36:33 INFO  : both9.rclone_chunk.001_xgysxs: Moved (server-side) to: both9
2025/05/08 03:36:38 INFO  : only9.rclone_chunk.001_xgyyye: Moved (server-side) to: only9
2025/05/08 03:36:42 INFO  : both10.rclone_chunk.001_xgz3sf: Moved (server-side) to: both10
2025/05/08 03:36:47 INFO  : only10.rclone_chunk.001_xgz74e: Moved (server-side) to: only10
2025/05/08 03:36:52 INFO  : both11.rclone_chunk.001_xgzc8h: Moved (server-side) to: both11
2025/05/08 03:36:57 INFO  : only11.rclone_chunk.001_xgzh8k: Moved (server-side) to: only11
2025/05/08 03:37:01 INFO  : both12.rclone_chunk.001_xgzmh9: Moved (server-side) to: both12
2025/05/08 03:37:06 INFO  : only12.rclone_chunk.001_xgzqsz: Moved (server-side) to: only12
2025/05/08 03:37:11 INFO  : both13.rclone_chunk.001_xgzve1: Moved (server-side) to: both13
2025/05/08 03:37:16 INFO  : only13.rclone_chunk.001_xh013j: Moved (server-side) to: only13
2025/05/08 03:37:21 INFO  : both14.rclone_chunk.001_xh05k3: Moved (server-side) to: both14
2025/05/08 03:37:25 INFO  : only14.rclone_chunk.001_xh0a8g: Moved (server-side) to: only14
2025/05/08 03:37:30 INFO  : both15.rclone_chunk.001_xh0ei5: Moved (server-side) to: both15
2025/05/08 03:37:34 INFO  : only15.rclone_chunk.001_xh0jnz: Moved (server-side) to: only15
2025/05/08 03:37:39 INFO  : both16.rclone_chunk.001_xh0o1m: Moved (server-side) to: both16
2025/05/08 03:37:45 INFO  : only16.rclone_chunk.001_xh0twy: Moved (server-side) to: only16
2025/05/08 03:37:50 INFO  : both17.rclone_chunk.001_xh0ycm: Moved (server-side) to: both17
2025/05/08 03:37:57 INFO  : only17.rclone_chunk.001_xh13kq: Moved (server-side) to: only17
2025/05/08 03:38:01 INFO  : both18.rclone_chunk.001_xh1auq: Moved (server-side) to: both18
2025/05/08 03:38:07 INFO  : only18.rclone_chunk.001_xh1ebk: Moved (server-side) to: only18
2025/05/08 03:38:11 INFO  : both19.rclone_chunk.001_xh1k2z: Moved (server-side) to: both19
2025/05/08 03:38:16 INFO  : only19.rclone_chunk.001_xh1pzl: Moved (server-side) to: only19
2025/05/08 03:38:17 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both0: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both1: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both10: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both11: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for checks to finish
2025/05/08 03:38:17 DEBUG : both13: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both14: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both12: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both16: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both19: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both17: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both15: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both18: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both4: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both6: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both5: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both3: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/05/08 03:38:17 DEBUG : both2: Unchanged skipping
2025/05/08 03:38:17 DEBUG : both9: Unchanged skipping
2025/05/08 03:38:17 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : both8: Unchanged skipping
2025/05/08 03:38:17 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : both7: Unchanged skipping
2025/05/08 03:38:17 DEBUG : only1: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:17 DEBUG : only10: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:17 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only12: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:17 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only0: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:17 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2025/05/08 03:38:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june': Waiting for transfers to finish
2025/05/08 03:38:25 INFO  : only0: Deleted
2025/05/08 03:38:25 INFO  : only12: Deleted
2025/05/08 03:38:25 INFO  : only1: Deleted
2025/05/08 03:38:25 INFO  : only10: Deleted
2025/05/08 03:38:26 INFO  : only12.rclone_chunk.001_xh1ur5: Moved (server-side) to: only12
2025/05/08 03:38:26 DEBUG : only12: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:26 INFO  : only12: Copied (replaced existing)
2025/05/08 03:38:26 DEBUG : only11: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:26 INFO  : only0.rclone_chunk.001_xh1u6r: Moved (server-side) to: only0
2025/05/08 03:38:26 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:26 INFO  : only0: Copied (replaced existing)
2025/05/08 03:38:26 DEBUG : only14: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:26 INFO  : only1.rclone_chunk.001_xh1ut9: Moved (server-side) to: only1
2025/05/08 03:38:26 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:26 INFO  : only1: Copied (replaced existing)
2025/05/08 03:38:26 DEBUG : only13: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:27 INFO  : only10.rclone_chunk.001_xh1uhz: Moved (server-side) to: only10
2025/05/08 03:38:27 DEBUG : only10: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:27 INFO  : only10: Copied (replaced existing)
2025/05/08 03:38:27 DEBUG : only15: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:33 INFO  : only11: Deleted
2025/05/08 03:38:34 INFO  : only13: Deleted
2025/05/08 03:38:34 INFO  : only14: Deleted
2025/05/08 03:38:35 INFO  : only15: Deleted
2025/05/08 03:38:36 INFO  : only14.rclone_chunk.001_xh23pi: Moved (server-side) to: only14
2025/05/08 03:38:36 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:36 INFO  : only14: Copied (replaced existing)
2025/05/08 03:38:36 DEBUG : only17: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:36 INFO  : only13.rclone_chunk.001_xh234w: Moved (server-side) to: only13
2025/05/08 03:38:36 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:36 INFO  : only13: Copied (replaced existing)
2025/05/08 03:38:36 DEBUG : only2: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:36 INFO  : only15.rclone_chunk.001_xh245q: Moved (server-side) to: only15
2025/05/08 03:38:36 DEBUG : only15: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:36 INFO  : only15: Copied (replaced existing)
2025/05/08 03:38:36 DEBUG : only3: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:36 INFO  : only11.rclone_chunk.001_xh237o: Moved (server-side) to: only11
2025/05/08 03:38:36 DEBUG : only11: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:36 INFO  : only11: Copied (replaced existing)
2025/05/08 03:38:36 DEBUG : only4: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:44 INFO  : only17: Deleted
2025/05/08 03:38:44 INFO  : only2: Deleted
2025/05/08 03:38:44 INFO  : only4: Deleted
2025/05/08 03:38:45 INFO  : only17.rclone_chunk.001_xh2d24: Moved (server-side) to: only17
2025/05/08 03:38:45 DEBUG : only17: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:45 INFO  : only17: Copied (replaced existing)
2025/05/08 03:38:45 DEBUG : only5: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:45 INFO  : only2.rclone_chunk.001_xh2doj: Moved (server-side) to: only2
2025/05/08 03:38:45 DEBUG : only2: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:45 INFO  : only2: Copied (replaced existing)
2025/05/08 03:38:45 DEBUG : only6: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:46 INFO  : only3: Deleted
2025/05/08 03:38:46 INFO  : only4.rclone_chunk.001_xh2d7s: Moved (server-side) to: only4
2025/05/08 03:38:46 DEBUG : only4: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:46 INFO  : only4: Copied (replaced existing)
2025/05/08 03:38:46 DEBUG : only7: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:48 INFO  : only3.rclone_chunk.001_xh2dez: Moved (server-side) to: only3
2025/05/08 03:38:48 DEBUG : only3: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:48 INFO  : only3: Copied (replaced existing)
2025/05/08 03:38:48 DEBUG : only8: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:53 INFO  : only5: Deleted
2025/05/08 03:38:54 INFO  : only6: Deleted
2025/05/08 03:38:54 INFO  : only7: Deleted
2025/05/08 03:38:55 INFO  : only5.rclone_chunk.001_xh2mij: Moved (server-side) to: only5
2025/05/08 03:38:55 DEBUG : only5: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:55 INFO  : only5: Copied (replaced existing)
2025/05/08 03:38:55 DEBUG : only9: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:55 INFO  : only6.rclone_chunk.001_xh2mos: Moved (server-side) to: only6
2025/05/08 03:38:55 DEBUG : only6: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:55 INFO  : only6: Copied (replaced existing)
2025/05/08 03:38:55 DEBUG : only16: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:56 INFO  : only7.rclone_chunk.001_xh2ny2: Moved (server-side) to: only7
2025/05/08 03:38:56 DEBUG : only7: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:56 INFO  : only7: Copied (replaced existing)
2025/05/08 03:38:56 DEBUG : only18: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:38:56 INFO  : only8: Deleted
2025/05/08 03:38:58 INFO  : only8.rclone_chunk.001_xh2ppw: Moved (server-side) to: only8
2025/05/08 03:38:58 DEBUG : only8: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:38:58 INFO  : only8: Copied (replaced existing)
2025/05/08 03:38:58 DEBUG : only19: skip slow MD5 on source file, hashing in-transit
2025/05/08 03:39:03 INFO  : only9: Deleted
2025/05/08 03:39:05 INFO  : only18: Deleted
2025/05/08 03:39:05 INFO  : only16: Deleted
2025/05/08 03:39:05 INFO  : only9.rclone_chunk.001_xh2wjt: Moved (server-side) to: only9
2025/05/08 03:39:05 DEBUG : only9: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:39:05 INFO  : only9: Copied (replaced existing)
2025/05/08 03:39:06 INFO  : only19: Deleted
2025/05/08 03:39:06 INFO  : only16.rclone_chunk.001_xh2wfq: Moved (server-side) to: only16
2025/05/08 03:39:06 DEBUG : only16: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:39:06 INFO  : only16: Copied (replaced existing)
2025/05/08 03:39:07 INFO  : only18.rclone_chunk.001_xh2xhl: Moved (server-side) to: only18
2025/05/08 03:39:07 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:39:07 INFO  : only18: Copied (replaced existing)
2025/05/08 03:39:08 INFO  : only19.rclone_chunk.001_xh2zqj: Moved (server-side) to: only19
2025/05/08 03:39:08 DEBUG : only19: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/05/08 03:39:08 INFO  : only19: Copied (replaced existing)
2025/05/08 03:39:08 DEBUG : Waiting for deletions to finish
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncConcurrentTruncate (436.70s)
=== RUN   TestSyncReplaceDirModTime
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:42:09 DEBUG : empty_dir: Making directory with metadata
2025/05/08 03:42:09 INFO  : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/05/08 03:42:09 DEBUG : empty_on_remote: Making directory with metadata
2025/05/08 03:42:09 INFO  : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/05/08 03:42:09 INFO  : empty_on_remote: Making directory
2025/05/08 03:42:09 INFO  : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:42:17 INFO  : test_dir1: Set directory modification time (using DirSetModTime)
2025/05/08 03:42:17 INFO  : test_dir2: Set directory modification time (using DirSetModTime)
2025/05/08 03:42:17 INFO  : test_dir2/sub_dir: Set directory modification time (using DirSetModTime)
2025/05/08 03:42:17 INFO  : empty_on_remote: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncReplaceDirModTime (49.89s)
=== RUN   TestSyncReplaceDirModTimeWithEmptyDirs
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:42:59 DEBUG : empty_dir: Making directory with metadata
2025/05/08 03:42:59 INFO  : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/05/08 03:42:59 DEBUG : empty_on_remote: Making directory with metadata
2025/05/08 03:42:59 INFO  : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z)
2025/05/08 03:42:59 INFO  : empty_on_remote: Making directory
2025/05/08 03:42:59 INFO  : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:43:07 INFO  : test_dir1: Set directory modification time (using DirSetModTime)
2025/05/08 03:43:07 INFO  : test_dir2: Set directory modification time (using DirSetModTime)
2025/05/08 03:43:07 INFO  : test_dir2/sub_dir: Set directory modification time (using DirSetModTime)
2025/05/08 03:43:07 INFO  : empty_on_remote: Set directory modification time (using DirSetModTime)
2025/05/08 03:43:07 INFO  : empty_dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (55.01s)
=== RUN   TestNothingToTransferWithEmptyDirs
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:43:54 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/05/08 03:43:54 INFO  : sub dir: Making directory
2025/05/08 03:43:54 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
--- PASS: TestNothingToTransferWithEmptyDirs (95.45s)
=== RUN   TestNothingToTransferWithoutEmptyDirs
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
2025/05/08 03:45:29 INFO  : sub dir: Set directory modification time (using DirSetModTime)
2025/05/08 03:45:29 INFO  : sub dir: Making directory
2025/05/08 03:45:30 INFO  : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
    run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-vewexes2june'", Local "Local file system at /tmp/rclone3392324280", Modify Window "1ns"
panic: test timed out after 1h0m0s
	running tests:
		TestNothingToTransferWithoutEmptyDirs (28s)

goroutine 23305 [running]:
testing.(*M).startAlarm.func1()
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2373 +0x385
created by time.goFunc
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/time/sleep.go:215 +0x2d

goroutine 1 [chan receive]:
testing.(*T).Run(0xc00078a000, {0x26371fb?, 0x0?}, 0x271f8d0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1751 +0x3ab
testing.runTests.func1(0xc00078a000)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2168 +0x37
testing.tRunner(0xc00078a000, 0xc00006dc18)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1690 +0xf4
testing.runTests(0xc000b82570, {0x3cfdae0, 0x5c, 0x5c}, {0x3d148c0?, 0x1?, 0x3d148c0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2166 +0x43d
testing.(*M).Run(0xc000615540)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2034 +0x64a
github.com/rclone/rclone/fstest.TestMain(0xc000615540)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5
github.com/rclone/rclone/fs/sync.TestMain(...)
	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:44
main.main()
	_testmain.go:229 +0xa9

goroutine 22863 [semacquire]:
sync.runtime_Semacquire(0xc000aadb68?)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/sema.go:71 +0x25
sync.(*WaitGroup).Wait(0x29fbb60?)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/sync/waitgroup.go:118 +0x48
github.com/rclone/rclone/fs/walk.walk({0x29fbb60, 0x3d37da0}, {0x2a0fb80, 0xc0006fe000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc00109a270, 0x2720da8)
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:453 +0x355
github.com/rclone/rclone/fs/walk.walkListDirSorted(...)
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:349
github.com/rclone/rclone/fs/walk.Walk({0x29fbb60, 0x3d37da0}, {0x2a0fb80, 0xc0006fe000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc00109a270)
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:76 +0x2a5
github.com/rclone/rclone/fs/walk.listRwalk({0x29fbb60, 0x3d37da0}, {0x2a0fb80, 0xc0006fe000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc00109a240)
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:170 +0x110
github.com/rclone/rclone/fs/walk.ListR({0x29fbb60, 0x3d37da0}, {0x2a0fb80, 0xc0006fe000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc00109a240)
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:160 +0x236
github.com/rclone/rclone/fstest.newRunIndividual.func1()
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:150 +0x111
github.com/rclone/rclone/fstest.(*Run).Finalise(0xc0003f4d90)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 +0x1c
testing.(*common).Cleanup.func1()
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1176 +0x109
testing.(*common).runCleanup(0xc000494340, 0xc000800400?)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1354 +0xd5
testing.tRunner.func2()
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1684 +0x25
testing.tRunner(0xc000494340, 0x271f8d0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1696 +0x11e
created by testing.(*T).Run in goroutine 1
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1743 +0x390

goroutine 20 [syscall, 60 minutes]:
os/signal.signal_recv()
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/sigqueue.go:152 +0x29
os/signal.loop()
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/os/signal/signal_unix.go:23 +0x13
created by os/signal.Notify.func1.1 in goroutine 1
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/os/signal/signal.go:151 +0x1f

goroutine 21 [chan receive, 60 minutes]:
github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1()
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27
created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7

goroutine 23298 [select]:
github.com/rclone/rclone/fs/walk.walk.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16a
created by github.com/rclone/rclone/fs/walk.walk in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5

goroutine 23184 [select]:
github.com/rclone/rclone/fs/walk.walk.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16a
created by github.com/rclone/rclone/fs/walk.walk in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5

goroutine 23301 [select]:
github.com/rclone/rclone/fs/walk.walk.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16a
created by github.com/rclone/rclone/fs/walk.walk in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5

goroutine 23024 [select]:
net/http.(*persistConn).writeLoop(0xc000902240)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7
created by net/http.(*Transport).dialConn in goroutine 22987
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5

goroutine 23036 [select]:
net/http.(*persistConn).writeLoop(0xc0007bc120)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7
created by net/http.(*Transport).dialConn in goroutine 23029
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5

goroutine 23023 [IO wait]:
internal/poll.runtime_pollWait(0x7fda08114678, 0x72)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0xc0003c4400?, 0xc000916000?, 0x0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27
internal/poll.(*pollDesc).waitRead(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0xc0003c4400, {0xc000916000, 0x1300, 0x1300})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a
net.(*netFD).Read(0xc0003c4400, {0xc000916000?, 0xc000916000?, 0x2?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25
net.(*conn).Read(0xc000654240, {0xc000916000?, 0x3d148c0?, 0xc000b5b9d0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000656858, {0xc000916000?, 0x18?, 0xc000a2e0c0?})
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c
crypto/tls.(*atLeastReader).Read(0xc000657ea8, {0xc000916000?, 0x0?, 0xc000657ea8?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b
bytes.(*Buffer).ReadFrom(0xc0003609b8, {0x29d7040, 0xc000657ea8})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98
crypto/tls.(*Conn).readFromUntil(0xc000360708, {0x7fda016328e8, 0xc000656858}, 0xc000b5ba40?)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde
crypto/tls.(*Conn).readRecordOrCCS(0xc000360708, 0x0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf
crypto/tls.(*Conn).readRecord(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591
crypto/tls.(*Conn).Read(0xc000360708, {0xc0007a3000, 0x1000, 0xc000100508?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150
net/http.(*persistConn).Read(0xc000902240, {0xc0007a3000?, 0x81e525?, 0x2143d60?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a
bufio.(*Reader).fill(0xc0005340c0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103
bufio.(*Reader).Peek(0xc0005340c0, 0x1)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53
net/http.(*persistConn).readLoop(0xc000902240)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185
created by net/http.(*Transport).dialConn in goroutine 22987
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f

goroutine 23019 [select]:
net/http.(*persistConn).writeLoop(0xc0007bc000)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7
created by net/http.(*Transport).dialConn in goroutine 23010
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5

goroutine 23303 [select]:
net/http.(*persistConn).roundTrip(0xc0007bc000, 0xc000150320)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2755 +0x7b0
net/http.(*Transport).roundTrip(0xc0007e0000, 0xc0002e6f00)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:644 +0xb91
net/http.(*Transport).RoundTrip(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/roundtrip.go:30
github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0003eaa80, 0xc0002e6f00)
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345
golang.org/x/oauth2.(*Transport).RoundTrip(0xc00040f0a0, 0xc0002e6b40)
	/home/rclone/go/pkg/mod/golang.org/x/oauth2@v0.27.0/transport.go:55 +0x3de
net/http.send(0xc0002e6b40, {0x29d7be0, 0xc00040f0a0}, {0xc000e98e01?, 0x41790b?, 0x0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:259 +0x5e4
net/http.(*Client).send(0xc000161b90, 0xc0002e6b40, {0xc0008bc4ec?, 0x12?, 0x0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:180 +0x98
net/http.(*Client).do(0xc000161b90, 0xc0002e6b40)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:725 +0x8bc
net/http.(*Client).Do(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:590
github.com/rclone/rclone/lib/rest.(*Client).Call(0xc0000c40a0, {0x29fbb60, 0x3d37da0}, 0xc0002e6a00)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:353 +0xd67
github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc0000c40a0, {0x29fbb60, 0x3d37da0}, 0x47233d?, {0x0?, 0x0?}, {0x1fe9ae0, 0xc0001d29c0}, 0x40ba0b?, 0x2720fb0, ...)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:550 +0x3f2
github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:493
github.com/rclone/rclone/backend/yandex.(*Fs).readMetaDataForPath.func1()
	/home/rclone/go/src/github.com/rclone/rclone/backend/yandex/yandex.go:243 +0x85
github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0x40?)
	/home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32
github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0000c9500, 0xc000b80140, 0xa)
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c
github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0000c9500, 0xc000b80140)
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x92
github.com/rclone/rclone/backend/yandex.(*Fs).readMetaDataForPath(0xc000248480, {0x29fbb60, 0x3d37da0}, {0xc0009c6280, 0x1f}, 0xc000b56be8)
	/home/rclone/go/src/github.com/rclone/rclone/backend/yandex/yandex.go:242 +0x6b6
github.com/rclone/rclone/backend/yandex.(*Fs).List(0xc000248480, {0x29fbb60, 0x3d37da0}, {0x0, 0x0})
	/home/rclone/go/src/github.com/rclone/rclone/backend/yandex/yandex.go:366 +0x15b
github.com/rclone/rclone/backend/chunker.(*Fs).List(0xc0006fe000, {0x29fbb60, 0x3d37da0}, {0x0, 0x0})
	/home/rclone/go/src/github.com/rclone/rclone/backend/chunker/chunker.go:726 +0x38
github.com/rclone/rclone/fs/list.DirSorted({0x29fbb60, 0x3d37da0}, {0x2a0fb80, 0xc0006fe000}, 0x1, {0x0, 0x0})
	/home/rclone/go/src/github.com/rclone/rclone/fs/list/list.go:26 +0x6d
github.com/rclone/rclone/fs/walk.walk.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:403 +0x1e6
created by github.com/rclone/rclone/fs/walk.walk in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5

goroutine 23300 [select]:
github.com/rclone/rclone/fs/walk.walk.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16a
created by github.com/rclone/rclone/fs/walk.walk in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5

goroutine 23018 [IO wait]:
internal/poll.runtime_pollWait(0x7fda08113c28, 0x72)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0xc000ba8280?, 0xc000379800?, 0x0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27
internal/poll.(*pollDesc).waitRead(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0xc000ba8280, {0xc000379800, 0x1800, 0x1800})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a
net.(*netFD).Read(0xc000ba8280, {0xc000379800?, 0xc000379800?, 0x2?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25
net.(*conn).Read(0xc0006540e0, {0xc000379800?, 0x3d148c0?, 0xc00009f9d0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000656258, {0xc000379800?, 0x18?, 0x7fda0051ddd8?})
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c
crypto/tls.(*atLeastReader).Read(0xc0006e28d0, {0xc000379800?, 0x0?, 0xc0006e28d0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b
bytes.(*Buffer).ReadFrom(0xc000d702b8, {0x29d7040, 0xc0006e28d0})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98
crypto/tls.(*Conn).readFromUntil(0xc000d70008, {0x7fda016328e8, 0xc000656258}, 0xc00009fa40?)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde
crypto/tls.(*Conn).readRecordOrCCS(0xc000d70008, 0x0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf
crypto/tls.(*Conn).readRecord(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591
crypto/tls.(*Conn).Read(0xc000d70008, {0xc0006c6000, 0x1000, 0xc000800508?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150
net/http.(*persistConn).Read(0xc0007bc000, {0xc0006c6000?, 0x81e525?, 0x2143d60?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a
bufio.(*Reader).fill(0xc000a0d500)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103
bufio.(*Reader).Peek(0xc000a0d500, 0x1)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53
net/http.(*persistConn).readLoop(0xc0007bc000)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185
created by net/http.(*Transport).dialConn in goroutine 23010
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f

goroutine 23035 [IO wait]:
internal/poll.runtime_pollWait(0x7fda08113d30, 0x72)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0xc000ba8600?, 0xc000917300?, 0x0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27
internal/poll.(*pollDesc).waitRead(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0xc000ba8600, {0xc000917300, 0x1300, 0x1300})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a
net.(*netFD).Read(0xc000ba8600, {0xc000917300?, 0xc000917300?, 0x2?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25
net.(*conn).Read(0xc00094e1d8, {0xc000917300?, 0x3d148c0?, 0xc00009b9d0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000b82cf0, {0xc000917300?, 0x18?, 0xc0006f9040?})
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c
crypto/tls.(*atLeastReader).Read(0xc000657b48, {0xc000917300?, 0x0?, 0xc000657b48?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b
bytes.(*Buffer).ReadFrom(0xc000b4cd38, {0x29d7040, 0xc000657b48})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98
crypto/tls.(*Conn).readFromUntil(0xc000b4ca88, {0x7fda016328e8, 0xc000b82cf0}, 0xc00009ba40?)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde
crypto/tls.(*Conn).readRecordOrCCS(0xc000b4ca88, 0x0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf
crypto/tls.(*Conn).readRecord(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591
crypto/tls.(*Conn).Read(0xc000b4ca88, {0xc0007da000, 0x1000, 0x0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150
net/http.(*persistConn).Read(0xc0007bc120, {0xc0007da000?, 0x81e525?, 0x2143d60?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a
bufio.(*Reader).fill(0xc000d39080)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103
bufio.(*Reader).Peek(0xc000d39080, 0x1)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53
net/http.(*persistConn).readLoop(0xc0007bc120)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185
created by net/http.(*Transport).dialConn in goroutine 23029
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f

goroutine 23302 [select]:
github.com/rclone/rclone/fs/walk.walk.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16a
created by github.com/rclone/rclone/fs/walk.walk in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5

goroutine 21761 [IO wait]:
internal/poll.runtime_pollWait(0x7fda00d8bea8, 0x72)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0xc000ba9f00?, 0xc000378000?, 0x0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27
internal/poll.(*pollDesc).waitRead(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0xc000ba9f00, {0xc000378000, 0x1800, 0x1800})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a
net.(*netFD).Read(0xc000ba9f00, {0xc000378000?, 0xc000378000?, 0x2?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25
net.(*conn).Read(0xc0006f0498, {0xc000378000?, 0x3d148c0?, 0xc000b599d0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000d17560, {0xc000378000?, 0x18?, 0x7fda0813d758?})
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c
crypto/tls.(*atLeastReader).Read(0xc0006e2258, {0xc000378000?, 0x0?, 0xc0006e2258?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b
bytes.(*Buffer).ReadFrom(0xc000d710b8, {0x29d7040, 0xc0006e2258})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98
crypto/tls.(*Conn).readFromUntil(0xc000d70e08, {0x7fda016328e8, 0xc000d17560}, 0xc000b59a40?)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde
crypto/tls.(*Conn).readRecordOrCCS(0xc000d70e08, 0x0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf
crypto/tls.(*Conn).readRecord(...)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591
crypto/tls.(*Conn).Read(0xc000d70e08, {0xc000790000, 0x1000, 0x0?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150
net/http.(*persistConn).Read(0xc000902000, {0xc000790000?, 0x81e525?, 0x2143d60?})
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a
bufio.(*Reader).fill(0xc000dabaa0)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103
bufio.(*Reader).Peek(0xc000dabaa0, 0x1)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53
net/http.(*persistConn).readLoop(0xc000902000)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185
created by net/http.(*Transport).dialConn in goroutine 21731
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f

goroutine 3405 [chan receive, 52 minutes]:
github.com/rclone/rclone/lib/atexit.Register.func1.1()
	/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29
created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 3358
	/home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68

goroutine 23147 [select]:
github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0007a76c0)
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:343 +0x13d
created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:408 +0xb6

goroutine 23299 [select]:
github.com/rclone/rclone/fs/walk.walk.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16a
created by github.com/rclone/rclone/fs/walk.walk in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5

goroutine 21778 [select]:
net/http.(*persistConn).writeLoop(0xc000902000)
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7
created by net/http.(*Transport).dialConn in goroutine 21731
	/home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5

goroutine 23185 [select]:
github.com/rclone/rclone/fs/walk.walk.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:398 +0x16a
created by github.com/rclone/rclone/fs/walk.walk in goroutine 22863
	/home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5
"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024" - Finished ERROR in 1h0m0.659685802s (try 1/5): exit status 2: Failed [TestServerSideMoveDeleteEmptySourceDirs TestSyncBackupDirWithSuffixKeepExtension]