"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMailru: -verbose -fast-list -size-limit 10240" - Starting (try 1/5) 2024/04/21 02:09:55 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1" 2024/04/21 02:09:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/21 02:09:55 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1" 2024/04/21 02:09:56 DEBUG : Reset feature "ListR" 2024/04/21 02:09:56 DEBUG : Creating backend with remote "/tmp/rclone1400021508" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.03s) === 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 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:09:56 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:09:56 NOTICE: sub dir: Skipped make directory as --dry-run is set 2024/04/21 02:09:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:09:56 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2024/04/21 02:09:56 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:09:56 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyWithDryRun (0.85s) === RUN TestCopy run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:09:57 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/21 02:09:57 DEBUG : sub dir: Making directory 2024/04/21 02:09:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:09:57 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:09:57 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:09:57 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:09:57 INFO : sub dir/hello world.rclone_chunk.001_9y6xmi: Moved (server-side) to: sub dir/hello world 2024/04/21 02:09:57 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:09:58 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.68s) === RUN TestCopyMetadata run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.19s) === RUN TestCopyMissingDirectory run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:09:59 DEBUG : Creating backend with remote "/non-existing" 2024/04/21 02:09:59 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2024/04/21 02:09:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:09:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyMissingDirectory (0.39s) === RUN TestCopyNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:09:59 DEBUG : sub dir: Making directory 2024/04/21 02:09:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:09:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:09:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:09:59 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:00 INFO : sub dir/hello world.rclone_chunk.001_9y6zj2: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:00 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyNoTraverse (1.61s) === RUN TestCopyCheckFirst run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:01 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Running all checks before starting transfers 2024/04/21 02:10:01 DEBUG : sub dir: Making directory 2024/04/21 02:10:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:10:01 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:01 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Checks finished, now starting transfers 2024/04/21 02:10:01 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:01 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:01 INFO : sub dir/hello world.rclone_chunk.001_9y71ry: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:01 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyCheckFirst (1.62s) === RUN TestSyncNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:02 ERROR : Ignoring --no-traverse with sync 2024/04/21 02:10:02 DEBUG : sub dir: Making directory 2024/04/21 02:10:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:10:02 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:02 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:02 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:03 INFO : sub dir/hello world.rclone_chunk.001_9y724x: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:03 INFO : sub dir/hello world: Copied (new) 2024/04/21 02:10:03 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncNoTraverse (1.68s) === RUN TestCopyWithDepth run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:04 DEBUG : hello world2: Need to transfer - File not found at Destination 2024/04/21 02:10:04 DEBUG : sub dir: Making directory 2024/04/21 02:10:04 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:04 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:04 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:05 INFO : hello world2.rclone_chunk.001_9y743a: Moved (server-side) to: hello world2 2024/04/21 02:10:05 INFO : hello world2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyWithDepth (1.62s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:06 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:10:06 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:06 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:06 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:06 INFO : potato2.rclone_chunk.001_9y76p8: Moved (server-side) to: potato2 2024/04/21 02:10:06 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyWithFilesFrom (1.32s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:07 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:10:07 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:07 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:07 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:07 INFO : potato2.rclone_chunk.001_9y77he: Moved (server-side) to: potato2 2024/04/21 02:10:07 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyWithFilesFromAndNoTraverse (1.23s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:08 DEBUG : sub dir2: Making directory with metadata 2024/04/21 02:10:08 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/21 02:10:08 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/21 02:10:08 DEBUG : sub dir: Making directory 2024/04/21 02:10:08 DEBUG : sub dir2: Making directory 2024/04/21 02:10:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:10:08 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:08 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:08 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:09 INFO : sub dir/hello world.rclone_chunk.001_9y783o: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:09 INFO : sub dir/hello world: Copied (new) 2024/04/21 02:10:09 DEBUG : sub dir2: Making directory 2024/04/21 02:10:09 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': copied 1 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:09 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (2.05s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:10 DEBUG : sub dir2: Making directory with metadata 2024/04/21 02:10:10 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/21 02:10:10 DEBUG : sub dir: Making directory 2024/04/21 02:10:10 DEBUG : sub dir2: Making directory 2024/04/21 02:10:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:10:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:10 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:11 INFO : sub dir/hello world.rclone_chunk.001_9y7axj: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:11 INFO : sub dir/hello world: Copied (new) 2024/04/21 02:10:11 INFO : sub dir/hello world: Deleted 2024/04/21 02:10:11 DEBUG : sub dir: Making directory 2024/04/21 02:10:11 DEBUG : sub dir2: Making directory 2024/04/21 02:10:11 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': copied 2 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:11 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2024/04/21 02:10:11 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (2.23s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:351: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.24s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:13 DEBUG : sub dir2: Making directory with metadata 2024/04/21 02:10:13 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/21 02:10:13 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/21 02:10:13 DEBUG : sub dir: Making directory 2024/04/21 02:10:13 DEBUG : sub dir2: Making directory 2024/04/21 02:10:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:10:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:13 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:13 INFO : sub dir/hello world.rclone_chunk.001_9y7drb: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:13 INFO : sub dir/hello world: Copied (new) 2024/04/21 02:10:13 DEBUG : sub dir2: Making directory 2024/04/21 02:10:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': copied 1 directories 2024/04/21 02:10:13 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:14 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.98s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:427: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.16s) === RUN TestServerSideCopy run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:15 INFO : sub dir/hello world.rclone_chunk.001_9y7fa5: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:15 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-rupaquh3banepat5xutogez9" 2024/04/21 02:10:15 DEBUG : Creating backend with remote "TestMailru:rclone-test-rupaquh3banepat5xutogez9" 2024/04/21 02:10:16 DEBUG : Reset feature "ListR" sync_test.go:487: Server side copy (if possible) Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1' -> Chunked 'TestChunkerChunk50bMailru:rclone-test-rupaquh3banepat5xutogez9' 2024/04/21 02:10:16 DEBUG : sub dir: Making directory 2024/04/21 02:10:16 DEBUG : [rclone-test-rupaquh3banepat5xutogez9]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:10:16 DEBUG : [rclone-test-rupaquh3banepat5xutogez9]: mkDirs by part "/rclone-test-rupaquh3banepat5xutogez9/sub dir" 2024/04/21 02:10:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:10:16 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-rupaquh3banepat5xutogez9': Waiting for checks to finish 2024/04/21 02:10:16 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-rupaquh3banepat5xutogez9': Waiting for transfers to finish 2024/04/21 02:10:16 DEBUG : sub dir/hello world: copy non-chunked object... 2024/04/21 02:10:16 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:16 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-rupaquh3banepat5xutogez9': Purge remote --- PASS: TestServerSideCopy (2.54s) === RUN TestCopyAfterDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:18 INFO : sub dir/hello world.rclone_chunk.001_9y7hpk: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:18 ERROR : : error listing: directory not found 2024/04/21 02:10:18 DEBUG : Local file system at /tmp/rclone1400021508: Making directory 2024/04/21 02:10:18 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:18 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyAfterDelete (1.80s) === RUN TestCopyRedownload run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:20 INFO : sub dir/hello world.rclone_chunk.001_9y7jlo: Moved (server-side) to: sub dir/hello world 2024/04/21 02:10:20 DEBUG : sub dir: Making directory with metadata 2024/04/21 02:10:20 INFO : sub dir: Made directory with metadata (mtime=1970-01-01T00:00:00Z) 2024/04/21 02:10:20 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2024/04/21 02:10:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:10:20 DEBUG : Local file system at /tmp/rclone1400021508: Waiting for checks to finish 2024/04/21 02:10:20 DEBUG : Local file system at /tmp/rclone1400021508: Waiting for transfers to finish 2024/04/21 02:10:20 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Switch file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/, expiry "2024-04-21T02:13:20.569565423Z" 2024/04/21 02:10:20 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:10:20 DEBUG : sub dir/hello world.powijod4.partial: renamed to: sub dir/hello world 2024/04/21 02:10:20 INFO : sub dir/hello world: Copied (new) 2024/04/21 02:10:20 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyRedownload (1.87s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:21 DEBUG : check sum: Need to transfer - File not found at Destination 2024/04/21 02:10:21 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:21 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:21 DEBUG : check sum: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:22 INFO : check sum.rclone_chunk.001_9y7lql: Moved (server-side) to: check sum 2024/04/21 02:10:22 INFO : check sum: Copied (new) 2024/04/21 02:10:22 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:22 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:22 NOTICE: Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2024/04/21 02:10:22 DEBUG : check sum: Size of src and dst objects identical 2024/04/21 02:10:22 DEBUG : check sum: Unchanged skipping 2024/04/21 02:10:22 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:22 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:22 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncBasedOnCheckSum (1.57s) === RUN TestSyncSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:23 DEBUG : sizeonly: Need to transfer - File not found at Destination 2024/04/21 02:10:23 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:23 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:23 DEBUG : sizeonly: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:23 INFO : sizeonly.rclone_chunk.001_9y7n39: Moved (server-side) to: sizeonly 2024/04/21 02:10:23 INFO : sizeonly: Copied (new) 2024/04/21 02:10:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:23 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:23 DEBUG : sizeonly: Sizes identical 2024/04/21 02:10:23 DEBUG : sizeonly: Unchanged skipping 2024/04/21 02:10:23 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:23 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:23 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncSizeOnly (1.61s) === RUN TestSyncIgnoreSize run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:24 DEBUG : ignore-size: Need to transfer - File not found at Destination 2024/04/21 02:10:24 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:24 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:24 DEBUG : ignore-size: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:25 INFO : ignore-size.rclone_chunk.001_9y7ors: Moved (server-side) to: ignore-size 2024/04/21 02:10:25 INFO : ignore-size: Copied (new) 2024/04/21 02:10:25 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:25 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:25 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:10:25 DEBUG : ignore-size: Unchanged skipping 2024/04/21 02:10:25 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:25 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:25 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncIgnoreSize (1.57s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:26 INFO : existing.rclone_chunk.001_9y7q6a: Moved (server-side) to: existing 2024/04/21 02:10:26 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:10:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:26 DEBUG : existing: Unchanged skipping 2024/04/21 02:10:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:26 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:26 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:26 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/04/21 02:10:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:26 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:27 INFO : existing: Deleted 2024/04/21 02:10:27 INFO : existing.rclone_chunk.001_9y7qh7: Moved (server-side) to: existing 2024/04/21 02:10:27 INFO : existing: Copied (replaced existing) 2024/04/21 02:10:27 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncIgnoreTimes (2.26s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:28 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/21 02:10:28 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:28 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:28 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:29 INFO : existing.rclone_chunk.001_9y7suy: Moved (server-side) to: existing 2024/04/21 02:10:29 INFO : existing: Copied (new) 2024/04/21 02:10:29 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:29 DEBUG : existing: Destination exists, skipping 2024/04/21 02:10:29 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:29 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:29 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:29 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncIgnoreExisting (1.51s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:30 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: new upload shard: https://uploader.cloud.mail.ru/upload/ 2024/04/21 02:10:30 INFO : b/potato.rclone_chunk.001_9y7tm5: Moved (server-side) to: b/potato 2024/04/21 02:10:31 INFO : c/non empty space.rclone_chunk.001_9y7ubu: Moved (server-side) to: c/non empty space 2024/04/21 02:10:31 DEBUG : d: Making directory 2024/04/21 02:10:31 DEBUG : a: Making directory 2024/04/21 02:10:31 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/21 02:10:31 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:10:31 DEBUG : c/non empty space: Unchanged skipping 2024/04/21 02:10:31 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:32 INFO : a/potato2.rclone_chunk.001_9y7vdp: Moved (server-side) to: a/potato2.rclone_chunk.001 2024/04/21 02:10:33 INFO : a/potato2.rclone_chunk.002_9y7vdp: Moved (server-side) to: a/potato2.rclone_chunk.002 2024/04/21 02:10:33 INFO : a/potato2: Copied (new) 2024/04/21 02:10:33 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:33 INFO : b/potato: Deleted 2024/04/21 02:10:33 INFO : d: Removing directory 2024/04/21 02:10:33 INFO : b: Removing directory 2024/04/21 02:10:33 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': deleted 2 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:34 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:10:34 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ --- PASS: TestSyncIgnoreErrors (5.14s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:35 INFO : empty space.rclone_chunk.001_9y7zob: Moved (server-side) to: empty space 2024/04/21 02:10:35 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:35 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2024/04/21 02:10:35 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:35 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2024/04/21 02:10:35 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:36 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:36 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2024/04/21 02:10:36 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:36 DEBUG : empty space: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:36 INFO : empty space: Deleted 2024/04/21 02:10:36 INFO : empty space.rclone_chunk.001_9y80pn: Moved (server-side) to: empty space 2024/04/21 02:10:36 INFO : empty space: Copied (replaced existing) 2024/04/21 02:10:36 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncAfterChangingModtimeOnly (2.53s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:811: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.19s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:38 INFO : foo.rclone_chunk.001_9y81g8: Moved (server-side) to: foo 2024/04/21 02:10:38 DEBUG : foo: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2024/04/21 02:10:38 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:38 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:38 DEBUG : foo: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:39 INFO : foo: Deleted 2024/04/21 02:10:39 INFO : foo.rclone_chunk.001_9y82p3: Moved (server-side) to: foo 2024/04/21 02:10:39 INFO : foo: Copied (replaced existing) 2024/04/21 02:10:39 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncDoesntUpdateModtime (2.12s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:40 INFO : empty space.rclone_chunk.001_9y83gm: Moved (server-side) to: empty space 2024/04/21 02:10:40 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/21 02:10:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:40 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:10:40 DEBUG : empty space: Unchanged skipping 2024/04/21 02:10:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:40 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:41 INFO : potato.rclone_chunk.001_9y84j9: Moved (server-side) to: potato.rclone_chunk.001 2024/04/21 02:10:41 INFO : potato.rclone_chunk.002_9y84j9: Moved (server-side) to: potato.rclone_chunk.002 2024/04/21 02:10:42 INFO : potato: Copied (new) 2024/04/21 02:10:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:42 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:10:42 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ --- PASS: TestSyncAfterAddingAFile (3.28s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:44 INFO : potato.rclone_chunk.001_9y87jx: Moved (server-side) to: potato.rclone_chunk.001 2024/04/21 02:10:44 INFO : potato.rclone_chunk.002_9y87jx: Moved (server-side) to: potato.rclone_chunk.002 2024/04/21 02:10:44 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/04/21 02:10:44 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:44 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:44 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:10:44 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:10:44 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:45 DEBUG : potato.rclone_chunk.001_9y88hb: File has been put by hash from source 2024/04/21 02:10:45 INFO : potato: Deleted 2024/04/21 02:10:45 INFO : potato.rclone_chunk.001_9y88hb: Moved (server-side) to: potato 2024/04/21 02:10:45 INFO : potato: Copied (replaced existing) 2024/04/21 02:10:45 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncAfterChangingFilesSizeOnly (3.15s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:47 INFO : potato.rclone_chunk.001_9y8aq7: Moved (server-side) to: potato 2024/04/21 02:10:47 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:47 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2024/04/21 02:10:47 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:47 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:47 DEBUG : potato.rclone_chunk.001_9y8b9d: File has been put by hash from source 2024/04/21 02:10:47 INFO : potato: Deleted 2024/04/21 02:10:48 INFO : potato.rclone_chunk.001_9y8b9d: Moved (server-side) to: potato 2024/04/21 02:10:48 INFO : potato: Copied (replaced existing) 2024/04/21 02:10:48 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncAfterChangingContentsOnly (2.30s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:49 INFO : potato.rclone_chunk.001_9y8c9f: Moved (server-side) to: potato 2024/04/21 02:10:50 INFO : empty space.rclone_chunk.001_9y8d5s: Moved (server-side) to: empty space 2024/04/21 02:10:50 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:10:50 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2024/04/21 02:10:50 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:50 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:10:50 DEBUG : empty space: Unchanged skipping 2024/04/21 02:10:50 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:50 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:50 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.12s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:51 INFO : potato.rclone_chunk.001_9y8eyk: Moved (server-side) to: potato 2024/04/21 02:10:52 INFO : empty space.rclone_chunk.001_9y8f0i: Moved (server-side) to: empty space 2024/04/21 02:10:52 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:10:52 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:10:52 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:52 DEBUG : empty space: Unchanged skipping 2024/04/21 02:10:52 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:52 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:53 INFO : potato2.rclone_chunk.001_9y8g9m: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/21 02:10:53 INFO : potato2.rclone_chunk.002_9y8g9m: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/21 02:10:53 INFO : potato2: Copied (new) 2024/04/21 02:10:53 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:53 INFO : potato: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:54 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:10:54 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.88s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:55 INFO : b/potato.rclone_chunk.001_9y8iya: Moved (server-side) to: b/potato 2024/04/21 02:10:56 INFO : c/non empty space.rclone_chunk.001_9y8jxs: Moved (server-side) to: c/non empty space 2024/04/21 02:10:56 DEBUG : d: Making directory 2024/04/21 02:10:56 DEBUG : d/e: Making directory 2024/04/21 02:10:56 DEBUG : a: Making directory 2024/04/21 02:10:56 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/21 02:10:56 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:10:56 DEBUG : c/non empty space: Unchanged skipping 2024/04/21 02:10:56 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:10:56 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:10:56 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:10:57 INFO : a/potato2.rclone_chunk.001_9y8k44: Moved (server-side) to: a/potato2.rclone_chunk.001 2024/04/21 02:10:57 INFO : a/potato2.rclone_chunk.002_9y8k44: Moved (server-side) to: a/potato2.rclone_chunk.002 2024/04/21 02:10:57 INFO : a/potato2: Copied (new) 2024/04/21 02:10:57 DEBUG : Waiting for deletions to finish 2024/04/21 02:10:58 INFO : b/potato: Deleted 2024/04/21 02:10:58 INFO : d/e: Removing directory 2024/04/21 02:10:58 INFO : d: Removing directory 2024/04/21 02:10:58 INFO : b: Removing directory 2024/04/21 02:10:58 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': deleted 3 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:10:59 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:10:59 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (5.15s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:00 INFO : b/potato.rclone_chunk.001_9y8n99: Moved (server-side) to: b/potato 2024/04/21 02:11:01 INFO : c/non empty space.rclone_chunk.001_9y8oep: Moved (server-side) to: c/non empty space 2024/04/21 02:11:01 DEBUG : d: Making directory 2024/04/21 02:11:01 DEBUG : a: Making directory 2024/04/21 02:11:01 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/21 02:11:01 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:11:01 DEBUG : c/non empty space: Unchanged skipping 2024/04/21 02:11:01 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:01 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:01 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:02 INFO : a/potato2.rclone_chunk.001_9y8p75: Moved (server-side) to: a/potato2.rclone_chunk.001 2024/04/21 02:11:02 INFO : a/potato2.rclone_chunk.002_9y8p75: Moved (server-side) to: a/potato2.rclone_chunk.002 2024/04/21 02:11:03 INFO : a/potato2: Copied (new) 2024/04/21 02:11:03 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': not deleting files as there were IO errors 2024/04/21 02:11:03 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:03 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:03 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (4.91s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:05 INFO : potato.rclone_chunk.001_9y8ss1: Moved (server-side) to: potato 2024/04/21 02:11:06 INFO : empty space.rclone_chunk.001_9y8tns: Moved (server-side) to: empty space 2024/04/21 02:11:06 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:06 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:11:06 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:11:06 DEBUG : empty space: Unchanged skipping 2024/04/21 02:11:06 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:06 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:06 INFO : potato: Deleted 2024/04/21 02:11:06 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:07 INFO : potato2.rclone_chunk.001_9y8uck: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/21 02:11:07 INFO : potato2.rclone_chunk.002_9y8uck: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/21 02:11:07 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:08 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:08 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ --- PASS: TestSyncDeleteDuring (3.92s) === RUN TestSyncDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:09 INFO : potato.rclone_chunk.001_9y8w3n: Moved (server-side) to: potato 2024/04/21 02:11:10 INFO : empty space.rclone_chunk.001_9y8x3s: Moved (server-side) to: empty space 2024/04/21 02:11:10 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:10 INFO : potato: Deleted 2024/04/21 02:11:10 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:11:10 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:11:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:10 DEBUG : empty space: Unchanged skipping 2024/04/21 02:11:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:10 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:11 INFO : potato2.rclone_chunk.001_9y8y2i: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/21 02:11:11 INFO : potato2.rclone_chunk.002_9y8y2i: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/21 02:11:11 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:12 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:12 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ --- PASS: TestSyncDeleteBefore (4.14s) === RUN TestCopyDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:13 INFO : potato.rclone_chunk.001_9y90c6: Moved (server-side) to: potato 2024/04/21 02:11:13 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:11:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:13 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:14 INFO : potato2.rclone_chunk.001_9y9115: Moved (server-side) to: potato2 2024/04/21 02:11:14 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestCopyDeleteBefore (2.26s) === RUN TestSyncWithExclude run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:15 INFO : potato2.rclone_chunk.001_9y9353: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/21 02:11:16 INFO : potato2.rclone_chunk.002_9y9353: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/21 02:11:16 INFO : empty space.rclone_chunk.001_9y94qh: Moved (server-side) to: empty space 2024/04/21 02:11:17 DEBUG : enormous: Excluded (Size Filter) 2024/04/21 02:11:17 DEBUG : enormous: Excluded 2024/04/21 02:11:17 DEBUG : potato2: Excluded (Size Filter) 2024/04/21 02:11:17 DEBUG : potato2: Excluded 2024/04/21 02:11:17 DEBUG : potato2: Excluded (Size Filter) 2024/04/21 02:11:17 DEBUG : potato2: Excluded 2024/04/21 02:11:17 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:11:17 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:17 DEBUG : empty space: Unchanged skipping 2024/04/21 02:11:17 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:17 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:17 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:17 DEBUG : enormous: Excluded (Size Filter) 2024/04/21 02:11:17 DEBUG : enormous: Excluded 2024/04/21 02:11:17 DEBUG : potato2: Excluded (Size Filter) 2024/04/21 02:11:17 DEBUG : potato2: Excluded 2024/04/21 02:11:17 DEBUG : potato2: Excluded (Size Filter) 2024/04/21 02:11:17 DEBUG : potato2: Excluded 2024/04/21 02:11:17 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2024/04/21 02:11:17 DEBUG : empty space: Unchanged skipping 2024/04/21 02:11:17 DEBUG : Local file system at /tmp/rclone1400021508: Waiting for checks to finish 2024/04/21 02:11:17 DEBUG : Local file system at /tmp/rclone1400021508: Waiting for transfers to finish 2024/04/21 02:11:17 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:17 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:17 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:17 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ --- PASS: TestSyncWithExclude (3.18s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:19 INFO : potato2.rclone_chunk.001_9y96d5: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/21 02:11:19 INFO : potato2.rclone_chunk.002_9y96d5: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/21 02:11:20 INFO : empty space.rclone_chunk.001_9y97s0: Moved (server-side) to: empty space 2024/04/21 02:11:21 INFO : enormous.rclone_chunk.001_9y98ah: Moved (server-side) to: enormous.rclone_chunk.001 2024/04/21 02:11:21 INFO : enormous.rclone_chunk.002_9y98ah: Moved (server-side) to: enormous.rclone_chunk.002 2024/04/21 02:11:22 DEBUG : enormous: Excluded (Size Filter) 2024/04/21 02:11:22 DEBUG : enormous: Excluded 2024/04/21 02:11:22 DEBUG : potato2: Excluded (Size Filter) 2024/04/21 02:11:22 DEBUG : potato2: Excluded 2024/04/21 02:11:22 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:11:22 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:22 DEBUG : empty space: Unchanged skipping 2024/04/21 02:11:22 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:22 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:22 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:22 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 2, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:22 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:22 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:22 INFO : potato2: Deleted 2024/04/21 02:11:22 INFO : enormous: Deleted 2024/04/21 02:11:22 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:22 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2024/04/21 02:11:22 DEBUG : empty space: Unchanged skipping 2024/04/21 02:11:22 DEBUG : Local file system at /tmp/rclone1400021508: Waiting for checks to finish 2024/04/21 02:11:22 DEBUG : Local file system at /tmp/rclone1400021508: Waiting for transfers to finish 2024/04/21 02:11:22 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:22 INFO : enormous: Deleted 2024/04/21 02:11:22 INFO : potato2: Deleted 2024/04/21 02:11:22 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncWithExcludeAndDeleteExcluded (5.11s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:23 INFO : one.rclone_chunk.001_9y9bj8: Moved (server-side) to: one 2024/04/21 02:11:24 INFO : two.rclone_chunk.001_9y9c7p: Moved (server-side) to: two 2024/04/21 02:11:25 INFO : three.rclone_chunk.001_9y9chs: Moved (server-side) to: three 2024/04/21 02:11:25 INFO : four.rclone_chunk.001_9y9diw: Moved (server-side) to: four 2024/04/21 02:11:26 DEBUG : five: Need to transfer - File not found at Destination 2024/04/21 02:11:26 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/04/21 02:11:26 DEBUG : one: Destination is newer than source, skipping 2024/04/21 02:11:26 DEBUG : three: Sizes identical 2024/04/21 02:11:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:26 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/04/21 02:11:26 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/04/21 02:11:26 DEBUG : four: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:26 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:26 DEBUG : five: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:26 INFO : four: Deleted 2024/04/21 02:11:26 INFO : two: Deleted 2024/04/21 02:11:26 INFO : five.rclone_chunk.001_9y9e4g: Moved (server-side) to: five 2024/04/21 02:11:26 INFO : five: Copied (new) 2024/04/21 02:11:26 INFO : four.rclone_chunk.001_9y9e8x: Moved (server-side) to: four 2024/04/21 02:11:26 INFO : four: Copied (replaced existing) 2024/04/21 02:11:26 INFO : two.rclone_chunk.001_9y9eo1: Moved (server-side) to: two 2024/04/21 02:11:26 INFO : two: Copied (replaced existing) 2024/04/21 02:11:26 DEBUG : Waiting for deletions to finish sync_test.go:1244: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (4.33s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1261: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1261: 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 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:1327: Can track renames: false 2024/04/21 02:11:27 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/21 02:11:27 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/21 02:11:27 DEBUG : yam: Need to transfer - File not found at Destination 2024/04/21 02:11:27 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:27 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:27 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:27 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:28 INFO : potato.rclone_chunk.001_9y9fov: Moved (server-side) to: potato 2024/04/21 02:11:28 INFO : potato: Copied (new) 2024/04/21 02:11:28 INFO : yam.rclone_chunk.001_9y9fo9: Moved (server-side) to: yam 2024/04/21 02:11:28 INFO : yam: Copied (new) 2024/04/21 02:11:28 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:28 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/21 02:11:28 DEBUG : yaml: Need to transfer - File not found at Destination 2024/04/21 02:11:28 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:11:28 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:28 DEBUG : potato: Unchanged skipping 2024/04/21 02:11:28 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:28 DEBUG : yaml: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:29 INFO : yaml.rclone_chunk.001_9y9ggb: Moved (server-side) to: yaml 2024/04/21 02:11:29 INFO : yaml: Copied (new) 2024/04/21 02:11:29 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:29 INFO : yam: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncWithTrackRenames (2.43s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:1399: Can track renames: true 2024/04/21 02:11:30 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Making map for --track-renames 2024/04/21 02:11:30 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Finished making map for --track-renames 2024/04/21 02:11:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:30 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/04/21 02:11:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for renames to finish 2024/04/21 02:11:30 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/04/21 02:11:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:30 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:30 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:30 INFO : potato.rclone_chunk.001_9y9i4u: Moved (server-side) to: potato 2024/04/21 02:11:30 INFO : potato: Copied (new) 2024/04/21 02:11:30 INFO : yam.rclone_chunk.001_9y9idq: Moved (server-side) to: yam 2024/04/21 02:11:30 INFO : yam: Copied (new) 2024/04/21 02:11:30 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:31 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Making map for --track-renames 2024/04/21 02:11:31 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:11:31 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Finished making map for --track-renames 2024/04/21 02:11:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:31 DEBUG : potato: Unchanged skipping 2024/04/21 02:11:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for renames to finish 2024/04/21 02:11:31 DEBUG : yam: move non-chunked object... 2024/04/21 02:11:31 INFO : yam: Moved (server-side) to: yaml 2024/04/21 02:11:31 INFO : yam: Moved (server-side) to: yaml 2024/04/21 02:11:31 INFO : yaml: Renamed from "yam" 2024/04/21 02:11:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:31 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:31 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.00s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:1438: Can track renames: true 2024/04/21 02:11:32 DEBUG : sub: Making directory 2024/04/21 02:11:32 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Making map for --track-renames 2024/04/21 02:11:32 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Finished making map for --track-renames 2024/04/21 02:11:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:32 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/04/21 02:11:32 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2024/04/21 02:11:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for renames to finish 2024/04/21 02:11:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:32 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:32 DEBUG : sub/yam: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:32 INFO : potato.rclone_chunk.001_9y9k39: Moved (server-side) to: potato 2024/04/21 02:11:32 INFO : potato: Copied (new) 2024/04/21 02:11:32 INFO : sub/yam.rclone_chunk.001_9y9k3y: Moved (server-side) to: sub/yam 2024/04/21 02:11:32 INFO : sub/yam: Copied (new) 2024/04/21 02:11:32 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:33 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:11:33 DEBUG : potato: Unchanged skipping 2024/04/21 02:11:33 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Making map for --track-renames 2024/04/21 02:11:33 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Finished making map for --track-renames 2024/04/21 02:11:33 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:33 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for renames to finish 2024/04/21 02:11:33 DEBUG : sub/yam: move non-chunked object... 2024/04/21 02:11:33 INFO : sub/yam: Moved (server-side) to: yam 2024/04/21 02:11:33 INFO : sub/yam: Moved (server-side) to: yam 2024/04/21 02:11:33 INFO : yam: Renamed from "sub/yam" 2024/04/21 02:11:33 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:33 DEBUG : Waiting for deletions to finish 2024/04/21 02:11:33 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.76s) === RUN TestServerSideMoveLocal run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:34 DEBUG : Creating backend with remote "/tmp/rclone1400021508/dir1" 2024/04/21 02:11:34 DEBUG : Creating backend with remote "/tmp/rclone1400021508/dir2" 2024/04/21 02:11:34 DEBUG : Local file system at /tmp/rclone1400021508/dir2: Using server-side directory move 2024/04/21 02:11:34 INFO : Local file system at /tmp/rclone1400021508/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/21 02:11:34 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/21 02:11:34 DEBUG : Local file system at /tmp/rclone1400021508/dir2: Waiting for checks to finish 2024/04/21 02:11:34 DEBUG : Local file system at /tmp/rclone1400021508/dir2: Waiting for transfers to finish 2024/04/21 02:11:34 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.17s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:35 DEBUG : nested: Making directory 2024/04/21 02:11:35 DEBUG : sub dir: Making directory 2024/04/21 02:11:35 DEBUG : nested/sub dir: Making directory 2024/04/21 02:11:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:11:35 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/04/21 02:11:35 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:35 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:35 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:35 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:35 INFO : nested/sub dir/file.rclone_chunk.001_9y9nig: Moved (server-side) to: nested/sub dir/file 2024/04/21 02:11:35 INFO : nested/sub dir/file: Copied (new) 2024/04/21 02:11:35 INFO : nested/sub dir/file: Deleted 2024/04/21 02:11:35 INFO : sub dir/hello world.rclone_chunk.001_9y9nb4: Moved (server-side) to: sub dir/hello world 2024/04/21 02:11:35 INFO : sub dir/hello world: Copied (new) 2024/04/21 02:11:35 INFO : sub dir/hello world: Deleted 2024/04/21 02:11:35 INFO : sub dir: Removing directory 2024/04/21 02:11:35 INFO : nested/sub dir: Removing directory 2024/04/21 02:11:35 INFO : nested: Removing directory 2024/04/21 02:11:35 DEBUG : Local file system at /tmp/rclone1400021508: deleted 3 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestMoveWithDeleteEmptySrcDirs (2.43s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:37 DEBUG : nested: Making directory 2024/04/21 02:11:37 DEBUG : sub dir: Making directory 2024/04/21 02:11:37 DEBUG : nested/sub dir: Making directory 2024/04/21 02:11:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/21 02:11:37 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/04/21 02:11:37 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:37 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:37 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:37 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:38 INFO : sub dir/hello world.rclone_chunk.001_9y9peg: Moved (server-side) to: sub dir/hello world 2024/04/21 02:11:38 INFO : sub dir/hello world: Copied (new) 2024/04/21 02:11:38 INFO : sub dir/hello world: Deleted 2024/04/21 02:11:38 INFO : nested/sub dir/file.rclone_chunk.001_9y9pip: Moved (server-side) to: nested/sub dir/file 2024/04/21 02:11:38 INFO : nested/sub dir/file: Copied (new) 2024/04/21 02:11:38 INFO : nested/sub dir/file: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (2.38s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:39 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/21 02:11:39 DEBUG : existing-b: Need to transfer - File not found at Destination 2024/04/21 02:11:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:39 DEBUG : existing-b: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:39 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:11:40 INFO : existing.rclone_chunk.001_9y9re1: Moved (server-side) to: existing 2024/04/21 02:11:40 INFO : existing: Copied (new) 2024/04/21 02:11:40 INFO : existing: Deleted 2024/04/21 02:11:40 INFO : existing-b.rclone_chunk.001_9y9ryp: Moved (server-side) to: existing-b 2024/04/21 02:11:40 INFO : existing-b: Copied (new) 2024/04/21 02:11:40 INFO : existing-b: Deleted 2024/04/21 02:11:40 DEBUG : existing: Destination exists, skipping 2024/04/21 02:11:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:11:40 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2024/04/21 02:11:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:11:40 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestMoveWithIgnoreExisting (1.55s) === RUN TestServerSideMove run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:41 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-jukoxum2lolepug8jikevus0" 2024/04/21 02:11:41 DEBUG : Creating backend with remote "TestMailru:rclone-test-jukoxum2lolepug8jikevus0" 2024/04/21 02:11:41 DEBUG : Reset feature "ListR" 2024/04/21 02:11:42 INFO : potato2.rclone_chunk.001_9y9t4c: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/21 02:11:42 INFO : potato2.rclone_chunk.002_9y9t4c: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/21 02:11:43 INFO : empty space.rclone_chunk.001_9y9vxc: Moved (server-side) to: empty space 2024/04/21 02:11:44 INFO : potato3.rclone_chunk.001_9y9vf3: Moved (server-side) to: potato3.rclone_chunk.001 2024/04/21 02:11:45 INFO : potato3.rclone_chunk.002_9y9vf3: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1494: Server side move (if possible) Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1' -> Chunked 'TestChunkerChunk50bMailru:rclone-test-jukoxum2lolepug8jikevus0' 2024/04/21 02:11:45 DEBUG : [rclone-test-jukoxum2lolepug8jikevus0]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:11:46 INFO : empty space.rclone_chunk.001_9y9xw9: Moved (server-side) to: empty space 2024/04/21 02:11:46 DEBUG : [rclone-test-jukoxum2lolepug8jikevus0]: new upload shard: https://uploader.cloud.mail.ru/upload/ 2024/04/21 02:11:47 INFO : potato3.rclone_chunk.001_9y9yx9: Moved (server-side) to: potato3.rclone_chunk.001 2024/04/21 02:11:47 INFO : potato3.rclone_chunk.002_9y9yx9: Moved (server-side) to: potato3.rclone_chunk.002 2024/04/21 02:11:47 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-jukoxum2lolepug8jikevus0': Using server-side directory move 2024/04/21 02:11:48 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-jukoxum2lolepug8jikevus0': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/21 02:11:48 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:11:48 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:48 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/21 02:11:48 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-jukoxum2lolepug8jikevus0': Waiting for checks to finish 2024/04/21 02:11:48 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2024/04/21 02:11:48 DEBUG : empty space: Unchanged skipping 2024/04/21 02:11:48 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:48 DEBUG : potato2: move 2 data chunks... 2024/04/21 02:11:48 DEBUG : [rclone-test-jukoxum2lolepug8jikevus0]: Switch file server: locks 1, url https://cloclo51.datacloudmail.ru/oauth-get/, expiry "2024-04-21T02:14:48.167570195Z" 2024/04/21 02:11:48 INFO : empty space: Deleted 2024/04/21 02:11:48 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-jukoxum2lolepug8jikevus0': Waiting for transfers to finish 2024/04/21 02:11:48 INFO : potato2.rclone_chunk.001: Moved (server-side) 2024/04/21 02:11:48 DEBUG : [rclone-test-jukoxum2lolepug8jikevus0]: Unlock file server: locks 0, url https://cloclo51.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:48 INFO : potato2.rclone_chunk.002: Moved (server-side) 2024/04/21 02:11:48 INFO : potato3: Deleted 2024/04/21 02:11:48 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:48 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:48 DEBUG : potato3: move 2 data chunks... 2024/04/21 02:11:48 INFO : potato2: Moved (server-side) 2024/04/21 02:11:48 DEBUG : potato2: File has been put by hash from source 2024/04/21 02:11:48 INFO : potato2: Moved (server-side) 2024/04/21 02:11:48 INFO : potato3.rclone_chunk.001: Moved (server-side) 2024/04/21 02:11:49 INFO : potato3.rclone_chunk.002: Moved (server-side) 2024/04/21 02:11:49 INFO : potato3: Moved (server-side) 2024/04/21 02:11:49 DEBUG : potato3: File has been put by hash from source 2024/04/21 02:11:49 INFO : potato3: Moved (server-side) 2024/04/21 02:11:49 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-rizuxot9wohokin6dozapef9" 2024/04/21 02:11:49 DEBUG : Creating backend with remote "TestMailru:rclone-test-rizuxot9wohokin6dozapef9" 2024/04/21 02:11:49 DEBUG : Reset feature "ListR" 2024/04/21 02:11:49 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-rizuxot9wohokin6dozapef9': Using server-side directory move 2024/04/21 02:11:49 DEBUG : [rclone-test-rizuxot9wohokin6dozapef9]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:11:49 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-rizuxot9wohokin6dozapef9': Server side directory move succeeded 2024/04/21 02:11:50 ERROR : : error listing: directory not found 2024/04/21 02:11:50 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-rizuxot9wohokin6dozapef9': Purge remote 2024/04/21 02:11:50 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-jukoxum2lolepug8jikevus0': Purge remote 2024/04/21 02:11:50 purge failed: purge failed: object not found --- PASS: TestServerSideMove (9.06s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:50 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-hunihat0vikoxor5qatorop7" 2024/04/21 02:11:50 DEBUG : Creating backend with remote "TestMailru:rclone-test-hunihat0vikoxor5qatorop7" 2024/04/21 02:11:50 DEBUG : Reset feature "ListR" 2024/04/21 02:11:51 INFO : potato2.rclone_chunk.001_9ya29d: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/21 02:11:51 INFO : potato2.rclone_chunk.002_9ya29d: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/21 02:11:52 INFO : empty space.rclone_chunk.001_9ya3x5: Moved (server-side) to: empty space 2024/04/21 02:11:53 INFO : potato3.rclone_chunk.001_9ya4a5: Moved (server-side) to: potato3.rclone_chunk.001 2024/04/21 02:11:53 INFO : potato3.rclone_chunk.002_9ya4a5: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1494: Server side move (if possible) Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1' -> Chunked 'TestChunkerChunk50bMailru:rclone-test-hunihat0vikoxor5qatorop7' 2024/04/21 02:11:54 DEBUG : [rclone-test-hunihat0vikoxor5qatorop7]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:11:54 INFO : empty space.rclone_chunk.001_9ya6p2: Moved (server-side) to: empty space 2024/04/21 02:11:55 DEBUG : [rclone-test-hunihat0vikoxor5qatorop7]: new upload shard: https://uploader.cloud.mail.ru/upload/ 2024/04/21 02:11:55 INFO : potato3.rclone_chunk.001_9ya6v5: Moved (server-side) to: potato3.rclone_chunk.001 2024/04/21 02:11:55 INFO : potato3.rclone_chunk.002_9ya6v5: Moved (server-side) to: potato3.rclone_chunk.002 2024/04/21 02:11:56 DEBUG : empty space: Excluded (Size Filter) 2024/04/21 02:11:56 DEBUG : empty space: Excluded 2024/04/21 02:11:56 DEBUG : empty space: Excluded (Size Filter) 2024/04/21 02:11:56 DEBUG : empty space: Excluded 2024/04/21 02:11:56 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:11:56 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/21 02:11:56 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-hunihat0vikoxor5qatorop7': Waiting for checks to finish 2024/04/21 02:11:56 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-hunihat0vikoxor5qatorop7': Waiting for transfers to finish 2024/04/21 02:11:56 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:56 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:56 DEBUG : potato2: move 2 data chunks... 2024/04/21 02:11:56 DEBUG : [rclone-test-hunihat0vikoxor5qatorop7]: Switch file server: locks 1, url https://cloclo53.datacloudmail.ru/oauth-get/, expiry "2024-04-21T02:14:56.494827922Z" 2024/04/21 02:11:56 DEBUG : [rclone-test-hunihat0vikoxor5qatorop7]: Unlock file server: locks 0, url https://cloclo53.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:56 INFO : potato2.rclone_chunk.001: Moved (server-side) 2024/04/21 02:11:56 INFO : potato3: Deleted 2024/04/21 02:11:56 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:56 INFO : potato2.rclone_chunk.002: Moved (server-side) 2024/04/21 02:11:56 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:56 DEBUG : potato3: move 2 data chunks... 2024/04/21 02:11:57 INFO : potato2: Moved (server-side) 2024/04/21 02:11:57 INFO : potato3.rclone_chunk.001: Moved (server-side) 2024/04/21 02:11:57 DEBUG : potato2: File has been put by hash from source 2024/04/21 02:11:57 INFO : potato2: Moved (server-side) 2024/04/21 02:11:57 INFO : potato3.rclone_chunk.002: Moved (server-side) 2024/04/21 02:11:57 INFO : potato3: Moved (server-side) 2024/04/21 02:11:57 DEBUG : potato3: File has been put by hash from source 2024/04/21 02:11:57 INFO : potato3: Moved (server-side) 2024/04/21 02:11:57 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-fasewog0nowuvex9wojepit5" 2024/04/21 02:11:57 DEBUG : Creating backend with remote "TestMailru:rclone-test-fasewog0nowuvex9wojepit5" 2024/04/21 02:11:57 DEBUG : Reset feature "ListR" 2024/04/21 02:11:57 DEBUG : empty space: Excluded (Size Filter) 2024/04/21 02:11:57 DEBUG : empty space: Excluded 2024/04/21 02:11:57 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:11:57 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/21 02:11:57 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-fasewog0nowuvex9wojepit5': Waiting for checks to finish 2024/04/21 02:11:57 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-fasewog0nowuvex9wojepit5': Waiting for transfers to finish 2024/04/21 02:11:57 DEBUG : [rclone-test-hunihat0vikoxor5qatorop7]: Lock file server: locks 1, url https://cloclo53.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:57 DEBUG : [rclone-test-hunihat0vikoxor5qatorop7]: Lock file server: locks 2, url https://cloclo53.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:58 DEBUG : [rclone-test-hunihat0vikoxor5qatorop7]: Unlock file server: locks 1, url https://cloclo53.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:58 DEBUG : potato2: move 2 data chunks... 2024/04/21 02:11:58 DEBUG : [rclone-test-fasewog0nowuvex9wojepit5]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:11:58 DEBUG : [rclone-test-hunihat0vikoxor5qatorop7]: Unlock file server: locks 0, url https://cloclo53.datacloudmail.ru/oauth-get/ 2024/04/21 02:11:58 DEBUG : potato3: move 2 data chunks... 2024/04/21 02:11:58 INFO : potato3.rclone_chunk.001: Moved (server-side) 2024/04/21 02:11:58 INFO : potato2.rclone_chunk.001: Moved (server-side) 2024/04/21 02:11:58 INFO : potato2.rclone_chunk.002: Moved (server-side) 2024/04/21 02:11:58 INFO : potato3.rclone_chunk.002: Moved (server-side) 2024/04/21 02:11:58 INFO : potato2: Moved (server-side) 2024/04/21 02:11:58 INFO : potato3: Moved (server-side) 2024/04/21 02:11:58 DEBUG : potato2: File has been put by hash from source 2024/04/21 02:11:58 INFO : potato2: Moved (server-side) 2024/04/21 02:11:58 DEBUG : potato3: File has been put by hash from source 2024/04/21 02:11:58 INFO : potato3: Moved (server-side) 2024/04/21 02:11:58 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-fasewog0nowuvex9wojepit5': Purge remote 2024/04/21 02:11:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-hunihat0vikoxor5qatorop7': Purge remote --- PASS: TestServerSideMoveWithFilter (9.16s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:11:59 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-hacunaw4qehamej3zavedil7" 2024/04/21 02:11:59 DEBUG : Creating backend with remote "TestMailru:rclone-test-hacunaw4qehamej3zavedil7" 2024/04/21 02:11:59 DEBUG : Reset feature "ListR" 2024/04/21 02:12:00 INFO : potato2.rclone_chunk.001_9yabfs: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/21 02:12:00 INFO : potato2.rclone_chunk.002_9yabfs: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/21 02:12:01 INFO : empty space.rclone_chunk.001_9yadem: Moved (server-side) to: empty space 2024/04/21 02:12:02 INFO : potato3.rclone_chunk.001_9yadti: Moved (server-side) to: potato3.rclone_chunk.001 2024/04/21 02:12:02 INFO : potato3.rclone_chunk.002_9yadti: Moved (server-side) to: potato3.rclone_chunk.002 2024/04/21 02:12:03 DEBUG : tomatoDir: Making directory sync_test.go:1494: Server side move (if possible) Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1' -> Chunked 'TestChunkerChunk50bMailru:rclone-test-hacunaw4qehamej3zavedil7' 2024/04/21 02:12:03 DEBUG : [rclone-test-hacunaw4qehamej3zavedil7]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:04 INFO : empty space.rclone_chunk.001_9yafnk: Moved (server-side) to: empty space 2024/04/21 02:12:04 DEBUG : [rclone-test-hacunaw4qehamej3zavedil7]: new upload shard: https://uploader.cloud.mail.ru/upload/ 2024/04/21 02:12:05 INFO : potato3.rclone_chunk.001_9yaghj: Moved (server-side) to: potato3.rclone_chunk.001 2024/04/21 02:12:05 INFO : potato3.rclone_chunk.002_9yaghj: Moved (server-side) to: potato3.rclone_chunk.002 2024/04/21 02:12:05 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-hacunaw4qehamej3zavedil7': Using server-side directory move 2024/04/21 02:12:05 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-hacunaw4qehamej3zavedil7': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/21 02:12:06 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/21 02:12:06 DEBUG : tomatoDir: Making directory 2024/04/21 02:12:06 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:12:06 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:12:06 DEBUG : potato2: move 2 data chunks... 2024/04/21 02:12:06 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/21 02:12:06 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2024/04/21 02:12:06 DEBUG : empty space: Unchanged skipping 2024/04/21 02:12:06 DEBUG : [rclone-test-hacunaw4qehamej3zavedil7]: Switch file server: locks 1, url https://cloclo55.datacloudmail.ru/oauth-get/, expiry "2024-04-21T02:15:06.060440984Z" 2024/04/21 02:12:06 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-hacunaw4qehamej3zavedil7': Waiting for checks to finish 2024/04/21 02:12:06 INFO : empty space: Deleted 2024/04/21 02:12:06 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-hacunaw4qehamej3zavedil7': Waiting for transfers to finish 2024/04/21 02:12:06 DEBUG : [rclone-test-hacunaw4qehamej3zavedil7]: Unlock file server: locks 0, url https://cloclo55.datacloudmail.ru/oauth-get/ 2024/04/21 02:12:06 INFO : potato2.rclone_chunk.001: Moved (server-side) 2024/04/21 02:12:06 INFO : potato2.rclone_chunk.002: Moved (server-side) 2024/04/21 02:12:06 INFO : potato3: Deleted 2024/04/21 02:12:06 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Lock file server: locks 1, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:12:06 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: Unlock file server: locks 0, url https://cloclo57.datacloudmail.ru/oauth-get/ 2024/04/21 02:12:06 DEBUG : potato3: move 2 data chunks... 2024/04/21 02:12:06 INFO : potato2: Moved (server-side) 2024/04/21 02:12:06 INFO : potato3.rclone_chunk.001: Moved (server-side) 2024/04/21 02:12:06 DEBUG : potato2: File has been put by hash from source 2024/04/21 02:12:06 INFO : potato2: Moved (server-side) 2024/04/21 02:12:07 INFO : potato3.rclone_chunk.002: Moved (server-side) 2024/04/21 02:12:07 INFO : potato3: Moved (server-side) 2024/04/21 02:12:07 DEBUG : potato3: File has been put by hash from source 2024/04/21 02:12:07 INFO : potato3: Moved (server-side) 2024/04/21 02:12:07 INFO : tomatoDir: Removing directory 2024/04/21 02:12:07 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': deleted 1 directories 2024/04/21 02:12:07 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-rukutah2kuqulug3lusofoc7" 2024/04/21 02:12:07 DEBUG : Creating backend with remote "TestMailru:rclone-test-rukutah2kuqulug3lusofoc7" 2024/04/21 02:12:07 DEBUG : Reset feature "ListR" 2024/04/21 02:12:07 DEBUG : tomatoDir: Making directory 2024/04/21 02:12:07 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-rukutah2kuqulug3lusofoc7': Using server-side directory move 2024/04/21 02:12:08 DEBUG : [rclone-test-rukutah2kuqulug3lusofoc7]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:08 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-rukutah2kuqulug3lusofoc7': Server side directory move succeeded 2024/04/21 02:12:08 ERROR : : error listing: directory not found 2024/04/21 02:12:08 ERROR : : error listing: directory not found 2024/04/21 02:12:08 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-rukutah2kuqulug3lusofoc7': Purge remote 2024/04/21 02:12:08 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-hacunaw4qehamej3zavedil7': Purge remote 2024/04/21 02:12:08 purge failed: purge failed: object not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (9.54s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:1699: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.14s) === RUN TestSyncOverlap run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:09 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test" 2024/04/21 02:12:09 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test" 2024/04/21 02:12:09 DEBUG : Reset feature "ListR" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncOverlap (0.95s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:10 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test" 2024/04/21 02:12:10 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test" 2024/04/21 02:12:10 DEBUG : Reset feature "ListR" 2024/04/21 02:12:10 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:10 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-include/layer2" 2024/04/21 02:12:10 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-include/layer2" 2024/04/21 02:12:10 DEBUG : Reset feature "ListR" 2024/04/21 02:12:10 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-include/layer2]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:10 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-include/layer2]: mkDirs by part "/rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-include/layer2" 2024/04/21 02:12:10 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-ignore-file" 2024/04/21 02:12:10 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-ignore-file" 2024/04/21 02:12:10 DEBUG : Reset feature "ListR" 2024/04/21 02:12:10 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-ignore-file]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:11 INFO : rclone-sync-test-ignore-file/.ignore.rclone_chunk.001_9yam1u: Moved (server-side) to: rclone-sync-test-ignore-file/.ignore 2024/04/21 02:12:11 DEBUG : rclone-sync-test: Excluded 2024/04/21 02:12:11 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/21 02:12:11 DEBUG : rclone-sync-test-include: Making directory 2024/04/21 02:12:12 DEBUG : rclone-sync-test-include/layer2: Excluded 2024/04/21 02:12:12 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test': Waiting for checks to finish 2024/04/21 02:12:12 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test': Waiting for transfers to finish 2024/04/21 02:12:12 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:12 INFO : There was nothing to transfer 2024/04/21 02:12:12 DEBUG : rclone-sync-test: Excluded 2024/04/21 02:12:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/21 02:12:12 DEBUG : rclone-sync-test-include/layer2: Excluded 2024/04/21 02:12:12 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:12:12 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:12:12 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:12 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:12 DEBUG : rclone-sync-test: Excluded 2024/04/21 02:12:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/21 02:12:13 DEBUG : rclone-sync-test-include: Making directory 2024/04/21 02:12:13 DEBUG : rclone-sync-test-include/layer2: Excluded 2024/04/21 02:12:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-include/layer2': Waiting for checks to finish 2024/04/21 02:12:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-include/layer2': Waiting for transfers to finish 2024/04/21 02:12:13 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:13 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:13 DEBUG : rclone-sync-test: Excluded 2024/04/21 02:12:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/21 02:12:13 DEBUG : rclone-sync-test-include/layer2: Excluded 2024/04/21 02:12:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:12:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:12:13 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:13 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:14 DEBUG : : Excluded 2024/04/21 02:12:14 DEBUG : rclone-sync-test: Excluded 2024/04/21 02:12:14 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/21 02:12:14 DEBUG : rclone-sync-test-include: Making directory 2024/04/21 02:12:14 DEBUG : rclone-sync-test-include/layer2: Excluded 2024/04/21 02:12:14 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-ignore-file': Waiting for checks to finish 2024/04/21 02:12:14 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/rclone-sync-test-ignore-file': Waiting for transfers to finish 2024/04/21 02:12:14 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:14 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncOverlapWithFilter (8.88s) === RUN TestSyncCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:19 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:12:19 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:12:19 DEBUG : Reset feature "ListR" 2024/04/21 02:12:19 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/CompareDest" 2024/04/21 02:12:19 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/CompareDest" 2024/04/21 02:12:19 DEBUG : Reset feature "ListR" 2024/04/21 02:12:19 DEBUG : one: Need to transfer - File not found at Destination 2024/04/21 02:12:19 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:19 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:19 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:19 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dst]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:20 INFO : one.rclone_chunk.001_9yavrw: Moved (server-side) to: one 2024/04/21 02:12:20 INFO : one: Copied (new) 2024/04/21 02:12:20 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:20 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:20 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:20 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:21 INFO : one: Deleted 2024/04/21 02:12:21 INFO : one.rclone_chunk.001_9yawbm: Moved (server-side) to: one 2024/04/21 02:12:21 INFO : one: Copied (replaced existing) 2024/04/21 02:12:21 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:22 INFO : dst/one: Deleted 2024/04/21 02:12:22 INFO : dst/one.rclone_chunk.001_9yaxab: Moved (server-side) to: dst/one 2024/04/21 02:12:23 INFO : CompareDest/one.rclone_chunk.001_9yayrk: Moved (server-side) to: CompareDest/one 2024/04/21 02:12:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:23 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:23 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/21 02:12:23 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:23 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:23 INFO : There was nothing to transfer 2024/04/21 02:12:24 INFO : CompareDest/two.rclone_chunk.001_9yaz15: Moved (server-side) to: CompareDest/two 2024/04/21 02:12:24 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:24 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/21 02:12:24 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:24 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:24 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:24 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/21 02:12:24 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:24 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:24 INFO : There was nothing to transfer 2024/04/21 02:12:25 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:25 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/21 02:12:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:25 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:25 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:25 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/21 02:12:25 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:25 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:25 INFO : There was nothing to transfer sync_test.go:1964: No hash on uploaded file so skipping compare timestamp test 2024/04/21 02:12:26 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:26 DEBUG : two: Need to transfer - File not found at Destination 2024/04/21 02:12:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:26 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:26 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:26 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/21 02:12:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:26 INFO : two.rclone_chunk.001_9yb2rm: Moved (server-side) to: two 2024/04/21 02:12:26 INFO : two: Copied (new) 2024/04/21 02:12:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (8.86s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:28 INFO : pre-dest1/1.rclone_chunk.001_9yb4lj: Moved (server-side) to: pre-dest1/1 2024/04/21 02:12:29 INFO : pre-dest2/2.rclone_chunk.001_9yb479: Moved (server-side) to: pre-dest2/2 2024/04/21 02:12:29 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dest" 2024/04/21 02:12:29 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dest" 2024/04/21 02:12:29 DEBUG : Reset feature "ListR" 2024/04/21 02:12:29 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/pre-dest1" 2024/04/21 02:12:29 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/pre-dest1" 2024/04/21 02:12:29 DEBUG : Reset feature "ListR" 2024/04/21 02:12:29 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/pre-dest2" 2024/04/21 02:12:29 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/pre-dest2" 2024/04/21 02:12:29 DEBUG : Reset feature "ListR" 2024/04/21 02:12:29 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:12:29 DEBUG : 1: Destination found in --compare-dest, skipping 2024/04/21 02:12:30 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:12:30 DEBUG : 2: Destination found in --compare-dest, skipping 2024/04/21 02:12:30 DEBUG : 3: Need to transfer - File not found at Destination 2024/04/21 02:12:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dest': Waiting for checks to finish 2024/04/21 02:12:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dest': Waiting for transfers to finish 2024/04/21 02:12:30 DEBUG : 3: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:30 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dest]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:31 INFO : 3.rclone_chunk.001_9yb6hp: Moved (server-side) to: 3 2024/04/21 02:12:31 INFO : 3: Copied (new) 2024/04/21 02:12:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (4.25s) === RUN TestSyncCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:32 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:12:32 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:12:32 DEBUG : Reset feature "ListR" 2024/04/21 02:12:32 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/CopyDest" 2024/04/21 02:12:32 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/CopyDest" 2024/04/21 02:12:32 DEBUG : Reset feature "ListR" 2024/04/21 02:12:32 DEBUG : one: Need to transfer - File not found at Destination 2024/04/21 02:12:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:32 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:32 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dst]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:33 INFO : one.rclone_chunk.001_9yb8m7: Moved (server-side) to: one 2024/04/21 02:12:33 INFO : one: Copied (new) 2024/04/21 02:12:33 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:33 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:33 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:33 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:34 INFO : one: Deleted 2024/04/21 02:12:34 INFO : one.rclone_chunk.001_9yb9yj: Moved (server-side) to: one 2024/04/21 02:12:34 INFO : one: Copied (replaced existing) 2024/04/21 02:12:34 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:35 INFO : dst/one: Deleted 2024/04/21 02:12:35 INFO : dst/one.rclone_chunk.001_9ybacm: Moved (server-side) to: dst/one 2024/04/21 02:12:36 INFO : CopyDest/one.rclone_chunk.001_9ybb42: Moved (server-side) to: CopyDest/one 2024/04/21 02:12:36 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/BackupDir" 2024/04/21 02:12:36 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/BackupDir" 2024/04/21 02:12:36 DEBUG : Reset feature "ListR" 2024/04/21 02:12:36 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:36 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:36 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:36 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/21 02:12:36 DEBUG : one: move non-chunked object... 2024/04/21 02:12:36 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/BackupDir]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:37 INFO : one: Moved (server-side) 2024/04/21 02:12:37 INFO : one: Moved (server-side) 2024/04/21 02:12:37 DEBUG : one: copy non-chunked object... 2024/04/21 02:12:37 INFO : one: Copied (server-side copy) 2024/04/21 02:12:37 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/04/21 02:12:37 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:37 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:38 INFO : CopyDest/two.rclone_chunk.001_9ybd8d: Moved (server-side) to: CopyDest/two 2024/04/21 02:12:38 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:38 DEBUG : two: copy non-chunked object... 2024/04/21 02:12:39 INFO : two: Copied (server-side copy) 2024/04/21 02:12:39 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/04/21 02:12:39 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:39 DEBUG : one: Unchanged skipping 2024/04/21 02:12:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:39 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:39 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:39 DEBUG : two: Unchanged skipping 2024/04/21 02:12:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:39 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:39 DEBUG : one: Unchanged skipping 2024/04/21 02:12:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:39 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:39 INFO : There was nothing to transfer 2024/04/21 02:12:40 INFO : CopyDest/three.rclone_chunk.001_9ybfb7: Moved (server-side) to: CopyDest/three 2024/04/21 02:12:40 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/04/21 02:12:40 DEBUG : three: Destination not found in --copy-dest 2024/04/21 02:12:40 DEBUG : three: Need to transfer - File not found at Destination 2024/04/21 02:12:40 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:40 DEBUG : one: Unchanged skipping 2024/04/21 02:12:40 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/21 02:12:40 DEBUG : two: Unchanged skipping 2024/04/21 02:12:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:40 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:41 INFO : three.rclone_chunk.001_9ybgwf: Moved (server-side) to: three 2024/04/21 02:12:41 INFO : three: Copied (new) 2024/04/21 02:12:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (10.84s) === RUN TestSyncBackupDir run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:43 INFO : dst/one.rclone_chunk.001_9ybjl1: Moved (server-side) to: dst/one 2024/04/21 02:12:44 INFO : dst/two.rclone_chunk.001_9ybjpb: Moved (server-side) to: dst/two 2024/04/21 02:12:44 INFO : dst/three.txt.rclone_chunk.001_9ybk1h: Moved (server-side) to: dst/three.txt 2024/04/21 02:12:45 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:12:45 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:12:45 DEBUG : Reset feature "ListR" 2024/04/21 02:12:45 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/backup" 2024/04/21 02:12:45 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/backup" 2024/04/21 02:12:45 DEBUG : Reset feature "ListR" 2024/04/21 02:12:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/21 02:12:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:12:45 DEBUG : two: Unchanged skipping 2024/04/21 02:12:45 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:45 DEBUG : one: move non-chunked object... 2024/04/21 02:12:45 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/backup]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:45 INFO : one: Moved (server-side) 2024/04/21 02:12:45 INFO : one: Moved (server-side) 2024/04/21 02:12:45 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:45 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:45 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dst]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:46 INFO : one.rclone_chunk.001_9yblw5: Moved (server-side) to: one 2024/04/21 02:12:46 INFO : one: Copied (new) 2024/04/21 02:12:46 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:46 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:12:46 INFO : three.txt: Moved (server-side) 2024/04/21 02:12:46 INFO : three.txt: Moved (server-side) 2024/04/21 02:12:46 INFO : three.txt: Moved into backup dir 2024/04/21 02:12:47 INFO : dst/three.txt.rclone_chunk.001_9ybmhd: Moved (server-side) to: dst/three.txt 2024/04/21 02:12:47 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/21 02:12:47 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:12:47 DEBUG : two: Unchanged skipping 2024/04/21 02:12:47 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:48 INFO : one: Deleted 2024/04/21 02:12:48 DEBUG : one: move non-chunked object... 2024/04/21 02:12:48 INFO : one: Moved (server-side) 2024/04/21 02:12:48 INFO : one: Moved (server-side) 2024/04/21 02:12:48 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:48 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:49 INFO : one.rclone_chunk.001_9yboni: Moved (server-side) to: one 2024/04/21 02:12:49 INFO : one: Copied (new) 2024/04/21 02:12:49 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:49 INFO : three.txt: Deleted 2024/04/21 02:12:49 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:12:49 INFO : three.txt: Moved (server-side) 2024/04/21 02:12:49 INFO : three.txt: Moved (server-side) 2024/04/21 02:12:49 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (7.60s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:51 INFO : dst/one.rclone_chunk.001_9ybq41: Moved (server-side) to: dst/one 2024/04/21 02:12:52 INFO : dst/two.rclone_chunk.001_9ybr6s: Moved (server-side) to: dst/two 2024/04/21 02:12:52 INFO : dst/three.txt.rclone_chunk.001_9ybstd: Moved (server-side) to: dst/three.txt 2024/04/21 02:12:52 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:12:52 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:12:52 DEBUG : Reset feature "ListR" 2024/04/21 02:12:52 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/backup" 2024/04/21 02:12:52 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/backup" 2024/04/21 02:12:53 DEBUG : Reset feature "ListR" 2024/04/21 02:12:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/21 02:12:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:12:53 DEBUG : two: Unchanged skipping 2024/04/21 02:12:53 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:53 DEBUG : one: move non-chunked object... 2024/04/21 02:12:53 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/backup]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:53 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:12:53 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:12:53 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:53 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:53 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dst]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:12:54 INFO : one.rclone_chunk.001_9ybt9y: Moved (server-side) to: one 2024/04/21 02:12:54 INFO : one: Copied (new) 2024/04/21 02:12:54 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:54 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:12:54 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:12:54 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:12:54 INFO : three.txt: Moved into backup dir 2024/04/21 02:12:55 INFO : dst/three.txt.rclone_chunk.001_9ybucb: Moved (server-side) to: dst/three.txt 2024/04/21 02:12:55 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/21 02:12:55 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:12:55 DEBUG : two: Unchanged skipping 2024/04/21 02:12:55 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:12:55 INFO : one.bak: Deleted 2024/04/21 02:12:55 DEBUG : one: move non-chunked object... 2024/04/21 02:12:56 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:12:56 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:12:56 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:12:56 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:12:56 INFO : one.rclone_chunk.001_9ybwti: Moved (server-side) to: one 2024/04/21 02:12:56 INFO : one: Copied (new) 2024/04/21 02:12:56 DEBUG : Waiting for deletions to finish 2024/04/21 02:12:57 INFO : three.txt.bak: Deleted 2024/04/21 02:12:57 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:12:57 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:12:57 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:12:57 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (7.67s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:12:58 INFO : dst/one.rclone_chunk.001_9yby2r: Moved (server-side) to: dst/one 2024/04/21 02:12:59 INFO : dst/two.rclone_chunk.001_9ybz40: Moved (server-side) to: dst/two 2024/04/21 02:13:00 INFO : dst/three.txt.rclone_chunk.001_9ybzi1: Moved (server-side) to: dst/three.txt 2024/04/21 02:13:00 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:13:00 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:13:00 DEBUG : Reset feature "ListR" 2024/04/21 02:13:00 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/backup" 2024/04/21 02:13:00 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/backup" 2024/04/21 02:13:00 DEBUG : Reset feature "ListR" 2024/04/21 02:13:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/21 02:13:00 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:00 DEBUG : two: Unchanged skipping 2024/04/21 02:13:00 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:13:00 DEBUG : one: move non-chunked object... 2024/04/21 02:13:00 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/backup]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:13:00 INFO : one: Moved (server-side) to: one-2019-01-01 2024/04/21 02:13:00 INFO : one: Moved (server-side) to: one-2019-01-01 2024/04/21 02:13:00 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:13:01 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:01 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dst]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:13:01 INFO : one.rclone_chunk.001_9yc1yl: Moved (server-side) to: one 2024/04/21 02:13:01 INFO : one: Copied (new) 2024/04/21 02:13:01 DEBUG : Waiting for deletions to finish 2024/04/21 02:13:01 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:13:01 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/04/21 02:13:01 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/04/21 02:13:01 INFO : three.txt: Moved into backup dir 2024/04/21 02:13:02 INFO : dst/three.txt.rclone_chunk.001_9yc2xj: Moved (server-side) to: dst/three.txt 2024/04/21 02:13:03 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/21 02:13:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:03 DEBUG : two: Unchanged skipping 2024/04/21 02:13:03 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:13:03 INFO : one-2019-01-01: Deleted 2024/04/21 02:13:03 DEBUG : one: move non-chunked object... 2024/04/21 02:13:03 INFO : one: Moved (server-side) to: one-2019-01-01 2024/04/21 02:13:03 INFO : one: Moved (server-side) to: one-2019-01-01 2024/04/21 02:13:03 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:13:03 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:04 INFO : one.rclone_chunk.001_9yc3yn: Moved (server-side) to: one 2024/04/21 02:13:04 INFO : one: Copied (new) 2024/04/21 02:13:04 DEBUG : Waiting for deletions to finish 2024/04/21 02:13:04 INFO : three-2019-01-01.txt: Deleted 2024/04/21 02:13:04 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:13:04 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/04/21 02:13:04 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/04/21 02:13:04 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (7.50s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:13:06 INFO : dst/one.rclone_chunk.001_9yc514: Moved (server-side) to: dst/one 2024/04/21 02:13:07 INFO : dst/two.rclone_chunk.001_9yc6s8: Moved (server-side) to: dst/two 2024/04/21 02:13:07 INFO : dst/three.txt.rclone_chunk.001_9yc7qn: Moved (server-side) to: dst/three.txt 2024/04/21 02:13:08 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:13:08 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:13:08 DEBUG : Reset feature "ListR" 2024/04/21 02:13:08 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/21 02:13:08 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:08 DEBUG : two: Unchanged skipping 2024/04/21 02:13:08 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:13:08 DEBUG : one: move non-chunked object... 2024/04/21 02:13:08 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dst]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:13:08 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:13:08 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:13:08 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:13:08 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:09 INFO : one.rclone_chunk.001_9yc88e: Moved (server-side) to: one 2024/04/21 02:13:09 INFO : one: Copied (new) 2024/04/21 02:13:09 DEBUG : Waiting for deletions to finish 2024/04/21 02:13:09 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:13:09 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:13:09 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:13:09 INFO : three.txt: Moved into backup dir 2024/04/21 02:13:10 INFO : dst/three.txt.rclone_chunk.001_9yc9db: Moved (server-side) to: dst/three.txt 2024/04/21 02:13:10 DEBUG : one.bak: Excluded (Path Filter) 2024/04/21 02:13:10 DEBUG : one.bak: Excluded 2024/04/21 02:13:10 DEBUG : three.txt.bak: Excluded (Path Filter) 2024/04/21 02:13:10 DEBUG : three.txt.bak: Excluded 2024/04/21 02:13:10 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/21 02:13:10 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:10 DEBUG : two: Unchanged skipping 2024/04/21 02:13:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for checks to finish 2024/04/21 02:13:10 INFO : one.bak: Deleted 2024/04/21 02:13:10 DEBUG : one: move non-chunked object... 2024/04/21 02:13:10 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:13:10 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:13:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst': Waiting for transfers to finish 2024/04/21 02:13:11 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:11 INFO : one.rclone_chunk.001_9ycbf1: Moved (server-side) to: one 2024/04/21 02:13:11 INFO : one: Copied (new) 2024/04/21 02:13:11 DEBUG : Waiting for deletions to finish 2024/04/21 02:13:11 INFO : three.txt.bak: Deleted 2024/04/21 02:13:11 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:13:12 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:13:12 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:13:12 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (7.20s) === RUN TestSyncSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:13:13 INFO : dst/one.rclone_chunk.001_9ycdfv: Moved (server-side) to: dst/one 2024/04/21 02:13:14 INFO : dst/two.rclone_chunk.001_9ycd4c: Moved (server-side) to: dst/two 2024/04/21 02:13:15 INFO : dst/three.txt.rclone_chunk.001_9yceni: Moved (server-side) to: dst/three.txt 2024/04/21 02:13:15 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:13:15 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:13:15 DEBUG : Reset feature "ListR" 2024/04/21 02:13:15 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/21 02:13:15 DEBUG : one: move non-chunked object... 2024/04/21 02:13:15 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dst]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:13:15 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:13:15 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:13:15 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:16 INFO : one.rclone_chunk.001_9ycfdr: Moved (server-side) to: one 2024/04/21 02:13:16 INFO : one: Copied (new) 2024/04/21 02:13:16 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:16 DEBUG : two: Unchanged skipping 2024/04/21 02:13:16 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/04/21 02:13:16 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:13:17 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:13:17 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:13:17 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:17 INFO : three.txt.rclone_chunk.001_9ychcv: Moved (server-side) to: three.txt 2024/04/21 02:13:17 INFO : three.txt: Copied (new) 2024/04/21 02:13:18 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/21 02:13:18 INFO : one.bak: Deleted 2024/04/21 02:13:18 DEBUG : one: move non-chunked object... 2024/04/21 02:13:18 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:13:18 INFO : one: Moved (server-side) to: one.bak 2024/04/21 02:13:18 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:19 INFO : one.rclone_chunk.001_9yci7b: Moved (server-side) to: one 2024/04/21 02:13:19 INFO : one: Copied (new) 2024/04/21 02:13:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:19 DEBUG : two: Unchanged skipping 2024/04/21 02:13:19 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/04/21 02:13:19 INFO : three.txt.bak: Deleted 2024/04/21 02:13:19 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:13:20 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:13:20 INFO : three.txt: Moved (server-side) to: three.txt.bak 2024/04/21 02:13:20 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:20 INFO : three.txt.rclone_chunk.001_9yckz5: Moved (server-side) to: three.txt 2024/04/21 02:13:20 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (8.88s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:13:22 INFO : dst/one.rclone_chunk.001_9yclgd: Moved (server-side) to: dst/one 2024/04/21 02:13:23 INFO : dst/two.rclone_chunk.001_9ycm48: Moved (server-side) to: dst/two 2024/04/21 02:13:24 INFO : dst/three.txt.rclone_chunk.001_9ycnj6: Moved (server-side) to: dst/three.txt 2024/04/21 02:13:24 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:13:24 DEBUG : Creating backend with remote "TestMailru:rclone-test-lolitap9xagobow0yilicak1/dst" 2024/04/21 02:13:24 DEBUG : Reset feature "ListR" 2024/04/21 02:13:24 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/21 02:13:24 DEBUG : one: move non-chunked object... 2024/04/21 02:13:24 DEBUG : [rclone-test-lolitap9xagobow0yilicak1/dst]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/21 02:13:24 INFO : one: Moved (server-side) to: one-2019-01-01 2024/04/21 02:13:24 INFO : one: Moved (server-side) to: one-2019-01-01 2024/04/21 02:13:24 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:25 INFO : one.rclone_chunk.001_9ycoe2: Moved (server-side) to: one 2024/04/21 02:13:25 INFO : one: Copied (new) 2024/04/21 02:13:25 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:25 DEBUG : two: Unchanged skipping 2024/04/21 02:13:25 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/04/21 02:13:25 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:13:26 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/04/21 02:13:26 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/04/21 02:13:26 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:26 INFO : three.txt.rclone_chunk.001_9ycq9v: Moved (server-side) to: three.txt 2024/04/21 02:13:26 INFO : three.txt: Copied (new) 2024/04/21 02:13:27 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/21 02:13:27 INFO : one-2019-01-01: Deleted 2024/04/21 02:13:27 DEBUG : one: move non-chunked object... 2024/04/21 02:13:27 INFO : one: Moved (server-side) to: one-2019-01-01 2024/04/21 02:13:27 INFO : one: Moved (server-side) to: one-2019-01-01 2024/04/21 02:13:27 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:28 INFO : one.rclone_chunk.001_9ycr0z: Moved (server-side) to: one 2024/04/21 02:13:28 INFO : one: Copied (new) 2024/04/21 02:13:28 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:28 DEBUG : two: Unchanged skipping 2024/04/21 02:13:28 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/04/21 02:13:28 INFO : three-2019-01-01.txt: Deleted 2024/04/21 02:13:28 DEBUG : three.txt: move non-chunked object... 2024/04/21 02:13:29 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/04/21 02:13:29 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2024/04/21 02:13:29 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:29 INFO : three.txt.rclone_chunk.001_9yctos: Moved (server-side) to: three.txt 2024/04/21 02:13:29 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (8.66s) === RUN TestSyncUTFNorm run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:13:31 INFO : Testêé.rclone_chunk.001_9ycuii: Moved (server-side) to: Testêé 2024/04/21 02:13:31 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/04/21 02:13:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:13:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:13:31 INFO : Testêé: Deleted 2024/04/21 02:13:32 INFO : Testêé.rclone_chunk.001_9ycvu9: Moved (server-side) to: Testêé 2024/04/21 02:13:32 INFO : Testêé: Copied (replaced existing) to: Testêé 2024/04/21 02:13:32 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.94s) === RUN TestSyncImmutable run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:13:32 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/21 02:13:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:13:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:13:32 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:33 INFO : existing.rclone_chunk.001_9ycw56: Moved (server-side) to: existing 2024/04/21 02:13:33 INFO : existing: Copied (new) 2024/04/21 02:13:33 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:13:33 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2024/04/21 02:13:33 ERROR : existing: Source and destination exist but do not match: immutable file modified 2024/04/21 02:13:33 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:13:33 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:13:33 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': not deleting files as there were IO errors 2024/04/21 02:13:33 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncImmutable (1.72s) === RUN TestSyncIgnoreCase run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:2391: Skipping test as local or remote are case-insensitive --- SKIP: TestSyncIgnoreCase (0.15s) === RUN TestFixCase run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:13:35 INFO : EXISTING.rclone_chunk.001_9ycy8a: Moved (server-side) to: EXISTING 2024/04/21 02:13:35 INFO : EXISTINGBUTDIFFERENT.rclone_chunk.001_9yczzl: Moved (server-side) to: EXISTINGBUTDIFFERENT 2024/04/21 02:13:35 DEBUG : [rclone-test-lolitap9xagobow0yilicak1]: mkDirs by part "/rclone-test-lolitap9xagobow0yilicak1/SUBDIRA/subdirb/SUBDIRC" 2024/04/21 02:13:36 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO.rclone_chunk.001_9ycz89: Moved (server-side) to: SUBDIRA/subdirb/SUBDIRC/HELLO 2024/04/21 02:13:37 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences.rclone_chunk.001_9yd0ke: Moved (server-side) to: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences 2024/04/21 02:13:38 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:13:38 DEBUG : existing: Unchanged skipping 2024/04/21 02:13:38 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2024/04/21 02:13:38 DEBUG : EXISTING: moving to existing-rclone-move-pojevez4 2024/04/21 02:13:38 DEBUG : EXISTING: move non-chunked object... 2024/04/21 02:13:38 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-zubogib8 2024/04/21 02:13:38 DEBUG : EXISTINGBUTDIFFERENT: move non-chunked object... 2024/04/21 02:13:38 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-pojevez4 2024/04/21 02:13:38 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-pojevez4 2024/04/21 02:13:38 DEBUG : EXISTING: moving to existing 2024/04/21 02:13:38 DEBUG : existing-rclone-move-pojevez4: move non-chunked object... 2024/04/21 02:13:38 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-zubogib8 2024/04/21 02:13:38 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-zubogib8 2024/04/21 02:13:38 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2024/04/21 02:13:38 DEBUG : existingbutdifferent-rclone-move-zubogib8: move non-chunked object... 2024/04/21 02:13:39 INFO : SUBDIRA: Fixed case by renaming to: subdira 2024/04/21 02:13:39 INFO : existing-rclone-move-pojevez4: Moved (server-side) to: existing 2024/04/21 02:13:39 INFO : existing-rclone-move-pojevez4: Moved (server-side) to: existing 2024/04/21 02:13:39 INFO : EXISTING: Moved (server-side) to: existing 2024/04/21 02:13:39 INFO : EXISTING: Fixed case by renaming to: existing 2024/04/21 02:13:39 INFO : existingbutdifferent-rclone-move-zubogib8: Moved (server-side) to: existingbutdifferent 2024/04/21 02:13:39 INFO : existingbutdifferent-rclone-move-zubogib8: Moved (server-side) to: existingbutdifferent 2024/04/21 02:13:39 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2024/04/21 02:13:39 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2024/04/21 02:13:39 DEBUG : existingbutdifferent: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:39 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2024/04/21 02:13:39 INFO : existingbutdifferent: Deleted 2024/04/21 02:13:39 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2024/04/21 02:13:39 INFO : existingbutdifferent.rclone_chunk.001_9yd3nj: Moved (server-side) to: existingbutdifferent 2024/04/21 02:13:39 INFO : existingbutdifferent: Copied (replaced existing) 2024/04/21 02:13:39 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8) 2024/04/21 02:13:40 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-sofawen6 2024/04/21 02:13:40 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: move non-chunked object... 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-sofawen6 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-sofawen6 2024/04/21 02:13:40 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2024/04/21 02:13:40 DEBUG : subdira/subdirb/subdirc/hello-rclone-move-sofawen6: move non-chunked object... 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird 2024/04/21 02:13:40 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8) 2024/04/21 02:13:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:13:40 INFO : subdira/subdirb/subdirc/hello-rclone-move-sofawen6: Moved (server-side) to: subdira/subdirb/subdirc/hello 2024/04/21 02:13:40 INFO : subdira/subdirb/subdirc/hello-rclone-move-sofawen6: Moved (server-side) to: subdira/subdirb/subdirc/hello 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2024/04/21 02:13:40 DEBUG : subdira/subdirb/subdirc/hello: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:40 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-culotot9 2024/04/21 02:13:40 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: move non-chunked object... 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-culotot9 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-culotot9 2024/04/21 02:13:40 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2024/04/21 02:13:40 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-culotot9: move non-chunked object... 2024/04/21 02:13:40 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-culotot9: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2024/04/21 02:13:40 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-culotot9: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2024/04/21 02:13:40 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2024/04/21 02:13:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:13:40 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:13:41 INFO : subdira/subdirb/subdirc/hello: Deleted 2024/04/21 02:13:41 INFO : subdira/subdirb/subdirc/hello.rclone_chunk.001_9yd4iz: Moved (server-side) to: subdira/subdirb/subdirc/hello 2024/04/21 02:13:41 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2024/04/21 02:13:41 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Deleted 2024/04/21 02:13:41 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences.rclone_chunk.001_9yd4i8: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2024/04/21 02:13:41 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing) 2024/04/21 02:13:41 DEBUG : Waiting for deletions to finish --- PASS: TestFixCase (9.21s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:2459: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:2459: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:2459: This test only runs on local --- PASS: TestMaxTransfer (0.42s) --- SKIP: TestMaxTransfer/Hard (0.14s) --- SKIP: TestMaxTransfer/Soft (0.13s) --- SKIP: TestMaxTransfer/Cautious (0.15s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:13:44 INFO : both0.rclone_chunk.001_9yd8ru: Moved (server-side) to: both0 2024/04/21 02:13:45 INFO : only0.rclone_chunk.001_9yd8tz: Moved (server-side) to: only0 2024/04/21 02:13:46 INFO : both1.rclone_chunk.001_9yd92k: Moved (server-side) to: both1 2024/04/21 02:13:46 INFO : only1.rclone_chunk.001_9ydazu: Moved (server-side) to: only1 2024/04/21 02:13:47 INFO : both2.rclone_chunk.001_9yda2y: Moved (server-side) to: both2 2024/04/21 02:13:48 INFO : only2.rclone_chunk.001_9ydbaa: Moved (server-side) to: only2 2024/04/21 02:13:49 INFO : both3.rclone_chunk.001_9ydck7: Moved (server-side) to: both3 2024/04/21 02:13:50 INFO : only3.rclone_chunk.001_9ydd0r: Moved (server-side) to: only3 2024/04/21 02:13:51 INFO : both4.rclone_chunk.001_9ydesr: Moved (server-side) to: both4 2024/04/21 02:13:52 INFO : only4.rclone_chunk.001_9ydf36: Moved (server-side) to: only4 2024/04/21 02:13:52 INFO : both5.rclone_chunk.001_9ydgif: Moved (server-side) to: both5 2024/04/21 02:13:53 INFO : only5.rclone_chunk.001_9ydgna: Moved (server-side) to: only5 2024/04/21 02:13:54 INFO : both6.rclone_chunk.001_9ydhnp: Moved (server-side) to: both6 2024/04/21 02:13:54 INFO : only6.rclone_chunk.001_9ydi0q: Moved (server-side) to: only6 2024/04/21 02:13:55 INFO : both7.rclone_chunk.001_9ydidq: Moved (server-side) to: both7 2024/04/21 02:13:56 INFO : only7.rclone_chunk.001_9ydjif: Moved (server-side) to: only7 2024/04/21 02:13:56 INFO : both8.rclone_chunk.001_9ydkzh: Moved (server-side) to: both8 2024/04/21 02:13:57 INFO : only8.rclone_chunk.001_9ydkrz: Moved (server-side) to: only8 2024/04/21 02:13:58 INFO : both9.rclone_chunk.001_9ydl64: Moved (server-side) to: both9 2024/04/21 02:13:59 INFO : only9.rclone_chunk.001_9ydm91: Moved (server-side) to: only9 2024/04/21 02:13:59 INFO : both10.rclone_chunk.001_9ydn16: Moved (server-side) to: both10 2024/04/21 02:14:00 INFO : only10.rclone_chunk.001_9ydnte: Moved (server-side) to: only10 2024/04/21 02:14:01 INFO : both11.rclone_chunk.001_9ydojx: Moved (server-side) to: both11 2024/04/21 02:14:01 INFO : only11.rclone_chunk.001_9ydphu: Moved (server-side) to: only11 2024/04/21 02:14:02 INFO : both12.rclone_chunk.001_9ydqze: Moved (server-side) to: both12 2024/04/21 02:14:03 INFO : only12.rclone_chunk.001_9ydqx1: Moved (server-side) to: only12 2024/04/21 02:14:04 INFO : both13.rclone_chunk.001_9ydrsl: Moved (server-side) to: both13 2024/04/21 02:14:04 INFO : only13.rclone_chunk.001_9ydsjq: Moved (server-side) to: only13 2024/04/21 02:14:05 INFO : both14.rclone_chunk.001_9yds87: Moved (server-side) to: both14 2024/04/21 02:14:06 INFO : only14.rclone_chunk.001_9ydt88: Moved (server-side) to: only14 2024/04/21 02:14:06 INFO : both15.rclone_chunk.001_9ydu5d: Moved (server-side) to: both15 2024/04/21 02:14:07 INFO : only15.rclone_chunk.001_9ydubj: Moved (server-side) to: only15 2024/04/21 02:14:08 INFO : both16.rclone_chunk.001_9ydvmo: Moved (server-side) to: both16 2024/04/21 02:14:09 INFO : only16.rclone_chunk.001_9ydw8b: Moved (server-side) to: only16 2024/04/21 02:14:09 INFO : both17.rclone_chunk.001_9ydxhw: Moved (server-side) to: both17 2024/04/21 02:14:10 INFO : only17.rclone_chunk.001_9ydx0k: Moved (server-side) to: only17 2024/04/21 02:14:11 INFO : both18.rclone_chunk.001_9ydyuy: Moved (server-side) to: both18 2024/04/21 02:14:11 INFO : only18.rclone_chunk.001_9ydzs6: Moved (server-side) to: only18 2024/04/21 02:14:12 INFO : both19.rclone_chunk.001_9ydz9y: Moved (server-side) to: both19 2024/04/21 02:14:13 INFO : only19.rclone_chunk.001_9ye05u: Moved (server-side) to: only19 2024/04/21 02:14:13 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:14:13 DEBUG : both0: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both12: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both13: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both14: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both1: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both10: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both15: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both11: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both18: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both16: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both2: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both3: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both5: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both6: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both4: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both7: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:13 DEBUG : both9: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both8: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both19: Unchanged skipping 2024/04/21 02:14:13 DEBUG : both17: Unchanged skipping 2024/04/21 02:14:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:14:13 DEBUG : Waiting for deletions to finish 2024/04/21 02:14:13 INFO : only13: Deleted 2024/04/21 02:14:13 INFO : only10: Deleted 2024/04/21 02:14:13 INFO : only0: Deleted 2024/04/21 02:14:13 INFO : only3: Deleted 2024/04/21 02:14:13 INFO : only12: Deleted 2024/04/21 02:14:13 INFO : only14: Deleted 2024/04/21 02:14:13 INFO : only18: Deleted 2024/04/21 02:14:13 INFO : only6: Deleted 2024/04/21 02:14:13 INFO : only1: Deleted 2024/04/21 02:14:13 INFO : only15: Deleted 2024/04/21 02:14:13 INFO : only4: Deleted 2024/04/21 02:14:13 INFO : only19: Deleted 2024/04/21 02:14:13 INFO : only5: Deleted 2024/04/21 02:14:13 INFO : only7: Deleted 2024/04/21 02:14:13 INFO : only8: Deleted 2024/04/21 02:14:13 INFO : only9: Deleted 2024/04/21 02:14:13 INFO : only11: Deleted 2024/04/21 02:14:13 INFO : only17: Deleted 2024/04/21 02:14:13 INFO : only2: Deleted 2024/04/21 02:14:13 INFO : only16: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" --- PASS: TestSyncConcurrentDelete (31.72s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 2024/04/21 02:14:16 INFO : both0.rclone_chunk.001_9ye3ha: Moved (server-side) to: both0 2024/04/21 02:14:17 INFO : only0.rclone_chunk.001_9ye4e6: Moved (server-side) to: only0 2024/04/21 02:14:17 INFO : both1.rclone_chunk.001_9ye5yr: Moved (server-side) to: both1 2024/04/21 02:14:18 INFO : only1.rclone_chunk.001_9ye58g: Moved (server-side) to: only1 2024/04/21 02:14:19 INFO : both2.rclone_chunk.001_9ye6qm: Moved (server-side) to: both2 2024/04/21 02:14:19 INFO : only2.rclone_chunk.001_9ye7nx: Moved (server-side) to: only2 2024/04/21 02:14:20 INFO : both3.rclone_chunk.001_9ye7og: Moved (server-side) to: both3 2024/04/21 02:14:21 INFO : only3.rclone_chunk.001_9ye874: Moved (server-side) to: only3 2024/04/21 02:14:22 INFO : both4.rclone_chunk.001_9ye92t: Moved (server-side) to: both4 2024/04/21 02:14:22 INFO : only4.rclone_chunk.001_9yeatp: Moved (server-side) to: only4 2024/04/21 02:14:23 INFO : both5.rclone_chunk.001_9yeac6: Moved (server-side) to: both5 2024/04/21 02:14:24 INFO : only5.rclone_chunk.001_9yeb9n: Moved (server-side) to: only5 2024/04/21 02:14:24 INFO : both6.rclone_chunk.001_9yecya: Moved (server-side) to: both6 2024/04/21 02:14:25 INFO : only6.rclone_chunk.001_9yechg: Moved (server-side) to: only6 2024/04/21 02:14:26 INFO : both7.rclone_chunk.001_9yed3n: Moved (server-side) to: both7 2024/04/21 02:14:26 INFO : only7.rclone_chunk.001_9yeehe: Moved (server-side) to: only7 2024/04/21 02:14:27 INFO : both8.rclone_chunk.001_9yef8c: Moved (server-side) to: both8 2024/04/21 02:14:28 INFO : only8.rclone_chunk.001_9yef2l: Moved (server-side) to: only8 2024/04/21 02:14:28 INFO : both9.rclone_chunk.001_9yegzt: Moved (server-side) to: both9 2024/04/21 02:14:29 INFO : only9.rclone_chunk.001_9yehac: Moved (server-side) to: only9 2024/04/21 02:14:30 INFO : both10.rclone_chunk.001_9yehcc: Moved (server-side) to: both10 2024/04/21 02:14:31 INFO : only10.rclone_chunk.001_9yeim7: Moved (server-side) to: only10 2024/04/21 02:14:31 INFO : both11.rclone_chunk.001_9yejte: Moved (server-side) to: both11 2024/04/21 02:14:32 INFO : only11.rclone_chunk.001_9yej9g: Moved (server-side) to: only11 2024/04/21 02:14:33 INFO : both12.rclone_chunk.001_9yekyn: Moved (server-side) to: both12 2024/04/21 02:14:33 INFO : only12.rclone_chunk.001_9yelou: Moved (server-side) to: only12 2024/04/21 02:14:34 INFO : both13.rclone_chunk.001_9yel3x: Moved (server-side) to: both13 2024/04/21 02:14:35 INFO : only13.rclone_chunk.001_9yemsa: Moved (server-side) to: only13 2024/04/21 02:14:35 INFO : both14.rclone_chunk.001_9yen6o: Moved (server-side) to: both14 2024/04/21 02:14:36 INFO : only14.rclone_chunk.001_9yenox: Moved (server-side) to: only14 2024/04/21 02:14:37 INFO : both15.rclone_chunk.001_9yeocy: Moved (server-side) to: both15 2024/04/21 02:14:37 INFO : only15.rclone_chunk.001_9yep7d: Moved (server-side) to: only15 2024/04/21 02:14:38 INFO : both16.rclone_chunk.001_9yepyw: Moved (server-side) to: both16 2024/04/21 02:14:39 INFO : only16.rclone_chunk.001_9yeq18: Moved (server-side) to: only16 2024/04/21 02:14:39 INFO : both17.rclone_chunk.001_9yerw8: Moved (server-side) to: both17 2024/04/21 02:14:40 INFO : only17.rclone_chunk.001_9yerk7: Moved (server-side) to: only17 2024/04/21 02:14:41 INFO : both18.rclone_chunk.001_9yeskj: Moved (server-side) to: both18 2024/04/21 02:14:41 INFO : only18.rclone_chunk.001_9yeto9: Moved (server-side) to: only18 2024/04/21 02:14:42 INFO : both19.rclone_chunk.001_9yetbc: Moved (server-side) to: both19 2024/04/21 02:14:43 INFO : only19.rclone_chunk.001_9yeuu9: Moved (server-side) to: only19 2024/04/21 02:14:43 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both0: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both1: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both10: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both11: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both12: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both13: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both14: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both15: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both16: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both17: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both18: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both19: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both2: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both3: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both4: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both5: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both6: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both7: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both8: Unchanged skipping 2024/04/21 02:14:43 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/21 02:14:43 DEBUG : both9: Unchanged skipping 2024/04/21 02:14:43 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2024/04/21 02:14:43 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for checks to finish 2024/04/21 02:14:43 DEBUG : only0: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:43 DEBUG : only1: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:43 DEBUG : only10: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:43 DEBUG : only11: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:43 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': Waiting for transfers to finish 2024/04/21 02:14:44 INFO : only0: Deleted 2024/04/21 02:14:44 INFO : only1: Deleted 2024/04/21 02:14:44 INFO : only11: Deleted 2024/04/21 02:14:44 INFO : only10: Deleted 2024/04/21 02:14:44 INFO : only0.rclone_chunk.001_9yev1s: Moved (server-side) to: only0 2024/04/21 02:14:44 INFO : only0: Copied (replaced existing) 2024/04/21 02:14:44 DEBUG : only12: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:44 INFO : only1.rclone_chunk.001_9yevuz: Moved (server-side) to: only1 2024/04/21 02:14:44 INFO : only1: Copied (replaced existing) 2024/04/21 02:14:44 DEBUG : only13: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:44 INFO : only11.rclone_chunk.001_9yevkx: Moved (server-side) to: only11 2024/04/21 02:14:44 INFO : only11: Copied (replaced existing) 2024/04/21 02:14:44 DEBUG : only14: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:44 INFO : only10.rclone_chunk.001_9yevby: Moved (server-side) to: only10 2024/04/21 02:14:44 INFO : only10: Copied (replaced existing) 2024/04/21 02:14:44 DEBUG : only15: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:44 INFO : only13: Deleted 2024/04/21 02:14:45 INFO : only12: Deleted 2024/04/21 02:14:45 INFO : only15: Deleted 2024/04/21 02:14:45 INFO : only14: Deleted 2024/04/21 02:14:45 INFO : only13.rclone_chunk.001_9yew8a: Moved (server-side) to: only13 2024/04/21 02:14:45 INFO : only13: Copied (replaced existing) 2024/04/21 02:14:45 DEBUG : only16: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:45 INFO : only12.rclone_chunk.001_9yew8k: Moved (server-side) to: only12 2024/04/21 02:14:45 INFO : only12: Copied (replaced existing) 2024/04/21 02:14:45 DEBUG : only17: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:45 INFO : only15.rclone_chunk.001_9yewsb: Moved (server-side) to: only15 2024/04/21 02:14:45 INFO : only15: Copied (replaced existing) 2024/04/21 02:14:45 DEBUG : only18: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:45 INFO : only14.rclone_chunk.001_9yew98: Moved (server-side) to: only14 2024/04/21 02:14:45 INFO : only14: Copied (replaced existing) 2024/04/21 02:14:45 DEBUG : only19: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:45 ERROR : only17.rclone_chunk.001_9yexwa: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only18.rclone_chunk.001_9yexl5: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only19.rclone_chunk.001_9yexfl: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only16.rclone_chunk.001_9yexgb: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only17.rclone_chunk.001_9yexwa: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only18.rclone_chunk.001_9yexl5: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only19.rclone_chunk.001_9yexfl: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only16.rclone_chunk.001_9yexgb: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only17.rclone_chunk.001_9yexwa: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only17: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 DEBUG : only2: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:45 ERROR : only18.rclone_chunk.001_9yexl5: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only18: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 DEBUG : only3: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:45 ERROR : only19.rclone_chunk.001_9yexfl: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only19: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 DEBUG : only4: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:45 ERROR : only16.rclone_chunk.001_9yexgb: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 ERROR : only16: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:45 DEBUG : only5: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:46 ERROR : only2.rclone_chunk.001_9yexxx: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only3.rclone_chunk.001_9yexk7: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only4.rclone_chunk.001_9yexia: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only5.rclone_chunk.001_9yexqd: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only2.rclone_chunk.001_9yexxx: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only4.rclone_chunk.001_9yexia: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only3.rclone_chunk.001_9yexk7: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only5.rclone_chunk.001_9yexqd: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only2.rclone_chunk.001_9yexxx: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only2: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 DEBUG : only6: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:46 ERROR : only4.rclone_chunk.001_9yexia: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only4: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 DEBUG : only7: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:46 ERROR : only3.rclone_chunk.001_9yexk7: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only3: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 DEBUG : only8: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:46 ERROR : only5.rclone_chunk.001_9yexqd: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only5: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 DEBUG : only9: skip slow MD5 on source file, hashing in-transit 2024/04/21 02:14:46 ERROR : only6.rclone_chunk.001_9yeyje: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only7.rclone_chunk.001_9yeyen: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only8.rclone_chunk.001_9yey6i: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only9.rclone_chunk.001_9yey4o: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only7.rclone_chunk.001_9yeyen: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only6.rclone_chunk.001_9yeyje: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:46 ERROR : only8.rclone_chunk.001_9yey6i: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only9.rclone_chunk.001_9yey4o: Couldn't move: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only7.rclone_chunk.001_9yeyen: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only7: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only6.rclone_chunk.001_9yeyje: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only6: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only8.rclone_chunk.001_9yey6i: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only8: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only9.rclone_chunk.001_9yey4o: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : only9: Failed to copy: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': not deleting files as there were IO errors 2024/04/21 02:14:47 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" sync_test.go:2529: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2529 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2539 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncConcurrentTruncate Messages: Sync must not return a error sync_test.go:2530: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2530 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2539 Error: Should be false Test: TestSyncConcurrentTruncate Messages: Low level errors must not have happened 2024/04/21 02:14:47 ERROR : : error listing: invalid character '<' looking for beginning of value fstest.go:276: Error listing: invalid character '<' looking for beginning of value 2024/04/21 02:14:47 ERROR : : error listing: invalid character '<' looking for beginning of value run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1175 /usr/local/go/src/testing/testing.go:1353 /usr/local/go/src/testing/testing.go:1683 /usr/local/go/src/runtime/panic.go:626 /usr/local/go/src/testing/testing.go:1005 /usr/local/go/src/testing/testing.go:1089 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:276 /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:2531 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2539 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncConcurrentTruncate 2024/04/21 02:14:47 ERROR : : error listing: invalid character '<' looking for beginning of value run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1175 /usr/local/go/src/testing/testing.go:1353 /usr/local/go/src/testing/testing.go:1337 /usr/local/go/src/runtime/panic.go:626 /usr/local/go/src/testing/testing.go:1005 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1175 /usr/local/go/src/testing/testing.go:1353 /usr/local/go/src/testing/testing.go:1683 /usr/local/go/src/runtime/panic.go:626 /usr/local/go/src/testing/testing.go:1005 /usr/local/go/src/testing/testing.go:1089 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:276 /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:2531 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2539 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncConcurrentTruncate --- FAIL: TestSyncConcurrentTruncate (31.57s) === RUN TestNothingToTransfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-lolitap9xagobow0yilicak1'", Local "Local file system at /tmp/rclone1400021508", Modify Window "1s" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: listing wrong, want sub dir/hello world (11), sub dir2/very/very/very/very/very/nested/subdir/hello world (11) 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 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0), sub dir/hello world (11), sub dir2/very/very/very/very/very/nested/subdir/hello world (11) 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both0" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both1" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both10" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both11" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both12" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both13" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both14" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both15" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both16" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both17" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both18" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both19" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both2" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both3" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both4" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both5" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both6" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both7" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both8" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "both9" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only0" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only1" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only10" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only11" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only12" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only13" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only14" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only15" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only16" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only17" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only18" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only19" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only2" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only3" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only4" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only5" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only6" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only7" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only8" 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:334 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2550 Error: Should be true Test: TestNothingToTransfer Messages: Unexpected file "only9" 2024/04/21 02:14:54 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/21 02:14:54 DEBUG : sub dir: Making directory 2024/04/21 02:14:54 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC sync_test.go:2570: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2570 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:25 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2568 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestNothingToTransfer run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /usr/local/go/src/testing/testing.go:1175 /usr/local/go/src/testing/testing.go:1353 /usr/local/go/src/testing/testing.go:1683 /usr/local/go/src/runtime/panic.go:626 /usr/local/go/src/testing/testing.go:1005 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2570 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:25 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2568 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestNothingToTransfer --- FAIL: TestNothingToTransfer (7.19s) FAIL "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMailru: -verbose -fast-list -size-limit 10240" - Finished ERROR in 4m58.635976884s (try 1/5): exit status 1: Failed [TestSyncConcurrentTruncate TestNothingToTransfer]