"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose" - Starting (try 1/5) 2025/01/29 03:10:18 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe" 2025/01/29 03:10:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/29 03:10:18 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Token expired but no uploads in progress - doing nothing 2025/01/29 03:10:18 DEBUG : TestOneDrive: Loaded invalid token from config file - ignoring 2025/01/29 03:10:18 DEBUG : Saving config "token" in section "TestOneDrive" of the config file 2025/01/29 03:10:18 DEBUG : TestOneDrive: Saved new token in config file 2025/01/29 03:10:19 DEBUG : Creating backend with remote "/tmp/rclone2870153325" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:19 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:10:19 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:19 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2025/01/29 03:10:19 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:19 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:19 NOTICE: sub dir: Skipped set directory modification time as --dry-run is set run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyWithDryRun (1.33s) === RUN TestCopy run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:20 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:10:20 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:10:20 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:20 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:20 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:20 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:21 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:21 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:21 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:10:21 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopy (2.89s) === RUN TestCopyMetadata run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:23 DEBUG : metadata sub dir: Making directory with metadata 2025/01/29 03:10:23 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:23 DEBUG : empty metadata sub dir: Making directory with metadata 2025/01/29 03:10:23 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:23 DEBUG : Local file system at /tmp/rclone2870153325: File to upload is small (21 bytes), uploading instead of streaming 2025/01/29 03:10:23 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2025/01/29 03:10:23 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2025/01/29 03:10:23 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:10:23 DEBUG : empty metadata sub dir: skipping unsupported metadata item: potato: king edward 2025/01/29 03:10:23 DEBUG : empty metadata sub dir: skipping unsupported metadata item: mode: 40775 2025/01/29 03:10:23 DEBUG : empty metadata sub dir: skipping unsupported metadata item: uid: 1000 2025/01/29 03:10:23 DEBUG : empty metadata sub dir: skipping unsupported metadata item: gid: 1000 2025/01/29 03:10:23 DEBUG : empty metadata sub dir: skipping unsupported metadata item: atime: 2011-12-25T12:59:59.123456789Z 2025/01/29 03:10:23 INFO : empty metadata sub dir: Updated directory metadata 2025/01/29 03:10:23 DEBUG : metadata sub dir: skipping unsupported metadata item: mode: 40775 2025/01/29 03:10:23 DEBUG : metadata sub dir: skipping unsupported metadata item: uid: 1000 2025/01/29 03:10:23 DEBUG : metadata sub dir: skipping unsupported metadata item: gid: 1000 2025/01/29 03:10:23 DEBUG : metadata sub dir: skipping unsupported metadata item: atime: 2011-12-25T12:59:59.123456789Z 2025/01/29 03:10:23 DEBUG : metadata sub dir: skipping unsupported metadata item: potato: king edward 2025/01/29 03:10:24 INFO : metadata sub dir: Updated directory metadata 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: Starting multipart upload 2025/01/29 03:10:24 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: uid: 1000 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: gid: 1000 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: atime: 2025-01-29T03:10:24.182259609Z 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: potato: jersey 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: mode: 100664 2025/01/29 03:10:24 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: Uploading segment 0/21 size 21 2025/01/29 03:10:24 DEBUG : metadata sub dir/hello metadata world: quickxor = 88266c8bdbebce00b6a10c741d0319c240873080 OK 2025/01/29 03:10:24 INFO : metadata sub dir/hello metadata world: Copied (new) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyMetadata (4.27s) === RUN TestCopyMetadataNoEmptyDirs run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:27 DEBUG : metadata sub dir: Making directory with metadata 2025/01/29 03:10:27 INFO : metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:27 DEBUG : empty metadata sub dir: Making directory with metadata 2025/01/29 03:10:27 INFO : empty metadata sub dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:27 DEBUG : Local file system at /tmp/rclone2870153325: File to upload is small (21 bytes), uploading instead of streaming 2025/01/29 03:10:27 DEBUG : metadata sub dir/hello metadata world: md5 = baa7a013449dbba2b1ed02b5cfb4e1a2 OK 2025/01/29 03:10:27 DEBUG : metadata sub dir/hello metadata world: Size and md5 of src and dst objects identical 2025/01/29 03:10:27 INFO : metadata sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:10:27 DEBUG : Added delayed dir = "empty metadata sub dir", newDst= 2025/01/29 03:10:27 DEBUG : Added delayed dir = "metadata sub dir", newDst= 2025/01/29 03:10:27 DEBUG : metadata sub dir/hello metadata world: Need to transfer - File not found at Destination 2025/01/29 03:10:27 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:27 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:28 DEBUG : metadata sub dir/hello metadata world: Starting multipart upload 2025/01/29 03:10:28 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: uid: 1000 2025/01/29 03:10:28 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: gid: 1000 2025/01/29 03:10:28 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: atime: 2025-01-29T03:10:27.822232447Z 2025/01/29 03:10:28 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: potato: jersey 2025/01/29 03:10:28 DEBUG : metadata sub dir/hello metadata world: skipping unsupported metadata item: mode: 100664 2025/01/29 03:10:28 DEBUG : metadata sub dir/hello metadata world: Uploading segment 0/21 size 21 2025/01/29 03:10:28 DEBUG : metadata sub dir/hello metadata world: quickxor = 88266c8bdbebce00b6a10c741d0319c240873080 OK 2025/01/29 03:10:28 INFO : metadata sub dir/hello metadata world: Copied (new) 2025/01/29 03:10:28 DEBUG : metadata sub dir: skipping unsupported metadata item: gid: 1000 2025/01/29 03:10:28 DEBUG : metadata sub dir: skipping unsupported metadata item: atime: 2025-01-29T03:10:27.822232447Z 2025/01/29 03:10:28 DEBUG : metadata sub dir: skipping unsupported metadata item: potato: king edward 2025/01/29 03:10:28 DEBUG : metadata sub dir: skipping unsupported metadata item: mode: 40775 2025/01/29 03:10:28 DEBUG : metadata sub dir: skipping unsupported metadata item: uid: 1000 2025/01/29 03:10:28 INFO : metadata sub dir: Updated directory metadata run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyMetadataNoEmptyDirs (3.05s) === RUN TestCopyMissingDirectory run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:30 DEBUG : Creating backend with remote "/non-existing" 2025/01/29 03:10:30 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2025/01/29 03:10:30 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:30 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyMissingDirectory (0.69s) === RUN TestCopyNoTraverse run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:31 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:10:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:31 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:31 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:31 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:31 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:32 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:32 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:10:32 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyNoTraverse (2.56s) === RUN TestCopyCheckFirst run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:33 INFO : OneDrive root 'rclone-test-kociqez2hufe': Running all checks before starting transfers 2025/01/29 03:10:34 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:10:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:34 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:34 INFO : OneDrive root 'rclone-test-kociqez2hufe': Checks finished, now starting transfers 2025/01/29 03:10:34 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:34 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:34 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:34 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:34 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:10:35 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyCheckFirst (2.65s) === RUN TestSyncNoTraverse run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:36 ERROR : Ignoring --no-traverse with sync 2025/01/29 03:10:36 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:10:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:36 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:36 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:37 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:37 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:37 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:37 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:10:37 DEBUG : Waiting for deletions to finish 2025/01/29 03:10:37 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncNoTraverse (2.70s) === RUN TestCopyWithDepth run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:39 DEBUG : hello world2: Need to transfer - File not found at Destination 2025/01/29 03:10:39 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:10:39 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:39 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:39 DEBUG : hello world2: Starting multipart upload 2025/01/29 03:10:39 DEBUG : hello world2: Uploading segment 0/12 size 12 2025/01/29 03:10:39 DEBUG : hello world2: quickxor = 6828031bd8f00610dce10d726c03196400000000 OK 2025/01/29 03:10:39 INFO : hello world2: Copied (new) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyWithDepth (1.52s) === RUN TestCopyWithFilesFrom run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:41 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:10:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:41 DEBUG : potato2: Starting multipart upload 2025/01/29 03:10:41 DEBUG : potato2: Uploading segment 0/11 size 11 2025/01/29 03:10:41 DEBUG : potato2: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:41 INFO : potato2: Copied (new) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyWithFilesFrom (1.44s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:42 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:10:42 DEBUG : potato2: Starting multipart upload 2025/01/29 03:10:42 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:42 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:42 DEBUG : potato2: Uploading segment 0/11 size 11 2025/01/29 03:10:42 DEBUG : potato2: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:42 INFO : potato2: Copied (new) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyWithFilesFromAndNoTraverse (1.57s) === RUN TestCopyEmptyDirectories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:43 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/01/29 03:10:43 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:43 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/01/29 03:10:43 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:10:43 DEBUG : sub dir: Making directory with metadata 2025/01/29 03:10:44 INFO : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/01/29 03:10:44 DEBUG : sub dir2: Making directory with metadata 2025/01/29 03:10:44 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:44 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/01/29 03:10:44 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:44 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:44 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:44 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:45 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:45 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyEmptyDirectories (4.38s) === RUN TestCopyNoEmptyDirectories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:48 INFO : sub dir2: Making directory 2025/01/29 03:10:48 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/01/29 03:10:48 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:48 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:10:48 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/01/29 03:10:48 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:48 DEBUG : Added delayed dir = "sub dir2/sub sub dir2", newDst= 2025/01/29 03:10:48 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:48 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:48 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:48 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:49 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:49 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:10:49 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyNoEmptyDirectories (2.25s) === RUN TestMoveEmptyDirectories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:50 DEBUG : sub dir2: Making directory with metadata 2025/01/29 03:10:50 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:50 DEBUG : sub dir: Making directory with metadata 2025/01/29 03:10:50 INFO : sub dir: Made directory with metadata (mtime=2025-01-29T03:10:50.486063673Z) 2025/01/29 03:10:50 DEBUG : sub dir2: Making directory with metadata 2025/01/29 03:10:51 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:51 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:51 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:51 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:51 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:51 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:51 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:10:51 INFO : sub dir/hello world: Deleted run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestMoveEmptyDirectories (3.60s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:54 DEBUG : sub dir no update dir modtime: Making directory with metadata 2025/01/29 03:10:54 INFO : sub dir no update dir modtime: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/01/29 03:10:54 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:54 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:54 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncNoUpdateDirModtime (2.17s) === RUN TestMoveNoEmptyDirectories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:56 INFO : sub dir2: Making directory 2025/01/29 03:10:56 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:10:56 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/01/29 03:10:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:56 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:56 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:56 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:56 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:57 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:57 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:10:57 INFO : sub dir/hello world: Deleted 2025/01/29 03:10:57 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestMoveNoEmptyDirectories (2.34s) === RUN TestSyncEmptyDirectories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:10:58 DEBUG : sub dir2: Making directory with metadata 2025/01/29 03:10:58 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:58 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:10:58 DEBUG : sub dir: Making directory with metadata 2025/01/29 03:10:59 INFO : sub dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2025/01/29 03:10:59 DEBUG : sub dir2: Making directory with metadata 2025/01/29 03:10:59 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:10:59 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:10:59 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:10:59 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:10:59 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:10:59 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:10:59 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:10:59 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:10:59 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncEmptyDirectories (3.26s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:507: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.30s) === RUN TestSyncNoEmptyDirectories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:02 INFO : sub dir2: Making directory 2025/01/29 03:11:02 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:11:02 DEBUG : Added delayed dir = "sub dir2", newDst= 2025/01/29 03:11:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:11:02 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:02 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:02 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:02 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:11:02 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:11:02 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:11:02 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:03 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestSyncNoEmptyDirectories (2.27s) === RUN TestServerSideCopy run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:04 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:04 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:11:05 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-pacidap2sopu" sync_test.go:590: Server side copy (if possible) OneDrive root 'rclone-test-kociqez2hufe' -> OneDrive root 'rclone-test-pacidap2sopu' 2025/01/29 03:11:06 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:11:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:11:06 DEBUG : OneDrive root 'rclone-test-pacidap2sopu': Waiting for checks to finish 2025/01/29 03:11:06 DEBUG : OneDrive root 'rclone-test-pacidap2sopu': Waiting for transfers to finish 2025/01/29 03:11:07 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:11:07 INFO : sub dir/hello world: Copied (server-side copy) 2025/01/29 03:11:08 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:08 DEBUG : OneDrive root 'rclone-test-pacidap2sopu': Purge remote --- PASS: TestServerSideCopy (5.43s) === RUN TestCopyOverSelf run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:10 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:10 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:11:11 DEBUG : sub dir: Directory modification time the same (differ by -757.912442ms, within tolerance 1s) 2025/01/29 03:11:11 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/01/29 03:11:11 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/29 03:11:11 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:11 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:11 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:11 DEBUG : sub dir/hello world: Uploading segment 0/17 size 17 2025/01/29 03:11:11 DEBUG : sub dir/hello world: quickxor = 49256d1bd8f00610dce10d727103194010863384 OK 2025/01/29 03:11:11 INFO : sub dir/hello world: Copied (replaced existing) 2025/01/29 03:11:11 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyOverSelf (3.41s) === RUN TestServerSideCopyOverSelf run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:13 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:13 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:11:14 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-yeseqoj8wiza" sync_test.go:626: Server side copy (if possible) OneDrive root 'rclone-test-kociqez2hufe' -> OneDrive root 'rclone-test-yeseqoj8wiza' 2025/01/29 03:11:14 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:11:14 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:11:14 DEBUG : OneDrive root 'rclone-test-yeseqoj8wiza': Waiting for checks to finish 2025/01/29 03:11:14 DEBUG : OneDrive root 'rclone-test-yeseqoj8wiza': Waiting for transfers to finish 2025/01/29 03:11:16 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:11:16 INFO : sub dir/hello world: Copied (server-side copy) 2025/01/29 03:11:16 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:16 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:16 DEBUG : sub dir/hello world: Uploading segment 0/17 size 17 2025/01/29 03:11:17 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1s) 2025/01/29 03:11:17 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/01/29 03:11:17 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/29 03:11:17 DEBUG : OneDrive root 'rclone-test-yeseqoj8wiza': Waiting for checks to finish 2025/01/29 03:11:17 DEBUG : OneDrive root 'rclone-test-yeseqoj8wiza': Waiting for transfers to finish 2025/01/29 03:11:17 DEBUG : sub dir/hello world: server side copy: renaming existing object to "sub dir/hello world.cumuhum2" before starting 2025/01/29 03:11:18 DEBUG : sub dir/hello world.cumuhum2: server side copy: removing renamed existing file after operation 2025/01/29 03:11:18 DEBUG : sub dir/hello world: quickxor = 49256d1bd8f00610dce10d727103194010863384 OK 2025/01/29 03:11:18 INFO : sub dir/hello world: Copied (server-side copy) 2025/01/29 03:11:18 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:19 DEBUG : OneDrive root 'rclone-test-yeseqoj8wiza': Purge remote --- PASS: TestServerSideCopyOverSelf (7.74s) === RUN TestMoveOverSelf run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:21 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:21 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:11:22 DEBUG : sub dir: Directory modification time the same (differ by -719.829583ms, within tolerance 1s) 2025/01/29 03:11:22 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/01/29 03:11:22 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/29 03:11:22 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:22 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:22 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:22 DEBUG : sub dir/hello world: Uploading segment 0/17 size 17 2025/01/29 03:11:22 DEBUG : sub dir/hello world: quickxor = 49256d1bd8f00610dce10d727103194010863384 OK 2025/01/29 03:11:22 INFO : sub dir/hello world: Copied (replaced existing) 2025/01/29 03:11:22 INFO : sub dir/hello world: Deleted 2025/01/29 03:11:22 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestMoveOverSelf (3.55s) === RUN TestServerSideMoveOverSelf run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:24 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:24 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:11:25 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-silaxom2miqi" sync_test.go:671: Server side copy (if possible) OneDrive root 'rclone-test-kociqez2hufe' -> OneDrive root 'rclone-test-silaxom2miqi' 2025/01/29 03:11:26 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:11:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:11:26 DEBUG : OneDrive root 'rclone-test-silaxom2miqi': Waiting for checks to finish 2025/01/29 03:11:26 DEBUG : OneDrive root 'rclone-test-silaxom2miqi': Waiting for transfers to finish 2025/01/29 03:11:28 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:11:28 INFO : sub dir/hello world: Copied (server-side copy) 2025/01/29 03:11:28 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:28 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:28 DEBUG : sub dir/hello world: Uploading segment 0/17 size 17 2025/01/29 03:11:29 DEBUG : OneDrive root 'rclone-test-silaxom2miqi': Using server-side directory move 2025/01/29 03:11:29 INFO : OneDrive root 'rclone-test-silaxom2miqi': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/01/29 03:11:29 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1s) 2025/01/29 03:11:29 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/01/29 03:11:29 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/01/29 03:11:29 DEBUG : OneDrive root 'rclone-test-silaxom2miqi': Waiting for checks to finish 2025/01/29 03:11:29 DEBUG : OneDrive root 'rclone-test-silaxom2miqi': Waiting for transfers to finish 2025/01/29 03:11:29 INFO : sub dir/hello world: Deleted 2025/01/29 03:11:30 INFO : sub dir/hello world: Moved (server-side) 2025/01/29 03:11:30 INFO : sub dir: Set directory modification time (using SetModTime) 2025/01/29 03:11:30 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:30 DEBUG : sub dir/hello world: Uploading segment 0/24 size 24 2025/01/29 03:11:31 DEBUG : testing file moves 2025/01/29 03:11:31 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1s) 2025/01/29 03:11:31 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2025/01/29 03:11:31 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17) 2025/01/29 03:11:31 DEBUG : OneDrive root 'rclone-test-silaxom2miqi': Waiting for checks to finish 2025/01/29 03:11:31 DEBUG : OneDrive root 'rclone-test-silaxom2miqi': Waiting for transfers to finish 2025/01/29 03:11:31 INFO : sub dir/hello world: Deleted 2025/01/29 03:11:32 INFO : sub dir/hello world: Moved (server-side) 2025/01/29 03:11:32 INFO : sub dir: Set directory modification time (using SetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:33 DEBUG : OneDrive root 'rclone-test-silaxom2miqi': Purge remote --- PASS: TestServerSideMoveOverSelf (10.02s) === RUN TestCopyAfterDelete run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:34 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:35 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:11:35 ERROR : : error listing: directory not found 2025/01/29 03:11:35 INFO : Local file system at /tmp/rclone2870153325: Making directory 2025/01/29 03:11:35 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:35 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyAfterDelete (2.81s) === RUN TestCopyRedownload run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:37 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:11:37 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:11:38 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:11:38 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:11:38 DEBUG : Local file system at /tmp/rclone2870153325: Waiting for checks to finish 2025/01/29 03:11:38 DEBUG : Local file system at /tmp/rclone2870153325: Waiting for transfers to finish 2025/01/29 03:11:39 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:11:39 DEBUG : sub dir/hello world.4f013cba.partial: renamed to: sub dir/hello world 2025/01/29 03:11:39 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:11:39 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyRedownload (3.12s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:40 DEBUG : check sum: Need to transfer - File not found at Destination 2025/01/29 03:11:40 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:40 DEBUG : check sum: Starting multipart upload 2025/01/29 03:11:40 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:40 DEBUG : check sum: Uploading segment 0/1 size 1 2025/01/29 03:11:41 DEBUG : check sum: quickxor = 2d00000000000000000000000100000000000000 OK 2025/01/29 03:11:41 INFO : check sum: Copied (new) 2025/01/29 03:11:41 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:41 DEBUG : check sum: quickxor = 2d00000000000000000000000100000000000000 OK 2025/01/29 03:11:41 DEBUG : check sum: Size and quickxor of src and dst objects identical 2025/01/29 03:11:41 DEBUG : check sum: Unchanged skipping 2025/01/29 03:11:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:41 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:41 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncBasedOnCheckSum (1.94s) === RUN TestSyncSizeOnly run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:42 DEBUG : sizeonly: Need to transfer - File not found at Destination 2025/01/29 03:11:42 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:42 DEBUG : sizeonly: Starting multipart upload 2025/01/29 03:11:42 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:42 DEBUG : sizeonly: Uploading segment 0/6 size 6 2025/01/29 03:11:42 DEBUG : sizeonly: quickxor = 7078031dc2408737000000000600000000000000 OK 2025/01/29 03:11:42 INFO : sizeonly: Copied (new) 2025/01/29 03:11:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:43 DEBUG : sizeonly: Sizes identical 2025/01/29 03:11:43 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:43 DEBUG : sizeonly: Unchanged skipping 2025/01/29 03:11:43 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:43 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:43 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncSizeOnly (2.01s) === RUN TestSyncIgnoreSize run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:44 DEBUG : ignore-size: Need to transfer - File not found at Destination 2025/01/29 03:11:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:44 DEBUG : ignore-size: Starting multipart upload 2025/01/29 03:11:44 DEBUG : ignore-size: Uploading segment 0/8 size 8 2025/01/29 03:11:44 DEBUG : ignore-size: quickxor = 6378831be8500637d0610e000800000000000000 OK 2025/01/29 03:11:44 INFO : ignore-size: Copied (new) 2025/01/29 03:11:44 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:45 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:45 DEBUG : ignore-size: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:11:45 DEBUG : ignore-size: Unchanged skipping 2025/01/29 03:11:45 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:45 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:45 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncIgnoreSize (1.97s) === RUN TestSyncIgnoreTimes run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:46 DEBUG : existing: Starting multipart upload 2025/01/29 03:11:46 DEBUG : existing: Uploading segment 0/6 size 6 2025/01/29 03:11:47 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:11:47 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:47 DEBUG : existing: Unchanged skipping 2025/01/29 03:11:47 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:47 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:47 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:47 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:47 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2025/01/29 03:11:47 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:47 DEBUG : existing: Starting multipart upload 2025/01/29 03:11:47 DEBUG : existing: Uploading segment 0/6 size 6 2025/01/29 03:11:47 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2025/01/29 03:11:47 INFO : existing: Copied (replaced existing) 2025/01/29 03:11:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncIgnoreTimes (2.56s) === RUN TestSyncIgnoreExisting run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:49 DEBUG : existing: Need to transfer - File not found at Destination 2025/01/29 03:11:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:49 DEBUG : existing: Starting multipart upload 2025/01/29 03:11:49 DEBUG : existing: Uploading segment 0/6 size 6 2025/01/29 03:11:49 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2025/01/29 03:11:49 INFO : existing: Copied (new) 2025/01/29 03:11:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:49 DEBUG : existing: Destination exists, skipping 2025/01/29 03:11:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:49 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:49 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncIgnoreExisting (2.03s) === RUN TestSyncIgnoreErrors run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:51 DEBUG : b/potato: Starting multipart upload 2025/01/29 03:11:51 DEBUG : b/potato: Uploading segment 0/21 size 21 2025/01/29 03:11:52 DEBUG : c/non empty space: Starting multipart upload 2025/01/29 03:11:52 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2025/01/29 03:11:52 INFO : d: Making directory 2025/01/29 03:11:53 DEBUG : Added delayed dir = "a", newDst= 2025/01/29 03:11:53 DEBUG : c: Directory modification time the same (differ by 248.389554ms, within tolerance 1s) 2025/01/29 03:11:53 DEBUG : Added delayed dir = "c", newDst=c 2025/01/29 03:11:53 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/01/29 03:11:53 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:11:53 DEBUG : c/non empty space: Unchanged skipping 2025/01/29 03:11:53 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:53 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:53 DEBUG : a/potato2: Starting multipart upload 2025/01/29 03:11:53 DEBUG : a/potato2: Uploading segment 0/60 size 60 2025/01/29 03:11:54 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2025/01/29 03:11:54 INFO : a/potato2: Copied (new) 2025/01/29 03:11:54 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:54 INFO : b/potato: Deleted 2025/01/29 03:11:54 INFO : a: Set directory modification time (using DirSetModTime) 2025/01/29 03:11:54 INFO : d: Removing directory 2025/01/29 03:11:54 INFO : b: Removing directory 2025/01/29 03:11:55 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': deleted 2 directories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncIgnoreErrors (6.19s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:57 DEBUG : empty space: Starting multipart upload 2025/01/29 03:11:57 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:11:57 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 2025/01/29 03:11:57 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:57 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2025/01/29 03:11:57 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2025/01/29 03:11:57 DEBUG : empty space: Unchanged skipping 2025/01/29 03:11:57 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:57 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:57 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:58 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:11:58 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 2025/01/29 03:11:58 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2025/01/29 03:11:58 INFO : empty space: Updated modification time in destination 2025/01/29 03:11:58 DEBUG : empty space: Unchanged skipping 2025/01/29 03:11:58 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:11:58 DEBUG : Waiting for deletions to finish 2025/01/29 03:11:58 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterChangingModtimeOnly (2.29s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:11:59 DEBUG : empty space: Starting multipart upload 2025/01/29 03:11:59 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:12:00 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:00 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 2025/01/29 03:12:00 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2025/01/29 03:12:00 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:00 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:00 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:00 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (1.55s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:01 DEBUG : foo: Starting multipart upload 2025/01/29 03:12:01 DEBUG : foo: Uploading segment 0/3 size 3 2025/01/29 03:12:01 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 2025/01/29 03:12:01 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:01 DEBUG : foo: quickxor = 6678c31b00000000000000000300000000000000 (Local file system at /tmp/rclone2870153325) 2025/01/29 03:12:01 DEBUG : foo: quickxor = 6208831c00000000000000000300000000000000 (OneDrive root 'rclone-test-kociqez2hufe') 2025/01/29 03:12:01 DEBUG : foo: quickxor differ 2025/01/29 03:12:01 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:01 DEBUG : foo: Starting multipart upload 2025/01/29 03:12:01 DEBUG : foo: Uploading segment 0/3 size 3 2025/01/29 03:12:02 DEBUG : foo: quickxor = 6678c31b00000000000000000300000000000000 OK 2025/01/29 03:12:02 INFO : foo: Copied (replaced existing) 2025/01/29 03:12:02 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncDoesntUpdateModtime (2.47s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:03 DEBUG : empty space: Starting multipart upload 2025/01/29 03:12:03 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:12:04 DEBUG : potato: Need to transfer - File not found at Destination 2025/01/29 03:12:04 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:04 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:04 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:04 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:04 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:04 DEBUG : potato: Uploading segment 0/60 size 60 2025/01/29 03:12:04 DEBUG : potato: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2025/01/29 03:12:04 INFO : potato: Copied (new) 2025/01/29 03:12:04 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterAddingAFile (2.27s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:05 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:05 DEBUG : potato: Uploading segment 0/60 size 60 2025/01/29 03:12:06 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2025/01/29 03:12:06 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:06 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:06 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:06 DEBUG : potato: Uploading segment 0/21 size 21 2025/01/29 03:12:06 DEBUG : potato: quickxor = d26463389bd86e62ce010462bd031d40308730b4 OK 2025/01/29 03:12:06 INFO : potato: Copied (replaced existing) 2025/01/29 03:12:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterChangingFilesSizeOnly (1.91s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:07 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:07 DEBUG : potato: Uploading segment 0/21 size 21 2025/01/29 03:12:08 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03: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 2025/01/29 03:12:08 DEBUG : potato: quickxor = f2606230dad02c724c010442bd02154030852034 (Local file system at /tmp/rclone2870153325) 2025/01/29 03:12:08 DEBUG : potato: quickxor = d26463389bd86e62ce010462bd031d40308730b4 (OneDrive root 'rclone-test-kociqez2hufe') 2025/01/29 03:12:08 DEBUG : potato: quickxor differ 2025/01/29 03:12:08 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:08 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:08 DEBUG : potato: Uploading segment 0/21 size 21 2025/01/29 03:12:08 DEBUG : potato: quickxor = f2606230dad02c724c010442bd02154030852034 OK 2025/01/29 03:12:08 INFO : potato: Copied (replaced existing) 2025/01/29 03:12:08 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterChangingContentsOnly (2.09s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:09 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:09 DEBUG : potato: Uploading segment 0/21 size 21 2025/01/29 03:12:10 DEBUG : empty space: Starting multipart upload 2025/01/29 03:12:10 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:12:10 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:12:10 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2025/01/29 03:12:10 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:10 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:10 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:10 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:10 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:10 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.05s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:11 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:11 DEBUG : potato: Uploading segment 0/21 size 21 2025/01/29 03:12:12 DEBUG : empty space: Starting multipart upload 2025/01/29 03:12:12 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:12:12 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:12:12 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:12 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:12 DEBUG : potato2: Starting multipart upload 2025/01/29 03:12:12 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:12 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:13 DEBUG : potato2: Uploading segment 0/60 size 60 2025/01/29 03:12:13 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2025/01/29 03:12:13 INFO : potato2: Copied (new) 2025/01/29 03:12:13 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:13 INFO : potato: Deleted run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.72s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:14 DEBUG : b/potato: Starting multipart upload 2025/01/29 03:12:15 DEBUG : b/potato: Uploading segment 0/21 size 21 2025/01/29 03:12:15 DEBUG : c/non empty space: Starting multipart upload 2025/01/29 03:12:15 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2025/01/29 03:12:15 INFO : d: Making directory 2025/01/29 03:12:16 INFO : d/e: Making directory 2025/01/29 03:12:17 DEBUG : Added delayed dir = "a", newDst= 2025/01/29 03:12:17 DEBUG : c: Directory modification time the same (differ by 232.562728ms, within tolerance 1s) 2025/01/29 03:12:17 DEBUG : Added delayed dir = "c", newDst=c 2025/01/29 03:12:17 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/01/29 03:12:17 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:17 DEBUG : c/non empty space: Unchanged skipping 2025/01/29 03:12:17 DEBUG : a/potato2: Starting multipart upload 2025/01/29 03:12:17 DEBUG : a/potato2: Uploading segment 0/60 size 60 2025/01/29 03:12:17 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:17 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:17 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2025/01/29 03:12:17 INFO : a/potato2: Copied (new) 2025/01/29 03:12:17 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:18 INFO : b/potato: Deleted 2025/01/29 03:12:18 INFO : a: Set directory modification time (using DirSetModTime) 2025/01/29 03:12:18 INFO : d/e: Removing directory 2025/01/29 03:12:19 INFO : d: Removing directory 2025/01/29 03:12:19 INFO : b: Removing directory 2025/01/29 03:12:19 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': deleted 3 directories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (7.36s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:22 DEBUG : b/potato: Starting multipart upload 2025/01/29 03:12:22 DEBUG : b/potato: Uploading segment 0/21 size 21 2025/01/29 03:12:22 DEBUG : c/non empty space: Starting multipart upload 2025/01/29 03:12:23 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2025/01/29 03:12:23 INFO : d: Making directory 2025/01/29 03:12:24 DEBUG : Added delayed dir = "a", newDst= 2025/01/29 03:12:24 DEBUG : c: Directory modification time the same (differ by 261.616652ms, within tolerance 1s) 2025/01/29 03:12:24 DEBUG : Added delayed dir = "c", newDst=c 2025/01/29 03:12:24 DEBUG : a/potato2: Need to transfer - File not found at Destination 2025/01/29 03:12:24 DEBUG : c/non empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:24 DEBUG : c/non empty space: Unchanged skipping 2025/01/29 03:12:24 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:24 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:24 DEBUG : a/potato2: Starting multipart upload 2025/01/29 03:12:24 DEBUG : a/potato2: Uploading segment 0/60 size 60 2025/01/29 03:12:24 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2025/01/29 03:12:24 INFO : a/potato2: Copied (new) 2025/01/29 03:12:24 ERROR : OneDrive root 'rclone-test-kociqez2hufe': not deleting files as there were IO errors 2025/01/29 03:12:25 INFO : a: Set directory modification time (using DirSetModTime) 2025/01/29 03:12:25 ERROR : OneDrive root 'rclone-test-kociqez2hufe': not deleting directories as there were IO errors run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (6.27s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:28 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:28 DEBUG : potato: Uploading segment 0/21 size 21 2025/01/29 03:12:28 DEBUG : empty space: Starting multipart upload 2025/01/29 03:12:28 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:12:29 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:29 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:12:29 DEBUG : potato2: Starting multipart upload 2025/01/29 03:12:29 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:29 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:29 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:29 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:29 DEBUG : potato2: Uploading segment 0/60 size 60 2025/01/29 03:12:29 INFO : potato: Deleted 2025/01/29 03:12:29 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2025/01/29 03:12:29 INFO : potato2: Copied (new) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncDeleteDuring (2.66s) === RUN TestSyncDeleteBefore run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:30 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:31 DEBUG : potato: Uploading segment 0/21 size 21 2025/01/29 03:12:31 DEBUG : empty space: Starting multipart upload 2025/01/29 03:12:31 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:12:31 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:31 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:31 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:32 INFO : potato: Deleted 2025/01/29 03:12:32 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:12:32 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:32 DEBUG : potato2: Starting multipart upload 2025/01/29 03:12:32 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:32 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:32 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:32 DEBUG : potato2: Uploading segment 0/60 size 60 2025/01/29 03:12:32 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2025/01/29 03:12:32 INFO : potato2: Copied (new) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncDeleteBefore (2.81s) === RUN TestCopyDeleteBefore run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:33 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:33 DEBUG : potato: Uploading segment 0/21 size 21 2025/01/29 03:12:34 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:12:34 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:34 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:34 DEBUG : potato2: Starting multipart upload 2025/01/29 03:12:34 DEBUG : potato2: Uploading segment 0/19 size 19 2025/01/29 03:12:34 DEBUG : potato2: quickxor = e9742354497b863ab0810d7913c118de00873494 OK 2025/01/29 03:12:34 INFO : potato2: Copied (new) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestCopyDeleteBefore (2.19s) === RUN TestSyncWithExclude run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:35 DEBUG : potato2: Starting multipart upload 2025/01/29 03:12:36 DEBUG : potato2: Uploading segment 0/60 size 60 2025/01/29 03:12:36 DEBUG : empty space: Starting multipart upload 2025/01/29 03:12:36 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:12:36 DEBUG : enormous: Excluded (Size Filter) 2025/01/29 03:12:36 DEBUG : enormous: Excluded 2025/01/29 03:12:36 DEBUG : potato2: Excluded (Size Filter) 2025/01/29 03:12:36 DEBUG : potato2: Excluded 2025/01/29 03:12:36 DEBUG : potato2: Excluded (Size Filter) 2025/01/29 03:12:36 DEBUG : potato2: Excluded 2025/01/29 03:12:36 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:36 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:36 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:36 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:36 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:36 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:37 DEBUG : enormous: Excluded (Size Filter) 2025/01/29 03:12:37 DEBUG : enormous: Excluded 2025/01/29 03:12:37 DEBUG : potato2: Excluded (Size Filter) 2025/01/29 03:12:37 DEBUG : potato2: Excluded 2025/01/29 03:12:37 DEBUG : potato2: Excluded (Size Filter) 2025/01/29 03:12:37 DEBUG : potato2: Excluded 2025/01/29 03:12:37 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1s) 2025/01/29 03:12:37 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:37 DEBUG : Local file system at /tmp/rclone2870153325: Waiting for checks to finish 2025/01/29 03:12:37 DEBUG : Local file system at /tmp/rclone2870153325: Waiting for transfers to finish 2025/01/29 03:12:37 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:37 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncWithExclude (2.63s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:38 DEBUG : potato2: Starting multipart upload 2025/01/29 03:12:38 DEBUG : potato2: Uploading segment 0/60 size 60 2025/01/29 03:12:39 DEBUG : empty space: Starting multipart upload 2025/01/29 03:12:39 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:12:39 DEBUG : enormous: Starting multipart upload 2025/01/29 03:12:39 DEBUG : enormous: Uploading segment 0/100 size 100 2025/01/29 03:12:40 DEBUG : enormous: Excluded (Size Filter) 2025/01/29 03:12:40 DEBUG : enormous: Excluded 2025/01/29 03:12:40 DEBUG : potato2: Excluded (Size Filter) 2025/01/29 03:12:40 DEBUG : potato2: Excluded 2025/01/29 03:12:40 DEBUG : empty space: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:12:40 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:40 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:40 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:40 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:40 INFO : enormous: Deleted 2025/01/29 03:12:40 INFO : potato2: Deleted 2025/01/29 03:12:40 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:41 DEBUG : Local file system at /tmp/rclone2870153325: Waiting for checks to finish 2025/01/29 03:12:41 DEBUG : empty space: Size and modification time the same (differ by 456.789µs, within tolerance 1s) 2025/01/29 03:12:41 DEBUG : empty space: Unchanged skipping 2025/01/29 03:12:41 DEBUG : Local file system at /tmp/rclone2870153325: Waiting for transfers to finish 2025/01/29 03:12:41 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:41 INFO : enormous: Deleted 2025/01/29 03:12:41 INFO : potato2: Deleted 2025/01/29 03:12:41 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncWithExcludeAndDeleteExcluded (3.80s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:42 DEBUG : one: Starting multipart upload 2025/01/29 03:12:42 DEBUG : one: Uploading segment 0/3 size 3 2025/01/29 03:12:42 DEBUG : two: Starting multipart upload 2025/01/29 03:12:42 DEBUG : two: Uploading segment 0/3 size 3 2025/01/29 03:12:43 DEBUG : three: Starting multipart upload 2025/01/29 03:12:43 DEBUG : three: Uploading segment 0/5 size 5 2025/01/29 03:12:43 DEBUG : four: Starting multipart upload 2025/01/29 03:12:43 DEBUG : four: Uploading segment 0/8 size 8 2025/01/29 03:12:44 DEBUG : five: Need to transfer - File not found at Destination 2025/01/29 03:12:44 DEBUG : four: Sizes differ (src 4 vs dst 8) 2025/01/29 03:12:44 DEBUG : one: Destination is newer than source, skipping 2025/01/29 03:12:44 DEBUG : three: Sizes identical 2025/01/29 03:12:44 DEBUG : five: Starting multipart upload 2025/01/29 03:12:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:44 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2025/01/29 03:12:44 DEBUG : four: Starting multipart upload 2025/01/29 03:12:44 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2025/01/29 03:12:44 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 (Local file system at /tmp/rclone2870153325) 2025/01/29 03:12:44 DEBUG : two: quickxor = 54b8c21300000000000000000300000000000000 (OneDrive root 'rclone-test-kociqez2hufe') 2025/01/29 03:12:44 DEBUG : two: quickxor differ 2025/01/29 03:12:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:44 DEBUG : two: Starting multipart upload 2025/01/29 03:12:44 DEBUG : five: Uploading segment 0/4 size 4 2025/01/29 03:12:44 DEBUG : four: Uploading segment 0/4 size 4 2025/01/29 03:12:44 DEBUG : two: Uploading segment 0/3 size 3 2025/01/29 03:12:44 DEBUG : four: quickxor = 6678431de4000000000000000400000000000000 OK 2025/01/29 03:12:44 INFO : four: Copied (replaced existing) 2025/01/29 03:12:44 DEBUG : five: quickxor = 6648831dca000000000000000400000000000000 OK 2025/01/29 03:12:44 INFO : five: Copied (new) 2025/01/29 03:12:44 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2025/01/29 03:12:44 INFO : two: Copied (replaced existing) 2025/01/29 03:12:44 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:44 DEBUG : one: Destination is newer than source, skipping 2025/01/29 03:12:44 DEBUG : five: quickxor = 6648831dca000000000000000400000000000000 OK 2025/01/29 03:12:44 DEBUG : five: Size and quickxor of src and dst objects identical 2025/01/29 03:12:44 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2025/01/29 03:12:44 DEBUG : three: quickxor = 7440831cca500600000000000500000000000000 (Local file system at /tmp/rclone2870153325) 2025/01/29 03:12:44 DEBUG : three: quickxor = 544082148a500400000000000500000000000000 (OneDrive root 'rclone-test-kociqez2hufe') 2025/01/29 03:12:44 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2025/01/29 03:12:44 DEBUG : three: quickxor differ 2025/01/29 03:12:44 DEBUG : two: Size and quickxor of src and dst objects identical 2025/01/29 03:12:44 DEBUG : four: quickxor = 6678431de4000000000000000400000000000000 OK 2025/01/29 03:12:44 DEBUG : four: Size and quickxor of src and dst objects identical 2025/01/29 03:12:44 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2025/01/29 03:12:44 DEBUG : three: Starting multipart upload 2025/01/29 03:12:44 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2025/01/29 03:12:44 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2025/01/29 03:12:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:44 DEBUG : three: Uploading segment 0/5 size 5 2025/01/29 03:12:45 DEBUG : three: quickxor = 7440831cca500600000000000500000000000000 OK 2025/01/29 03:12:45 INFO : three: Copied (replaced existing) 2025/01/29 03:12:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (4.22s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1466: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1466: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:1538: Can track renames: true 2025/01/29 03:12:46 INFO : OneDrive root 'rclone-test-kociqez2hufe': Making map for --track-renames 2025/01/29 03:12:46 INFO : OneDrive root 'rclone-test-kociqez2hufe': Finished making map for --track-renames 2025/01/29 03:12:46 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:46 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/01/29 03:12:46 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/01/29 03:12:46 DEBUG : yam: Starting multipart upload 2025/01/29 03:12:46 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for renames to finish 2025/01/29 03:12:46 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:46 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:46 DEBUG : yam: Uploading segment 0/11 size 11 2025/01/29 03:12:46 DEBUG : potato: Uploading segment 0/14 size 14 2025/01/29 03:12:47 DEBUG : yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2025/01/29 03:12:47 INFO : yam: Copied (new) 2025/01/29 03:12:47 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2025/01/29 03:12:47 INFO : potato: Copied (new) 2025/01/29 03:12:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:47 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:12:47 INFO : OneDrive root 'rclone-test-kociqez2hufe': Making map for --track-renames 2025/01/29 03:12:47 INFO : OneDrive root 'rclone-test-kociqez2hufe': Finished making map for --track-renames 2025/01/29 03:12:47 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:47 DEBUG : potato: Unchanged skipping 2025/01/29 03:12:47 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for renames to finish 2025/01/29 03:12:47 INFO : yam: Moved (server-side) to: yaml 2025/01/29 03:12:47 INFO : yaml: Renamed from "yam" 2025/01/29 03:12:47 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:47 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:47 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncWithTrackRenames (2.58s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:1610: Can track renames: true 2025/01/29 03:12:49 INFO : OneDrive root 'rclone-test-kociqez2hufe': Making map for --track-renames 2025/01/29 03:12:49 INFO : OneDrive root 'rclone-test-kociqez2hufe': Finished making map for --track-renames 2025/01/29 03:12:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:49 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/01/29 03:12:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for renames to finish 2025/01/29 03:12:49 DEBUG : yam: Need to transfer - No matching file found at Destination 2025/01/29 03:12:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:49 DEBUG : yam: Starting multipart upload 2025/01/29 03:12:49 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:49 DEBUG : yam: Uploading segment 0/11 size 11 2025/01/29 03:12:49 DEBUG : potato: Uploading segment 0/14 size 14 2025/01/29 03:12:49 DEBUG : yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2025/01/29 03:12:49 INFO : yam: Copied (new) 2025/01/29 03:12:49 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2025/01/29 03:12:49 INFO : potato: Copied (new) 2025/01/29 03:12:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:49 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:12:49 INFO : OneDrive root 'rclone-test-kociqez2hufe': Making map for --track-renames 2025/01/29 03:12:49 INFO : OneDrive root 'rclone-test-kociqez2hufe': Finished making map for --track-renames 2025/01/29 03:12:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:49 DEBUG : potato: Unchanged skipping 2025/01/29 03:12:49 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for renames to finish 2025/01/29 03:12:50 INFO : yam: Moved (server-side) to: yaml 2025/01/29 03:12:50 INFO : yaml: Renamed from "yam" 2025/01/29 03:12:50 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:50 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:50 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.58s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:1649: Can track renames: true 2025/01/29 03:12:51 DEBUG : Added delayed dir = "sub", newDst= 2025/01/29 03:12:51 INFO : OneDrive root 'rclone-test-kociqez2hufe': Making map for --track-renames 2025/01/29 03:12:51 INFO : OneDrive root 'rclone-test-kociqez2hufe': Finished making map for --track-renames 2025/01/29 03:12:51 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:51 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for renames to finish 2025/01/29 03:12:51 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/01/29 03:12:51 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/01/29 03:12:51 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:51 DEBUG : potato: Starting multipart upload 2025/01/29 03:12:52 DEBUG : sub/yam: Starting multipart upload 2025/01/29 03:12:52 DEBUG : potato: Uploading segment 0/14 size 14 2025/01/29 03:12:52 DEBUG : sub/yam: Uploading segment 0/11 size 11 2025/01/29 03:12:52 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2025/01/29 03:12:52 INFO : potato: Copied (new) 2025/01/29 03:12:52 DEBUG : sub/yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2025/01/29 03:12:52 INFO : sub/yam: Copied (new) 2025/01/29 03:12:52 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:52 INFO : sub: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:53 DEBUG : Added delayed dir = "sub", newDst=sub 2025/01/29 03:12:53 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:12:53 DEBUG : potato: Unchanged skipping 2025/01/29 03:12:53 INFO : OneDrive root 'rclone-test-kociqez2hufe': Making map for --track-renames 2025/01/29 03:12:53 INFO : OneDrive root 'rclone-test-kociqez2hufe': Finished making map for --track-renames 2025/01/29 03:12:53 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:53 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for renames to finish 2025/01/29 03:12:53 INFO : sub/yam: Moved (server-side) to: yam 2025/01/29 03:12:53 INFO : yam: Renamed from "sub/yam" 2025/01/29 03:12:53 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:53 DEBUG : Waiting for deletions to finish 2025/01/29 03:12:53 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (4.22s) === RUN TestServerSideMoveLocal run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:55 DEBUG : Creating backend with remote "/tmp/rclone2870153325/dir1" 2025/01/29 03:12:55 DEBUG : Creating backend with remote "/tmp/rclone2870153325/dir2" 2025/01/29 03:12:55 DEBUG : Local file system at /tmp/rclone2870153325/dir2: Using server-side directory move 2025/01/29 03:12:55 INFO : Local file system at /tmp/rclone2870153325/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/01/29 03:12:55 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/01/29 03:12:55 INFO : file1.txt: Moved (server-side) 2025/01/29 03:12:55 DEBUG : Local file system at /tmp/rclone2870153325/dir2: Waiting for checks to finish 2025/01/29 03:12:55 DEBUG : Local file system at /tmp/rclone2870153325/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.28s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:12:56 DEBUG : Added delayed dir = "nested", newDst= 2025/01/29 03:12:56 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:12:56 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:12:56 DEBUG : Added delayed dir = "nested/sub dir", newDst= 2025/01/29 03:12:56 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/01/29 03:12:56 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:12:56 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:12:56 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:12:56 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:12:57 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:12:57 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:12:57 INFO : sub dir/hello world: Deleted 2025/01/29 03:12:57 DEBUG : nested/sub dir/file: Starting multipart upload 2025/01/29 03:12:57 DEBUG : nested/sub dir/file: Uploading segment 0/6 size 6 2025/01/29 03:12:57 DEBUG : nested/sub dir/file: quickxor = 6e28c31ce8500632000000000600000000000000 OK 2025/01/29 03:12:57 INFO : nested/sub dir/file: Copied (new) 2025/01/29 03:12:57 INFO : nested/sub dir/file: Deleted 2025/01/29 03:12:57 INFO : nested/sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:12:58 INFO : nested: Set directory modification time (using DirSetModTime) 2025/01/29 03:12:58 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:12:58 INFO : sub dir: Removing directory 2025/01/29 03:12:58 INFO : nested/sub dir: Removing directory 2025/01/29 03:12:58 INFO : nested: Removing directory 2025/01/29 03:12:58 DEBUG : Local file system at /tmp/rclone2870153325: deleted 3 directories run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestMoveWithDeleteEmptySrcDirs (4.65s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:00 DEBUG : Added delayed dir = "nested", newDst= 2025/01/29 03:13:00 DEBUG : Added delayed dir = "sub dir", newDst= 2025/01/29 03:13:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/01/29 03:13:00 DEBUG : Added delayed dir = "nested/sub dir", newDst= 2025/01/29 03:13:00 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/01/29 03:13:00 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:13:00 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:13:01 DEBUG : sub dir/hello world: Starting multipart upload 2025/01/29 03:13:01 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2025/01/29 03:13:01 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2025/01/29 03:13:01 INFO : sub dir/hello world: Copied (new) 2025/01/29 03:13:01 INFO : sub dir/hello world: Deleted 2025/01/29 03:13:01 DEBUG : nested/sub dir/file: Starting multipart upload 2025/01/29 03:13:02 DEBUG : nested/sub dir/file: Uploading segment 0/6 size 6 2025/01/29 03:13:02 DEBUG : nested/sub dir/file: quickxor = 6e28c31ce8500632000000000600000000000000 OK 2025/01/29 03:13:02 INFO : nested/sub dir/file: Copied (new) 2025/01/29 03:13:02 INFO : nested/sub dir/file: Deleted 2025/01/29 03:13:02 INFO : nested/sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:13:02 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:13:02 INFO : nested: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (4.64s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:05 DEBUG : existing: Need to transfer - File not found at Destination 2025/01/29 03:13:05 DEBUG : existing-b: Need to transfer - File not found at Destination 2025/01/29 03:13:05 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:13:05 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:13:05 DEBUG : existing: Starting multipart upload 2025/01/29 03:13:05 DEBUG : existing-b: Starting multipart upload 2025/01/29 03:13:05 DEBUG : existing: Uploading segment 0/6 size 6 2025/01/29 03:13:05 DEBUG : existing-b: Uploading segment 0/6 size 6 2025/01/29 03:13:06 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2025/01/29 03:13:06 INFO : existing: Copied (new) 2025/01/29 03:13:06 INFO : existing: Deleted 2025/01/29 03:13:06 DEBUG : existing-b: quickxor = 7478431bc2408737000000000600000000000000 OK 2025/01/29 03:13:06 INFO : existing-b: Copied (new) 2025/01/29 03:13:06 INFO : existing-b: Deleted 2025/01/29 03:13:06 DEBUG : existing: Destination exists, skipping 2025/01/29 03:13:06 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2025/01/29 03:13:06 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:13:06 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:13:06 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestMoveWithIgnoreExisting (2.19s) === RUN TestServerSideMove run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:07 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-hafuwoq4yela" 2025/01/29 03:13:07 DEBUG : Config file has changed externally - reloading 2025/01/29 03:13:08 DEBUG : potato2: Starting multipart upload 2025/01/29 03:13:08 DEBUG : potato2: Uploading segment 0/60 size 60 2025/01/29 03:13:08 DEBUG : empty space: Starting multipart upload 2025/01/29 03:13:08 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:13:09 DEBUG : potato3: Starting multipart upload 2025/01/29 03:13:09 DEBUG : potato3: Uploading segment 0/68 size 68 sync_test.go:1705: Server side move (if possible) OneDrive root 'rclone-test-kociqez2hufe' -> OneDrive root 'rclone-test-hafuwoq4yela' 2025/01/29 03:13:10 DEBUG : empty space: Starting multipart upload 2025/01/29 03:13:10 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:13:10 DEBUG : potato3: Starting multipart upload 2025/01/29 03:13:10 DEBUG : potato3: Uploading segment 0/60 size 60 2025/01/29 03:13:11 DEBUG : OneDrive root 'rclone-test-hafuwoq4yela': Using server-side directory move 2025/01/29 03:13:11 INFO : OneDrive root 'rclone-test-hafuwoq4yela': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/01/29 03:13:11 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:13:11 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/01/29 03:13:11 DEBUG : empty space: Unchanged skipping 2025/01/29 03:13:11 DEBUG : OneDrive root 'rclone-test-hafuwoq4yela': Waiting for checks to finish 2025/01/29 03:13:11 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/01/29 03:13:11 INFO : empty space: Deleted 2025/01/29 03:13:11 DEBUG : OneDrive root 'rclone-test-hafuwoq4yela': Waiting for transfers to finish 2025/01/29 03:13:11 INFO : potato3: Deleted 2025/01/29 03:13:11 INFO : potato2: Moved (server-side) 2025/01/29 03:13:12 INFO : potato3: Moved (server-side) 2025/01/29 03:13:12 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-zuxizid0raqo" 2025/01/29 03:13:13 DEBUG : OneDrive root 'rclone-test-zuxizid0raqo': Using server-side directory move 2025/01/29 03:13:13 INFO : OneDrive root 'rclone-test-zuxizid0raqo': Server side directory move succeeded 2025/01/29 03:13:13 ERROR : : error listing: directory not found 2025/01/29 03:13:13 DEBUG : OneDrive root 'rclone-test-zuxizid0raqo': Purge remote 2025/01/29 03:13:14 DEBUG : OneDrive root 'rclone-test-hafuwoq4yela': Purge remote 2025/01/29 03:13:14 NOTICE: purge failed: directory not found --- PASS: TestServerSideMove (7.25s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:14 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-lamufup1lima" 2025/01/29 03:13:15 DEBUG : potato2: Starting multipart upload 2025/01/29 03:13:15 DEBUG : potato2: Uploading segment 0/60 size 60 2025/01/29 03:13:15 DEBUG : empty space: Starting multipart upload 2025/01/29 03:13:16 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:13:16 DEBUG : potato3: Starting multipart upload 2025/01/29 03:13:16 DEBUG : potato3: Uploading segment 0/68 size 68 sync_test.go:1705: Server side move (if possible) OneDrive root 'rclone-test-kociqez2hufe' -> OneDrive root 'rclone-test-lamufup1lima' 2025/01/29 03:13:17 DEBUG : empty space: Starting multipart upload 2025/01/29 03:13:17 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:13:17 DEBUG : potato3: Starting multipart upload 2025/01/29 03:13:18 DEBUG : potato3: Uploading segment 0/60 size 60 2025/01/29 03:13:18 DEBUG : empty space: Excluded (Size Filter) 2025/01/29 03:13:18 DEBUG : empty space: Excluded 2025/01/29 03:13:18 DEBUG : empty space: Excluded (Size Filter) 2025/01/29 03:13:18 DEBUG : empty space: Excluded 2025/01/29 03:13:18 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:13:18 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/01/29 03:13:18 DEBUG : OneDrive root 'rclone-test-lamufup1lima': Waiting for checks to finish 2025/01/29 03:13:18 DEBUG : OneDrive root 'rclone-test-lamufup1lima': Waiting for transfers to finish 2025/01/29 03:13:19 INFO : potato3: Deleted 2025/01/29 03:13:19 INFO : potato2: Moved (server-side) 2025/01/29 03:13:19 INFO : potato3: Moved (server-side) 2025/01/29 03:13:20 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-zemapas8xibi" 2025/01/29 03:13:20 DEBUG : empty space: Excluded (Size Filter) 2025/01/29 03:13:20 DEBUG : empty space: Excluded 2025/01/29 03:13:20 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:13:20 DEBUG : potato3: Need to transfer - File not found at Destination 2025/01/29 03:13:20 DEBUG : OneDrive root 'rclone-test-zemapas8xibi': Waiting for checks to finish 2025/01/29 03:13:20 DEBUG : OneDrive root 'rclone-test-zemapas8xibi': Waiting for transfers to finish 2025/01/29 03:13:21 INFO : potato2: Moved (server-side) 2025/01/29 03:13:22 INFO : potato3: Moved (server-side) 2025/01/29 03:13:22 DEBUG : OneDrive root 'rclone-test-zemapas8xibi': Purge remote 2025/01/29 03:13:22 DEBUG : OneDrive root 'rclone-test-lamufup1lima': Purge remote --- PASS: TestServerSideMoveWithFilter (8.57s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:23 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-fucunid1fama" 2025/01/29 03:13:24 DEBUG : potato2: Starting multipart upload 2025/01/29 03:13:24 DEBUG : potato2: Uploading segment 0/60 size 60 2025/01/29 03:13:24 DEBUG : empty space: Starting multipart upload 2025/01/29 03:13:24 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:13:24 DEBUG : potato3: Starting multipart upload 2025/01/29 03:13:25 DEBUG : potato3: Uploading segment 0/68 size 68 2025/01/29 03:13:25 INFO : tomatoDir: Making directory sync_test.go:1705: Server side move (if possible) OneDrive root 'rclone-test-kociqez2hufe' -> OneDrive root 'rclone-test-fucunid1fama' 2025/01/29 03:13:26 DEBUG : empty space: Starting multipart upload 2025/01/29 03:13:26 DEBUG : empty space: Uploading segment 0/1 size 1 2025/01/29 03:13:27 DEBUG : potato3: Starting multipart upload 2025/01/29 03:13:27 DEBUG : potato3: Uploading segment 0/60 size 60 2025/01/29 03:13:27 DEBUG : OneDrive root 'rclone-test-fucunid1fama': Using server-side directory move 2025/01/29 03:13:27 INFO : OneDrive root 'rclone-test-fucunid1fama': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2025/01/29 03:13:27 DEBUG : potato2: Need to transfer - File not found at Destination 2025/01/29 03:13:27 DEBUG : Added delayed dir = "tomatoDir", newDst= 2025/01/29 03:13:27 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2025/01/29 03:13:27 DEBUG : empty space: Unchanged skipping 2025/01/29 03:13:27 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2025/01/29 03:13:28 DEBUG : OneDrive root 'rclone-test-fucunid1fama': Waiting for checks to finish 2025/01/29 03:13:28 INFO : empty space: Deleted 2025/01/29 03:13:28 DEBUG : OneDrive root 'rclone-test-fucunid1fama': Waiting for transfers to finish 2025/01/29 03:13:28 INFO : potato3: Deleted 2025/01/29 03:13:28 INFO : potato2: Moved (server-side) 2025/01/29 03:13:28 INFO : potato3: Moved (server-side) 2025/01/29 03:13:28 INFO : tomatoDir: Removing directory 2025/01/29 03:13:29 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': deleted 1 directories 2025/01/29 03:13:29 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-huvaxid1cemu" 2025/01/29 03:13:30 INFO : tomatoDir: Making directory 2025/01/29 03:13:30 DEBUG : OneDrive root 'rclone-test-huvaxid1cemu': Using server-side directory move 2025/01/29 03:13:31 INFO : OneDrive root 'rclone-test-huvaxid1cemu': Server side directory move succeeded 2025/01/29 03:13:31 ERROR : : error listing: directory not found 2025/01/29 03:13:31 ERROR : : error listing: directory not found 2025/01/29 03:13:31 DEBUG : OneDrive root 'rclone-test-huvaxid1cemu': Purge remote 2025/01/29 03:13:32 DEBUG : OneDrive root 'rclone-test-fucunid1fama': Purge remote 2025/01/29 03:13:32 NOTICE: purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (9.23s) === RUN TestServerSideMoveOverlap run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:1910: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.34s) === RUN TestSyncOverlap run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:33 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/rclone-sync-test" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncOverlap (2.27s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:35 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/rclone-sync-test" 2025/01/29 03:13:36 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/rclone-sync-test-include/layer2" 2025/01/29 03:13:37 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/rclone-sync-test-ignore-file" 2025/01/29 03:13:39 DEBUG : rclone-sync-test-ignore-file/.ignore: Starting multipart upload 2025/01/29 03:13:39 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading segment 0/1 size 1 2025/01/29 03:13:40 DEBUG : rclone-sync-test: Excluded 2025/01/29 03:13:40 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/29 03:13:40 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2025/01/29 03:13:41 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/29 03:13:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/rclone-sync-test': Waiting for checks to finish 2025/01/29 03:13:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/rclone-sync-test': Waiting for transfers to finish 2025/01/29 03:13:41 DEBUG : Waiting for deletions to finish 2025/01/29 03:13:41 INFO : There was nothing to transfer 2025/01/29 03:13:41 DEBUG : rclone-sync-test: Excluded 2025/01/29 03:13:41 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/29 03:13:42 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/29 03:13:42 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:13:42 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:13:42 DEBUG : Waiting for deletions to finish 2025/01/29 03:13:42 INFO : rclone-sync-test-include: Removing directory 2025/01/29 03:13:42 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/01/29 03:13:42 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': failed to delete 1 directories 2025/01/29 03:13:42 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:42 DEBUG : rclone-sync-test: Excluded 2025/01/29 03:13:43 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/29 03:13:43 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2025/01/29 03:13:43 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/29 03:13:43 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/01/29 03:13:43 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/01/29 03:13:43 DEBUG : Waiting for deletions to finish 2025/01/29 03:13:43 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:44 DEBUG : rclone-sync-test: Excluded 2025/01/29 03:13:44 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/29 03:13:44 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/29 03:13:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:13:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:13:44 DEBUG : Waiting for deletions to finish 2025/01/29 03:13:44 INFO : rclone-sync-test-include: Removing directory 2025/01/29 03:13:44 DEBUG : rclone-sync-test-include: Failed to Rmdir: directory not empty 2025/01/29 03:13:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': failed to delete 1 directories 2025/01/29 03:13:44 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:45 DEBUG : : Excluded 2025/01/29 03:13:45 DEBUG : rclone-sync-test: Excluded 2025/01/29 03:13:45 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/01/29 03:13:45 DEBUG : Added delayed dir = "rclone-sync-test-include", newDst= 2025/01/29 03:13:45 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/01/29 03:13:45 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/01/29 03:13:45 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/01/29 03:13:45 DEBUG : Waiting for deletions to finish 2025/01/29 03:13:45 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncOverlapWithFilter (18.56s) === RUN TestSyncCompareDest run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:13:53 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dst" 2025/01/29 03:13:54 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/CompareDest" 2025/01/29 03:13:56 DEBUG : one: Need to transfer - File not found at Destination 2025/01/29 03:13:56 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:13:56 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:13:56 DEBUG : one: Starting multipart upload 2025/01/29 03:13:56 DEBUG : one: Uploading segment 0/3 size 3 2025/01/29 03:13:57 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK 2025/01/29 03:13:57 INFO : one: Copied (new) 2025/01/29 03:13:57 DEBUG : Waiting for deletions to finish 2025/01/29 03:13:57 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:13:57 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:13:58 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:13:58 DEBUG : one: Starting multipart upload 2025/01/29 03:13:58 DEBUG : one: Uploading segment 0/5 size 5 2025/01/29 03:13:58 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2025/01/29 03:13:58 INFO : one: Copied (replaced existing) 2025/01/29 03:13:58 DEBUG : Waiting for deletions to finish 2025/01/29 03:13:59 DEBUG : dst/one: Starting multipart upload 2025/01/29 03:13:59 DEBUG : dst/one: Uploading segment 0/3 size 3 2025/01/29 03:14:00 DEBUG : CompareDest/one: Starting multipart upload 2025/01/29 03:14:00 DEBUG : CompareDest/one: Uploading segment 0/5 size 5 2025/01/29 03:14:01 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:01 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:01 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:01 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/29 03:14:01 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:01 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:01 INFO : There was nothing to transfer 2025/01/29 03:14:02 DEBUG : CompareDest/two: Starting multipart upload 2025/01/29 03:14:02 DEBUG : CompareDest/two: Uploading segment 0/3 size 3 2025/01/29 03:14:03 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:03 DEBUG : two: Destination found in --compare-dest, skipping 2025/01/29 03:14:03 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:03 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:03 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:03 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/29 03:14:03 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:03 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:03 INFO : There was nothing to transfer 2025/01/29 03:14:04 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:04 DEBUG : two: Destination found in --compare-dest, skipping 2025/01/29 03:14:04 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:04 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:04 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:04 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/29 03:14:04 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:04 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:04 INFO : There was nothing to transfer 2025/01/29 03:14:05 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 2025/01/29 03:14:05 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2025/01/29 03:14:05 DEBUG : two: Destination found in --compare-dest, skipping 2025/01/29 03:14:05 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:05 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:05 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:05 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/29 03:14:05 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:05 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:05 INFO : There was nothing to transfer 2025/01/29 03:14:06 DEBUG : two: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:06 DEBUG : two: Need to transfer - File not found at Destination 2025/01/29 03:14:06 DEBUG : two: Starting multipart upload 2025/01/29 03:14:06 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:06 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:06 DEBUG : two: Uploading segment 0/5 size 5 2025/01/29 03:14:06 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:06 DEBUG : one: Destination found in --compare-dest, skipping 2025/01/29 03:14:06 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:06 DEBUG : two: quickxor = 74b8c31be8300300000000000500000000000000 OK 2025/01/29 03:14:06 INFO : two: Copied (new) 2025/01/29 03:14:06 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (15.52s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:14:09 DEBUG : pre-dest1/1: Starting multipart upload 2025/01/29 03:14:09 DEBUG : pre-dest1/1: Uploading segment 0/1 size 1 2025/01/29 03:14:10 DEBUG : pre-dest2/2: Starting multipart upload 2025/01/29 03:14:10 DEBUG : pre-dest2/2: Uploading segment 0/1 size 1 2025/01/29 03:14:11 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dest" 2025/01/29 03:14:12 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/pre-dest1" 2025/01/29 03:14:12 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/pre-dest2" 2025/01/29 03:14:13 DEBUG : 1: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:14:13 DEBUG : 1: Destination found in --compare-dest, skipping 2025/01/29 03:14:13 DEBUG : 2: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:14:13 DEBUG : 2: Destination found in --compare-dest, skipping 2025/01/29 03:14:13 DEBUG : 3: Need to transfer - File not found at Destination 2025/01/29 03:14:13 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dest': Waiting for checks to finish 2025/01/29 03:14:13 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dest': Waiting for transfers to finish 2025/01/29 03:14:14 DEBUG : 3: Starting multipart upload 2025/01/29 03:14:14 DEBUG : 3: Uploading segment 0/1 size 1 2025/01/29 03:14:14 DEBUG : 3: quickxor = 3300000000000000000000000100000000000000 OK 2025/01/29 03:14:14 INFO : 3: Copied (new) 2025/01/29 03:14:14 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (8.00s) === RUN TestSyncCopyDest run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:14:17 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dst" 2025/01/29 03:14:18 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/CopyDest" 2025/01/29 03:14:19 DEBUG : one: Need to transfer - File not found at Destination 2025/01/29 03:14:19 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:19 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:20 DEBUG : one: Starting multipart upload 2025/01/29 03:14:20 DEBUG : one: Uploading segment 0/3 size 3 2025/01/29 03:14:20 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK 2025/01/29 03:14:20 INFO : one: Copied (new) 2025/01/29 03:14:20 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:21 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:21 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:21 DEBUG : one: Starting multipart upload 2025/01/29 03:14:22 DEBUG : one: Uploading segment 0/5 size 5 2025/01/29 03:14:22 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2025/01/29 03:14:22 INFO : one: Copied (replaced existing) 2025/01/29 03:14:22 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:22 DEBUG : dst/one: Starting multipart upload 2025/01/29 03:14:22 DEBUG : dst/one: Uploading segment 0/3 size 3 2025/01/29 03:14:23 DEBUG : CopyDest/one: Starting multipart upload 2025/01/29 03:14:23 DEBUG : CopyDest/one: Uploading segment 0/5 size 5 2025/01/29 03:14:24 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/BackupDir" 2025/01/29 03:14:25 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:25 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:25 DEBUG : one: Sizes differ (src 5 vs dst 3) 2025/01/29 03:14:26 INFO : one: Moved (server-side) 2025/01/29 03:14:27 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2025/01/29 03:14:27 INFO : one: Copied (server-side copy) 2025/01/29 03:14:27 DEBUG : one: Destination found in --copy-dest, using server-side copy 2025/01/29 03:14:27 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:27 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:27 DEBUG : CopyDest/two: Starting multipart upload 2025/01/29 03:14:28 DEBUG : CopyDest/two: Uploading segment 0/3 size 3 2025/01/29 03:14:28 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:29 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2025/01/29 03:14:29 INFO : two: Copied (server-side copy) 2025/01/29 03:14:29 DEBUG : two: Destination found in --copy-dest, using server-side copy 2025/01/29 03:14:29 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:29 DEBUG : one: Unchanged skipping 2025/01/29 03:14:29 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:29 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:29 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:30 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:30 DEBUG : one: Unchanged skipping 2025/01/29 03:14:30 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:30 DEBUG : two: Unchanged skipping 2025/01/29 03:14:30 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:30 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:30 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:30 INFO : There was nothing to transfer 2025/01/29 03:14:30 DEBUG : CopyDest/three: Starting multipart upload 2025/01/29 03:14:30 DEBUG : CopyDest/three: Uploading segment 0/5 size 5 2025/01/29 03:14:31 DEBUG : three: Sizes differ (src 7 vs dst 5) 2025/01/29 03:14:31 DEBUG : three: Destination not found in --copy-dest 2025/01/29 03:14:31 DEBUG : three: Need to transfer - File not found at Destination 2025/01/29 03:14:31 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:31 DEBUG : one: Unchanged skipping 2025/01/29 03:14:31 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1s) 2025/01/29 03:14:31 DEBUG : two: Unchanged skipping 2025/01/29 03:14:31 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:31 DEBUG : three: Starting multipart upload 2025/01/29 03:14:31 DEBUG : three: Uploading segment 0/7 size 7 2025/01/29 03:14:32 DEBUG : three: quickxor = 7440831cca50063acc0000000700000000000000 OK 2025/01/29 03:14:32 INFO : three: Copied (new) 2025/01/29 03:14:32 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (18.87s) === RUN TestSyncBackupDir run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:14:36 DEBUG : dst/one: Starting multipart upload 2025/01/29 03:14:36 DEBUG : dst/one: Uploading segment 0/3 size 3 2025/01/29 03:14:37 DEBUG : dst/two: Starting multipart upload 2025/01/29 03:14:37 DEBUG : dst/two: Uploading segment 0/3 size 3 2025/01/29 03:14:37 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:14:37 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2025/01/29 03:14:38 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dst" 2025/01/29 03:14:39 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/backup" 2025/01/29 03:14:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/29 03:14:39 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:14:39 DEBUG : two: Unchanged skipping 2025/01/29 03:14:39 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:41 INFO : one: Moved (server-side) 2025/01/29 03:14:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:41 DEBUG : one: Starting multipart upload 2025/01/29 03:14:41 DEBUG : one: Uploading segment 0/4 size 4 2025/01/29 03:14:41 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2025/01/29 03:14:41 INFO : one: Copied (new) 2025/01/29 03:14:41 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:42 INFO : three.txt: Moved (server-side) 2025/01/29 03:14:42 INFO : three.txt: Moved into backup dir 2025/01/29 03:14:42 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:14:42 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2025/01/29 03:14:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/29 03:14:43 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:14:43 DEBUG : two: Unchanged skipping 2025/01/29 03:14:43 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:43 INFO : one: Deleted 2025/01/29 03:14:44 INFO : one: Moved (server-side) 2025/01/29 03:14:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:44 DEBUG : one: Starting multipart upload 2025/01/29 03:14:44 DEBUG : one: Uploading segment 0/5 size 5 2025/01/29 03:14:44 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2025/01/29 03:14:44 INFO : one: Copied (new) 2025/01/29 03:14:44 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:44 INFO : three.txt: Deleted 2025/01/29 03:14:45 INFO : three.txt: Moved (server-side) 2025/01/29 03:14:45 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (11.44s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:14:48 DEBUG : dst/one: Starting multipart upload 2025/01/29 03:14:48 DEBUG : dst/one: Uploading segment 0/3 size 3 2025/01/29 03:14:48 DEBUG : dst/two: Starting multipart upload 2025/01/29 03:14:48 DEBUG : dst/two: Uploading segment 0/3 size 3 2025/01/29 03:14:48 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:14:49 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2025/01/29 03:14:49 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dst" 2025/01/29 03:14:50 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/backup" 2025/01/29 03:14:51 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/29 03:14:51 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:14:51 DEBUG : two: Unchanged skipping 2025/01/29 03:14:51 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:52 INFO : one: Moved (server-side) to: one.bak 2025/01/29 03:14:52 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:52 DEBUG : one: Starting multipart upload 2025/01/29 03:14:52 DEBUG : one: Uploading segment 0/4 size 4 2025/01/29 03:14:53 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2025/01/29 03:14:53 INFO : one: Copied (new) 2025/01/29 03:14:53 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:53 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/29 03:14:53 INFO : three.txt: Moved into backup dir 2025/01/29 03:14:54 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:14:54 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2025/01/29 03:14:54 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/29 03:14:54 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:14:54 DEBUG : two: Unchanged skipping 2025/01/29 03:14:54 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:14:55 INFO : one.bak: Deleted 2025/01/29 03:14:55 INFO : one: Moved (server-side) to: one.bak 2025/01/29 03:14:55 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:14:55 DEBUG : one: Starting multipart upload 2025/01/29 03:14:55 DEBUG : one: Uploading segment 0/5 size 5 2025/01/29 03:14:56 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2025/01/29 03:14:56 INFO : one: Copied (new) 2025/01/29 03:14:56 DEBUG : Waiting for deletions to finish 2025/01/29 03:14:56 INFO : three.txt.bak: Deleted 2025/01/29 03:14:56 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/29 03:14:56 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (11.69s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:14:59 DEBUG : dst/one: Starting multipart upload 2025/01/29 03:14:59 DEBUG : dst/one: Uploading segment 0/3 size 3 2025/01/29 03:15:00 DEBUG : dst/two: Starting multipart upload 2025/01/29 03:15:00 DEBUG : dst/two: Uploading segment 0/3 size 3 2025/01/29 03:15:00 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:15:00 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2025/01/29 03:15:01 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dst" 2025/01/29 03:15:01 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/backup" 2025/01/29 03:15:02 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/29 03:15:02 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:15:02 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:02 DEBUG : two: Unchanged skipping 2025/01/29 03:15:04 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/29 03:15:04 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:15:04 DEBUG : one: Starting multipart upload 2025/01/29 03:15:04 DEBUG : one: Uploading segment 0/4 size 4 2025/01/29 03:15:04 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2025/01/29 03:15:04 INFO : one: Copied (new) 2025/01/29 03:15:04 DEBUG : Waiting for deletions to finish 2025/01/29 03:15:05 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/29 03:15:05 INFO : three.txt: Moved into backup dir 2025/01/29 03:15:05 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:15:05 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2025/01/29 03:15:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/29 03:15:06 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:15:06 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:06 DEBUG : two: Unchanged skipping 2025/01/29 03:15:06 INFO : one-2019-01-01: Deleted 2025/01/29 03:15:07 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/29 03:15:07 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:15:07 DEBUG : one: Starting multipart upload 2025/01/29 03:15:07 DEBUG : one: Uploading segment 0/5 size 5 2025/01/29 03:15:07 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2025/01/29 03:15:07 INFO : one: Copied (new) 2025/01/29 03:15:07 DEBUG : Waiting for deletions to finish 2025/01/29 03:15:08 INFO : three-2019-01-01.txt: Deleted 2025/01/29 03:15:08 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/29 03:15:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (11.49s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:15:11 DEBUG : dst/one: Starting multipart upload 2025/01/29 03:15:11 DEBUG : dst/one: Uploading segment 0/3 size 3 2025/01/29 03:15:11 DEBUG : dst/two: Starting multipart upload 2025/01/29 03:15:11 DEBUG : dst/two: Uploading segment 0/3 size 3 2025/01/29 03:15:12 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:15:12 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2025/01/29 03:15:12 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dst" 2025/01/29 03:15:13 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/29 03:15:13 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:13 DEBUG : two: Unchanged skipping 2025/01/29 03:15:13 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:15:14 INFO : one: Moved (server-side) to: one.bak 2025/01/29 03:15:14 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:15:14 DEBUG : one: Starting multipart upload 2025/01/29 03:15:14 DEBUG : one: Uploading segment 0/4 size 4 2025/01/29 03:15:14 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2025/01/29 03:15:14 INFO : one: Copied (new) 2025/01/29 03:15:14 DEBUG : Waiting for deletions to finish 2025/01/29 03:15:15 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/29 03:15:15 INFO : three.txt: Moved into backup dir 2025/01/29 03:15:15 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:15:15 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2025/01/29 03:15:16 DEBUG : one.bak: Excluded (Path Filter) 2025/01/29 03:15:16 DEBUG : one.bak: Excluded 2025/01/29 03:15:16 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/01/29 03:15:16 DEBUG : three.txt.bak: Excluded 2025/01/29 03:15:16 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/29 03:15:16 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:16 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for checks to finish 2025/01/29 03:15:16 DEBUG : two: Unchanged skipping 2025/01/29 03:15:17 INFO : one.bak: Deleted 2025/01/29 03:15:17 INFO : one: Moved (server-side) to: one.bak 2025/01/29 03:15:17 DEBUG : OneDrive root 'rclone-test-kociqez2hufe/dst': Waiting for transfers to finish 2025/01/29 03:15:17 DEBUG : one: Starting multipart upload 2025/01/29 03:15:17 DEBUG : one: Uploading segment 0/5 size 5 2025/01/29 03:15:17 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2025/01/29 03:15:17 INFO : one: Copied (new) 2025/01/29 03:15:17 DEBUG : Waiting for deletions to finish 2025/01/29 03:15:18 INFO : three.txt.bak: Deleted 2025/01/29 03:15:18 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/29 03:15:18 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (9.88s) === RUN TestSyncSuffix run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:15:21 DEBUG : dst/one: Starting multipart upload 2025/01/29 03:15:21 DEBUG : dst/one: Uploading segment 0/3 size 3 2025/01/29 03:15:21 DEBUG : dst/two: Starting multipart upload 2025/01/29 03:15:21 DEBUG : dst/two: Uploading segment 0/3 size 3 2025/01/29 03:15:21 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:15:22 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2025/01/29 03:15:22 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dst" 2025/01/29 03:15:23 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/29 03:15:23 INFO : one: Moved (server-side) to: one.bak 2025/01/29 03:15:23 DEBUG : one: Starting multipart upload 2025/01/29 03:15:24 DEBUG : one: Uploading segment 0/4 size 4 2025/01/29 03:15:24 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2025/01/29 03:15:24 INFO : one: Copied (new) 2025/01/29 03:15:24 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:24 DEBUG : two: Unchanged skipping 2025/01/29 03:15:24 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/01/29 03:15:25 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/29 03:15:25 DEBUG : three.txt: Starting multipart upload 2025/01/29 03:15:25 DEBUG : three.txt: Uploading segment 0/6 size 6 2025/01/29 03:15:25 DEBUG : three.txt: quickxor = 7440831cca508620000000000600000000000000 OK 2025/01/29 03:15:25 INFO : three.txt: Copied (new) 2025/01/29 03:15:26 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/29 03:15:27 INFO : one.bak: Deleted 2025/01/29 03:15:27 INFO : one: Moved (server-side) to: one.bak 2025/01/29 03:15:27 DEBUG : one: Starting multipart upload 2025/01/29 03:15:27 DEBUG : one: Uploading segment 0/5 size 5 2025/01/29 03:15:27 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2025/01/29 03:15:27 INFO : one: Copied (new) 2025/01/29 03:15:27 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:27 DEBUG : two: Unchanged skipping 2025/01/29 03:15:28 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/01/29 03:15:28 INFO : three.txt.bak: Deleted 2025/01/29 03:15:28 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/01/29 03:15:28 DEBUG : three.txt: Starting multipart upload 2025/01/29 03:15:29 DEBUG : three.txt: Uploading segment 0/19 size 19 2025/01/29 03:15:29 DEBUG : three.txt: quickxor = 154aeacc8949062110210d66234319e4500637d0 OK 2025/01/29 03:15:29 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (10.79s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:15:31 DEBUG : dst/one: Starting multipart upload 2025/01/29 03:15:32 DEBUG : dst/one: Uploading segment 0/3 size 3 2025/01/29 03:15:32 DEBUG : dst/two: Starting multipart upload 2025/01/29 03:15:32 DEBUG : dst/two: Uploading segment 0/3 size 3 2025/01/29 03:15:32 DEBUG : dst/three.txt: Starting multipart upload 2025/01/29 03:15:32 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2025/01/29 03:15:33 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-kociqez2hufe/dst" 2025/01/29 03:15:33 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/01/29 03:15:34 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/29 03:15:34 DEBUG : one: Starting multipart upload 2025/01/29 03:15:34 DEBUG : one: Uploading segment 0/4 size 4 2025/01/29 03:15:34 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2025/01/29 03:15:34 INFO : one: Copied (new) 2025/01/29 03:15:34 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:34 DEBUG : two: Unchanged skipping 2025/01/29 03:15:34 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/01/29 03:15:35 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/29 03:15:35 DEBUG : three.txt: Starting multipart upload 2025/01/29 03:15:35 DEBUG : three.txt: Uploading segment 0/6 size 6 2025/01/29 03:15:35 DEBUG : three.txt: quickxor = 7440831cca508620000000000600000000000000 OK 2025/01/29 03:15:35 INFO : three.txt: Copied (new) 2025/01/29 03:15:36 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/01/29 03:15:36 INFO : one-2019-01-01: Deleted 2025/01/29 03:15:36 INFO : one: Moved (server-side) to: one-2019-01-01 2025/01/29 03:15:36 DEBUG : one: Starting multipart upload 2025/01/29 03:15:37 DEBUG : one: Uploading segment 0/5 size 5 2025/01/29 03:15:37 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2025/01/29 03:15:37 INFO : one: Copied (new) 2025/01/29 03:15:37 DEBUG : two: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:37 DEBUG : two: Unchanged skipping 2025/01/29 03:15:37 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/01/29 03:15:37 INFO : three-2019-01-01.txt: Deleted 2025/01/29 03:15:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/01/29 03:15:37 DEBUG : three.txt: Starting multipart upload 2025/01/29 03:15:37 DEBUG : three.txt: Uploading segment 0/19 size 19 2025/01/29 03:15:38 DEBUG : three.txt: quickxor = 154aeacc8949062110210d66234319e4500637d0 OK 2025/01/29 03:15:38 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (9.46s) === RUN TestSyncUTFNorm run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:15:41 DEBUG : Testêé: Starting multipart upload 2025/01/29 03:15:41 DEBUG : Testêé: Uploading segment 0/18 size 18 2025/01/29 03:15:41 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2025/01/29 03:15:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:15:41 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:15:41 DEBUG : Testêé: Starting multipart upload 2025/01/29 03:15:41 DEBUG : Testêé: Uploading segment 0/14 size 14 2025/01/29 03:15:42 DEBUG : Testêé: Cancelling multipart upload: HTTP error 409 (409 Conflict) returned body: "{\"error\":{\"code\":\"resourceModified\",\"message\":\"ETag does not match current item's value\"}}" 2025/01/29 03:15:42 DEBUG : info from Update error: null 2025/01/29 03:15:42 ERROR : Testêé: Failed to copy: HTTP error 409 (409 Conflict) returned body: "{\"error\":{\"code\":\"resourceModified\",\"message\":\"ETag does not match current item's value\"}}" 2025/01/29 03:15:42 ERROR : OneDrive root 'rclone-test-kociqez2hufe': not deleting files as there were IO errors 2025/01/29 03:15:42 ERROR : OneDrive root 'rclone-test-kociqez2hufe': not deleting directories as there were IO errors sync_test.go:2551: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2551 Error: Received unexpected error: HTTP error 409 (409 Conflict) returned body: "{\"error\":{\"code\":\"resourceModified\",\"message\":\"ETag does not match current item's value\"}}" Test: TestSyncUTFNorm --- FAIL: TestSyncUTFNorm (1.96s) === RUN TestSyncImmutable run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:15:43 DEBUG : existing: Need to transfer - File not found at Destination 2025/01/29 03:15:43 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:15:43 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:15:43 DEBUG : existing: Starting multipart upload 2025/01/29 03:15:43 DEBUG : existing: Uploading segment 0/6 size 6 2025/01/29 03:15:43 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2025/01/29 03:15:43 INFO : existing: Copied (new) 2025/01/29 03:15:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:15:44 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2025/01/29 03:15:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:15:44 ERROR : existing: Source and destination exist but do not match: immutable file modified 2025/01/29 03:15:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:15:44 ERROR : OneDrive root 'rclone-test-kociqez2hufe': not deleting files as there were IO errors 2025/01/29 03:15:44 ERROR : OneDrive root 'rclone-test-kociqez2hufe': not deleting directories as there were IO errors run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncImmutable (2.64s) === RUN TestSyncIgnoreCase run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:2606: Skipping test as local or remote are case-insensitive --- SKIP: TestSyncIgnoreCase (0.37s) === RUN TestFixCase run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:15:45 DEBUG : EXISTING: Starting multipart upload 2025/01/29 03:15:46 DEBUG : EXISTING: Uploading segment 0/6 size 6 2025/01/29 03:15:46 DEBUG : EXISTINGBUTDIFFERENT: Starting multipart upload 2025/01/29 03:15:46 DEBUG : EXISTINGBUTDIFFERENT: Uploading segment 0/8 size 8 2025/01/29 03:15:47 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Starting multipart upload 2025/01/29 03:15:48 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading segment 0/8 size 8 2025/01/29 03:15:48 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Starting multipart upload 2025/01/29 03:15:49 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading segment 0/8 size 8 2025/01/29 03:15:50 DEBUG : Added delayed dir = "SUBDIRA", newDst=SUBDIRA 2025/01/29 03:15:50 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:15:50 DEBUG : existing: Unchanged skipping 2025/01/29 03:15:50 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2025/01/29 03:15:50 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-qiyisoy9 2025/01/29 03:15:50 DEBUG : EXISTING: moving to existing-rclone-move-dofobag8 2025/01/29 03:15:51 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-qiyisoy9 2025/01/29 03:15:51 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2025/01/29 03:15:51 INFO : SUBDIRA: Fixed case by renaming to: subdira 2025/01/29 03:15:51 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-dofobag8 2025/01/29 03:15:51 DEBUG : EXISTING: moving to existing 2025/01/29 03:15:51 INFO : existingbutdifferent-rclone-move-qiyisoy9: Moved (server-side) to: existingbutdifferent 2025/01/29 03:15:51 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2025/01/29 03:15:51 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2025/01/29 03:15:51 DEBUG : existingbutdifferent: Starting multipart upload 2025/01/29 03:15:51 DEBUG : Added delayed dir = "SUBDIRA/subdirb", newDst=SUBDIRA/subdirb 2025/01/29 03:15:51 DEBUG : existingbutdifferent: Uploading segment 0/5 size 5 2025/01/29 03:15:52 DEBUG : existingbutdifferent: quickxor = 6478831bea400700000000000500000000000000 OK 2025/01/29 03:15:52 INFO : existingbutdifferent: Copied (replaced existing) 2025/01/29 03:15:52 INFO : existing-rclone-move-dofobag8: Moved (server-side) to: existing 2025/01/29 03:15:52 INFO : EXISTING: Moved (server-side) to: existing 2025/01/29 03:15:52 INFO : EXISTING: Fixed case by renaming to: existing 2025/01/29 03:15:53 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2025/01/29 03:15:53 DEBUG : Added delayed dir = "SUBDIRA/subdirb/SUBDIRC", newDst=SUBDIRA/subdirb/SUBDIRC 2025/01/29 03:15:54 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2025/01/29 03:15:55 DEBUG : Added delayed dir = "SUBDIRA/subdirb/SUBDIRC/subdird", newDst=SUBDIRA/subdirb/SUBDIRC/subdird 2025/01/29 03:15:55 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8) 2025/01/29 03:15:55 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-siyukur8 2025/01/29 03:15:56 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-siyukur8 2025/01/29 03:15:56 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2025/01/29 03:15:56 INFO : subdira/subdirb/subdirc/hello-rclone-move-siyukur8: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/01/29 03:15:56 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/01/29 03:15:56 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2025/01/29 03:15:56 DEBUG : subdira/subdirb/subdirc/hello: Starting multipart upload 2025/01/29 03:15:56 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird 2025/01/29 03:15:56 DEBUG : subdira/subdirb/subdirc/hello: Uploading segment 0/5 size 5 2025/01/29 03:15:56 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:15:56 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8) 2025/01/29 03:15:57 DEBUG : subdira/subdirb/subdirc/hello: quickxor = 6478831bea400700000000000500000000000000 OK 2025/01/29 03:15:57 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2025/01/29 03:15:57 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-cubixiw7 2025/01/29 03:15:57 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-cubixiw7 2025/01/29 03:15:57 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/01/29 03:15:58 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-cubixiw7: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/01/29 03:15:58 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/01/29 03:15:58 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/01/29 03:15:58 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:15:58 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Starting multipart upload 2025/01/29 03:15:58 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading segment 0/5 size 5 2025/01/29 03:15:58 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: quickxor = 6478831bea400700000000000500000000000000 OK 2025/01/29 03:15:58 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing) 2025/01/29 03:15:58 DEBUG : Waiting for deletions to finish --- PASS: TestFixCase (17.25s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:2674: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:2674: This test only runs on local --- PASS: TestMaxTransfer (0.96s) --- SKIP: TestMaxTransfer/Hard (0.32s) --- SKIP: TestMaxTransfer/Soft (0.32s) --- SKIP: TestMaxTransfer/Cautious (0.32s) === RUN TestSyncConcurrentDelete run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:16:04 DEBUG : both0: Starting multipart upload 2025/01/29 03:16:04 DEBUG : both0: Uploading segment 0/6 size 6 2025/01/29 03:16:04 DEBUG : only0: Starting multipart upload 2025/01/29 03:16:04 DEBUG : only0: Uploading segment 0/6 size 6 2025/01/29 03:16:05 DEBUG : both1: Starting multipart upload 2025/01/29 03:16:05 DEBUG : both1: Uploading segment 0/6 size 6 2025/01/29 03:16:05 DEBUG : only1: Starting multipart upload 2025/01/29 03:16:05 DEBUG : only1: Uploading segment 0/6 size 6 2025/01/29 03:16:05 DEBUG : both2: Starting multipart upload 2025/01/29 03:16:05 DEBUG : both2: Uploading segment 0/6 size 6 2025/01/29 03:16:06 DEBUG : only2: Starting multipart upload 2025/01/29 03:16:06 DEBUG : only2: Uploading segment 0/6 size 6 2025/01/29 03:16:06 DEBUG : both3: Starting multipart upload 2025/01/29 03:16:06 DEBUG : both3: Uploading segment 0/6 size 6 2025/01/29 03:16:07 DEBUG : only3: Starting multipart upload 2025/01/29 03:16:07 DEBUG : only3: Uploading segment 0/6 size 6 2025/01/29 03:16:07 DEBUG : both4: Starting multipart upload 2025/01/29 03:16:07 DEBUG : both4: Uploading segment 0/6 size 6 2025/01/29 03:16:07 DEBUG : only4: Starting multipart upload 2025/01/29 03:16:07 DEBUG : only4: Uploading segment 0/6 size 6 2025/01/29 03:16:08 DEBUG : both5: Starting multipart upload 2025/01/29 03:16:08 DEBUG : both5: Uploading segment 0/6 size 6 2025/01/29 03:16:08 DEBUG : only5: Starting multipart upload 2025/01/29 03:16:08 DEBUG : only5: Uploading segment 0/6 size 6 2025/01/29 03:16:09 DEBUG : both6: Starting multipart upload 2025/01/29 03:16:09 DEBUG : both6: Uploading segment 0/6 size 6 2025/01/29 03:16:09 DEBUG : only6: Starting multipart upload 2025/01/29 03:16:09 DEBUG : only6: Uploading segment 0/6 size 6 2025/01/29 03:16:09 DEBUG : both7: Starting multipart upload 2025/01/29 03:16:10 DEBUG : both7: Uploading segment 0/6 size 6 2025/01/29 03:16:10 DEBUG : only7: Starting multipart upload 2025/01/29 03:16:10 DEBUG : only7: Uploading segment 0/6 size 6 2025/01/29 03:16:10 DEBUG : both8: Starting multipart upload 2025/01/29 03:16:10 DEBUG : both8: Uploading segment 0/6 size 6 2025/01/29 03:16:11 DEBUG : only8: Starting multipart upload 2025/01/29 03:16:11 DEBUG : only8: Uploading segment 0/6 size 6 2025/01/29 03:16:11 DEBUG : both9: Starting multipart upload 2025/01/29 03:16:11 DEBUG : both9: Uploading segment 0/6 size 6 2025/01/29 03:16:11 DEBUG : only9: Starting multipart upload 2025/01/29 03:16:11 DEBUG : only9: Uploading segment 0/6 size 6 2025/01/29 03:16:12 DEBUG : both10: Starting multipart upload 2025/01/29 03:16:12 DEBUG : both10: Uploading segment 0/6 size 6 2025/01/29 03:16:12 DEBUG : only10: Starting multipart upload 2025/01/29 03:16:12 DEBUG : only10: Uploading segment 0/6 size 6 2025/01/29 03:16:12 DEBUG : both11: Starting multipart upload 2025/01/29 03:16:13 DEBUG : both11: Uploading segment 0/6 size 6 2025/01/29 03:16:13 DEBUG : only11: Starting multipart upload 2025/01/29 03:16:13 DEBUG : only11: Uploading segment 0/6 size 6 2025/01/29 03:16:13 DEBUG : both12: Starting multipart upload 2025/01/29 03:16:13 DEBUG : both12: Uploading segment 0/6 size 6 2025/01/29 03:16:14 DEBUG : only12: Starting multipart upload 2025/01/29 03:16:14 DEBUG : only12: Uploading segment 0/6 size 6 2025/01/29 03:16:14 DEBUG : both13: Starting multipart upload 2025/01/29 03:16:14 DEBUG : both13: Uploading segment 0/6 size 6 2025/01/29 03:16:14 DEBUG : only13: Starting multipart upload 2025/01/29 03:16:15 DEBUG : only13: Uploading segment 0/6 size 6 2025/01/29 03:16:15 DEBUG : both14: Starting multipart upload 2025/01/29 03:16:15 DEBUG : both14: Uploading segment 0/6 size 6 2025/01/29 03:16:15 DEBUG : only14: Starting multipart upload 2025/01/29 03:16:15 DEBUG : only14: Uploading segment 0/6 size 6 2025/01/29 03:16:16 DEBUG : both15: Starting multipart upload 2025/01/29 03:16:16 DEBUG : both15: Uploading segment 0/6 size 6 2025/01/29 03:16:16 DEBUG : only15: Starting multipart upload 2025/01/29 03:16:16 DEBUG : only15: Uploading segment 0/6 size 6 2025/01/29 03:16:17 DEBUG : both16: Starting multipart upload 2025/01/29 03:16:17 DEBUG : both16: Uploading segment 0/6 size 6 2025/01/29 03:16:17 DEBUG : only16: Starting multipart upload 2025/01/29 03:16:17 DEBUG : only16: Uploading segment 0/6 size 6 2025/01/29 03:16:17 DEBUG : both17: Starting multipart upload 2025/01/29 03:16:18 DEBUG : both17: Uploading segment 0/6 size 6 2025/01/29 03:16:18 DEBUG : only17: Starting multipart upload 2025/01/29 03:16:18 DEBUG : only17: Uploading segment 0/6 size 6 2025/01/29 03:16:18 DEBUG : both18: Starting multipart upload 2025/01/29 03:16:18 DEBUG : both18: Uploading segment 0/6 size 6 2025/01/29 03:16:19 DEBUG : only18: Starting multipart upload 2025/01/29 03:16:19 DEBUG : only18: Uploading segment 0/6 size 6 2025/01/29 03:16:19 DEBUG : both19: Starting multipart upload 2025/01/29 03:16:19 DEBUG : both19: Uploading segment 0/6 size 6 2025/01/29 03:16:19 DEBUG : only19: Starting multipart upload 2025/01/29 03:16:20 DEBUG : only19: Uploading segment 0/6 size 6 2025/01/29 03:16:20 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:16:20 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both0: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both12: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both13: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both14: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both15: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both16: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both17: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both18: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both19: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both10: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both11: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both1: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both2: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both3: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both7: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both8: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:20 DEBUG : both9: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both4: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both5: Unchanged skipping 2025/01/29 03:16:20 DEBUG : both6: Unchanged skipping 2025/01/29 03:16:20 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:16:20 DEBUG : Waiting for deletions to finish 2025/01/29 03:16:21 INFO : only12: Deleted 2025/01/29 03:16:21 INFO : only7: Deleted 2025/01/29 03:16:21 INFO : only8: Deleted 2025/01/29 03:16:21 INFO : only3: Deleted 2025/01/29 03:16:21 INFO : only9: Deleted 2025/01/29 03:16:21 INFO : only1: Deleted 2025/01/29 03:16:21 INFO : only16: Deleted 2025/01/29 03:16:21 INFO : only0: Deleted 2025/01/29 03:16:21 INFO : only17: Deleted 2025/01/29 03:16:21 INFO : only18: Deleted 2025/01/29 03:16:21 INFO : only5: Deleted 2025/01/29 03:16:21 INFO : only4: Deleted 2025/01/29 03:16:21 INFO : only19: Deleted 2025/01/29 03:16:21 INFO : only11: Deleted 2025/01/29 03:16:21 INFO : only13: Deleted 2025/01/29 03:16:21 INFO : only6: Deleted 2025/01/29 03:16:21 INFO : only15: Deleted 2025/01/29 03:16:22 INFO : only2: Deleted 2025/01/29 03:16:22 INFO : only10: Deleted 2025/01/29 03:16:22 INFO : only14: Deleted run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestSyncConcurrentDelete (23.13s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:16:27 DEBUG : both0: Starting multipart upload 2025/01/29 03:16:27 DEBUG : both0: Uploading segment 0/6 size 6 2025/01/29 03:16:27 DEBUG : only0: Starting multipart upload 2025/01/29 03:16:27 DEBUG : only0: Uploading segment 0/6 size 6 2025/01/29 03:16:28 DEBUG : both1: Starting multipart upload 2025/01/29 03:16:28 DEBUG : both1: Uploading segment 0/6 size 6 2025/01/29 03:16:28 DEBUG : only1: Starting multipart upload 2025/01/29 03:16:28 DEBUG : only1: Uploading segment 0/6 size 6 2025/01/29 03:16:28 DEBUG : both2: Starting multipart upload 2025/01/29 03:16:29 DEBUG : both2: Uploading segment 0/6 size 6 2025/01/29 03:16:29 DEBUG : only2: Starting multipart upload 2025/01/29 03:16:29 DEBUG : only2: Uploading segment 0/6 size 6 2025/01/29 03:16:29 DEBUG : both3: Starting multipart upload 2025/01/29 03:16:29 DEBUG : both3: Uploading segment 0/6 size 6 2025/01/29 03:16:30 DEBUG : only3: Starting multipart upload 2025/01/29 03:16:30 DEBUG : only3: Uploading segment 0/6 size 6 2025/01/29 03:16:30 DEBUG : both4: Starting multipart upload 2025/01/29 03:16:30 DEBUG : both4: Uploading segment 0/6 size 6 2025/01/29 03:16:30 DEBUG : only4: Starting multipart upload 2025/01/29 03:16:31 DEBUG : only4: Uploading segment 0/6 size 6 2025/01/29 03:16:31 DEBUG : both5: Starting multipart upload 2025/01/29 03:16:31 DEBUG : both5: Uploading segment 0/6 size 6 2025/01/29 03:16:31 DEBUG : only5: Starting multipart upload 2025/01/29 03:16:31 DEBUG : only5: Uploading segment 0/6 size 6 2025/01/29 03:16:32 DEBUG : both6: Starting multipart upload 2025/01/29 03:16:32 DEBUG : both6: Uploading segment 0/6 size 6 2025/01/29 03:16:32 DEBUG : only6: Starting multipart upload 2025/01/29 03:16:32 DEBUG : only6: Uploading segment 0/6 size 6 2025/01/29 03:16:32 DEBUG : both7: Starting multipart upload 2025/01/29 03:16:33 DEBUG : both7: Uploading segment 0/6 size 6 2025/01/29 03:16:33 DEBUG : only7: Starting multipart upload 2025/01/29 03:16:33 DEBUG : only7: Uploading segment 0/6 size 6 2025/01/29 03:16:33 DEBUG : both8: Starting multipart upload 2025/01/29 03:16:33 DEBUG : both8: Uploading segment 0/6 size 6 2025/01/29 03:16:34 DEBUG : only8: Starting multipart upload 2025/01/29 03:16:34 DEBUG : only8: Uploading segment 0/6 size 6 2025/01/29 03:16:34 DEBUG : both9: Starting multipart upload 2025/01/29 03:16:34 DEBUG : both9: Uploading segment 0/6 size 6 2025/01/29 03:16:34 DEBUG : only9: Starting multipart upload 2025/01/29 03:16:35 DEBUG : only9: Uploading segment 0/6 size 6 2025/01/29 03:16:35 DEBUG : both10: Starting multipart upload 2025/01/29 03:16:35 DEBUG : both10: Uploading segment 0/6 size 6 2025/01/29 03:16:35 DEBUG : only10: Starting multipart upload 2025/01/29 03:16:35 DEBUG : only10: Uploading segment 0/6 size 6 2025/01/29 03:16:36 DEBUG : both11: Starting multipart upload 2025/01/29 03:16:36 DEBUG : both11: Uploading segment 0/6 size 6 2025/01/29 03:16:36 DEBUG : only11: Starting multipart upload 2025/01/29 03:16:36 DEBUG : only11: Uploading segment 0/6 size 6 2025/01/29 03:16:37 DEBUG : both12: Starting multipart upload 2025/01/29 03:16:37 DEBUG : both12: Uploading segment 0/6 size 6 2025/01/29 03:16:37 DEBUG : only12: Starting multipart upload 2025/01/29 03:16:37 DEBUG : only12: Uploading segment 0/6 size 6 2025/01/29 03:16:37 DEBUG : both13: Starting multipart upload 2025/01/29 03:16:38 DEBUG : both13: Uploading segment 0/6 size 6 2025/01/29 03:16:38 DEBUG : only13: Starting multipart upload 2025/01/29 03:16:38 DEBUG : only13: Uploading segment 0/6 size 6 2025/01/29 03:16:38 DEBUG : both14: Starting multipart upload 2025/01/29 03:16:38 DEBUG : both14: Uploading segment 0/6 size 6 2025/01/29 03:16:39 DEBUG : only14: Starting multipart upload 2025/01/29 03:16:39 DEBUG : only14: Uploading segment 0/6 size 6 2025/01/29 03:16:39 DEBUG : both15: Starting multipart upload 2025/01/29 03:16:39 DEBUG : both15: Uploading segment 0/6 size 6 2025/01/29 03:16:40 DEBUG : only15: Starting multipart upload 2025/01/29 03:16:40 DEBUG : only15: Uploading segment 0/6 size 6 2025/01/29 03:16:40 DEBUG : both16: Starting multipart upload 2025/01/29 03:16:40 DEBUG : both16: Uploading segment 0/6 size 6 2025/01/29 03:16:40 DEBUG : only16: Starting multipart upload 2025/01/29 03:16:41 DEBUG : only16: Uploading segment 0/6 size 6 2025/01/29 03:16:41 DEBUG : both17: Starting multipart upload 2025/01/29 03:16:41 DEBUG : both17: Uploading segment 0/6 size 6 2025/01/29 03:16:41 DEBUG : only17: Starting multipart upload 2025/01/29 03:16:41 DEBUG : only17: Uploading segment 0/6 size 6 2025/01/29 03:16:42 DEBUG : both18: Starting multipart upload 2025/01/29 03:16:42 DEBUG : both18: Uploading segment 0/6 size 6 2025/01/29 03:16:42 DEBUG : only18: Starting multipart upload 2025/01/29 03:16:42 DEBUG : only18: Uploading segment 0/6 size 6 2025/01/29 03:16:42 DEBUG : both19: Starting multipart upload 2025/01/29 03:16:43 DEBUG : both19: Uploading segment 0/6 size 6 2025/01/29 03:16:43 DEBUG : only19: Starting multipart upload 2025/01/29 03:16:43 DEBUG : only19: Uploading segment 0/6 size 6 2025/01/29 03:16:44 DEBUG : both1: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both0: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both10: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both11: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for checks to finish 2025/01/29 03:16:44 DEBUG : both1: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both12: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both12: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both13: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both13: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both14: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both14: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both15: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both15: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both16: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both0: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both17: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both17: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both18: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both10: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both19: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both11: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both2: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both2: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both3: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both3: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both4: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both4: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both5: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both5: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both6: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both6: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both7: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both7: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both8: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both8: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both9: Size and modification time the same (differ by -999.999µs, within tolerance 1s) 2025/01/29 03:16:44 DEBUG : both9: Unchanged skipping 2025/01/29 03:16:44 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2025/01/29 03:16:44 DEBUG : both16: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both18: Unchanged skipping 2025/01/29 03:16:44 DEBUG : both19: Unchanged skipping 2025/01/29 03:16:44 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Waiting for transfers to finish 2025/01/29 03:16:44 DEBUG : only0: Starting singlepart upload 2025/01/29 03:16:44 DEBUG : only1: Starting singlepart upload 2025/01/29 03:16:44 DEBUG : only10: Starting singlepart upload 2025/01/29 03:16:44 DEBUG : only11: Starting singlepart upload 2025/01/29 03:16:44 DEBUG : only1: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:44 INFO : only1: Copied (replaced existing) 2025/01/29 03:16:44 DEBUG : only12: Starting singlepart upload 2025/01/29 03:16:44 DEBUG : only0: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:44 INFO : only0: Copied (replaced existing) 2025/01/29 03:16:44 DEBUG : only13: Starting singlepart upload 2025/01/29 03:16:44 DEBUG : only10: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:44 INFO : only10: Copied (replaced existing) 2025/01/29 03:16:44 DEBUG : only14: Starting singlepart upload 2025/01/29 03:16:44 DEBUG : only11: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:44 INFO : only11: Copied (replaced existing) 2025/01/29 03:16:44 DEBUG : only15: Starting singlepart upload 2025/01/29 03:16:45 DEBUG : only12: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:45 INFO : only12: Copied (replaced existing) 2025/01/29 03:16:45 DEBUG : only16: Starting singlepart upload 2025/01/29 03:16:45 DEBUG : only13: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:45 INFO : only13: Copied (replaced existing) 2025/01/29 03:16:45 DEBUG : only17: Starting singlepart upload 2025/01/29 03:16:45 DEBUG : only14: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:45 INFO : only14: Copied (replaced existing) 2025/01/29 03:16:45 DEBUG : only18: Starting singlepart upload 2025/01/29 03:16:45 DEBUG : only15: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:45 INFO : only15: Copied (replaced existing) 2025/01/29 03:16:45 DEBUG : only19: Starting singlepart upload 2025/01/29 03:16:45 DEBUG : info from Update error: null 2025/01/29 03:16:45 ERROR : only19: Failed to copy: resourceModified: ETag does not match current item's value 2025/01/29 03:16:45 DEBUG : only2: Starting singlepart upload 2025/01/29 03:16:45 DEBUG : only16: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:45 INFO : only16: Copied (replaced existing) 2025/01/29 03:16:45 DEBUG : only3: Starting singlepart upload 2025/01/29 03:16:45 DEBUG : only17: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:45 INFO : only17: Copied (replaced existing) 2025/01/29 03:16:45 DEBUG : only4: Starting singlepart upload 2025/01/29 03:16:45 DEBUG : only18: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:45 INFO : only18: Copied (replaced existing) 2025/01/29 03:16:45 DEBUG : only5: Starting singlepart upload 2025/01/29 03:16:46 DEBUG : only2: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:46 INFO : only2: Copied (replaced existing) 2025/01/29 03:16:46 DEBUG : only6: Starting singlepart upload 2025/01/29 03:16:46 DEBUG : only3: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:46 INFO : only3: Copied (replaced existing) 2025/01/29 03:16:46 DEBUG : only7: Starting singlepart upload 2025/01/29 03:16:46 DEBUG : only4: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:46 INFO : only4: Copied (replaced existing) 2025/01/29 03:16:46 DEBUG : only8: Starting singlepart upload 2025/01/29 03:16:46 DEBUG : only5: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:46 INFO : only5: Copied (replaced existing) 2025/01/29 03:16:46 DEBUG : only9: Starting singlepart upload 2025/01/29 03:16:46 DEBUG : only6: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:46 INFO : only6: Copied (replaced existing) 2025/01/29 03:16:46 DEBUG : only7: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:46 INFO : only7: Copied (replaced existing) 2025/01/29 03:16:46 DEBUG : only8: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:46 INFO : only8: Copied (replaced existing) 2025/01/29 03:16:46 DEBUG : only9: quickxor = 0000000000000000000000000000000000000000 OK 2025/01/29 03:16:46 INFO : only9: Copied (replaced existing) 2025/01/29 03:16:46 ERROR : OneDrive root 'rclone-test-kociqez2hufe': not deleting files as there were IO errors 2025/01/29 03:16:46 ERROR : OneDrive root 'rclone-test-kociqez2hufe': not deleting directories as there were IO errors run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" sync_test.go:2752: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2752 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Received unexpected error: resourceModified: ETag does not match current item's value Test: TestSyncConcurrentTruncate Messages: Sync must not return a error sync_test.go:2753: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2753 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Should be false Test: TestSyncConcurrentTruncate Messages: Low level errors must not have happened fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Should be true Test: TestSyncConcurrentTruncate Messages: listing wrong, want both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (0), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) got both0 (6), both1 (6), both10 (6), both11 (6), both12 (6), both13 (6), both14 (6), both15 (6), both16 (6), both17 (6), both18 (6), both19 (6), both2 (6), both3 (6), both4 (6), both5 (6), both6 (6), both7 (6), both8 (6), both9 (6), only0 (0), only1 (0), only10 (0), only11 (0), only12 (0), only13 (0), only14 (0), only15 (0), only16 (0), only17 (0), only18 (0), only19 (6), only2 (0), only3 (0), only4 (0), only5 (0), only6 (0), only7 (0), only8 (0), only9 (0) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Should be true Test: TestSyncConcurrentTruncate Messages: OneDrive root 'rclone-test-kociqez2hufe'/only19: quickxor hash incorrect - expecting "0000000000000000000000000000000000000000" got "7078031dc2408737000000000600000000000000" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2754 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2762 Error: Not equal: expected: 0 actual : 6 Test: TestSyncConcurrentTruncate Messages: only19: size incorrect file=0 vs obj=6 --- FAIL: TestSyncConcurrentTruncate (37.65s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:17:04 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:17:04 DEBUG : sub dir: Making directory with metadata 2025/01/29 03:17:05 INFO : sub dir: Made directory with metadata (mtime=2011-12-30T12:59:59Z) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:17:15 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/01/29 03:17:15 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/01/29 03:17:15 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:17:15 INFO : sub dirEmpty: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestNothingToTransferWithEmptyDirs (23.87s) === RUN TestNothingToTransferWithoutEmptyDirs run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:17:28 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/01/29 03:17:28 DEBUG : sub dir: Making directory with metadata 2025/01/29 03:17:29 INFO : sub dir: Made directory with metadata (mtime=2011-12-30T12:59:59Z) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" 2025/01/29 03:17:40 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/01/29 03:17:41 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/01/29 03:17:41 INFO : sub dirEmpty/sub dirEmpty2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/01/29 03:17:41 INFO : sub dirEmpty: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-kociqez2hufe'", Local "Local file system at /tmp/rclone2870153325", Modify Window "1s" --- PASS: TestNothingToTransferWithoutEmptyDirs (24.58s) FAIL 2025/01/29 03:17:53 DEBUG : OneDrive root 'rclone-test-kociqez2hufe': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose" - Finished ERROR in 7m35.555763045s (try 1/5): exit status 1: Failed [TestSyncUTFNorm TestSyncConcurrentTruncate]