"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024" - Starting (try 1/5) 2025/02/13 01:02:32 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu" 2025/02/13 01:02:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/13 01:02:32 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu" 2025/02/13 01:02:33 DEBUG : box root 'rclone-test-rufokex1nobu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:02:33 DEBUG : Reset feature "ListR" 2025/02/13 01:02:33 DEBUG : Creating backend with remote "/tmp/rclone2426187867" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === 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 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:02:34 DEBUG : box root 'rclone-test-rufokex1nobu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:02:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:02:35 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/02/13 01:02:35 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:02:35 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyWithDryRun (3.71s) === RUN TestCopy run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:02:37 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/13 01:02:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:02:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:02:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:02:37 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:02:42 INFO : sub dir/hello world.rclone_chunk.001_lptm0m: Moved (server-side) to: sub dir/hello world 2025/02/13 01:02:42 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:02:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (9.98s) === RUN TestCopyMetadata run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.83s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.91s) === RUN TestCopyMissingDirectory run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:02:48 DEBUG : Creating backend with remote "/non-existing" 2025/02/13 01:02:49 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/02/13 01:02:49 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:02:49 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyMissingDirectory (2.01s) === RUN TestCopyNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:02:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:02:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:02:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:02:51 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:02:56 INFO : sub dir/hello world.rclone_chunk.001_lpu0bt: Moved (server-side) to: sub dir/hello world 2025/02/13 01:02:56 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyNoTraverse (11.23s) === RUN TestCopyCheckFirst run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:03:02 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Running all checks before starting transfers 2025/02/13 01:03:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:03:02 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:03:02 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Checks finished, now starting transfers 2025/02/13 01:03:02 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:03:02 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:03:07 INFO : sub dir/hello world.rclone_chunk.001_lpubpv: Moved (server-side) to: sub dir/hello world 2025/02/13 01:03:07 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyCheckFirst (11.35s) === RUN TestSyncNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:03:13 ERROR : Ignoring --no-traverse with sync 2025/02/13 01:03:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:03:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:03:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:03:14 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:03:18 INFO : sub dir/hello world.rclone_chunk.001_lpunxu: Moved (server-side) to: sub dir/hello world 2025/02/13 01:03:18 INFO : sub dir/hello world: Copied (new) 2025/02/13 01:03:18 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncNoTraverse (10.09s) === RUN TestCopyWithDepth run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:03:24 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/02/13 01:03:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:03:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:03:24 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:03:27 INFO : hello world2.rclone_chunk.001_lpux4g: Moved (server-side) to: hello world2 2025/02/13 01:03:27 INFO : hello world2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyWithDepth (6.77s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:03:30 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:03:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:03:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:03:31 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:03:34 INFO : potato2.rclone_chunk.001_lpv44i: Moved (server-side) to: potato2 2025/02/13 01:03:34 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyWithFilesFrom (6.40s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:03:37 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:03:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:03:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:03:37 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:03:40 INFO : potato2.rclone_chunk.001_lpvay4: Moved (server-side) to: potato2 2025/02/13 01:03:40 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyWithFilesFromAndNoTraverse (6.41s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:03:43 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/02/13 01:03:43 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/13 01:03:43 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/02/13 01:03:43 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/13 01:03:43 INFO : sub dir: Making directory 2025/02/13 01:03:44 INFO : sub dir2: Making directory 2025/02/13 01:03:45 INFO : sub dir2/sub sub dir2: Making directory 2025/02/13 01:03:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:03:46 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:03:46 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:03:47 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:03:51 INFO : sub dir/hello world.rclone_chunk.001_lpvkpa: Moved (server-side) to: sub dir/hello world 2025/02/13 01:03:51 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:03:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (16.13s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:03:59 INFO : sub dir2: Making directory 2025/02/13 01:03:59 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/02/13 01:03:59 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/13 01:03:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:03:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:03:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:04:00 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:04:05 INFO : sub dir/hello world.rclone_chunk.001_lpvxgh: Moved (server-side) to: sub dir/hello world 2025/02/13 01:04:05 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (9.83s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:04:09 DEBUG : sub dir2: Making directory with metadata 2025/02/13 01:04:09 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/13 01:04:09 INFO : sub dir: Making directory 2025/02/13 01:04:10 INFO : sub dir2: Making directory 2025/02/13 01:04:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:04:12 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:04:12 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:04:12 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:04:16 INFO : sub dir/hello world.rclone_chunk.001_lpw985: Moved (server-side) to: sub dir/hello world 2025/02/13 01:04:16 INFO : sub dir/hello world: Copied (new) 2025/02/13 01:04:16 INFO : sub dir/hello world: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:04:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/02/13 01:04:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (13.22s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.75s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:04:23 INFO : sub dir2: Making directory 2025/02/13 01:04:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:04:23 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:04:23 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:04:23 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:04:28 INFO : sub dir/hello world.rclone_chunk.001_lpwkqh: Moved (server-side) to: sub dir/hello world 2025/02/13 01:04:28 INFO : sub dir/hello world: Copied (new) 2025/02/13 01:04:28 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (8.47s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:04:31 DEBUG : sub dir2: Making directory with metadata 2025/02/13 01:04:31 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/13 01:04:31 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/13 01:04:31 INFO : sub dir: Making directory 2025/02/13 01:04:32 INFO : sub dir2: Making directory 2025/02/13 01:04:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:04:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:04:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:04:34 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:04:37 INFO : sub dir/hello world.rclone_chunk.001_lpwvq8: Moved (server-side) to: sub dir/hello world 2025/02/13 01:04:37 INFO : sub dir/hello world: Copied (new) 2025/02/13 01:04:37 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:04:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (10.77s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.73s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:04:43 INFO : sub dir2: Making directory 2025/02/13 01:04:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:04:43 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:04:43 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:04:43 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:04:49 INFO : sub dir/hello world.rclone_chunk.001_lpx4qu: Moved (server-side) to: sub dir/hello world 2025/02/13 01:04:49 INFO : sub dir/hello world: Copied (new) 2025/02/13 01:04:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (10.22s) === RUN TestServerSideCopy run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:04:58 INFO : sub dir/hello world.rclone_chunk.001_lpxear: Moved (server-side) to: sub dir/hello world 2025/02/13 01:04:59 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-radoweg8pena" 2025/02/13 01:04:59 DEBUG : Creating backend with remote "TestBox:rclone-test-radoweg8pena" 2025/02/13 01:05:00 DEBUG : box root 'rclone-test-radoweg8pena': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:05:00 DEBUG : Reset feature "ListR" sync_test.go:590: Server side copy (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu' -> Chunked 'TestChunkerChunk50bBox:rclone-test-radoweg8pena' 2025/02/13 01:05:01 DEBUG : box root 'rclone-test-radoweg8pena': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:05:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:05:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-radoweg8pena': Waiting for checks to finish 2025/02/13 01:05:01 DEBUG : sub dir/hello world: copy non-chunked object... 2025/02/13 01:05:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-radoweg8pena': Waiting for transfers to finish 2025/02/13 01:05:02 DEBUG : box root 'rclone-test-radoweg8pena': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:05:05 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:05:06 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-radoweg8pena': Purge remote --- PASS: TestServerSideCopy (18.79s) === RUN TestCopyOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:05:19 INFO : sub dir/hello world.rclone_chunk.001_lpxxlv: Moved (server-side) to: sub dir/hello world 2025/02/13 01:05:21 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/02/13 01:05:21 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:05:21 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:05:21 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:05:25 INFO : sub dir/hello world: Deleted 2025/02/13 01:05:26 INFO : sub dir/hello world.rclone_chunk.001_lpy600: Moved (server-side) to: sub dir/hello world 2025/02/13 01:05:26 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyOverSelf (18.89s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:05:35 INFO : sub dir/hello world.rclone_chunk.001_lpygvd: Moved (server-side) to: sub dir/hello world 2025/02/13 01:05:36 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-jigisun5kecu" 2025/02/13 01:05:36 DEBUG : Config file has changed externally - reloading 2025/02/13 01:05:36 DEBUG : Creating backend with remote "TestBox:rclone-test-jigisun5kecu" 2025/02/13 01:05:37 DEBUG : box root 'rclone-test-jigisun5kecu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:05:38 DEBUG : Reset feature "ListR" sync_test.go:626: Server side copy (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu' -> Chunked 'TestChunkerChunk50bBox:rclone-test-jigisun5kecu' 2025/02/13 01:05:38 DEBUG : box root 'rclone-test-jigisun5kecu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:05:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:05:38 DEBUG : sub dir/hello world: copy non-chunked object... 2025/02/13 01:05:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-jigisun5kecu': Waiting for checks to finish 2025/02/13 01:05:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-jigisun5kecu': Waiting for transfers to finish 2025/02/13 01:05:39 DEBUG : box root 'rclone-test-jigisun5kecu': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:05:43 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:05:51 INFO : sub dir/hello world: Deleted 2025/02/13 01:05:51 INFO : sub dir/hello world.rclone_chunk.001_lpyu42: Moved (server-side) to: sub dir/hello world 2025/02/13 01:05:53 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/02/13 01:05:53 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-jigisun5kecu': Waiting for checks to finish 2025/02/13 01:05:53 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-jigisun5kecu': Waiting for transfers to finish 2025/02/13 01:05:53 DEBUG : sub dir/hello world: copy non-chunked object... 2025/02/13 01:05:53 DEBUG : sub dir/hello world: dst already exists, copying to temp name sub dir/hello world-rclone-copy-quzuram7 2025/02/13 01:05:57 DEBUG : sub dir/hello world-rclone-copy-quzuram7: moving to real name sub dir/hello world 2025/02/13 01:05:59 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:06:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-jigisun5kecu': Purge remote --- PASS: TestServerSideCopyOverSelf (35.71s) === RUN TestMoveOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:06:11 INFO : sub dir/hello world.rclone_chunk.001_lpzgu6: Moved (server-side) to: sub dir/hello world 2025/02/13 01:06:13 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/02/13 01:06:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:06:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:06:13 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:06:18 INFO : sub dir/hello world: Deleted 2025/02/13 01:06:18 INFO : sub dir/hello world.rclone_chunk.001_lpzmbl: Moved (server-side) to: sub dir/hello world 2025/02/13 01:06:18 INFO : sub dir/hello world: Copied (replaced existing) 2025/02/13 01:06:18 INFO : sub dir/hello world: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestMoveOverSelf (16.93s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:06:28 INFO : sub dir/hello world.rclone_chunk.001_lpzxuc: Moved (server-side) to: sub dir/hello world 2025/02/13 01:06:28 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-lohuxod2zuto" 2025/02/13 01:06:28 DEBUG : Creating backend with remote "TestBox:rclone-test-lohuxod2zuto" 2025/02/13 01:06:29 DEBUG : box root 'rclone-test-lohuxod2zuto': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:06:29 DEBUG : Reset feature "ListR" sync_test.go:671: Server side copy (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu' -> Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto' 2025/02/13 01:06:30 DEBUG : box root 'rclone-test-lohuxod2zuto': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:06:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:06:31 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Waiting for checks to finish 2025/02/13 01:06:31 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Waiting for transfers to finish 2025/02/13 01:06:31 DEBUG : sub dir/hello world: copy non-chunked object... 2025/02/13 01:06:31 DEBUG : box root 'rclone-test-lohuxod2zuto': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:06:34 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:06:40 INFO : sub dir/hello world: Deleted 2025/02/13 01:06:41 INFO : sub dir/hello world.rclone_chunk.001_lq09c5: Moved (server-side) to: sub dir/hello world 2025/02/13 01:06:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Using server-side directory move 2025/02/13 01:06:41 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/02/13 01:06:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Waiting for checks to finish 2025/02/13 01:06:42 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/02/13 01:06:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Waiting for transfers to finish 2025/02/13 01:06:43 INFO : sub dir/hello world: Deleted 2025/02/13 01:06:43 DEBUG : sub dir/hello world: move non-chunked object... 2025/02/13 01:06:45 INFO : sub dir/hello world: Moved (server-side) 2025/02/13 01:06:45 INFO : sub dir/hello world: Moved (server-side) 2025/02/13 01:06:51 INFO : sub dir/hello world.rclone_chunk.001_lq0kne: Moved (server-side) to: sub dir/hello world 2025/02/13 01:06:52 DEBUG : testing file moves 2025/02/13 01:06:52 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/02/13 01:06:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Waiting for checks to finish 2025/02/13 01:06:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Waiting for transfers to finish 2025/02/13 01:06:54 INFO : sub dir/hello world: Deleted 2025/02/13 01:06:54 DEBUG : sub dir/hello world: move non-chunked object... 2025/02/13 01:06:55 INFO : sub dir/hello world: Moved (server-side) 2025/02/13 01:06:55 INFO : sub dir/hello world: Moved (server-side) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:06:57 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lohuxod2zuto': Purge remote --- PASS: TestServerSideMoveOverSelf (38.97s) === RUN TestCopyAfterDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:08 INFO : sub dir/hello world.rclone_chunk.001_lq10je: Moved (server-side) to: sub dir/hello world 2025/02/13 01:07:08 ERROR : : error listing: directory not found 2025/02/13 01:07:09 INFO : Local file system at /tmp/rclone2426187867: Making directory 2025/02/13 01:07:10 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:07:10 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyAfterDelete (12.29s) === RUN TestCopyRedownload run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:19 INFO : sub dir/hello world.rclone_chunk.001_lq1c7g: Moved (server-side) to: sub dir/hello world 2025/02/13 01:07:20 DEBUG : Added delayed dir = "sub dir", newDst= 2025/02/13 01:07:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:07:21 DEBUG : Local file system at /tmp/rclone2426187867: Waiting for checks to finish 2025/02/13 01:07:21 DEBUG : Local file system at /tmp/rclone2426187867: Waiting for transfers to finish 2025/02/13 01:07:22 DEBUG : sub dir/hello world.e9ff470d.partial: renamed to: sub dir/hello world 2025/02/13 01:07:22 INFO : sub dir/hello world: Copied (new) 2025/02/13 01:07:22 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyRedownload (10.94s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:26 DEBUG : check sum: Need to transfer - File not found at Destination 2025/02/13 01:07:26 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:07:26 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:07:26 DEBUG : check sum: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:07:29 INFO : check sum.rclone_chunk.001_lq1no8: Moved (server-side) to: check sum 2025/02/13 01:07:29 INFO : check sum: Copied (new) 2025/02/13 01:07:29 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:30 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/02/13 01:07:30 DEBUG : check sum: Size of src and dst objects identical 2025/02/13 01:07:30 DEBUG : check sum: Unchanged skipping 2025/02/13 01:07:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:07:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:07:30 DEBUG : Waiting for deletions to finish 2025/02/13 01:07:30 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncBasedOnCheckSum (7.76s) === RUN TestSyncSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:33 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/02/13 01:07:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:07:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:07:34 DEBUG : sizeonly: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:07:37 INFO : sizeonly.rclone_chunk.001_lq1v4p: Moved (server-side) to: sizeonly 2025/02/13 01:07:37 INFO : sizeonly: Copied (new) 2025/02/13 01:07:37 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:38 DEBUG : sizeonly: Sizes identical 2025/02/13 01:07:38 DEBUG : sizeonly: Unchanged skipping 2025/02/13 01:07:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:07:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:07:38 DEBUG : Waiting for deletions to finish 2025/02/13 01:07:38 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncSizeOnly (7.70s) === RUN TestSyncIgnoreSize run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:41 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/02/13 01:07:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:07:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:07:42 DEBUG : ignore-size: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:07:46 INFO : ignore-size.rclone_chunk.001_lq23r6: Moved (server-side) to: ignore-size 2025/02/13 01:07:46 INFO : ignore-size: Copied (new) 2025/02/13 01:07:46 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:48 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:07:48 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:07:48 DEBUG : ignore-size: Unchanged skipping 2025/02/13 01:07:48 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:07:48 DEBUG : Waiting for deletions to finish 2025/02/13 01:07:48 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncIgnoreSize (13.85s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:07:59 INFO : existing.rclone_chunk.001_lq2gkl: Moved (server-side) to: existing 2025/02/13 01:08:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:08:00 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:08:00 DEBUG : existing: Unchanged skipping 2025/02/13 01:08:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:08:00 DEBUG : Waiting for deletions to finish 2025/02/13 01:08:00 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:08:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:08:00 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/02/13 01:08:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:08:00 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:08:05 INFO : existing: Deleted 2025/02/13 01:08:06 INFO : existing.rclone_chunk.001_lq2l23: Moved (server-side) to: existing 2025/02/13 01:08:06 INFO : existing: Copied (replaced existing) 2025/02/13 01:08:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncIgnoreTimes (19.04s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:08:14 DEBUG : existing: Need to transfer - File not found at Destination 2025/02/13 01:08:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:08:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:08:15 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:08:18 INFO : existing.rclone_chunk.001_lq30lx: Moved (server-side) to: existing 2025/02/13 01:08:18 INFO : existing: Copied (new) 2025/02/13 01:08:18 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:08:19 DEBUG : existing: Destination exists, skipping 2025/02/13 01:08:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:08:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:08:19 DEBUG : Waiting for deletions to finish 2025/02/13 01:08:19 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncIgnoreExisting (8.65s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:08:27 INFO : b/potato.rclone_chunk.001_lq38ey: Moved (server-side) to: b/potato 2025/02/13 01:08:32 INFO : c/non empty space.rclone_chunk.001_lq3czw: Moved (server-side) to: c/non empty space 2025/02/13 01:08:32 INFO : d: Making directory 2025/02/13 01:08:34 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/02/13 01:08:34 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:08:34 DEBUG : c/non empty space: Unchanged skipping 2025/02/13 01:08:34 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:08:34 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:08:34 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:08:40 INFO : a/potato2.rclone_chunk.001_lq3jm4: Moved (server-side) to: a/potato2.rclone_chunk.001 2025/02/13 01:08:40 INFO : a/potato2.rclone_chunk.002_lq3jm4: Moved (server-side) to: a/potato2.rclone_chunk.002 2025/02/13 01:08:42 INFO : a/potato2: Copied (new) 2025/02/13 01:08:42 DEBUG : Waiting for deletions to finish 2025/02/13 01:08:43 INFO : b/potato: Deleted 2025/02/13 01:08:43 INFO : d: Removing directory 2025/02/13 01:08:44 INFO : b: Removing directory 2025/02/13 01:08:45 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': deleted 2 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncIgnoreErrors (32.77s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:09:00 INFO : empty space.rclone_chunk.001_lq450l: Moved (server-side) to: empty space 2025/02/13 01:09:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:09:01 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-02 20:05:06 -0800 -0800 2025/02/13 01:09:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:09:01 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2025/02/13 01:09:01 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:09:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:09:01 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-02 20:05:06 -0800 -0800 2025/02/13 01:09:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:09:01 DEBUG : empty space: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:09:06 INFO : empty space: Deleted 2025/02/13 01:09:07 INFO : empty space.rclone_chunk.001_lq4a2s: Moved (server-side) to: empty space 2025/02/13 01:09:07 INFO : empty space: Copied (replaced existing) 2025/02/13 01:09:07 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncAfterChangingModtimeOnly (15.71s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:1016: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.82s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:09:16 INFO : foo.rclone_chunk.001_lq4l6w: Moved (server-side) to: foo 2025/02/13 01:09:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:09:17 DEBUG : foo: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-02 20:05:06 -0800 -0800 2025/02/13 01:09:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:09:17 DEBUG : foo: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:09:22 INFO : foo: Deleted 2025/02/13 01:09:23 INFO : foo.rclone_chunk.001_lq4qe3: Moved (server-side) to: foo 2025/02/13 01:09:23 INFO : foo: Copied (replaced existing) 2025/02/13 01:09:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncDoesntUpdateModtime (13.68s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:09:29 INFO : empty space.rclone_chunk.001_lq4zkz: Moved (server-side) to: empty space 2025/02/13 01:09:29 DEBUG : potato: Need to transfer - File not found at Destination 2025/02/13 01:09:29 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:09:29 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:09:29 DEBUG : empty space: Unchanged skipping 2025/02/13 01:09:29 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:09:30 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:09:34 INFO : potato.rclone_chunk.001_lq53ba: Moved (server-side) to: potato.rclone_chunk.001 2025/02/13 01:09:35 INFO : potato.rclone_chunk.002_lq53ba: Moved (server-side) to: potato.rclone_chunk.002 2025/02/13 01:09:36 INFO : potato: Copied (new) 2025/02/13 01:09:36 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncAfterAddingAFile (16.89s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:09:48 INFO : potato.rclone_chunk.001_lq5gz5: Moved (server-side) to: potato.rclone_chunk.001 2025/02/13 01:09:48 INFO : potato.rclone_chunk.002_lq5gz5: Moved (server-side) to: potato.rclone_chunk.002 2025/02/13 01:09:51 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/02/13 01:09:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:09:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:09:52 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:10:00 INFO : potato: Deleted 2025/02/13 01:10:01 INFO : potato.rclone_chunk.001_lq5pkf: Moved (server-side) to: potato 2025/02/13 01:10:01 INFO : potato: Copied (replaced existing) 2025/02/13 01:10:01 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncAfterChangingFilesSizeOnly (22.35s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:10:10 INFO : potato.rclone_chunk.001_lq62q9: Moved (server-side) to: potato 2025/02/13 01:10:11 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 04:59:59 -0800 -0800 2025/02/13 01:10:11 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:10:11 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:10:11 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:10:17 INFO : potato: Deleted 2025/02/13 01:10:17 INFO : potato.rclone_chunk.001_lq68dw: Moved (server-side) to: potato 2025/02/13 01:10:17 INFO : potato: Copied (replaced existing) 2025/02/13 01:10:17 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncAfterChangingContentsOnly (15.64s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:10:24 INFO : potato.rclone_chunk.001_lq6iwx: Moved (server-side) to: potato 2025/02/13 01:10:27 INFO : empty space.rclone_chunk.001_lq6l0r: Moved (server-side) to: empty space 2025/02/13 01:10:27 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:10:27 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/02/13 01:10:27 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:10:27 DEBUG : empty space: Unchanged skipping 2025/02/13 01:10:27 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:10:27 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:10:27 DEBUG : Waiting for deletions to finish 2025/02/13 01:10:27 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (10.49s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:10:34 INFO : potato.rclone_chunk.001_lq6sxw: Moved (server-side) to: potato 2025/02/13 01:10:38 INFO : empty space.rclone_chunk.001_lq6w3p: Moved (server-side) to: empty space 2025/02/13 01:10:38 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:10:38 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:10:38 DEBUG : empty space: Unchanged skipping 2025/02/13 01:10:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:10:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:10:39 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:10:43 INFO : potato2.rclone_chunk.001_lq70sb: Moved (server-side) to: potato2.rclone_chunk.001 2025/02/13 01:10:43 INFO : potato2.rclone_chunk.002_lq70sb: Moved (server-side) to: potato2.rclone_chunk.002 2025/02/13 01:10:45 INFO : potato2: Copied (new) 2025/02/13 01:10:45 DEBUG : Waiting for deletions to finish 2025/02/13 01:10:46 INFO : potato: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (22.68s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:10:59 INFO : b/potato.rclone_chunk.001_lq7f10: Moved (server-side) to: b/potato 2025/02/13 01:11:04 INFO : c/non empty space.rclone_chunk.001_lq7k3x: Moved (server-side) to: c/non empty space 2025/02/13 01:11:04 INFO : d: Making directory 2025/02/13 01:11:05 INFO : d/e: Making directory 2025/02/13 01:11:08 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/02/13 01:11:09 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:11:09 DEBUG : c/non empty space: Unchanged skipping 2025/02/13 01:11:09 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:11:10 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:11:10 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:11:15 INFO : a/potato2.rclone_chunk.001_lq7uck: Moved (server-side) to: a/potato2.rclone_chunk.001 2025/02/13 01:11:16 INFO : a/potato2.rclone_chunk.002_lq7uck: Moved (server-side) to: a/potato2.rclone_chunk.002 2025/02/13 01:11:17 INFO : a/potato2: Copied (new) 2025/02/13 01:11:17 DEBUG : Waiting for deletions to finish 2025/02/13 01:11:18 INFO : b/potato: Deleted 2025/02/13 01:11:18 INFO : d/e: Removing directory 2025/02/13 01:11:19 INFO : d: Removing directory 2025/02/13 01:11:20 INFO : b: Removing directory 2025/02/13 01:11:21 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': deleted 3 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (36.93s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:11:35 INFO : b/potato.rclone_chunk.001_lq8gu0: Moved (server-side) to: b/potato 2025/02/13 01:11:39 INFO : c/non empty space.rclone_chunk.001_lq8ka5: Moved (server-side) to: c/non empty space 2025/02/13 01:11:39 INFO : d: Making directory 2025/02/13 01:11:42 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/02/13 01:11:42 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:11:42 DEBUG : c/non empty space: Unchanged skipping 2025/02/13 01:11:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:11:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:11:42 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:11:48 INFO : a/potato2.rclone_chunk.001_lq8rme: Moved (server-side) to: a/potato2.rclone_chunk.001 2025/02/13 01:11:49 INFO : a/potato2.rclone_chunk.002_lq8rme: Moved (server-side) to: a/potato2.rclone_chunk.002 2025/02/13 01:11:51 INFO : a/potato2: Copied (new) 2025/02/13 01:11:51 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': not deleting files as there were IO errors 2025/02/13 01:11:51 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:12:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2025/02/13 01:12:07 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway" (502)) 2025/02/13 01:12:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/02/13 01:12:08 DEBUG : pacer: Reducing sleep to 15ms 2025/02/13 01:12:09 DEBUG : pacer: Reducing sleep to 11.25ms 2025/02/13 01:12:09 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (38.83s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:12:13 INFO : potato.rclone_chunk.001_lq9j1m: Moved (server-side) to: potato 2025/02/13 01:12:17 INFO : empty space.rclone_chunk.001_lq9nrp: Moved (server-side) to: empty space 2025/02/13 01:12:17 DEBUG : Waiting for deletions to finish 2025/02/13 01:12:18 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:12:18 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:12:18 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:12:18 DEBUG : empty space: Unchanged skipping 2025/02/13 01:12:18 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:12:18 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:12:19 INFO : potato: Deleted 2025/02/13 01:12:23 INFO : potato2.rclone_chunk.001_lq9rdl: Moved (server-side) to: potato2.rclone_chunk.001 2025/02/13 01:12:24 INFO : potato2.rclone_chunk.002_lq9rdl: Moved (server-side) to: potato2.rclone_chunk.002 2025/02/13 01:12:25 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncDeleteDuring (21.69s) === RUN TestSyncDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:12:34 INFO : potato.rclone_chunk.001_lqa465: Moved (server-side) to: potato 2025/02/13 01:12:38 INFO : empty space.rclone_chunk.001_lqa8rg: Moved (server-side) to: empty space 2025/02/13 01:12:38 DEBUG : Waiting for deletions to finish 2025/02/13 01:12:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:12:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:12:39 INFO : potato: Deleted 2025/02/13 01:12:40 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:12:40 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:12:40 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:12:40 DEBUG : empty space: Unchanged skipping 2025/02/13 01:12:40 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:12:40 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:12:45 INFO : potato2.rclone_chunk.001_lqad41: Moved (server-side) to: potato2.rclone_chunk.001 2025/02/13 01:12:46 INFO : potato2.rclone_chunk.002_lqad41: Moved (server-side) to: potato2.rclone_chunk.002 2025/02/13 01:12:48 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncDeleteBefore (24.38s) === RUN TestCopyDeleteBefore run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:12:59 INFO : potato.rclone_chunk.001_lqat6m: Moved (server-side) to: potato 2025/02/13 01:13:00 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:13:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:13:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:13:01 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:13:04 INFO : potato2.rclone_chunk.001_lqayd7: Moved (server-side) to: potato2 2025/02/13 01:13:04 INFO : potato2: Copied (new) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestCopyDeleteBefore (13.31s) === RUN TestSyncWithExclude run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:13:14 INFO : potato2.rclone_chunk.001_lqb6i9: Moved (server-side) to: potato2.rclone_chunk.001 2025/02/13 01:13:15 INFO : potato2.rclone_chunk.002_lqb6i9: Moved (server-side) to: potato2.rclone_chunk.002 2025/02/13 01:13:20 INFO : empty space.rclone_chunk.001_lqberv: Moved (server-side) to: empty space 2025/02/13 01:13:21 DEBUG : enormous: Excluded (Size Filter) 2025/02/13 01:13:21 DEBUG : enormous: Excluded 2025/02/13 01:13:21 DEBUG : potato2: Excluded (Size Filter) 2025/02/13 01:13:21 DEBUG : potato2: Excluded 2025/02/13 01:13:21 DEBUG : potato2: Excluded (Size Filter) 2025/02/13 01:13:21 DEBUG : potato2: Excluded 2025/02/13 01:13:21 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:13:21 DEBUG : empty space: Unchanged skipping 2025/02/13 01:13:21 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:13:21 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:13:21 DEBUG : Waiting for deletions to finish 2025/02/13 01:13:21 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:13:22 DEBUG : enormous: Excluded (Size Filter) 2025/02/13 01:13:22 DEBUG : enormous: Excluded 2025/02/13 01:13:22 DEBUG : potato2: Excluded (Size Filter) 2025/02/13 01:13:22 DEBUG : potato2: Excluded 2025/02/13 01:13:22 DEBUG : potato2: Excluded (Size Filter) 2025/02/13 01:13:22 DEBUG : potato2: Excluded 2025/02/13 01:13:22 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2025/02/13 01:13:22 DEBUG : empty space: Unchanged skipping 2025/02/13 01:13:22 DEBUG : Local file system at /tmp/rclone2426187867: Waiting for checks to finish 2025/02/13 01:13:22 DEBUG : Local file system at /tmp/rclone2426187867: Waiting for transfers to finish 2025/02/13 01:13:22 DEBUG : Waiting for deletions to finish 2025/02/13 01:13:22 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncWithExclude (19.85s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:13:33 INFO : potato2.rclone_chunk.001_lqbqbi: Moved (server-side) to: potato2.rclone_chunk.001 2025/02/13 01:13:33 INFO : potato2.rclone_chunk.002_lqbqbi: Moved (server-side) to: potato2.rclone_chunk.002 2025/02/13 01:13:38 INFO : empty space.rclone_chunk.001_lqbw31: Moved (server-side) to: empty space 2025/02/13 01:13:43 INFO : enormous.rclone_chunk.001_lqbzlq: Moved (server-side) to: enormous.rclone_chunk.001 2025/02/13 01:13:43 INFO : enormous.rclone_chunk.002_lqbzlq: Moved (server-side) to: enormous.rclone_chunk.002 2025/02/13 01:13:45 DEBUG : enormous: Excluded (Size Filter) 2025/02/13 01:13:45 DEBUG : enormous: Excluded 2025/02/13 01:13:45 DEBUG : potato2: Excluded (Size Filter) 2025/02/13 01:13:45 DEBUG : potato2: Excluded 2025/02/13 01:13:46 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:13:46 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:13:46 DEBUG : empty space: Unchanged skipping 2025/02/13 01:13:46 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:13:46 DEBUG : Waiting for deletions to finish 2025/02/13 01:13:50 INFO : enormous: Deleted 2025/02/13 01:13:50 INFO : potato2: Deleted 2025/02/13 01:13:50 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:13:51 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2025/02/13 01:13:51 DEBUG : Local file system at /tmp/rclone2426187867: Waiting for checks to finish 2025/02/13 01:13:51 DEBUG : empty space: Unchanged skipping 2025/02/13 01:13:51 DEBUG : Local file system at /tmp/rclone2426187867: Waiting for transfers to finish 2025/02/13 01:13:51 DEBUG : Waiting for deletions to finish 2025/02/13 01:13:51 INFO : potato2: Deleted 2025/02/13 01:13:51 INFO : enormous: Deleted 2025/02/13 01:13:51 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncWithExcludeAndDeleteExcluded (26.97s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:13:59 INFO : one.rclone_chunk.001_lqchgk: Moved (server-side) to: one 2025/02/13 01:14:03 INFO : two.rclone_chunk.001_lqcl9g: Moved (server-side) to: two 2025/02/13 01:14:08 INFO : three.rclone_chunk.001_lqcpue: Moved (server-side) to: three 2025/02/13 01:14:12 INFO : four.rclone_chunk.001_lqcth4: Moved (server-side) to: four 2025/02/13 01:14:13 DEBUG : five: Need to transfer - File not found at Destination 2025/02/13 01:14:13 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/02/13 01:14:13 DEBUG : one: Destination is newer than source, skipping 2025/02/13 01:14:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:14:13 DEBUG : three: Sizes identical 2025/02/13 01:14:13 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/02/13 01:14:13 DEBUG : four: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:13 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/02/13 01:14:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:14:13 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:13 DEBUG : five: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:17 INFO : five.rclone_chunk.001_lqcyqi: Moved (server-side) to: five 2025/02/13 01:14:17 INFO : five: Copied (new) 2025/02/13 01:14:18 INFO : two: Deleted 2025/02/13 01:14:18 INFO : four: Deleted 2025/02/13 01:14:18 INFO : two.rclone_chunk.001_lqcyp3: Moved (server-side) to: two 2025/02/13 01:14:18 INFO : two: Copied (replaced existing) 2025/02/13 01:14:18 INFO : four.rclone_chunk.001_lqcyzm: Moved (server-side) to: four 2025/02/13 01:14:18 INFO : four: Copied (replaced existing) 2025/02/13 01:14:18 DEBUG : Waiting for deletions to finish sync_test.go:1449: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (28.38s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1466: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1466: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:1538: Can track renames: false 2025/02/13 01:14:24 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Ignoring --track-renames as the source and destination do not have a common hash 2025/02/13 01:14:24 DEBUG : potato: Need to transfer - File not found at Destination 2025/02/13 01:14:24 DEBUG : yam: Need to transfer - File not found at Destination 2025/02/13 01:14:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:14:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:14:24 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:24 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:27 INFO : yam.rclone_chunk.001_lqd9ky: Moved (server-side) to: yam 2025/02/13 01:14:27 INFO : yam: Copied (new) 2025/02/13 01:14:27 INFO : potato.rclone_chunk.001_lqd9jr: Moved (server-side) to: potato 2025/02/13 01:14:27 INFO : potato: Copied (new) 2025/02/13 01:14:27 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:14:28 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Ignoring --track-renames as the source and destination do not have a common hash 2025/02/13 01:14:28 DEBUG : yaml: Need to transfer - File not found at Destination 2025/02/13 01:14:28 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:14:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:14:28 DEBUG : potato: Unchanged skipping 2025/02/13 01:14:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:14:29 DEBUG : yaml: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:32 INFO : yaml.rclone_chunk.001_lqdekf: Moved (server-side) to: yaml 2025/02/13 01:14:32 INFO : yaml: Copied (new) 2025/02/13 01:14:32 DEBUG : Waiting for deletions to finish 2025/02/13 01:14:32 INFO : yam: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncWithTrackRenames (12.94s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:1610: Can track renames: true 2025/02/13 01:14:37 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Making map for --track-renames 2025/02/13 01:14:37 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Finished making map for --track-renames 2025/02/13 01:14:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:14:37 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/02/13 01:14:37 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/02/13 01:14:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for renames to finish 2025/02/13 01:14:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:14:37 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:37 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:40 INFO : yam.rclone_chunk.001_lqdmva: Moved (server-side) to: yam 2025/02/13 01:14:40 INFO : yam: Copied (new) 2025/02/13 01:14:40 INFO : potato.rclone_chunk.001_lqdmr4: Moved (server-side) to: potato 2025/02/13 01:14:40 INFO : potato: Copied (new) 2025/02/13 01:14:40 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:14:41 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Making map for --track-renames 2025/02/13 01:14:41 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:14:41 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Finished making map for --track-renames 2025/02/13 01:14:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:14:41 DEBUG : potato: Unchanged skipping 2025/02/13 01:14:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for renames to finish 2025/02/13 01:14:41 DEBUG : yam: move non-chunked object... 2025/02/13 01:14:42 INFO : yam: Moved (server-side) to: yaml 2025/02/13 01:14:42 INFO : yam: Moved (server-side) to: yaml 2025/02/13 01:14:42 INFO : yaml: Renamed from "yam" 2025/02/13 01:14:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:14:42 DEBUG : Waiting for deletions to finish 2025/02/13 01:14:42 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncWithTrackRenamesStrategyModtime (10.10s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:1649: Can track renames: true 2025/02/13 01:14:47 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Making map for --track-renames 2025/02/13 01:14:47 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Finished making map for --track-renames 2025/02/13 01:14:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:14:47 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/02/13 01:14:47 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/02/13 01:14:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for renames to finish 2025/02/13 01:14:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:14:48 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:48 DEBUG : sub/yam: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:14:53 INFO : sub/yam.rclone_chunk.001_lqdxlt: Moved (server-side) to: sub/yam 2025/02/13 01:14:53 INFO : sub/yam: Copied (new) 2025/02/13 01:14:53 INFO : potato.rclone_chunk.001_lqdx4w: Moved (server-side) to: potato 2025/02/13 01:14:53 INFO : potato: Copied (new) 2025/02/13 01:14:53 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:14:55 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:14:55 DEBUG : potato: Unchanged skipping 2025/02/13 01:14:55 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Making map for --track-renames 2025/02/13 01:14:55 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Finished making map for --track-renames 2025/02/13 01:14:55 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:14:55 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for renames to finish 2025/02/13 01:14:55 DEBUG : sub/yam: move non-chunked object... 2025/02/13 01:14:56 INFO : sub/yam: Moved (server-side) to: yam 2025/02/13 01:14:56 INFO : sub/yam: Moved (server-side) to: yam 2025/02/13 01:14:56 INFO : yam: Renamed from "sub/yam" 2025/02/13 01:14:56 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:14:56 DEBUG : Waiting for deletions to finish 2025/02/13 01:14:56 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (16.33s) === RUN TestServerSideMoveLocal run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:15:03 DEBUG : Creating backend with remote "/tmp/rclone2426187867/dir1" 2025/02/13 01:15:03 DEBUG : Config file has changed externally - reloading 2025/02/13 01:15:03 DEBUG : Creating backend with remote "/tmp/rclone2426187867/dir2" 2025/02/13 01:15:03 DEBUG : Local file system at /tmp/rclone2426187867/dir2: Using server-side directory move 2025/02/13 01:15:03 INFO : Local file system at /tmp/rclone2426187867/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/02/13 01:15:03 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/02/13 01:15:03 INFO : file1.txt: Moved (server-side) 2025/02/13 01:15:03 DEBUG : Local file system at /tmp/rclone2426187867/dir2: Waiting for checks to finish 2025/02/13 01:15:03 DEBUG : Local file system at /tmp/rclone2426187867/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.80s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:15:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:15:04 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/02/13 01:15:04 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:15:04 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:15:05 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:15:06 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:15:13 INFO : sub dir/hello world.rclone_chunk.001_lqeexj: Moved (server-side) to: sub dir/hello world 2025/02/13 01:15:13 INFO : sub dir/hello world: Copied (new) 2025/02/13 01:15:13 INFO : sub dir/hello world: Deleted 2025/02/13 01:15:13 INFO : nested/sub dir/file.rclone_chunk.001_lqefbr: Moved (server-side) to: nested/sub dir/file 2025/02/13 01:15:13 INFO : nested/sub dir/file: Copied (new) 2025/02/13 01:15:13 INFO : nested/sub dir/file: Deleted 2025/02/13 01:15:13 INFO : sub dir: Removing directory 2025/02/13 01:15:13 INFO : nested/sub dir: Removing directory 2025/02/13 01:15:13 INFO : nested: Removing directory 2025/02/13 01:15:13 DEBUG : Local file system at /tmp/rclone2426187867: deleted 3 directories run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestMoveWithDeleteEmptySrcDirs (17.55s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:15:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/02/13 01:15:22 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/02/13 01:15:22 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:15:22 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:15:22 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:15:23 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:15:29 INFO : nested/sub dir/file.rclone_chunk.001_lqew7c: Moved (server-side) to: nested/sub dir/file 2025/02/13 01:15:29 INFO : nested/sub dir/file: Copied (new) 2025/02/13 01:15:29 INFO : nested/sub dir/file: Deleted 2025/02/13 01:15:29 INFO : sub dir/hello world.rclone_chunk.001_lqevj3: Moved (server-side) to: sub dir/hello world 2025/02/13 01:15:29 INFO : sub dir/hello world: Copied (new) 2025/02/13 01:15:29 INFO : sub dir/hello world: Deleted run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (14.92s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:15:37 DEBUG : existing: Need to transfer - File not found at Destination 2025/02/13 01:15:37 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/02/13 01:15:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:15:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:15:37 DEBUG : existing-b: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:15:37 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:15:40 INFO : existing.rclone_chunk.001_lqfain: Moved (server-side) to: existing 2025/02/13 01:15:40 INFO : existing: Copied (new) 2025/02/13 01:15:40 INFO : existing: Deleted 2025/02/13 01:15:40 INFO : existing-b.rclone_chunk.001_lqfala: Moved (server-side) to: existing-b 2025/02/13 01:15:40 INFO : existing-b: Copied (new) 2025/02/13 01:15:40 INFO : existing-b: Deleted 2025/02/13 01:15:41 DEBUG : existing: Destination exists, skipping 2025/02/13 01:15:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:15:41 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/02/13 01:15:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:15:41 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestMoveWithIgnoreExisting (8.63s) === RUN TestServerSideMove run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:15:45 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-gubanoc1subo" 2025/02/13 01:15:45 DEBUG : Config file has changed externally - reloading 2025/02/13 01:15:45 DEBUG : Creating backend with remote "TestBox:rclone-test-gubanoc1subo" 2025/02/13 01:15:46 DEBUG : box root 'rclone-test-gubanoc1subo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:15:47 DEBUG : Reset feature "ListR" 2025/02/13 01:15:52 INFO : potato2.rclone_chunk.001_lqfktn: Moved (server-side) to: potato2.rclone_chunk.001 2025/02/13 01:15:53 INFO : potato2.rclone_chunk.002_lqfktn: Moved (server-side) to: potato2.rclone_chunk.002 2025/02/13 01:16:00 INFO : empty space.rclone_chunk.001_lqftud: Moved (server-side) to: empty space 2025/02/13 01:16:06 INFO : potato3.rclone_chunk.001_lqfxuw: Moved (server-side) to: potato3.rclone_chunk.001 2025/02/13 01:16:07 INFO : potato3.rclone_chunk.002_lqfxuw: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu' -> Chunked 'TestChunkerChunk50bBox:rclone-test-gubanoc1subo' 2025/02/13 01:16:10 DEBUG : box root 'rclone-test-gubanoc1subo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:16:15 INFO : empty space.rclone_chunk.001_lqg8j7: Moved (server-side) to: empty space 2025/02/13 01:16:20 INFO : potato3.rclone_chunk.001_lqgcsd: Moved (server-side) to: potato3.rclone_chunk.001 2025/02/13 01:16:20 INFO : potato3.rclone_chunk.002_lqgcsd: Moved (server-side) to: potato3.rclone_chunk.002 2025/02/13 01:16:22 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-gubanoc1subo': Using server-side directory move 2025/02/13 01:16:22 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-gubanoc1subo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/02/13 01:16:22 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:16:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/02/13 01:16:22 DEBUG : empty space: Unchanged skipping 2025/02/13 01:16:22 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/02/13 01:16:22 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-gubanoc1subo': Waiting for checks to finish 2025/02/13 01:16:23 DEBUG : potato2: move 2 data chunks... 2025/02/13 01:16:23 INFO : empty space: Deleted 2025/02/13 01:16:23 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-gubanoc1subo': Waiting for transfers to finish 2025/02/13 01:16:24 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/02/13 01:16:25 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/02/13 01:16:26 INFO : potato3: Deleted 2025/02/13 01:16:26 INFO : potato2: Moved (server-side) 2025/02/13 01:16:27 DEBUG : potato3: move 2 data chunks... 2025/02/13 01:16:28 INFO : potato2: Moved (server-side) 2025/02/13 01:16:28 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/02/13 01:16:29 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/02/13 01:16:30 INFO : potato3: Moved (server-side) 2025/02/13 01:16:31 INFO : potato3: Moved (server-side) 2025/02/13 01:16:32 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-ripudok6jade" 2025/02/13 01:16:32 DEBUG : Config file has changed externally - reloading 2025/02/13 01:16:32 DEBUG : Creating backend with remote "TestBox:rclone-test-ripudok6jade" 2025/02/13 01:16:32 DEBUG : box root 'rclone-test-ripudok6jade': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:16:32 DEBUG : Reset feature "ListR" 2025/02/13 01:16:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-ripudok6jade': Using server-side directory move 2025/02/13 01:16:33 DEBUG : box root 'rclone-test-ripudok6jade': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:16:33 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-ripudok6jade': Server side directory move succeeded 2025/02/13 01:16:35 DEBUG : box root 'rclone-test-gubanoc1subo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:16:35 ERROR : : error listing: directory not found 2025/02/13 01:16:35 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-ripudok6jade': Purge remote 2025/02/13 01:16:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-gubanoc1subo': Purge remote 2025/02/13 01:16:38 DEBUG : box root 'rclone-test-gubanoc1subo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:16:38 NOTICE: purge failed: directory not found --- PASS: TestServerSideMove (53.20s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:16:38 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-xinaxoz3teso" 2025/02/13 01:16:38 DEBUG : Creating backend with remote "TestBox:rclone-test-xinaxoz3teso" 2025/02/13 01:16:39 DEBUG : box root 'rclone-test-xinaxoz3teso': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:16:39 DEBUG : Reset feature "ListR" 2025/02/13 01:16:44 INFO : potato2.rclone_chunk.001_lqh19v: Moved (server-side) to: potato2.rclone_chunk.001 2025/02/13 01:16:45 INFO : potato2.rclone_chunk.002_lqh19v: Moved (server-side) to: potato2.rclone_chunk.002 2025/02/13 01:16:51 INFO : empty space.rclone_chunk.001_lqh8zr: Moved (server-side) to: empty space 2025/02/13 01:16:57 INFO : potato3.rclone_chunk.001_lqhdsr: Moved (server-side) to: potato3.rclone_chunk.001 2025/02/13 01:16:58 INFO : potato3.rclone_chunk.002_lqhdsr: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu' -> Chunked 'TestChunkerChunk50bBox:rclone-test-xinaxoz3teso' 2025/02/13 01:17:01 DEBUG : box root 'rclone-test-xinaxoz3teso': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:17:06 INFO : empty space.rclone_chunk.001_lqhn7s: Moved (server-side) to: empty space 2025/02/13 01:17:12 INFO : potato3.rclone_chunk.001_lqhsur: Moved (server-side) to: potato3.rclone_chunk.001 2025/02/13 01:17:12 INFO : potato3.rclone_chunk.002_lqhsur: Moved (server-side) to: potato3.rclone_chunk.002 2025/02/13 01:17:15 DEBUG : empty space: Excluded (Size Filter) 2025/02/13 01:17:15 DEBUG : empty space: Excluded 2025/02/13 01:17:15 DEBUG : empty space: Excluded (Size Filter) 2025/02/13 01:17:15 DEBUG : empty space: Excluded 2025/02/13 01:17:15 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:17:15 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/02/13 01:17:15 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-xinaxoz3teso': Waiting for checks to finish 2025/02/13 01:17:15 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-xinaxoz3teso': Waiting for transfers to finish 2025/02/13 01:17:16 DEBUG : potato2: move 2 data chunks... 2025/02/13 01:17:17 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/02/13 01:17:18 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/02/13 01:17:19 INFO : potato2: Moved (server-side) 2025/02/13 01:17:20 INFO : potato3: Deleted 2025/02/13 01:17:21 INFO : potato2: Moved (server-side) 2025/02/13 01:17:21 DEBUG : potato3: move 2 data chunks... 2025/02/13 01:17:22 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/02/13 01:17:23 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/02/13 01:17:24 INFO : potato3: Moved (server-side) 2025/02/13 01:17:25 INFO : potato3: Moved (server-side) 2025/02/13 01:17:26 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-ragifuw4zuzo" 2025/02/13 01:17:26 DEBUG : Creating backend with remote "TestBox:rclone-test-ragifuw4zuzo" 2025/02/13 01:17:26 DEBUG : box root 'rclone-test-ragifuw4zuzo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:17:27 DEBUG : Reset feature "ListR" 2025/02/13 01:17:27 DEBUG : empty space: Excluded (Size Filter) 2025/02/13 01:17:27 DEBUG : empty space: Excluded 2025/02/13 01:17:28 DEBUG : box root 'rclone-test-ragifuw4zuzo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:17:28 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:17:28 DEBUG : potato3: Need to transfer - File not found at Destination 2025/02/13 01:17:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-ragifuw4zuzo': Waiting for checks to finish 2025/02/13 01:17:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-ragifuw4zuzo': Waiting for transfers to finish 2025/02/13 01:17:28 DEBUG : potato2: move 2 data chunks... 2025/02/13 01:17:28 DEBUG : potato3: move 2 data chunks... 2025/02/13 01:17:29 DEBUG : box root 'rclone-test-ragifuw4zuzo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:17:30 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/02/13 01:17:30 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/02/13 01:17:31 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/02/13 01:17:31 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/02/13 01:17:32 INFO : potato3: Moved (server-side) 2025/02/13 01:17:32 INFO : potato2: Moved (server-side) 2025/02/13 01:17:33 INFO : potato3: Moved (server-side) 2025/02/13 01:17:34 INFO : potato2: Moved (server-side) 2025/02/13 01:17:35 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-ragifuw4zuzo': Purge remote 2025/02/13 01:17:36 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-xinaxoz3teso': Purge remote --- PASS: TestServerSideMoveWithFilter (60.81s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:17:39 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-codiras8buwo" 2025/02/13 01:17:39 DEBUG : Config file has changed externally - reloading 2025/02/13 01:17:39 DEBUG : Creating backend with remote "TestBox:rclone-test-codiras8buwo" 2025/02/13 01:17:40 DEBUG : box root 'rclone-test-codiras8buwo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:17:40 DEBUG : Reset feature "ListR" 2025/02/13 01:17:45 INFO : potato2.rclone_chunk.001_lqips0: Moved (server-side) to: potato2.rclone_chunk.001 2025/02/13 01:17:46 INFO : potato2.rclone_chunk.002_lqips0: Moved (server-side) to: potato2.rclone_chunk.002 2025/02/13 01:17:52 INFO : empty space.rclone_chunk.001_lqixrk: Moved (server-side) to: empty space 2025/02/13 01:17:58 INFO : potato3.rclone_chunk.001_lqj2qr: Moved (server-side) to: potato3.rclone_chunk.001 2025/02/13 01:17:58 INFO : potato3.rclone_chunk.002_lqj2qr: Moved (server-side) to: potato3.rclone_chunk.002 2025/02/13 01:18:00 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu' -> Chunked 'TestChunkerChunk50bBox:rclone-test-codiras8buwo' 2025/02/13 01:18:03 DEBUG : box root 'rclone-test-codiras8buwo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:18:07 INFO : empty space.rclone_chunk.001_lqjdrw: Moved (server-side) to: empty space 2025/02/13 01:18:14 INFO : potato3.rclone_chunk.001_lqjhb3: Moved (server-side) to: potato3.rclone_chunk.001 2025/02/13 01:18:14 INFO : potato3.rclone_chunk.002_lqjhb3: Moved (server-side) to: potato3.rclone_chunk.002 2025/02/13 01:18:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-codiras8buwo': Using server-side directory move 2025/02/13 01:18:17 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-codiras8buwo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/02/13 01:18:17 DEBUG : potato2: Need to transfer - File not found at Destination 2025/02/13 01:18:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/02/13 01:18:17 DEBUG : empty space: Unchanged skipping 2025/02/13 01:18:17 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/02/13 01:18:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-codiras8buwo': Waiting for checks to finish 2025/02/13 01:18:18 DEBUG : potato2: move 2 data chunks... 2025/02/13 01:18:18 INFO : empty space: Deleted 2025/02/13 01:18:18 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-codiras8buwo': Waiting for transfers to finish 2025/02/13 01:18:19 INFO : potato2.rclone_chunk.001: Moved (server-side) 2025/02/13 01:18:20 INFO : potato2.rclone_chunk.002: Moved (server-side) 2025/02/13 01:18:21 INFO : potato3: Deleted 2025/02/13 01:18:21 INFO : potato2: Moved (server-side) 2025/02/13 01:18:21 DEBUG : potato3: move 2 data chunks... 2025/02/13 01:18:22 INFO : potato2: Moved (server-side) 2025/02/13 01:18:22 INFO : potato3.rclone_chunk.001: Moved (server-side) 2025/02/13 01:18:23 INFO : potato3.rclone_chunk.002: Moved (server-side) 2025/02/13 01:18:24 INFO : potato3: Moved (server-side) 2025/02/13 01:18:25 INFO : potato3: Moved (server-side) 2025/02/13 01:18:25 INFO : tomatoDir: Removing directory 2025/02/13 01:18:26 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': deleted 1 directories 2025/02/13 01:18:27 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-cafutir0ruha" 2025/02/13 01:18:27 DEBUG : Config file has changed externally - reloading 2025/02/13 01:18:27 DEBUG : Creating backend with remote "TestBox:rclone-test-cafutir0ruha" 2025/02/13 01:18:28 DEBUG : box root 'rclone-test-cafutir0ruha': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:18:28 DEBUG : Reset feature "ListR" 2025/02/13 01:18:28 INFO : tomatoDir: Making directory 2025/02/13 01:18:29 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-cafutir0ruha': Using server-side directory move 2025/02/13 01:18:30 DEBUG : box root 'rclone-test-cafutir0ruha': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:18:30 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-cafutir0ruha': Server side directory move succeeded 2025/02/13 01:18:32 DEBUG : box root 'rclone-test-codiras8buwo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:18:32 ERROR : : error listing: directory not found 2025/02/13 01:18:32 DEBUG : box root 'rclone-test-codiras8buwo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:18:32 ERROR : : error listing: directory not found 2025/02/13 01:18:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-cafutir0ruha': Purge remote 2025/02/13 01:18:35 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-codiras8buwo': Purge remote 2025/02/13 01:18:35 DEBUG : box root 'rclone-test-codiras8buwo': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2025/02/13 01:18:35 NOTICE: purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (56.94s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:1910: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.72s) === RUN TestSyncOverlap run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:18:37 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test" 2025/02/13 01:18:37 DEBUG : Config file has changed externally - reloading 2025/02/13 01:18:37 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/rclone-sync-test" 2025/02/13 01:18:38 DEBUG : Reset feature "ListR" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncOverlap (5.24s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:18:42 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test" 2025/02/13 01:18:42 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/rclone-sync-test" 2025/02/13 01:18:44 DEBUG : Reset feature "ListR" 2025/02/13 01:18:46 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test-include/layer2" 2025/02/13 01:18:46 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/rclone-sync-test-include/layer2" 2025/02/13 01:18:48 DEBUG : Reset feature "ListR" 2025/02/13 01:18:50 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test-ignore-file" 2025/02/13 01:18:50 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/rclone-sync-test-ignore-file" 2025/02/13 01:18:53 DEBUG : Reset feature "ListR" 2025/02/13 01:18:58 INFO : rclone-sync-test-ignore-file/.ignore.rclone_chunk.001_lqks8c: Moved (server-side) to: rclone-sync-test-ignore-file/.ignore 2025/02/13 01:19:00 DEBUG : rclone-sync-test: Excluded 2025/02/13 01:19:01 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/02/13 01:19:03 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/02/13 01:19:03 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test': Waiting for checks to finish 2025/02/13 01:19:03 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test': Waiting for transfers to finish 2025/02/13 01:19:03 DEBUG : Waiting for deletions to finish 2025/02/13 01:19:03 INFO : There was nothing to transfer 2025/02/13 01:19:04 DEBUG : rclone-sync-test: Excluded 2025/02/13 01:19:05 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/02/13 01:19:06 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/02/13 01:19:06 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:19:06 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:19:06 DEBUG : Waiting for deletions to finish 2025/02/13 01:19:06 INFO : rclone-sync-test-include: Removing directory 2025/02/13 01:19:07 DEBUG : rclone-sync-test-include: Failed to Rmdir: rmdir failed: Error "folder_not_empty" (400): Cannot delete - folder not empty 2025/02/13 01:19:07 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': failed to delete 1 directories 2025/02/13 01:19:07 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:19:08 DEBUG : rclone-sync-test: Excluded 2025/02/13 01:19:09 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/02/13 01:19:10 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/02/13 01:19:10 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/02/13 01:19:10 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/02/13 01:19:10 DEBUG : Waiting for deletions to finish 2025/02/13 01:19:10 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:19:12 DEBUG : rclone-sync-test: Excluded 2025/02/13 01:19:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/02/13 01:19:14 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/02/13 01:19:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:19:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:19:14 DEBUG : Waiting for deletions to finish 2025/02/13 01:19:14 INFO : rclone-sync-test-include: Removing directory 2025/02/13 01:19:14 DEBUG : rclone-sync-test-include: Failed to Rmdir: rmdir failed: Error "folder_not_empty" (400): Cannot delete - folder not empty 2025/02/13 01:19:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': failed to delete 1 directories 2025/02/13 01:19:14 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:19:16 DEBUG : : Excluded 2025/02/13 01:19:16 DEBUG : rclone-sync-test: Excluded 2025/02/13 01:19:18 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/02/13 01:19:19 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/02/13 01:19:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/02/13 01:19:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/02/13 01:19:19 DEBUG : Waiting for deletions to finish 2025/02/13 01:19:19 INFO : There was nothing to transfer run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncOverlapWithFilter (54.62s) === RUN TestSyncCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:19:37 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:19:37 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:19:39 DEBUG : Reset feature "ListR" 2025/02/13 01:19:39 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/CompareDest" 2025/02/13 01:19:39 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/CompareDest" 2025/02/13 01:19:41 DEBUG : Reset feature "ListR" 2025/02/13 01:19:42 DEBUG : one: Need to transfer - File not found at Destination 2025/02/13 01:19:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:19:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:19:42 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:19:48 INFO : one.rclone_chunk.001_lqm3xg: Moved (server-side) to: one 2025/02/13 01:19:48 INFO : one: Copied (new) 2025/02/13 01:19:48 DEBUG : Waiting for deletions to finish 2025/02/13 01:19:50 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:19:50 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/13 01:19:50 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:19:50 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:19:55 INFO : one: Deleted 2025/02/13 01:19:56 INFO : one.rclone_chunk.001_lqmbv0: Moved (server-side) to: one 2025/02/13 01:19:56 INFO : one: Copied (replaced existing) 2025/02/13 01:19:56 DEBUG : Waiting for deletions to finish 2025/02/13 01:20:03 INFO : dst/one: Deleted 2025/02/13 01:20:04 INFO : dst/one.rclone_chunk.001_lqmjs3: Moved (server-side) to: dst/one 2025/02/13 01:20:09 INFO : CompareDest/one.rclone_chunk.001_lqmpgt: Moved (server-side) to: CompareDest/one 2025/02/13 01:20:11 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/13 01:20:11 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:20:12 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:20:12 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/13 01:20:12 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:20:12 DEBUG : Waiting for deletions to finish 2025/02/13 01:20:12 INFO : There was nothing to transfer 2025/02/13 01:20:17 INFO : CompareDest/two.rclone_chunk.001_lqmy9s: Moved (server-side) to: CompareDest/two 2025/02/13 01:20:19 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:20:19 DEBUG : two: Destination found in --compare-dest, skipping 2025/02/13 01:20:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/13 01:20:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:20:20 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:20:20 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/13 01:20:20 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:20:20 DEBUG : Waiting for deletions to finish 2025/02/13 01:20:20 INFO : There was nothing to transfer 2025/02/13 01:20:23 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:20:23 DEBUG : two: Destination found in --compare-dest, skipping 2025/02/13 01:20:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/13 01:20:23 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:20:24 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:20:24 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/13 01:20:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:20:24 DEBUG : Waiting for deletions to finish 2025/02/13 01:20:24 INFO : There was nothing to transfer sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test 2025/02/13 01:20:27 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/02/13 01:20:27 DEBUG : two: Need to transfer - File not found at Destination 2025/02/13 01:20:27 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/13 01:20:27 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:20:27 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:20:28 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:20:28 DEBUG : one: Destination found in --compare-dest, skipping 2025/02/13 01:20:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:20:30 INFO : two.rclone_chunk.001_lqncsu: Moved (server-side) to: two 2025/02/13 01:20:30 INFO : two: Copied (new) 2025/02/13 01:20:30 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (60.61s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:20:42 INFO : pre-dest1/1.rclone_chunk.001_lqnmto: Moved (server-side) to: pre-dest1/1 2025/02/13 01:20:49 INFO : pre-dest2/2.rclone_chunk.001_lqnrim: Moved (server-side) to: pre-dest2/2 2025/02/13 01:20:50 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dest" 2025/02/13 01:20:50 DEBUG : Config file has changed externally - reloading 2025/02/13 01:20:50 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dest" 2025/02/13 01:20:52 DEBUG : Reset feature "ListR" 2025/02/13 01:20:52 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/pre-dest1" 2025/02/13 01:20:52 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/pre-dest1" 2025/02/13 01:20:53 DEBUG : Reset feature "ListR" 2025/02/13 01:20:53 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/pre-dest2" 2025/02/13 01:20:53 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/pre-dest2" 2025/02/13 01:20:54 DEBUG : Reset feature "ListR" 2025/02/13 01:20:56 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:20:56 DEBUG : 1: Destination found in --compare-dest, skipping 2025/02/13 01:20:58 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:20:58 DEBUG : 2: Destination found in --compare-dest, skipping 2025/02/13 01:20:59 DEBUG : 3: Need to transfer - File not found at Destination 2025/02/13 01:20:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dest': Waiting for checks to finish 2025/02/13 01:20:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dest': Waiting for transfers to finish 2025/02/13 01:20:59 DEBUG : 3: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:21:04 INFO : 3.rclone_chunk.001_lqo8y7: Moved (server-side) to: 3 2025/02/13 01:21:04 INFO : 3: Copied (new) 2025/02/13 01:21:04 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (35.57s) === RUN TestSyncCopyDest run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:21:13 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:21:13 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:21:16 DEBUG : Reset feature "ListR" 2025/02/13 01:21:16 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/CopyDest" 2025/02/13 01:21:16 DEBUG : Config file has changed externally - reloading 2025/02/13 01:21:16 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/CopyDest" 2025/02/13 01:21:19 DEBUG : Reset feature "ListR" 2025/02/13 01:21:19 DEBUG : one: Need to transfer - File not found at Destination 2025/02/13 01:21:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:21:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:21:20 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:21:26 INFO : one.rclone_chunk.001_lqot1u: Moved (server-side) to: one 2025/02/13 01:21:26 INFO : one: Copied (new) 2025/02/13 01:21:26 DEBUG : Waiting for deletions to finish 2025/02/13 01:21:28 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/13 01:21:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:21:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:21:28 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:21:33 INFO : one: Deleted 2025/02/13 01:21:34 INFO : one.rclone_chunk.001_lqp15y: Moved (server-side) to: one 2025/02/13 01:21:34 INFO : one: Copied (replaced existing) 2025/02/13 01:21:34 DEBUG : Waiting for deletions to finish 2025/02/13 01:21:40 INFO : dst/one: Deleted 2025/02/13 01:21:41 INFO : dst/one.rclone_chunk.001_lqp927: Moved (server-side) to: dst/one 2025/02/13 01:21:46 INFO : CopyDest/one.rclone_chunk.001_lqpevm: Moved (server-side) to: CopyDest/one 2025/02/13 01:21:47 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/BackupDir" 2025/02/13 01:21:47 DEBUG : Config file has changed externally - reloading 2025/02/13 01:21:47 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/BackupDir" 2025/02/13 01:21:49 DEBUG : Reset feature "ListR" 2025/02/13 01:21:49 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/13 01:21:49 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:21:51 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:21:51 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/02/13 01:21:51 DEBUG : one: move non-chunked object... 2025/02/13 01:21:54 INFO : one: Moved (server-side) 2025/02/13 01:21:54 INFO : one: Moved (server-side) 2025/02/13 01:21:54 DEBUG : one: copy non-chunked object... 2025/02/13 01:21:55 INFO : one: Copied (server-side copy) 2025/02/13 01:21:55 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/02/13 01:21:55 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:21:55 DEBUG : Waiting for deletions to finish 2025/02/13 01:22:00 INFO : CopyDest/two.rclone_chunk.001_lqptuv: Moved (server-side) to: CopyDest/two 2025/02/13 01:22:02 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:22:02 DEBUG : two: copy non-chunked object... 2025/02/13 01:22:03 INFO : two: Copied (server-side copy) 2025/02/13 01:22:03 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/02/13 01:22:03 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:22:03 DEBUG : one: Unchanged skipping 2025/02/13 01:22:03 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:22:03 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:22:03 DEBUG : Waiting for deletions to finish 2025/02/13 01:22:05 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:22:05 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:22:05 DEBUG : one: Unchanged skipping 2025/02/13 01:22:05 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:22:05 DEBUG : two: Unchanged skipping 2025/02/13 01:22:05 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:22:05 DEBUG : Waiting for deletions to finish 2025/02/13 01:22:05 INFO : There was nothing to transfer 2025/02/13 01:22:10 INFO : CopyDest/three.rclone_chunk.001_lqq31k: Moved (server-side) to: CopyDest/three 2025/02/13 01:22:13 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/02/13 01:22:13 DEBUG : three: Destination not found in --copy-dest 2025/02/13 01:22:13 DEBUG : three: Need to transfer - File not found at Destination 2025/02/13 01:22:13 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:22:13 DEBUG : one: Unchanged skipping 2025/02/13 01:22:13 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2025/02/13 01:22:13 DEBUG : two: Unchanged skipping 2025/02/13 01:22:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:22:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:22:13 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:22:17 INFO : three.rclone_chunk.001_lqqaon: Moved (server-side) to: three 2025/02/13 01:22:17 INFO : three: Copied (new) 2025/02/13 01:22:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (74.76s) === RUN TestSyncBackupDir run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:22:32 INFO : dst/one.rclone_chunk.001_lqqpc4: Moved (server-side) to: dst/one 2025/02/13 01:22:35 INFO : dst/two.rclone_chunk.001_lqqtee: Moved (server-side) to: dst/two 2025/02/13 01:22:38 INFO : dst/three.txt.rclone_chunk.001_lqqwsg: Moved (server-side) to: dst/three.txt 2025/02/13 01:22:39 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:22:39 DEBUG : Config file has changed externally - reloading 2025/02/13 01:22:39 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:22:41 DEBUG : Reset feature "ListR" 2025/02/13 01:22:41 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/backup" 2025/02/13 01:22:41 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/backup" 2025/02/13 01:22:43 DEBUG : Reset feature "ListR" 2025/02/13 01:22:44 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/13 01:22:44 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:22:44 DEBUG : two: Unchanged skipping 2025/02/13 01:22:44 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:22:44 DEBUG : one: move non-chunked object... 2025/02/13 01:22:47 INFO : one: Moved (server-side) 2025/02/13 01:22:47 INFO : one: Moved (server-side) 2025/02/13 01:22:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:22:47 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:22:51 INFO : one.rclone_chunk.001_lqr83x: Moved (server-side) to: one 2025/02/13 01:22:51 INFO : one: Copied (new) 2025/02/13 01:22:51 DEBUG : Waiting for deletions to finish 2025/02/13 01:22:51 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:22:52 INFO : three.txt: Moved (server-side) 2025/02/13 01:22:52 INFO : three.txt: Moved (server-side) 2025/02/13 01:22:52 INFO : three.txt: Moved into backup dir 2025/02/13 01:22:58 INFO : dst/three.txt.rclone_chunk.001_lqrf8o: Moved (server-side) to: dst/three.txt 2025/02/13 01:22:59 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/13 01:22:59 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:22:59 DEBUG : two: Unchanged skipping 2025/02/13 01:22:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:23:01 INFO : one: Deleted 2025/02/13 01:23:01 DEBUG : one: move non-chunked object... 2025/02/13 01:23:02 INFO : one: Moved (server-side) 2025/02/13 01:23:02 INFO : one: Moved (server-side) 2025/02/13 01:23:02 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:23:03 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:23:07 INFO : one.rclone_chunk.001_lqrob3: Moved (server-side) to: one 2025/02/13 01:23:07 INFO : one: Copied (new) 2025/02/13 01:23:07 DEBUG : Waiting for deletions to finish 2025/02/13 01:23:09 INFO : three.txt: Deleted 2025/02/13 01:23:09 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:23:10 INFO : three.txt: Moved (server-side) 2025/02/13 01:23:10 INFO : three.txt: Moved (server-side) 2025/02/13 01:23:10 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (50.61s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:23:23 INFO : dst/one.rclone_chunk.001_lqs3ao: Moved (server-side) to: dst/one 2025/02/13 01:23:26 INFO : dst/two.rclone_chunk.001_lqs81x: Moved (server-side) to: dst/two 2025/02/13 01:23:30 INFO : dst/three.txt.rclone_chunk.001_lqschp: Moved (server-side) to: dst/three.txt 2025/02/13 01:23:30 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:23:30 DEBUG : Config file has changed externally - reloading 2025/02/13 01:23:30 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:23:31 DEBUG : Reset feature "ListR" 2025/02/13 01:23:31 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/backup" 2025/02/13 01:23:31 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/backup" 2025/02/13 01:23:33 DEBUG : Reset feature "ListR" 2025/02/13 01:23:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/13 01:23:34 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:23:34 DEBUG : two: Unchanged skipping 2025/02/13 01:23:34 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:23:34 DEBUG : one: move non-chunked object... 2025/02/13 01:23:36 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:23:36 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:23:36 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:23:36 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:23:40 INFO : one.rclone_chunk.001_lqsluw: Moved (server-side) to: one 2025/02/13 01:23:40 INFO : one: Copied (new) 2025/02/13 01:23:40 DEBUG : Waiting for deletions to finish 2025/02/13 01:23:40 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:23:41 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:23:41 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:23:41 INFO : three.txt: Moved into backup dir 2025/02/13 01:23:46 INFO : dst/three.txt.rclone_chunk.001_lqsrqb: Moved (server-side) to: dst/three.txt 2025/02/13 01:23:47 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/13 01:23:47 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:23:47 DEBUG : two: Unchanged skipping 2025/02/13 01:23:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:23:50 INFO : one.bak: Deleted 2025/02/13 01:23:50 DEBUG : one: move non-chunked object... 2025/02/13 01:23:51 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:23:51 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:23:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:23:51 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:23:55 INFO : one.rclone_chunk.001_lqt0pv: Moved (server-side) to: one 2025/02/13 01:23:55 INFO : one: Copied (new) 2025/02/13 01:23:55 DEBUG : Waiting for deletions to finish 2025/02/13 01:23:58 INFO : three.txt.bak: Deleted 2025/02/13 01:23:58 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:23:59 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:23:59 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:23:59 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (49.02s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:24:12 INFO : dst/one.rclone_chunk.001_lqtgvn: Moved (server-side) to: dst/one 2025/02/13 01:24:16 INFO : dst/two.rclone_chunk.001_lqtmvf: Moved (server-side) to: dst/two 2025/02/13 01:24:20 INFO : dst/three.txt.rclone_chunk.001_lqtpfw: Moved (server-side) to: dst/three.txt 2025/02/13 01:24:21 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:24:21 DEBUG : Config file has changed externally - reloading 2025/02/13 01:24:21 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:24:22 DEBUG : Reset feature "ListR" 2025/02/13 01:24:22 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/backup" 2025/02/13 01:24:22 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/backup" 2025/02/13 01:24:24 DEBUG : Reset feature "ListR" 2025/02/13 01:24:24 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/13 01:24:24 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:24:24 DEBUG : two: Unchanged skipping 2025/02/13 01:24:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:24:25 DEBUG : one: move non-chunked object... 2025/02/13 01:24:27 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/13 01:24:27 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/13 01:24:27 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:24:27 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:24:30 INFO : one.rclone_chunk.001_lqu0x4: Moved (server-side) to: one 2025/02/13 01:24:30 INFO : one: Copied (new) 2025/02/13 01:24:30 DEBUG : Waiting for deletions to finish 2025/02/13 01:24:31 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:24:32 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/13 01:24:32 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/13 01:24:32 INFO : three.txt: Moved into backup dir 2025/02/13 01:24:36 INFO : dst/three.txt.rclone_chunk.001_lqu6kb: Moved (server-side) to: dst/three.txt 2025/02/13 01:24:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/13 01:24:37 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:24:37 DEBUG : two: Unchanged skipping 2025/02/13 01:24:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:24:39 INFO : one-2019-01-01: Deleted 2025/02/13 01:24:39 DEBUG : one: move non-chunked object... 2025/02/13 01:24:40 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/13 01:24:40 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/13 01:24:40 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:24:41 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:24:44 INFO : one.rclone_chunk.001_lque0n: Moved (server-side) to: one 2025/02/13 01:24:44 INFO : one: Copied (new) 2025/02/13 01:24:44 DEBUG : Waiting for deletions to finish 2025/02/13 01:24:46 INFO : three-2019-01-01.txt: Deleted 2025/02/13 01:24:46 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:24:48 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/13 01:24:48 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/13 01:24:48 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (48.91s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:25:01 INFO : dst/one.rclone_chunk.001_lqut2m: Moved (server-side) to: dst/one 2025/02/13 01:25:05 INFO : dst/two.rclone_chunk.001_lquzi3: Moved (server-side) to: dst/two 2025/02/13 01:25:10 INFO : dst/three.txt.rclone_chunk.001_lqv3r5: Moved (server-side) to: dst/three.txt 2025/02/13 01:25:11 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:25:11 DEBUG : Config file has changed externally - reloading 2025/02/13 01:25:11 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:25:12 DEBUG : Reset feature "ListR" 2025/02/13 01:25:12 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/13 01:25:12 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:25:12 DEBUG : two: Unchanged skipping 2025/02/13 01:25:12 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:25:13 DEBUG : one: move non-chunked object... 2025/02/13 01:25:13 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:25:13 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:25:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:25:14 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:25:18 INFO : one.rclone_chunk.001_lqvb5q: Moved (server-side) to: one 2025/02/13 01:25:18 INFO : one: Copied (new) 2025/02/13 01:25:18 DEBUG : Waiting for deletions to finish 2025/02/13 01:25:19 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:25:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:25:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:25:19 INFO : three.txt: Moved into backup dir 2025/02/13 01:25:24 INFO : dst/three.txt.rclone_chunk.001_lqvh82: Moved (server-side) to: dst/three.txt 2025/02/13 01:25:25 DEBUG : one.bak: Excluded (Path Filter) 2025/02/13 01:25:25 DEBUG : one.bak: Excluded 2025/02/13 01:25:25 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/02/13 01:25:25 DEBUG : three.txt.bak: Excluded 2025/02/13 01:25:25 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/13 01:25:25 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:25:25 DEBUG : two: Unchanged skipping 2025/02/13 01:25:25 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for checks to finish 2025/02/13 01:25:28 INFO : one.bak: Deleted 2025/02/13 01:25:28 DEBUG : one: move non-chunked object... 2025/02/13 01:25:29 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:25:29 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:25:29 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst': Waiting for transfers to finish 2025/02/13 01:25:29 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:25:32 INFO : one.rclone_chunk.001_lqvqk7: Moved (server-side) to: one 2025/02/13 01:25:32 INFO : one: Copied (new) 2025/02/13 01:25:32 DEBUG : Waiting for deletions to finish 2025/02/13 01:25:34 INFO : three.txt.bak: Deleted 2025/02/13 01:25:34 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:25:35 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:25:35 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:25:35 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (44.47s) === RUN TestSyncSuffix run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:25:45 INFO : dst/one.rclone_chunk.001_lqw22x: Moved (server-side) to: dst/one 2025/02/13 01:25:49 INFO : dst/two.rclone_chunk.001_lqw75j: Moved (server-side) to: dst/two 2025/02/13 01:25:54 INFO : dst/three.txt.rclone_chunk.001_lqwbv4: Moved (server-side) to: dst/three.txt 2025/02/13 01:25:55 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:25:55 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:25:56 DEBUG : Reset feature "ListR" 2025/02/13 01:25:57 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/13 01:25:58 DEBUG : one: move non-chunked object... 2025/02/13 01:25:58 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:25:58 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:25:59 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:26:02 INFO : one.rclone_chunk.001_lqwkz1: Moved (server-side) to: one 2025/02/13 01:26:02 INFO : one: Copied (new) 2025/02/13 01:26:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:26:03 DEBUG : two: Unchanged skipping 2025/02/13 01:26:04 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/02/13 01:26:05 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:26:05 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:26:05 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:26:06 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:26:09 INFO : three.txt.rclone_chunk.001_lqwrdp: Moved (server-side) to: three.txt 2025/02/13 01:26:09 INFO : three.txt: Copied (new) 2025/02/13 01:26:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/13 01:26:14 INFO : one.bak: Deleted 2025/02/13 01:26:14 DEBUG : one: move non-chunked object... 2025/02/13 01:26:15 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:26:15 INFO : one: Moved (server-side) to: one.bak 2025/02/13 01:26:15 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:26:19 INFO : one.rclone_chunk.001_lqx03m: Moved (server-side) to: one 2025/02/13 01:26:19 INFO : one: Copied (new) 2025/02/13 01:26:20 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:26:20 DEBUG : two: Unchanged skipping 2025/02/13 01:26:21 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/02/13 01:26:23 INFO : three.txt.bak: Deleted 2025/02/13 01:26:23 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:26:23 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:26:23 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/02/13 01:26:24 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:26:27 INFO : three.txt.rclone_chunk.001_lqx9po: Moved (server-side) to: three.txt 2025/02/13 01:26:27 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (53.64s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:26:39 INFO : dst/one.rclone_chunk.001_lqxjg0: Moved (server-side) to: dst/one 2025/02/13 01:26:43 INFO : dst/two.rclone_chunk.001_lqxoqj: Moved (server-side) to: dst/two 2025/02/13 01:26:47 INFO : dst/three.txt.rclone_chunk.001_lqxs7p: Moved (server-side) to: dst/three.txt 2025/02/13 01:26:47 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:26:47 DEBUG : Config file has changed externally - reloading 2025/02/13 01:26:47 DEBUG : Creating backend with remote "TestBox:rclone-test-rufokex1nobu/dst" 2025/02/13 01:26:48 DEBUG : Reset feature "ListR" 2025/02/13 01:26:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/02/13 01:26:50 DEBUG : one: move non-chunked object... 2025/02/13 01:26:51 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/13 01:26:51 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/13 01:26:51 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:26:56 INFO : one.rclone_chunk.001_lqy09m: Moved (server-side) to: one 2025/02/13 01:26:56 INFO : one: Copied (new) 2025/02/13 01:26:57 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:26:57 DEBUG : two: Unchanged skipping 2025/02/13 01:26:58 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/02/13 01:26:58 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:26:59 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/13 01:26:59 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/13 01:26:59 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:27:03 INFO : three.txt.rclone_chunk.001_lqy8ix: Moved (server-side) to: three.txt 2025/02/13 01:27:03 INFO : three.txt: Copied (new) 2025/02/13 01:27:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/02/13 01:27:08 INFO : one-2019-01-01: Deleted 2025/02/13 01:27:08 DEBUG : one: move non-chunked object... 2025/02/13 01:27:08 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/13 01:27:08 INFO : one: Moved (server-side) to: one-2019-01-01 2025/02/13 01:27:09 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:27:12 INFO : one.rclone_chunk.001_lqyixa: Moved (server-side) to: one 2025/02/13 01:27:12 INFO : one: Copied (new) 2025/02/13 01:27:14 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:27:14 DEBUG : two: Unchanged skipping 2025/02/13 01:27:15 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/02/13 01:27:17 INFO : three-2019-01-01.txt: Deleted 2025/02/13 01:27:17 DEBUG : three.txt: move non-chunked object... 2025/02/13 01:27:17 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/13 01:27:17 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/02/13 01:27:18 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:27:21 INFO : three.txt.rclone_chunk.001_lqyrj4: Moved (server-side) to: three.txt 2025/02/13 01:27:21 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (54.10s) === RUN TestSyncUTFNorm run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:27:31 INFO : Testêé.rclone_chunk.001_lqz2ew: Moved (server-side) to: Testêé 2025/02/13 01:27:32 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/02/13 01:27:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:27:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:27:37 INFO : Testêé: Deleted 2025/02/13 01:27:37 INFO : Testêé.rclone_chunk.001_lqz53d: Moved (server-side) to: Testêé 2025/02/13 01:27:37 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/02/13 01:27:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (10.72s) === RUN TestSyncImmutable run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:27:40 DEBUG : existing: Need to transfer - File not found at Destination 2025/02/13 01:27:40 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:27:40 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:27:40 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:27:43 INFO : existing.rclone_chunk.001_lqzdyf: Moved (server-side) to: existing 2025/02/13 01:27:43 INFO : existing: Copied (new) 2025/02/13 01:27:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:27:44 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:27:44 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/02/13 01:27:44 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/02/13 01:27:44 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:27:44 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': not deleting files as there were IO errors 2025/02/13 01:27:44 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncImmutable (9.42s) === RUN TestSyncIgnoreCase run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:2606: Skipping test as local or remote are case-insensitive --- SKIP: TestSyncIgnoreCase (0.83s) === RUN TestFixCase run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:27:53 INFO : EXISTING.rclone_chunk.001_lqzn3t: Moved (server-side) to: EXISTING 2025/02/13 01:27:57 INFO : EXISTINGBUTDIFFERENT.rclone_chunk.001_lqzrt5: Moved (server-side) to: EXISTINGBUTDIFFERENT 2025/02/13 01:28:05 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO.rclone_chunk.001_lqzv1i: Moved (server-side) to: SUBDIRA/subdirb/SUBDIRC/HELLO 2025/02/13 01:28:11 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences.rclone_chunk.001_lr025y: Moved (server-side) to: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences 2025/02/13 01:28:14 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:28:14 DEBUG : existing: Unchanged skipping 2025/02/13 01:28:14 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2025/02/13 01:28:15 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-kitibet7 2025/02/13 01:28:15 DEBUG : EXISTINGBUTDIFFERENT: move non-chunked object... 2025/02/13 01:28:15 DEBUG : EXISTING: moving to existing-rclone-move-dikucez0 2025/02/13 01:28:15 DEBUG : EXISTING: move non-chunked object... 2025/02/13 01:28:16 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-kitibet7 2025/02/13 01:28:16 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-kitibet7 2025/02/13 01:28:16 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2025/02/13 01:28:16 DEBUG : existingbutdifferent-rclone-move-kitibet7: move non-chunked object... 2025/02/13 01:28:16 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-dikucez0 2025/02/13 01:28:16 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-dikucez0 2025/02/13 01:28:16 DEBUG : EXISTING: moving to existing 2025/02/13 01:28:16 DEBUG : existing-rclone-move-dikucez0: move non-chunked object... 2025/02/13 01:28:17 INFO : SUBDIRA: Fixed case by renaming to: subdira 2025/02/13 01:28:17 INFO : existingbutdifferent-rclone-move-kitibet7: Moved (server-side) to: existingbutdifferent 2025/02/13 01:28:17 INFO : existingbutdifferent-rclone-move-kitibet7: Moved (server-side) to: existingbutdifferent 2025/02/13 01:28:17 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2025/02/13 01:28:17 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2025/02/13 01:28:17 DEBUG : existingbutdifferent: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:28:17 INFO : existing-rclone-move-dikucez0: Moved (server-side) to: existing 2025/02/13 01:28:17 INFO : existing-rclone-move-dikucez0: Moved (server-side) to: existing 2025/02/13 01:28:17 INFO : EXISTING: Moved (server-side) to: existing 2025/02/13 01:28:17 INFO : EXISTING: Fixed case by renaming to: existing 2025/02/13 01:28:20 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2025/02/13 01:28:23 INFO : existingbutdifferent: Deleted 2025/02/13 01:28:24 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2025/02/13 01:28:24 INFO : existingbutdifferent.rclone_chunk.001_lr0e9c: Moved (server-side) to: existingbutdifferent 2025/02/13 01:28:24 INFO : existingbutdifferent: Copied (replaced existing) 2025/02/13 01:28:25 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8) 2025/02/13 01:28:26 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-yogihip3 2025/02/13 01:28:26 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: move non-chunked object... 2025/02/13 01:28:26 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-yogihip3 2025/02/13 01:28:26 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-yogihip3 2025/02/13 01:28:26 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2025/02/13 01:28:26 DEBUG : subdira/subdirb/subdirc/hello-rclone-move-yogihip3: move non-chunked object... 2025/02/13 01:28:27 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird 2025/02/13 01:28:27 INFO : subdira/subdirb/subdirc/hello-rclone-move-yogihip3: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/02/13 01:28:27 INFO : subdira/subdirb/subdirc/hello-rclone-move-yogihip3: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/02/13 01:28:27 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/02/13 01:28:27 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2025/02/13 01:28:27 DEBUG : subdira/subdirb/subdirc/hello: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:28:28 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8) 2025/02/13 01:28:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:28:29 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-sinixuq7 2025/02/13 01:28:29 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: move non-chunked object... 2025/02/13 01:28:29 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-sinixuq7 2025/02/13 01:28:29 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-sinixuq7 2025/02/13 01:28:29 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/02/13 01:28:29 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-sinixuq7: move non-chunked object... 2025/02/13 01:28:30 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-sinixuq7: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/02/13 01:28:30 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-sinixuq7: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/02/13 01:28:30 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/02/13 01:28:30 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/02/13 01:28:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:28:30 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:28:33 INFO : subdira/subdirb/subdirc/hello: Deleted 2025/02/13 01:28:34 INFO : subdira/subdirb/subdirc/hello.rclone_chunk.001_lr0ozq: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/02/13 01:28:34 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2025/02/13 01:28:34 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Deleted 2025/02/13 01:28:35 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences.rclone_chunk.001_lr0rua: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/02/13 01:28:35 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing) 2025/02/13 01:28:35 DEBUG : Waiting for deletions to finish --- PASS: TestFixCase (58.29s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (2.42s) --- SKIP: TestMaxTransfer/Hard (0.79s) --- SKIP: TestMaxTransfer/Soft (0.82s) --- SKIP: TestMaxTransfer/Cautious (0.81s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:28:54 INFO : both0.rclone_chunk.001_lr1bhz: Moved (server-side) to: both0 2025/02/13 01:28:58 INFO : only0.rclone_chunk.001_lr1fyd: Moved (server-side) to: only0 2025/02/13 01:29:02 INFO : both1.rclone_chunk.001_lr1ksw: Moved (server-side) to: both1 2025/02/13 01:29:07 INFO : only1.rclone_chunk.001_lr1oy8: Moved (server-side) to: only1 2025/02/13 01:29:11 INFO : both2.rclone_chunk.001_lr1sml: Moved (server-side) to: both2 2025/02/13 01:29:15 INFO : only2.rclone_chunk.001_lr1wrv: Moved (server-side) to: only2 2025/02/13 01:29:19 INFO : both3.rclone_chunk.001_lr21ij: Moved (server-side) to: both3 2025/02/13 01:29:23 INFO : only3.rclone_chunk.001_lr24nh: Moved (server-side) to: only3 2025/02/13 01:29:26 INFO : both4.rclone_chunk.001_lr28jm: Moved (server-side) to: both4 2025/02/13 01:29:29 INFO : only4.rclone_chunk.001_lr2bhu: Moved (server-side) to: only4 2025/02/13 01:29:33 INFO : both5.rclone_chunk.001_lr2fso: Moved (server-side) to: both5 2025/02/13 01:29:36 INFO : only5.rclone_chunk.001_lr2iwr: Moved (server-side) to: only5 2025/02/13 01:29:40 INFO : both6.rclone_chunk.001_lr2ltu: Moved (server-side) to: both6 2025/02/13 01:29:43 INFO : only6.rclone_chunk.001_lr2p9v: Moved (server-side) to: only6 2025/02/13 01:29:47 INFO : both7.rclone_chunk.001_lr2trr: Moved (server-side) to: both7 2025/02/13 01:29:52 INFO : only7.rclone_chunk.001_lr2xxw: Moved (server-side) to: only7 2025/02/13 01:29:56 INFO : both8.rclone_chunk.001_lr31tq: Moved (server-side) to: both8 2025/02/13 01:30:00 INFO : only8.rclone_chunk.001_lr35om: Moved (server-side) to: only8 2025/02/13 01:30:04 INFO : both9.rclone_chunk.001_lr39q6: Moved (server-side) to: both9 2025/02/13 01:30:08 INFO : only9.rclone_chunk.001_lr3dmg: Moved (server-side) to: only9 2025/02/13 01:30:12 INFO : both10.rclone_chunk.001_lr3hly: Moved (server-side) to: both10 2025/02/13 01:30:16 INFO : only10.rclone_chunk.001_lr3lht: Moved (server-side) to: only10 2025/02/13 01:30:20 INFO : both11.rclone_chunk.001_lr3pqp: Moved (server-side) to: both11 2025/02/13 01:30:23 INFO : only11.rclone_chunk.001_lr3tb1: Moved (server-side) to: only11 2025/02/13 01:30:27 INFO : both12.rclone_chunk.001_lr3xcs: Moved (server-side) to: both12 2025/02/13 01:30:30 INFO : only12.rclone_chunk.001_lr40uo: Moved (server-side) to: only12 2025/02/13 01:30:34 INFO : both13.rclone_chunk.001_lr4440: Moved (server-side) to: both13 2025/02/13 01:30:37 INFO : only13.rclone_chunk.001_lr47sv: Moved (server-side) to: only13 2025/02/13 01:30:41 INFO : both14.rclone_chunk.001_lr4bz8: Moved (server-side) to: both14 2025/02/13 01:30:45 INFO : only14.rclone_chunk.001_lr4elc: Moved (server-side) to: only14 2025/02/13 01:30:49 INFO : both15.rclone_chunk.001_lr4in0: Moved (server-side) to: both15 2025/02/13 01:30:53 INFO : only15.rclone_chunk.001_lr4mzh: Moved (server-side) to: only15 2025/02/13 01:30:57 INFO : both16.rclone_chunk.001_lr4qof: Moved (server-side) to: both16 2025/02/13 01:31:01 INFO : only16.rclone_chunk.001_lr4u9b: Moved (server-side) to: only16 2025/02/13 01:31:05 INFO : both17.rclone_chunk.001_lr4yo1: Moved (server-side) to: both17 2025/02/13 01:31:09 INFO : only17.rclone_chunk.001_lr520m: Moved (server-side) to: only17 2025/02/13 01:31:13 INFO : both18.rclone_chunk.001_lr56ai: Moved (server-side) to: both18 2025/02/13 01:31:17 INFO : only18.rclone_chunk.001_lr5axc: Moved (server-side) to: only18 2025/02/13 01:31:20 INFO : both19.rclone_chunk.001_lr5efd: Moved (server-side) to: both19 2025/02/13 01:31:24 INFO : only19.rclone_chunk.001_lr5iag: Moved (server-side) to: only19 2025/02/13 01:31:25 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both0: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both1: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both10: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both11: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both12: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both13: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both14: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both15: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both16: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both17: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both18: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both19: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both2: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both3: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both4: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both5: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both6: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both7: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both8: Unchanged skipping 2025/02/13 01:31:25 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:31:25 DEBUG : both9: Unchanged skipping 2025/02/13 01:31:25 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:31:25 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:31:25 DEBUG : Waiting for deletions to finish 2025/02/13 01:31:26 INFO : only15: Deleted 2025/02/13 01:31:26 INFO : only12: Deleted 2025/02/13 01:31:26 INFO : only0: Deleted 2025/02/13 01:31:26 INFO : only16: Deleted 2025/02/13 01:31:27 INFO : only11: Deleted 2025/02/13 01:31:27 INFO : only19: Deleted 2025/02/13 01:31:27 INFO : only3: Deleted 2025/02/13 01:31:27 DEBUG : Config file has changed externally - reloading 2025/02/13 01:31:27 DEBUG : TestBox: Loaded invalid token from config file - ignoring 2025/02/13 01:31:27 INFO : only4: Deleted 2025/02/13 01:31:27 INFO : only8: Deleted 2025/02/13 01:31:28 INFO : only9: Deleted 2025/02/13 01:31:28 DEBUG : TestBox: got fatal oauth error: oauth2: "invalid_grant" "Refresh token has expired" 2025/02/13 01:31:28 ERROR : only10: Couldn't delete: Delete "https://api.box.com/2.0/files/1776227773954": couldn't fetch token: invalid_grant: maybe token expired? - try refreshing with "rclone config reconnect TestBox:" 2025/02/13 01:31:28 DEBUG : Config file has changed externally - reloading 2025/02/13 01:31:28 DEBUG : TestBox: Loaded fresh token from config file 2025/02/13 01:31:28 DEBUG : TestBox: Loaded new refresh token from config file 2025/02/13 01:31:29 INFO : only2: Deleted 2025/02/13 01:31:29 INFO : only18: Deleted 2025/02/13 01:31:29 INFO : only13: Deleted 2025/02/13 01:31:29 INFO : only14: Deleted 2025/02/13 01:31:29 INFO : only17: Deleted 2025/02/13 01:31:30 INFO : only5: Deleted 2025/02/13 01:31:30 INFO : only1: Deleted 2025/02/13 01:31:30 INFO : only6: Deleted 2025/02/13 01:31:30 INFO : only7: Deleted 2025/02/13 01:31:30 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': not deleting directories as there were IO errors run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" sync_test.go:2752: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2752 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Received unexpected error: failed to delete 1 files Test: TestSyncConcurrentDelete Messages: Sync must not return a error sync_test.go:2753: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2753 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be false Test: TestSyncConcurrentDelete Messages: Low level errors must not have happened fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be true Test: TestSyncConcurrentDelete Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only10 (6) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "only10" --- FAIL: TestSyncConcurrentDelete (192.95s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:32:07 INFO : both0.rclone_chunk.001_lr6ody: Moved (server-side) to: both0 2025/02/13 01:32:11 INFO : only0.rclone_chunk.001_lr6sjv: Moved (server-side) to: only0 2025/02/13 01:32:14 INFO : both1.rclone_chunk.001_lr6whg: Moved (server-side) to: both1 2025/02/13 01:32:18 INFO : only1.rclone_chunk.001_lr705j: Moved (server-side) to: only1 2025/02/13 01:32:22 INFO : both2.rclone_chunk.001_lr74xf: Moved (server-side) to: both2 2025/02/13 01:32:25 INFO : only2.rclone_chunk.001_lr77jb: Moved (server-side) to: only2 2025/02/13 01:32:29 INFO : both3.rclone_chunk.001_lr7bos: Moved (server-side) to: both3 2025/02/13 01:32:32 INFO : only3.rclone_chunk.001_lr7em4: Moved (server-side) to: only3 2025/02/13 01:32:35 INFO : both4.rclone_chunk.001_lr7h69: Moved (server-side) to: both4 2025/02/13 01:32:38 INFO : only4.rclone_chunk.001_lr7lx1: Moved (server-side) to: only4 2025/02/13 01:32:42 INFO : both5.rclone_chunk.001_lr7oq9: Moved (server-side) to: both5 2025/02/13 01:32:46 INFO : only5.rclone_chunk.001_lr7rtm: Moved (server-side) to: only5 2025/02/13 01:32:50 INFO : both6.rclone_chunk.001_lr7vw0: Moved (server-side) to: both6 2025/02/13 01:32:55 INFO : only6.rclone_chunk.001_lr80fw: Moved (server-side) to: only6 2025/02/13 01:32:59 INFO : both7.rclone_chunk.001_lr84o3: Moved (server-side) to: both7 2025/02/13 01:33:03 INFO : only7.rclone_chunk.001_lr88av: Moved (server-side) to: only7 2025/02/13 01:33:06 INFO : both8.rclone_chunk.001_lr8cjq: Moved (server-side) to: both8 2025/02/13 01:33:10 INFO : only8.rclone_chunk.001_lr8gqk: Moved (server-side) to: only8 2025/02/13 01:33:14 INFO : both9.rclone_chunk.001_lr8k1v: Moved (server-side) to: both9 2025/02/13 01:33:18 INFO : only9.rclone_chunk.001_lr8n50: Moved (server-side) to: only9 2025/02/13 01:33:21 INFO : both10.rclone_chunk.001_lr8rha: Moved (server-side) to: both10 2025/02/13 01:33:25 INFO : only10.rclone_chunk.001_lr8vpp: Moved (server-side) to: only10 2025/02/13 01:33:28 INFO : both11.rclone_chunk.001_lr8yfd: Moved (server-side) to: both11 2025/02/13 01:33:32 INFO : only11.rclone_chunk.001_lr922m: Moved (server-side) to: only11 2025/02/13 01:33:35 INFO : both12.rclone_chunk.001_lr955f: Moved (server-side) to: both12 2025/02/13 01:33:39 INFO : only12.rclone_chunk.001_lr985l: Moved (server-side) to: only12 2025/02/13 01:33:42 INFO : both13.rclone_chunk.001_lr9cpt: Moved (server-side) to: both13 2025/02/13 01:33:46 INFO : only13.rclone_chunk.001_lr9fhw: Moved (server-side) to: only13 2025/02/13 01:33:50 INFO : both14.rclone_chunk.001_lr9kj2: Moved (server-side) to: both14 2025/02/13 01:33:54 INFO : only14.rclone_chunk.001_lr9nvy: Moved (server-side) to: only14 2025/02/13 01:33:58 INFO : both15.rclone_chunk.001_lr9r8i: Moved (server-side) to: both15 2025/02/13 01:34:02 INFO : only15.rclone_chunk.001_lr9vi9: Moved (server-side) to: only15 2025/02/13 01:34:06 INFO : both16.rclone_chunk.001_lr9z57: Moved (server-side) to: both16 2025/02/13 01:34:10 INFO : only16.rclone_chunk.001_lra3cu: Moved (server-side) to: only16 2025/02/13 01:34:14 INFO : both17.rclone_chunk.001_lra7nx: Moved (server-side) to: both17 2025/02/13 01:34:17 INFO : only17.rclone_chunk.001_lrabax: Moved (server-side) to: only17 2025/02/13 01:34:21 INFO : both18.rclone_chunk.001_lraf7p: Moved (server-side) to: both18 2025/02/13 01:34:25 INFO : only18.rclone_chunk.001_lraja5: Moved (server-side) to: only18 2025/02/13 01:34:29 INFO : both19.rclone_chunk.001_lramg2: Moved (server-side) to: both19 2025/02/13 01:34:32 INFO : only19.rclone_chunk.001_lraqc8: Moved (server-side) to: only19 2025/02/13 01:34:33 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both0: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both1: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both10: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both11: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both12: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both13: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both14: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both15: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both16: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both17: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both18: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both19: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both2: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both3: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both4: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both5: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both6: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both7: Unchanged skipping 2025/02/13 01:34:33 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/02/13 01:34:33 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for checks to finish 2025/02/13 01:34:33 DEBUG : both8: Unchanged skipping 2025/02/13 01:34:33 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only10: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:33 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only11: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:33 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : both9: Unchanged skipping 2025/02/13 01:34:33 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only12: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:33 DEBUG : only13: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:33 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/02/13 01:34:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Waiting for transfers to finish 2025/02/13 01:34:37 INFO : only11: Deleted 2025/02/13 01:34:38 INFO : only10: Deleted 2025/02/13 01:34:38 INFO : only13: Deleted 2025/02/13 01:34:38 INFO : only12: Deleted 2025/02/13 01:34:38 INFO : only11.rclone_chunk.001_lrauei: Moved (server-side) to: only11 2025/02/13 01:34:38 INFO : only11: Copied (replaced existing) 2025/02/13 01:34:38 DEBUG : only14: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:38 INFO : only10.rclone_chunk.001_lrauct: Moved (server-side) to: only10 2025/02/13 01:34:38 INFO : only10: Copied (replaced existing) 2025/02/13 01:34:38 DEBUG : only15: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:38 INFO : only13.rclone_chunk.001_lraut5: Moved (server-side) to: only13 2025/02/13 01:34:38 INFO : only13: Copied (replaced existing) 2025/02/13 01:34:38 DEBUG : only16: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:38 INFO : only12.rclone_chunk.001_lraukv: Moved (server-side) to: only12 2025/02/13 01:34:38 INFO : only12: Copied (replaced existing) 2025/02/13 01:34:38 DEBUG : only0: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:43 INFO : only14: Deleted 2025/02/13 01:34:43 INFO : only0: Deleted 2025/02/13 01:34:43 INFO : only15: Deleted 2025/02/13 01:34:43 INFO : only16: Deleted 2025/02/13 01:34:43 INFO : only14.rclone_chunk.001_lraz1t: Moved (server-side) to: only14 2025/02/13 01:34:43 INFO : only14: Copied (replaced existing) 2025/02/13 01:34:43 DEBUG : only18: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:43 INFO : only0.rclone_chunk.001_lrazgv: Moved (server-side) to: only0 2025/02/13 01:34:43 INFO : only0: Copied (replaced existing) 2025/02/13 01:34:43 DEBUG : only19: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:43 INFO : only15.rclone_chunk.001_lrazai: Moved (server-side) to: only15 2025/02/13 01:34:43 INFO : only15: Copied (replaced existing) 2025/02/13 01:34:43 DEBUG : only2: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:44 INFO : only16.rclone_chunk.001_lrazja: Moved (server-side) to: only16 2025/02/13 01:34:44 INFO : only16: Copied (replaced existing) 2025/02/13 01:34:44 DEBUG : only4: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:48 INFO : only18: Deleted 2025/02/13 01:34:49 INFO : only2: Deleted 2025/02/13 01:34:49 INFO : only19: Deleted 2025/02/13 01:34:49 INFO : only18.rclone_chunk.001_lrb4a5: Moved (server-side) to: only18 2025/02/13 01:34:49 INFO : only18: Copied (replaced existing) 2025/02/13 01:34:49 DEBUG : only5: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:49 INFO : only2.rclone_chunk.001_lrb428: Moved (server-side) to: only2 2025/02/13 01:34:49 INFO : only2: Copied (replaced existing) 2025/02/13 01:34:49 DEBUG : only6: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:49 INFO : only4: Deleted 2025/02/13 01:34:49 INFO : only19.rclone_chunk.001_lrb49o: Moved (server-side) to: only19 2025/02/13 01:34:49 INFO : only19: Copied (replaced existing) 2025/02/13 01:34:49 DEBUG : only7: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:50 INFO : only4.rclone_chunk.001_lrb5df: Moved (server-side) to: only4 2025/02/13 01:34:50 INFO : only4: Copied (replaced existing) 2025/02/13 01:34:50 DEBUG : only8: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:55 INFO : only5: Deleted 2025/02/13 01:34:55 INFO : only7: Deleted 2025/02/13 01:34:55 INFO : only6: Deleted 2025/02/13 01:34:55 INFO : only7.rclone_chunk.001_lrba43: Moved (server-side) to: only7 2025/02/13 01:34:55 INFO : only7: Copied (replaced existing) 2025/02/13 01:34:55 DEBUG : only9: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:55 INFO : only5.rclone_chunk.001_lrbadv: Moved (server-side) to: only5 2025/02/13 01:34:55 INFO : only5: Copied (replaced existing) 2025/02/13 01:34:55 DEBUG : only1: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:56 INFO : only8: Deleted 2025/02/13 01:34:56 INFO : only6.rclone_chunk.001_lrba2v: Moved (server-side) to: only6 2025/02/13 01:34:56 INFO : only6: Copied (replaced existing) 2025/02/13 01:34:56 DEBUG : only3: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:34:56 INFO : only8.rclone_chunk.001_lrbben: Moved (server-side) to: only8 2025/02/13 01:34:56 INFO : only8: Copied (replaced existing) 2025/02/13 01:34:56 DEBUG : only17: skip slow MD5 on source file, hashing in-transit 2025/02/13 01:35:01 INFO : only1: Deleted 2025/02/13 01:35:01 INFO : only9: Deleted 2025/02/13 01:35:01 INFO : only1.rclone_chunk.001_lrbg3v: Moved (server-side) to: only1 2025/02/13 01:35:01 INFO : only1: Copied (replaced existing) 2025/02/13 01:35:01 INFO : only9.rclone_chunk.001_lrbgxz: Moved (server-side) to: only9 2025/02/13 01:35:01 INFO : only9: Copied (replaced existing) 2025/02/13 01:35:02 INFO : only3: Deleted 2025/02/13 01:35:02 INFO : only17: Deleted 2025/02/13 01:35:02 INFO : only3.rclone_chunk.001_lrbhdm: Moved (server-side) to: only3 2025/02/13 01:35:02 INFO : only3: Copied (replaced existing) 2025/02/13 01:35:03 INFO : only17.rclone_chunk.001_lrbhe8: Moved (server-side) to: only17 2025/02/13 01:35:03 INFO : only17: Copied (replaced existing) 2025/02/13 01:35:03 DEBUG : Waiting for deletions to finish run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncConcurrentTruncate (218.88s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:35:42 DEBUG : empty_dir: Making directory with metadata 2025/02/13 01:35:42 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/13 01:35:42 DEBUG : empty_on_remote: Making directory with metadata 2025/02/13 01:35:42 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/13 01:35:42 INFO : empty_on_remote: Making directory 2025/02/13 01:35:43 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:35:52 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/02/13 01:35:52 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/02/13 01:35:52 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/02/13 01:35:52 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncReplaceDirModTime (22.66s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:36:04 DEBUG : empty_dir: Making directory with metadata 2025/02/13 01:36:04 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/13 01:36:04 DEBUG : empty_on_remote: Making directory with metadata 2025/02/13 01:36:04 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/02/13 01:36:04 INFO : empty_on_remote: Making directory 2025/02/13 01:36:05 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:36:14 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/02/13 01:36:14 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/02/13 01:36:14 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/02/13 01:36:14 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/02/13 01:36:14 INFO : empty_dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (22.33s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:36:27 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/13 01:36:27 INFO : sub dir: Making directory 2025/02/13 01:36:28 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestNothingToTransferWithEmptyDirs (42.19s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" 2025/02/13 01:37:09 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/02/13 01:37:09 INFO : sub dir: Making directory 2025/02/13 01:37:10 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" run.go:180: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu'", Local "Local file system at /tmp/rclone2426187867", Modify Window "1s" --- PASS: TestNothingToTransferWithoutEmptyDirs (41.77s) FAIL 2025/02/13 01:37:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-rufokex1nobu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024" - Finished ERROR in 35m19.62098007s (try 1/5): exit status 1: Failed [TestSyncConcurrentDelete]