"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose" - Starting (try 1/5) 2025/04/30 06:15:58 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu" 2025/04/30 06:15:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/30 06:15:58 DEBUG : Creating backend with remote "/tmp/rclone424510806" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.05s) === 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 "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:16:00 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/04/30 06:16:00 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:00 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyWithDryRun (3.00s) === RUN TestCopy run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:01 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/30 06:16:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:16:01 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:01 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:16:06 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:06 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (8.99s) === RUN TestCopyMetadata run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.72s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadataNoEmptyDirs (0.39s) === RUN TestCopyMissingDirectory run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:11 DEBUG : Creating backend with remote "/non-existing" 2025/04/30 06:16:12 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/04/30 06:16:12 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:12 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyMissingDirectory (1.00s) === RUN TestCopyNoTraverse run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:16:13 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:13 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:16:17 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyNoTraverse (8.93s) === RUN TestCopyCheckFirst run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:21 INFO : Linkbox root 'rclone-test-jesuhin5fonu': Running all checks before starting transfers 2025/04/30 06:16:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:16:22 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:22 INFO : Linkbox root 'rclone-test-jesuhin5fonu': Checks finished, now starting transfers 2025/04/30 06:16:22 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:16:26 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyCheckFirst (8.82s) === RUN TestSyncNoTraverse run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:30 ERROR : Ignoring --no-traverse with sync 2025/04/30 06:16:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:16:30 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:30 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:16:35 INFO : sub dir/hello world: Copied (new) 2025/04/30 06:16:35 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncNoTraverse (9.47s) === RUN TestCopyWithDepth run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:40 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/04/30 06:16:40 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:40 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:16:42 INFO : hello world2: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyWithDepth (4.93s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:45 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:16:45 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:45 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:16:47 INFO : potato2: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyWithFilesFrom (5.14s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:50 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:16:50 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:16:50 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:16:52 INFO : potato2: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyWithFilesFromAndNoTraverse (5.37s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:16:55 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/30 06:16:55 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/30 06:16:55 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/04/30 06:16:55 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/30 06:16:55 INFO : sub dir: Making directory 2025/04/30 06:16:57 INFO : sub dir2: Making directory 2025/04/30 06:16:58 INFO : sub dir2/sub sub dir2: Making directory 2025/04/30 06:16:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:17:00 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:17:00 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:17:02 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:17:03 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (15.53s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:17:11 INFO : sub dir2: Making directory 2025/04/30 06:17:11 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/04/30 06:17:11 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/30 06:17:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:17:11 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:17:11 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:17:15 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (9.01s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:17:20 DEBUG : sub dir2: Making directory with metadata 2025/04/30 06:17:20 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/30 06:17:20 INFO : sub dir: Making directory 2025/04/30 06:17:21 INFO : sub dir2: Making directory 2025/04/30 06:17:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:17:23 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:17:23 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:17:25 INFO : sub dir/hello world: Copied (new) 2025/04/30 06:17:25 INFO : sub dir/hello world: Deleted run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:17:26 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/04/30 06:17:26 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (12.79s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:408: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.56s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:17:33 INFO : sub dir2: Making directory 2025/04/30 06:17:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:17:33 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:17:33 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:17:37 INFO : sub dir/hello world: Copied (new) 2025/04/30 06:17:37 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (8.33s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:17:41 DEBUG : sub dir2: Making directory with metadata 2025/04/30 06:17:41 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/30 06:17:41 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/30 06:17:41 INFO : sub dir: Making directory 2025/04/30 06:17:43 INFO : sub dir2: Making directory 2025/04/30 06:17:45 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:17:45 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:17:45 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:17:47 INFO : sub dir/hello world: Copied (new) 2025/04/30 06:17:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:17:48 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (12.30s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.75s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:17:54 INFO : sub dir2: Making directory 2025/04/30 06:17:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:17:54 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:17:54 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:17:58 INFO : sub dir/hello world: Copied (new) 2025/04/30 06:17:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (7.94s) === RUN TestServerSideCopy run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:18:07 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-mademod1gugo" sync_test.go:590: Server side copy (if possible) Linkbox root 'rclone-test-jesuhin5fonu' -> Linkbox root 'rclone-test-mademod1gugo' 2025/04/30 06:18:08 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:18:08 DEBUG : Linkbox root 'rclone-test-mademod1gugo': Waiting for checks to finish 2025/04/30 06:18:08 DEBUG : Linkbox root 'rclone-test-mademod1gugo': Waiting for transfers to finish 2025/04/30 06:18:08 ERROR : sub dir/hello world: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" sync_test.go:594: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:594 Error: Received unexpected error: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestServerSideCopy 2025/04/30 06:18:08 DEBUG : Linkbox root 'rclone-test-mademod1gugo': Purge remote 2025/04/30 06:18:08 NOTICE: purge failed: directory not found --- FAIL: TestServerSideCopy (9.66s) === RUN TestCopyOverSelf run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:18:16 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:18:16 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/30 06:18:16 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:18:16 DEBUG : sub dir/hello world: Update: removing old file 2025/04/30 06:18:20 INFO : sub dir/hello world: Copied (replaced existing) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyOverSelf (12.60s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:18:30 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-qucoret8juwu" sync_test.go:626: Server side copy (if possible) Linkbox root 'rclone-test-jesuhin5fonu' -> Linkbox root 'rclone-test-qucoret8juwu' 2025/04/30 06:18:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:18:31 DEBUG : Linkbox root 'rclone-test-qucoret8juwu': Waiting for checks to finish 2025/04/30 06:18:31 DEBUG : Linkbox root 'rclone-test-qucoret8juwu': Waiting for transfers to finish 2025/04/30 06:18:31 ERROR : sub dir/hello world: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" sync_test.go:630: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:630 Error: Received unexpected error: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestServerSideCopyOverSelf 2025/04/30 06:18:31 DEBUG : Linkbox root 'rclone-test-qucoret8juwu': Purge remote 2025/04/30 06:18:31 NOTICE: purge failed: directory not found --- FAIL: TestServerSideCopyOverSelf (10.53s) === RUN TestMoveOverSelf run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:18:40 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/04/30 06:18:40 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:18:40 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:18:40 DEBUG : sub dir/hello world: Update: removing old file 2025/04/30 06:18:43 INFO : sub dir/hello world: Copied (replaced existing) 2025/04/30 06:18:43 INFO : sub dir/hello world: Deleted run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestMoveOverSelf (13.07s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:18:53 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-pofezos5kixa" sync_test.go:671: Server side copy (if possible) Linkbox root 'rclone-test-jesuhin5fonu' -> Linkbox root 'rclone-test-pofezos5kixa' 2025/04/30 06:18:54 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:18:54 DEBUG : Linkbox root 'rclone-test-pofezos5kixa': Waiting for checks to finish 2025/04/30 06:18:54 DEBUG : Linkbox root 'rclone-test-pofezos5kixa': Waiting for transfers to finish 2025/04/30 06:18:54 ERROR : sub dir/hello world: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" sync_test.go:675: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:675 Error: Received unexpected error: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestServerSideMoveOverSelf 2025/04/30 06:18:54 DEBUG : Linkbox root 'rclone-test-pofezos5kixa': Purge remote 2025/04/30 06:18:54 NOTICE: purge failed: directory not found --- FAIL: TestServerSideMoveOverSelf (9.33s) === RUN TestCopyAfterDelete run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:01 ERROR : : error listing: directory not found 2025/04/30 06:19:02 INFO : Local file system at /tmp/rclone424510806: Making directory 2025/04/30 06:19:02 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:02 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyAfterDelete (10.00s) === RUN TestCopyRedownload run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:12 DEBUG : Added delayed dir = "sub dir", newDst= 2025/04/30 06:19:12 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:19:12 DEBUG : Local file system at /tmp/rclone424510806: Waiting for checks to finish 2025/04/30 06:19:12 DEBUG : Local file system at /tmp/rclone424510806: Waiting for transfers to finish 2025/04/30 06:19:12 ERROR : sub dir/hello world: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:19:12 ERROR : sub dir: Failed to update directory timestamp or metadata: chtimes /tmp/rclone424510806/sub dir: no such file or directory sync_test.go:732: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:732 Error: Received unexpected error: failed to set directory modtime: chtimes /tmp/rclone424510806/sub dir: no such file or directory Test: TestCopyRedownload --- FAIL: TestCopyRedownload (10.71s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:19 DEBUG : check sum: Need to transfer - File not found at Destination 2025/04/30 06:19:19 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:19 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:21 INFO : check sum: Copied (new) 2025/04/30 06:19:21 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:21 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:21 NOTICE: Linkbox root 'rclone-test-jesuhin5fonu': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2025/04/30 06:19:21 DEBUG : check sum: Size of src and dst objects identical 2025/04/30 06:19:21 DEBUG : check sum: Unchanged skipping 2025/04/30 06:19:21 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:21 DEBUG : Waiting for deletions to finish 2025/04/30 06:19:21 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncBasedOnCheckSum (6.34s) === RUN TestSyncSizeOnly run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:25 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/04/30 06:19:25 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:25 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:27 INFO : sizeonly: Copied (new) 2025/04/30 06:19:27 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:27 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:27 DEBUG : sizeonly: Sizes identical 2025/04/30 06:19:27 DEBUG : sizeonly: Unchanged skipping 2025/04/30 06:19:27 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:27 DEBUG : Waiting for deletions to finish 2025/04/30 06:19:27 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncSizeOnly (6.82s) === RUN TestSyncIgnoreSize run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:32 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/04/30 06:19:32 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:32 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:34 INFO : ignore-size: Copied (new) 2025/04/30 06:19:34 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:34 DEBUG : ignore-size: Sizes identical 2025/04/30 06:19:34 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:34 DEBUG : ignore-size: Unchanged skipping 2025/04/30 06:19:34 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:34 DEBUG : Waiting for deletions to finish 2025/04/30 06:19:34 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncIgnoreSize (7.32s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:41 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:41 DEBUG : existing: Sizes identical 2025/04/30 06:19:41 DEBUG : existing: Unchanged skipping 2025/04/30 06:19:41 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:41 DEBUG : Waiting for deletions to finish 2025/04/30 06:19:41 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:41 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/04/30 06:19:41 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:41 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:41 DEBUG : existing: Update: removing old file 2025/04/30 06:19:45 INFO : existing: Copied (replaced existing) 2025/04/30 06:19:45 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncIgnoreTimes (9.25s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:48 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/30 06:19:48 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:48 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:51 INFO : existing: Copied (new) 2025/04/30 06:19:51 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:19:52 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:19:52 DEBUG : existing: Destination exists, skipping 2025/04/30 06:19:52 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:19:52 DEBUG : Waiting for deletions to finish 2025/04/30 06:19:52 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncIgnoreExisting (7.18s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:20:05 INFO : d: Making directory 2025/04/30 06:20:08 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/30 06:20:08 DEBUG : c/non empty space: Sizes identical 2025/04/30 06:20:08 DEBUG : c/non empty space: Unchanged skipping 2025/04/30 06:20:09 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:20:09 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:20:13 INFO : a/potato2: Copied (new) 2025/04/30 06:20:13 DEBUG : Waiting for deletions to finish 2025/04/30 06:20:14 INFO : b/potato: Deleted 2025/04/30 06:20:14 INFO : d: Removing directory 2025/04/30 06:20:16 INFO : b: Removing directory 2025/04/30 06:20:18 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': deleted 2 directories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncIgnoreErrors (31.19s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:20:29 DEBUG : empty space: Sizes identical 2025/04/30 06:20:29 DEBUG : empty space: Unchanged skipping 2025/04/30 06:20:29 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:20:29 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:20:29 DEBUG : Waiting for deletions to finish 2025/04/30 06:20:29 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:20:29 DEBUG : empty space: Sizes identical 2025/04/30 06:20:29 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:20:29 DEBUG : empty space: Unchanged skipping 2025/04/30 06:20:29 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:20:29 DEBUG : Waiting for deletions to finish 2025/04/30 06:20:29 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncAfterChangingModtimeOnly (6.31s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:1016: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.41s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:1042: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncDoesntUpdateModtime (0.40s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:20:36 DEBUG : empty space: Sizes identical 2025/04/30 06:20:36 DEBUG : potato: Need to transfer - File not found at Destination 2025/04/30 06:20:36 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:20:36 DEBUG : empty space: Unchanged skipping 2025/04/30 06:20:36 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:20:39 INFO : potato: Copied (new) 2025/04/30 06:20:39 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncAfterAddingAFile (9.09s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:20:45 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/04/30 06:20:45 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:20:45 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:20:45 DEBUG : potato: Update: removing old file 2025/04/30 06:20:49 INFO : potato: Copied (replaced existing) 2025/04/30 06:20:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncAfterChangingFilesSizeOnly (8.97s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:1106: ModTimeNotSupported so forcing file to be a different size 2025/04/30 06:20:54 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:20:54 DEBUG : potato: Sizes differ (src 21 vs dst 36) 2025/04/30 06:20:54 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:20:54 DEBUG : potato: Update: removing old file 2025/04/30 06:20:57 INFO : potato: Copied (replaced existing) 2025/04/30 06:20:57 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncAfterChangingContentsOnly (8.72s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:21:05 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:21:05 DEBUG : empty space: Sizes identical 2025/04/30 06:21:05 DEBUG : empty space: Unchanged skipping 2025/04/30 06:21:05 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/04/30 06:21:05 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:21:05 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:21:05 DEBUG : Waiting for deletions to finish 2025/04/30 06:21:05 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (8.64s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:21:14 DEBUG : empty space: Sizes identical 2025/04/30 06:21:14 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:21:14 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:21:14 DEBUG : empty space: Unchanged skipping 2025/04/30 06:21:14 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:21:16 INFO : potato2: Copied (new) 2025/04/30 06:21:16 DEBUG : Waiting for deletions to finish 2025/04/30 06:21:17 INFO : potato: Deleted run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (12.38s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:21:29 INFO : d: Making directory 2025/04/30 06:21:31 INFO : d/e: Making directory 2025/04/30 06:21:34 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/30 06:21:34 DEBUG : c/non empty space: Sizes identical 2025/04/30 06:21:34 DEBUG : c/non empty space: Unchanged skipping 2025/04/30 06:21:36 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:21:36 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:21:38 INFO : a/potato2: Copied (new) 2025/04/30 06:21:38 DEBUG : Waiting for deletions to finish 2025/04/30 06:21:39 INFO : b/potato: Deleted 2025/04/30 06:21:39 INFO : d/e: Removing directory 2025/04/30 06:21:41 INFO : d: Removing directory 2025/04/30 06:21:43 INFO : b: Removing directory 2025/04/30 06:21:44 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': deleted 3 directories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (31.36s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:22:00 INFO : d: Making directory 2025/04/30 06:22:03 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/04/30 06:22:03 DEBUG : c/non empty space: Sizes identical 2025/04/30 06:22:03 DEBUG : c/non empty space: Unchanged skipping 2025/04/30 06:22:04 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:22:04 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:22:07 INFO : a/potato2: Copied (new) 2025/04/30 06:22:07 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting files as there were IO errors 2025/04/30 06:22:07 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting directories as there were IO errors run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (29.26s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:22:27 DEBUG : Waiting for deletions to finish 2025/04/30 06:22:27 DEBUG : empty space: Sizes identical 2025/04/30 06:22:27 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:22:27 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:22:27 DEBUG : empty space: Unchanged skipping 2025/04/30 06:22:27 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:22:29 INFO : potato: Deleted 2025/04/30 06:22:30 INFO : potato2: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncDeleteDuring (12.27s) === RUN TestSyncDeleteBefore run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:22:41 DEBUG : Waiting for deletions to finish 2025/04/30 06:22:41 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:22:41 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:22:43 INFO : potato: Deleted 2025/04/30 06:22:43 DEBUG : empty space: Sizes identical 2025/04/30 06:22:43 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:22:43 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:22:43 DEBUG : empty space: Unchanged skipping 2025/04/30 06:22:43 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:22:47 INFO : potato2: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncDeleteBefore (21.30s) === RUN TestCopyDeleteBefore run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:22:58 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:22:58 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:22:58 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:23:06 INFO : potato2: Copied (new) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestCopyDeleteBefore (14.37s) === RUN TestSyncWithExclude run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:23:22 DEBUG : enormous: Excluded (Size Filter) 2025/04/30 06:23:22 DEBUG : enormous: Excluded 2025/04/30 06:23:22 DEBUG : potato2: Excluded (Size Filter) 2025/04/30 06:23:22 DEBUG : potato2: Excluded 2025/04/30 06:23:22 DEBUG : potato2: Excluded (Size Filter) 2025/04/30 06:23:22 DEBUG : potato2: Excluded 2025/04/30 06:23:22 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:23:22 DEBUG : empty space: Sizes identical 2025/04/30 06:23:22 DEBUG : empty space: Unchanged skipping 2025/04/30 06:23:22 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:23:22 DEBUG : Waiting for deletions to finish 2025/04/30 06:23:22 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:23:23 DEBUG : enormous: Excluded (Size Filter) 2025/04/30 06:23:23 DEBUG : enormous: Excluded 2025/04/30 06:23:23 DEBUG : potato2: Excluded (Size Filter) 2025/04/30 06:23:23 DEBUG : potato2: Excluded 2025/04/30 06:23:23 DEBUG : potato2: Excluded (Size Filter) 2025/04/30 06:23:23 DEBUG : potato2: Excluded 2025/04/30 06:23:23 DEBUG : empty space: Sizes identical 2025/04/30 06:23:23 DEBUG : Local file system at /tmp/rclone424510806: Waiting for checks to finish 2025/04/30 06:23:23 DEBUG : empty space: Unchanged skipping 2025/04/30 06:23:23 DEBUG : Local file system at /tmp/rclone424510806: Waiting for transfers to finish 2025/04/30 06:23:23 DEBUG : Waiting for deletions to finish 2025/04/30 06:23:23 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncWithExclude (17.62s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:23:34 DEBUG : enormous: Excluded (Size Filter) 2025/04/30 06:23:34 DEBUG : enormous: Excluded 2025/04/30 06:23:34 DEBUG : potato2: Excluded (Size Filter) 2025/04/30 06:23:34 DEBUG : potato2: Excluded 2025/04/30 06:23:34 DEBUG : empty space: Sizes identical 2025/04/30 06:23:34 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:23:34 DEBUG : empty space: Unchanged skipping 2025/04/30 06:23:34 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:23:34 DEBUG : Waiting for deletions to finish 2025/04/30 06:23:36 INFO : enormous: Deleted 2025/04/30 06:23:36 INFO : potato2: Deleted 2025/04/30 06:23:36 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:23:37 DEBUG : empty space: Sizes identical 2025/04/30 06:23:37 DEBUG : empty space: Unchanged skipping 2025/04/30 06:23:37 DEBUG : Local file system at /tmp/rclone424510806: Waiting for checks to finish 2025/04/30 06:23:37 DEBUG : Local file system at /tmp/rclone424510806: Waiting for transfers to finish 2025/04/30 06:23:37 DEBUG : Waiting for deletions to finish 2025/04/30 06:23:37 INFO : potato2: Deleted 2025/04/30 06:23:37 INFO : enormous: Deleted 2025/04/30 06:23:37 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncWithExcludeAndDeleteExcluded (11.98s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:1423: Can't run this test on fs which doesn't support mod time --- SKIP: TestSyncWithUpdateOlder (0.58s) === 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 "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:1538: Can track renames: false 2025/04/30 06:23:40 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as the destination does not support server-side move or copy 2025/04/30 06:23:40 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as the source and destination do not have a common hash 2025/04/30 06:23:40 DEBUG : potato: Need to transfer - File not found at Destination 2025/04/30 06:23:40 DEBUG : yam: Need to transfer - File not found at Destination 2025/04/30 06:23:40 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:23:40 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:23:43 INFO : potato: Copied (new) 2025/04/30 06:23:43 INFO : yam: Copied (new) 2025/04/30 06:23:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:23:43 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as the destination does not support server-side move or copy 2025/04/30 06:23:43 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as the source and destination do not have a common hash 2025/04/30 06:23:43 DEBUG : potato: Sizes identical 2025/04/30 06:23:43 DEBUG : yaml: Need to transfer - File not found at Destination 2025/04/30 06:23:43 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:23:43 DEBUG : potato: Unchanged skipping 2025/04/30 06:23:43 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:23:46 INFO : yaml: Copied (new) 2025/04/30 06:23:46 DEBUG : Waiting for deletions to finish 2025/04/30 06:23:47 INFO : yam: Deleted run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncWithTrackRenames (11.57s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:1610: Can track renames: false 2025/04/30 06:23:51 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as the destination does not support server-side move or copy 2025/04/30 06:23:51 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as either the source or destination do not support modtime 2025/04/30 06:23:52 DEBUG : potato: Need to transfer - File not found at Destination 2025/04/30 06:23:52 DEBUG : yam: Need to transfer - File not found at Destination 2025/04/30 06:23:52 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:23:52 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:23:54 INFO : potato: Copied (new) 2025/04/30 06:23:54 INFO : yam: Copied (new) 2025/04/30 06:23:54 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:23:55 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as the destination does not support server-side move or copy 2025/04/30 06:23:55 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as either the source or destination do not support modtime 2025/04/30 06:23:55 DEBUG : potato: Sizes identical 2025/04/30 06:23:55 DEBUG : yaml: Need to transfer - File not found at Destination 2025/04/30 06:23:55 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:23:55 DEBUG : potato: Unchanged skipping 2025/04/30 06:23:55 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:23:57 INFO : yaml: Copied (new) 2025/04/30 06:23:57 DEBUG : Waiting for deletions to finish 2025/04/30 06:23:59 INFO : yam: Deleted run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncWithTrackRenamesStrategyModtime (11.64s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:1649: Can track renames: false 2025/04/30 06:24:03 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as the destination does not support server-side move or copy 2025/04/30 06:24:03 DEBUG : potato: Need to transfer - File not found at Destination 2025/04/30 06:24:03 DEBUG : sub/yam: Need to transfer - File not found at Destination 2025/04/30 06:24:03 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:24:03 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:24:07 INFO : potato: Copied (new) 2025/04/30 06:24:08 INFO : sub/yam: Copied (new) 2025/04/30 06:24:08 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:24:08 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': Ignoring --track-renames as the destination does not support server-side move or copy 2025/04/30 06:24:09 DEBUG : potato: Sizes identical 2025/04/30 06:24:09 DEBUG : yam: Need to transfer - File not found at Destination 2025/04/30 06:24:09 DEBUG : potato: Unchanged skipping 2025/04/30 06:24:09 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:24:09 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:24:11 INFO : yam: Copied (new) 2025/04/30 06:24:11 DEBUG : Waiting for deletions to finish 2025/04/30 06:24:13 INFO : sub/yam: Deleted run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (15.74s) === RUN TestServerSideMoveLocal run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:24:19 DEBUG : Creating backend with remote "/tmp/rclone424510806/dir1" 2025/04/30 06:24:19 DEBUG : Config file has changed externally - reloading 2025/04/30 06:24:19 DEBUG : Creating backend with remote "/tmp/rclone424510806/dir2" 2025/04/30 06:24:19 DEBUG : Local file system at /tmp/rclone424510806/dir2: Using server-side directory move 2025/04/30 06:24:19 INFO : Local file system at /tmp/rclone424510806/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/04/30 06:24:19 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/04/30 06:24:19 DEBUG : Local file system at /tmp/rclone424510806/dir2: Waiting for checks to finish 2025/04/30 06:24:19 DEBUG : Local file system at /tmp/rclone424510806/dir2: Waiting for transfers to finish 2025/04/30 06:24:19 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.58s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:24:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:24:20 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/04/30 06:24:20 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:24:20 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:24:26 INFO : sub dir/hello world: Copied (new) 2025/04/30 06:24:26 INFO : sub dir/hello world: Deleted 2025/04/30 06:24:27 INFO : nested/sub dir/file: Copied (new) 2025/04/30 06:24:27 INFO : nested/sub dir/file: Deleted 2025/04/30 06:24:27 INFO : sub dir: Removing directory 2025/04/30 06:24:27 INFO : nested/sub dir: Removing directory 2025/04/30 06:24:27 INFO : nested: Removing directory 2025/04/30 06:24:27 DEBUG : Local file system at /tmp/rclone424510806: deleted 3 directories run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestMoveWithDeleteEmptySrcDirs (17.99s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:24:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/04/30 06:24:38 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/04/30 06:24:38 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:24:38 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:24:45 INFO : sub dir/hello world: Copied (new) 2025/04/30 06:24:45 INFO : sub dir/hello world: Deleted 2025/04/30 06:24:45 INFO : nested/sub dir/file: Copied (new) 2025/04/30 06:24:45 INFO : nested/sub dir/file: Deleted run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (18.27s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:24:56 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/30 06:24:56 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/04/30 06:24:56 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:24:56 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:24:58 INFO : existing: Copied (new) 2025/04/30 06:24:58 INFO : existing: Deleted 2025/04/30 06:24:59 INFO : existing-b: Copied (new) 2025/04/30 06:24:59 INFO : existing-b: Deleted 2025/04/30 06:24:59 DEBUG : existing: Destination exists, skipping 2025/04/30 06:24:59 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/04/30 06:24:59 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:24:59 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:24:59 INFO : There was nothing to transfer run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestMoveWithIgnoreExisting (7.40s) === RUN TestServerSideMove run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:25:03 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dorulim5duwu" sync_test.go:1705: Server side move (if possible) Linkbox root 'rclone-test-jesuhin5fonu' -> Linkbox root 'rclone-test-dorulim5duwu' 2025/04/30 06:25:17 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:25:17 DEBUG : empty space: Sizes identical 2025/04/30 06:25:17 DEBUG : empty space: Unchanged skipping 2025/04/30 06:25:17 DEBUG : Linkbox root 'rclone-test-dorulim5duwu': Waiting for checks to finish 2025/04/30 06:25:17 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/30 06:25:18 ERROR : potato2: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:25:18 ERROR : potato2: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:25:18 ERROR : potato3: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:25:18 ERROR : potato3: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:25:19 INFO : empty space: Deleted 2025/04/30 06:25:19 DEBUG : Linkbox root 'rclone-test-dorulim5duwu': Waiting for transfers to finish sync_test.go:1716: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1716 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1881 Error: Received unexpected error: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestServerSideMove 2025/04/30 06:25:19 DEBUG : Linkbox root 'rclone-test-dorulim5duwu': Purge remote --- FAIL: TestServerSideMove (20.01s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:25:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-tadapag0zayo" sync_test.go:1705: Server side move (if possible) Linkbox root 'rclone-test-jesuhin5fonu' -> Linkbox root 'rclone-test-tadapag0zayo' 2025/04/30 06:25:37 DEBUG : empty space: Excluded (Size Filter) 2025/04/30 06:25:37 DEBUG : empty space: Excluded 2025/04/30 06:25:37 DEBUG : empty space: Excluded (Size Filter) 2025/04/30 06:25:37 DEBUG : empty space: Excluded 2025/04/30 06:25:37 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:25:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/30 06:25:37 DEBUG : Linkbox root 'rclone-test-tadapag0zayo': Waiting for checks to finish 2025/04/30 06:25:37 DEBUG : Linkbox root 'rclone-test-tadapag0zayo': Waiting for transfers to finish 2025/04/30 06:25:37 ERROR : potato2: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:25:37 ERROR : potato2: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:25:37 ERROR : potato3: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:25:37 ERROR : potato3: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" sync_test.go:1716: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1716 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1894 Error: Received unexpected error: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestServerSideMoveWithFilter 2025/04/30 06:25:37 DEBUG : Linkbox root 'rclone-test-tadapag0zayo': Purge remote --- FAIL: TestServerSideMoveWithFilter (20.22s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:25:43 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zibamoj1cowo" 2025/04/30 06:25:51 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) Linkbox root 'rclone-test-jesuhin5fonu' -> Linkbox root 'rclone-test-zibamoj1cowo' 2025/04/30 06:25:59 DEBUG : potato2: Need to transfer - File not found at Destination 2025/04/30 06:25:59 DEBUG : empty space: Sizes identical 2025/04/30 06:25:59 DEBUG : empty space: Unchanged skipping 2025/04/30 06:25:59 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/04/30 06:25:59 DEBUG : Linkbox root 'rclone-test-zibamoj1cowo': Waiting for checks to finish 2025/04/30 06:26:00 ERROR : potato2: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:26:00 ERROR : potato2: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:26:00 ERROR : potato3: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:26:00 ERROR : potato3: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:26:01 INFO : empty space: Deleted 2025/04/30 06:26:01 DEBUG : Linkbox root 'rclone-test-zibamoj1cowo': Waiting for transfers to finish 2025/04/30 06:26:01 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting directories as there were IO errors sync_test.go:1716: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1716 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1901 Error: Received unexpected error: not deleting directories as there were IO errors Test: TestServerSideMoveDeleteEmptySourceDirs 2025/04/30 06:26:01 DEBUG : Linkbox root 'rclone-test-zibamoj1cowo': Purge remote --- FAIL: TestServerSideMoveDeleteEmptySourceDirs (23.79s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:26:07 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/rclone-move-test" --- PASS: TestServerSideMoveOverlap (5.47s) === RUN TestSyncOverlap run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:26:13 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/rclone-sync-test" run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncOverlap (3.56s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:26:16 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/rclone-sync-test" 2025/04/30 06:26:19 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/rclone-sync-test-include/layer2" 2025/04/30 06:26:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/rclone-sync-test-ignore-file" run.go:285: Failed to put "rclone-sync-test-ignore-file/.ignore" to "Linkbox root 'rclone-test-jesuhin5fonu'": Update second step failed: Linkbox error 500: The file name needs to include a suffix, such as xxx.mp4 --- FAIL: TestSyncOverlapWithFilter (18.54s) === RUN TestSyncCompareDest run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:26:35 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/dst" 2025/04/30 06:26:36 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/CompareDest" 2025/04/30 06:26:37 DEBUG : one: Need to transfer - File not found at Destination 2025/04/30 06:26:37 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for checks to finish 2025/04/30 06:26:37 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for transfers to finish 2025/04/30 06:26:41 INFO : one: Copied (new) 2025/04/30 06:26:41 DEBUG : Waiting for deletions to finish 2025/04/30 06:26:42 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/30 06:26:42 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for checks to finish 2025/04/30 06:26:43 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for transfers to finish 2025/04/30 06:26:43 DEBUG : one: Update: removing old file 2025/04/30 06:26:46 INFO : one: Copied (replaced existing) 2025/04/30 06:26:46 DEBUG : Waiting for deletions to finish 2025/04/30 06:26:47 DEBUG : dst/one: Update: removing old file 2025/04/30 06:26:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/30 06:26:55 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for checks to finish 2025/04/30 06:26:55 DEBUG : one: Sizes identical 2025/04/30 06:26:55 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/30 06:26:55 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for transfers to finish 2025/04/30 06:26:55 DEBUG : Waiting for deletions to finish 2025/04/30 06:26:55 INFO : There was nothing to transfer 2025/04/30 06:26:59 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/30 06:27:00 DEBUG : two: Sizes identical 2025/04/30 06:27:00 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/30 06:27:00 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for checks to finish 2025/04/30 06:27:00 DEBUG : one: Sizes identical 2025/04/30 06:27:00 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/30 06:27:00 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for transfers to finish 2025/04/30 06:27:00 DEBUG : Waiting for deletions to finish 2025/04/30 06:27:00 INFO : There was nothing to transfer 2025/04/30 06:27:00 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/30 06:27:00 DEBUG : two: Sizes identical 2025/04/30 06:27:00 DEBUG : two: Destination found in --compare-dest, skipping 2025/04/30 06:27:01 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for checks to finish 2025/04/30 06:27:01 DEBUG : one: Sizes identical 2025/04/30 06:27:01 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/30 06:27:01 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for transfers to finish 2025/04/30 06:27:01 DEBUG : Waiting for deletions to finish 2025/04/30 06:27:01 INFO : There was nothing to transfer sync_test.go:2175: No hash on uploaded file so skipping compare timestamp test 2025/04/30 06:27:03 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/04/30 06:27:03 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/04/30 06:27:03 DEBUG : two: Need to transfer - File not found at Destination 2025/04/30 06:27:03 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for checks to finish 2025/04/30 06:27:03 DEBUG : one: Sizes identical 2025/04/30 06:27:03 DEBUG : one: Destination found in --compare-dest, skipping 2025/04/30 06:27:03 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dst': Waiting for transfers to finish 2025/04/30 06:27:05 INFO : two: Copied (new) 2025/04/30 06:27:05 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (40.75s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:27:25 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/dest" 2025/04/30 06:27:26 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/pre-dest1" 2025/04/30 06:27:27 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-jesuhin5fonu/pre-dest2" 2025/04/30 06:27:28 DEBUG : 1: Sizes identical 2025/04/30 06:27:28 DEBUG : 1: Destination found in --compare-dest, skipping 2025/04/30 06:27:29 DEBUG : 2: Sizes identical 2025/04/30 06:27:29 DEBUG : 2: Destination found in --compare-dest, skipping 2025/04/30 06:27:29 DEBUG : 3: Need to transfer - File not found at Destination 2025/04/30 06:27:29 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dest': Waiting for checks to finish 2025/04/30 06:27:29 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu/dest': Waiting for transfers to finish 2025/04/30 06:27:33 INFO : 3: Copied (new) 2025/04/30 06:27:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (28.55s) === RUN TestSyncCopyDest run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2235: Skipping test as remote does not support server-side copy --- SKIP: TestSyncCopyDest (0.75s) === RUN TestSyncBackupDir run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2348: Skipping test as remote does not support server-side move --- SKIP: TestSyncBackupDir (0.38s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2348: Skipping test as remote does not support server-side move --- SKIP: TestSyncBackupDirWithSuffix (0.56s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2348: Skipping test as remote does not support server-side move --- SKIP: TestSyncBackupDirWithSuffixKeepExtension (0.74s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2348: Skipping test as remote does not support server-side move --- SKIP: TestSyncBackupDirSuffixOnly (0.38s) === RUN TestSyncSuffix run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2449: Skipping test as remote does not support server-side move --- SKIP: TestSyncSuffix (0.40s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2449: Skipping test as remote does not support server-side move --- SKIP: TestSyncSuffixKeepExtension (0.45s) === RUN TestSyncUTFNorm run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:27:50 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:27:50 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/04/30 06:27:50 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:27:50 DEBUG : Testêé: Update: removing old file 2025/04/30 06:27:54 INFO : Testêé: Copied (replaced existing) to: Testêé 2025/04/30 06:27:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (8.21s) === RUN TestSyncImmutable run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:27:56 DEBUG : existing: Need to transfer - File not found at Destination 2025/04/30 06:27:56 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:27:56 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:27:59 INFO : existing: Copied (new) 2025/04/30 06:27:59 DEBUG : Waiting for deletions to finish run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:27:59 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/04/30 06:27:59 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/04/30 06:27:59 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:27:59 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:27:59 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting files as there were IO errors 2025/04/30 06:27:59 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting directories as there were IO errors run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncImmutable (6.52s) === RUN TestSyncIgnoreCase run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2606: Skipping test as local or remote are case-insensitive --- SKIP: TestSyncIgnoreCase (0.73s) === RUN TestFixCase run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:28:20 DEBUG : existing: Sizes identical 2025/04/30 06:28:20 DEBUG : existing: Unchanged skipping 2025/04/30 06:28:20 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2025/04/30 06:28:20 ERROR : EXISTING: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:20 ERROR : EXISTING: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:20 ERROR : EXISTING: Error while attempting to rename to existing: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:20 ERROR : EXISTINGBUTDIFFERENT: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:20 ERROR : EXISTINGBUTDIFFERENT: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:20 ERROR : EXISTINGBUTDIFFERENT: Error while attempting to rename to existingbutdifferent: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:20 DEBUG : EXISTINGBUTDIFFERENT: Update: removing old file 2025/04/30 06:28:26 INFO : existingbutdifferent: Copied (replaced existing) to: EXISTINGBUTDIFFERENT 2025/04/30 06:28:28 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:28 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:29 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Failed to copy: failed to open source object: Open failed: Get "https://aht.nuplink2.net/pool/pub/be/a0/0a/1b908e69c96485706095ed1b37bea00a/8?token=227d3c387c84bc80e2dc89a035563c9c&ts=1745994501&ip=65.108.87.141": context canceled 2025/04/30 06:28:29 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Not deleting source as copy failed: failed to open source object: Open failed: Get "https://aht.nuplink2.net/pool/pub/be/a0/0a/1b908e69c96485706095ed1b37bea00a/8?token=227d3c387c84bc80e2dc89a035563c9c&ts=1745994501&ip=65.108.87.141": context canceled 2025/04/30 06:28:29 ERROR : SUBDIRA: Error while attempting to rename to subdira: RenameDir renames: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:35 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:35 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:35 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Failed to copy: failed to open source object: Open failed: Get "https://aht.nuplink2.net/pool/pub/be/a0/0a/1b908e69c96485706095ed1b37bea00a/8?token=468acb461f2971cd5b22796d941fbba5&ts=1745994510&ip=65.108.87.141": context canceled 2025/04/30 06:28:35 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Not deleting source as copy failed: failed to open source object: Open failed: Get "https://aht.nuplink2.net/pool/pub/be/a0/0a/1b908e69c96485706095ed1b37bea00a/8?token=468acb461f2971cd5b22796d941fbba5&ts=1745994510&ip=65.108.87.141": context canceled 2025/04/30 06:28:35 ERROR : SUBDIRA/subdirb: Error while attempting to rename to subdira/subdirb: RenameDir renames: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:40 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:40 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:40 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Failed to copy: failed to open source object: Open failed: Get "https://aht.nuplink2.net/pool/pub/be/a0/0a/1b908e69c96485706095ed1b37bea00a/8?token=2106c97f0a4f17dcddd468a933b804eb&ts=1745994516&ip=65.108.87.141": context canceled 2025/04/30 06:28:40 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Not deleting source as copy failed: failed to open source object: Open failed: Get "https://aht.nuplink2.net/pool/pub/be/a0/0a/1b908e69c96485706095ed1b37bea00a/8?token=2106c97f0a4f17dcddd468a933b804eb&ts=1745994516&ip=65.108.87.141": context canceled 2025/04/30 06:28:40 ERROR : SUBDIRA/subdirb/SUBDIRC: Error while attempting to rename to subdira/subdirb/subdirc: RenameDir renames: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:41 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8) 2025/04/30 06:28:41 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:41 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:41 ERROR : SUBDIRA/subdirb/SUBDIRC/HELLO: Error while attempting to rename to subdira/subdirb/subdirc/hello: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:41 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Update: removing old file 2025/04/30 06:28:44 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:44 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:44 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird: Error while attempting to rename to subdira/subdirb/subdirc/subdird: RenameDir renames: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:45 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8) 2025/04/30 06:28:45 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:28:45 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Failed to copy: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:45 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Not deleting source as copy failed: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:45 ERROR : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Error while attempting to rename to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" 2025/04/30 06:28:45 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:28:45 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Update: removing old file 2025/04/30 06:28:45 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) to: SUBDIRA/subdirb/SUBDIRC/HELLO 2025/04/30 06:28:48 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing) to: SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences 2025/04/30 06:28:48 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting files as there were IO errors 2025/04/30 06:28:48 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting directories as there were IO errors sync_test.go:2655: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2655 Error: Received unexpected error: failed to open source object: Open failed: HTTP error 403 (403 Forbidden) returned body: "ip incorrect" Test: TestFixCase --- FAIL: TestFixCase (80.39s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (2.24s) --- SKIP: TestMaxTransfer/Hard (1.45s) --- SKIP: TestMaxTransfer/Soft (0.38s) --- SKIP: TestMaxTransfer/Cautious (0.41s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache 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:2744 /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), only0 (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (6) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (6), only0(1) (6), only1 (6), only10 (6), only11 (6), only12 (6), only13 (6), only14 (6), only15 (6), only16 (6), only17 (6), only18 (6), only19 (6), only2 (6), only3 (6), only4 (6), only5 (6), only6 (6), only7 (6), only8 (6), only9 (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:2744 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2758 Error: Should be true Test: TestSyncConcurrentDelete Messages: Unexpected file "only0(1)" 2025/04/30 06:31:28 DEBUG : both0: Sizes identical 2025/04/30 06:31:28 DEBUG : both1: Sizes identical 2025/04/30 06:31:28 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:31:28 DEBUG : both11: Sizes identical 2025/04/30 06:31:28 DEBUG : both10: Sizes identical 2025/04/30 06:31:28 DEBUG : both0: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both12: Sizes identical 2025/04/30 06:31:28 DEBUG : both1: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both13: Sizes identical 2025/04/30 06:31:28 DEBUG : both13: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both14: Sizes identical 2025/04/30 06:31:28 DEBUG : both14: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both15: Sizes identical 2025/04/30 06:31:28 DEBUG : both11: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both16: Sizes identical 2025/04/30 06:31:28 DEBUG : both16: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both17: Sizes identical 2025/04/30 06:31:28 DEBUG : both17: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both18: Sizes identical 2025/04/30 06:31:28 DEBUG : both18: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both19: Sizes identical 2025/04/30 06:31:28 DEBUG : both19: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both2: Sizes identical 2025/04/30 06:31:28 DEBUG : both10: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both3: Sizes identical 2025/04/30 06:31:28 DEBUG : both3: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both4: Sizes identical 2025/04/30 06:31:28 DEBUG : both12: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both5: Sizes identical 2025/04/30 06:31:28 DEBUG : both15: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both6: Sizes identical 2025/04/30 06:31:28 DEBUG : both2: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both7: Sizes identical 2025/04/30 06:31:28 DEBUG : both7: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both8: Sizes identical 2025/04/30 06:31:28 DEBUG : both4: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both9: Sizes identical 2025/04/30 06:31:28 DEBUG : both9: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both5: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both6: Unchanged skipping 2025/04/30 06:31:28 DEBUG : both8: Unchanged skipping 2025/04/30 06:31:28 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:31:28 DEBUG : Waiting for deletions to finish 2025/04/30 06:31:29 INFO : only4: Deleted 2025/04/30 06:31:29 INFO : only6: Deleted 2025/04/30 06:31:29 INFO : only18: Deleted 2025/04/30 06:31:30 INFO : only2: Deleted 2025/04/30 06:31:30 INFO : only5: Deleted 2025/04/30 06:31:30 INFO : only17: Deleted 2025/04/30 06:31:31 INFO : only7: Deleted 2025/04/30 06:31:31 INFO : only9: Deleted 2025/04/30 06:31:32 INFO : only0(1): Deleted 2025/04/30 06:31:32 INFO : only11: Deleted 2025/04/30 06:31:32 INFO : only15: Deleted 2025/04/30 06:31:32 INFO : only3: Deleted 2025/04/30 06:31:33 INFO : only13: Deleted 2025/04/30 06:31:33 INFO : only14: Deleted 2025/04/30 06:31:33 INFO : only16: Deleted 2025/04/30 06:31:34 INFO : only19: Deleted 2025/04/30 06:31:34 INFO : only0: Deleted 2025/04/30 06:31:35 INFO : only1: Deleted 2025/04/30 06:31:35 INFO : only10: Deleted 2025/04/30 06:31:35 INFO : only12: Deleted 2025/04/30 06:31:36 INFO : only8: Deleted run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- FAIL: TestSyncConcurrentDelete (158.87s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:33:29 DEBUG : both0: Sizes identical 2025/04/30 06:33:29 DEBUG : both1: Sizes identical 2025/04/30 06:33:29 DEBUG : both10: Sizes identical 2025/04/30 06:33:29 DEBUG : both11: Sizes identical 2025/04/30 06:33:29 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for checks to finish 2025/04/30 06:33:29 DEBUG : both0: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both12: Sizes identical 2025/04/30 06:33:29 DEBUG : both12: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both13: Sizes identical 2025/04/30 06:33:29 DEBUG : both13: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both14: Sizes identical 2025/04/30 06:33:29 DEBUG : both14: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both15: Sizes identical 2025/04/30 06:33:29 DEBUG : both15: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both16: Sizes identical 2025/04/30 06:33:29 DEBUG : both16: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both17: Sizes identical 2025/04/30 06:33:29 DEBUG : both17: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both18: Sizes identical 2025/04/30 06:33:29 DEBUG : both18: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both19: Sizes identical 2025/04/30 06:33:29 DEBUG : both11: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both2: Sizes identical 2025/04/30 06:33:29 DEBUG : both2: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both3: Sizes identical 2025/04/30 06:33:29 DEBUG : both3: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both4: Sizes identical 2025/04/30 06:33:29 DEBUG : both4: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both5: Sizes identical 2025/04/30 06:33:29 DEBUG : both5: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both6: Sizes identical 2025/04/30 06:33:29 DEBUG : both6: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both7: Sizes identical 2025/04/30 06:33:29 DEBUG : both7: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both8: Sizes identical 2025/04/30 06:33:29 DEBUG : both8: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both9: Sizes identical 2025/04/30 06:33:29 DEBUG : both9: Unchanged skipping 2025/04/30 06:33:29 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : both1: Unchanged skipping 2025/04/30 06:33:29 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : both19: Unchanged skipping 2025/04/30 06:33:29 DEBUG : both10: Unchanged skipping 2025/04/30 06:33:29 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 ERROR : only0: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 ERROR : only10: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only1: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only11: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 ERROR : only12: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/04/30 06:33:29 ERROR : only14: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only15: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only17: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only16: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Waiting for transfers to finish 2025/04/30 06:33:29 ERROR : only2: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only13: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only19: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only3: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only18: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only7: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only8: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only9: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only5: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only6: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : only4: Failed to copy: can't upload empty files to this remote 2025/04/30 06:33:29 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting files as there were IO errors 2025/04/30 06:33:29 ERROR : Linkbox root 'rclone-test-jesuhin5fonu': not deleting directories as there were IO errors run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" sync_test.go:2750: Skip test because remote cannot upload empty files --- SKIP: TestSyncConcurrentTruncate (137.08s) === RUN TestSyncReplaceDirModTime run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:34:22 DEBUG : empty_dir: Making directory with metadata 2025/04/30 06:34:22 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/30 06:34:22 DEBUG : empty_on_remote: Making directory with metadata 2025/04/30 06:34:22 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/30 06:34:22 INFO : empty_on_remote: Making directory 2025/04/30 06:34:23 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:34:31 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/04/30 06:34:31 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/04/30 06:34:31 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/04/30 06:34:31 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncReplaceDirModTime (23.41s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:34:45 DEBUG : empty_dir: Making directory with metadata 2025/04/30 06:34:45 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/30 06:34:45 DEBUG : empty_on_remote: Making directory with metadata 2025/04/30 06:34:45 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/04/30 06:34:45 INFO : empty_on_remote: Making directory 2025/04/30 06:34:47 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:34:56 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/04/30 06:34:56 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/04/30 06:34:56 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/04/30 06:34:56 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/04/30 06:34:56 INFO : empty_dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (26.95s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:35:12 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/30 06:35:12 INFO : sub dir: Making directory 2025/04/30 06:35:14 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestNothingToTransferWithEmptyDirs (41.58s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" 2025/04/30 06:35:54 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/30 06:35:54 INFO : sub dir: Making directory 2025/04/30 06:35:55 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" run.go:180: Remote "Linkbox root 'rclone-test-jesuhin5fonu'", Local "Local file system at /tmp/rclone424510806", Modify Window "876000h0m0s" --- PASS: TestNothingToTransferWithoutEmptyDirs (41.55s) FAIL 2025/04/30 06:36:35 DEBUG : Linkbox root 'rclone-test-jesuhin5fonu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose" - Finished ERROR in 20m38.882837895s (try 1/5): exit status 1: Failed [TestServerSideCopy TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestCopyRedownload TestServerSideMove TestServerSideMoveWithFilter TestServerSideMoveDeleteEmptySourceDirs TestSyncConcurrentDelete]