"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMailru: -verbose -fast-list -size-limit 10240" - Starting (try 1/5) 2024/04/18 02:02:57 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5" 2024/04/18 02:02:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/18 02:02:57 DEBUG : Creating backend with remote "TestMailru:rclone-test-baqudoh9wuhevuy9dibufac5" 2024/04/18 02:02:57 DEBUG : Reset feature "ListR" 2024/04/18 02:02:57 DEBUG : Creating backend with remote "/tmp/rclone1274103239" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.04s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:02:57 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/18 02:02:57 NOTICE: sub dir: Skipped make directory as --dry-run is set 2024/04/18 02:02:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:02:57 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:02:57 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2024/04/18 02:02:57 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyWithDryRun (0.70s) === RUN TestCopy run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:02:57 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/18 02:02:58 DEBUG : sub dir: Making directory 2024/04/18 02:02:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:02:58 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:02:58 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:02:58 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:02:58 INFO : sub dir/hello world.rclone_chunk.001_4dva25: Moved (server-side) to: sub dir/hello world 2024/04/18 02:02:58 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:02:58 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.62s) === RUN TestCopyMetadata run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.16s) === RUN TestCopyMissingDirectory run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:02:59 DEBUG : Creating backend with remote "/non-existing" 2024/04/18 02:02:59 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2024/04/18 02:02:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:02:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyMissingDirectory (0.41s) === RUN TestCopyNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:00 DEBUG : sub dir: Making directory 2024/04/18 02:03:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:03:00 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:00 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:00 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:00 INFO : sub dir/hello world.rclone_chunk.001_4dvcy8: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:00 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyNoTraverse (1.62s) === RUN TestCopyCheckFirst run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:01 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Running all checks before starting transfers 2024/04/18 02:03:01 DEBUG : sub dir: Making directory 2024/04/18 02:03:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:03:01 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:01 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Checks finished, now starting transfers 2024/04/18 02:03:01 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:01 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:02 INFO : sub dir/hello world.rclone_chunk.001_4dvdw4: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:02 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyCheckFirst (1.67s) === RUN TestSyncNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:03 ERROR : Ignoring --no-traverse with sync 2024/04/18 02:03:03 DEBUG : sub dir: Making directory 2024/04/18 02:03:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:03:03 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:03 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:03 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:04 INFO : sub dir/hello world.rclone_chunk.001_4dvfzx: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:04 INFO : sub dir/hello world: Copied (new) 2024/04/18 02:03:04 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncNoTraverse (1.63s) === RUN TestCopyWithDepth run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:05 DEBUG : hello world2: Need to transfer - File not found at Destination 2024/04/18 02:03:05 DEBUG : sub dir: Making directory 2024/04/18 02:03:05 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:05 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:05 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:05 INFO : hello world2.rclone_chunk.001_4dvhef: Moved (server-side) to: hello world2 2024/04/18 02:03:05 INFO : hello world2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyWithDepth (1.61s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:06 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 02:03:06 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:06 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:06 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:07 INFO : potato2.rclone_chunk.001_4dviii: Moved (server-side) to: potato2 2024/04/18 02:03:07 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyWithFilesFrom (1.16s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:07 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 02:03:07 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:07 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:07 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:08 INFO : potato2.rclone_chunk.001_4dvj6r: Moved (server-side) to: potato2 2024/04/18 02:03:08 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyWithFilesFromAndNoTraverse (1.28s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:09 DEBUG : sub dir2: Making directory with metadata 2024/04/18 02:03:09 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/18 02:03:09 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/18 02:03:09 DEBUG : sub dir: Making directory 2024/04/18 02:03:09 DEBUG : sub dir2: Making directory 2024/04/18 02:03:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:03:09 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:09 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:09 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:09 INFO : sub dir/hello world.rclone_chunk.001_4dvlss: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:09 INFO : sub dir/hello world: Copied (new) 2024/04/18 02:03:09 DEBUG : sub dir2: Making directory 2024/04/18 02:03:09 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': copied 1 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:10 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.88s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:10 DEBUG : sub dir2: Making directory with metadata 2024/04/18 02:03:10 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/18 02:03:11 DEBUG : sub dir: Making directory 2024/04/18 02:03:11 DEBUG : sub dir2: Making directory 2024/04/18 02:03:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:03:11 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:11 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:11 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:11 INFO : sub dir/hello world.rclone_chunk.001_4dvnjx: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:11 INFO : sub dir/hello world: Copied (new) 2024/04/18 02:03:11 INFO : sub dir/hello world: Deleted 2024/04/18 02:03:11 DEBUG : sub dir: Making directory 2024/04/18 02:03:11 DEBUG : sub dir2: Making directory 2024/04/18 02:03:11 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': copied 2 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:12 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2024/04/18 02:03:12 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (2.05s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:351: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.17s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:13 DEBUG : sub dir2: Making directory with metadata 2024/04/18 02:03:13 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/18 02:03:13 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/18 02:03:13 DEBUG : sub dir: Making directory 2024/04/18 02:03:13 DEBUG : sub dir2: Making directory 2024/04/18 02:03:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:03:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:13 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:14 INFO : sub dir/hello world.rclone_chunk.001_4dvpnm: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:14 INFO : sub dir/hello world: Copied (new) 2024/04/18 02:03:14 DEBUG : sub dir2: Making directory 2024/04/18 02:03:14 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': copied 1 directories 2024/04/18 02:03:14 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:14 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (2.06s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:427: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.20s) === RUN TestServerSideCopy run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:16 INFO : sub dir/hello world.rclone_chunk.001_4dvr5t: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:16 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-bananab7takifec2moxeyuq7" 2024/04/18 02:03:16 DEBUG : Creating backend with remote "TestMailru:rclone-test-bananab7takifec2moxeyuq7" 2024/04/18 02:03:16 DEBUG : Reset feature "ListR" sync_test.go:487: Server side copy (if possible) Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5' -> Chunked 'TestChunkerChunk50bMailru:rclone-test-bananab7takifec2moxeyuq7' 2024/04/18 02:03:16 DEBUG : sub dir: Making directory 2024/04/18 02:03:16 DEBUG : [rclone-test-bananab7takifec2moxeyuq7]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/18 02:03:16 DEBUG : [rclone-test-bananab7takifec2moxeyuq7]: mkDirs by part "/rclone-test-bananab7takifec2moxeyuq7/sub dir" 2024/04/18 02:03:16 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:03:16 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-bananab7takifec2moxeyuq7': Waiting for checks to finish 2024/04/18 02:03:16 DEBUG : sub dir/hello world: copy non-chunked object... 2024/04/18 02:03:16 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-bananab7takifec2moxeyuq7': Waiting for transfers to finish 2024/04/18 02:03:16 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:17 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-bananab7takifec2moxeyuq7': Purge remote --- PASS: TestServerSideCopy (2.50s) === RUN TestCopyAfterDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:18 INFO : sub dir/hello world.rclone_chunk.001_4dvukl: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:18 ERROR : : error listing: directory not found 2024/04/18 02:03:18 DEBUG : Local file system at /tmp/rclone1274103239: Making directory 2024/04/18 02:03:19 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:19 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyAfterDelete (1.99s) === RUN TestCopyRedownload run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:20 INFO : sub dir/hello world.rclone_chunk.001_4dvw4u: Moved (server-side) to: sub dir/hello world 2024/04/18 02:03:20 DEBUG : sub dir: Making directory with metadata 2024/04/18 02:03:20 INFO : sub dir: Made directory with metadata (mtime=1970-01-01T00:00:00Z) 2024/04/18 02:03:20 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2024/04/18 02:03:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 02:03:20 DEBUG : Local file system at /tmp/rclone1274103239: Waiting for checks to finish 2024/04/18 02:03:20 DEBUG : Local file system at /tmp/rclone1274103239: Waiting for transfers to finish 2024/04/18 02:03:20 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Switch file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/, expiry "2024-04-18T02:06:20.924310388Z" 2024/04/18 02:03:21 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:03:21 DEBUG : sub dir/hello world.zusamof0.partial: renamed to: sub dir/hello world 2024/04/18 02:03:21 INFO : sub dir/hello world: Copied (new) 2024/04/18 02:03:21 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyRedownload (1.88s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:21 DEBUG : check sum: Need to transfer - File not found at Destination 2024/04/18 02:03:21 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:21 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:21 DEBUG : check sum: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:22 INFO : check sum.rclone_chunk.001_4dvxtl: Moved (server-side) to: check sum 2024/04/18 02:03:22 INFO : check sum: Copied (new) 2024/04/18 02:03:22 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:22 NOTICE: Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2024/04/18 02:03:22 DEBUG : check sum: Size of src and dst objects identical 2024/04/18 02:03:22 DEBUG : check sum: Unchanged skipping 2024/04/18 02:03:22 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:22 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:22 DEBUG : Waiting for deletions to finish 2024/04/18 02:03:22 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncBasedOnCheckSum (1.75s) === RUN TestSyncSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:23 DEBUG : sizeonly: Need to transfer - File not found at Destination 2024/04/18 02:03:23 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:23 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:23 DEBUG : sizeonly: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:24 INFO : sizeonly.rclone_chunk.001_4dvz00: Moved (server-side) to: sizeonly 2024/04/18 02:03:24 INFO : sizeonly: Copied (new) 2024/04/18 02:03:24 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:24 DEBUG : sizeonly: Sizes identical 2024/04/18 02:03:24 DEBUG : sizeonly: Unchanged skipping 2024/04/18 02:03:24 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:24 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:24 DEBUG : Waiting for deletions to finish 2024/04/18 02:03:24 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncSizeOnly (1.63s) === RUN TestSyncIgnoreSize run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:25 DEBUG : ignore-size: Need to transfer - File not found at Destination 2024/04/18 02:03:25 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:25 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:25 DEBUG : ignore-size: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:25 INFO : ignore-size.rclone_chunk.001_4dw11a: Moved (server-side) to: ignore-size 2024/04/18 02:03:25 INFO : ignore-size: Copied (new) 2024/04/18 02:03:25 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:26 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/18 02:03:26 DEBUG : ignore-size: Unchanged skipping 2024/04/18 02:03:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:26 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:26 DEBUG : Waiting for deletions to finish 2024/04/18 02:03:26 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncIgnoreSize (1.64s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:27 INFO : existing.rclone_chunk.001_4dw2l1: Moved (server-side) to: existing 2024/04/18 02:03:27 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2024/04/18 02:03:27 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:27 DEBUG : existing: Unchanged skipping 2024/04/18 02:03:27 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:27 DEBUG : Waiting for deletions to finish 2024/04/18 02:03:27 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:27 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/04/18 02:03:27 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:27 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:27 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:28 INFO : existing: Deleted 2024/04/18 02:03:28 INFO : existing.rclone_chunk.001_4dw36s: Moved (server-side) to: existing 2024/04/18 02:03:28 INFO : existing: Copied (replaced existing) 2024/04/18 02:03:28 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncIgnoreTimes (2.49s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:29 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/18 02:03:29 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:29 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:29 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:30 INFO : existing.rclone_chunk.001_4dw5sw: Moved (server-side) to: existing 2024/04/18 02:03:30 INFO : existing: Copied (new) 2024/04/18 02:03:30 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:30 DEBUG : existing: Destination exists, skipping 2024/04/18 02:03:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:30 DEBUG : Waiting for deletions to finish 2024/04/18 02:03:30 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncIgnoreExisting (1.58s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:31 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: new upload shard: https://uploader.cloud.mail.ru/upload/ 2024/04/18 02:03:31 INFO : b/potato.rclone_chunk.001_4dw7nh: Moved (server-side) to: b/potato 2024/04/18 02:03:32 INFO : c/non empty space.rclone_chunk.001_4dw7ow: Moved (server-side) to: c/non empty space 2024/04/18 02:03:32 DEBUG : d: Making directory 2024/04/18 02:03:32 DEBUG : a: Making directory 2024/04/18 02:03:32 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/18 02:03:32 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:03:32 DEBUG : c/non empty space: Unchanged skipping 2024/04/18 02:03:32 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:32 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:33 INFO : a/potato2.rclone_chunk.001_4dw8h0: Moved (server-side) to: a/potato2.rclone_chunk.001 2024/04/18 02:03:33 INFO : a/potato2.rclone_chunk.002_4dw8h0: Moved (server-side) to: a/potato2.rclone_chunk.002 2024/04/18 02:03:34 INFO : a/potato2: Copied (new) 2024/04/18 02:03:34 DEBUG : Waiting for deletions to finish 2024/04/18 02:03:34 INFO : b/potato: Deleted 2024/04/18 02:03:34 INFO : d: Removing directory 2024/04/18 02:03:34 INFO : b: Removing directory 2024/04/18 02:03:34 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': deleted 2 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:34 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:03:34 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ --- PASS: TestSyncIgnoreErrors (4.74s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:36 INFO : empty space.rclone_chunk.001_4dwb96: Moved (server-side) to: empty space 2024/04/18 02:03: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/18 02:03:36 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:36 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:36 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2024/04/18 02:03:36 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03: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/18 02:03:36 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:36 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:36 DEBUG : empty space: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:37 INFO : empty space: Deleted 2024/04/18 02:03:37 INFO : empty space.rclone_chunk.001_4dwcge: Moved (server-side) to: empty space 2024/04/18 02:03:37 INFO : empty space: Copied (replaced existing) 2024/04/18 02:03:37 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncAfterChangingModtimeOnly (2.77s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", 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-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:39 INFO : foo.rclone_chunk.001_4dwesj: Moved (server-side) to: foo 2024/04/18 02:03:39 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/18 02:03:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:39 DEBUG : foo: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:40 INFO : foo: Deleted 2024/04/18 02:03:40 INFO : foo.rclone_chunk.001_4dwfbq: Moved (server-side) to: foo 2024/04/18 02:03:40 INFO : foo: Copied (replaced existing) 2024/04/18 02:03:40 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncDoesntUpdateModtime (2.13s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:41 INFO : empty space.rclone_chunk.001_4dwgo5: Moved (server-side) to: empty space 2024/04/18 02:03:41 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/18 02:03:41 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:03:41 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:41 DEBUG : empty space: Unchanged skipping 2024/04/18 02:03:41 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:41 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:42 INFO : potato.rclone_chunk.001_4dwh6t: Moved (server-side) to: potato.rclone_chunk.001 2024/04/18 02:03:42 INFO : potato.rclone_chunk.002_4dwh6t: Moved (server-side) to: potato.rclone_chunk.002 2024/04/18 02:03:42 INFO : potato: Copied (new) 2024/04/18 02:03:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:43 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:03:43 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ --- PASS: TestSyncAfterAddingAFile (2.96s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:44 INFO : potato.rclone_chunk.001_4dwjgd: Moved (server-side) to: potato.rclone_chunk.001 2024/04/18 02:03:44 INFO : potato.rclone_chunk.002_4dwjgd: Moved (server-side) to: potato.rclone_chunk.002 2024/04/18 02:03:45 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/04/18 02:03:45 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:45 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:45 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:03:45 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:03:45 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:45 DEBUG : potato.rclone_chunk.001_4dwlfz: File has been put by hash from source 2024/04/18 02:03:46 INFO : potato: Deleted 2024/04/18 02:03:46 INFO : potato.rclone_chunk.001_4dwlfz: Moved (server-side) to: potato 2024/04/18 02:03:46 INFO : potato: Copied (replaced existing) 2024/04/18 02:03:46 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncAfterChangingFilesSizeOnly (3.29s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:47 INFO : potato.rclone_chunk.001_4dwnsg: Moved (server-side) to: potato 2024/04/18 02:03:47 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03: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/18 02:03:47 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:47 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:48 DEBUG : potato.rclone_chunk.001_4dwnlb: File has been put by hash from source 2024/04/18 02:03:48 INFO : potato: Deleted 2024/04/18 02:03:48 INFO : potato.rclone_chunk.001_4dwnlb: Moved (server-side) to: potato 2024/04/18 02:03:48 INFO : potato: Copied (replaced existing) 2024/04/18 02:03:48 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncAfterChangingContentsOnly (2.38s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:50 INFO : potato.rclone_chunk.001_4dwpi4: Moved (server-side) to: potato 2024/04/18 02:03:50 INFO : empty space.rclone_chunk.001_4dwq4k: Moved (server-side) to: empty space 2024/04/18 02:03:50 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 02:03:50 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2024/04/18 02:03:50 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:03:50 DEBUG : empty space: Unchanged skipping 2024/04/18 02:03:50 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:50 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:50 DEBUG : Waiting for deletions to finish 2024/04/18 02:03:50 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.09s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:52 INFO : potato.rclone_chunk.001_4dwr02: Moved (server-side) to: potato 2024/04/18 02:03:52 INFO : empty space.rclone_chunk.001_4dwsd1: Moved (server-side) to: empty space 2024/04/18 02:03:52 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 02:03:52 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:52 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:03:52 DEBUG : empty space: Unchanged skipping 2024/04/18 02:03:52 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:03:52 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:53 INFO : potato2.rclone_chunk.001_4dwsfr: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/18 02:03:54 INFO : potato2.rclone_chunk.002_4dwsfr: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/18 02:03:54 INFO : potato2: Copied (new) 2024/04/18 02:03:54 DEBUG : Waiting for deletions to finish 2024/04/18 02:03:54 INFO : potato: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:54 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:03:54 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.97s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:03:57 INFO : b/potato.rclone_chunk.001_4dwvq2: Moved (server-side) to: b/potato 2024/04/18 02:03:58 INFO : c/non empty space.rclone_chunk.001_4dwyhl: Moved (server-side) to: c/non empty space 2024/04/18 02:03:58 DEBUG : d: Making directory 2024/04/18 02:03:58 DEBUG : d/e: Making directory 2024/04/18 02:03:59 DEBUG : a: Making directory 2024/04/18 02:03:59 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/18 02:03:59 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:03:59 DEBUG : c/non empty space: Unchanged skipping 2024/04/18 02:03:59 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:03:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:03:59 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:00 INFO : a/potato2.rclone_chunk.001_4dwz8y: Moved (server-side) to: a/potato2.rclone_chunk.001 2024/04/18 02:04:00 INFO : a/potato2.rclone_chunk.002_4dwz8y: Moved (server-side) to: a/potato2.rclone_chunk.002 2024/04/18 02:04:00 INFO : a/potato2: Copied (new) 2024/04/18 02:04:00 DEBUG : Waiting for deletions to finish 2024/04/18 02:04:00 INFO : b/potato: Deleted 2024/04/18 02:04:00 INFO : d/e: Removing directory 2024/04/18 02:04:01 INFO : d: Removing directory 2024/04/18 02:04:01 INFO : b: Removing directory 2024/04/18 02:04:01 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': deleted 3 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:01 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:02 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (7.44s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:03 INFO : b/potato.rclone_chunk.001_4dx2wu: Moved (server-side) to: b/potato 2024/04/18 02:04:04 INFO : c/non empty space.rclone_chunk.001_4dx3al: Moved (server-side) to: c/non empty space 2024/04/18 02:04:04 DEBUG : d: Making directory 2024/04/18 02:04:04 DEBUG : a: Making directory 2024/04/18 02:04:04 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/18 02:04:04 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:04 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:04:04 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:04 DEBUG : c/non empty space: Unchanged skipping 2024/04/18 02:04:04 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:05 INFO : a/potato2.rclone_chunk.001_4dx4a0: Moved (server-side) to: a/potato2.rclone_chunk.001 2024/04/18 02:04:05 INFO : a/potato2.rclone_chunk.002_4dx4a0: Moved (server-side) to: a/potato2.rclone_chunk.002 2024/04/18 02:04:05 INFO : a/potato2: Copied (new) 2024/04/18 02:04:05 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': not deleting files as there were IO errors 2024/04/18 02:04:05 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:06 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:06 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (5.33s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:08 INFO : potato.rclone_chunk.001_4dx8ws: Moved (server-side) to: potato 2024/04/18 02:04:09 INFO : empty space.rclone_chunk.001_4dx813: Moved (server-side) to: empty space 2024/04/18 02:04:09 DEBUG : Waiting for deletions to finish 2024/04/18 02:04:09 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 02:04:09 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:04:09 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:09 DEBUG : empty space: Unchanged skipping 2024/04/18 02:04:09 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:09 INFO : potato: Deleted 2024/04/18 02:04:09 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:10 INFO : potato2.rclone_chunk.001_4dx9oo: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/18 02:04:10 INFO : potato2.rclone_chunk.002_4dx9oo: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/18 02:04:11 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:11 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:11 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ --- PASS: TestSyncDeleteDuring (3.62s) === RUN TestSyncDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:12 INFO : potato.rclone_chunk.001_4dxbj5: Moved (server-side) to: potato 2024/04/18 02:04:13 INFO : empty space.rclone_chunk.001_4dxcxq: Moved (server-side) to: empty space 2024/04/18 02:04:13 DEBUG : Waiting for deletions to finish 2024/04/18 02:04:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:13 INFO : potato: Deleted 2024/04/18 02:04:13 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 02:04:13 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:04:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:13 DEBUG : empty space: Unchanged skipping 2024/04/18 02:04:13 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:13 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:14 INFO : potato2.rclone_chunk.001_4dxd62: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/18 02:04:14 INFO : potato2.rclone_chunk.002_4dxd62: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/18 02:04:15 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:15 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:15 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ --- PASS: TestSyncDeleteBefore (4.35s) === RUN TestCopyDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:16 INFO : potato.rclone_chunk.001_4dxg6i: Moved (server-side) to: potato 2024/04/18 02:04:17 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 02:04:17 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:17 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:17 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:17 INFO : potato2.rclone_chunk.001_4dxhaf: Moved (server-side) to: potato2 2024/04/18 02:04:17 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestCopyDeleteBefore (2.16s) === RUN TestSyncWithExclude run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:19 INFO : potato2.rclone_chunk.001_4dxidr: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/18 02:04:19 INFO : potato2.rclone_chunk.002_4dxidr: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/18 02:04:20 INFO : empty space.rclone_chunk.001_4dxjg4: Moved (server-side) to: empty space 2024/04/18 02:04:20 DEBUG : enormous: Excluded (Size Filter) 2024/04/18 02:04:20 DEBUG : enormous: Excluded 2024/04/18 02:04:20 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 02:04:20 DEBUG : potato2: Excluded 2024/04/18 02:04:20 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 02:04:20 DEBUG : potato2: Excluded 2024/04/18 02:04:20 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:20 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:04:20 DEBUG : empty space: Unchanged skipping 2024/04/18 02:04:20 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:20 DEBUG : Waiting for deletions to finish 2024/04/18 02:04:20 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:20 DEBUG : enormous: Excluded (Size Filter) 2024/04/18 02:04:20 DEBUG : enormous: Excluded 2024/04/18 02:04:20 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 02:04:20 DEBUG : potato2: Excluded 2024/04/18 02:04:20 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 02:04:20 DEBUG : potato2: Excluded 2024/04/18 02:04:20 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2024/04/18 02:04:20 DEBUG : empty space: Unchanged skipping 2024/04/18 02:04:20 DEBUG : Local file system at /tmp/rclone1274103239: Waiting for checks to finish 2024/04/18 02:04:20 DEBUG : Local file system at /tmp/rclone1274103239: Waiting for transfers to finish 2024/04/18 02:04:20 DEBUG : Waiting for deletions to finish 2024/04/18 02:04:20 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:20 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:20 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ --- PASS: TestSyncWithExclude (3.30s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:22 INFO : potato2.rclone_chunk.001_4dxlcy: Moved (server-side) to: potato2.rclone_chunk.001 2024/04/18 02:04:22 INFO : potato2.rclone_chunk.002_4dxlcy: Moved (server-side) to: potato2.rclone_chunk.002 2024/04/18 02:04:23 INFO : empty space.rclone_chunk.001_4dxm4r: Moved (server-side) to: empty space 2024/04/18 02:04:24 INFO : enormous.rclone_chunk.001_4dxn1j: Moved (server-side) to: enormous.rclone_chunk.001 2024/04/18 02:04:24 INFO : enormous.rclone_chunk.002_4dxn1j: Moved (server-side) to: enormous.rclone_chunk.002 2024/04/18 02:04:24 DEBUG : enormous: Excluded (Size Filter) 2024/04/18 02:04:24 DEBUG : enormous: Excluded 2024/04/18 02:04:24 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 02:04:24 DEBUG : potato2: Excluded 2024/04/18 02:04:25 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2024/04/18 02:04:25 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:25 DEBUG : empty space: Unchanged skipping 2024/04/18 02:04:25 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:25 DEBUG : Waiting for deletions to finish 2024/04/18 02:04:25 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:25 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Lock file server: locks 2, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:25 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 1, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:25 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5]: Unlock file server: locks 0, url https://cloclo61.datacloudmail.ru/oauth-get/ 2024/04/18 02:04:25 INFO : enormous: Deleted 2024/04/18 02:04:25 INFO : potato2: Deleted 2024/04/18 02:04:25 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:25 DEBUG : Local file system at /tmp/rclone1274103239: Waiting for checks to finish 2024/04/18 02:04:25 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2024/04/18 02:04:25 DEBUG : empty space: Unchanged skipping 2024/04/18 02:04:25 DEBUG : Local file system at /tmp/rclone1274103239: Waiting for transfers to finish 2024/04/18 02:04:25 DEBUG : Waiting for deletions to finish 2024/04/18 02:04:25 INFO : enormous: Deleted 2024/04/18 02:04:25 INFO : potato2: Deleted 2024/04/18 02:04:25 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" --- PASS: TestSyncWithExcludeAndDeleteExcluded (4.61s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:26 INFO : one.rclone_chunk.001_4dxq68: Moved (server-side) to: one 2024/04/18 02:04:27 INFO : two.rclone_chunk.001_4dxqen: Moved (server-side) to: two 2024/04/18 02:04:28 INFO : three.rclone_chunk.001_4dxrzc: Moved (server-side) to: three 2024/04/18 02:04:28 INFO : four.rclone_chunk.001_4dxsjm: Moved (server-side) to: four 2024/04/18 02:04:29 DEBUG : five: Need to transfer - File not found at Destination 2024/04/18 02:04:29 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/04/18 02:04:29 DEBUG : one: Destination is newer than source, skipping 2024/04/18 02:04:29 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:29 DEBUG : three: Sizes identical 2024/04/18 02:04:29 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/04/18 02:04:29 DEBUG : four: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:29 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:29 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/04/18 02:04:29 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:29 DEBUG : five: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:29 INFO : two: Deleted 2024/04/18 02:04:29 INFO : four: Deleted 2024/04/18 02:04:29 INFO : five.rclone_chunk.001_4dxtwi: Moved (server-side) to: five 2024/04/18 02:04:29 INFO : five: Copied (new) 2024/04/18 02:04:29 INFO : two.rclone_chunk.001_4dxt7j: Moved (server-side) to: two 2024/04/18 02:04:29 INFO : two: Copied (replaced existing) 2024/04/18 02:04:29 INFO : four.rclone_chunk.001_4dxtfs: Moved (server-side) to: four 2024/04/18 02:04:29 INFO : four: Copied (replaced existing) 2024/04/18 02:04:29 DEBUG : Waiting for deletions to finish sync_test.go:1244: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (4.18s) === 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-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:1327: Can track renames: false 2024/04/18 02:04:30 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/18 02:04:30 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/18 02:04:30 DEBUG : yam: Need to transfer - File not found at Destination 2024/04/18 02:04:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:30 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:30 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:30 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2024/04/18 02:04:30 ERROR : potato.rclone_chunk.001_4dxuxh: invalid character '<' looking for beginning of value 2024/04/18 02:04:30 ERROR : yam.rclone_chunk.001_4dxujc: invalid character '<' looking for beginning of value 2024/04/18 02:04:30 ERROR : potato.rclone_chunk.001_4dxuxh: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:04:31 ERROR : yam.rclone_chunk.001_4dxujc: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:04:31 ERROR : potato.rclone_chunk.001_4dxuxh: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/18 02:04:31 ERROR : potato: Failed to copy: invalid character '<' looking for beginning of value 2024/04/18 02:04:31 ERROR : yam.rclone_chunk.001_4dxujc: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/18 02:04:31 ERROR : yam: Failed to copy: invalid character '<' looking for beginning of value 2024/04/18 02:04:31 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': not deleting files as there were IO errors 2024/04/18 02:04:31 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': not deleting directories as there were IO errors sync_test.go:1334: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1334 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncWithTrackRenames 2024/04/18 02:04:31 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 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1334 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncWithTrackRenames --- FAIL: TestSyncWithTrackRenames (0.74s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:1399: Can track renames: true 2024/04/18 02:04:31 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': error reading destination root directory: invalid character '<' looking for beginning of value 2024/04/18 02:04:31 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Making map for --track-renames 2024/04/18 02:04:31 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Finished making map for --track-renames 2024/04/18 02:04:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for renames to finish 2024/04/18 02:04:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:31 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': not deleting files as there were IO errors 2024/04/18 02:04:31 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': not deleting directories as there were IO errors sync_test.go:1406: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1406 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncWithTrackRenamesStrategyModtime 2024/04/18 02:04:31 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 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1406 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncWithTrackRenamesStrategyModtime --- FAIL: TestSyncWithTrackRenamesStrategyModtime (0.10s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:1438: Can track renames: true 2024/04/18 02:04:31 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': error reading destination root directory: invalid character '<' looking for beginning of value 2024/04/18 02:04:31 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Making map for --track-renames 2024/04/18 02:04:31 INFO : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Finished making map for --track-renames 2024/04/18 02:04:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for renames to finish 2024/04/18 02:04:31 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish 2024/04/18 02:04:31 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': not deleting files as there were IO errors 2024/04/18 02:04:31 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': not deleting directories as there were IO errors sync_test.go:1445: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1445 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncWithTrackRenamesStrategyLeaf 2024/04/18 02:04:31 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 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1445 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncWithTrackRenamesStrategyLeaf --- FAIL: TestSyncWithTrackRenamesStrategyLeaf (0.10s) === RUN TestServerSideMoveLocal run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", 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:1556 Error: Should be true Test: TestServerSideMoveLocal Messages: listing wrong, want dir1/file1.txt (5), dir2/file2.txt (11) got dir1/file1.txt (5), dir2/file2.txt (11), potato (14), sub/yam (11), yam (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:1556 Error: Should be true Test: TestServerSideMoveLocal Messages: Unexpected file "potato" 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:1556 Error: Should be true Test: TestServerSideMoveLocal Messages: Unexpected file "yam" 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:1556 Error: Should be true Test: TestServerSideMoveLocal Messages: Unexpected file "sub/yam" 2024/04/18 02:04:38 DEBUG : Creating backend with remote "/tmp/rclone1274103239/dir1" 2024/04/18 02:04:38 DEBUG : Creating backend with remote "/tmp/rclone1274103239/dir2" 2024/04/18 02:04:38 DEBUG : Local file system at /tmp/rclone1274103239/dir2: Using server-side directory move 2024/04/18 02:04:38 INFO : Local file system at /tmp/rclone1274103239/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/18 02:04:38 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/18 02:04:38 DEBUG : Local file system at /tmp/rclone1274103239/dir2: Waiting for checks to finish 2024/04/18 02:04:38 DEBUG : Local file system at /tmp/rclone1274103239/dir2: Waiting for transfers to finish 2024/04/18 02:04:38 INFO : file1.txt: Moved (server-side) 2024/04/18 02:04:38 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 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestServerSideMoveLocal --- FAIL: TestServerSideMoveLocal (7.08s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:38 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': error reading destination root directory: invalid character '<' looking for beginning of value 2024/04/18 02:04:38 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:38 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish sync_test.go:1577: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1577 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMoveWithDeleteEmptySrcDirs 2024/04/18 02:04:38 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 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1577 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMoveWithDeleteEmptySrcDirs --- FAIL: TestMoveWithDeleteEmptySrcDirs (0.11s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:38 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': error reading destination root directory: invalid character '<' looking for beginning of value 2024/04/18 02:04:38 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:38 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish sync_test.go:1597: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1597 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMoveWithoutDeleteEmptySrcDirs 2024/04/18 02:04:38 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 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1597 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMoveWithoutDeleteEmptySrcDirs --- FAIL: TestMoveWithoutDeleteEmptySrcDirs (0.10s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:38 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': error reading destination root directory: invalid character '<' looking for beginning of value 2024/04/18 02:04:38 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for checks to finish 2024/04/18 02:04:38 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5': Waiting for transfers to finish sync_test.go:1624: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1624 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMoveWithIgnoreExisting 2024/04/18 02:04:38 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 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1624 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMoveWithIgnoreExisting --- FAIL: TestMoveWithIgnoreExisting (0.10s) === RUN TestServerSideMove run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:38 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-zuposeq2tetubed0dasebaz0" 2024/04/18 02:04:38 DEBUG : Creating backend with remote "TestMailru:rclone-test-zuposeq2tetubed0dasebaz0" 2024/04/18 02:04:38 DEBUG : Reset feature "ListR" 2024/04/18 02:04:39 ERROR : potato2.rclone_chunk.001_4dy2km: invalid character '<' looking for beginning of value 2024/04/18 02:04:39 ERROR : potato2.rclone_chunk.002_4dy2km: invalid character '<' looking for beginning of value 2024/04/18 02:04:39 ERROR : potato2.rclone_chunk.001_4dy2km: invalid character '<' looking for beginning of value 2024/04/18 02:04:39 ERROR : potato2.rclone_chunk.001_4dy2km: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:04:39 ERROR : potato2.rclone_chunk.001_4dy2km: invalid character '<' looking for beginning of value 2024/04/18 02:04:39 ERROR : potato2.rclone_chunk.001_4dy2km: Couldn't move: move file failed with error 1 2024/04/18 02:04:39 ERROR : potato2.rclone_chunk.001_4dy2km: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/18 02:04:39 ERROR : potato2.rclone_chunk.002_4dy2km: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "potato2" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": move file failed with error 1 2024/04/18 02:04:39 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-zuposeq2tetubed0dasebaz0': Purge remote 2024/04/18 02:04:39 purge failed: purge failed: invalid character '<' looking for beginning of value 2024/04/18 02:04:39 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:303 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1483 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1670 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestServerSideMove --- FAIL: TestServerSideMove (1.21s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:39 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-migepud1sukorel4dowigej8" 2024/04/18 02:04:39 DEBUG : Creating backend with remote "TestMailru:rclone-test-migepud1sukorel4dowigej8" 2024/04/18 02:04:40 DEBUG : Reset feature "ListR" 2024/04/18 02:04:40 ERROR : potato2.rclone_chunk.001_4dy4aw: invalid character '<' looking for beginning of value 2024/04/18 02:04:40 ERROR : potato2.rclone_chunk.002_4dy4aw: invalid character '<' looking for beginning of value 2024/04/18 02:04:40 ERROR : potato2.rclone_chunk.001_4dy4aw: invalid character '<' looking for beginning of value 2024/04/18 02:04:40 ERROR : potato2.rclone_chunk.001_4dy4aw: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:04:40 ERROR : potato2.rclone_chunk.001_4dy4aw: invalid character '<' looking for beginning of value 2024/04/18 02:04:40 ERROR : potato2.rclone_chunk.001_4dy4aw: Couldn't move: move file failed with error 1 2024/04/18 02:04:40 ERROR : potato2.rclone_chunk.001_4dy4aw: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/18 02:04:40 ERROR : potato2.rclone_chunk.002_4dy4aw: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "potato2" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": move file failed with error 1 2024/04/18 02:04:40 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-migepud1sukorel4dowigej8': Purge remote 2024/04/18 02:04:41 purge failed: purge failed: invalid character '<' looking for beginning of value 2024/04/18 02:04:41 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:303 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1483 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1683 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestServerSideMoveWithFilter --- FAIL: TestServerSideMoveWithFilter (1.10s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:41 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-zuzaruk9quyeqed3vuhudog1" 2024/04/18 02:04:41 DEBUG : Creating backend with remote "TestMailru:rclone-test-zuzaruk9quyeqed3vuhudog1" 2024/04/18 02:04:41 DEBUG : Reset feature "ListR" 2024/04/18 02:04:41 ERROR : potato2.rclone_chunk.001_4dy56q: invalid character '<' looking for beginning of value 2024/04/18 02:04:41 ERROR : potato2.rclone_chunk.002_4dy56q: invalid character '<' looking for beginning of value 2024/04/18 02:04:41 ERROR : potato2.rclone_chunk.001_4dy56q: invalid character '<' looking for beginning of value 2024/04/18 02:04:41 ERROR : potato2.rclone_chunk.001_4dy56q: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:04:41 ERROR : potato2.rclone_chunk.001_4dy56q: invalid character '<' looking for beginning of value 2024/04/18 02:04:41 ERROR : potato2.rclone_chunk.001_4dy56q: Couldn't move: move file failed with error 1 2024/04/18 02:04:41 ERROR : potato2.rclone_chunk.001_4dy56q: Failed to remove temporary chunk: invalid character '<' looking for beginning of value 2024/04/18 02:04:42 ERROR : potato2.rclone_chunk.002_4dy56q: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "potato2" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": move file failed with error 1 2024/04/18 02:04:42 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-zuzaruk9quyeqed3vuhudog1': Purge remote 2024/04/18 02:04:42 purge failed: purge failed: invalid character '<' looking for beginning of value 2024/04/18 02:04:42 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:303 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1483 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1690 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestServerSideMoveDeleteEmptySourceDirs --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (1.08s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:1699: Skipping test as remote supports DirMove 2024/04/18 02:04:42 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:1120 /usr/local/go/src/testing/testing.go:1096 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1699 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestServerSideMoveOverlap --- FAIL: TestServerSideMoveOverlap (0.05s) === RUN TestSyncOverlap run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:42 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test" 2024/04/18 02:04:42 DEBUG : Creating backend with remote "TestMailru:rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test" 2024/04/18 02:04:42 DEBUG : Reset feature "ListR" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:42 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 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncOverlap 2024/04/18 02:04:42 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 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncOverlap 2024/04/18 02:04:42 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: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 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncOverlap 2024/04/18 02:04:42 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: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: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 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncOverlap 2024/04/18 02:04:42 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: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: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: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 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncOverlap --- FAIL: TestSyncOverlap (0.30s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:42 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test" 2024/04/18 02:04:42 DEBUG : Reset feature "ListR" 2024/04/18 02:04:42 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/18 02:04:42 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test-include/layer2" 2024/04/18 02:04:42 DEBUG : Creating backend with remote "TestMailru:rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test-include/layer2" 2024/04/18 02:04:42 DEBUG : Reset feature "ListR" 2024/04/18 02:04:42 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test-include/layer2]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/18 02:04:42 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test-include/layer2]: mkDirs by part "/rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test-include/layer2" 2024/04/18 02:04:42 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test-ignore-file" 2024/04/18 02:04:42 DEBUG : Creating backend with remote "TestMailru:rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test-ignore-file" 2024/04/18 02:04:42 DEBUG : Reset feature "ListR" 2024/04/18 02:04:42 DEBUG : [rclone-test-baqudoh9wuhevuy9dibufac5/rclone-sync-test-ignore-file]: new meta server: https://cld-extapi.datacloudmail.ru/meta/ 2024/04/18 02:04:43 ERROR : rclone-sync-test-ignore-file/.ignore.rclone_chunk.001_4dy6sd: invalid character '<' looking for beginning of value 2024/04/18 02:04:43 ERROR : rclone-sync-test-ignore-file/.ignore.rclone_chunk.001_4dy6sd: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:04:43 ERROR : rclone-sync-test-ignore-file/.ignore.rclone_chunk.001_4dy6sd: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "rclone-sync-test-ignore-file/.ignore" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:04:43 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1782 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncOverlapWithFilter --- FAIL: TestSyncOverlapWithFilter (1.01s) === RUN TestSyncCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:43 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst" 2024/04/18 02:04:43 DEBUG : Creating backend with remote "TestMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst" 2024/04/18 02:04:43 DEBUG : Reset feature "ListR" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: listing wrong, want one (3) got dir2/file1.txt (5), dir2/file2.txt (11), existing (6), existing-b (6), nested/sub dir/file (6), one (3), potato (14), potato2 (60), sub dir/hello world (11), sub/yam (11), yam (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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "existing" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "existing-b" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "potato" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "potato2" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "yam" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "sub dir/hello world" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "dir2/file1.txt" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "dir2/file2.txt" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "nested/sub dir/file" 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:1868 Error: Should be true Test: TestSyncCompareDest Messages: Unexpected file "sub/yam" 2024/04/18 02:04:50 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/CompareDest" 2024/04/18 02:04:50 DEBUG : Creating backend with remote "TestMailru:rclone-test-baqudoh9wuhevuy9dibufac5/CompareDest" 2024/04/18 02:04:50 DEBUG : Reset feature "ListR" 2024/04/18 02:04:50 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': error reading destination root directory: invalid character '<' looking for beginning of value 2024/04/18 02:04:50 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': Waiting for checks to finish 2024/04/18 02:04:50 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': Waiting for transfers to finish 2024/04/18 02:04:50 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': not deleting files as there were IO errors 2024/04/18 02:04:50 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': not deleting directories as there were IO errors sync_test.go:1874: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1874 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncCompareDest 2024/04/18 02:04:50 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 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1874 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncCompareDest --- FAIL: TestSyncCompareDest (7.23s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: listing wrong, want 1 (1), 2 (1), 3 (1) got 1 (1), 2 (1), 3 (1), dir2/file1.txt (5), dir2/file2.txt (11), existing (6), existing-b (6), nested/sub dir/file (6), one (3), potato (14), potato2 (60), sub dir/hello world (11), sub/yam (11), yam (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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "existing" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "existing-b" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "one" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "potato" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "potato2" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "yam" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "sub dir/hello world" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "nested/sub dir/file" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "dir2/file1.txt" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "dir2/file2.txt" 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:1997 Error: Should be true Test: TestSyncMultipleCompareDest Messages: Unexpected file "sub/yam" 2024/04/18 02:04:58 ERROR : pre-dest1/1.rclone_chunk.001_4dylbd: invalid character '<' looking for beginning of value 2024/04/18 02:04:58 ERROR : pre-dest1/1.rclone_chunk.001_4dylbd: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:04:58 ERROR : pre-dest1/1.rclone_chunk.001_4dylbd: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "pre-dest1/1" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:04:58 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1999 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncMultipleCompareDest --- FAIL: TestSyncMultipleCompareDest (7.63s) === RUN TestSyncCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:04:58 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst" 2024/04/18 02:04:58 DEBUG : Reset feature "ListR" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: listing wrong, want one (3) got 1 (1), 2 (1), 3 (1), dir2/file1.txt (5), dir2/file2.txt (11), existing (6), existing-b (6), nested/sub dir/file (6), one (3), potato (14), potato2 (60), sub dir/hello world (11), sub/yam (11), yam (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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "1" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "2" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "3" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "existing" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "existing-b" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "potato" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "potato2" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "yam" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "sub dir/hello world" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "dir2/file1.txt" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "dir2/file2.txt" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "nested/sub dir/file" 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:2034 Error: Should be true Test: TestSyncCopyDest Messages: Unexpected file "sub/yam" 2024/04/18 02:05:05 DEBUG : Creating backend with remote "TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/CopyDest" 2024/04/18 02:05:05 DEBUG : Creating backend with remote "TestMailru:rclone-test-baqudoh9wuhevuy9dibufac5/CopyDest" 2024/04/18 02:05:05 DEBUG : Reset feature "ListR" 2024/04/18 02:05:05 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': error reading destination root directory: invalid character '<' looking for beginning of value 2024/04/18 02:05:05 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': Waiting for checks to finish 2024/04/18 02:05:05 DEBUG : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': Waiting for transfers to finish 2024/04/18 02:05:05 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': not deleting files as there were IO errors 2024/04/18 02:05:05 ERROR : Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5/dst': not deleting directories as there were IO errors sync_test.go:2040: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2040 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncCopyDest 2024/04/18 02:05:05 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 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2040 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncCopyDest --- FAIL: TestSyncCopyDest (7.17s) === RUN TestSyncBackupDir run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:05:05 ERROR : dst/one.rclone_chunk.001_4dytxv: invalid character '<' looking for beginning of value 2024/04/18 02:05:05 ERROR : dst/one.rclone_chunk.001_4dytxv: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:06 ERROR : dst/one.rclone_chunk.001_4dytxv: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "dst/one" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:06 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2160 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2215 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncBackupDir --- FAIL: TestSyncBackupDir (0.58s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:05:06 ERROR : dst/one.rclone_chunk.001_4dyun9: invalid character '<' looking for beginning of value 2024/04/18 02:05:06 ERROR : dst/one.rclone_chunk.001_4dyun9: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:06 ERROR : dst/one.rclone_chunk.001_4dyun9: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "dst/one" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:06 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2160 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2218 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncBackupDirWithSuffix --- FAIL: TestSyncBackupDirWithSuffix (0.58s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:05:07 ERROR : dst/one.rclone_chunk.001_4dyunx: invalid character '<' looking for beginning of value 2024/04/18 02:05:07 ERROR : dst/one.rclone_chunk.001_4dyunx: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:07 ERROR : dst/one.rclone_chunk.001_4dyunx: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "dst/one" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:07 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2160 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2221 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncBackupDirWithSuffixKeepExtension --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (0.61s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:05:07 ERROR : dst/one.rclone_chunk.001_4dyv76: invalid character '<' looking for beginning of value 2024/04/18 02:05:07 ERROR : dst/one.rclone_chunk.001_4dyv76: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:07 ERROR : dst/one.rclone_chunk.001_4dyv76: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "dst/one" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:07 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2160 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2224 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncBackupDirSuffixOnly --- FAIL: TestSyncBackupDirSuffixOnly (0.60s) === RUN TestSyncSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:05:08 ERROR : dst/one.rclone_chunk.001_4dyv3g: invalid character '<' looking for beginning of value 2024/04/18 02:05:08 ERROR : dst/one.rclone_chunk.001_4dyv3g: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:08 ERROR : dst/one.rclone_chunk.001_4dyv3g: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "dst/one" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:08 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2243 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2308 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncSuffix --- FAIL: TestSyncSuffix (0.63s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:05:08 ERROR : dst/one.rclone_chunk.001_4dywjg: invalid character '<' looking for beginning of value 2024/04/18 02:05:08 ERROR : dst/one.rclone_chunk.001_4dywjg: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:09 ERROR : dst/one.rclone_chunk.001_4dywjg: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "dst/one" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:09 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2243 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2309 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncSuffixKeepExtension --- FAIL: TestSyncSuffixKeepExtension (0.59s) === RUN TestSyncUTFNorm run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: listing wrong, want Testêé (14) got 1 (1), 2 (1), 3 (1), Testêé (14), dir2/file1.txt (5), dir2/file2.txt (11), existing (6), existing-b (6), nested/sub dir/file (6), one (3), potato (14), potato2 (60), sub dir/hello world (11), sub/yam (11), yam (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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "1" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "2" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "3" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "existing" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "existing-b" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "one" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "potato" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "potato2" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "yam" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "sub dir/hello world" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "sub/yam" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "dir2/file1.txt" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "dir2/file2.txt" 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:2327 Error: Should be true Test: TestSyncUTFNorm Messages: Unexpected file "nested/sub dir/file" 2024/04/18 02:05:16 ERROR : Testêé.rclone_chunk.001_4dz4yv: invalid character '<' looking for beginning of value 2024/04/18 02:05:16 ERROR : Testêé.rclone_chunk.001_4dz4yv: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:16 ERROR : Testêé.rclone_chunk.001_4dz4yv: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "Testêé" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:16 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2329 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncUTFNorm --- FAIL: TestSyncUTFNorm (7.61s) === RUN TestSyncImmutable run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", 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:2356 Error: Should be true Test: TestSyncImmutable Messages: listing wrong, want existing (6) got 1 (1), 2 (1), 3 (1), Testêé (14), dir2/file1.txt (5), dir2/file2.txt (11), existing (6), existing-b (6), nested/sub dir/file (6), one (3), potato (14), potato2 (60), sub dir/hello world (11), sub/yam (11), yam (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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "1" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "2" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "3" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "Testêé" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "existing-b" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "one" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "potato" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "potato2" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "yam" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "sub dir/hello world" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "nested/sub dir/file" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "sub/yam" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "dir2/file1.txt" 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:2356 Error: Should be true Test: TestSyncImmutable Messages: Unexpected file "dir2/file2.txt" 2024/04/18 02:05:23 ERROR : : error listing: invalid character '<' looking for beginning of value fstest.go:276: Error listing: invalid character '<' looking for beginning of value 2024/04/18 02:05:23 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:2357 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncImmutable --- FAIL: TestSyncImmutable (7.12s) === RUN TestSyncIgnoreCase run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:2391: Skipping test as local or remote are case-insensitive 2024/04/18 02:05:23 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:1120 /usr/local/go/src/testing/testing.go:1096 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2391 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncIgnoreCase --- FAIL: TestSyncIgnoreCase (0.05s) === RUN TestFixCase run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", 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:2430 Error: Should be true Test: TestFixCase Messages: listing wrong, want existing (6), existingbutdifferent (5), subdira/subdirb/subdirc/hello (5), subdira/subdirb/subdirc/subdird/filewithoutcasedifferences (5) got 1 (1), 2 (1), 3 (1), Testêé (14), dir2/file1.txt (5), dir2/file2.txt (11), existing (6), existing-b (6), existingbutdifferent (5), nested/sub dir/file (6), one (3), potato (14), potato2 (60), sub dir/hello world (11), sub/yam (11), subdira/subdirb/subdirc/hello (5), subdira/subdirb/subdirc/subdird/filewithoutcasedifferences (5), yam (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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "1" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "2" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "3" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "Testêé" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "existing-b" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "one" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "potato" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "potato2" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "yam" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "sub dir/hello world" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "sub/yam" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "dir2/file1.txt" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "dir2/file2.txt" 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:2430 Error: Should be true Test: TestFixCase Messages: Unexpected file "nested/sub dir/file" 2024/04/18 02:05:31 ERROR : EXISTING.rclone_chunk.001_4dzioa: invalid character '<' looking for beginning of value 2024/04/18 02:05:31 ERROR : EXISTING.rclone_chunk.001_4dzioa: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:31 ERROR : EXISTING.rclone_chunk.001_4dzioa: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "EXISTING" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:31 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2431 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestFixCase --- FAIL: TestFixCase (7.92s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:2459: This test only runs on local 2024/04/18 02:05:31 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:1120 /usr/local/go/src/testing/testing.go:1096 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2459 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2482 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMaxTransfer/Hard === RUN TestMaxTransfer/Soft run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:2459: This test only runs on local 2024/04/18 02:05:31 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:1120 /usr/local/go/src/testing/testing.go:1096 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2459 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2483 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMaxTransfer/Soft === RUN TestMaxTransfer/Cautious run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" sync_test.go:2459: This test only runs on local 2024/04/18 02:05:31 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:1120 /usr/local/go/src/testing/testing.go:1096 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2459 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2484 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestMaxTransfer/Cautious --- FAIL: TestMaxTransfer (0.15s) --- FAIL: TestMaxTransfer/Hard (0.05s) --- FAIL: TestMaxTransfer/Soft (0.05s) --- FAIL: TestMaxTransfer/Cautious (0.05s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:05:32 ERROR : both0.rclone_chunk.001_4dzkpm: invalid character '<' looking for beginning of value 2024/04/18 02:05:32 ERROR : both0.rclone_chunk.001_4dzkpm: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:32 ERROR : both0.rclone_chunk.001_4dzkpm: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "both0" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:32 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:303 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2508 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2535 Error: Received unexpected error: invalid character '<' looking for beginning of value Test: TestSyncConcurrentDelete --- FAIL: TestSyncConcurrentDelete (0.61s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", Modify Window "1s" 2024/04/18 02:05:32 ERROR : both0.rclone_chunk.001_4dzkux: invalid character '<' looking for beginning of value 2024/04/18 02:05:33 ERROR : both0.rclone_chunk.001_4dzkux: Couldn't move: invalid character '<' looking for beginning of value 2024/04/18 02:05:33 ERROR : both0.rclone_chunk.001_4dzkux: Failed to remove temporary chunk: invalid character '<' looking for beginning of value run.go:285: Failed to put "both0" to "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'": invalid character '<' looking for beginning of value 2024/04/18 02:05:33 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/run.go:285 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:292 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:303 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2513 /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 (0.59s) === RUN TestNothingToTransfer run.go:180: Remote "Chunked 'TestChunkerChunk50bMailru:rclone-test-baqudoh9wuhevuy9dibufac5'", Local "Local file system at /tmp/rclone1274103239", 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 1 (1), 2 (1), 3 (1), Testêé (14), both0 (6), dir2/file1.txt (5), dir2/file2.txt (11), existing (6), existing-b (6), existingbutdifferent (5), nested/sub dir/file (6), one (3), potato (14), potato2 (60), sub dir/hello world (11), sub dir2/very/very/very/very/very/nested/subdir/hello world (11), sub/yam (11), subdira/subdirb/subdirc/hello (5), subdira/subdirb/subdirc/subdird/filewithoutcasedifferences (5), yam (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 "1" 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 "2" 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 "3" 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 "Testêé" 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 "existing" 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 "existing-b" 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 "existingbutdifferent" 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 "one" 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 "potato" 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 "potato2" 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 "yam" 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 "subdira/subdirb/subdirc/hello" 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 "subdira/subdirb/subdirc/subdird/filewithoutcasedifferences" 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 "sub/yam" 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 "dir2/file1.txt" 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 "dir2/file2.txt" 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 "nested/sub dir/file" 2024/04/18 02:05:40 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/18 02:05:40 DEBUG : sub dir: Making directory 2024/04/18 02:05:40 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.16s) FAIL "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bMailru: -verbose -fast-list -size-limit 10240" - Finished ERROR in 2m43.390799371s (try 1/5): exit status 1: Failed [TestSyncWithTrackRenames TestSyncWithTrackRenamesStrategyModtime TestSyncWithTrackRenamesStrategyLeaf TestServerSideMoveLocal TestMoveWithDeleteEmptySrcDirs TestMoveWithoutDeleteEmptySrcDirs TestMoveWithIgnoreExisting TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestServerSideMoveOverlap TestSyncOverlap TestSyncOverlapWithFilter TestSyncCompareDest TestSyncMultipleCompareDest TestSyncCopyDest TestSyncBackupDir TestSyncBackupDirWithSuffix TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly TestSyncSuffix TestSyncSuffixKeepExtension TestSyncUTFNorm TestSyncImmutable TestSyncIgnoreCase TestFixCase TestMaxTransfer/Hard TestMaxTransfer/Soft TestMaxTransfer/Cautious TestSyncConcurrentDelete TestSyncConcurrentTruncate TestNothingToTransfer]