"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bYandex: -verbose -fast-list -size-limit 1024" - Starting (try 1/5) 2025/04/24 04:39:09 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa" 2025/04/24 04:39:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/24 04:39:09 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa" 2025/04/24 04:39:09 DEBUG : Creating backend with remote "/tmp/rclone524710327" === 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-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:39:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:39:10 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/04/24 04:39:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:39:10 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyWithDryRun (2.60s) === RUN TestCopy run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:39:12 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/24 04:39:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:39:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:39:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:39:13 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:39:18 INFO : sub dir/hello world.rclone_chunk.001_7mjerq: Moved (server-side) to: sub dir/hello world 2025/04/24 04:39:18 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:39:18 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:39:19 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (18.74s) === RUN TestCopyMetadata run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.56s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.74s) === RUN TestCopyMissingDirectory run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:39:32 DEBUG : Creating backend with remote "/non-existing" 2025/04/24 04:39:33 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/04/24 04:39:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:39:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (2.64s) === RUN TestCopyNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:39:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:39:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:39:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:39:35 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:39:41 INFO : sub dir/hello world.rclone_chunk.001_7mk03m: Moved (server-side) to: sub dir/hello world 2025/04/24 04:39:41 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:39:41 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyNoTraverse (18.69s) === RUN TestCopyCheckFirst run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:39:53 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Running all checks before starting transfers 2025/04/24 04:39:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:39:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:39:54 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Checks finished, now starting transfers 2025/04/24 04:39:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:39:54 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:39:59 INFO : sub dir/hello world.rclone_chunk.001_7mkj7j: Moved (server-side) to: sub dir/hello world 2025/04/24 04:39:59 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:39:59 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyCheckFirst (17.74s) === RUN TestSyncNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:40:11 ERROR : Ignoring --no-traverse with sync 2025/04/24 04:40:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:40:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:40:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:40:12 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:40:17 INFO : sub dir/hello world.rclone_chunk.001_7ml167: Moved (server-side) to: sub dir/hello world 2025/04/24 04:40:17 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:40:17 INFO : sub dir/hello world: Copied (new) 2025/04/24 04:40:17 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncNoTraverse (25.90s) === RUN TestCopyWithDepth run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:40:37 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/04/24 04:40:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:40:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:40:38 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:40:43 INFO : hello world2.rclone_chunk.001_7mlr6s: Moved (server-side) to: hello world2 2025/04/24 04:40:43 DEBUG : hello world2: md5 = cc2c857f89648dbd139d7b2a6665957d OK 2025/04/24 04:40:43 INFO : hello world2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyWithDepth (11.53s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:40:49 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 04:40:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:40:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:40:49 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:40:54 INFO : potato2.rclone_chunk.001_7mm2go: Moved (server-side) to: potato2 2025/04/24 04:40:54 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:40:54 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (12.45s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:41:01 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 04:41:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:41:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:41:02 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:41:07 INFO : potato2.rclone_chunk.001_7mmf4v: Moved (server-side) to: potato2 2025/04/24 04:41:07 DEBUG : potato2: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:41:07 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (11.92s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:41:13 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/24 04:41:13 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/24 04:41:13 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/04/24 04:41:13 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/24 04:41:13 INFO : sub dir: Making directory 2025/04/24 04:41:14 INFO : sub dir2: Making directory 2025/04/24 04:41:14 INFO : sub dir2/sub sub dir2: Making directory 2025/04/24 04:41:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:41:14 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:41:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:41:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:41:20 INFO : sub dir/hello world.rclone_chunk.001_7mmrpj: Moved (server-side) to: sub dir/hello world 2025/04/24 04:41:20 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:41:20 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:41:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (29.83s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:41:43 INFO : sub dir2: Making directory 2025/04/24 04:41:43 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/24 04:41:43 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/24 04:41:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:41:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:41:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:41:43 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:41:47 INFO : sub dir/hello world.rclone_chunk.001_7mnkoj: Moved (server-side) to: sub dir/hello world 2025/04/24 04:41:47 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:41:47 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (15.87s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:41:59 DEBUG : sub dir2: Making directory with metadata 2025/04/24 04:41:59 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/24 04:41:59 INFO : sub dir: Making directory 2025/04/24 04:42:00 INFO : sub dir2: Making directory 2025/04/24 04:42:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:42:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:42:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:42:00 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:42:05 INFO : sub dir/hello world.rclone_chunk.001_7mo1dx: Moved (server-side) to: sub dir/hello world 2025/04/24 04:42:05 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:42:05 INFO : sub dir/hello world: Copied (new) 2025/04/24 04:42:05 INFO : sub dir/hello world: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:42:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/04/24 04:42:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (24.87s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.60s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:42:24 INFO : sub dir2: Making directory 2025/04/24 04:42:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:42:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:42:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:42:25 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:42:29 INFO : sub dir/hello world.rclone_chunk.001_7moqst: Moved (server-side) to: sub dir/hello world 2025/04/24 04:42:29 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:42:29 INFO : sub dir/hello world: Copied (new) 2025/04/24 04:42:29 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (16.23s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:42:40 DEBUG : sub dir2: Making directory with metadata 2025/04/24 04:42:40 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/24 04:42:40 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/24 04:42:41 INFO : sub dir: Making directory 2025/04/24 04:42:41 INFO : sub dir2: Making directory 2025/04/24 04:42:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:42:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:42:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:42:43 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:42:49 INFO : sub dir/hello world.rclone_chunk.001_7mp8wj: Moved (server-side) to: sub dir/hello world 2025/04/24 04:42:49 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:42:49 INFO : sub dir/hello world: Copied (new) 2025/04/24 04:42:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:42:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (26.01s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.47s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:43:07 INFO : sub dir2: Making directory 2025/04/24 04:43:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:43:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:43:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:43:08 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:43:13 INFO : sub dir/hello world.rclone_chunk.001_7mpxct: Moved (server-side) to: sub dir/hello world 2025/04/24 04:43:13 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:43:13 INFO : sub dir/hello world: Copied (new) 2025/04/24 04:43:13 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (17.72s) === RUN TestServerSideCopy run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:43:30 INFO : sub dir/hello world.rclone_chunk.001_7mqefd: Moved (server-side) to: sub dir/hello world 2025/04/24 04:43:30 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-xobulek6qecu" 2025/04/24 04:43:30 DEBUG : Creating backend with remote "TestYandex:rclone-test-xobulek6qecu" sync_test.go:590: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-xobulek6qecu' 2025/04/24 04:43:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:43:31 DEBUG : sub dir/hello world: copy non-chunked object... 2025/04/24 04:43:31 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xobulek6qecu': Waiting for checks to finish 2025/04/24 04:43:31 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xobulek6qecu': Waiting for transfers to finish 2025/04/24 04:43:34 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:43:34 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:43:36 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-xobulek6qecu': Purge remote --- PASS: TestServerSideCopy (29.33s) === RUN TestCopyOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:43:59 INFO : sub dir/hello world.rclone_chunk.001_7mr779: Moved (server-side) to: sub dir/hello world 2025/04/24 04:44:00 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/24 04:44:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:44:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:44:00 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:44:08 INFO : sub dir/hello world: Deleted 2025/04/24 04:44:10 INFO : sub dir/hello world.rclone_chunk.001_7mrd7n: Moved (server-side) to: sub dir/hello world 2025/04/24 04:44:10 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/04/24 04:44:10 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyOverSelf (28.34s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:44:27 INFO : sub dir/hello world.rclone_chunk.001_7mrzkd: Moved (server-side) to: sub dir/hello world 2025/04/24 04:44:28 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-kebawal7maqi" 2025/04/24 04:44:28 DEBUG : Creating backend with remote "TestYandex:rclone-test-kebawal7maqi" sync_test.go:626: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-kebawal7maqi' 2025/04/24 04:44:29 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:44:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kebawal7maqi': Waiting for checks to finish 2025/04/24 04:44:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kebawal7maqi': Waiting for transfers to finish 2025/04/24 04:44:29 DEBUG : sub dir/hello world: copy non-chunked object... 2025/04/24 04:44:32 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:44:32 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:44:43 INFO : sub dir/hello world: Deleted 2025/04/24 04:44:45 INFO : sub dir/hello world.rclone_chunk.001_7msbfq: Moved (server-side) to: sub dir/hello world 2025/04/24 04:44:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kebawal7maqi': Waiting for checks to finish 2025/04/24 04:44:47 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/24 04:44:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kebawal7maqi': Waiting for transfers to finish 2025/04/24 04:44:47 DEBUG : sub dir/hello world: copy non-chunked object... 2025/04/24 04:44:48 DEBUG : sub dir/hello world: server side copy: renaming existing object to "sub dir/hello world.xujalun6" before starting 2025/04/24 04:44:51 DEBUG : sub dir/hello world.xujalun6: server side copy: removing renamed existing file after operation 2025/04/24 04:44:55 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/04/24 04:44:55 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:44:56 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kebawal7maqi': Purge remote --- PASS: TestServerSideCopyOverSelf (53.52s) === RUN TestMoveOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:45:21 INFO : sub dir/hello world.rclone_chunk.001_7mthrk: Moved (server-side) to: sub dir/hello world 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:649 Error: Should be true Test: TestMoveOverSelf Messages: sub dir/hello world: Modification time difference too big |-212304h40m13.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-04-24 04:45:20 +0000 UTC) (precision 1ns) 2025/04/24 04:45:22 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/24 04:45:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:45:22 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:45:22 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:45:30 INFO : sub dir/hello world: Deleted 2025/04/24 04:45:32 INFO : sub dir/hello world.rclone_chunk.001_7mtndt: Moved (server-side) to: sub dir/hello world 2025/04/24 04:45:32 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/04/24 04:45:32 INFO : sub dir/hello world: Copied (replaced existing) 2025/04/24 04:45:32 INFO : sub dir/hello world: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- FAIL: TestMoveOverSelf (30.12s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:45:52 INFO : sub dir/hello world.rclone_chunk.001_7mubws: Moved (server-side) to: sub dir/hello world 2025/04/24 04:45:52 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe" 2025/04/24 04:45:52 DEBUG : Config file has changed externally - reloading 2025/04/24 04:45:52 DEBUG : Creating backend with remote "TestYandex:rclone-test-kiyowid4zafe" sync_test.go:671: Server side copy (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe' 2025/04/24 04:45:53 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:45:53 DEBUG : sub dir/hello world: copy non-chunked object... 2025/04/24 04:45:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Waiting for checks to finish 2025/04/24 04:45:53 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Waiting for transfers to finish 2025/04/24 04:45:59 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:45:59 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:46:10 INFO : sub dir/hello world: Deleted 2025/04/24 04:46:12 INFO : sub dir/hello world.rclone_chunk.001_7mus4y: Moved (server-side) to: sub dir/hello world 2025/04/24 04:46:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Using server-side directory move 2025/04/24 04:46:13 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/24 04:46:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Waiting for checks to finish 2025/04/24 04:46:14 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/24 04:46:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Waiting for transfers to finish 2025/04/24 04:46:19 INFO : sub dir/hello world: Deleted 2025/04/24 04:46:19 DEBUG : sub dir/hello world: move non-chunked object... 2025/04/24 04:46:21 INFO : sub dir/hello world: Moved (server-side) 2025/04/24 04:46:21 INFO : sub dir/hello world: Moved (server-side) 2025/04/24 04:46:28 INFO : sub dir/hello world.rclone_chunk.001_7mvbl8: Moved (server-side) to: sub dir/hello world 2025/04/24 04:46:29 DEBUG : testing file moves 2025/04/24 04:46:29 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/04/24 04:46:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Waiting for checks to finish 2025/04/24 04:46:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Waiting for transfers to finish 2025/04/24 04:46:34 INFO : sub dir/hello world: Deleted 2025/04/24 04:46:34 DEBUG : sub dir/hello world: move non-chunked object... 2025/04/24 04:46:37 INFO : sub dir/hello world: Moved (server-side) 2025/04/24 04:46:37 INFO : sub dir/hello world: Moved (server-side) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:46:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-kiyowid4zafe': Purge remote --- PASS: TestServerSideMoveOverSelf (66.61s) === RUN TestCopyAfterDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:46:58 INFO : sub dir/hello world.rclone_chunk.001_7mw6uj: Moved (server-side) to: sub dir/hello world 2025/04/24 04:46:58 ERROR : : error listing: directory not found 2025/04/24 04:46:59 INFO : Local file system at /tmp/rclone524710327: Making directory 2025/04/24 04:46:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:46:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyAfterDelete (26.41s) === RUN TestCopyRedownload run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:47:23 INFO : sub dir/hello world.rclone_chunk.001_7mwwq7: Moved (server-side) to: sub dir/hello world 2025/04/24 04:47:27 DEBUG : Added delayed dir = "sub dir", newDst= 2025/04/24 04:47:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 04:47:27 DEBUG : Local file system at /tmp/rclone524710327: Waiting for checks to finish 2025/04/24 04:47:27 DEBUG : Local file system at /tmp/rclone524710327: Waiting for transfers to finish 2025/04/24 04:47:28 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 04:47:28 DEBUG : sub dir/hello world.7ddbfcba.partial: renamed to: sub dir/hello world 2025/04/24 04:47:28 INFO : sub dir/hello world: Copied (new) 2025/04/24 04:47:28 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyRedownload (21.87s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:47:41 DEBUG : check sum: Need to transfer - File not found at Destination 2025/04/24 04:47:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:47:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:47:42 DEBUG : check sum: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:47:46 INFO : check sum.rclone_chunk.001_7mxjgs: Moved (server-side) to: check sum 2025/04/24 04:47:46 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/04/24 04:47:46 INFO : check sum: Copied (new) 2025/04/24 04:47:46 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:47:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:47:47 DEBUG : check sum: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/04/24 04:47:47 DEBUG : check sum: Size and md5 of src and dst objects identical 2025/04/24 04:47:47 DEBUG : check sum: Unchanged skipping 2025/04/24 04:47:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:47:47 DEBUG : Waiting for deletions to finish 2025/04/24 04:47:47 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (15.43s) === RUN TestSyncSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:47:57 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/04/24 04:47:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:47:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:47:57 DEBUG : sizeonly: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:48:06 INFO : sizeonly.rclone_chunk.001_7mxy86: Moved (server-side) to: sizeonly 2025/04/24 04:48:06 DEBUG : sizeonly: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/04/24 04:48:06 INFO : sizeonly: Copied (new) 2025/04/24 04:48:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:48:07 DEBUG : sizeonly: Sizes identical 2025/04/24 04:48:07 DEBUG : sizeonly: Unchanged skipping 2025/04/24 04:48:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:48:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:48:07 DEBUG : Waiting for deletions to finish 2025/04/24 04:48:07 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncSizeOnly (17.23s) === RUN TestSyncIgnoreSize run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:48:14 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/04/24 04:48:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:48:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:48:14 DEBUG : ignore-size: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:48:19 INFO : ignore-size.rclone_chunk.001_7myfxi: Moved (server-side) to: ignore-size 2025/04/24 04:48:19 DEBUG : ignore-size: md5 = 98bf7d8c15784f0a3d63204441e1e2aa OK 2025/04/24 04:48:19 INFO : ignore-size: Copied (new) 2025/04/24 04:48:19 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:48:20 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:48:20 DEBUG : ignore-size: Unchanged skipping 2025/04/24 04:48:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:48:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:48:20 DEBUG : Waiting for deletions to finish 2025/04/24 04:48:20 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (13.02s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:48:32 INFO : existing.rclone_chunk.001_7mysdy: Moved (server-side) to: existing 2025/04/24 04:48:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:48:33 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:48:33 DEBUG : existing: Unchanged skipping 2025/04/24 04:48:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:48:33 DEBUG : Waiting for deletions to finish 2025/04/24 04:48:33 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:48:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:48:34 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/04/24 04:48:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:48:34 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:48:42 INFO : existing: Deleted 2025/04/24 04:48:47 INFO : existing.rclone_chunk.001_7myz95: Moved (server-side) to: existing 2025/04/24 04:48:47 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/04/24 04:48:47 INFO : existing: Copied (replaced existing) 2025/04/24 04:48:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (28.29s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:48:55 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/24 04:48:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:48:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:48:55 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:48:59 INFO : existing.rclone_chunk.001_7mzkda: Moved (server-side) to: existing 2025/04/24 04:48:59 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/04/24 04:48:59 INFO : existing: Copied (new) 2025/04/24 04:48:59 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:49:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:49:01 DEBUG : existing: Destination exists, skipping 2025/04/24 04:49:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:49:01 DEBUG : Waiting for deletions to finish 2025/04/24 04:49:01 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (12.57s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:49:13 INFO : b/potato.rclone_chunk.001_7mzx7p: Moved (server-side) to: b/potato 2025/04/24 04:49:19 INFO : c/non empty space.rclone_chunk.001_7n0206: Moved (server-side) to: c/non empty space 2025/04/24 04:49:19 INFO : d: Making directory 2025/04/24 04:49:20 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/24 04:49:21 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:49:21 DEBUG : c/non empty space: Unchanged skipping 2025/04/24 04:49:21 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:49:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:49:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:49:27 INFO : a/potato2.rclone_chunk.001_7n0abm: Moved (server-side) to: a/potato2.rclone_chunk.001 2025/04/24 04:49:28 INFO : a/potato2.rclone_chunk.002_7n0abm: Moved (server-side) to: a/potato2.rclone_chunk.002 2025/04/24 04:49:31 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/04/24 04:49:31 INFO : a/potato2: Copied (new) 2025/04/24 04:49:31 DEBUG : Waiting for deletions to finish 2025/04/24 04:49:36 INFO : b/potato: Deleted 2025/04/24 04:49:36 INFO : d: Removing directory 2025/04/24 04:49:42 INFO : b: Removing directory 2025/04/24 04:49:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': deleted 2 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (69.43s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:50:22 INFO : empty space.rclone_chunk.001_7n1ujt: Moved (server-side) to: empty space 2025/04/24 04:50:24 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/04/24 04:50:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:50:24 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/04/24 04:50:24 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/04/24 04:50:24 DEBUG : empty space: Unchanged skipping 2025/04/24 04:50:24 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:50:24 DEBUG : Waiting for deletions to finish 2025/04/24 04:50:24 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:50:25 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/04/24 04:50:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:50:25 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/04/24 04:50:25 INFO : empty space: Updated modification time in destination 2025/04/24 04:50:25 DEBUG : empty space: Unchanged skipping 2025/04/24 04:50:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:50:25 DEBUG : Waiting for deletions to finish 2025/04/24 04:50:25 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (15.91s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:50:38 INFO : empty space.rclone_chunk.001_7n2agj: Moved (server-side) to: empty space 2025/04/24 04:50:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:50:39 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/04/24 04:50:39 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/04/24 04:50:39 DEBUG : empty space: Unchanged skipping 2025/04/24 04:50:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:50:39 DEBUG : Waiting for deletions to finish 2025/04/24 04:50:39 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (13.01s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:50:50 INFO : foo.rclone_chunk.001_7n2nc9: Moved (server-side) to: foo 2025/04/24 04:50:51 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/04/24 04:50:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:50:51 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 (Local file system at /tmp/rclone524710327) 2025/04/24 04:50:51 DEBUG : foo: md5 = 37b51d194a7513e45b56f6524f2d51f2 (Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa') 2025/04/24 04:50:51 DEBUG : foo: md5 differ 2025/04/24 04:50:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:50:51 DEBUG : foo: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:51:00 INFO : foo: Deleted 2025/04/24 04:51:01 INFO : foo.rclone_chunk.001_7n2sal: Moved (server-side) to: foo 2025/04/24 04:51:01 DEBUG : foo: md5 = acbd18db4cc2f85cedef654fccc4a4d8 OK 2025/04/24 04:51:01 INFO : foo: Copied (replaced existing) 2025/04/24 04:51:01 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (22.62s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:51:14 INFO : empty space.rclone_chunk.001_7n3911: Moved (server-side) to: empty space 2025/04/24 04:51:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:51:15 DEBUG : potato: Need to transfer - File not found at Destination 2025/04/24 04:51:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:51:15 DEBUG : empty space: Unchanged skipping 2025/04/24 04:51:15 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:51:15 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:51:23 INFO : potato.rclone_chunk.001_7n3gxj: Moved (server-side) to: potato.rclone_chunk.001 2025/04/24 04:51:24 INFO : potato.rclone_chunk.002_7n3gxj: Moved (server-side) to: potato.rclone_chunk.002 2025/04/24 04:51:28 DEBUG : potato: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/04/24 04:51:28 INFO : potato: Copied (new) 2025/04/24 04:51:28 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (42.63s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:51:59 INFO : potato.rclone_chunk.001_7n4gsz: Moved (server-side) to: potato.rclone_chunk.001 2025/04/24 04:52:00 INFO : potato.rclone_chunk.002_7n4gsz: Moved (server-side) to: potato.rclone_chunk.002 2025/04/24 04:52:05 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/04/24 04:52:05 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:52:05 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:52:05 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:52:21 INFO : potato: Deleted 2025/04/24 04:52:23 INFO : potato.rclone_chunk.001_7n4u3s: Moved (server-side) to: potato 2025/04/24 04:52:23 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 OK 2025/04/24 04:52:23 INFO : potato: Copied (replaced existing) 2025/04/24 04:52:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (38.37s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:52:34 INFO : potato.rclone_chunk.001_7n5itk: Moved (server-side) to: potato 2025/04/24 04:52:34 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/04/24 04:52:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:52:34 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 (Local file system at /tmp/rclone524710327) 2025/04/24 04:52:34 DEBUG : potato: md5 = 100defcf18c42a1e0dc42a789b107cd2 (Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa') 2025/04/24 04:52:34 DEBUG : potato: md5 differ 2025/04/24 04:52:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:52:34 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:52:43 INFO : potato: Deleted 2025/04/24 04:52:44 INFO : potato.rclone_chunk.001_7n5nb9: Moved (server-side) to: potato 2025/04/24 04:52:44 DEBUG : potato: md5 = e4cb6955d9106df6263c45fcfc10f163 OK 2025/04/24 04:52:44 INFO : potato: Copied (replaced existing) 2025/04/24 04:52:44 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (22.88s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:52:57 INFO : potato.rclone_chunk.001_7n658n: Moved (server-side) to: potato 2025/04/24 04:53:02 INFO : empty space.rclone_chunk.001_7n6az0: Moved (server-side) to: empty space 2025/04/24 04:53:02 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 04:53:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:53:02 DEBUG : empty space: Unchanged skipping 2025/04/24 04:53:02 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/04/24 04:53:02 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:53:02 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:53:02 DEBUG : Waiting for deletions to finish 2025/04/24 04:53:02 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (21.05s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:53:18 INFO : potato.rclone_chunk.001_7n6q1w: Moved (server-side) to: potato 2025/04/24 04:53:24 INFO : empty space.rclone_chunk.001_7n6wei: Moved (server-side) to: empty space 2025/04/24 04:53:25 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:53:25 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 04:53:25 DEBUG : empty space: Unchanged skipping 2025/04/24 04:53:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:53:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:53:25 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:53:32 INFO : potato2.rclone_chunk.001_7n72on: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/24 04:53:33 INFO : potato2.rclone_chunk.002_7n72on: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/24 04:53:36 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/04/24 04:53:36 INFO : potato2: Copied (new) 2025/04/24 04:53:36 DEBUG : Waiting for deletions to finish 2025/04/24 04:53:41 INFO : potato: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (48.70s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:54:09 INFO : b/potato.rclone_chunk.001_7n83lb: Moved (server-side) to: b/potato 2025/04/24 04:54:14 INFO : c/non empty space.rclone_chunk.001_7n8aa3: Moved (server-side) to: c/non empty space 2025/04/24 04:54:14 INFO : d: Making directory 2025/04/24 04:54:15 INFO : d/e: Making directory 2025/04/24 04:54:17 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/24 04:54:17 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:54:17 DEBUG : c/non empty space: Unchanged skipping 2025/04/24 04:54:18 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:54:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:54:18 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:54:25 INFO : a/potato2.rclone_chunk.001_7n8j4j: Moved (server-side) to: a/potato2.rclone_chunk.001 2025/04/24 04:54:26 INFO : a/potato2.rclone_chunk.002_7n8j4j: Moved (server-side) to: a/potato2.rclone_chunk.002 2025/04/24 04:54:30 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/04/24 04:54:30 INFO : a/potato2: Copied (new) 2025/04/24 04:54:30 DEBUG : Waiting for deletions to finish 2025/04/24 04:54:34 INFO : b/potato: Deleted 2025/04/24 04:54:34 INFO : d/e: Removing directory 2025/04/24 04:54:38 INFO : d: Removing directory 2025/04/24 04:54:44 INFO : b: Removing directory 2025/04/24 04:54:48 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': deleted 3 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (78.96s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:55:26 INFO : b/potato.rclone_chunk.001_7naanj: Moved (server-side) to: b/potato 2025/04/24 04:55:32 INFO : c/non empty space.rclone_chunk.001_7nafwq: Moved (server-side) to: c/non empty space 2025/04/24 04:55:32 INFO : d: Making directory 2025/04/24 04:55:33 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/24 04:55:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:55:34 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:55:34 DEBUG : c/non empty space: Unchanged skipping 2025/04/24 04:55:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:55:34 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:55:41 INFO : a/potato2.rclone_chunk.001_7nan08: Moved (server-side) to: a/potato2.rclone_chunk.001 2025/04/24 04:55:42 INFO : a/potato2.rclone_chunk.002_7nan08: Moved (server-side) to: a/potato2.rclone_chunk.002 2025/04/24 04:55:45 DEBUG : a/potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/04/24 04:55:45 INFO : a/potato2: Copied (new) 2025/04/24 04:55:45 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': not deleting files as there were IO errors 2025/04/24 04:55:45 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (69.98s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:56:36 INFO : potato.rclone_chunk.001_7nc8nr: Moved (server-side) to: potato 2025/04/24 04:56:41 INFO : empty space.rclone_chunk.001_7nce01: Moved (server-side) to: empty space 2025/04/24 04:56:41 DEBUG : Waiting for deletions to finish 2025/04/24 04:56:42 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 04:56:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:56:42 DEBUG : empty space: Unchanged skipping 2025/04/24 04:56:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:56:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:56:42 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:56:46 INFO : potato: Deleted 2025/04/24 04:56:50 INFO : potato2.rclone_chunk.001_7ncjjx: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/24 04:56:52 INFO : potato2.rclone_chunk.002_7ncjjx: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/24 04:56:55 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/04/24 04:56:55 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (45.92s) === RUN TestSyncDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:57:23 INFO : potato.rclone_chunk.001_7ndi5q: Moved (server-side) to: potato 2025/04/24 04:57:29 INFO : empty space.rclone_chunk.001_7ndoks: Moved (server-side) to: empty space 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:1151 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1326 Error: Should be true Test: TestSyncDeleteBefore Messages: empty space: Modification time difference too big |-116847h57m28.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-24 04:57:28 +0000 UTC) (precision 1ns) 2025/04/24 04:57:29 DEBUG : Waiting for deletions to finish 2025/04/24 04:57:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:57:30 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:57:34 INFO : potato: Deleted 2025/04/24 04:57:35 DEBUG : empty space: Modification times differ by 116847h57m28.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2025-04-24 04:57:28 +0000 UTC 2025/04/24 04:57:35 DEBUG : empty space: md5 = 336d5ebc5436534e61d16e63ddfca327 OK 2025/04/24 04:57:35 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 04:57:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:57:35 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:57:35 INFO : empty space: Updated modification time in destination 2025/04/24 04:57:35 DEBUG : empty space: Unchanged skipping 2025/04/24 04:57:35 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:57:43 INFO : potato2.rclone_chunk.001_7ne0q3: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/24 04:57:45 INFO : potato2.rclone_chunk.002_7ne0q3: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/24 04:57:48 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK 2025/04/24 04:57:48 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- FAIL: TestSyncDeleteBefore (52.89s) === RUN TestCopyDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:58:15 INFO : potato.rclone_chunk.001_7nezrk: Moved (server-side) to: potato 2025/04/24 04:58:16 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 04:58:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:58:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:58:16 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/04/24 04:58:21 INFO : potato2.rclone_chunk.001_7nf55o: Moved (server-side) to: potato2 2025/04/24 04:58:21 DEBUG : potato2: md5 = 9519bc2e940e1696ea7365b4b81adedc OK 2025/04/24 04:58:21 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (22.69s) === RUN TestSyncWithExclude run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:58:40 INFO : potato2.rclone_chunk.001_7nfm64: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/24 04:58:42 INFO : potato2.rclone_chunk.002_7nfm64: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/24 04:58:49 INFO : empty space.rclone_chunk.001_7nfxtn: Moved (server-side) to: empty space 2025/04/24 04:58:50 DEBUG : enormous: Excluded (Size Filter) 2025/04/24 04:58:50 DEBUG : enormous: Excluded 2025/04/24 04:58:50 DEBUG : potato2: Excluded (Size Filter) 2025/04/24 04:58:50 DEBUG : potato2: Excluded 2025/04/24 04:58:51 DEBUG : potato2: Excluded (Size Filter) 2025/04/24 04:58:51 DEBUG : potato2: Excluded 2025/04/24 04:58:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:58:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:58:51 DEBUG : empty space: Unchanged skipping 2025/04/24 04:58:51 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:58:51 DEBUG : Waiting for deletions to finish 2025/04/24 04:58:51 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:58:51 DEBUG : potato2: Excluded (Size Filter) 2025/04/24 04:58:51 DEBUG : potato2: Excluded 2025/04/24 04:58:52 DEBUG : enormous: Excluded (Size Filter) 2025/04/24 04:58:52 DEBUG : enormous: Excluded 2025/04/24 04:58:52 DEBUG : potato2: Excluded (Size Filter) 2025/04/24 04:58:52 DEBUG : potato2: Excluded 2025/04/24 04:58:52 DEBUG : potato2: Excluded (Size Filter) 2025/04/24 04:58:52 DEBUG : potato2: Excluded 2025/04/24 04:58:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:58:52 DEBUG : empty space: Unchanged skipping 2025/04/24 04:58:52 DEBUG : Local file system at /tmp/rclone524710327: Waiting for checks to finish 2025/04/24 04:58:52 DEBUG : Local file system at /tmp/rclone524710327: Waiting for transfers to finish 2025/04/24 04:58:52 DEBUG : Waiting for deletions to finish 2025/04/24 04:58:52 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:58:53 DEBUG : potato2: Excluded (Size Filter) 2025/04/24 04:58:53 DEBUG : potato2: Excluded --- PASS: TestSyncWithExclude (40.72s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 04:59:19 INFO : potato2.rclone_chunk.001_7ngqp7: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/24 04:59:22 INFO : potato2.rclone_chunk.002_7ngqp7: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/24 04:59:29 INFO : empty space.rclone_chunk.001_7nh1oc: Moved (server-side) to: empty space 2025/04/24 04:59:36 INFO : enormous.rclone_chunk.001_7nh7m2: Moved (server-side) to: enormous.rclone_chunk.001 2025/04/24 04:59:38 INFO : enormous.rclone_chunk.002_7nh7m2: Moved (server-side) to: enormous.rclone_chunk.002 2025/04/24 04:59:41 DEBUG : enormous: Excluded (Size Filter) 2025/04/24 04:59:41 DEBUG : enormous: Excluded 2025/04/24 04:59:41 DEBUG : potato2: Excluded (Size Filter) 2025/04/24 04:59:41 DEBUG : potato2: Excluded 2025/04/24 04:59:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 04:59:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 04:59:42 DEBUG : empty space: Unchanged skipping 2025/04/24 04:59:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 04:59:42 DEBUG : Waiting for deletions to finish 2025/04/24 04:59:55 INFO : potato2: Deleted 2025/04/24 04:59:59 INFO : enormous: Deleted 2025/04/24 04:59:59 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:00:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:00:00 DEBUG : Local file system at /tmp/rclone524710327: Waiting for checks to finish 2025/04/24 05:00:00 DEBUG : empty space: Unchanged skipping 2025/04/24 05:00:00 DEBUG : Local file system at /tmp/rclone524710327: Waiting for transfers to finish 2025/04/24 05:00:00 DEBUG : Waiting for deletions to finish 2025/04/24 05:00:00 INFO : enormous: Deleted 2025/04/24 05:00:00 INFO : potato2: Deleted 2025/04/24 05:00:00 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (55.76s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:00:18 INFO : one.rclone_chunk.001_7nibo7: Moved (server-side) to: one 2025/04/24 05:00:23 INFO : two.rclone_chunk.001_7nijvm: Moved (server-side) to: two 2025/04/24 05:00:28 INFO : three.rclone_chunk.001_7nipth: Moved (server-side) to: three 2025/04/24 05:00:33 INFO : four.rclone_chunk.001_7nits6: Moved (server-side) to: four 2025/04/24 05:00:34 DEBUG : five: Need to transfer - File not found at Destination 2025/04/24 05:00:34 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/04/24 05:00:34 DEBUG : one: Destination is newer than source, skipping 2025/04/24 05:00:34 DEBUG : three: Sizes identical 2025/04/24 05:00:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:00:34 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/04/24 05:00:34 DEBUG : four: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:00:34 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/04/24 05:00:34 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone524710327) 2025/04/24 05:00:34 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa') 2025/04/24 05:00:34 DEBUG : two: md5 differ 2025/04/24 05:00:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:00:34 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:00:34 DEBUG : five: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:00:38 INFO : five.rclone_chunk.001_7nizw2: Moved (server-side) to: five 2025/04/24 05:00:38 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2025/04/24 05:00:38 INFO : five: Copied (new) 2025/04/24 05:00:41 INFO : four: Deleted 2025/04/24 05:00:42 INFO : two: Deleted 2025/04/24 05:00:43 INFO : four.rclone_chunk.001_7niz1r: Moved (server-side) to: four 2025/04/24 05:00:43 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2025/04/24 05:00:43 INFO : four: Copied (replaced existing) 2025/04/24 05:00:44 INFO : two.rclone_chunk.001_7niz13: Moved (server-side) to: two 2025/04/24 05:00:44 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/04/24 05:00:44 INFO : two: Copied (replaced existing) 2025/04/24 05:00:44 DEBUG : Waiting for deletions to finish 2025/04/24 05:00:45 DEBUG : one: Destination is newer than source, skipping 2025/04/24 05:00:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:00:45 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK 2025/04/24 05:00:45 DEBUG : five: Size and md5 of src and dst objects identical 2025/04/24 05:00:45 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2025/04/24 05:00:45 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK 2025/04/24 05:00:45 DEBUG : four: Size and md5 of src and dst objects identical 2025/04/24 05:00:45 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2025/04/24 05:00:45 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone524710327) 2025/04/24 05:00:45 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa') 2025/04/24 05:00:45 DEBUG : three: md5 differ 2025/04/24 05:00:45 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2025/04/24 05:00:45 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:00:45 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/04/24 05:00:45 DEBUG : two: Size and md5 of src and dst objects identical 2025/04/24 05:00:45 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2025/04/24 05:00:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:00:53 INFO : three: Deleted 2025/04/24 05:00:54 INFO : three.rclone_chunk.001_7njalk: Moved (server-side) to: three 2025/04/24 05:00:54 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 OK 2025/04/24 05:00:54 INFO : three: Copied (replaced existing) 2025/04/24 05:00:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (67.98s) === 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-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:1538: Can track renames: true 2025/04/24 05:01:17 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Making map for --track-renames 2025/04/24 05:01:17 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Finished making map for --track-renames 2025/04/24 05:01:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:01:17 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/24 05:01:17 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/04/24 05:01:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for renames to finish 2025/04/24 05:01:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:01:18 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:01:18 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:01:23 INFO : yam.rclone_chunk.001_7nk7f9: Moved (server-side) to: yam 2025/04/24 05:01:23 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2025/04/24 05:01:23 INFO : yam: Copied (new) 2025/04/24 05:01:27 INFO : potato.rclone_chunk.001_7nk75h: Moved (server-side) to: potato 2025/04/24 05:01:27 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2025/04/24 05:01:27 INFO : potato: Copied (new) 2025/04/24 05:01:27 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:01:28 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:01:28 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Making map for --track-renames 2025/04/24 05:01:28 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Finished making map for --track-renames 2025/04/24 05:01:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:01:28 DEBUG : potato: Unchanged skipping 2025/04/24 05:01:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for renames to finish 2025/04/24 05:01:28 DEBUG : yam: move non-chunked object... 2025/04/24 05:01:32 INFO : yam: Moved (server-side) to: yaml 2025/04/24 05:01:32 INFO : yam: Moved (server-side) to: yaml 2025/04/24 05:01:32 INFO : yaml: Renamed from "yam" 2025/04/24 05:01:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:01:32 DEBUG : Waiting for deletions to finish 2025/04/24 05:01:32 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (26.65s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:1610: Can track renames: true 2025/04/24 05:01:44 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Making map for --track-renames 2025/04/24 05:01:44 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Finished making map for --track-renames 2025/04/24 05:01:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:01:44 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/04/24 05:01:44 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/24 05:01:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for renames to finish 2025/04/24 05:01:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:01:44 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:01:44 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:01:49 INFO : potato.rclone_chunk.001_7nkxnm: Moved (server-side) to: potato 2025/04/24 05:01:49 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2025/04/24 05:01:49 INFO : potato: Copied (new) 2025/04/24 05:01:51 INFO : yam.rclone_chunk.001_7nkxwv: Moved (server-side) to: yam 2025/04/24 05:01:51 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2025/04/24 05:01:51 INFO : yam: Copied (new) 2025/04/24 05:01:51 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:01:52 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:01:52 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Making map for --track-renames 2025/04/24 05:01:52 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Finished making map for --track-renames 2025/04/24 05:01:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:01:52 DEBUG : potato: Unchanged skipping 2025/04/24 05:01:52 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for renames to finish 2025/04/24 05:01:53 DEBUG : yam: move non-chunked object... 2025/04/24 05:01:55 INFO : yam: Moved (server-side) to: yaml 2025/04/24 05:01:55 INFO : yam: Moved (server-side) to: yaml 2025/04/24 05:01:55 INFO : yaml: Renamed from "yam" 2025/04/24 05:01:55 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:01:55 DEBUG : Waiting for deletions to finish 2025/04/24 05:01:55 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (22.95s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:1649: Can track renames: true 2025/04/24 05:02:07 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Making map for --track-renames 2025/04/24 05:02:07 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Finished making map for --track-renames 2025/04/24 05:02:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:02:07 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/24 05:02:07 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/04/24 05:02:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for renames to finish 2025/04/24 05:02:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:02:07 DEBUG : sub/yam: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:02:07 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:02:11 INFO : sub/yam.rclone_chunk.001_7nlksd: Moved (server-side) to: sub/yam 2025/04/24 05:02:11 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2025/04/24 05:02:11 INFO : sub/yam: Copied (new) 2025/04/24 05:02:12 INFO : potato.rclone_chunk.001_7nlks2: Moved (server-side) to: potato 2025/04/24 05:02:12 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2025/04/24 05:02:12 INFO : potato: Copied (new) 2025/04/24 05:02:12 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:02:14 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:02:14 DEBUG : potato: Unchanged skipping 2025/04/24 05:02:14 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Making map for --track-renames 2025/04/24 05:02:14 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Finished making map for --track-renames 2025/04/24 05:02:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:02:14 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for renames to finish 2025/04/24 05:02:14 DEBUG : sub/yam: move non-chunked object... 2025/04/24 05:02:16 INFO : sub/yam: Moved (server-side) to: yam 2025/04/24 05:02:16 INFO : sub/yam: Moved (server-side) to: yam 2025/04/24 05:02:16 INFO : yam: Renamed from "sub/yam" 2025/04/24 05:02:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:02:16 DEBUG : Waiting for deletions to finish 2025/04/24 05:02:16 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (26.39s) === RUN TestServerSideMoveLocal run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:02:33 DEBUG : Creating backend with remote "/tmp/rclone524710327/dir1" 2025/04/24 05:02:33 DEBUG : Config file has changed externally - reloading 2025/04/24 05:02:33 DEBUG : Creating backend with remote "/tmp/rclone524710327/dir2" 2025/04/24 05:02:33 DEBUG : Local file system at /tmp/rclone524710327/dir2: Using server-side directory move 2025/04/24 05:02:33 INFO : Local file system at /tmp/rclone524710327/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/24 05:02:33 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/04/24 05:02:33 DEBUG : Local file system at /tmp/rclone524710327/dir2: Waiting for checks to finish 2025/04/24 05:02:33 INFO : file1.txt: Moved (server-side) 2025/04/24 05:02:33 DEBUG : Local file system at /tmp/rclone524710327/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.74s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:02:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 05:02:34 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/04/24 05:02:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:02:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:02:34 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:02:34 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:02:40 INFO : sub dir/hello world.rclone_chunk.001_7nmbui: Moved (server-side) to: sub dir/hello world 2025/04/24 05:02:40 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 05:02:40 INFO : sub dir/hello world: Copied (new) 2025/04/24 05:02:40 INFO : sub dir/hello world: Deleted 2025/04/24 05:02:40 INFO : nested/sub dir/file.rclone_chunk.001_7nmbr6: Moved (server-side) to: nested/sub dir/file 2025/04/24 05:02:40 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2025/04/24 05:02:40 INFO : nested/sub dir/file: Copied (new) 2025/04/24 05:02:40 INFO : nested/sub dir/file: Deleted 2025/04/24 05:02:40 INFO : sub dir: Removing directory 2025/04/24 05:02:40 INFO : nested/sub dir: Removing directory 2025/04/24 05:02:40 INFO : nested: Removing directory 2025/04/24 05:02:40 DEBUG : Local file system at /tmp/rclone524710327: deleted 3 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (37.39s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:03:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/24 05:03:11 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/04/24 05:03:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:03:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:03:11 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:03:11 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:03:17 INFO : sub dir/hello world.rclone_chunk.001_7nncil: Moved (server-side) to: sub dir/hello world 2025/04/24 05:03:17 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/04/24 05:03:17 INFO : sub dir/hello world: Copied (new) 2025/04/24 05:03:17 INFO : sub dir/hello world: Deleted 2025/04/24 05:03:19 INFO : nested/sub dir/file.rclone_chunk.001_7nncm2: Moved (server-side) to: nested/sub dir/file 2025/04/24 05:03:19 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2025/04/24 05:03:19 INFO : nested/sub dir/file: Copied (new) 2025/04/24 05:03:19 INFO : nested/sub dir/file: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (36.16s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:03:47 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/24 05:03:47 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/04/24 05:03:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:03:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:03:48 DEBUG : existing-b: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:03:48 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:03:52 INFO : existing-b.rclone_chunk.001_7nodkv: Moved (server-side) to: existing-b 2025/04/24 05:03:52 DEBUG : existing-b: md5 = 006f87892f47ef9aa60fa5ed01a440fb OK 2025/04/24 05:03:52 INFO : existing-b: Copied (new) 2025/04/24 05:03:52 INFO : existing-b: Deleted 2025/04/24 05:03:57 INFO : existing.rclone_chunk.001_7nodfs: Moved (server-side) to: existing 2025/04/24 05:03:57 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/04/24 05:03:57 INFO : existing: Copied (new) 2025/04/24 05:03:57 INFO : existing: Deleted 2025/04/24 05:03:57 DEBUG : existing: Destination exists, skipping 2025/04/24 05:03:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:03:57 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/04/24 05:03:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:03:57 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (20.34s) === RUN TestServerSideMove run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:04:07 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-yojolof0bala" 2025/04/24 05:04:07 DEBUG : Config file has changed externally - reloading 2025/04/24 05:04:07 DEBUG : Creating backend with remote "TestYandex:rclone-test-yojolof0bala" 2025/04/24 05:04:15 INFO : potato2.rclone_chunk.001_7nox2u: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/24 05:04:17 INFO : potato2.rclone_chunk.002_7nox2u: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/24 05:04:26 INFO : empty space.rclone_chunk.001_7np9pt: Moved (server-side) to: empty space 2025/04/24 05:04:35 INFO : potato3.rclone_chunk.001_7npfri: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/24 05:04:37 INFO : potato3.rclone_chunk.002_7npfri: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-yojolof0bala' 2025/04/24 05:04:47 INFO : empty space.rclone_chunk.001_7npwqr: Moved (server-side) to: empty space 2025/04/24 05:04:54 INFO : potato3.rclone_chunk.001_7nq07x: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/24 05:04:56 INFO : potato3.rclone_chunk.002_7nq07x: Moved (server-side) to: potato3.rclone_chunk.002 2025/04/24 05:04:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-yojolof0bala': Using server-side directory move 2025/04/24 05:05:00 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-yojolof0bala': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/24 05:05:00 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 05:05:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:05:00 DEBUG : empty space: Unchanged skipping 2025/04/24 05:05:00 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/24 05:05:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-yojolof0bala': Waiting for checks to finish 2025/04/24 05:05:00 DEBUG : potato2: move 2 data chunks... 2025/04/24 05:05:02 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/04/24 05:05:04 INFO : empty space: Deleted 2025/04/24 05:05:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-yojolof0bala': Waiting for transfers to finish 2025/04/24 05:05:04 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/04/24 05:05:06 INFO : potato2: Moved (server-side) 2025/04/24 05:05:08 INFO : potato2: Moved (server-side) 2025/04/24 05:05:12 INFO : potato3: Deleted 2025/04/24 05:05:13 DEBUG : potato3: move 2 data chunks... 2025/04/24 05:05:15 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/04/24 05:05:17 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/04/24 05:05:19 INFO : potato3: Moved (server-side) 2025/04/24 05:05:22 INFO : potato3: Moved (server-side) 2025/04/24 05:05:24 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-juziyuq7buxa" 2025/04/24 05:05:24 DEBUG : Creating backend with remote "TestYandex:rclone-test-juziyuq7buxa" 2025/04/24 05:05:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-juziyuq7buxa': Using server-side directory move 2025/04/24 05:05:30 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-juziyuq7buxa': Server side directory move succeeded 2025/04/24 05:05:33 ERROR : : error listing: directory not found 2025/04/24 05:05:33 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-juziyuq7buxa': Purge remote 2025/04/24 05:05:44 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-yojolof0bala': Purge remote 2025/04/24 05:05:44 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) --- PASS: TestServerSideMove (97.60s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:05:45 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-fevobut2kuxe" 2025/04/24 05:05:45 DEBUG : Creating backend with remote "TestYandex:rclone-test-fevobut2kuxe" 2025/04/24 05:05:53 INFO : potato2.rclone_chunk.001_7nrnlo: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/24 05:05:54 INFO : potato2.rclone_chunk.002_7nrnlo: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/24 05:06:06 INFO : empty space.rclone_chunk.001_7ns1k9: Moved (server-side) to: empty space 2025/04/24 05:06:13 INFO : potato3.rclone_chunk.001_7ns8u0: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/24 05:06:15 INFO : potato3.rclone_chunk.002_7ns8u0: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-fevobut2kuxe' 2025/04/24 05:06:24 INFO : empty space.rclone_chunk.001_7nsk11: Moved (server-side) to: empty space 2025/04/24 05:06:33 INFO : potato3.rclone_chunk.001_7nspr3: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/24 05:06:34 INFO : potato3.rclone_chunk.002_7nspr3: Moved (server-side) to: potato3.rclone_chunk.002 2025/04/24 05:06:38 DEBUG : empty space: Excluded (Size Filter) 2025/04/24 05:06:38 DEBUG : empty space: Excluded 2025/04/24 05:06:38 DEBUG : empty space: Excluded (Size Filter) 2025/04/24 05:06:38 DEBUG : empty space: Excluded 2025/04/24 05:06:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 05:06:38 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/24 05:06:38 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fevobut2kuxe': Waiting for checks to finish 2025/04/24 05:06:38 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fevobut2kuxe': Waiting for transfers to finish 2025/04/24 05:06:39 DEBUG : potato2: move 2 data chunks... 2025/04/24 05:06:41 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/04/24 05:06:43 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/04/24 05:06:45 INFO : potato2: Moved (server-side) 2025/04/24 05:06:47 INFO : potato2: Moved (server-side) 2025/04/24 05:06:52 INFO : potato3: Deleted 2025/04/24 05:06:53 DEBUG : potato3: move 2 data chunks... 2025/04/24 05:06:55 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/04/24 05:06:56 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/04/24 05:06:58 INFO : potato3: Moved (server-side) 2025/04/24 05:07:01 INFO : potato3: Moved (server-side) 2025/04/24 05:07:03 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-vedayow0joge" 2025/04/24 05:07:03 DEBUG : Creating backend with remote "TestYandex:rclone-test-vedayow0joge" 2025/04/24 05:07:04 DEBUG : empty space: Excluded (Size Filter) 2025/04/24 05:07:04 DEBUG : empty space: Excluded 2025/04/24 05:07:04 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 05:07:04 DEBUG : potato3: Need to transfer - File not found at Destination 2025/04/24 05:07:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vedayow0joge': Waiting for checks to finish 2025/04/24 05:07:04 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vedayow0joge': Waiting for transfers to finish 2025/04/24 05:07:04 DEBUG : potato3: move 2 data chunks... 2025/04/24 05:07:04 DEBUG : potato2: move 2 data chunks... 2025/04/24 05:07:06 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/04/24 05:07:08 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/04/24 05:07:09 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/04/24 05:07:10 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/04/24 05:07:11 INFO : potato2: Moved (server-side) 2025/04/24 05:07:12 INFO : potato3: Moved (server-side) 2025/04/24 05:07:13 INFO : potato2: Moved (server-side) 2025/04/24 05:07:13 INFO : potato3: Moved (server-side) 2025/04/24 05:07:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-vedayow0joge': Purge remote 2025/04/24 05:07:25 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-fevobut2kuxe': Purge remote --- PASS: TestServerSideMoveWithFilter (111.95s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:07:37 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-qozumol1teyo" 2025/04/24 05:07:37 DEBUG : Creating backend with remote "TestYandex:rclone-test-qozumol1teyo" 2025/04/24 05:07:44 INFO : potato2.rclone_chunk.001_7nur8w: Moved (server-side) to: potato2.rclone_chunk.001 2025/04/24 05:07:46 INFO : potato2.rclone_chunk.002_7nur8w: Moved (server-side) to: potato2.rclone_chunk.002 2025/04/24 05:07:54 INFO : empty space.rclone_chunk.001_7nv2n3: Moved (server-side) to: empty space 2025/04/24 05:08:02 INFO : potato3.rclone_chunk.001_7nv7yh: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/24 05:08:04 INFO : potato3.rclone_chunk.002_7nv7yh: Moved (server-side) to: potato3.rclone_chunk.002 2025/04/24 05:08:06 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa' -> Chunked 'TestChunkerChunk50bYandex:rclone-test-qozumol1teyo' 2025/04/24 05:08:15 INFO : empty space.rclone_chunk.001_7nvm29: Moved (server-side) to: empty space 2025/04/24 05:08:22 INFO : potato3.rclone_chunk.001_7nvsee: Moved (server-side) to: potato3.rclone_chunk.001 2025/04/24 05:08:23 INFO : potato3.rclone_chunk.002_7nvsee: Moved (server-side) to: potato3.rclone_chunk.002 2025/04/24 05:08:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-qozumol1teyo': Using server-side directory move 2025/04/24 05:08:28 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-qozumol1teyo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/24 05:08:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/24 05:08:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:08:29 DEBUG : empty space: Unchanged skipping 2025/04/24 05:08:29 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/24 05:08:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-qozumol1teyo': Waiting for checks to finish 2025/04/24 05:08:29 DEBUG : potato2: move 2 data chunks... 2025/04/24 05:08:31 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/04/24 05:08:33 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/04/24 05:08:35 INFO : potato2: Moved (server-side) 2025/04/24 05:08:37 INFO : empty space: Deleted 2025/04/24 05:08:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-qozumol1teyo': Waiting for transfers to finish 2025/04/24 05:08:37 INFO : potato2: Moved (server-side) 2025/04/24 05:08:42 INFO : potato3: Deleted 2025/04/24 05:08:43 DEBUG : potato3: move 2 data chunks... 2025/04/24 05:08:44 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/04/24 05:08:46 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/04/24 05:08:48 INFO : potato3: Moved (server-side) 2025/04/24 05:08:50 INFO : potato3: Moved (server-side) 2025/04/24 05:08:50 INFO : tomatoDir: Removing directory 2025/04/24 05:08:56 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': deleted 1 directories 2025/04/24 05:08:58 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-juweqin0nuna" 2025/04/24 05:08:58 DEBUG : Creating backend with remote "TestYandex:rclone-test-juweqin0nuna" 2025/04/24 05:08:58 INFO : tomatoDir: Making directory 2025/04/24 05:08:59 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-juweqin0nuna': Using server-side directory move 2025/04/24 05:09:04 INFO : Chunked 'TestChunkerChunk50bYandex:rclone-test-juweqin0nuna': Server side directory move succeeded 2025/04/24 05:09:07 ERROR : : error listing: directory not found 2025/04/24 05:09:07 ERROR : : error listing: directory not found 2025/04/24 05:09:07 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-juweqin0nuna': Purge remote 2025/04/24 05:09:21 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-qozumol1teyo': Purge remote 2025/04/24 05:09:22 NOTICE: purge failed: [404 - DiskNotFoundError] Resource not found. (Не удалось найти запрошенный ресурс.) --- PASS: TestServerSideMoveDeleteEmptySourceDirs (105.40s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:1910: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.52s) === RUN TestSyncOverlap run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:09:23 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test" 2025/04/24 05:09:23 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/rclone-sync-test" run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncOverlap (6.75s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:09:30 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test" 2025/04/24 05:09:30 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/rclone-sync-test" 2025/04/24 05:09:30 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test-include/layer2" 2025/04/24 05:09:30 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/rclone-sync-test-include/layer2" 2025/04/24 05:09:32 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test-ignore-file" 2025/04/24 05:09:32 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/rclone-sync-test-ignore-file" 2025/04/24 05:09:40 INFO : rclone-sync-test-ignore-file/.ignore.rclone_chunk.001_7nxzaw: Moved (server-side) to: rclone-sync-test-ignore-file/.ignore 2025/04/24 05:09:40 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:09:41 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:09:42 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/24 05:09:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test': Waiting for checks to finish 2025/04/24 05:09:42 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test': Waiting for transfers to finish 2025/04/24 05:09:42 DEBUG : Waiting for deletions to finish 2025/04/24 05:09:42 INFO : There was nothing to transfer 2025/04/24 05:09:43 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:09:44 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:09:45 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/24 05:09:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:09:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:09:45 DEBUG : Waiting for deletions to finish 2025/04/24 05:09:45 INFO : rclone-sync-test-include: Removing directory 2025/04/24 05:09:45 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/04/24 05:09:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': failed to delete 1 directories 2025/04/24 05:09:45 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:09:46 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:09:47 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:09:48 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:09:49 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:09:49 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:09:50 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:09:51 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:09:52 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:09:53 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:09:54 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:09:54 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:09:55 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:09:56 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:09:57 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:09:58 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:09:58 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:09:59 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:00 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/24 05:10:01 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:02 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:03 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/24 05:10:03 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/04/24 05:10:03 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/04/24 05:10:03 DEBUG : Waiting for deletions to finish 2025/04/24 05:10:03 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:05 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:05 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:06 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:07 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:09 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:10 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/24 05:10:11 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:13 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/24 05:10:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:10:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:10:13 DEBUG : Waiting for deletions to finish 2025/04/24 05:10:13 INFO : rclone-sync-test-include: Removing directory 2025/04/24 05:10:13 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/04/24 05:10:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': failed to delete 1 directories 2025/04/24 05:10:13 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:14 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:15 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:16 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:17 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:18 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:19 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:20 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:20 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:22 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:22 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:23 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:24 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/24 05:10:25 DEBUG : : Excluded 2025/04/24 05:10:25 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:26 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:27 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/24 05:10:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/04/24 05:10:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/04/24 05:10:27 DEBUG : Waiting for deletions to finish 2025/04/24 05:10:27 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:28 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:28 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:29 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:30 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:31 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:32 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:33 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:34 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:35 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:36 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:36 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:37 DEBUG : rclone-sync-test-include/layer2: Excluded run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:10:38 DEBUG : rclone-sync-test: Excluded 2025/04/24 05:10:38 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/24 05:10:39 DEBUG : rclone-sync-test-include/layer2: Excluded --- PASS: TestSyncOverlapWithFilter (118.01s) === RUN TestSyncCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:11:28 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:11:28 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:11:28 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/CompareDest" 2025/04/24 05:11:28 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/CompareDest" 2025/04/24 05:11:29 DEBUG : one: Need to transfer - File not found at Destination 2025/04/24 05:11:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:11:29 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:11:29 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:11:34 INFO : one.rclone_chunk.001_7o16rp: Moved (server-side) to: one 2025/04/24 05:11:34 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/04/24 05:11:34 INFO : one: Copied (new) 2025/04/24 05:11:34 DEBUG : Waiting for deletions to finish 2025/04/24 05:11:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:11:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:11:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:11:37 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:11:39 DEBUG : pacer: low level retry 1/10 (error [500 - InternalServerError] Internal Server Error (Ошибка сервера.)) 2025/04/24 05:11:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/04/24 05:11:40 DEBUG : pacer: Reducing sleep to 15ms 2025/04/24 05:11:40 DEBUG : pacer: Reducing sleep to 11.25ms 2025/04/24 05:11:40 DEBUG : pacer: Reducing sleep to 10ms 2025/04/24 05:11:46 INFO : one: Deleted 2025/04/24 05:11:48 INFO : one.rclone_chunk.001_7o1e42: Moved (server-side) to: one 2025/04/24 05:11:48 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/04/24 05:11:48 INFO : one: Copied (replaced existing) 2025/04/24 05:11:48 DEBUG : Waiting for deletions to finish 2025/04/24 05:11:57 INFO : dst/one: Deleted 2025/04/24 05:11:59 INFO : dst/one.rclone_chunk.001_7o1qs9: Moved (server-side) to: dst/one 2025/04/24 05:12:04 INFO : CompareDest/one.rclone_chunk.001_7o20jp: Moved (server-side) to: CompareDest/one 2025/04/24 05:12:05 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:12:05 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:12:05 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:12:05 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/24 05:12:05 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:12:05 DEBUG : Waiting for deletions to finish 2025/04/24 05:12:05 INFO : There was nothing to transfer 2025/04/24 05:12:12 INFO : CompareDest/two.rclone_chunk.001_7o27ac: Moved (server-side) to: CompareDest/two 2025/04/24 05:12:13 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:12:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:12:13 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/24 05:12:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:12:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:12:13 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/24 05:12:13 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:12:13 DEBUG : Waiting for deletions to finish 2025/04/24 05:12:13 INFO : There was nothing to transfer 2025/04/24 05:12:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:12:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:12:16 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/24 05:12:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:12:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:12:16 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/24 05:12:16 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:12:16 DEBUG : Waiting for deletions to finish 2025/04/24 05:12:16 INFO : There was nothing to transfer 2025/04/24 05:12:18 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:12:19 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/04/24 05:12:19 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/04/24 05:12:19 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/24 05:12:19 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:12:20 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:12:20 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/24 05:12:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:12:20 DEBUG : Waiting for deletions to finish 2025/04/24 05:12:20 INFO : There was nothing to transfer 2025/04/24 05:12:22 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:12:23 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:12:23 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/24 05:12:23 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/04/24 05:12:23 DEBUG : two: Need to transfer - File not found at Destination 2025/04/24 05:12:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:12:23 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:12:24 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:12:28 INFO : two.rclone_chunk.001_7o2pqv: Moved (server-side) to: two 2025/04/24 05:12:28 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK 2025/04/24 05:12:28 INFO : two: Copied (new) 2025/04/24 05:12:28 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (93.26s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:13:06 INFO : pre-dest1/1.rclone_chunk.001_7o3qly: Moved (server-side) to: pre-dest1/1 2025/04/24 05:13:12 INFO : pre-dest2/2.rclone_chunk.001_7o3wwc: Moved (server-side) to: pre-dest2/2 2025/04/24 05:13:12 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dest" 2025/04/24 05:13:12 DEBUG : Config file has changed externally - reloading 2025/04/24 05:13:12 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dest" 2025/04/24 05:13:13 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/pre-dest1" 2025/04/24 05:13:13 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/pre-dest1" 2025/04/24 05:13:13 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/pre-dest2" 2025/04/24 05:13:13 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/pre-dest2" 2025/04/24 05:13:15 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:13:15 DEBUG : 1: Destination found in --compare-dest, skipping 2025/04/24 05:13:16 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:13:16 DEBUG : 2: Destination found in --compare-dest, skipping 2025/04/24 05:13:17 DEBUG : 3: Need to transfer - File not found at Destination 2025/04/24 05:13:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dest': Waiting for checks to finish 2025/04/24 05:13:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dest': Waiting for transfers to finish 2025/04/24 05:13:17 DEBUG : 3: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:13:21 INFO : 3.rclone_chunk.001_7o465n: Moved (server-side) to: 3 2025/04/24 05:13:21 DEBUG : 3: md5 = eccbc87e4b5ce2fe28308fd9f2a7baf3 OK 2025/04/24 05:13:21 INFO : 3: Copied (new) 2025/04/24 05:13:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (52.09s) === RUN TestSyncCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:13:53 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:13:53 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:13:53 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/CopyDest" 2025/04/24 05:13:53 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/CopyDest" 2025/04/24 05:13:54 DEBUG : one: Need to transfer - File not found at Destination 2025/04/24 05:13:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:13:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:13:55 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:14:00 INFO : one.rclone_chunk.001_7o58l6: Moved (server-side) to: one 2025/04/24 05:14:00 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK 2025/04/24 05:14:00 INFO : one: Copied (new) 2025/04/24 05:14:00 DEBUG : Waiting for deletions to finish 2025/04/24 05:14:01 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:14:01 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:14:02 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:14:02 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:14:09 INFO : one: Deleted 2025/04/24 05:14:13 INFO : one.rclone_chunk.001_7o5fs9: Moved (server-side) to: one 2025/04/24 05:14:13 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/04/24 05:14:13 INFO : one: Copied (replaced existing) 2025/04/24 05:14:13 DEBUG : Waiting for deletions to finish 2025/04/24 05:14:23 INFO : dst/one: Deleted 2025/04/24 05:14:25 INFO : dst/one.rclone_chunk.001_7o5rjf: Moved (server-side) to: dst/one 2025/04/24 05:14:30 INFO : CopyDest/one.rclone_chunk.001_7o62ri: Moved (server-side) to: CopyDest/one 2025/04/24 05:14:31 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/BackupDir" 2025/04/24 05:14:31 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/BackupDir" 2025/04/24 05:14:32 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:14:32 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:14:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:14:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/24 05:14:33 DEBUG : one: move non-chunked object... 2025/04/24 05:14:35 INFO : one: Moved (server-side) 2025/04/24 05:14:35 INFO : one: Moved (server-side) 2025/04/24 05:14:35 DEBUG : one: copy non-chunked object... 2025/04/24 05:14:37 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK 2025/04/24 05:14:37 INFO : one: Copied (server-side copy) 2025/04/24 05:14:37 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/04/24 05:14:37 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:14:37 DEBUG : Waiting for deletions to finish 2025/04/24 05:14:43 INFO : CopyDest/two.rclone_chunk.001_7o6geb: Moved (server-side) to: CopyDest/two 2025/04/24 05:14:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:14:45 DEBUG : one: Unchanged skipping 2025/04/24 05:14:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:14:46 DEBUG : two: copy non-chunked object... 2025/04/24 05:14:49 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK 2025/04/24 05:14:49 INFO : two: Copied (server-side copy) 2025/04/24 05:14:49 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/04/24 05:14:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:14:49 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:14:49 DEBUG : Waiting for deletions to finish 2025/04/24 05:14:50 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:14:50 DEBUG : one: Unchanged skipping 2025/04/24 05:14:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:14:50 DEBUG : two: Unchanged skipping 2025/04/24 05:14:50 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:14:50 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:14:50 DEBUG : Waiting for deletions to finish 2025/04/24 05:14:50 INFO : There was nothing to transfer 2025/04/24 05:14:56 INFO : CopyDest/three.rclone_chunk.001_7o6s7c: Moved (server-side) to: CopyDest/three 2025/04/24 05:14:57 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:14:57 DEBUG : one: Unchanged skipping 2025/04/24 05:14:58 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/04/24 05:14:58 DEBUG : three: Destination not found in --copy-dest 2025/04/24 05:14:58 DEBUG : three: Need to transfer - File not found at Destination 2025/04/24 05:14:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:14:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:14:58 DEBUG : two: Unchanged skipping 2025/04/24 05:14:58 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:14:58 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:15:03 INFO : three.rclone_chunk.001_7o6zzk: Moved (server-side) to: three 2025/04/24 05:15:03 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK 2025/04/24 05:15:03 INFO : three: Copied (new) 2025/04/24 05:15:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (121.32s) === RUN TestSyncBackupDir run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:16:03 INFO : dst/one.rclone_chunk.001_7o8kzz: Moved (server-side) to: dst/one 2025/04/24 05:16:09 INFO : dst/two.rclone_chunk.001_7o8t6e: Moved (server-side) to: dst/two 2025/04/24 05:16:14 INFO : dst/three.txt.rclone_chunk.001_7o8yap: Moved (server-side) to: dst/three.txt 2025/04/24 05:16:15 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:16:15 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:16:15 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/backup" 2025/04/24 05:16:15 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/backup" 2025/04/24 05:16:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/24 05:16:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:16:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:16:17 DEBUG : two: Unchanged skipping 2025/04/24 05:16:18 DEBUG : one: move non-chunked object... 2025/04/24 05:16:20 INFO : one: Moved (server-side) 2025/04/24 05:16:20 INFO : one: Moved (server-side) 2025/04/24 05:16:20 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:16:20 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:16:25 INFO : one.rclone_chunk.001_7o99v0: Moved (server-side) to: one 2025/04/24 05:16:25 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/24 05:16:25 INFO : one: Copied (new) 2025/04/24 05:16:25 DEBUG : Waiting for deletions to finish 2025/04/24 05:16:25 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:16:26 INFO : three.txt: Moved (server-side) 2025/04/24 05:16:26 INFO : three.txt: Moved (server-side) 2025/04/24 05:16:26 INFO : three.txt: Moved into backup dir 2025/04/24 05:16:32 INFO : dst/three.txt.rclone_chunk.001_7o9gf9: Moved (server-side) to: dst/three.txt 2025/04/24 05:16:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/24 05:16:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:16:34 DEBUG : two: Unchanged skipping 2025/04/24 05:16:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:16:39 INFO : one: Deleted 2025/04/24 05:16:39 DEBUG : one: move non-chunked object... 2025/04/24 05:16:41 INFO : one: Moved (server-side) 2025/04/24 05:16:41 INFO : one: Moved (server-side) 2025/04/24 05:16:41 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:16:41 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:16:47 INFO : one.rclone_chunk.001_7o9ur8: Moved (server-side) to: one 2025/04/24 05:16:47 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/24 05:16:47 INFO : one: Copied (new) 2025/04/24 05:16:47 DEBUG : Waiting for deletions to finish 2025/04/24 05:16:52 INFO : three.txt: Deleted 2025/04/24 05:16:52 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:16:54 INFO : three.txt: Moved (server-side) 2025/04/24 05:16:54 INFO : three.txt: Moved (server-side) 2025/04/24 05:16:54 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (88.53s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:17:28 INFO : dst/one.rclone_chunk.001_7ob08c: Moved (server-side) to: dst/one 2025/04/24 05:17:35 INFO : dst/two.rclone_chunk.001_7ob6d4: Moved (server-side) to: dst/two 2025/04/24 05:17:41 INFO : dst/three.txt.rclone_chunk.001_7obdvh: Moved (server-side) to: dst/three.txt 2025/04/24 05:17:42 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:17:42 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:17:43 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/backup" 2025/04/24 05:17:43 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/backup" 2025/04/24 05:17:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/24 05:17:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:17:43 DEBUG : two: Unchanged skipping 2025/04/24 05:17:43 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:17:44 DEBUG : one: move non-chunked object... 2025/04/24 05:17:46 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:17:46 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:17:46 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:17:46 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:17:51 INFO : one.rclone_chunk.001_7obnpb: Moved (server-side) to: one 2025/04/24 05:17:51 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/24 05:17:51 INFO : one: Copied (new) 2025/04/24 05:17:51 DEBUG : Waiting for deletions to finish 2025/04/24 05:17:51 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:17:53 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:17:53 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:17:53 INFO : three.txt: Moved into backup dir 2025/04/24 05:17:59 INFO : dst/three.txt.rclone_chunk.001_7obv4d: Moved (server-side) to: dst/three.txt 2025/04/24 05:18:00 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/24 05:18:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:18:00 DEBUG : two: Unchanged skipping 2025/04/24 05:18:00 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:18:06 INFO : one.bak: Deleted 2025/04/24 05:18:06 DEBUG : one: move non-chunked object... 2025/04/24 05:18:12 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:18:12 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:18:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:18:12 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:18:18 INFO : one.rclone_chunk.001_7ocdpo: Moved (server-side) to: one 2025/04/24 05:18:18 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/24 05:18:18 INFO : one: Copied (new) 2025/04/24 05:18:18 DEBUG : Waiting for deletions to finish 2025/04/24 05:18:22 INFO : three.txt.bak: Deleted 2025/04/24 05:18:22 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:18:24 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:18:24 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:18:24 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (93.03s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:19:01 INFO : dst/one.rclone_chunk.001_7odlbf: Moved (server-side) to: dst/one 2025/04/24 05:19:09 INFO : dst/two.rclone_chunk.001_7odqjq: Moved (server-side) to: dst/two 2025/04/24 05:19:15 INFO : dst/three.txt.rclone_chunk.001_7odyfm: Moved (server-side) to: dst/three.txt 2025/04/24 05:19:15 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:19:15 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:19:16 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/backup" 2025/04/24 05:19:16 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/backup" 2025/04/24 05:19:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/24 05:19:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:19:17 DEBUG : two: Unchanged skipping 2025/04/24 05:19:17 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:19:17 DEBUG : one: move non-chunked object... 2025/04/24 05:19:19 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/24 05:19:19 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/24 05:19:19 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:19:20 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:19:24 INFO : one.rclone_chunk.001_7oe97n: Moved (server-side) to: one 2025/04/24 05:19:24 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/24 05:19:24 INFO : one: Copied (new) 2025/04/24 05:19:24 DEBUG : Waiting for deletions to finish 2025/04/24 05:19:25 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:19:27 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/24 05:19:27 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/24 05:19:27 INFO : three.txt: Moved into backup dir 2025/04/24 05:19:36 INFO : dst/three.txt.rclone_chunk.001_7oeh3o: Moved (server-side) to: dst/three.txt 2025/04/24 05:19:39 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/24 05:19:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:19:39 DEBUG : two: Unchanged skipping 2025/04/24 05:19:39 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:19:44 INFO : one-2019-01-01: Deleted 2025/04/24 05:19:44 DEBUG : one: move non-chunked object... 2025/04/24 05:19:45 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/24 05:19:45 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/24 05:19:45 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:19:46 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:19:51 INFO : one.rclone_chunk.001_7oezj9: Moved (server-side) to: one 2025/04/24 05:19:51 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/24 05:19:51 INFO : one: Copied (new) 2025/04/24 05:19:51 DEBUG : Waiting for deletions to finish 2025/04/24 05:19:58 INFO : three-2019-01-01.txt: Deleted 2025/04/24 05:19:58 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:20:00 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/24 05:20:00 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/24 05:20:00 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: dst/one: Modification time difference too big |-116728h19m51s| > 1ns (want 2011-12-30 12:59:59 +0000 UTC vs got 2025-04-24 05:19:50 +0000 UTC) (precision 1ns) --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (96.38s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:20:39 INFO : dst/one.rclone_chunk.001_7oga0b: Moved (server-side) to: dst/one 2025/04/24 05:20:46 INFO : dst/two.rclone_chunk.001_7oggrl: Moved (server-side) to: dst/two 2025/04/24 05:20:53 INFO : dst/three.txt.rclone_chunk.001_7ogoil: Moved (server-side) to: dst/three.txt 2025/04/24 05:20:53 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:20:53 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:20:54 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/24 05:20:54 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:20:54 DEBUG : two: Unchanged skipping 2025/04/24 05:20:54 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:20:54 DEBUG : one: move non-chunked object... 2025/04/24 05:20:57 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:20:57 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:20:57 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:20:57 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:21:02 INFO : one.rclone_chunk.001_7ogyk8: Moved (server-side) to: one 2025/04/24 05:21:02 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/24 05:21:02 INFO : one: Copied (new) 2025/04/24 05:21:02 DEBUG : Waiting for deletions to finish 2025/04/24 05:21:02 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:21:04 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:21:04 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:21:04 INFO : three.txt: Moved into backup dir 2025/04/24 05:21:11 INFO : dst/three.txt.rclone_chunk.001_7oh67k: 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:2439 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: dst/three.txt: Modification time difference too big |-116848h21m10.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-24 05:21:10 +0000 UTC) (precision 1ns) 2025/04/24 05:21:12 DEBUG : one.bak: Excluded (Path Filter) 2025/04/24 05:21:12 DEBUG : one.bak: Excluded 2025/04/24 05:21:12 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/04/24 05:21:12 DEBUG : three.txt.bak: Excluded 2025/04/24 05:21:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/24 05:21:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:21:12 DEBUG : two: Unchanged skipping 2025/04/24 05:21:12 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for checks to finish 2025/04/24 05:21:17 INFO : one.bak: Deleted 2025/04/24 05:21:17 DEBUG : one: move non-chunked object... 2025/04/24 05:21:19 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:21:19 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:21:19 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst': Waiting for transfers to finish 2025/04/24 05:21:19 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:21:24 INFO : one.rclone_chunk.001_7ohk64: Moved (server-side) to: one 2025/04/24 05:21:24 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/24 05:21:24 INFO : one: Copied (new) 2025/04/24 05:21:24 DEBUG : Waiting for deletions to finish 2025/04/24 05:21:30 INFO : three.txt.bak: Deleted 2025/04/24 05:21:30 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:21:31 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:21:31 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:21:31 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:2439 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: dst/three.txt.bak: Modification time difference too big |-116848h21m31.876543211s| > 1ns (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2025-04-24 05:21:31 +0000 UTC) (precision 1ns) --- FAIL: TestSyncBackupDirSuffixOnly (83.25s) === RUN TestSyncSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:22:01 INFO : dst/one.rclone_chunk.001_7oil6u: Moved (server-side) to: dst/one 2025/04/24 05:22:06 INFO : dst/two.rclone_chunk.001_7oiqov: Moved (server-side) to: dst/two 2025/04/24 05:22:12 INFO : dst/three.txt.rclone_chunk.001_7oivhs: Moved (server-side) to: dst/three.txt 2025/04/24 05:22:13 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:22:13 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:22:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/24 05:22:14 DEBUG : one: move non-chunked object... 2025/04/24 05:22:17 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:22:17 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:22:17 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:22:22 INFO : one.rclone_chunk.001_7oj6vt: Moved (server-side) to: one 2025/04/24 05:22:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/24 05:22:22 INFO : one: Copied (new) 2025/04/24 05:22:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:22:22 DEBUG : two: Unchanged skipping 2025/04/24 05:22:23 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/04/24 05:22:23 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:22:25 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:22:25 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:22:26 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:22:31 INFO : three.txt.rclone_chunk.001_7ojflw: Moved (server-side) to: three.txt 2025/04/24 05:22:31 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/04/24 05:22:31 INFO : three.txt: Copied (new) 2025/04/24 05:22:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/24 05:22:39 INFO : one.bak: Deleted 2025/04/24 05:22:39 DEBUG : one: move non-chunked object... 2025/04/24 05:22:40 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:22:40 INFO : one: Moved (server-side) to: one.bak 2025/04/24 05:22:40 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:22:47 INFO : one.rclone_chunk.001_7ojt18: Moved (server-side) to: one 2025/04/24 05:22:47 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/24 05:22:47 INFO : one: Copied (new) 2025/04/24 05:22:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:22:47 DEBUG : two: Unchanged skipping 2025/04/24 05:22:48 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/04/24 05:22:52 INFO : three.txt.bak: Deleted 2025/04/24 05:22:52 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:22:54 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:22:54 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/24 05:22:54 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:22:59 INFO : three.txt.rclone_chunk.001_7ok7ny: Moved (server-side) to: three.txt 2025/04/24 05:22:59 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/04/24 05:22:59 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (91.89s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:23:33 INFO : dst/one.rclone_chunk.001_7ol5jx: Moved (server-side) to: dst/one 2025/04/24 05:23:40 INFO : dst/two.rclone_chunk.001_7olbyz: Moved (server-side) to: dst/two 2025/04/24 05:23:46 INFO : dst/three.txt.rclone_chunk.001_7olhw0: Moved (server-side) to: dst/three.txt 2025/04/24 05:23:46 DEBUG : Creating backend with remote "TestChunkerChunk50bYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:23:46 DEBUG : Creating backend with remote "TestYandex:rclone-test-judugod3nuxa/dst" 2025/04/24 05:23:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/24 05:23:48 DEBUG : one: move non-chunked object... 2025/04/24 05:23:50 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/24 05:23:50 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/24 05:23:50 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:23:54 INFO : one.rclone_chunk.001_7olrrr: Moved (server-side) to: one 2025/04/24 05:23:54 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/24 05:23:54 INFO : one: Copied (new) 2025/04/24 05:23:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:23:55 DEBUG : two: Unchanged skipping 2025/04/24 05:23:56 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/04/24 05:23:56 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:23:58 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/24 05:23:58 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/24 05:23:58 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:24:04 INFO : three.txt.rclone_chunk.001_7olzsg: Moved (server-side) to: three.txt 2025/04/24 05:24:04 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/04/24 05:24:04 INFO : three.txt: Copied (new) 2025/04/24 05:24:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/24 05:24:11 INFO : one-2019-01-01: Deleted 2025/04/24 05:24:11 DEBUG : one: move non-chunked object... 2025/04/24 05:24:13 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/24 05:24:13 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/24 05:24:13 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:24:20 INFO : one.rclone_chunk.001_7omedz: Moved (server-side) to: one 2025/04/24 05:24:20 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/24 05:24:20 INFO : one: Copied (new) 2025/04/24 05:24:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:24:21 DEBUG : two: Unchanged skipping 2025/04/24 05:24:22 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/04/24 05:24:27 INFO : three-2019-01-01.txt: Deleted 2025/04/24 05:24:27 DEBUG : three.txt: move non-chunked object... 2025/04/24 05:24:29 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/24 05:24:29 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/24 05:24:29 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:24:35 INFO : three.txt.rclone_chunk.001_7omu42: Moved (server-side) to: three.txt 2025/04/24 05:24:35 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/04/24 05:24:35 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (98.29s) === RUN TestSyncUTFNorm run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:25:10 INFO : Testêé.rclone_chunk.001_7onviy: Moved (server-side) to: Testêé 2025/04/24 05:25:11 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/04/24 05:25:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:25:11 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:25:19 INFO : Testêé: Deleted 2025/04/24 05:25:21 INFO : Testêé.rclone_chunk.001_7oo0te: Moved (server-side) to: Testêé 2025/04/24 05:25:21 DEBUG : Testêé: md5 = ce114e4501d2f4e2dcea3e17b546f339 OK 2025/04/24 05:25:21 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/04/24 05:25:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (19.74s) === RUN TestSyncImmutable run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:25:26 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/24 05:25:26 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:25:26 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:25:26 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:25:33 INFO : existing.rclone_chunk.001_7oof9n: Moved (server-side) to: existing 2025/04/24 05:25:33 DEBUG : existing: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/04/24 05:25:33 INFO : existing: Copied (new) 2025/04/24 05:25:33 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:25:34 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/04/24 05:25:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:25:34 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/04/24 05:25:34 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:25:34 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': not deleting files as there were IO errors 2025/04/24 05:25:34 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" --- PASS: TestSyncImmutable (16.04s) === RUN TestSyncIgnoreCase run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:25:46 INFO : EXISTING.rclone_chunk.001_7oovlj: Moved (server-side) to: EXISTING 2025/04/24 05:25:47 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:25:47 DEBUG : existing: Unchanged skipping 2025/04/24 05:25:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:25:47 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:25:47 DEBUG : Waiting for deletions to finish 2025/04/24 05:25:47 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (9.77s) === RUN TestFixCase run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:2635: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.56s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (2.35s) --- SKIP: TestMaxTransfer/Hard (1.17s) --- SKIP: TestMaxTransfer/Soft (0.57s) --- SKIP: TestMaxTransfer/Cautious (0.61s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:26:00 INFO : both0.rclone_chunk.001_7op7r6: Moved (server-side) to: both0 2025/04/24 05:26:06 INFO : only0.rclone_chunk.001_7opdm7: Moved (server-side) to: only0 2025/04/24 05:26:11 INFO : both1.rclone_chunk.001_7opjj5: Moved (server-side) to: both1 2025/04/24 05:26:17 INFO : only1.rclone_chunk.001_7opozw: Moved (server-side) to: only1 2025/04/24 05:26:23 INFO : both2.rclone_chunk.001_7opum1: Moved (server-side) to: both2 2025/04/24 05:26:28 INFO : only2.rclone_chunk.001_7oq00n: Moved (server-side) to: only2 2025/04/24 05:26:34 INFO : both3.rclone_chunk.001_7oq5wa: Moved (server-side) to: both3 2025/04/24 05:26:40 INFO : only3.rclone_chunk.001_7oqbhe: Moved (server-side) to: only3 2025/04/24 05:26:45 INFO : both4.rclone_chunk.001_7oqhw9: Moved (server-side) to: both4 2025/04/24 05:26:53 INFO : only4.rclone_chunk.001_7oqmxk: Moved (server-side) to: only4 2025/04/24 05:26:59 INFO : both5.rclone_chunk.001_7oqu07: Moved (server-side) to: both5 2025/04/24 05:27:05 INFO : only5.rclone_chunk.001_7or051: Moved (server-side) to: only5 2025/04/24 05:27:10 INFO : both6.rclone_chunk.001_7or68a: Moved (server-side) to: both6 2025/04/24 05:27:14 INFO : only6.rclone_chunk.001_7orbiq: Moved (server-side) to: only6 2025/04/24 05:27:19 INFO : both7.rclone_chunk.001_7orgc3: Moved (server-side) to: both7 2025/04/24 05:27:25 INFO : only7.rclone_chunk.001_7orlok: Moved (server-side) to: only7 2025/04/24 05:27:29 INFO : both8.rclone_chunk.001_7orqyi: Moved (server-side) to: both8 2025/04/24 05:27:34 INFO : only8.rclone_chunk.001_7oru2c: Moved (server-side) to: only8 2025/04/24 05:27:38 INFO : both9.rclone_chunk.001_7orzna: Moved (server-side) to: both9 2025/04/24 05:27:43 INFO : only9.rclone_chunk.001_7os4d8: Moved (server-side) to: only9 2025/04/24 05:27:49 INFO : both10.rclone_chunk.001_7os8ye: Moved (server-side) to: both10 2025/04/24 05:27:53 INFO : only10.rclone_chunk.001_7ose29: Moved (server-side) to: only10 2025/04/24 05:27:58 INFO : both11.rclone_chunk.001_7osiem: Moved (server-side) to: both11 2025/04/24 05:28:03 INFO : only11.rclone_chunk.001_7osnib: Moved (server-side) to: only11 2025/04/24 05:28:08 INFO : both12.rclone_chunk.001_7ossdp: Moved (server-side) to: both12 2025/04/24 05:28:13 INFO : only12.rclone_chunk.001_7osyxv: Moved (server-side) to: only12 2025/04/24 05:28:18 INFO : both13.rclone_chunk.001_7ot2x0: Moved (server-side) to: both13 2025/04/24 05:28:23 INFO : only13.rclone_chunk.001_7ot83a: Moved (server-side) to: only13 2025/04/24 05:28:30 INFO : both14.rclone_chunk.001_7otdlr: Moved (server-side) to: both14 2025/04/24 05:28:35 INFO : only14.rclone_chunk.001_7otjzc: Moved (server-side) to: only14 2025/04/24 05:28:40 INFO : both15.rclone_chunk.001_7oton6: Moved (server-side) to: both15 2025/04/24 05:28:46 INFO : only15.rclone_chunk.001_7otvkl: Moved (server-side) to: only15 2025/04/24 05:28:51 INFO : both16.rclone_chunk.001_7otz0y: Moved (server-side) to: both16 2025/04/24 05:28:57 INFO : only16.rclone_chunk.001_7ou5xf: Moved (server-side) to: only16 2025/04/24 05:29:01 INFO : both17.rclone_chunk.001_7ouay1: Moved (server-side) to: both17 2025/04/24 05:29:05 INFO : only17.rclone_chunk.001_7ouerh: Moved (server-side) to: only17 2025/04/24 05:29:10 INFO : both18.rclone_chunk.001_7oujr3: Moved (server-side) to: both18 2025/04/24 05:29:15 INFO : only18.rclone_chunk.001_7oun9k: Moved (server-side) to: only18 2025/04/24 05:29:21 INFO : both19.rclone_chunk.001_7outal: Moved (server-side) to: both19 2025/04/24 05:29:27 INFO : only19.rclone_chunk.001_7ouykw: Moved (server-side) to: only19 2025/04/24 05:29:28 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both0: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both1: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both10: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both11: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both12: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both13: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both14: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both15: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both16: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both17: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both18: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both19: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both2: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both3: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both4: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both5: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both6: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both7: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both8: Unchanged skipping 2025/04/24 05:29:28 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:29:28 DEBUG : both9: Unchanged skipping 2025/04/24 05:29:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:29:28 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:29:28 DEBUG : Waiting for deletions to finish 2025/04/24 05:29:31 INFO : only7: Deleted 2025/04/24 05:29:32 INFO : only1: Deleted 2025/04/24 05:29:33 INFO : only9: Deleted 2025/04/24 05:29:34 INFO : only3: Deleted 2025/04/24 05:29:36 INFO : only13: Deleted 2025/04/24 05:29:37 INFO : only18: Deleted 2025/04/24 05:29:38 INFO : only8: Deleted 2025/04/24 05:29:40 INFO : only11: Deleted 2025/04/24 05:29:40 INFO : only12: Deleted 2025/04/24 05:29:42 INFO : only2: Deleted 2025/04/24 05:29:42 ERROR : only0: Couldn't delete: [500 - InternalServerError] Internal Server Error (Ошибка сервера.) 2025/04/24 05:29:44 INFO : only5: Deleted 2025/04/24 05:29:45 INFO : only6: Deleted 2025/04/24 05:29:46 INFO : only10: Deleted 2025/04/24 05:29:48 INFO : only19: Deleted 2025/04/24 05:29:48 INFO : only15: Deleted 2025/04/24 05:29:49 INFO : only17: Deleted 2025/04/24 05:29:50 INFO : only14: Deleted 2025/04/24 05:29:52 INFO : only16: Deleted 2025/04/24 05:29:52 INFO : only4: Deleted 2025/04/24 05:29:52 ERROR : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" sync_test.go:2752: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2752 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Received unexpected error: failed to delete 1 files Test: TestSyncConcurrentDelete Messages: Sync must not return a error sync_test.go:2753: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2753 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be false Test: TestSyncConcurrentDelete Messages: Low level errors must not have happened fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /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:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "only0" --- FAIL: TestSyncConcurrentDelete (338.09s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" 2025/04/24 05:31:39 INFO : both0.rclone_chunk.001_7oym6u: Moved (server-side) to: both0 2025/04/24 05:31:45 INFO : only0.rclone_chunk.001_7oys6m: Moved (server-side) to: only0 2025/04/24 05:31:51 INFO : both1.rclone_chunk.001_7oyylz: Moved (server-side) to: both1 2025/04/24 05:31:56 INFO : only1.rclone_chunk.001_7oz4jj: Moved (server-side) to: only1 2025/04/24 05:32:03 INFO : both2.rclone_chunk.001_7ozaom: Moved (server-side) to: both2 2025/04/24 05:32:08 INFO : only2.rclone_chunk.001_7ozgb2: Moved (server-side) to: only2 2025/04/24 05:32:13 INFO : both3.rclone_chunk.001_7ozlnv: Moved (server-side) to: both3 2025/04/24 05:32:19 INFO : only3.rclone_chunk.001_7ozqsi: Moved (server-side) to: only3 2025/04/24 05:32:25 INFO : both4.rclone_chunk.001_7ozxzj: Moved (server-side) to: both4 2025/04/24 05:32:31 INFO : only4.rclone_chunk.001_7p02cm: Moved (server-side) to: only4 2025/04/24 05:32:37 INFO : both5.rclone_chunk.001_7p098v: Moved (server-side) to: both5 2025/04/24 05:32:42 INFO : only5.rclone_chunk.001_7p0efa: Moved (server-side) to: only5 2025/04/24 05:32:47 INFO : both6.rclone_chunk.001_7p0kst: Moved (server-side) to: both6 2025/04/24 05:32:53 INFO : only6.rclone_chunk.001_7p0o58: Moved (server-side) to: only6 2025/04/24 05:32:59 INFO : both7.rclone_chunk.001_7p0ux4: Moved (server-side) to: both7 2025/04/24 05:33:04 INFO : only7.rclone_chunk.001_7p1096: Moved (server-side) to: only7 2025/04/24 05:33:11 INFO : both8.rclone_chunk.001_7p16gz: Moved (server-side) to: both8 2025/04/24 05:33:16 INFO : only8.rclone_chunk.001_7p1ca5: Moved (server-side) to: only8 2025/04/24 05:33:23 INFO : both9.rclone_chunk.001_7p1hgg: Moved (server-side) to: both9 2025/04/24 05:33:29 INFO : only9.rclone_chunk.001_7p1pdx: Moved (server-side) to: only9 2025/04/24 05:33:34 INFO : both10.rclone_chunk.001_7p1uyv: Moved (server-side) to: both10 2025/04/24 05:33:40 INFO : only10.rclone_chunk.001_7p1zdt: Moved (server-side) to: only10 2025/04/24 05:33:50 INFO : both11.rclone_chunk.001_7p26vm: Moved (server-side) to: both11 2025/04/24 05:33:55 INFO : only11.rclone_chunk.001_7p2fs4: Moved (server-side) to: only11 2025/04/24 05:34:01 INFO : both12.rclone_chunk.001_7p2kcv: Moved (server-side) to: both12 2025/04/24 05:34:08 INFO : only12.rclone_chunk.001_7p2rc6: Moved (server-side) to: only12 2025/04/24 05:34:13 INFO : both13.rclone_chunk.001_7p2xz2: Moved (server-side) to: both13 2025/04/24 05:34:18 INFO : only13.rclone_chunk.001_7p32g8: Moved (server-side) to: only13 2025/04/24 05:34:26 INFO : both14.rclone_chunk.001_7p38k9: Moved (server-side) to: both14 2025/04/24 05:34:32 INFO : only14.rclone_chunk.001_7p3fho: Moved (server-side) to: only14 2025/04/24 05:34:38 INFO : both15.rclone_chunk.001_7p3ld3: Moved (server-side) to: both15 2025/04/24 05:34:43 INFO : only15.rclone_chunk.001_7p3rem: Moved (server-side) to: only15 2025/04/24 05:34:48 INFO : both16.rclone_chunk.001_7p3wf5: Moved (server-side) to: both16 2025/04/24 05:34:53 INFO : only16.rclone_chunk.001_7p42do: Moved (server-side) to: only16 2025/04/24 05:34:58 INFO : both17.rclone_chunk.001_7p46a5: Moved (server-side) to: both17 2025/04/24 05:35:05 INFO : only17.rclone_chunk.001_7p4c9m: Moved (server-side) to: only17 2025/04/24 05:35:10 INFO : both18.rclone_chunk.001_7p4ili: Moved (server-side) to: both18 2025/04/24 05:35:16 INFO : only18.rclone_chunk.001_7p4nc0: Moved (server-side) to: only18 2025/04/24 05:35:21 INFO : both19.rclone_chunk.001_7p4tag: Moved (server-side) to: both19 2025/04/24 05:35:26 INFO : only19.rclone_chunk.001_7p4y72: Moved (server-side) to: only19 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:2744 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Should be true Test: TestSyncConcurrentTruncate Messages: both7: Modification time difference too big |-212305h27m51.500000001s| > 1ns (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2025-04-24 05:32:58 +0000 UTC) (precision 1ns) 2025/04/24 05:35:27 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both0: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both1: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both10: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both11: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both12: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both13: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both14: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both15: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both16: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both17: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both18: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both19: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for checks to finish 2025/04/24 05:35:27 DEBUG : both2: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both6: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both7: Modification times differ by 212305h27m51.500000001s: 2001-02-03 04:05:06.499999999 +0000 UTC, 2025-04-24 05:32:58 +0000 UTC 2025/04/24 05:35:27 DEBUG : both3: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both8: Unchanged skipping 2025/04/24 05:35:27 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2025/04/24 05:35:27 DEBUG : both9: Unchanged skipping 2025/04/24 05:35:27 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : both7: md5 = 8ee2027983915ec78acc45027d874316 OK 2025/04/24 05:35:27 DEBUG : only0: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:27 DEBUG : only1: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:27 DEBUG : only10: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:27 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only11: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:27 DEBUG : both4: Unchanged skipping 2025/04/24 05:35:27 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : both5: Unchanged skipping 2025/04/24 05:35:27 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/04/24 05:35:27 INFO : both7: Updated modification time in destination 2025/04/24 05:35:27 DEBUG : both7: Unchanged skipping 2025/04/24 05:35:27 DEBUG : Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa': Waiting for transfers to finish 2025/04/24 05:35:35 INFO : only11: Deleted 2025/04/24 05:35:35 INFO : only10: Deleted 2025/04/24 05:35:35 INFO : only1: Deleted 2025/04/24 05:35:36 INFO : only0: Deleted 2025/04/24 05:35:36 INFO : only10.rclone_chunk.001_7p54bi: Moved (server-side) to: only10 2025/04/24 05:35:36 DEBUG : only10: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:36 INFO : only10: Copied (replaced existing) 2025/04/24 05:35:36 DEBUG : only12: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:37 INFO : only1.rclone_chunk.001_7p54q4: Moved (server-side) to: only1 2025/04/24 05:35:37 DEBUG : only1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:37 INFO : only1: Copied (replaced existing) 2025/04/24 05:35:37 DEBUG : only15: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:37 INFO : only11.rclone_chunk.001_7p54qz: Moved (server-side) to: only11 2025/04/24 05:35:37 DEBUG : only11: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:37 INFO : only11: Copied (replaced existing) 2025/04/24 05:35:37 DEBUG : only16: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:38 INFO : only0.rclone_chunk.001_7p54ys: Moved (server-side) to: only0 2025/04/24 05:35:38 DEBUG : only0: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:38 INFO : only0: Copied (replaced existing) 2025/04/24 05:35:38 DEBUG : only17: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:44 INFO : only12: Deleted 2025/04/24 05:35:45 INFO : only16: Deleted 2025/04/24 05:35:46 INFO : only15: Deleted 2025/04/24 05:35:46 INFO : only12.rclone_chunk.001_7p5d9i: Moved (server-side) to: only12 2025/04/24 05:35:46 DEBUG : only12: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:46 INFO : only12: Copied (replaced existing) 2025/04/24 05:35:46 DEBUG : only18: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:47 INFO : only17: Deleted 2025/04/24 05:35:47 INFO : only16.rclone_chunk.001_7p5eud: Moved (server-side) to: only16 2025/04/24 05:35:47 DEBUG : only16: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:47 INFO : only16: Copied (replaced existing) 2025/04/24 05:35:47 DEBUG : only19: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:48 INFO : only15.rclone_chunk.001_7p5eun: Moved (server-side) to: only15 2025/04/24 05:35:48 DEBUG : only15: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:48 INFO : only15: Copied (replaced existing) 2025/04/24 05:35:48 DEBUG : only2: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:49 INFO : only17.rclone_chunk.001_7p5f4e: Moved (server-side) to: only17 2025/04/24 05:35:49 DEBUG : only17: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:49 INFO : only17: Copied (replaced existing) 2025/04/24 05:35:49 DEBUG : only3: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:54 INFO : only18: Deleted 2025/04/24 05:35:55 INFO : only19: Deleted 2025/04/24 05:35:56 INFO : only18.rclone_chunk.001_7p5n75: Moved (server-side) to: only18 2025/04/24 05:35:56 DEBUG : only18: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:56 INFO : only18: Copied (replaced existing) 2025/04/24 05:35:56 DEBUG : only4: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:56 INFO : only2: Deleted 2025/04/24 05:35:57 INFO : only19.rclone_chunk.001_7p5oui: Moved (server-side) to: only19 2025/04/24 05:35:57 DEBUG : only19: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:57 INFO : only19: Copied (replaced existing) 2025/04/24 05:35:57 DEBUG : only5: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:57 INFO : only3: Deleted 2025/04/24 05:35:58 INFO : only2.rclone_chunk.001_7p5pvp: Moved (server-side) to: only2 2025/04/24 05:35:58 DEBUG : only2: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:58 INFO : only2: Copied (replaced existing) 2025/04/24 05:35:58 DEBUG : only6: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:35:59 INFO : only3.rclone_chunk.001_7p5qjf: Moved (server-side) to: only3 2025/04/24 05:35:59 DEBUG : only3: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:35:59 INFO : only3: Copied (replaced existing) 2025/04/24 05:35:59 DEBUG : only7: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:36:05 INFO : only4: Deleted 2025/04/24 05:36:06 INFO : only5: Deleted 2025/04/24 05:36:07 INFO : only4.rclone_chunk.001_7p5xs0: Moved (server-side) to: only4 2025/04/24 05:36:07 DEBUG : only4: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:36:07 INFO : only4: Copied (replaced existing) 2025/04/24 05:36:07 DEBUG : only8: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:36:07 INFO : only6: Deleted 2025/04/24 05:36:07 INFO : only7: Deleted 2025/04/24 05:36:08 INFO : only6.rclone_chunk.001_7p5zpk: Moved (server-side) to: only6 2025/04/24 05:36:08 DEBUG : only6: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:36:08 INFO : only6: Copied (replaced existing) 2025/04/24 05:36:08 DEBUG : only13: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:36:10 INFO : only7.rclone_chunk.001_7p600h: Moved (server-side) to: only7 2025/04/24 05:36:10 DEBUG : only7: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:36:10 INFO : only7: Copied (replaced existing) 2025/04/24 05:36:10 DEBUG : only14: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:36:11 INFO : only5.rclone_chunk.001_7p5yt8: Moved (server-side) to: only5 2025/04/24 05:36:11 DEBUG : only5: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:36:11 INFO : only5: Copied (replaced existing) 2025/04/24 05:36:11 DEBUG : only9: skip slow MD5 on source file, hashing in-transit 2025/04/24 05:36:15 INFO : only8: Deleted 2025/04/24 05:36:17 INFO : only13: Deleted 2025/04/24 05:36:17 INFO : only8.rclone_chunk.001_7p6828: Moved (server-side) to: only8 2025/04/24 05:36:17 DEBUG : only8: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:36:17 INFO : only8: Copied (replaced existing) 2025/04/24 05:36:18 INFO : only14: Deleted 2025/04/24 05:36:19 INFO : only9: Deleted 2025/04/24 05:36:20 INFO : only13.rclone_chunk.001_7p69mh: Moved (server-side) to: only13 2025/04/24 05:36:20 DEBUG : only13: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:36:20 INFO : only13: Copied (replaced existing) 2025/04/24 05:36:20 INFO : only14.rclone_chunk.001_7p6bs4: Moved (server-side) to: only14 2025/04/24 05:36:20 DEBUG : only14: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:36:20 INFO : only14: Copied (replaced existing) 2025/04/24 05:36:21 INFO : only9.rclone_chunk.001_7p6ci5: Moved (server-side) to: only9 2025/04/24 05:36:21 DEBUG : only9: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/04/24 05:36:21 INFO : only9: Copied (replaced existing) 2025/04/24 05:36:21 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bYandex:rclone-test-judugod3nuxa'", Local "Local file system at /tmp/rclone524710327", Modify Window "1ns" panic: test timed out after 1h0m0s running tests: TestSyncConcurrentTruncate (7m37s) goroutine 21647 [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, 7 minutes]: testing.(*T).Run(0xc0000fe4e0, {0x260bdb6?, 0x0?}, 0x271eb18) /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(0xc0000fe4e0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2168 +0x37 testing.tRunner(0xc0000fe4e0, 0xc000687c18) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1690 +0xf4 testing.runTests(0xc000afe8d0, {0x3cfbaa0, 0x5c, 0x5c}, {0x3d12880?, 0x1?, 0x3d12880?}) /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(0xc0006c3b80) /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(0xc0006c3b80) /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 18587 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0009e6a80) /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 18545 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:408 +0xb6 goroutine 35 [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 36 [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 19926 [semacquire, 2 minutes]: sync.runtime_Semacquire(0xc001045b68?) /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(0x29fab60?) /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({0x29fab60, 0x3d35d60}, {0x2a0eb80, 0xc0001e2000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc000c293e0, 0x271fee8) /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({0x29fab60, 0x3d35d60}, {0x2a0eb80, 0xc0001e2000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0xc000c293e0) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:76 +0x2a5 github.com/rclone/rclone/fs/walk.listRwalk({0x29fab60, 0x3d35d60}, {0x2a0eb80, 0xc0001e2000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc000c29380) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:170 +0x110 github.com/rclone/rclone/fs/walk.ListR({0x29fab60, 0x3d35d60}, {0x2a0eb80, 0xc0001e2000}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0xc000c29380) /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(0xc000310a10) /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(0xc0000fe000, 0x8?) /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(0xc0000fe000, 0x271eb18) /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 21591 [select, 2 minutes]: 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 19926 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5 goroutine 21593 [select, 2 minutes]: 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 19926 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5 goroutine 21594 [select]: net/http.(*persistConn).roundTrip(0xc0006fb320, 0xc0000c1220) /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(0xc0005c3c80, 0xc000bdc3c0) /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(0xc0003628c0, 0xc000bdc3c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 golang.org/x/oauth2.(*Transport).RoundTrip(0xc0004e4e20, 0xc000bdc280) /home/rclone/go/pkg/mod/golang.org/x/oauth2@v0.27.0/transport.go:55 +0x3de net/http.send(0xc000bdc280, {0x29d6be0, 0xc0004e4e20}, {0xc00006d201?, 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(0xc00052eea0, 0xc000bdc280, {0xc000dbefdc?, 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(0xc00052eea0, 0xc000bdc280) /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(0xc000694c30, {0x29fab60, 0x3d35d60}, 0xc000bdc140) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:353 +0xd67 github.com/rclone/rclone/backend/yandex.(*Fs).delete.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/yandex/yandex.go:603 +0x50 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(0xc000173e60, 0xc000f88140, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc000173e60, 0xc000f88140) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x92 github.com/rclone/rclone/backend/yandex.(*Fs).delete(0xc000397b00, {0x29fab60, 0x3d35d60}, {0xc000b7c8a0, 0x24}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/backend/yandex/yandex.go:602 +0x3db github.com/rclone/rclone/backend/yandex.(*Object).Remove(0xc000d78240, {0x29fab60, 0x3d35d60}) /home/rclone/go/src/github.com/rclone/rclone/backend/yandex/yandex.go:1164 +0x8b github.com/rclone/rclone/backend/chunker.(*Object).Remove(0xc000939980, {0x29fab60, 0x3d35d60}) /home/rclone/go/src/github.com/rclone/rclone/backend/chunker/chunker.go:1661 +0x130 github.com/rclone/rclone/fstest.newRunIndividual.func1.1.1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x24 github.com/rclone/rclone/fstest.retry(0xc0000fe000, {0xc0006a28e8, 0x15}, 0xc00006dd58) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:121 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0xc0007a9208?, 0x0?, 0xc00047d110?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:154 +0x369 github.com/rclone/rclone/fs/walk.listRwalk.func1({0x0?, 0x3d35d60?}, {0xc0007a9208, 0x28, 0x47}, {0x0?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:179 +0x162 github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:415 +0x344 created by github.com/rclone/rclone/fs/walk.walk in goroutine 19926 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5 goroutine 21592 [select, 2 minutes]: 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 19926 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5 goroutine 21588 [select, 2 minutes]: 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 19926 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5 goroutine 20977 [select]: net/http.(*persistConn).writeLoop(0xc0006fb320) /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 20891 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 21590 [select, 2 minutes]: 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 19926 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5 goroutine 21587 [select, 2 minutes]: 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 19926 /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:395 +0x1e5 goroutine 3381 [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 3399 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 20976 [IO wait]: internal/poll.runtime_pollWait(0x7f2413659dc8, 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(0xc000c44980?, 0xc0008d6000?, 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(0xc000c44980, {0xc0008d6000, 0x2a80, 0x2a80}) /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(0xc000c44980, {0xc0008d6000?, 0xc0008d6000?, 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(0xc00060db20, {0xc0008d6000?, 0x3d12880?, 0xc000e059d0?}) /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(0xc0008620c0, {0xc0008d6000?, 0x18?, 0xc000e05998?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000b7e318, {0xc0008d6000?, 0x0?, 0xc000b7e318?}) /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(0xc00099d7b8, {0x29d6040, 0xc000b7e318}) /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(0xc00099d508, {0x7f2410385300, 0xc0008620c0}, 0xc000e05a40?) /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(0xc00099d508, 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(0xc00099d508, {0xc000f5b000, 0x1000, 0xc0004aa508?}) /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(0xc0006fb320, {0xc000f5b000?, 0x81e525?, 0x2142d40?}) /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(0xc000a4dc20) /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(0xc000a4dc20, 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(0xc0006fb320) /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 20891 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 21589 [select, 2 minutes]: 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 19926 /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.574123685s (try 1/5): exit status 2: Failed [TestMoveOverSelf TestSyncDeleteBefore TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncConcurrentDelete]