"./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose" - Starting (try 1/5) 2026/09/07 05:11:06 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike" 2026/09/07 05:11:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/07 05:11:06 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:11:07 DEBUG : Creating backend with remote "/tmp/rclone4285399326" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.03s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/patternNeedsGlob === RUN TestNewLess/patternRejectsEmptyGlob === RUN TestNewLess/patternRejectsBadGlob === RUN TestNewLess/patternOrder === 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/patternNeedsGlob (0.00s) --- PASS: TestNewLess/patternRejectsEmptyGlob (0.00s) --- PASS: TestNewLess/patternRejectsBadGlob (0.00s) --- PASS: TestNewLess/patternOrder (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:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:07 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:11:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:11:07 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2026/09/07 05:11:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:07 NOTICE: sub dir: Skipped set directory modification time as --dry-run is set --- PASS: TestCopyWithDryRun (0.12s) === RUN TestCopy run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:07 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:07 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:11:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:11:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:17 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:11:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/07 05:11:17 DEBUG : sub dir/hello world: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:11:27 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:11:27 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/09/07 05:11:27 DEBUG : sub dir/hello world: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:11:28 DEBUG : pacer: Reducing sleep to 30ms 2026/09/07 05:11:28 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:11:28 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:28 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:11:28 DEBUG : pacer: Reducing sleep to 22.5ms 2026/09/07 05:11:28 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:28 DEBUG : pacer: Reducing sleep to 16.875ms 2026/09/07 05:11:28 DEBUG : pacer: Reducing sleep to 12.65625ms 2026/09/07 05:11:28 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestCopy (21.51s) === RUN TestCopyMetadata run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:28 DEBUG : metadata sub dir: Making directory with metadata 2026/09/07 05:11:28 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/07 05:11:28 DEBUG : empty metadata sub dir: Making directory with metadata 2026/09/07 05:11:28 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/07 05:11:28 DEBUG : Local file system at /tmp/rclone4285399326: File to upload is small (21 bytes), uploading instead of streaming 2026/09/07 05:11:28 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/09/07 05:11:28 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2026/09/07 05:11:28 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2026/09/07 05:11:28 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:28 INFO : empty metadata sub dir: Making directory 2026/09/07 05:11:28 INFO : empty metadata sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:28 INFO : metadata sub dir: Making directory 2026/09/07 05:11:28 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:28 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2026/09/07 05:11:28 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:28 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:28 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/09/07 05:11:28 DEBUG : metadata sub dir/hello metadata world: sha256 = 64d5652158c99e4dd08097075e4e76b287f3b63667c0968c7867720a543c6916 OK 2026/09/07 05:11:28 INFO : metadata sub dir/hello metadata world: Copied (new) --- PASS: TestCopyMetadata (0.42s) === RUN TestCopyMetadataNoEmptyDirs run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:29 DEBUG : metadata sub dir: Making directory with metadata 2026/09/07 05:11:29 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/07 05:11:29 DEBUG : empty metadata sub dir: Making directory with metadata 2026/09/07 05:11:29 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/07 05:11:29 DEBUG : Local file system at /tmp/rclone4285399326: File to upload is small (21 bytes), uploading instead of streaming 2026/09/07 05:11:29 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/09/07 05:11:29 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2026/09/07 05:11:29 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2026/09/07 05:11:29 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:29 DEBUG : Added delayed dir = "empty metadata sub dir", newDst= 2026/09/07 05:11:29 DEBUG : Added delayed dir = "metadata sub dir", newDst= 2026/09/07 05:11:29 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2026/09/07 05:11:29 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:29 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:39 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:11:39 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/07 05:11:39 DEBUG : metadata sub dir/hello metadata world: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:11:49 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:11:49 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/09/07 05:11:49 DEBUG : metadata sub dir/hello metadata world: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:11:57 DEBUG : pacer: Reducing sleep to 30ms 2026/09/07 05:11:57 DEBUG : metadata sub dir/hello metadata world: size = 21 OK 2026/09/07 05:11:57 DEBUG : metadata sub dir/hello metadata world: sha256 = 64d5652158c99e4dd08097075e4e76b287f3b63667c0968c7867720a543c6916 OK 2026/09/07 05:11:57 INFO : metadata sub dir/hello metadata world: Copied (new) 2026/09/07 05:11:57 DEBUG : pacer: Reducing sleep to 22.5ms 2026/09/07 05:11:57 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:57 DEBUG : pacer: Reducing sleep to 16.875ms 2026/09/07 05:11:57 DEBUG : pacer: Reducing sleep to 12.65625ms 2026/09/07 05:11:57 DEBUG : pacer: Reducing sleep to 10ms --- PASS: TestCopyMetadataNoEmptyDirs (28.23s) === RUN TestCopyMissingDirectory run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:57 DEBUG : Creating backend with remote "/non-existing" 2026/09/07 05:11:57 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2026/09/07 05:11:57 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:57 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.07s) === RUN TestCopyNoTraverse run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:57 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:11:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:11:57 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:57 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:57 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:11:57 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:57 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:11:57 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyNoTraverse (0.29s) === RUN TestCopyNoTraverseDeadlock run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:223: Only runs on local --- SKIP: TestCopyNoTraverseDeadlock (0.04s) === RUN TestCopyCheckFirst run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:57 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Running all checks before starting transfers 2026/09/07 05:11:57 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:11:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:11:57 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:57 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Checks finished, now starting transfers 2026/09/07 05:11:57 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:57 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:11:57 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:57 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:11:57 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyCheckFirst (0.29s) === RUN TestSyncNoTraverse run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:58 ERROR : Ignoring --no-traverse with sync 2026/09/07 05:11:58 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:11:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:11:58 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:58 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:58 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:11:58 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:58 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:11:58 DEBUG : Waiting for deletions to finish 2026/09/07 05:11:58 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncNoTraverse (0.29s) === RUN TestCopyWithDepth run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:58 DEBUG : hello world2: Need to transfer - File not found at Destination 2026/09/07 05:11:58 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:11:58 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:58 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:58 DEBUG : hello world2: size = 12 OK 2026/09/07 05:11:58 DEBUG : hello world2: sha256 = f93c20b30171d10e773dc2a2d8ed59524b25baddf381b83fcc4ec40f50bedb33 OK 2026/09/07 05:11:58 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (0.14s) === RUN TestCopyWithFilesFrom run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:58 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:11:58 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:58 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:58 DEBUG : potato2: size = 11 OK 2026/09/07 05:11:58 DEBUG : potato2: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:58 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (0.19s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:58 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:11:58 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:58 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:58 DEBUG : potato2: size = 11 OK 2026/09/07 05:11:58 DEBUG : potato2: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:58 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.20s) === RUN TestCopyEmptyDirectories run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:58 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/09/07 05:11:58 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/07 05:11:58 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:58 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:58 INFO : sub dir2: Making directory 2026/09/07 05:11:58 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:58 INFO : sub dir: Making directory 2026/09/07 05:11:58 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:11:58 INFO : sub dir2/sub sub dir2: Making directory 2026/09/07 05:11:59 INFO : sub dir2/sub sub dir2: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:59 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:59 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:59 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:11:59 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyEmptyDirectories (0.51s) === RUN TestCopyNoEmptyDirectories run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:59 INFO : sub dir2: Making directory 2026/09/07 05:11:59 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2026/09/07 05:11:59 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/07 05:11:59 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/09/07 05:11:59 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:11:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:11:59 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst= 2026/09/07 05:11:59 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:59 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:59 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:11:59 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:59 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:11:59 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyNoEmptyDirectories (0.29s) === RUN TestMoveEmptyDirectories run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:11:59 DEBUG : sub dir2: Making directory with metadata 2026/09/07 05:11:59 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/07 05:11:59 INFO : sub dir2: Making directory 2026/09/07 05:11:59 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:59 INFO : sub dir: Making directory 2026/09/07 05:11:59 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:11:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:11:59 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:11:59 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:11:59 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:11:59 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:11:59 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:11:59 INFO : sub dir/hello world: Deleted --- PASS: TestMoveEmptyDirectories (0.44s) === RUN TestSyncNoUpdateDirModtime run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:00 DEBUG : sub dir no update dir modtime: Making directory with metadata 2026/09/07 05:12:00 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/07 05:12:00 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:00 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoUpdateDirModtime (0.25s) === RUN TestMoveNoEmptyDirectories run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:00 INFO : sub dir2: Making directory 2026/09/07 05:12:00 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/09/07 05:12:00 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:12:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:00 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:00 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:00 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:12:00 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:00 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:00 INFO : sub dir/hello world: Deleted 2026/09/07 05:12:00 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestMoveNoEmptyDirectories (0.34s) === RUN TestSyncEmptyDirectories run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:00 DEBUG : sub dir2: Making directory with metadata 2026/09/07 05:12:00 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/07 05:12:00 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:00 INFO : sub dir2: Making directory 2026/09/07 05:12:00 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:00 INFO : sub dir: Making directory 2026/09/07 05:12:00 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:00 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:00 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:00 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:12:00 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:00 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (0.45s) === RUN TestSyncSetDelayedModTimes run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:538: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.04s) === RUN TestSyncNoEmptyDirectories run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:01 INFO : sub dir2: Making directory 2026/09/07 05:12:01 DEBUG : Added delayed dir = "sub dir2", newDst= 2026/09/07 05:12:01 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:12:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:01 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:01 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:01 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:12:01 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:01 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:01 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:01 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncNoEmptyDirectories (0.30s) === RUN TestServerSideCopy run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:01 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-revotef7vede" 2026/09/07 05:12:01 INFO : pixeldrain root 'rclone-test-revotef7vede': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 sync_test.go:620: Server side copy (if possible) pixeldrain root 'rclone-test-yodidaw8wike' -> pixeldrain root 'rclone-test-revotef7vede' 2026/09/07 05:12:01 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:12:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:01 DEBUG : pixeldrain root 'rclone-test-revotef7vede': Waiting for checks to finish 2026/09/07 05:12:01 DEBUG : pixeldrain root 'rclone-test-revotef7vede': Waiting for transfers to finish 2026/09/07 05:12:01 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:12:01 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:01 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:01 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:01 DEBUG : pixeldrain root 'rclone-test-revotef7vede': Purge remote --- PASS: TestServerSideCopy (0.65s) === RUN TestCopyOverSelf run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:02 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2026/09/07 05:12:02 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:02 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:02 DEBUG : sub dir/hello world: size = 11 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:02 DEBUG : sub dir/hello world: Sizes differ 2026/09/07 05:12:02 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:02 DEBUG : sub dir/hello world: size = 17 OK 2026/09/07 05:12:02 DEBUG : sub dir/hello world: sha256 = cbb2d50bd9c870e0f098b9230d66d2f74d55544f2e57f1aabe3830443a040eb6 OK 2026/09/07 05:12:02 INFO : sub dir/hello world: Copied (replaced existing) 2026/09/07 05:12:02 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyOverSelf (0.39s) === RUN TestServerSideCopyOverSelf run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:02 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-sijufet6bafo" 2026/09/07 05:12:02 INFO : pixeldrain root 'rclone-test-sijufet6bafo': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 sync_test.go:656: Server side copy (if possible) pixeldrain root 'rclone-test-yodidaw8wike' -> pixeldrain root 'rclone-test-sijufet6bafo' 2026/09/07 05:12:02 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:12:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:02 DEBUG : pixeldrain root 'rclone-test-sijufet6bafo': Waiting for checks to finish 2026/09/07 05:12:02 DEBUG : pixeldrain root 'rclone-test-sijufet6bafo': Waiting for transfers to finish 2026/09/07 05:12:02 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:12:02 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:02 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:02 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:03 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms) 2026/09/07 05:12:03 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2026/09/07 05:12:03 DEBUG : sub dir/hello world: size = 17 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:03 DEBUG : sub dir/hello world: size = 11 (pixeldrain root 'rclone-test-sijufet6bafo') 2026/09/07 05:12:03 DEBUG : sub dir/hello world: Sizes differ 2026/09/07 05:12:03 DEBUG : pixeldrain root 'rclone-test-sijufet6bafo': Waiting for checks to finish 2026/09/07 05:12:03 DEBUG : pixeldrain root 'rclone-test-sijufet6bafo': Waiting for transfers to finish 2026/09/07 05:12:03 DEBUG : sub dir/hello world: size = 17 OK 2026/09/07 05:12:03 DEBUG : sub dir/hello world: sha256 = cbb2d50bd9c870e0f098b9230d66d2f74d55544f2e57f1aabe3830443a040eb6 OK 2026/09/07 05:12:03 INFO : sub dir/hello world: Copied (replaced existing) 2026/09/07 05:12:03 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:03 DEBUG : pixeldrain root 'rclone-test-sijufet6bafo': Purge remote --- PASS: TestServerSideCopyOverSelf (1.12s) === RUN TestMoveOverSelf run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:03 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2026/09/07 05:12:03 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:03 DEBUG : sub dir/hello world: size = 11 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:03 DEBUG : sub dir/hello world: Sizes differ 2026/09/07 05:12:03 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:03 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:03 DEBUG : sub dir/hello world: size = 17 OK 2026/09/07 05:12:03 DEBUG : sub dir/hello world: sha256 = cbb2d50bd9c870e0f098b9230d66d2f74d55544f2e57f1aabe3830443a040eb6 OK 2026/09/07 05:12:03 INFO : sub dir/hello world: Copied (replaced existing) 2026/09/07 05:12:03 INFO : sub dir/hello world: Deleted 2026/09/07 05:12:03 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestMoveOverSelf (0.40s) === RUN TestServerSideMoveOverSelf run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:04 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-ginovis8fesi" 2026/09/07 05:12:04 INFO : pixeldrain root 'rclone-test-ginovis8fesi': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 sync_test.go:701: Server side copy (if possible) pixeldrain root 'rclone-test-yodidaw8wike' -> pixeldrain root 'rclone-test-ginovis8fesi' 2026/09/07 05:12:04 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:12:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:04 DEBUG : pixeldrain root 'rclone-test-ginovis8fesi': Waiting for checks to finish 2026/09/07 05:12:04 DEBUG : pixeldrain root 'rclone-test-ginovis8fesi': Waiting for transfers to finish 2026/09/07 05:12:04 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:12:04 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:04 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:04 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:04 DEBUG : pixeldrain root 'rclone-test-ginovis8fesi': Using server-side directory move 2026/09/07 05:12:04 INFO : pixeldrain root 'rclone-test-ginovis8fesi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/09/07 05:12:04 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms) 2026/09/07 05:12:04 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2026/09/07 05:12:04 DEBUG : pixeldrain root 'rclone-test-ginovis8fesi': Waiting for checks to finish 2026/09/07 05:12:04 DEBUG : sub dir/hello world: size = 17 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:04 DEBUG : sub dir/hello world: size = 11 (pixeldrain root 'rclone-test-ginovis8fesi') 2026/09/07 05:12:04 DEBUG : sub dir/hello world: Sizes differ 2026/09/07 05:12:04 DEBUG : pixeldrain root 'rclone-test-ginovis8fesi': Waiting for transfers to finish 2026/09/07 05:12:04 INFO : sub dir/hello world: Deleted 2026/09/07 05:12:04 INFO : sub dir/hello world: Moved (server-side) 2026/09/07 05:12:04 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:04 DEBUG : testing file moves 2026/09/07 05:12:04 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms) 2026/09/07 05:12:04 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2026/09/07 05:12:04 DEBUG : sub dir/hello world: size = 24 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:04 DEBUG : sub dir/hello world: size = 17 (pixeldrain root 'rclone-test-ginovis8fesi') 2026/09/07 05:12:04 DEBUG : sub dir/hello world: Sizes differ 2026/09/07 05:12:04 DEBUG : pixeldrain root 'rclone-test-ginovis8fesi': Waiting for checks to finish 2026/09/07 05:12:04 DEBUG : pixeldrain root 'rclone-test-ginovis8fesi': Waiting for transfers to finish 2026/09/07 05:12:04 INFO : sub dir/hello world: Deleted 2026/09/07 05:12:04 INFO : sub dir/hello world: Moved (server-side) 2026/09/07 05:12:04 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:05 DEBUG : pixeldrain root 'rclone-test-ginovis8fesi': Purge remote --- PASS: TestServerSideMoveOverSelf (1.16s) === RUN TestCopyAfterDelete run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:05 ERROR : error listing: directory not found 2026/09/07 05:12:05 INFO : Local file system at /tmp/rclone4285399326: Making directory 2026/09/07 05:12:05 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:05 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (0.33s) === RUN TestCopyRedownload run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:05 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:12:05 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:05 DEBUG : Local file system at /tmp/rclone4285399326: Waiting for checks to finish 2026/09/07 05:12:05 DEBUG : Local file system at /tmp/rclone4285399326: Waiting for transfers to finish 2026/09/07 05:12:05 DEBUG : sub dir/hello world.a28b5138.partial: size = 11 OK 2026/09/07 05:12:05 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:05 DEBUG : sub dir/hello world.a28b5138.partial: renamed to: sub dir/hello world 2026/09/07 05:12:05 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:05 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyRedownload (0.31s) === RUN TestSyncBasedOnCheckSum run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:05 DEBUG : check sum: Need to transfer - File not found at Destination 2026/09/07 05:12:05 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:05 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:05 DEBUG : check sum: size = 1 OK 2026/09/07 05:12:05 DEBUG : check sum: sha256 = 3973e022e93220f9212c18d0d0c543ae7c309e46640da93a4a0314de999f5112 OK 2026/09/07 05:12:05 INFO : check sum: Copied (new) 2026/09/07 05:12:05 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:05 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:05 DEBUG : check sum: size = 1 OK 2026/09/07 05:12:05 DEBUG : check sum: sha256 = 3973e022e93220f9212c18d0d0c543ae7c309e46640da93a4a0314de999f5112 OK 2026/09/07 05:12:05 DEBUG : check sum: Size and sha256 of src and dst objects identical 2026/09/07 05:12:05 DEBUG : check sum: Unchanged skipping 2026/09/07 05:12:05 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:05 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:05 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (0.22s) === RUN TestSyncSizeOnly run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:06 DEBUG : sizeonly: Need to transfer - File not found at Destination 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:06 DEBUG : sizeonly: size = 6 OK 2026/09/07 05:12:06 DEBUG : sizeonly: sha256 = e91c254ad58860a02c788dfb5c1a65d6a8846ab1dc649631c7db16fef4af2dec OK 2026/09/07 05:12:06 INFO : sizeonly: Copied (new) 2026/09/07 05:12:06 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:06 DEBUG : sizeonly: size = 6 OK 2026/09/07 05:12:06 DEBUG : sizeonly: Sizes identical 2026/09/07 05:12:06 DEBUG : sizeonly: Unchanged skipping 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:06 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:06 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (0.28s) === RUN TestSyncIgnoreSize run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:06 DEBUG : ignore-size: Need to transfer - File not found at Destination 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:06 DEBUG : ignore-size: sha256 = d1b2a59fbea7e20077af9f91b27e95e865061b270be03ff539ab3b73587882e8 OK 2026/09/07 05:12:06 INFO : ignore-size: Copied (new) 2026/09/07 05:12:06 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:06 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:06 DEBUG : ignore-size: Unchanged skipping 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:06 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:06 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (0.18s) === RUN TestSyncIgnoreTimes run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:06 DEBUG : existing: size = 6 OK 2026/09/07 05:12:06 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:06 DEBUG : existing: Unchanged skipping 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:06 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:06 INFO : There was nothing to transfer 2026/09/07 05:12:06 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:06 DEBUG : existing: size = 6 OK 2026/09/07 05:12:06 DEBUG : existing: sha256 = e91c254ad58860a02c788dfb5c1a65d6a8846ab1dc649631c7db16fef4af2dec OK 2026/09/07 05:12:06 INFO : existing: Copied (replaced existing) 2026/09/07 05:12:06 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (0.40s) === RUN TestSyncIgnoreExisting run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:06 DEBUG : existing: Need to transfer - File not found at Destination 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:06 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:07 DEBUG : existing: size = 6 OK 2026/09/07 05:12:07 DEBUG : existing: sha256 = e91c254ad58860a02c788dfb5c1a65d6a8846ab1dc649631c7db16fef4af2dec OK 2026/09/07 05:12:07 INFO : existing: Copied (new) 2026/09/07 05:12:07 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:07 DEBUG : existing: Destination exists, skipping 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:07 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:07 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (0.28s) === RUN TestSyncIgnoreErrors run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:07 INFO : d: Making directory 2026/09/07 05:12:07 DEBUG : Added delayed dir = "a", newDst= 2026/09/07 05:12:07 DEBUG : Added delayed dir = "c", newDst=c 2026/09/07 05:12:07 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:07 DEBUG : c/non empty space: size = 5 OK 2026/09/07 05:12:07 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:07 DEBUG : c/non empty space: Unchanged skipping 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:07 DEBUG : a/potato2: size = 60 OK 2026/09/07 05:12:07 DEBUG : a/potato2: sha256 = d398f81cd00b370b116d049d2f3b73a3a7ed35446486effb789791a7e0b98e9c OK 2026/09/07 05:12:07 INFO : a/potato2: Copied (new) 2026/09/07 05:12:07 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:07 INFO : b/potato: Deleted 2026/09/07 05:12:07 INFO : c: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:07 INFO : a: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:07 INFO : d: Removing directory 2026/09/07 05:12:07 INFO : b: Removing directory 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': deleted 2 directories --- PASS: TestSyncIgnoreErrors (0.58s) === RUN TestSyncAfterChangingModtimeOnly run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:07 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:07 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:07 DEBUG : empty space: sha256 = 3973e022e93220f9212c18d0d0c543ae7c309e46640da93a4a0314de999f5112 OK 2026/09/07 05:12:07 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2026/09/07 05:12:07 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:07 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:07 INFO : There was nothing to transfer 2026/09/07 05:12:07 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:07 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:07 DEBUG : empty space: sha256 = 3973e022e93220f9212c18d0d0c543ae7c309e46640da93a4a0314de999f5112 OK 2026/09/07 05:12:07 INFO : empty space: Updated modification time in destination 2026/09/07 05:12:07 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:07 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:07 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:07 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (0.26s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:08 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:08 DEBUG : empty space: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2026/09/07 05:12:08 DEBUG : empty space: sha256 = 3973e022e93220f9212c18d0d0c543ae7c309e46640da93a4a0314de999f5112 OK 2026/09/07 05:12:08 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:08 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:08 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.19s) === RUN TestSyncDoesntUpdateModtime run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:08 DEBUG : foo: size = 3 OK 2026/09/07 05:12:08 DEBUG : foo: Modification times differ by -95456h54m52.624456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499 +0000 UTC 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:08 DEBUG : foo: sha256 = 2c26b46b68ffc68ff99b453c1d30413413422d706483bfa0f98a5e886266e7ae (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:08 DEBUG : foo: sha256 = fcde2b2edba56bf408601fb721fe9b5c338d10ee429ea04fae5511b68fbf8fb9 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:08 DEBUG : foo: sha256 differ 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:08 DEBUG : foo: size = 3 OK 2026/09/07 05:12:08 DEBUG : foo: sha256 = 2c26b46b68ffc68ff99b453c1d30413413422d706483bfa0f98a5e886266e7ae OK 2026/09/07 05:12:08 INFO : foo: Copied (replaced existing) 2026/09/07 05:12:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (0.19s) === RUN TestSyncAfterAddingAFile run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:08 DEBUG : potato: Need to transfer - File not found at Destination 2026/09/07 05:12:08 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:08 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:08 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:08 DEBUG : potato: size = 60 OK 2026/09/07 05:12:08 DEBUG : potato: sha256 = d398f81cd00b370b116d049d2f3b73a3a7ed35446486effb789791a7e0b98e9c OK 2026/09/07 05:12:08 INFO : potato: Copied (new) 2026/09/07 05:12:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (0.27s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:08 DEBUG : potato: size = 21 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:08 DEBUG : potato: size = 60 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:08 DEBUG : potato: Sizes differ 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:08 DEBUG : potato: size = 21 OK 2026/09/07 05:12:08 DEBUG : potato: sha256 = 336410c71bfff5a8f5b50e933878018424f73936e974eeb532d367e68b5b07bb OK 2026/09/07 05:12:08 INFO : potato: Copied (replaced existing) 2026/09/07 05:12:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (0.25s) === RUN TestSyncAfterChangingContentsOnly run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:08 DEBUG : potato: size = 21 OK 2026/09/07 05:12:08 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:08 DEBUG : potato: sha256 = 88dafe01907c4144f886d8d75b726b59fc8d2758b20f11088e72d603e79821f6 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:08 DEBUG : potato: sha256 = 336410c71bfff5a8f5b50e933878018424f73936e974eeb532d367e68b5b07bb (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:08 DEBUG : potato: sha256 differ 2026/09/07 05:12:08 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:09 DEBUG : potato: size = 21 OK 2026/09/07 05:12:09 DEBUG : potato: sha256 = 88dafe01907c4144f886d8d75b726b59fc8d2758b20f11088e72d603e79821f6 OK 2026/09/07 05:12:09 INFO : potato: Copied (replaced existing) 2026/09/07 05:12:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (0.20s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:09 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:09 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:09 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:09 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:09 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:09 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:09 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2026/09/07 05:12:09 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:09 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (0.22s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:09 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:09 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:09 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:09 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:09 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:09 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:09 DEBUG : potato2: size = 60 OK 2026/09/07 05:12:09 DEBUG : potato2: sha256 = d398f81cd00b370b116d049d2f3b73a3a7ed35446486effb789791a7e0b98e9c OK 2026/09/07 05:12:09 INFO : potato2: Copied (new) 2026/09/07 05:12:09 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:09 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (0.34s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:09 INFO : d: Making directory 2026/09/07 05:12:09 INFO : d/e: Making directory 2026/09/07 05:12:09 DEBUG : Added delayed dir = "a", newDst= 2026/09/07 05:12:09 DEBUG : Added delayed dir = "c", newDst=c 2026/09/07 05:12:09 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:09 DEBUG : c/non empty space: size = 5 OK 2026/09/07 05:12:09 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:09 DEBUG : c/non empty space: Unchanged skipping 2026/09/07 05:12:09 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:09 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:10 DEBUG : a/potato2: size = 60 OK 2026/09/07 05:12:10 DEBUG : a/potato2: sha256 = d398f81cd00b370b116d049d2f3b73a3a7ed35446486effb789791a7e0b98e9c OK 2026/09/07 05:12:10 INFO : a/potato2: Copied (new) 2026/09/07 05:12:10 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:10 INFO : b/potato: Deleted 2026/09/07 05:12:10 INFO : c: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:10 INFO : a: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:10 INFO : d/e: Removing directory 2026/09/07 05:12:10 INFO : d: Removing directory 2026/09/07 05:12:10 INFO : b: Removing directory 2026/09/07 05:12:10 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (0.65s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:10 INFO : d: Making directory 2026/09/07 05:12:10 DEBUG : Added delayed dir = "a", newDst= 2026/09/07 05:12:10 DEBUG : Added delayed dir = "c", newDst=c 2026/09/07 05:12:10 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:10 DEBUG : c/non empty space: size = 5 OK 2026/09/07 05:12:10 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:10 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:10 DEBUG : c/non empty space: Unchanged skipping 2026/09/07 05:12:10 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:10 DEBUG : a/potato2: size = 60 OK 2026/09/07 05:12:10 DEBUG : a/potato2: sha256 = d398f81cd00b370b116d049d2f3b73a3a7ed35446486effb789791a7e0b98e9c OK 2026/09/07 05:12:10 INFO : a/potato2: Copied (new) 2026/09/07 05:12:10 ERROR : pixeldrain root 'rclone-test-yodidaw8wike': not deleting files as there were IO errors 2026/09/07 05:12:10 INFO : c: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:10 INFO : a: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:10 ERROR : pixeldrain root 'rclone-test-yodidaw8wike': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (0.63s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:11 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:11 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:11 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:11 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:11 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:11 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:11 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:11 INFO : potato: Deleted 2026/09/07 05:12:11 DEBUG : potato2: size = 60 OK 2026/09/07 05:12:11 DEBUG : potato2: sha256 = d398f81cd00b370b116d049d2f3b73a3a7ed35446486effb789791a7e0b98e9c OK 2026/09/07 05:12:11 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (0.32s) === RUN TestSyncDeleteBefore run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:11 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:11 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:11 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:11 INFO : potato: Deleted 2026/09/07 05:12:11 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:11 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:11 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:11 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:11 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:11 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:11 DEBUG : potato2: size = 60 OK 2026/09/07 05:12:11 DEBUG : potato2: sha256 = d398f81cd00b370b116d049d2f3b73a3a7ed35446486effb789791a7e0b98e9c OK 2026/09/07 05:12:11 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (0.36s) === RUN TestCopyDeleteBefore run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:11 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:11 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:11 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:11 DEBUG : potato2: size = 19 OK 2026/09/07 05:12:11 DEBUG : potato2: sha256 = 77f6983f12e73cba27d4f9c3ee2b2c850c71b9bed2845d0cb065c75bba33783e OK 2026/09/07 05:12:11 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (0.21s) === RUN TestSyncWithExclude run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:12 DEBUG : enormous: Excluded (Size Filter) 2026/09/07 05:12:12 DEBUG : enormous: Excluded 2026/09/07 05:12:12 DEBUG : potato2: Excluded (Size Filter) 2026/09/07 05:12:12 DEBUG : potato2: Excluded 2026/09/07 05:12:12 DEBUG : potato2: Excluded (Size Filter) 2026/09/07 05:12:12 DEBUG : potato2: Excluded 2026/09/07 05:12:12 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:12 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:12 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:12 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:12 INFO : There was nothing to transfer 2026/09/07 05:12:12 DEBUG : enormous: Excluded (Size Filter) 2026/09/07 05:12:12 DEBUG : enormous: Excluded 2026/09/07 05:12:12 DEBUG : potato2: Excluded (Size Filter) 2026/09/07 05:12:12 DEBUG : potato2: Excluded 2026/09/07 05:12:12 DEBUG : potato2: Excluded (Size Filter) 2026/09/07 05:12:12 DEBUG : potato2: Excluded 2026/09/07 05:12:12 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:12 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2026/09/07 05:12:12 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:12 DEBUG : Local file system at /tmp/rclone4285399326: Waiting for checks to finish 2026/09/07 05:12:12 DEBUG : Local file system at /tmp/rclone4285399326: Waiting for transfers to finish 2026/09/07 05:12:12 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:12 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (0.29s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:12 DEBUG : enormous: Excluded (Size Filter) 2026/09/07 05:12:12 DEBUG : enormous: Excluded 2026/09/07 05:12:12 DEBUG : potato2: Excluded (Size Filter) 2026/09/07 05:12:12 DEBUG : potato2: Excluded 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:12 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:12 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:12 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:12 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:12 INFO : potato2: Deleted 2026/09/07 05:12:12 INFO : enormous: Deleted 2026/09/07 05:12:12 INFO : There was nothing to transfer 2026/09/07 05:12:12 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:12 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1ms) 2026/09/07 05:12:12 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:12 DEBUG : Local file system at /tmp/rclone4285399326: Waiting for checks to finish 2026/09/07 05:12:12 DEBUG : Local file system at /tmp/rclone4285399326: Waiting for transfers to finish 2026/09/07 05:12:12 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:12 INFO : enormous: Deleted 2026/09/07 05:12:12 INFO : potato2: Deleted 2026/09/07 05:12:12 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (0.34s) === RUN TestSyncWithUpdateOlder run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:12 DEBUG : five: Need to transfer - File not found at Destination 2026/09/07 05:12:12 DEBUG : four: size = 4 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:12 DEBUG : one: Destination is newer than source, skipping 2026/09/07 05:12:12 DEBUG : four: size = 8 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:12 DEBUG : four: Sizes differ 2026/09/07 05:12:12 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2026/09/07 05:12:12 DEBUG : three: size = 5 OK 2026/09/07 05:12:12 DEBUG : three: Sizes identical 2026/09/07 05:12:12 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2026/09/07 05:12:12 DEBUG : two: size = 3 OK 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:12 DEBUG : two: sha256 = 3fc4ccfe745870e2c0d99f71f30ff0656c8dedd41cc1d7d3d376b0dbe685e2f3 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:12 DEBUG : two: sha256 = a1a8a8cbbed4eb53ae62ee4fb0787504087232c29aa4d817757d06b68d0501ca (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:12 DEBUG : two: sha256 differ 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:12 DEBUG : five: size = 4 OK 2026/09/07 05:12:12 DEBUG : five: sha256 = 222b0bd51fcef7e65c2e62db2ed65457013bab56be6fafeb19ee11d453153c80 OK 2026/09/07 05:12:12 INFO : five: Copied (new) 2026/09/07 05:12:12 DEBUG : four: size = 4 OK 2026/09/07 05:12:12 DEBUG : four: sha256 = 04efaf080f5a3e74e1c29d1ca6a48569382cbbcd324e8d59d2b83ef21c039f00 OK 2026/09/07 05:12:12 INFO : four: Copied (replaced existing) 2026/09/07 05:12:12 DEBUG : two: size = 3 OK 2026/09/07 05:12:12 DEBUG : two: sha256 = 3fc4ccfe745870e2c0d99f71f30ff0656c8dedd41cc1d7d3d376b0dbe685e2f3 OK 2026/09/07 05:12:12 INFO : two: Copied (replaced existing) 2026/09/07 05:12:12 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:12 DEBUG : five: size = 4 OK 2026/09/07 05:12:12 DEBUG : four: size = 4 OK 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:12 DEBUG : one: Destination is newer than source, skipping 2026/09/07 05:12:12 DEBUG : three: size = 5 OK 2026/09/07 05:12:12 DEBUG : two: size = 3 OK 2026/09/07 05:12:12 DEBUG : five: sha256 = 222b0bd51fcef7e65c2e62db2ed65457013bab56be6fafeb19ee11d453153c80 OK 2026/09/07 05:12:12 DEBUG : four: sha256 = 04efaf080f5a3e74e1c29d1ca6a48569382cbbcd324e8d59d2b83ef21c039f00 OK 2026/09/07 05:12:12 DEBUG : four: Size and sha256 of src and dst objects identical 2026/09/07 05:12:12 DEBUG : two: sha256 = 3fc4ccfe745870e2c0d99f71f30ff0656c8dedd41cc1d7d3d376b0dbe685e2f3 OK 2026/09/07 05:12:12 DEBUG : two: Size and sha256 of src and dst objects identical 2026/09/07 05:12:12 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2026/09/07 05:12:12 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2026/09/07 05:12:12 DEBUG : five: Size and sha256 of src and dst objects identical 2026/09/07 05:12:12 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2026/09/07 05:12:12 DEBUG : three: sha256 = 8b5b9db0c13db24256c829aa364aa90c6d2eba318b9232a4ab9313b954d3555f (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:12 DEBUG : three: sha256 = 1d79bf60835eb5cd16cdef124413a7552857e09ff50f62c40eb09ddc55d25829 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:12 DEBUG : three: sha256 differ 2026/09/07 05:12:12 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:12 DEBUG : three: size = 5 OK 2026/09/07 05:12:12 DEBUG : three: sha256 = 8b5b9db0c13db24256c829aa364aa90c6d2eba318b9232a4ab9313b954d3555f OK 2026/09/07 05:12:12 INFO : three: Copied (replaced existing) 2026/09/07 05:12:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (0.47s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1497: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1497: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:1569: Can track renames: true 2026/09/07 05:12:12 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Making map for --track-renames 2026/09/07 05:12:12 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Finished making map for --track-renames 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for renames to finish 2026/09/07 05:12:12 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/09/07 05:12:12 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/09/07 05:12:12 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:13 DEBUG : potato: size = 14 OK 2026/09/07 05:12:13 DEBUG : potato: sha256 = 5bd1953a4838a9e209ee46b52c8b830a3a3ac4d90c486c90819aaaa56fa1e925 OK 2026/09/07 05:12:13 INFO : potato: Copied (new) 2026/09/07 05:12:13 DEBUG : yam: size = 11 OK 2026/09/07 05:12:13 DEBUG : yam: sha256 = 8da6062e5db51dacc1d55c7b00ab1c408231ee1db69ac2d192b1e9a7f3c3c51c OK 2026/09/07 05:12:13 INFO : yam: Copied (new) 2026/09/07 05:12:13 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Making map for --track-renames 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Finished making map for --track-renames 2026/09/07 05:12:13 DEBUG : potato: size = 14 OK 2026/09/07 05:12:13 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:13 DEBUG : potato: Unchanged skipping 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for renames to finish 2026/09/07 05:12:13 INFO : yam: Moved (server-side) to: yaml 2026/09/07 05:12:13 INFO : yaml: Renamed from "yam" 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:13 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:13 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenames (0.27s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:1641: Can track renames: true 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Making map for --track-renames 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Finished making map for --track-renames 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:13 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/09/07 05:12:13 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for renames to finish 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:13 DEBUG : potato: size = 14 OK 2026/09/07 05:12:13 DEBUG : potato: sha256 = 5bd1953a4838a9e209ee46b52c8b830a3a3ac4d90c486c90819aaaa56fa1e925 OK 2026/09/07 05:12:13 INFO : potato: Copied (new) 2026/09/07 05:12:13 DEBUG : yam: size = 11 OK 2026/09/07 05:12:13 DEBUG : yam: sha256 = 8da6062e5db51dacc1d55c7b00ab1c408231ee1db69ac2d192b1e9a7f3c3c51c OK 2026/09/07 05:12:13 INFO : yam: Copied (new) 2026/09/07 05:12:13 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:13 DEBUG : potato: size = 14 OK 2026/09/07 05:12:13 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Making map for --track-renames 2026/09/07 05:12:13 DEBUG : potato: Unchanged skipping 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Finished making map for --track-renames 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for renames to finish 2026/09/07 05:12:13 INFO : yam: Moved (server-side) to: yaml 2026/09/07 05:12:13 INFO : yaml: Renamed from "yam" 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:13 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:13 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyModtime (0.27s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:1680: Can track renames: true 2026/09/07 05:12:13 DEBUG : Added delayed dir = "sub", newDst= 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Making map for --track-renames 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Finished making map for --track-renames 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:13 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/09/07 05:12:13 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for renames to finish 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:13 DEBUG : potato: size = 14 OK 2026/09/07 05:12:13 DEBUG : potato: sha256 = 5bd1953a4838a9e209ee46b52c8b830a3a3ac4d90c486c90819aaaa56fa1e925 OK 2026/09/07 05:12:13 INFO : potato: Copied (new) 2026/09/07 05:12:13 DEBUG : sub/yam: size = 11 OK 2026/09/07 05:12:13 DEBUG : sub/yam: sha256 = 8da6062e5db51dacc1d55c7b00ab1c408231ee1db69ac2d192b1e9a7f3c3c51c OK 2026/09/07 05:12:13 INFO : sub/yam: Copied (new) 2026/09/07 05:12:13 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:13 INFO : sub: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:13 DEBUG : Added delayed dir = "sub", newDst=sub 2026/09/07 05:12:13 DEBUG : potato: size = 14 OK 2026/09/07 05:12:13 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:13 DEBUG : potato: Unchanged skipping 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Making map for --track-renames 2026/09/07 05:12:13 INFO : pixeldrain root 'rclone-test-yodidaw8wike': Finished making map for --track-renames 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for renames to finish 2026/09/07 05:12:13 INFO : sub/yam: Moved (server-side) to: yam 2026/09/07 05:12:13 INFO : yam: Renamed from "sub/yam" 2026/09/07 05:12:13 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:13 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:13 INFO : sub: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:13 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyLeaf (0.44s) === RUN TestServerSideMoveLocal run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:13 DEBUG : Creating backend with remote "/tmp/rclone4285399326/dir1" 2026/09/07 05:12:13 DEBUG : Creating backend with remote "/tmp/rclone4285399326/dir2" 2026/09/07 05:12:13 DEBUG : Local file system at /tmp/rclone4285399326/dir2: Using server-side directory move 2026/09/07 05:12:13 INFO : Local file system at /tmp/rclone4285399326/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/09/07 05:12:13 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/09/07 05:12:13 INFO : file1.txt: Moved (server-side) 2026/09/07 05:12:13 DEBUG : Local file system at /tmp/rclone4285399326/dir2: Waiting for checks to finish 2026/09/07 05:12:13 DEBUG : Local file system at /tmp/rclone4285399326/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.05s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:14 DEBUG : Added delayed dir = "nested", newDst= 2026/09/07 05:12:14 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:12:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:14 DEBUG : Added delayed dir = "nested/sub dir", newDst= 2026/09/07 05:12:14 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/09/07 05:12:14 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:14 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:14 DEBUG : nested/sub dir/file: size = 6 OK 2026/09/07 05:12:14 DEBUG : nested/sub dir/file: sha256 = 233562de1a0288b139c4fa40b7d189f806e906eeb048517aeb67f34ac0e2faf1 OK 2026/09/07 05:12:14 INFO : nested/sub dir/file: Copied (new) 2026/09/07 05:12:14 INFO : nested/sub dir/file: Deleted 2026/09/07 05:12:14 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:12:14 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:14 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:14 INFO : sub dir/hello world: Deleted 2026/09/07 05:12:14 INFO : nested/sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:14 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:14 INFO : nested: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:14 INFO : sub dir: Removing directory 2026/09/07 05:12:14 INFO : nested/sub dir: Removing directory 2026/09/07 05:12:14 INFO : nested: Removing directory 2026/09/07 05:12:14 DEBUG : Local file system at /tmp/rclone4285399326: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (0.45s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:14 DEBUG : Added delayed dir = "nested", newDst= 2026/09/07 05:12:14 DEBUG : Added delayed dir = "sub dir", newDst= 2026/09/07 05:12:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/09/07 05:12:14 DEBUG : Added delayed dir = "nested/sub dir", newDst= 2026/09/07 05:12:14 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2026/09/07 05:12:14 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:14 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:14 DEBUG : nested/sub dir/file: size = 6 OK 2026/09/07 05:12:14 DEBUG : nested/sub dir/file: sha256 = 233562de1a0288b139c4fa40b7d189f806e906eeb048517aeb67f34ac0e2faf1 OK 2026/09/07 05:12:14 INFO : nested/sub dir/file: Copied (new) 2026/09/07 05:12:14 INFO : nested/sub dir/file: Deleted 2026/09/07 05:12:14 DEBUG : sub dir/hello world: size = 11 OK 2026/09/07 05:12:14 DEBUG : sub dir/hello world: sha256 = b94d27b9934d3e08a52e52d7da7dabfac484efe37a5380ee9088f7ace2efcde9 OK 2026/09/07 05:12:14 INFO : sub dir/hello world: Copied (new) 2026/09/07 05:12:14 INFO : sub dir/hello world: Deleted 2026/09/07 05:12:14 INFO : nested/sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:14 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/09/07 05:12:14 INFO : nested: Set directory modification time (using DirSetModTime) --- PASS: TestMoveWithoutDeleteEmptySrcDirs (0.45s) === RUN TestMoveWithIgnoreExisting run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:14 DEBUG : existing-b: Need to transfer - File not found at Destination 2026/09/07 05:12:14 DEBUG : existing: Need to transfer - File not found at Destination 2026/09/07 05:12:14 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:14 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:14 DEBUG : existing-b: size = 6 OK 2026/09/07 05:12:14 DEBUG : existing-b: sha256 = 5ed728c2fa5d767bc6c1ec6a732db1e37c343be46913e6498d340f7782691f14 OK 2026/09/07 05:12:14 INFO : existing-b: Copied (new) 2026/09/07 05:12:14 INFO : existing-b: Deleted 2026/09/07 05:12:15 DEBUG : existing: size = 6 OK 2026/09/07 05:12:15 DEBUG : existing: sha256 = e91c254ad58860a02c788dfb5c1a65d6a8846ab1dc649631c7db16fef4af2dec OK 2026/09/07 05:12:15 INFO : existing: Copied (new) 2026/09/07 05:12:15 INFO : existing: Deleted 2026/09/07 05:12:15 DEBUG : existing: Destination exists, skipping 2026/09/07 05:12:15 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2026/09/07 05:12:15 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:15 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:15 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (0.31s) === RUN TestServerSideMove run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:15 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-fikaned3yaru" 2026/09/07 05:12:15 INFO : pixeldrain root 'rclone-test-fikaned3yaru': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 sync_test.go:1736: Server side move (if possible) pixeldrain root 'rclone-test-yodidaw8wike' -> pixeldrain root 'rclone-test-fikaned3yaru' 2026/09/07 05:12:15 DEBUG : pixeldrain root 'rclone-test-fikaned3yaru': Using server-side directory move 2026/09/07 05:12:15 INFO : pixeldrain root 'rclone-test-fikaned3yaru': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/09/07 05:12:15 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:15 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/09/07 05:12:15 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:15 DEBUG : potato3: size = 68 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:15 DEBUG : potato3: size = 60 (pixeldrain root 'rclone-test-fikaned3yaru') 2026/09/07 05:12:15 DEBUG : potato3: Sizes differ 2026/09/07 05:12:15 DEBUG : pixeldrain root 'rclone-test-fikaned3yaru': Waiting for checks to finish 2026/09/07 05:12:15 INFO : empty space: Deleted 2026/09/07 05:12:15 DEBUG : pixeldrain root 'rclone-test-fikaned3yaru': Waiting for transfers to finish 2026/09/07 05:12:15 INFO : potato2: Moved (server-side) 2026/09/07 05:12:15 INFO : potato3: Deleted 2026/09/07 05:12:15 INFO : potato3: Moved (server-side) 2026/09/07 05:12:15 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-fikoras9ruci" 2026/09/07 05:12:15 INFO : pixeldrain root 'rclone-test-fikoras9ruci': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:15 DEBUG : pixeldrain root 'rclone-test-fikoras9ruci': Using server-side directory move 2026/09/07 05:12:15 INFO : pixeldrain root 'rclone-test-fikoras9ruci': Server side directory move succeeded 2026/09/07 05:12:15 ERROR : error listing: directory not found 2026/09/07 05:12:15 DEBUG : pixeldrain root 'rclone-test-fikoras9ruci': Purge remote 2026/09/07 05:12:16 DEBUG : pixeldrain root 'rclone-test-fikaned3yaru': Purge remote 2026/09/07 05:12:16 NOTICE: purge failed: directory not found --- PASS: TestServerSideMove (0.86s) === RUN TestServerSideMoveWithFilter run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:16 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-zemihay3fahu" 2026/09/07 05:12:16 INFO : pixeldrain root 'rclone-test-zemihay3fahu': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 sync_test.go:1736: Server side move (if possible) pixeldrain root 'rclone-test-yodidaw8wike' -> pixeldrain root 'rclone-test-zemihay3fahu' 2026/09/07 05:12:16 DEBUG : empty space: Excluded (Size Filter) 2026/09/07 05:12:16 DEBUG : empty space: Excluded 2026/09/07 05:12:16 DEBUG : empty space: Excluded (Size Filter) 2026/09/07 05:12:16 DEBUG : empty space: Excluded 2026/09/07 05:12:16 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:16 DEBUG : potato3: size = 68 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:16 DEBUG : potato3: size = 60 (pixeldrain root 'rclone-test-zemihay3fahu') 2026/09/07 05:12:16 DEBUG : potato3: Sizes differ 2026/09/07 05:12:16 DEBUG : pixeldrain root 'rclone-test-zemihay3fahu': Waiting for checks to finish 2026/09/07 05:12:16 DEBUG : pixeldrain root 'rclone-test-zemihay3fahu': Waiting for transfers to finish 2026/09/07 05:12:16 INFO : potato2: Moved (server-side) 2026/09/07 05:12:16 INFO : potato3: Deleted 2026/09/07 05:12:16 INFO : potato3: Moved (server-side) 2026/09/07 05:12:16 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-zeduqul6daca" 2026/09/07 05:12:16 INFO : pixeldrain root 'rclone-test-zeduqul6daca': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:16 DEBUG : empty space: Excluded (Size Filter) 2026/09/07 05:12:16 DEBUG : empty space: Excluded 2026/09/07 05:12:16 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:16 DEBUG : potato3: Need to transfer - File not found at Destination 2026/09/07 05:12:16 DEBUG : pixeldrain root 'rclone-test-zeduqul6daca': Waiting for checks to finish 2026/09/07 05:12:16 DEBUG : pixeldrain root 'rclone-test-zeduqul6daca': Waiting for transfers to finish 2026/09/07 05:12:16 INFO : potato2: Moved (server-side) 2026/09/07 05:12:16 INFO : potato3: Moved (server-side) 2026/09/07 05:12:16 DEBUG : pixeldrain root 'rclone-test-zeduqul6daca': Purge remote 2026/09/07 05:12:17 DEBUG : pixeldrain root 'rclone-test-zemihay3fahu': Purge remote --- PASS: TestServerSideMoveWithFilter (1.04s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:17 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-hipefah9vaxa" 2026/09/07 05:12:17 INFO : pixeldrain root 'rclone-test-hipefah9vaxa': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:17 INFO : tomatoDir: Making directory sync_test.go:1736: Server side move (if possible) pixeldrain root 'rclone-test-yodidaw8wike' -> pixeldrain root 'rclone-test-hipefah9vaxa' 2026/09/07 05:12:17 DEBUG : pixeldrain root 'rclone-test-hipefah9vaxa': Using server-side directory move 2026/09/07 05:12:17 INFO : pixeldrain root 'rclone-test-hipefah9vaxa': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2026/09/07 05:12:17 DEBUG : potato2: Need to transfer - File not found at Destination 2026/09/07 05:12:17 DEBUG : Added delayed dir = "tomatoDir", newDst= 2026/09/07 05:12:17 DEBUG : potato3: size = 68 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:17 DEBUG : empty space: size = 1 OK 2026/09/07 05:12:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/09/07 05:12:17 DEBUG : potato3: size = 60 (pixeldrain root 'rclone-test-hipefah9vaxa') 2026/09/07 05:12:17 DEBUG : empty space: Unchanged skipping 2026/09/07 05:12:17 DEBUG : potato3: Sizes differ 2026/09/07 05:12:17 DEBUG : pixeldrain root 'rclone-test-hipefah9vaxa': Waiting for checks to finish 2026/09/07 05:12:17 INFO : potato2: Moved (server-side) 2026/09/07 05:12:17 INFO : empty space: Deleted 2026/09/07 05:12:17 DEBUG : pixeldrain root 'rclone-test-hipefah9vaxa': Waiting for transfers to finish 2026/09/07 05:12:17 INFO : potato3: Deleted 2026/09/07 05:12:17 INFO : potato3: Moved (server-side) 2026/09/07 05:12:17 INFO : tomatoDir: Removing directory 2026/09/07 05:12:17 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': deleted 1 directories 2026/09/07 05:12:17 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-popezuj6zaqu" 2026/09/07 05:12:17 INFO : pixeldrain root 'rclone-test-popezuj6zaqu': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:17 INFO : tomatoDir: Making directory 2026/09/07 05:12:17 DEBUG : pixeldrain root 'rclone-test-popezuj6zaqu': Using server-side directory move 2026/09/07 05:12:17 INFO : pixeldrain root 'rclone-test-popezuj6zaqu': Server side directory move succeeded 2026/09/07 05:12:18 ERROR : error listing: directory not found 2026/09/07 05:12:18 ERROR : error listing: directory not found 2026/09/07 05:12:18 DEBUG : pixeldrain root 'rclone-test-popezuj6zaqu': Purge remote 2026/09/07 05:12:18 DEBUG : pixeldrain root 'rclone-test-hipefah9vaxa': Purge remote 2026/09/07 05:12:18 NOTICE: purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (1.01s) === RUN TestServerSideMoveOverlap run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:1941: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.04s) === RUN TestSyncOverlap run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:18 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/rclone-sync-test" 2026/09/07 05:12:18 INFO : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 --- PASS: TestSyncOverlap (0.14s) === RUN TestSyncOverlapWithFilter run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:18 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/rclone-sync-test" 2026/09/07 05:12:18 INFO : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:18 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/rclone-sync-test-include/layer2" 2026/09/07 05:12:18 INFO : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test-include/layer2': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:18 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/rclone-sync-test-ignore-file" 2026/09/07 05:12:18 INFO : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test-ignore-file': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:18 DEBUG : rclone-sync-test: Excluded 2026/09/07 05:12:18 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/09/07 05:12:18 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2026/09/07 05:12:18 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/09/07 05:12:18 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test': Waiting for checks to finish 2026/09/07 05:12:18 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test': Waiting for transfers to finish 2026/09/07 05:12:18 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:18 INFO : There was nothing to transfer 2026/09/07 05:12:18 DEBUG : rclone-sync-test: Excluded 2026/09/07 05:12:18 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/09/07 05:12:19 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:19 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:19 INFO : rclone-sync-test-include: Removing directory 2026/09/07 05:12:19 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': failed to delete 1 directories 2026/09/07 05:12:19 INFO : There was nothing to transfer 2026/09/07 05:12:19 DEBUG : rclone-sync-test: Excluded 2026/09/07 05:12:19 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/09/07 05:12:19 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2026/09/07 05:12:19 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test-include/layer2': Waiting for checks to finish 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test-include/layer2': Waiting for transfers to finish 2026/09/07 05:12:19 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:19 INFO : There was nothing to transfer 2026/09/07 05:12:19 DEBUG : rclone-sync-test: Excluded 2026/09/07 05:12:19 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/09/07 05:12:19 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:19 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:19 INFO : rclone-sync-test-include: Removing directory 2026/09/07 05:12:19 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': failed to delete 1 directories 2026/09/07 05:12:19 INFO : There was nothing to transfer 2026/09/07 05:12:19 DEBUG : Excluded 2026/09/07 05:12:19 DEBUG : rclone-sync-test: Excluded 2026/09/07 05:12:19 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/09/07 05:12:19 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2026/09/07 05:12:19 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test-ignore-file': Waiting for checks to finish 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/rclone-sync-test-ignore-file': Waiting for transfers to finish 2026/09/07 05:12:19 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:19 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (1.44s) === RUN TestSyncCompareDest run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:19 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/dst" 2026/09/07 05:12:19 INFO : pixeldrain root 'rclone-test-yodidaw8wike/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:19 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/CompareDest" 2026/09/07 05:12:19 INFO : pixeldrain root 'rclone-test-yodidaw8wike/CompareDest': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:19 DEBUG : one: Need to transfer - File not found at Destination 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:19 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:20 DEBUG : one: size = 3 OK 2026/09/07 05:12:20 DEBUG : one: sha256 = 7692c3ad3540bb803c020b3aee66cd8887123234ea0c6e7143c0add73ff431ed OK 2026/09/07 05:12:20 INFO : one: Copied (new) 2026/09/07 05:12:20 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:20 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:20 DEBUG : one: Sizes differ 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:20 DEBUG : one: size = 5 OK 2026/09/07 05:12:20 DEBUG : one: sha256 = 2b82f49cc03d076e84d5a393c42909b1fc2227c143ab8f84620f261fa795dec9 OK 2026/09/07 05:12:20 INFO : one: Copied (replaced existing) 2026/09/07 05:12:20 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:20 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:20 DEBUG : one: Sizes differ 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:20 DEBUG : one: size = 5 OK 2026/09/07 05:12:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:20 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:20 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:20 INFO : There was nothing to transfer 2026/09/07 05:12:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:20 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:20 DEBUG : one: Sizes differ 2026/09/07 05:12:20 DEBUG : two: size = 3 OK 2026/09/07 05:12:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:20 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:20 DEBUG : one: size = 5 OK 2026/09/07 05:12:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:20 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:20 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:20 INFO : There was nothing to transfer 2026/09/07 05:12:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:20 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:20 DEBUG : one: Sizes differ 2026/09/07 05:12:20 DEBUG : two: size = 3 OK 2026/09/07 05:12:20 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:20 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:20 DEBUG : one: size = 5 OK 2026/09/07 05:12:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:20 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:20 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:20 INFO : There was nothing to transfer 2026/09/07 05:12:20 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:20 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:20 DEBUG : one: Sizes differ 2026/09/07 05:12:20 DEBUG : two: size = 3 OK 2026/09/07 05:12:20 DEBUG : two: Modification times differ by -119h59m59.877s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59.123 +0000 UTC 2026/09/07 05:12:20 DEBUG : two: sha256 = 3fc4ccfe745870e2c0d99f71f30ff0656c8dedd41cc1d7d3d376b0dbe685e2f3 OK 2026/09/07 05:12:20 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:20 DEBUG : one: size = 5 OK 2026/09/07 05:12:20 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:20 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/07 05:12:20 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:20 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:20 INFO : There was nothing to transfer 2026/09/07 05:12:21 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:21 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:21 DEBUG : one: Sizes differ 2026/09/07 05:12:21 DEBUG : two: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:21 DEBUG : two: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/CompareDest') 2026/09/07 05:12:21 DEBUG : two: Sizes differ 2026/09/07 05:12:21 DEBUG : two: Need to transfer - File not found at Destination 2026/09/07 05:12:21 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:21 DEBUG : one: size = 5 OK 2026/09/07 05:12:21 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/07 05:12:21 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/07 05:12:21 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:21 DEBUG : two: size = 5 OK 2026/09/07 05:12:21 DEBUG : two: sha256 = c271f9e8cacd6ad5a9c26bd5bc26c1eb91d7d64a00a417253a1abf88211bd96d OK 2026/09/07 05:12:21 INFO : two: Copied (new) 2026/09/07 05:12:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (1.56s) === RUN TestSyncMultipleCompareDest run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:21 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/dest" 2026/09/07 05:12:21 INFO : pixeldrain root 'rclone-test-yodidaw8wike/dest': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:21 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/pre-dest1" 2026/09/07 05:12:21 INFO : pixeldrain root 'rclone-test-yodidaw8wike/pre-dest1': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:21 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/pre-dest2" 2026/09/07 05:12:21 INFO : pixeldrain root 'rclone-test-yodidaw8wike/pre-dest2': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:21 DEBUG : 1: size = 1 OK 2026/09/07 05:12:21 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:21 DEBUG : 1: Destination found in --compare-dest, skipping 2026/09/07 05:12:21 DEBUG : 2: size = 1 OK 2026/09/07 05:12:21 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:21 DEBUG : 2: Destination found in --compare-dest, skipping 2026/09/07 05:12:21 DEBUG : 3: Need to transfer - File not found at Destination 2026/09/07 05:12:21 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dest': Waiting for checks to finish 2026/09/07 05:12:21 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dest': Waiting for transfers to finish 2026/09/07 05:12:21 DEBUG : 3: size = 1 OK 2026/09/07 05:12:21 DEBUG : 3: sha256 = 4e07408562bedb8b60ce05c1decfe3ad16b72230967de01f640b7e4729b49fce OK 2026/09/07 05:12:21 INFO : 3: Copied (new) 2026/09/07 05:12:21 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (0.88s) === RUN TestSyncCopyDest run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:2266: Skipping test as remote does not support server-side copy --- SKIP: TestSyncCopyDest (0.04s) === RUN TestSyncBackupDir run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:22 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/dst" 2026/09/07 05:12:22 INFO : pixeldrain root 'rclone-test-yodidaw8wike/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:22 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/backup" 2026/09/07 05:12:22 INFO : pixeldrain root 'rclone-test-yodidaw8wike/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:22 DEBUG : two: size = 3 OK 2026/09/07 05:12:22 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:22 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:22 DEBUG : two: Unchanged skipping 2026/09/07 05:12:22 DEBUG : one: size = 4 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:22 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:22 DEBUG : one: Sizes differ 2026/09/07 05:12:22 INFO : one: Moved (server-side) 2026/09/07 05:12:22 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:22 DEBUG : one: size = 4 OK 2026/09/07 05:12:22 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/09/07 05:12:22 INFO : one: Copied (new) 2026/09/07 05:12:22 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:22 INFO : three.txt: Moved (server-side) 2026/09/07 05:12:22 INFO : three.txt: Moved into backup dir 2026/09/07 05:12:22 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:22 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:22 DEBUG : one: Sizes differ 2026/09/07 05:12:22 DEBUG : two: size = 3 OK 2026/09/07 05:12:22 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:22 DEBUG : two: Unchanged skipping 2026/09/07 05:12:22 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:22 INFO : one: Deleted 2026/09/07 05:12:22 INFO : one: Moved (server-side) 2026/09/07 05:12:22 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:22 DEBUG : one: size = 5 OK 2026/09/07 05:12:22 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/09/07 05:12:22 INFO : one: Copied (new) 2026/09/07 05:12:22 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:23 INFO : three.txt: Deleted 2026/09/07 05:12:23 INFO : three.txt: Moved (server-side) 2026/09/07 05:12:23 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (1.09s) === RUN TestSyncBackupDirWithSuffix run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:23 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/dst" 2026/09/07 05:12:23 INFO : pixeldrain root 'rclone-test-yodidaw8wike/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:23 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/backup" 2026/09/07 05:12:23 INFO : pixeldrain root 'rclone-test-yodidaw8wike/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:23 DEBUG : one: size = 4 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:23 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:23 DEBUG : one: Sizes differ 2026/09/07 05:12:23 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:23 DEBUG : two: size = 3 OK 2026/09/07 05:12:23 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:23 DEBUG : two: Unchanged skipping 2026/09/07 05:12:23 INFO : one: Moved (server-side) to: one.bak 2026/09/07 05:12:23 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:23 DEBUG : one: size = 4 OK 2026/09/07 05:12:23 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/09/07 05:12:23 INFO : one: Copied (new) 2026/09/07 05:12:23 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:23 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/07 05:12:23 INFO : three.txt: Moved into backup dir 2026/09/07 05:12:23 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:23 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:23 DEBUG : one: Sizes differ 2026/09/07 05:12:23 DEBUG : two: size = 3 OK 2026/09/07 05:12:23 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:23 DEBUG : two: Unchanged skipping 2026/09/07 05:12:23 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:24 INFO : one.bak: Deleted 2026/09/07 05:12:24 INFO : one: Moved (server-side) to: one.bak 2026/09/07 05:12:24 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:24 DEBUG : one: size = 5 OK 2026/09/07 05:12:24 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/09/07 05:12:24 INFO : one: Copied (new) 2026/09/07 05:12:24 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:24 INFO : three.txt.bak: Deleted 2026/09/07 05:12:24 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/07 05:12:24 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (1.10s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:24 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/dst" 2026/09/07 05:12:24 INFO : pixeldrain root 'rclone-test-yodidaw8wike/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:24 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/backup" 2026/09/07 05:12:24 INFO : pixeldrain root 'rclone-test-yodidaw8wike/backup': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:24 DEBUG : one: size = 4 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:24 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:24 DEBUG : one: Sizes differ 2026/09/07 05:12:24 DEBUG : two: size = 3 OK 2026/09/07 05:12:24 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:24 DEBUG : two: Unchanged skipping 2026/09/07 05:12:24 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:24 INFO : one: Moved (server-side) to: one-2019-01-01 2026/09/07 05:12:24 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:24 DEBUG : one: size = 4 OK 2026/09/07 05:12:24 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/09/07 05:12:24 INFO : one: Copied (new) 2026/09/07 05:12:24 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:24 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/09/07 05:12:24 INFO : three.txt: Moved into backup dir 2026/09/07 05:12:25 DEBUG : two: size = 3 OK 2026/09/07 05:12:25 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:25 DEBUG : two: Unchanged skipping 2026/09/07 05:12:25 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:25 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:25 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:25 DEBUG : one: Sizes differ 2026/09/07 05:12:25 INFO : one-2019-01-01: Deleted 2026/09/07 05:12:25 INFO : one: Moved (server-side) to: one-2019-01-01 2026/09/07 05:12:25 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:25 DEBUG : one: size = 5 OK 2026/09/07 05:12:25 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/09/07 05:12:25 INFO : one: Copied (new) 2026/09/07 05:12:25 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:25 INFO : three-2019-01-01.txt: Deleted 2026/09/07 05:12:25 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/09/07 05:12:25 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (1.10s) === RUN TestSyncBackupDirSuffixOnly run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:25 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/dst" 2026/09/07 05:12:25 INFO : pixeldrain root 'rclone-test-yodidaw8wike/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:25 DEBUG : two: size = 3 OK 2026/09/07 05:12:25 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:25 DEBUG : two: Unchanged skipping 2026/09/07 05:12:25 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:25 DEBUG : one: size = 4 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:25 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:25 DEBUG : one: Sizes differ 2026/09/07 05:12:25 INFO : one: Moved (server-side) to: one.bak 2026/09/07 05:12:25 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:25 DEBUG : one: size = 4 OK 2026/09/07 05:12:25 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/09/07 05:12:25 INFO : one: Copied (new) 2026/09/07 05:12:25 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:25 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/07 05:12:25 INFO : three.txt: Moved into backup dir 2026/09/07 05:12:26 DEBUG : one.bak: Excluded (Path Filter) 2026/09/07 05:12:26 DEBUG : one.bak: Excluded 2026/09/07 05:12:26 DEBUG : three.txt.bak: Excluded (Path Filter) 2026/09/07 05:12:26 DEBUG : three.txt.bak: Excluded 2026/09/07 05:12:26 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:26 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:26 DEBUG : one: Sizes differ 2026/09/07 05:12:26 DEBUG : two: size = 3 OK 2026/09/07 05:12:26 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:26 DEBUG : two: Unchanged skipping 2026/09/07 05:12:26 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for checks to finish 2026/09/07 05:12:26 INFO : one.bak: Deleted 2026/09/07 05:12:26 INFO : one: Moved (server-side) to: one.bak 2026/09/07 05:12:26 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike/dst': Waiting for transfers to finish 2026/09/07 05:12:26 DEBUG : one: size = 5 OK 2026/09/07 05:12:26 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/09/07 05:12:26 INFO : one: Copied (new) 2026/09/07 05:12:26 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:26 INFO : three.txt.bak: Deleted 2026/09/07 05:12:26 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/07 05:12:26 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (0.94s) === RUN TestSyncSuffix run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:26 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/dst" 2026/09/07 05:12:26 INFO : pixeldrain root 'rclone-test-yodidaw8wike/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:26 DEBUG : one: size = 4 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:26 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:26 DEBUG : one: Sizes differ 2026/09/07 05:12:26 INFO : one: Moved (server-side) to: one.bak 2026/09/07 05:12:26 DEBUG : one: size = 4 OK 2026/09/07 05:12:26 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/09/07 05:12:26 INFO : one: Copied (new) 2026/09/07 05:12:26 DEBUG : two: size = 3 OK 2026/09/07 05:12:26 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:26 DEBUG : two: Unchanged skipping 2026/09/07 05:12:26 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:26 DEBUG : three.txt: size = 5 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:26 DEBUG : three.txt: Sizes differ 2026/09/07 05:12:26 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/07 05:12:26 DEBUG : three.txt: size = 6 OK 2026/09/07 05:12:26 DEBUG : three.txt: sha256 = cd00a4f7ad2979126aafa7d11a2e21f3a4a22f7d09f2fb8af42f00af92d16f30 OK 2026/09/07 05:12:26 INFO : three.txt: Copied (new) 2026/09/07 05:12:27 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:27 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:27 DEBUG : one: Sizes differ 2026/09/07 05:12:27 INFO : one.bak: Deleted 2026/09/07 05:12:27 INFO : one: Moved (server-side) to: one.bak 2026/09/07 05:12:27 DEBUG : one: size = 5 OK 2026/09/07 05:12:27 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/09/07 05:12:27 INFO : one: Copied (new) 2026/09/07 05:12:27 DEBUG : two: size = 3 OK 2026/09/07 05:12:27 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:27 DEBUG : two: Unchanged skipping 2026/09/07 05:12:27 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:27 DEBUG : three.txt: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:27 DEBUG : three.txt: Sizes differ 2026/09/07 05:12:27 INFO : three.txt.bak: Deleted 2026/09/07 05:12:27 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/07 05:12:27 DEBUG : three.txt: size = 19 OK 2026/09/07 05:12:27 DEBUG : three.txt: sha256 = d7b08061d12e694eb27d1732a1f59c904e6fedb135c6a008981fefadf79a9694 OK 2026/09/07 05:12:27 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (1.10s) === RUN TestSyncSuffixKeepExtension run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:27 DEBUG : Creating backend with remote "TestPixeldrain:rclone-test-yodidaw8wike/dst" 2026/09/07 05:12:27 INFO : pixeldrain root 'rclone-test-yodidaw8wike/dst': Logged in as 'ncw', subscription 'Prepaid', storage limit -1 2026/09/07 05:12:27 DEBUG : one: size = 4 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:27 DEBUG : one: size = 3 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:27 DEBUG : one: Sizes differ 2026/09/07 05:12:27 INFO : one: Moved (server-side) to: one-2019-01-01 2026/09/07 05:12:27 DEBUG : one: size = 4 OK 2026/09/07 05:12:27 DEBUG : one: sha256 = adefbbf0dff80800a3eb8cd37fa8160a530c6ab1fed4f44001b69139c5177a93 OK 2026/09/07 05:12:27 INFO : one: Copied (new) 2026/09/07 05:12:27 DEBUG : two: size = 3 OK 2026/09/07 05:12:27 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:27 DEBUG : two: Unchanged skipping 2026/09/07 05:12:28 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:28 DEBUG : three.txt: size = 5 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:28 DEBUG : three.txt: Sizes differ 2026/09/07 05:12:28 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/09/07 05:12:28 DEBUG : three.txt: size = 6 OK 2026/09/07 05:12:28 DEBUG : three.txt: sha256 = cd00a4f7ad2979126aafa7d11a2e21f3a4a22f7d09f2fb8af42f00af92d16f30 OK 2026/09/07 05:12:28 INFO : three.txt: Copied (new) 2026/09/07 05:12:28 DEBUG : one: size = 5 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:28 DEBUG : one: size = 4 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:28 DEBUG : one: Sizes differ 2026/09/07 05:12:28 INFO : one-2019-01-01: Deleted 2026/09/07 05:12:28 INFO : one: Moved (server-side) to: one-2019-01-01 2026/09/07 05:12:28 DEBUG : one: size = 5 OK 2026/09/07 05:12:28 DEBUG : one: sha256 = 517b021836359b3420b6082b5b16f379079452b9e0ac97b88ae86c6c757ee7b8 OK 2026/09/07 05:12:28 INFO : one: Copied (new) 2026/09/07 05:12:28 DEBUG : two: size = 3 OK 2026/09/07 05:12:28 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:28 DEBUG : two: Unchanged skipping 2026/09/07 05:12:28 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:28 DEBUG : three.txt: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike/dst') 2026/09/07 05:12:28 DEBUG : three.txt: Sizes differ 2026/09/07 05:12:28 INFO : three-2019-01-01.txt: Deleted 2026/09/07 05:12:28 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/09/07 05:12:28 DEBUG : three.txt: size = 19 OK 2026/09/07 05:12:28 DEBUG : three.txt: sha256 = d7b08061d12e694eb27d1732a1f59c904e6fedb135c6a008981fefadf79a9694 OK 2026/09/07 05:12:28 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (1.15s) === RUN TestSyncUTFNorm run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:28 DEBUG : Testêé: size = 14 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:28 DEBUG : Testêé: size = 18 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:28 DEBUG : Testêé: Sizes differ 2026/09/07 05:12:28 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:28 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:28 DEBUG : Testêé: size = 14 OK 2026/09/07 05:12:28 DEBUG : Testêé: sha256 = c7be1ed902fb8dd4d48997c6452f5d7e509fbcdbe2808b16bcf4edce4c07d14e OK 2026/09/07 05:12:28 INFO : Testêé: Copied (replaced existing) to: Testêé 2026/09/07 05:12:28 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (0.20s) === RUN TestSyncImmutable run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:28 DEBUG : existing: Need to transfer - File not found at Destination 2026/09/07 05:12:28 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:28 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:29 DEBUG : existing: size = 6 OK 2026/09/07 05:12:29 DEBUG : existing: sha256 = e91c254ad58860a02c788dfb5c1a65d6a8846ab1dc649631c7db16fef4af2dec OK 2026/09/07 05:12:29 INFO : existing: Copied (new) 2026/09/07 05:12:29 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:29 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:29 DEBUG : existing: size = 8 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:29 DEBUG : existing: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:29 DEBUG : existing: Sizes differ 2026/09/07 05:12:29 ERROR : existing: Source and destination exist but do not match: immutable file modified 2026/09/07 05:12:29 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:29 ERROR : pixeldrain root 'rclone-test-yodidaw8wike': not deleting files as there were IO errors 2026/09/07 05:12:29 ERROR : pixeldrain root 'rclone-test-yodidaw8wike': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (0.32s) === RUN TestSyncIgnoreCase run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:29 DEBUG : EXISTING: size = 6 OK 2026/09/07 05:12:29 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:29 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:29 DEBUG : existing: Unchanged skipping 2026/09/07 05:12:29 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:29 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:29 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (0.25s) === RUN TestFixCase run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:2666: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.04s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:2705: This test only runs on local === RUN TestMaxTransfer/Soft run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:2705: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" sync_test.go:2705: This test only runs on local --- PASS: TestMaxTransfer (0.13s) --- SKIP: TestMaxTransfer/Hard (0.04s) --- SKIP: TestMaxTransfer/Soft (0.04s) --- SKIP: TestMaxTransfer/Cautious (0.04s) === RUN TestSyncConcurrentDelete run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:34 DEBUG : both0: size = 6 OK 2026/09/07 05:12:34 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both10: size = 6 OK 2026/09/07 05:12:34 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both11: size = 6 OK 2026/09/07 05:12:34 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:34 DEBUG : both12: size = 6 OK 2026/09/07 05:12:34 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both0: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both13: size = 6 OK 2026/09/07 05:12:34 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both13: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both14: size = 6 OK 2026/09/07 05:12:34 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both10: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both15: size = 6 OK 2026/09/07 05:12:34 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both15: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both16: size = 6 OK 2026/09/07 05:12:34 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both12: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both17: size = 6 OK 2026/09/07 05:12:34 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both14: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both18: size = 6 OK 2026/09/07 05:12:34 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both18: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both19: size = 6 OK 2026/09/07 05:12:34 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both11: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both1: size = 6 OK 2026/09/07 05:12:34 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both1: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both2: size = 6 OK 2026/09/07 05:12:34 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both2: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both3: size = 6 OK 2026/09/07 05:12:34 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both16: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both4: size = 6 OK 2026/09/07 05:12:34 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both4: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both5: size = 6 OK 2026/09/07 05:12:34 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both5: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both6: size = 6 OK 2026/09/07 05:12:34 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both17: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both7: size = 6 OK 2026/09/07 05:12:34 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both7: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both8: size = 6 OK 2026/09/07 05:12:34 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both8: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both9: size = 6 OK 2026/09/07 05:12:34 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:34 DEBUG : both19: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both9: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both3: Unchanged skipping 2026/09/07 05:12:34 DEBUG : both6: Unchanged skipping 2026/09/07 05:12:34 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:12:34 DEBUG : Waiting for deletions to finish 2026/09/07 05:12:34 INFO : only10: Deleted 2026/09/07 05:12:34 INFO : only16: Deleted 2026/09/07 05:12:34 INFO : only17: Deleted 2026/09/07 05:12:34 INFO : only19: Deleted 2026/09/07 05:12:34 INFO : only1: Deleted 2026/09/07 05:12:34 INFO : only4: Deleted 2026/09/07 05:12:34 INFO : only5: Deleted 2026/09/07 05:12:34 INFO : only0: Deleted 2026/09/07 05:12:34 INFO : only12: Deleted 2026/09/07 05:12:34 INFO : only13: Deleted 2026/09/07 05:12:34 INFO : only14: Deleted 2026/09/07 05:12:34 INFO : only2: Deleted 2026/09/07 05:12:34 INFO : only11: Deleted 2026/09/07 05:12:34 INFO : only6: Deleted 2026/09/07 05:12:34 INFO : only7: Deleted 2026/09/07 05:12:34 INFO : only9: Deleted 2026/09/07 05:12:34 INFO : only15: Deleted 2026/09/07 05:12:34 INFO : only18: Deleted 2026/09/07 05:12:34 INFO : only3: Deleted 2026/09/07 05:12:34 INFO : only8: Deleted 2026/09/07 05:12:34 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (5.58s) === RUN TestSyncConcurrentTruncate run.go:198: Remote "pixeldrain root 'rclone-test-yodidaw8wike'", Local "Local file system at /tmp/rclone4285399326", Modify Window "1ms" 2026/09/07 05:12:40 DEBUG : both10: size = 6 OK 2026/09/07 05:12:40 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for checks to finish 2026/09/07 05:12:40 DEBUG : both11: size = 6 OK 2026/09/07 05:12:40 DEBUG : both12: size = 6 OK 2026/09/07 05:12:40 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both10: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both13: size = 6 OK 2026/09/07 05:12:40 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both0: size = 6 OK 2026/09/07 05:12:40 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both13: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both14: size = 6 OK 2026/09/07 05:12:40 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both12: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both15: size = 6 OK 2026/09/07 05:12:40 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both15: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both16: size = 6 OK 2026/09/07 05:12:40 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both16: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both17: size = 6 OK 2026/09/07 05:12:40 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both17: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both18: size = 6 OK 2026/09/07 05:12:40 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both11: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both19: size = 6 OK 2026/09/07 05:12:40 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both19: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both1: size = 6 OK 2026/09/07 05:12:40 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both1: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both2: size = 6 OK 2026/09/07 05:12:40 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both0: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both3: size = 6 OK 2026/09/07 05:12:40 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both14: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both4: size = 6 OK 2026/09/07 05:12:40 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both18: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both5: size = 6 OK 2026/09/07 05:12:40 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both5: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both6: size = 6 OK 2026/09/07 05:12:40 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both2: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both7: size = 6 OK 2026/09/07 05:12:40 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both7: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both8: size = 6 OK 2026/09/07 05:12:40 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both3: Unchanged skipping 2026/09/07 05:12:40 DEBUG : both9: size = 6 OK 2026/09/07 05:12:40 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/07 05:12:40 DEBUG : both4: Unchanged skipping 2026/09/07 05:12:40 DEBUG : only0: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only0: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only0: Sizes differ 2026/09/07 05:12:40 DEBUG : both6: Unchanged skipping 2026/09/07 05:12:40 DEBUG : only10: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only10: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only10: Sizes differ 2026/09/07 05:12:40 DEBUG : both8: Unchanged skipping 2026/09/07 05:12:40 DEBUG : only11: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only11: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only11: Sizes differ 2026/09/07 05:12:40 DEBUG : both9: Unchanged skipping 2026/09/07 05:12:40 DEBUG : only12: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only12: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only12: Sizes differ 2026/09/07 05:12:40 DEBUG : only13: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only13: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only13: Sizes differ 2026/09/07 05:12:40 DEBUG : only14: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only14: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only14: Sizes differ 2026/09/07 05:12:40 DEBUG : only15: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only15: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only15: Sizes differ 2026/09/07 05:12:40 DEBUG : only16: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only16: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only16: Sizes differ 2026/09/07 05:12:40 DEBUG : only17: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only17: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only17: Sizes differ 2026/09/07 05:12:40 DEBUG : only18: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only18: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only18: Sizes differ 2026/09/07 05:12:40 DEBUG : only19: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only19: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only19: Sizes differ 2026/09/07 05:12:40 DEBUG : only1: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only1: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only1: Sizes differ 2026/09/07 05:12:40 DEBUG : only2: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only2: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only2: Sizes differ 2026/09/07 05:12:40 DEBUG : only3: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only3: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only3: Sizes differ 2026/09/07 05:12:40 DEBUG : only4: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only4: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only4: Sizes differ 2026/09/07 05:12:40 DEBUG : only5: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only5: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only5: Sizes differ 2026/09/07 05:12:40 DEBUG : only6: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only6: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only6: Sizes differ 2026/09/07 05:12:40 DEBUG : only7: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only7: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only7: Sizes differ 2026/09/07 05:12:40 DEBUG : only8: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only8: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only8: Sizes differ 2026/09/07 05:12:40 DEBUG : only9: size = 0 (Local file system at /tmp/rclone4285399326) 2026/09/07 05:12:40 DEBUG : only9: size = 6 (pixeldrain root 'rclone-test-yodidaw8wike') 2026/09/07 05:12:40 DEBUG : only9: Sizes differ 2026/09/07 05:12:40 DEBUG : pixeldrain root 'rclone-test-yodidaw8wike': Waiting for transfers to finish 2026/09/07 05:17:40 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only12?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:17:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/09/07 05:17:40 DEBUG : only12: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only12?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 0/10 2026/09/07 05:17:40 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only0?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:17:40 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/09/07 05:17:40 DEBUG : only0: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only0?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 0/10 2026/09/07 05:17:40 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only15?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:17:40 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/09/07 05:17:40 DEBUG : only15: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only15?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 0/10 2026/09/07 05:17:40 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only14?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:17:40 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/09/07 05:17:40 DEBUG : only14: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only14?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 0/10 2026/09/07 05:17:50 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:17:50 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/09/07 05:17:50 DEBUG : only12: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:17:50 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:17:50 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/09/07 05:17:50 DEBUG : only0: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:17:50 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:17:50 DEBUG : pacer: Rate limited, increasing sleep to 1s 2026/09/07 05:17:50 DEBUG : only15: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:17:50 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:17:50 DEBUG : only14: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:18:00 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:00 DEBUG : only12: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:18:00 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:00 DEBUG : only0: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:18:01 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:01 DEBUG : only15: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:18:02 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:02 DEBUG : only14: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:18:10 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:10 DEBUG : only12: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:18:11 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:11 DEBUG : only0: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:18:12 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:12 DEBUG : only15: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:18:13 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:13 DEBUG : only14: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:18:22 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:22 DEBUG : only15: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:18:23 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:23 DEBUG : only14: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:18:32 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:32 DEBUG : only15: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:18:33 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:33 DEBUG : only14: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:18:42 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:42 DEBUG : only15: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:18:43 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:43 DEBUG : only14: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:18:52 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:52 DEBUG : only15: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:18:53 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:18:53 DEBUG : only14: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:19:02 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:19:02 DEBUG : only15: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:19:03 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:19:03 DEBUG : only14: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:23:10 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only12?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:56948->[2404:b9c0:101:1::1]:443: i/o timeout) 2026/09/07 05:23:10 DEBUG : only12: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only12?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:56948->[2404:b9c0:101:1::1]:443: i/o timeout - low level retry 4/10 2026/09/07 05:23:11 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only0?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:33024->[2404:b9c0:101:3::1]:443: i/o timeout) 2026/09/07 05:23:11 DEBUG : only0: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only0?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:33024->[2404:b9c0:101:3::1]:443: i/o timeout - low level retry 4/10 2026/09/07 05:23:20 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:23:20 DEBUG : only12: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:23:21 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:23:21 DEBUG : only0: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:23:30 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:23:30 DEBUG : only12: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:23:31 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:23:31 DEBUG : only0: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:23:40 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:23:40 DEBUG : only12: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:23:41 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:23:41 DEBUG : only0: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:23:50 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:23:50 DEBUG : only12: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:23:51 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:23:51 DEBUG : only0: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:24:00 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:00 DEBUG : only12: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:24:00 ERROR : only12: Failed to copy: failed to put object: internal 2026/09/07 05:24:01 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:01 DEBUG : only0: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:24:01 ERROR : only0: Failed to copy: failed to put object: internal 2026/09/07 05:24:02 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only15?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:24:02 DEBUG : only15: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only15?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 9/10 2026/09/07 05:24:02 ERROR : only15: Failed to copy: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only15?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers 2026/09/07 05:24:03 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only14?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:24:03 DEBUG : only14: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only14?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 9/10 2026/09/07 05:24:03 ERROR : only14: Failed to copy: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only14?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers 2026/09/07 05:24:10 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:10 DEBUG : only10: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:24:11 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:11 DEBUG : only17: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:24:12 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:12 DEBUG : only11: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:24:13 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:13 DEBUG : only19: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:24:20 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:20 DEBUG : only10: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:24:21 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:21 DEBUG : only17: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:24:22 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:22 DEBUG : only11: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:24:23 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:23 DEBUG : only19: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:24:30 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:30 DEBUG : only10: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:24:31 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:31 DEBUG : only17: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:24:32 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:32 DEBUG : only11: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:24:33 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:33 DEBUG : only19: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:24:40 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:40 DEBUG : only10: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:24:42 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:42 DEBUG : only11: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:24:43 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:43 DEBUG : only19: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:24:52 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:52 DEBUG : only11: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:24:53 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:24:53 DEBUG : only19: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:25:02 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:25:02 DEBUG : only11: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:25:03 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:25:03 DEBUG : only19: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:25:12 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:25:12 DEBUG : only11: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:25:13 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:25:13 DEBUG : only19: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:25:23 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:25:23 DEBUG : only19: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:25:33 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:25:33 DEBUG : only19: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:25:43 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:25:43 DEBUG : only19: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:25:43 ERROR : only19: Failed to copy: failed to put object: internal 2026/09/07 05:27:48 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:27:48 DEBUG : only11: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:29:31 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only17?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:29:31 DEBUG : only17: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only17?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 3/10 2026/09/07 05:29:40 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:42424->[2404:b9c0:101:2::1]:443: i/o timeout) 2026/09/07 05:29:40 DEBUG : only10: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:42424->[2404:b9c0:101:2::1]:443: i/o timeout - low level retry 4/10 2026/09/07 05:29:41 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:29:41 DEBUG : only17: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:29:50 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:29:50 DEBUG : only10: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:30:43 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:30:43 DEBUG : only1: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 0/10 2026/09/07 05:31:05 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:31:05 DEBUG : only17: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:32:48 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only11?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:32:48 DEBUG : only11: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only11?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 8/10 2026/09/07 05:33:08 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:33:08 DEBUG : only11: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:33:08 ERROR : only11: Failed to copy: failed to put object: internal 2026/09/07 05:33:18 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:33:18 DEBUG : only2: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:33:28 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:33:28 DEBUG : only2: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:33:38 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:33:38 DEBUG : only2: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:34:50 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:49446->[2404:b9c0:101:1::1]:443: i/o timeout) 2026/09/07 05:34:50 DEBUG : only10: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:49446->[2404:b9c0:101:1::1]:443: i/o timeout - low level retry 6/10 2026/09/07 05:35:00 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:35:00 DEBUG : only10: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:35:43 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:36712->[2404:b9c0:101:3::1]:443: i/o timeout) 2026/09/07 05:35:43 DEBUG : only1: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:36712->[2404:b9c0:101:3::1]:443: i/o timeout - low level retry 1/10 2026/09/07 05:35:53 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:35:53 DEBUG : only1: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:36:05 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only17?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:49442->[2404:b9c0:101:1::1]:443: i/o timeout) 2026/09/07 05:36:05 DEBUG : only17: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only17?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:49442->[2404:b9c0:101:1::1]:443: i/o timeout - low level retry 6/10 2026/09/07 05:36:15 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:36:15 DEBUG : only17: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:36:25 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:36:25 DEBUG : only17: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:36:35 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:36:35 DEBUG : only17: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:36:35 ERROR : only17: Failed to copy: failed to put object: internal 2026/09/07 05:38:38 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only2?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:36410->[2404:b9c0:101:1::1]:443: i/o timeout) 2026/09/07 05:38:38 DEBUG : only2: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only2?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:36410->[2404:b9c0:101:1::1]:443: i/o timeout - low level retry 3/10 2026/09/07 05:38:49 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:38:49 DEBUG : only2: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:38:59 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:38:59 DEBUG : only2: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:39:09 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:39:09 DEBUG : only2: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:39:19 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:39:19 DEBUG : only2: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:39:29 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:39:29 DEBUG : only2: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:39:39 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:39:39 DEBUG : only2: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:39:39 ERROR : only2: Failed to copy: failed to put object: internal 2026/09/07 05:39:49 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:39:49 DEBUG : only4: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:40:00 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:40:00 DEBUG : only10: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 8/10 2026/09/07 05:40:52 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:40:52 DEBUG : only3: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:40:53 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:40:53 DEBUG : only1: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 3/10 2026/09/07 05:44:49 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only4?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:59382->[2404:b9c0:101:1::1]:443: i/o timeout) 2026/09/07 05:44:49 DEBUG : only4: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only4?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:59382->[2404:b9c0:101:1::1]:443: i/o timeout - low level retry 1/10 2026/09/07 05:45:00 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:45:00 DEBUG : only10: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 9/10 2026/09/07 05:45:00 ERROR : only10: Failed to copy: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only10?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers 2026/09/07 05:45:10 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:10 DEBUG : only5: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:45:20 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:20 DEBUG : only5: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:45:28 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:28 DEBUG : only4: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:45:30 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:30 DEBUG : only5: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:45:38 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:38 DEBUG : only4: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:45:40 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:40 DEBUG : only5: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:45:48 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:48 DEBUG : only4: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:45:50 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:50 DEBUG : only5: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:45:52 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only3?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:45:52 DEBUG : only3: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only3?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 1/10 2026/09/07 05:45:53 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:45:53 DEBUG : only1: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 4/10 2026/09/07 05:45:58 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:45:58 DEBUG : only4: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:46:00 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:00 DEBUG : only5: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:46:02 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:02 DEBUG : only3: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:46:03 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:03 DEBUG : only1: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:46:08 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:08 DEBUG : only4: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:46:10 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:10 DEBUG : only5: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:46:12 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:12 DEBUG : only3: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:46:13 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:13 DEBUG : only1: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:46:18 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:18 DEBUG : only4: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:46:20 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:20 DEBUG : only5: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:46:22 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:22 DEBUG : only3: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:46:23 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:23 DEBUG : only1: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:46:28 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:28 DEBUG : only4: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:46:30 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:30 DEBUG : only5: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:46:32 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:32 DEBUG : only3: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:46:38 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:38 DEBUG : only4: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:46:38 ERROR : only4: Failed to copy: failed to put object: internal 2026/09/07 05:46:41 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:41 DEBUG : only5: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:46:41 ERROR : only5: Failed to copy: failed to put object: internal 2026/09/07 05:46:42 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:42 DEBUG : only3: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:46:48 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:48 DEBUG : only6: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:46:51 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:51 DEBUG : only7: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:46:58 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:46:58 DEBUG : only6: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:47:08 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:47:08 DEBUG : only6: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:47:18 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:47:18 DEBUG : only6: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:51:23 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:51:23 DEBUG : only1: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only1?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 8/10 2026/09/07 05:51:34 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:51:34 DEBUG : only1: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:51:34 ERROR : only1: Failed to copy: failed to put object: internal 2026/09/07 05:51:42 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only3?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:35180->[2404:b9c0:101:3::1]:443: i/o timeout) 2026/09/07 05:51:42 DEBUG : only3: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only3?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:35180->[2404:b9c0:101:3::1]:443: i/o timeout - low level retry 7/10 2026/09/07 05:51:44 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:51:44 DEBUG : only13: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:51:51 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only7?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:51:51 DEBUG : only7: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only7?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 1/10 2026/09/07 05:51:52 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:51:52 DEBUG : only3: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:51:54 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:51:54 DEBUG : only13: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:52:01 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:52:01 DEBUG : only7: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:52:04 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:52:04 DEBUG : only13: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:52:18 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only6?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:52:18 DEBUG : only6: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only6?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 4/10 2026/09/07 05:52:28 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:52:28 DEBUG : only6: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:56:52 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only3?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:56:52 DEBUG : only3: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only3?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 9/10 2026/09/07 05:56:52 ERROR : only3: Failed to copy: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only3?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers 2026/09/07 05:57:01 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only7?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:57:01 DEBUG : only7: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only7?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 3/10 2026/09/07 05:57:02 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:02 DEBUG : only16: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:57:04 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only13?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:57:04 DEBUG : only13: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only13?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 3/10 2026/09/07 05:57:11 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:11 DEBUG : only7: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:57:12 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:12 DEBUG : only16: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:57:14 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:14 DEBUG : only13: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:57:21 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:21 DEBUG : only7: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:57:22 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:22 DEBUG : only16: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:57:24 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:24 DEBUG : only13: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:57:28 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only6?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 05:57:28 DEBUG : only6: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only6?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 6/10 2026/09/07 05:57:31 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:31 DEBUG : only7: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:57:34 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:34 DEBUG : only13: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:57:38 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:38 DEBUG : only6: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:57:41 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:41 DEBUG : only7: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:57:44 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:44 DEBUG : only13: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:57:48 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:48 DEBUG : only6: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:57:51 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:51 DEBUG : only7: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:57:54 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:54 DEBUG : only13: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:57:58 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:57:58 DEBUG : only6: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:57:58 ERROR : only6: Failed to copy: failed to put object: internal 2026/09/07 05:58:01 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:01 DEBUG : only7: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:58:01 ERROR : only7: Failed to copy: failed to put object: internal 2026/09/07 05:58:04 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:04 DEBUG : only13: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:58:04 ERROR : only13: Failed to copy: failed to put object: internal 2026/09/07 05:58:08 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:08 DEBUG : only18: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:58:11 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:11 DEBUG : only8: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:58:14 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:14 DEBUG : only9: Received error: failed to put object: internal - low level retry 0/10 2026/09/07 05:58:18 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:18 DEBUG : only18: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:58:21 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:21 DEBUG : only8: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:58:24 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:24 DEBUG : only9: Received error: failed to put object: internal - low level retry 1/10 2026/09/07 05:58:31 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:31 DEBUG : only8: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:58:34 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:34 DEBUG : only9: Received error: failed to put object: internal - low level retry 2/10 2026/09/07 05:58:41 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:41 DEBUG : only8: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:58:44 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:44 DEBUG : only9: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 05:58:51 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:51 DEBUG : only8: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:58:54 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:58:54 DEBUG : only9: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 05:59:01 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:59:01 DEBUG : only8: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:59:04 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:59:04 DEBUG : only9: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 05:59:11 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:59:11 DEBUG : only8: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:59:14 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:59:14 DEBUG : only9: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 05:59:24 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:59:24 DEBUG : only9: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 05:59:34 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:59:34 DEBUG : only9: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 05:59:44 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 05:59:44 DEBUG : only9: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 05:59:44 ERROR : only9: Failed to copy: failed to put object: internal 2026/09/07 06:00:13 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:00:13 DEBUG : only8: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 06:00:23 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:00:23 DEBUG : only8: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 06:00:33 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:00:33 DEBUG : only8: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 06:00:33 ERROR : only8: Failed to copy: failed to put object: internal 2026/09/07 06:02:22 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only16?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:40420->[2404:b9c0:101:1::1]:443: i/o timeout) 2026/09/07 06:02:22 DEBUG : only16: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only16?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": read tcp [2a01:4f9:c011:405e::1]:40420->[2404:b9c0:101:1::1]:443: i/o timeout - low level retry 3/10 2026/09/07 06:02:33 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:02:33 DEBUG : only16: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 06:02:43 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:02:43 DEBUG : only16: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 06:03:18 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only18?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 06:03:18 DEBUG : only18: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only18?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 2/10 2026/09/07 06:03:28 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:03:28 DEBUG : only18: Received error: failed to put object: internal - low level retry 3/10 2026/09/07 06:03:38 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:03:38 DEBUG : only18: Received error: failed to put object: internal - low level retry 4/10 2026/09/07 06:03:49 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:03:49 DEBUG : only18: Received error: failed to put object: internal - low level retry 5/10 2026/09/07 06:03:59 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:03:59 DEBUG : only18: Received error: failed to put object: internal - low level retry 6/10 2026/09/07 06:04:09 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:04:09 DEBUG : only18: Received error: failed to put object: internal - low level retry 7/10 2026/09/07 06:04:19 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:04:19 DEBUG : only18: Received error: failed to put object: internal - low level retry 8/10 2026/09/07 06:04:29 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:04:29 DEBUG : only18: Received error: failed to put object: internal - low level retry 9/10 2026/09/07 06:04:29 ERROR : only18: Failed to copy: failed to put object: internal 2026/09/07 06:07:43 DEBUG : pacer: low level retry 1/1 (error Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only16?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers) 2026/09/07 06:07:43 DEBUG : only16: Received error: failed to put object: Put "https://pixeldrain.com/api/filesystem/me/rclone-test-yodidaw8wike/only16?make_parents=true&modified=2001-02-03T04%3A05%3A06.499Z": net/http: timeout awaiting response headers - low level retry 6/10 2026/09/07 06:07:53 DEBUG : pacer: low level retry 1/1 (error internal) 2026/09/07 06:07:53 DEBUG : only16: Received error: failed to put object: internal - low level retry 7/10 panic: test timed out after 1h0m0s running tests: TestSyncConcurrentTruncate (58m32s) goroutine 9802 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2959 +0x34a created by time.goFunc /usr/local/go/src/time/sleep.go:182 +0x2d goroutine 1 [chan receive, 58 minutes]: testing.(*T).Run(0x33a7c82aa248, {0x24dd33f?, 0x33a7c7edfa58?}, 0x4838b60) /usr/local/go/src/testing/testing.go:2266 +0x4f2 testing.runTests.func1(0x33a7c82aa248) /usr/local/go/src/testing/testing.go:2742 +0x37 testing.tRunner(0x33a7c82aa248, 0x33a7c7edfb80) /usr/local/go/src/testing/testing.go:2193 +0xea testing.runTests({0x24d5141, 0x18}, {0x24f86ff, 0x20}, 0x33a7c86c8e88, {0x4b0fe60, 0x6d, 0x6d}, {0xc29fb33ec03bcf8e, 0x3463ae83d55, ...}) /usr/local/go/src/testing/testing.go:2740 +0x510 testing.(*M).Run(0x33a7c874a820) /usr/local/go/src/testing/testing.go:2600 +0x6af github.com/rclone/rclone/fstest.TestMain(0x33a7c874a820) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:75 +0xa6 github.com/rclone/rclone/fs/sync.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:46 main.main() _testmain.go:264 +0xa6 goroutine 13 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 12 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x98 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func2.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:164 +0x1f goroutine 9831 [select, 4 minutes]: net/http.(*persistConn).writeLoop(0x33a7c8950280) /usr/local/go/src/net/http/transport.go:2810 +0xe6 created by net/http.(*Transport).dialConn in goroutine 9745 /usr/local/go/src/net/http/transport.go:2124 +0x1e05 goroutine 9832 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0x33a7c8668600) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:222 +0xec created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 9078 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:120 +0x449 goroutine 9830 [IO wait, 4 minutes]: internal/poll.runtime_pollWait(0x702ca76dfc00, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0x33a7c8054500?, 0x33a7c850a800?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0x33a7c8054500, {0x33a7c850a800, 0x1800, 0x1800}) /usr/local/go/src/internal/poll/fd_unix.go:170 +0x2a8 net.(*netFD).Read(0x33a7c8054500, {0x33a7c850a800?, 0x0?, 0x0?}) /usr/local/go/src/net/fd_posix.go:68 +0x25 net.(*conn).Read(0x33a7c7e763a8, {0x33a7c850a800?, 0x0?, 0x33a7c8e0ea10?}) /usr/local/go/src/net/net.go:196 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0x33a7c8ca6108, {0x33a7c850a800?, 0x33a7c8e0ea38?, 0x33a7c7f80008?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*Conn).readFromUntil(0x33a7c882e808, {0x702cad110ca0, 0x33a7c8ca6108}, 0x33a7c8e0ec58?) /usr/local/go/src/crypto/tls/conn.go:820 +0xf4 crypto/tls.(*Conn).readRecordOrCCS(0x33a7c882e808, 0x0) /usr/local/go/src/crypto/tls/conn.go:626 +0x3fb crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:588 crypto/tls.(*Conn).Read(0x33a7c882e808, {0x33a7c827c000, 0x1000, 0x426ba40?}) /usr/local/go/src/crypto/tls/conn.go:1392 +0x148 net/http.(*persistConn).Read(0x33a7c8950280, {0x33a7c827c000?, 0x47f6c18?, 0x4ab2660?}) /usr/local/go/src/net/http/transport.go:2300 +0x47 bufio.(*Reader).fill(0x33a7c7fb1080) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0x33a7c7fb1080, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x52 net/http.(*persistConn).readLoop(0x33a7c8950280) /usr/local/go/src/net/http/transport.go:2483 +0x172 created by net/http.(*Transport).dialConn in goroutine 9745 /usr/local/go/src/net/http/transport.go:2123 +0x1da5 goroutine 2413 [chan receive, 59 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 2256 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 9009 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0x33a7c892e000, {0x481ef30, 0x33a7c8051270}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:352 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 9077 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:389 +0x12c goroutine 9014 [sync.WaitGroup.Wait, 58 minutes]: sync.runtime_SemacquireWaitGroup(0x0?, 0x0?) /usr/local/go/src/runtime/sema.go:114 +0x2e sync.(*WaitGroup).Wait(0x33a7c8d36118) /usr/local/go/src/sync/waitgroup.go:206 +0x85 github.com/rclone/rclone/fs/sync.(*syncCopyMove).stopTransfers(0x33a7c8d36008) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:556 +0x6f github.com/rclone/rclone/fs/sync.(*syncCopyMove).run(0x33a7c8d36008) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:987 +0x33f github.com/rclone/rclone/fs/sync.runSyncCopyMove({0x481eef8, 0x33a7c8746ab0}, {0x482ddc8, 0x33a7c847a600}, {0x482d508, 0x33a7c803cb40}, 0x3, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1391 +0x236 github.com/rclone/rclone/fs/sync.Sync({0x481eef8, 0x33a7c8746ab0}, {0x482ddc8, 0x33a7c847a600}, {0x482d508, 0x33a7c803cb40}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:1397 +0x77 github.com/rclone/rclone/fs/sync.testSyncConcurrent(0x33a7c845cd88, {0x249d0b3, 0x8}) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2778 +0xc4a github.com/rclone/rclone/fs/sync.TestSyncConcurrentTruncate(0x33a7c845cd88?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2793 +0x1f testing.tRunner(0x33a7c845cd88, 0x4838b60) /usr/local/go/src/testing/testing.go:2193 +0xea created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:2258 +0x4d4 goroutine 9078 [select, 4 minutes]: net/http.(*persistConn).roundTrip(0x33a7c8950280, 0x33a7c897e5a0) /usr/local/go/src/net/http/transport.go:3069 +0x84b net/http.(*Transport).roundTrip(0x33a7c841c000, 0x33a7c8950780) /usr/local/go/src/net/http/transport.go:725 +0xada net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:33 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0x33a7c8325380, 0x33a7c8950780) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:700 +0x63b net/http.send(0x33a7c8950780, {0x47f8478, 0x33a7c8325380}, {0x33a7c7edea78?, 0x497406?, 0x0?}) /usr/local/go/src/net/http/client.go:266 +0x654 net/http.(*Client).send(0x33a7c842fa10, 0x33a7c8950780, {0x263b3d8?, 0x1?, 0x0?}) /usr/local/go/src/net/http/client.go:187 +0x250 net/http.(*Client).do(0x33a7c842fa10, 0x33a7c8950780) /usr/local/go/src/net/http/client.go:745 +0x9f7 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:604 github.com/rclone/rclone/lib/rest.(*Client).Call(0x33a7c8354230, {0x481ef30, 0x33a7c83e9b30}, 0x33a7c7edf3d8) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:440 +0xe85 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0x33a7c8354230, {0x481ef30, 0x33a7c83e9b30}, 0x0?, {0x0?, 0x0?}, {0x40ff008, 0x33a7c8e28370}, 0x33a7c8436a00?, 0x483a0f8, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:686 +0x4e8 github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:623 github.com/rclone/rclone/backend/pixeldrain.(*Fs).put.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/pixeldrain/api_client.go:217 +0x1a9 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0x1, 0x24907a0?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0x33a7c83253e0, 0x33a7c8423570, 0x1) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:228 +0xd2 github.com/rclone/rclone/lib/pacer.(*Pacer).CallNoRetry(...) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:256 github.com/rclone/rclone/backend/pixeldrain.(*Fs).put(0x33a7c847a600, {0x481ef30, 0x33a7c83e9b30}, {0x33a7c8c88f7d, 0x6}, {0x47f7558, _}, _, {0x33a7c8290c40, 0x1, ...}) /home/rclone/go/src/github.com/rclone/rclone/backend/pixeldrain/api_client.go:216 +0x215 github.com/rclone/rclone/backend/pixeldrain.(*Fs).Put(0x33a7c847a600, {0x481ef30, 0x33a7c83e9b30}, {0x47f7558, 0x33a7c8668600}, {0x4828190, 0x33a7c8e28d10}, {0x33a7c8290c40, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/pixeldrain/pixeldrain.go:246 +0x345 github.com/rclone/rclone/backend/pixeldrain.(*Object).Update(0x33a7c8e28d10, {0x481ef30, 0x33a7c83e9b30}, {0x47f7558, 0x33a7c8668600}, {0x702ca7731098, 0x33a7c7fb0e40}, {0x33a7c8290c40, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/backend/pixeldrain/pixeldrain.go:486 +0x165 github.com/rclone/rclone/fs/operations.(*copy).updateOrPut(0x33a7c8352630, {0x481ef30, 0x33a7c83e9b30}, {0x480af78?, 0x33a7c83722d0?}, {0x33a7c8290c40, 0x1, 0x1}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:223 +0x1a5 github.com/rclone/rclone/fs/operations.(*copy).manualCopy(0x33a7c8352630, {0x481ef30, 0x33a7c83e9b30}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:287 +0x776 github.com/rclone/rclone/fs/operations.(*copy).copy(0x33a7c8352630, {0x481ef30, 0x33a7c83e9b30}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:327 +0x170 github.com/rclone/rclone/fs/operations.Copy({0x481ef30, 0x33a7c83e9b30}, {0x482ddc8, 0x33a7c847a600}, {0x482de38, 0x33a7c8e28d10}, {0x33a7c85daf28, 0x6}, {0x482d578, 0x33a7c7fb0e40}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy.go:429 +0x4dc github.com/rclone/rclone/fs/sync.(*syncCopyMove).pairCopyOrMove(0x33a7c8d36008, {0x481ef30, 0x33a7c83e9b30}, 0x33a7c83e9a90, {0x482ddc8, 0x33a7c847a600}, 0x19, 0x33a7c8d52fb8?) /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:518 +0x1fe created by github.com/rclone/rclone/fs/sync.(*syncCopyMove).startTransfers in goroutine 9014 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync.go:548 +0x7d "./sync.test -test.v -test.timeout 1h0m0s -remote TestPixeldrain: -verbose" - Finished ERROR in 1h0m0.189867708s (try 1/5): exit status 2: Failed []