"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose" - Starting (try 1/5) 2022/01/28 06:27:40 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0" 2022/01/28 06:27:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/28 06:27:40 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Token expired but no uploads in progress - doing nothing 2022/01/28 06:27:40 DEBUG : TestOneDriveBusiness: Loaded invalid token from config file - ignoring 2022/01/28 06:27:41 DEBUG : Saving config "token" in section "TestOneDriveBusiness" of the config file 2022/01/28 06:27:41 DEBUG : TestOneDriveBusiness: Saved new token in config file 2022/01/28 06:27:43 DEBUG : Creating backend with remote "/tmp/rclone1817180687" === 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:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:27:45 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/01/28 06:27:45 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:27:45 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (3.14s) === RUN TestCopy run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:27:47 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:27:47 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:27:48 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:27:49 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:27:49 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:27:49 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (7.37s) === RUN TestCopyMissingDirectory run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:27:54 DEBUG : Creating backend with remote "/non-existing" 2022/01/28 06:27:54 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/01/28 06:27:54 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:27:54 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (1.59s) === RUN TestCopyNoTraverse run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:27:56 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:27:56 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:27:57 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:27:58 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:27:59 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:27:59 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (6.77s) === RUN TestCopyCheckFirst run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:02 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Running all checks before starting transfers 2022/01/28 06:28:02 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:28:02 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Checks finished, now starting transfers 2022/01/28 06:28:02 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:28:03 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:28:04 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:28:05 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:28:05 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (5.89s) === RUN TestSyncNoTraverse run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:08 ERROR : Ignoring --no-traverse with sync 2022/01/28 06:28:08 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:28:08 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:28:09 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:28:10 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:28:11 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:28:11 INFO : sub dir/hello world: Copied (new) 2022/01/28 06:28:11 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (5.79s) === RUN TestCopyWithDepth run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:14 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:28:14 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:28:14 DEBUG : hello world2: Starting multipart upload 2022/01/28 06:28:15 DEBUG : hello world2: Uploading segment 0/12 size 12 2022/01/28 06:28:15 DEBUG : hello world2: quickxor = 6828031bd8f00610dce10d726c03196400000000 OK 2022/01/28 06:28:15 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (3.18s) === RUN TestCopyWithFilesFrom run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:17 DEBUG : hello world2: Excluded 2022/01/28 06:28:17 DEBUG : potato2: Starting multipart upload 2022/01/28 06:28:17 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:28:17 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:28:18 DEBUG : potato2: Uploading segment 0/11 size 11 2022/01/28 06:28:19 DEBUG : potato2: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:28:19 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (3.13s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:21 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:28:21 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:28:21 DEBUG : potato2: Starting multipart upload 2022/01/28 06:28:21 DEBUG : potato2: Uploading segment 0/11 size 11 2022/01/28 06:28:22 DEBUG : potato2: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:28:22 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (3.11s) === RUN TestCopyEmptyDirectories run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:23 DEBUG : sub dir2: Making directory 2022/01/28 06:28:23 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:28:23 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:28:24 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:28:25 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:28:25 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:28:25 INFO : sub dir/hello world: Copied (new) 2022/01/28 06:28:25 DEBUG : sub dir2: Making directory 2022/01/28 06:28:26 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': copied 1 directories --- PASS: TestCopyEmptyDirectories (8.27s) === RUN TestMoveEmptyDirectories run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:31 DEBUG : sub dir2: Making directory 2022/01/28 06:28:32 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:28:32 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:28:33 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:28:34 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:28:34 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:28:34 INFO : sub dir/hello world: Copied (new) 2022/01/28 06:28:34 INFO : sub dir/hello world: Deleted 2022/01/28 06:28:34 DEBUG : sub dir: Making directory 2022/01/28 06:28:34 DEBUG : sub dir2: Making directory 2022/01/28 06:28:35 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': copied 2 directories --- PASS: TestMoveEmptyDirectories (8.97s) === RUN TestSyncEmptyDirectories run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:40 DEBUG : sub dir2: Making directory 2022/01/28 06:28:41 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:28:41 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:28:42 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:28:43 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:28:43 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:28:43 INFO : sub dir/hello world: Copied (new) 2022/01/28 06:28:43 DEBUG : sub dir2: Making directory 2022/01/28 06:28:44 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': copied 1 directories 2022/01/28 06:28:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (9.20s) === RUN TestServerSideCopy run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:28:51 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:28:51 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:28:53 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-womikux8pohosup2petegup6" sync_test.go:275: Server side copy (if possible) One drive root 'rclone-test-luqopom7vininaj4muvivoc0' -> One drive root 'rclone-test-womikux8pohosup2petegup6' 2022/01/28 06:28:55 DEBUG : One drive root 'rclone-test-womikux8pohosup2petegup6': Waiting for checks to finish 2022/01/28 06:28:55 DEBUG : One drive root 'rclone-test-womikux8pohosup2petegup6': Waiting for transfers to finish 2022/01/28 06:29:01 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:29:01 INFO : sub dir/hello world: Copied (server-side copy) 2022/01/28 06:29:02 DEBUG : One drive root 'rclone-test-womikux8pohosup2petegup6': Purge remote --- PASS: TestServerSideCopy (14.83s) === RUN TestCopyAfterDelete run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:05 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:29:06 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:29:06 ERROR : : error listing: directory not found 2022/01/28 06:29:07 DEBUG : Local file system at /tmp/rclone1817180687: Making directory 2022/01/28 06:29:07 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:07 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (5.71s) === RUN TestCopyRedownload run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:11 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:29:12 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:29:14 DEBUG : Local file system at /tmp/rclone1817180687: Waiting for checks to finish 2022/01/28 06:29:14 DEBUG : Local file system at /tmp/rclone1817180687: Waiting for transfers to finish 2022/01/28 06:29:14 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:29:14 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (6.21s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:17 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:17 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:17 DEBUG : check sum: Starting multipart upload 2022/01/28 06:29:17 DEBUG : check sum: Uploading segment 0/1 size 1 2022/01/28 06:29:18 DEBUG : check sum: quickxor = 2d00000000000000000000000100000000000000 OK 2022/01/28 06:29:18 INFO : check sum: Copied (new) 2022/01/28 06:29:18 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:18 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:18 DEBUG : check sum: quickxor = 2d00000000000000000000000100000000000000 OK 2022/01/28 06:29:18 DEBUG : check sum: Size and quickxor of src and dst objects identical 2022/01/28 06:29:18 DEBUG : check sum: Unchanged skipping 2022/01/28 06:29:18 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:18 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:18 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (3.11s) === RUN TestSyncSizeOnly run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:20 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:20 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:20 DEBUG : sizeonly: Starting multipart upload 2022/01/28 06:29:20 DEBUG : sizeonly: Uploading segment 0/6 size 6 2022/01/28 06:29:21 DEBUG : sizeonly: quickxor = 7078031dc2408737000000000600000000000000 OK 2022/01/28 06:29:21 INFO : sizeonly: Copied (new) 2022/01/28 06:29:21 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:23 DEBUG : sizeonly: Sizes identical 2022/01/28 06:29:23 DEBUG : sizeonly: Unchanged skipping 2022/01/28 06:29:23 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:23 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:23 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:23 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (5.02s) === RUN TestSyncIgnoreSize run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:25 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:25 DEBUG : ignore-size: Starting multipart upload 2022/01/28 06:29:25 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:25 DEBUG : ignore-size: Uploading segment 0/8 size 8 2022/01/28 06:29:26 DEBUG : ignore-size: quickxor = 6378831be8500637d0610e000800000000000000 OK 2022/01/28 06:29:26 INFO : ignore-size: Copied (new) 2022/01/28 06:29:26 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:27 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:29:27 DEBUG : ignore-size: Unchanged skipping 2022/01/28 06:29:27 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:27 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:27 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:27 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (3.49s) === RUN TestSyncIgnoreTimes run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:28 DEBUG : existing: Starting multipart upload 2022/01/28 06:29:28 DEBUG : existing: Uploading segment 0/6 size 6 2022/01/28 06:29:29 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:29:29 DEBUG : existing: Unchanged skipping 2022/01/28 06:29:29 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:29 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:29 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:29 INFO : There was nothing to transfer 2022/01/28 06:29:30 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:30 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/01/28 06:29:30 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:30 DEBUG : existing: Starting multipart upload 2022/01/28 06:29:30 DEBUG : existing: Uploading segment 0/6 size 6 2022/01/28 06:29:31 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2022/01/28 06:29:31 INFO : existing: Copied (replaced existing) 2022/01/28 06:29:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (4.24s) === RUN TestSyncIgnoreExisting run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:32 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:32 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:32 DEBUG : existing: Starting multipart upload 2022/01/28 06:29:33 DEBUG : existing: Uploading segment 0/6 size 6 2022/01/28 06:29:33 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2022/01/28 06:29:33 INFO : existing: Copied (new) 2022/01/28 06:29:33 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:34 DEBUG : existing: Destination exists, skipping 2022/01/28 06:29:34 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:34 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:34 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:34 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (2.78s) === RUN TestSyncIgnoreErrors run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:35 DEBUG : b/potato: Starting multipart upload 2022/01/28 06:29:36 DEBUG : b/potato: Uploading segment 0/21 size 21 2022/01/28 06:29:37 DEBUG : c/non empty space: Starting multipart upload 2022/01/28 06:29:38 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2022/01/28 06:29:38 DEBUG : d: Making directory 2022/01/28 06:29:41 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:29:41 DEBUG : c/non empty space: Unchanged skipping 2022/01/28 06:29:41 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:41 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:41 DEBUG : a/potato2: Starting multipart upload 2022/01/28 06:29:42 DEBUG : a/potato2: Uploading segment 0/60 size 60 2022/01/28 06:29:42 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2022/01/28 06:29:42 INFO : a/potato2: Copied (new) 2022/01/28 06:29:42 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:42 INFO : b/potato: Deleted 2022/01/28 06:29:42 INFO : d: Removing directory 2022/01/28 06:29:43 INFO : b: Removing directory 2022/01/28 06:29:43 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': deleted 2 directories --- PASS: TestSyncIgnoreErrors (11.27s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:46 DEBUG : empty space: Starting multipart upload 2022/01/28 06:29:47 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:29:48 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:48 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2022/01/28 06:29:48 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2022/01/28 06:29:48 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2022/01/28 06:29:48 DEBUG : empty space: Unchanged skipping 2022/01/28 06:29:48 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:48 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:48 INFO : There was nothing to transfer 2022/01/28 06:29:48 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:48 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2022/01/28 06:29:48 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2022/01/28 06:29:49 INFO : empty space: Updated modification time in destination 2022/01/28 06:29:49 DEBUG : empty space: Unchanged skipping 2022/01/28 06:29:49 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:49 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:49 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (3.77s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:50 DEBUG : empty space: Starting multipart upload 2022/01/28 06:29:51 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:29:52 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2022/01/28 06:29:52 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:52 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2022/01/28 06:29:52 DEBUG : empty space: Unchanged skipping 2022/01/28 06:29:52 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:52 DEBUG : Waiting for deletions to finish 2022/01/28 06:29:52 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (2.77s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:53 DEBUG : foo: Starting multipart upload 2022/01/28 06:29:53 DEBUG : foo: Uploading segment 0/3 size 3 2022/01/28 06:29:54 DEBUG : foo: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06 +0000 UTC 2022/01/28 06:29:54 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:54 DEBUG : foo: quickxor = 6678c31b00000000000000000300000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:29:54 DEBUG : foo: quickxor = 6208831c00000000000000000300000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:29:54 DEBUG : foo: quickxor differ 2022/01/28 06:29:54 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:29:54 DEBUG : foo: Starting multipart upload 2022/01/28 06:29:55 DEBUG : foo: Uploading segment 0/3 size 3 2022/01/28 06:29:55 DEBUG : foo: quickxor = 6678c31b00000000000000000300000000000000 OK 2022/01/28 06:29:55 INFO : foo: Copied (replaced existing) 2022/01/28 06:29:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (3.56s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:29:56 DEBUG : empty space: Starting multipart upload 2022/01/28 06:29:57 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:29:58 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:29:58 DEBUG : empty space: Unchanged skipping 2022/01/28 06:29:58 DEBUG : potato: Starting multipart upload 2022/01/28 06:29:58 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:29:58 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:00 DEBUG : potato: Uploading segment 0/60 size 60 2022/01/28 06:30:00 DEBUG : potato: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2022/01/28 06:30:00 INFO : potato: Copied (new) 2022/01/28 06:30:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (4.97s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:01 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:02 DEBUG : potato: Uploading segment 0/60 size 60 2022/01/28 06:30:04 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:04 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/01/28 06:30:04 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:04 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:04 DEBUG : potato: Uploading segment 0/21 size 21 2022/01/28 06:30:05 DEBUG : potato: quickxor = d26463389bd86e62ce010462bd031d40308730b4 OK 2022/01/28 06:30:05 INFO : potato: Copied (replaced existing) 2022/01/28 06:30:05 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (4.60s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:06 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:06 DEBUG : potato: Uploading segment 0/21 size 21 2022/01/28 06:30:08 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30: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 2022/01/28 06:30:08 DEBUG : potato: quickxor = f2606230dad02c724c010442bd02154030852034 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:30:08 DEBUG : potato: quickxor = d26463389bd86e62ce010462bd031d40308730b4 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:30:08 DEBUG : potato: quickxor differ 2022/01/28 06:30:08 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:08 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:08 DEBUG : potato: Uploading segment 0/21 size 21 2022/01/28 06:30:08 DEBUG : potato: quickxor = f2606230dad02c724c010442bd02154030852034 OK 2022/01/28 06:30:08 INFO : potato: Copied (replaced existing) 2022/01/28 06:30:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (3.55s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:09 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:10 DEBUG : potato: Uploading segment 0/21 size 21 2022/01/28 06:30:11 DEBUG : empty space: Starting multipart upload 2022/01/28 06:30:11 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:30:12 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/01/28 06:30:12 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:30:12 DEBUG : empty space: Unchanged skipping 2022/01/28 06:30:12 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:12 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:12 DEBUG : Waiting for deletions to finish 2022/01/28 06:30:12 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (3.63s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:13 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:14 DEBUG : potato: Uploading segment 0/21 size 21 2022/01/28 06:30:14 DEBUG : empty space: Starting multipart upload 2022/01/28 06:30:15 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:30:16 DEBUG : potato2: Starting multipart upload 2022/01/28 06:30:16 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:30:16 DEBUG : empty space: Unchanged skipping 2022/01/28 06:30:16 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:16 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:17 DEBUG : potato2: Uploading segment 0/60 size 60 2022/01/28 06:30:17 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2022/01/28 06:30:17 INFO : potato2: Copied (new) 2022/01/28 06:30:17 DEBUG : Waiting for deletions to finish 2022/01/28 06:30:18 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (6.47s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:21 DEBUG : b/potato: Starting multipart upload 2022/01/28 06:30:22 DEBUG : b/potato: Uploading segment 0/21 size 21 2022/01/28 06:30:23 DEBUG : c/non empty space: Starting multipart upload 2022/01/28 06:30:24 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2022/01/28 06:30:24 DEBUG : d: Making directory 2022/01/28 06:30:25 DEBUG : d/e: Making directory 2022/01/28 06:30:26 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:30:26 DEBUG : c/non empty space: Unchanged skipping 2022/01/28 06:30:27 DEBUG : a/potato2: Starting multipart upload 2022/01/28 06:30:27 DEBUG : a/potato2: Uploading segment 0/60 size 60 2022/01/28 06:30:27 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:27 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:28 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2022/01/28 06:30:28 INFO : a/potato2: Copied (new) 2022/01/28 06:30:28 DEBUG : Waiting for deletions to finish 2022/01/28 06:30:28 INFO : b/potato: Deleted 2022/01/28 06:30:28 INFO : d/e: Removing directory 2022/01/28 06:30:29 INFO : d: Removing directory 2022/01/28 06:30:29 INFO : b: Removing directory 2022/01/28 06:30:29 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (12.57s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:33 DEBUG : b/potato: Starting multipart upload 2022/01/28 06:30:34 DEBUG : b/potato: Uploading segment 0/21 size 21 2022/01/28 06:30:35 DEBUG : c/non empty space: Starting multipart upload 2022/01/28 06:30:36 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2022/01/28 06:30:36 DEBUG : d: Making directory 2022/01/28 06:30:38 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:30:38 DEBUG : c/non empty space: Unchanged skipping 2022/01/28 06:30:38 DEBUG : a/potato2: Starting multipart upload 2022/01/28 06:30:38 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:38 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:39 DEBUG : a/potato2: Uploading segment 0/60 size 60 2022/01/28 06:30:39 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2022/01/28 06:30:39 INFO : a/potato2: Copied (new) 2022/01/28 06:30:39 ERROR : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': not deleting files as there were IO errors 2022/01/28 06:30:39 ERROR : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (10.95s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:43 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:44 DEBUG : potato: Uploading segment 0/21 size 21 2022/01/28 06:30:44 DEBUG : empty space: Starting multipart upload 2022/01/28 06:30:45 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:30:45 DEBUG : Waiting for deletions to finish 2022/01/28 06:30:46 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:30:46 DEBUG : empty space: Unchanged skipping 2022/01/28 06:30:46 DEBUG : potato2: Starting multipart upload 2022/01/28 06:30:46 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:46 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:46 INFO : potato: Deleted 2022/01/28 06:30:46 DEBUG : potato2: Uploading segment 0/60 size 60 2022/01/28 06:30:47 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2022/01/28 06:30:47 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (4.84s) === RUN TestSyncDeleteBefore run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:48 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:48 DEBUG : potato: Uploading segment 0/21 size 21 2022/01/28 06:30:49 DEBUG : empty space: Starting multipart upload 2022/01/28 06:30:50 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:30:51 DEBUG : Waiting for deletions to finish 2022/01/28 06:30:51 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:51 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:51 INFO : potato: Deleted 2022/01/28 06:30:51 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:30:51 DEBUG : potato2: Starting multipart upload 2022/01/28 06:30:51 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:51 DEBUG : empty space: Unchanged skipping 2022/01/28 06:30:51 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:52 DEBUG : potato2: Uploading segment 0/60 size 60 2022/01/28 06:30:52 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2022/01/28 06:30:52 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (5.66s) === RUN TestCopyDeleteBefore run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:54 DEBUG : potato: Starting multipart upload 2022/01/28 06:30:54 DEBUG : potato: Uploading segment 0/21 size 21 2022/01/28 06:30:55 DEBUG : potato2: Starting multipart upload 2022/01/28 06:30:55 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:30:55 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:30:56 DEBUG : potato2: Uploading segment 0/19 size 19 2022/01/28 06:30:57 DEBUG : potato2: quickxor = e9742354497b863ab0810d7913c118de00873494 OK 2022/01/28 06:30:57 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (4.59s) === RUN TestSyncWithExclude run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:30:58 DEBUG : potato2: Starting multipart upload 2022/01/28 06:30:59 DEBUG : potato2: Uploading segment 0/60 size 60 2022/01/28 06:30:59 DEBUG : empty space: Starting multipart upload 2022/01/28 06:31:00 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:31:00 DEBUG : enormous: Excluded 2022/01/28 06:31:00 DEBUG : potato2: Excluded 2022/01/28 06:31:01 DEBUG : potato2: Excluded 2022/01/28 06:31:01 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:31:01 DEBUG : empty space: Unchanged skipping 2022/01/28 06:31:01 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:01 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:01 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:01 INFO : There was nothing to transfer 2022/01/28 06:31:01 DEBUG : enormous: Excluded 2022/01/28 06:31:01 DEBUG : potato2: Excluded 2022/01/28 06:31:01 DEBUG : potato2: Excluded 2022/01/28 06:31:01 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2022/01/28 06:31:01 DEBUG : empty space: Unchanged skipping 2022/01/28 06:31:01 DEBUG : Local file system at /tmp/rclone1817180687: Waiting for checks to finish 2022/01/28 06:31:01 DEBUG : Local file system at /tmp/rclone1817180687: Waiting for transfers to finish 2022/01/28 06:31:01 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:01 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (4.14s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:31:02 DEBUG : potato2: Starting multipart upload 2022/01/28 06:31:03 DEBUG : potato2: Uploading segment 0/60 size 60 2022/01/28 06:31:03 DEBUG : empty space: Starting multipart upload 2022/01/28 06:31:04 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:31:04 DEBUG : enormous: Starting multipart upload 2022/01/28 06:31:05 DEBUG : enormous: Uploading segment 0/100 size 100 2022/01/28 06:31:06 DEBUG : enormous: Excluded 2022/01/28 06:31:06 DEBUG : potato2: Excluded 2022/01/28 06:31:06 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:06 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:31:06 DEBUG : empty space: Unchanged skipping 2022/01/28 06:31:06 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:06 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:06 INFO : potato2: Deleted 2022/01/28 06:31:06 INFO : enormous: Deleted 2022/01/28 06:31:06 INFO : There was nothing to transfer 2022/01/28 06:31:07 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2022/01/28 06:31:07 DEBUG : empty space: Unchanged skipping 2022/01/28 06:31:07 DEBUG : Local file system at /tmp/rclone1817180687: Waiting for checks to finish 2022/01/28 06:31:07 DEBUG : Local file system at /tmp/rclone1817180687: Waiting for transfers to finish 2022/01/28 06:31:07 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:07 INFO : enormous: Deleted 2022/01/28 06:31:07 INFO : potato2: Deleted 2022/01/28 06:31:07 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (5.22s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:31:07 DEBUG : one: Starting multipart upload 2022/01/28 06:31:08 DEBUG : one: Uploading segment 0/3 size 3 2022/01/28 06:31:09 DEBUG : two: Starting multipart upload 2022/01/28 06:31:09 DEBUG : two: Uploading segment 0/3 size 3 2022/01/28 06:31:10 DEBUG : three: Starting multipart upload 2022/01/28 06:31:10 DEBUG : three: Uploading segment 0/5 size 5 2022/01/28 06:31:11 DEBUG : four: Starting multipart upload 2022/01/28 06:31:11 DEBUG : four: Uploading segment 0/8 size 8 2022/01/28 06:31:13 DEBUG : five: Starting multipart upload 2022/01/28 06:31:13 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/01/28 06:31:13 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/01/28 06:31:13 DEBUG : four: Starting multipart upload 2022/01/28 06:31:13 DEBUG : one: Destination is newer than source, skipping 2022/01/28 06:31:13 DEBUG : three: Sizes identical 2022/01/28 06:31:13 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/01/28 06:31:13 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:13 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:31:13 DEBUG : two: quickxor = 54b8c21300000000000000000300000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:31:13 DEBUG : two: quickxor differ 2022/01/28 06:31:13 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:13 DEBUG : two: Starting multipart upload 2022/01/28 06:31:13 DEBUG : four: Uploading segment 0/4 size 4 2022/01/28 06:31:13 DEBUG : two: Uploading segment 0/3 size 3 2022/01/28 06:31:13 DEBUG : five: Uploading segment 0/4 size 4 2022/01/28 06:31:14 DEBUG : four: quickxor = 6678431de4000000000000000400000000000000 OK 2022/01/28 06:31:14 INFO : four: Copied (replaced existing) 2022/01/28 06:31:14 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2022/01/28 06:31:14 INFO : two: Copied (replaced existing) 2022/01/28 06:31:14 DEBUG : five: quickxor = 6648831dca000000000000000400000000000000 OK 2022/01/28 06:31:14 INFO : five: Copied (new) 2022/01/28 06:31:14 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:15 DEBUG : one: Destination is newer than source, skipping 2022/01/28 06:31:15 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:15 DEBUG : five: quickxor = 6648831dca000000000000000400000000000000 OK 2022/01/28 06:31:15 DEBUG : five: Size and quickxor of src and dst objects identical 2022/01/28 06:31:15 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2022/01/28 06:31:15 DEBUG : four: quickxor = 6678431de4000000000000000400000000000000 OK 2022/01/28 06:31:15 DEBUG : four: Size and quickxor of src and dst objects identical 2022/01/28 06:31:15 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2022/01/28 06:31:15 DEBUG : three: quickxor = 7440831cca500600000000000500000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:31:15 DEBUG : three: quickxor = 544082148a500400000000000500000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:31:15 DEBUG : three: quickxor differ 2022/01/28 06:31:15 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2022/01/28 06:31:15 DEBUG : three: Starting multipart upload 2022/01/28 06:31:15 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2022/01/28 06:31:15 DEBUG : two: Size and quickxor of src and dst objects identical 2022/01/28 06:31:15 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2022/01/28 06:31:15 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:15 DEBUG : three: Uploading segment 0/5 size 5 2022/01/28 06:31:15 DEBUG : three: quickxor = 7440831cca500600000000000500000000000000 OK 2022/01/28 06:31:15 INFO : three: Copied (replaced existing) 2022/01/28 06:31:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (9.81s) === RUN TestSyncWithMaxDuration sync_test.go:1009: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" sync_test.go:1058: Can track renames: true 2022/01/28 06:31:18 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Making map for --track-renames 2022/01/28 06:31:18 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Finished making map for --track-renames 2022/01/28 06:31:18 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:18 DEBUG : potato: Starting multipart upload 2022/01/28 06:31:18 DEBUG : yam: Starting multipart upload 2022/01/28 06:31:18 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for renames to finish 2022/01/28 06:31:18 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:18 DEBUG : yam: Uploading segment 0/11 size 11 2022/01/28 06:31:18 DEBUG : potato: Uploading segment 0/14 size 14 2022/01/28 06:31:19 DEBUG : yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2022/01/28 06:31:19 INFO : yam: Copied (new) 2022/01/28 06:31:19 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2022/01/28 06:31:19 INFO : potato: Copied (new) 2022/01/28 06:31:19 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:19 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:31:19 DEBUG : potato: Unchanged skipping 2022/01/28 06:31:19 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Making map for --track-renames 2022/01/28 06:31:19 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Finished making map for --track-renames 2022/01/28 06:31:19 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:19 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for renames to finish 2022/01/28 06:31:20 INFO : yam: Moved (server-side) to: yaml 2022/01/28 06:31:20 INFO : yaml: Renamed from "yam" 2022/01/28 06:31:20 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:20 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:20 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenames (4.12s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" sync_test.go:1127: Can track renames: true 2022/01/28 06:31:22 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Making map for --track-renames 2022/01/28 06:31:22 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Finished making map for --track-renames 2022/01/28 06:31:22 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:22 DEBUG : potato: Starting multipart upload 2022/01/28 06:31:22 DEBUG : yam: Starting multipart upload 2022/01/28 06:31:22 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for renames to finish 2022/01/28 06:31:22 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:22 DEBUG : potato: Uploading segment 0/14 size 14 2022/01/28 06:31:22 DEBUG : yam: Uploading segment 0/11 size 11 2022/01/28 06:31:23 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2022/01/28 06:31:23 INFO : potato: Copied (new) 2022/01/28 06:31:23 DEBUG : yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2022/01/28 06:31:23 INFO : yam: Copied (new) 2022/01/28 06:31:23 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:23 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:31:23 DEBUG : potato: Unchanged skipping 2022/01/28 06:31:23 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Making map for --track-renames 2022/01/28 06:31:23 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Finished making map for --track-renames 2022/01/28 06:31:23 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:23 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for renames to finish 2022/01/28 06:31:24 INFO : yam: Moved (server-side) to: yaml 2022/01/28 06:31:24 INFO : yaml: Renamed from "yam" 2022/01/28 06:31:24 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:24 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:24 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyModtime (4.92s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" sync_test.go:1163: Can track renames: true 2022/01/28 06:31:27 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Making map for --track-renames 2022/01/28 06:31:27 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Finished making map for --track-renames 2022/01/28 06:31:27 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:27 DEBUG : potato: Starting multipart upload 2022/01/28 06:31:27 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for renames to finish 2022/01/28 06:31:27 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:27 DEBUG : potato: Uploading segment 0/14 size 14 2022/01/28 06:31:28 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2022/01/28 06:31:28 INFO : potato: Copied (new) 2022/01/28 06:31:28 DEBUG : sub/yam: Starting multipart upload 2022/01/28 06:31:29 DEBUG : sub/yam: Uploading segment 0/11 size 11 2022/01/28 06:31:29 DEBUG : sub/yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2022/01/28 06:31:29 INFO : sub/yam: Copied (new) 2022/01/28 06:31:29 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:30 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:31:30 DEBUG : potato: Unchanged skipping 2022/01/28 06:31:30 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Making map for --track-renames 2022/01/28 06:31:30 INFO : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Finished making map for --track-renames 2022/01/28 06:31:30 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:30 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for renames to finish 2022/01/28 06:31:31 INFO : sub/yam: Moved (server-side) to: yam 2022/01/28 06:31:31 INFO : yam: Renamed from "sub/yam" 2022/01/28 06:31:31 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:31 DEBUG : Waiting for deletions to finish 2022/01/28 06:31:31 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyLeaf (7.20s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:31:34 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:34 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:34 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:31:35 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:31:35 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:31:35 INFO : sub dir/hello world: Copied (new) 2022/01/28 06:31:35 INFO : sub dir/hello world: Deleted 2022/01/28 06:31:36 DEBUG : nested/sub dir/file: Starting multipart upload 2022/01/28 06:31:36 DEBUG : nested/sub dir/file: Uploading segment 0/6 size 6 2022/01/28 06:31:37 DEBUG : nested/sub dir/file: quickxor = 6e28c31ce8500632000000000600000000000000 OK 2022/01/28 06:31:37 INFO : nested/sub dir/file: Copied (new) 2022/01/28 06:31:37 INFO : nested/sub dir/file: Deleted 2022/01/28 06:31:37 INFO : sub dir: Removing directory 2022/01/28 06:31:37 INFO : nested/sub dir: Removing directory 2022/01/28 06:31:37 INFO : nested: Removing directory 2022/01/28 06:31:37 DEBUG : Local file system at /tmp/rclone1817180687: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (7.83s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:31:42 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:42 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:42 DEBUG : sub dir/hello world: Starting multipart upload 2022/01/28 06:31:43 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2022/01/28 06:31:43 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2022/01/28 06:31:43 INFO : sub dir/hello world: Copied (new) 2022/01/28 06:31:43 INFO : sub dir/hello world: Deleted 2022/01/28 06:31:43 DEBUG : nested/sub dir/file: Starting multipart upload 2022/01/28 06:31:44 DEBUG : nested/sub dir/file: Uploading segment 0/6 size 6 2022/01/28 06:31:45 DEBUG : nested/sub dir/file: quickxor = 6e28c31ce8500632000000000600000000000000 OK 2022/01/28 06:31:45 INFO : nested/sub dir/file: Copied (new) 2022/01/28 06:31:45 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (6.67s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:31:48 DEBUG : existing: Starting multipart upload 2022/01/28 06:31:48 DEBUG : existing-b: Starting multipart upload 2022/01/28 06:31:48 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:48 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:49 DEBUG : existing: Uploading segment 0/6 size 6 2022/01/28 06:31:49 DEBUG : existing-b: Uploading segment 0/6 size 6 2022/01/28 06:31:49 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2022/01/28 06:31:49 INFO : existing: Copied (new) 2022/01/28 06:31:49 INFO : existing: Deleted 2022/01/28 06:31:50 DEBUG : existing-b: quickxor = 7478431bc2408737000000000600000000000000 OK 2022/01/28 06:31:50 INFO : existing-b: Copied (new) 2022/01/28 06:31:50 INFO : existing-b: Deleted 2022/01/28 06:31:50 DEBUG : existing: Destination exists, skipping 2022/01/28 06:31:50 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/01/28 06:31:50 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:31:50 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:31:50 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (3.42s) === RUN TestServerSideMove run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:31:51 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-qefiton2naqeqon8cojucam8" 2022/01/28 06:31:52 DEBUG : potato2: Starting multipart upload 2022/01/28 06:31:53 DEBUG : potato2: Uploading segment 0/60 size 60 2022/01/28 06:31:53 DEBUG : empty space: Starting multipart upload 2022/01/28 06:31:54 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:31:54 DEBUG : potato3: Starting multipart upload 2022/01/28 06:31:55 DEBUG : potato3: Uploading segment 0/68 size 68 sync_test.go:1215: Server side move (if possible) One drive root 'rclone-test-luqopom7vininaj4muvivoc0' -> One drive root 'rclone-test-qefiton2naqeqon8cojucam8' 2022/01/28 06:31:56 DEBUG : empty space: Starting multipart upload 2022/01/28 06:31:57 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:31:57 DEBUG : potato3: Starting multipart upload 2022/01/28 06:31:58 DEBUG : potato3: Uploading segment 0/60 size 60 2022/01/28 06:31:58 DEBUG : One drive root 'rclone-test-qefiton2naqeqon8cojucam8': Using server-side directory move 2022/01/28 06:31:58 INFO : One drive root 'rclone-test-qefiton2naqeqon8cojucam8': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/01/28 06:31:59 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2022/01/28 06:31:59 DEBUG : empty space: Unchanged skipping 2022/01/28 06:31:59 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/28 06:31:59 DEBUG : One drive root 'rclone-test-qefiton2naqeqon8cojucam8': Waiting for checks to finish 2022/01/28 06:31:59 INFO : empty space: Deleted 2022/01/28 06:31:59 DEBUG : One drive root 'rclone-test-qefiton2naqeqon8cojucam8': Waiting for transfers to finish 2022/01/28 06:31:59 INFO : potato3: Deleted 2022/01/28 06:31:59 INFO : potato2: Moved (server-side) 2022/01/28 06:31:59 INFO : potato3: Moved (server-side) 2022/01/28 06:31:59 INFO : There was nothing to transfer 2022/01/28 06:32:00 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-navorud3yepecot1vagiduw2" 2022/01/28 06:32:01 DEBUG : One drive root 'rclone-test-navorud3yepecot1vagiduw2': Using server-side directory move 2022/01/28 06:32:01 INFO : One drive root 'rclone-test-navorud3yepecot1vagiduw2': Server side directory move succeeded 2022/01/28 06:32:02 ERROR : : error listing: directory not found 2022/01/28 06:32:02 DEBUG : One drive root 'rclone-test-navorud3yepecot1vagiduw2': Purge remote 2022/01/28 06:32:03 DEBUG : One drive root 'rclone-test-qefiton2naqeqon8cojucam8': Purge remote 2022/01/28 06:32:03 purge failed: directory not found --- PASS: TestServerSideMove (11.89s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:32:03 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-coxosev1tecutur2licefip2" 2022/01/28 06:32:04 DEBUG : potato2: Starting multipart upload 2022/01/28 06:32:05 DEBUG : potato2: Uploading segment 0/60 size 60 2022/01/28 06:32:05 DEBUG : empty space: Starting multipart upload 2022/01/28 06:32:06 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:32:06 DEBUG : potato3: Starting multipart upload 2022/01/28 06:32:07 DEBUG : potato3: Uploading segment 0/68 size 68 sync_test.go:1215: Server side move (if possible) One drive root 'rclone-test-luqopom7vininaj4muvivoc0' -> One drive root 'rclone-test-coxosev1tecutur2licefip2' 2022/01/28 06:32:08 DEBUG : empty space: Starting multipart upload 2022/01/28 06:32:09 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:32:09 DEBUG : potato3: Starting multipart upload 2022/01/28 06:32:10 DEBUG : potato3: Uploading segment 0/60 size 60 2022/01/28 06:32:11 DEBUG : empty space: Excluded 2022/01/28 06:32:11 DEBUG : empty space: Excluded 2022/01/28 06:32:11 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/28 06:32:11 DEBUG : One drive root 'rclone-test-coxosev1tecutur2licefip2': Waiting for checks to finish 2022/01/28 06:32:11 DEBUG : One drive root 'rclone-test-coxosev1tecutur2licefip2': Waiting for transfers to finish 2022/01/28 06:32:11 INFO : potato3: Deleted 2022/01/28 06:32:11 INFO : potato2: Moved (server-side) 2022/01/28 06:32:12 INFO : potato3: Moved (server-side) 2022/01/28 06:32:12 INFO : There was nothing to transfer 2022/01/28 06:32:12 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-xujafob1kimabeg3wupohop6" 2022/01/28 06:32:13 DEBUG : empty space: Excluded 2022/01/28 06:32:13 DEBUG : One drive root 'rclone-test-xujafob1kimabeg3wupohop6': Waiting for checks to finish 2022/01/28 06:32:13 DEBUG : One drive root 'rclone-test-xujafob1kimabeg3wupohop6': Waiting for transfers to finish 2022/01/28 06:32:15 INFO : potato2: Moved (server-side) 2022/01/28 06:32:15 INFO : potato3: Moved (server-side) 2022/01/28 06:32:15 INFO : There was nothing to transfer 2022/01/28 06:32:16 DEBUG : One drive root 'rclone-test-xujafob1kimabeg3wupohop6': Purge remote 2022/01/28 06:32:17 DEBUG : One drive root 'rclone-test-coxosev1tecutur2licefip2': Purge remote --- PASS: TestServerSideMoveWithFilter (15.51s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:32:19 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-zupehed9zobikel8juxiqep9" 2022/01/28 06:32:20 DEBUG : potato2: Starting multipart upload 2022/01/28 06:32:21 DEBUG : potato2: Uploading segment 0/60 size 60 2022/01/28 06:32:22 DEBUG : empty space: Starting multipart upload 2022/01/28 06:32:22 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:32:23 DEBUG : potato3: Starting multipart upload 2022/01/28 06:32:24 DEBUG : potato3: Uploading segment 0/68 size 68 2022/01/28 06:32:24 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) One drive root 'rclone-test-luqopom7vininaj4muvivoc0' -> One drive root 'rclone-test-zupehed9zobikel8juxiqep9' 2022/01/28 06:32:27 DEBUG : empty space: Starting multipart upload 2022/01/28 06:32:28 DEBUG : empty space: Uploading segment 0/1 size 1 2022/01/28 06:32:29 DEBUG : potato3: Starting multipart upload 2022/01/28 06:32:29 DEBUG : potato3: Uploading segment 0/60 size 60 2022/01/28 06:32:30 DEBUG : One drive root 'rclone-test-zupehed9zobikel8juxiqep9': Using server-side directory move 2022/01/28 06:32:30 INFO : One drive root 'rclone-test-zupehed9zobikel8juxiqep9': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2022/01/28 06:32:31 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2022/01/28 06:32:31 DEBUG : empty space: Unchanged skipping 2022/01/28 06:32:31 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/28 06:32:31 INFO : potato3: Deleted 2022/01/28 06:32:31 INFO : potato2: Moved (server-side) 2022/01/28 06:32:31 DEBUG : One drive root 'rclone-test-zupehed9zobikel8juxiqep9': Waiting for checks to finish 2022/01/28 06:32:31 INFO : empty space: Deleted 2022/01/28 06:32:31 DEBUG : One drive root 'rclone-test-zupehed9zobikel8juxiqep9': Waiting for transfers to finish 2022/01/28 06:32:32 INFO : potato3: Moved (server-side) 2022/01/28 06:32:32 INFO : tomatoDir: Removing directory 2022/01/28 06:32:33 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': deleted 1 directories 2022/01/28 06:32:33 INFO : There was nothing to transfer 2022/01/28 06:32:34 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-jidivis0joxavip7wiyehow6" 2022/01/28 06:32:35 DEBUG : tomatoDir: Making directory 2022/01/28 06:32:36 DEBUG : One drive root 'rclone-test-jidivis0joxavip7wiyehow6': Using server-side directory move 2022/01/28 06:32:38 INFO : One drive root 'rclone-test-jidivis0joxavip7wiyehow6': Server side directory move succeeded 2022/01/28 06:32:40 ERROR : : error listing: directory not found 2022/01/28 06:32:41 ERROR : : error listing: directory not found 2022/01/28 06:32:41 DEBUG : One drive root 'rclone-test-jidivis0joxavip7wiyehow6': Purge remote 2022/01/28 06:32:41 DEBUG : One drive root 'rclone-test-zupehed9zobikel8juxiqep9': Purge remote 2022/01/28 06:32:41 purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (23.66s) === RUN TestServerSideMoveOverlap run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" sync_test.go:1399: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.77s) === RUN TestSyncOverlap run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:32:43 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/rclone-sync-test" --- PASS: TestSyncOverlap (3.46s) === RUN TestSyncCompareDest run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:32:47 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dst" 2022/01/28 06:32:49 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/CompareDest" 2022/01/28 06:32:52 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:32:52 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:32:53 DEBUG : one: Starting multipart upload 2022/01/28 06:32:54 DEBUG : one: Uploading segment 0/3 size 3 2022/01/28 06:32:55 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK 2022/01/28 06:32:55 INFO : one: Copied (new) 2022/01/28 06:32:55 DEBUG : Waiting for deletions to finish 2022/01/28 06:32:56 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:32:57 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/28 06:32:57 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:32:57 DEBUG : one: Starting multipart upload 2022/01/28 06:32:58 DEBUG : one: Uploading segment 0/5 size 5 2022/01/28 06:32:58 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2022/01/28 06:32:58 INFO : one: Copied (replaced existing) 2022/01/28 06:32:58 DEBUG : Waiting for deletions to finish 2022/01/28 06:32:59 DEBUG : dst/one: Starting multipart upload 2022/01/28 06:32:59 DEBUG : dst/one: Uploading segment 0/3 size 3 2022/01/28 06:33:00 DEBUG : CompareDest/one: Starting multipart upload 2022/01/28 06:33:02 DEBUG : CompareDest/one: Uploading segment 0/5 size 5 2022/01/28 06:33:04 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:33:05 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:33:05 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/28 06:33:05 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:33:05 DEBUG : Waiting for deletions to finish 2022/01/28 06:33:05 INFO : There was nothing to transfer 2022/01/28 06:33:06 DEBUG : CompareDest/two: Starting multipart upload 2022/01/28 06:33:07 DEBUG : CompareDest/two: Uploading segment 0/3 size 3 2022/01/28 06:33:10 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:33:10 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/28 06:33:10 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:33:12 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:33:12 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/28 06:33:12 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:33:12 DEBUG : Waiting for deletions to finish 2022/01/28 06:33:12 INFO : There was nothing to transfer 2022/01/28 06:33:14 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:33:14 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/28 06:33:14 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:33:15 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:33:15 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/28 06:33:15 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:33:15 DEBUG : Waiting for deletions to finish 2022/01/28 06:33:15 INFO : There was nothing to transfer 2022/01/28 06:33:19 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC 2022/01/28 06:33:19 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2022/01/28 06:33:19 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/28 06:33:19 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:33:19 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:33:19 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/28 06:33:19 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:33:19 DEBUG : Waiting for deletions to finish 2022/01/28 06:33:19 INFO : There was nothing to transfer 2022/01/28 06:33:23 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/01/28 06:33:23 DEBUG : two: Starting multipart upload 2022/01/28 06:33:23 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:33:24 DEBUG : two: Uploading segment 0/5 size 5 2022/01/28 06:33:24 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:33:24 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/28 06:33:24 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:33:24 DEBUG : two: quickxor = 74b8c31be8300300000000000500000000000000 OK 2022/01/28 06:33:24 INFO : two: Copied (new) 2022/01/28 06:33:24 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (42.46s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:33:30 DEBUG : pre-dest1/1: Starting multipart upload 2022/01/28 06:33:31 DEBUG : pre-dest1/1: Uploading segment 0/1 size 1 2022/01/28 06:33:32 DEBUG : pre-dest2/2: Starting multipart upload 2022/01/28 06:33:33 DEBUG : pre-dest2/2: Uploading segment 0/1 size 1 2022/01/28 06:33:34 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dest" 2022/01/28 06:33:37 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/pre-dest1" 2022/01/28 06:33:38 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/pre-dest2" 2022/01/28 06:33:40 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:33:40 DEBUG : 1: Destination found in --compare-dest, skipping 2022/01/28 06:33:41 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:33:41 DEBUG : 2: Destination found in --compare-dest, skipping 2022/01/28 06:33:43 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dest': Waiting for checks to finish 2022/01/28 06:33:43 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dest': Waiting for transfers to finish 2022/01/28 06:33:44 DEBUG : 3: Starting multipart upload 2022/01/28 06:33:45 DEBUG : 3: Uploading segment 0/1 size 1 2022/01/28 06:33:46 DEBUG : 3: quickxor = 3300000000000000000000000100000000000000 OK 2022/01/28 06:33:46 INFO : 3: Copied (new) 2022/01/28 06:33:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (20.74s) === RUN TestSyncCopyDest run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:33:50 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dst" 2022/01/28 06:33:52 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/CopyDest" 2022/01/28 06:33:55 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:33:55 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:33:55 DEBUG : one: Starting multipart upload 2022/01/28 06:33:56 DEBUG : one: Uploading segment 0/3 size 3 2022/01/28 06:33:56 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK 2022/01/28 06:33:56 INFO : one: Copied (new) 2022/01/28 06:33:56 DEBUG : Waiting for deletions to finish 2022/01/28 06:33:58 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:33:59 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/28 06:33:59 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:33:59 DEBUG : one: Starting multipart upload 2022/01/28 06:33:59 DEBUG : one: Uploading segment 0/5 size 5 2022/01/28 06:33:59 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2022/01/28 06:33:59 INFO : one: Copied (replaced existing) 2022/01/28 06:33:59 DEBUG : Waiting for deletions to finish 2022/01/28 06:34:00 DEBUG : dst/one: Starting multipart upload 2022/01/28 06:34:00 DEBUG : dst/one: Uploading segment 0/3 size 3 2022/01/28 06:34:02 DEBUG : CopyDest/one: Starting multipart upload 2022/01/28 06:34:02 DEBUG : CopyDest/one: Uploading segment 0/5 size 5 2022/01/28 06:34:03 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/BackupDir" 2022/01/28 06:34:06 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:34:06 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:06 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/28 06:34:08 INFO : one: Moved (server-side) 2022/01/28 06:34:11 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2022/01/28 06:34:11 INFO : one: Copied (server-side copy) 2022/01/28 06:34:11 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/01/28 06:34:11 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:34:11 DEBUG : Waiting for deletions to finish 2022/01/28 06:34:12 DEBUG : CopyDest/two: Starting multipart upload 2022/01/28 06:34:12 DEBUG : CopyDest/two: Uploading segment 0/3 size 3 2022/01/28 06:34:15 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:22 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2022/01/28 06:34:22 INFO : two: Copied (server-side copy) 2022/01/28 06:34:22 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/01/28 06:34:22 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:34:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:23 DEBUG : one: Unchanged skipping 2022/01/28 06:34:23 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:34:23 DEBUG : Waiting for deletions to finish 2022/01/28 06:34:25 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:34:26 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:26 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:26 DEBUG : one: Unchanged skipping 2022/01/28 06:34:26 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:26 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:26 DEBUG : two: Unchanged skipping 2022/01/28 06:34:26 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:34:26 DEBUG : Waiting for deletions to finish 2022/01/28 06:34:26 INFO : There was nothing to transfer 2022/01/28 06:34:27 DEBUG : CopyDest/three: Starting multipart upload 2022/01/28 06:34:28 DEBUG : CopyDest/three: Uploading segment 0/5 size 5 2022/01/28 06:34:32 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/01/28 06:34:32 DEBUG : three: Destination not found in --copy-dest 2022/01/28 06:34:32 DEBUG : three: Starting multipart upload 2022/01/28 06:34:32 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:34:32 DEBUG : three: Uploading segment 0/7 size 7 2022/01/28 06:34:33 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:33 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:33 DEBUG : one: Unchanged skipping 2022/01/28 06:34:33 DEBUG : three: quickxor = 7440831cca50063acc0000000700000000000000 OK 2022/01/28 06:34:33 INFO : three: Copied (new) 2022/01/28 06:34:33 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:33 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2022/01/28 06:34:33 DEBUG : two: Unchanged skipping 2022/01/28 06:34:33 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:34:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (49.11s) === RUN TestSyncBackupDir run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:34:40 DEBUG : dst/one: Starting multipart upload 2022/01/28 06:34:41 DEBUG : dst/one: Uploading segment 0/3 size 3 2022/01/28 06:34:41 DEBUG : dst/two: Starting multipart upload 2022/01/28 06:34:42 DEBUG : dst/two: Uploading segment 0/3 size 3 2022/01/28 06:34:43 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:34:44 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2022/01/28 06:34:46 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dst" 2022/01/28 06:34:47 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/backup" 2022/01/28 06:34:50 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/28 06:34:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:34:50 DEBUG : two: Unchanged skipping 2022/01/28 06:34:50 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:34:52 INFO : one: Moved (server-side) 2022/01/28 06:34:52 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:34:52 DEBUG : one: Starting multipart upload 2022/01/28 06:34:54 DEBUG : one: Uploading segment 0/4 size 4 2022/01/28 06:34:55 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2022/01/28 06:34:55 INFO : one: Copied (new) 2022/01/28 06:34:55 DEBUG : Waiting for deletions to finish 2022/01/28 06:34:56 INFO : three.txt: Moved (server-side) 2022/01/28 06:34:56 INFO : three.txt: Moved into backup dir 2022/01/28 06:34:57 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:34:58 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2022/01/28 06:35:00 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/28 06:35:00 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:35:00 DEBUG : two: Unchanged skipping 2022/01/28 06:35:00 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:35:01 INFO : one: Deleted 2022/01/28 06:35:01 INFO : one: Moved (server-side) 2022/01/28 06:35:01 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:35:01 DEBUG : one: Starting multipart upload 2022/01/28 06:35:02 DEBUG : one: Uploading segment 0/5 size 5 2022/01/28 06:35:02 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2022/01/28 06:35:02 INFO : one: Copied (new) 2022/01/28 06:35:02 DEBUG : Waiting for deletions to finish 2022/01/28 06:35:04 INFO : three.txt: Deleted 2022/01/28 06:35:04 INFO : three.txt: Moved (server-side) 2022/01/28 06:35:04 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (28.99s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:35:09 DEBUG : dst/one: Starting multipart upload 2022/01/28 06:35:10 DEBUG : dst/one: Uploading segment 0/3 size 3 2022/01/28 06:35:10 DEBUG : dst/two: Starting multipart upload 2022/01/28 06:35:11 DEBUG : dst/two: Uploading segment 0/3 size 3 2022/01/28 06:35:11 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:35:12 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2022/01/28 06:35:13 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dst" 2022/01/28 06:35:14 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/backup" 2022/01/28 06:35:17 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/28 06:35:17 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:35:17 DEBUG : two: Unchanged skipping 2022/01/28 06:35:17 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:35:19 INFO : one: Moved (server-side) to: one.bak 2022/01/28 06:35:19 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:35:19 DEBUG : one: Starting multipart upload 2022/01/28 06:35:19 DEBUG : one: Uploading segment 0/4 size 4 2022/01/28 06:35:20 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2022/01/28 06:35:20 INFO : one: Copied (new) 2022/01/28 06:35:20 DEBUG : Waiting for deletions to finish 2022/01/28 06:35:21 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/01/28 06:35:21 INFO : three.txt: Moved into backup dir 2022/01/28 06:35:22 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:35:22 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2022/01/28 06:35:24 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:35:24 DEBUG : two: Unchanged skipping 2022/01/28 06:35:24 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/28 06:35:24 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:35:25 INFO : one.bak: Deleted 2022/01/28 06:35:25 INFO : one: Moved (server-side) to: one.bak 2022/01/28 06:35:25 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:35:25 DEBUG : one: Starting multipart upload 2022/01/28 06:35:26 DEBUG : one: Uploading segment 0/5 size 5 2022/01/28 06:35:26 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2022/01/28 06:35:26 INFO : one: Copied (new) 2022/01/28 06:35:26 DEBUG : Waiting for deletions to finish 2022/01/28 06:35:28 INFO : three.txt.bak: Deleted 2022/01/28 06:35:28 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/01/28 06:35:28 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (23.17s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:35:32 DEBUG : dst/one: Starting multipart upload 2022/01/28 06:35:32 DEBUG : dst/one: Uploading segment 0/3 size 3 2022/01/28 06:35:33 DEBUG : dst/two: Starting multipart upload 2022/01/28 06:35:33 DEBUG : dst/two: Uploading segment 0/3 size 3 2022/01/28 06:35:34 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:35:34 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2022/01/28 06:35:36 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dst" 2022/01/28 06:35:36 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/backup" 2022/01/28 06:35:38 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/28 06:35:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:35:38 DEBUG : two: Unchanged skipping 2022/01/28 06:35:38 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:35:40 INFO : one: Moved (server-side) to: one-2019-01-01 2022/01/28 06:35:40 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:35:40 DEBUG : one: Starting multipart upload 2022/01/28 06:35:41 DEBUG : one: Uploading segment 0/4 size 4 2022/01/28 06:35:41 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2022/01/28 06:35:41 INFO : one: Copied (new) 2022/01/28 06:35:41 DEBUG : Waiting for deletions to finish 2022/01/28 06:35:42 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/01/28 06:35:42 INFO : three.txt: Moved into backup dir 2022/01/28 06:35:43 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:35:43 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2022/01/28 06:35:45 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/28 06:35:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:35:45 DEBUG : two: Unchanged skipping 2022/01/28 06:35:45 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:35:46 INFO : one-2019-01-01: Deleted 2022/01/28 06:35:46 INFO : one: Moved (server-side) to: one-2019-01-01 2022/01/28 06:35:46 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:35:46 DEBUG : one: Starting multipart upload 2022/01/28 06:35:47 DEBUG : one: Uploading segment 0/5 size 5 2022/01/28 06:35:47 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2022/01/28 06:35:47 INFO : one: Copied (new) 2022/01/28 06:35:47 DEBUG : Waiting for deletions to finish 2022/01/28 06:35:48 INFO : three-2019-01-01.txt: Deleted 2022/01/28 06:35:49 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/01/28 06:35:49 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (20.57s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:35:52 DEBUG : dst/one: Starting multipart upload 2022/01/28 06:35:53 DEBUG : dst/one: Uploading segment 0/3 size 3 2022/01/28 06:35:54 DEBUG : dst/two: Starting multipart upload 2022/01/28 06:35:54 DEBUG : dst/two: Uploading segment 0/3 size 3 2022/01/28 06:35:55 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:35:55 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2022/01/28 06:35:56 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dst" 2022/01/28 06:35:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/28 06:35:58 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:35:58 DEBUG : two: Unchanged skipping 2022/01/28 06:35:58 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:35:58 INFO : one: Moved (server-side) to: one.bak 2022/01/28 06:35:58 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:35:58 DEBUG : one: Starting multipart upload 2022/01/28 06:35:59 DEBUG : one: Uploading segment 0/4 size 4 2022/01/28 06:36:00 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2022/01/28 06:36:00 INFO : one: Copied (new) 2022/01/28 06:36:00 DEBUG : Waiting for deletions to finish 2022/01/28 06:36:00 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/01/28 06:36:00 INFO : three.txt: Moved into backup dir 2022/01/28 06:36:01 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:36:02 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2022/01/28 06:36:03 DEBUG : one.bak: Excluded 2022/01/28 06:36:03 DEBUG : three.txt.bak: Excluded 2022/01/28 06:36:03 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/28 06:36:03 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for checks to finish 2022/01/28 06:36:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:36:03 DEBUG : two: Unchanged skipping 2022/01/28 06:36:04 INFO : one.bak: Deleted 2022/01/28 06:36:04 INFO : one: Moved (server-side) to: one.bak 2022/01/28 06:36:04 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0/dst': Waiting for transfers to finish 2022/01/28 06:36:04 DEBUG : one: Starting multipart upload 2022/01/28 06:36:05 DEBUG : one: Uploading segment 0/5 size 5 2022/01/28 06:36:05 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2022/01/28 06:36:05 INFO : one: Copied (new) 2022/01/28 06:36:05 DEBUG : Waiting for deletions to finish 2022/01/28 06:36:06 INFO : three.txt.bak: Deleted 2022/01/28 06:36:06 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/01/28 06:36:06 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (16.98s) === RUN TestSyncSuffix run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:36:09 DEBUG : dst/one: Starting multipart upload 2022/01/28 06:36:10 DEBUG : dst/one: Uploading segment 0/3 size 3 2022/01/28 06:36:10 DEBUG : dst/two: Starting multipart upload 2022/01/28 06:36:11 DEBUG : dst/two: Uploading segment 0/3 size 3 2022/01/28 06:36:11 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:36:12 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2022/01/28 06:36:13 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dst" 2022/01/28 06:36:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/28 06:36:16 INFO : one: Moved (server-side) to: one.bak 2022/01/28 06:36:16 DEBUG : one: Starting multipart upload 2022/01/28 06:36:16 DEBUG : one: Uploading segment 0/4 size 4 2022/01/28 06:36:17 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2022/01/28 06:36:17 INFO : one: Copied (new) 2022/01/28 06:36:17 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:36:17 DEBUG : two: Unchanged skipping 2022/01/28 06:36:18 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/28 06:36:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/01/28 06:36:19 DEBUG : three.txt: Starting multipart upload 2022/01/28 06:36:19 DEBUG : three.txt: Uploading segment 0/6 size 6 2022/01/28 06:36:20 DEBUG : three.txt: quickxor = 7440831cca508620000000000600000000000000 OK 2022/01/28 06:36:20 INFO : three.txt: Copied (new) 2022/01/28 06:36:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/28 06:36:22 INFO : one.bak: Deleted 2022/01/28 06:36:22 INFO : one: Moved (server-side) to: one.bak 2022/01/28 06:36:22 DEBUG : one: Starting multipart upload 2022/01/28 06:36:23 DEBUG : one: Uploading segment 0/5 size 5 2022/01/28 06:36:24 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2022/01/28 06:36:24 INFO : one: Copied (new) 2022/01/28 06:36:24 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:36:24 DEBUG : two: Unchanged skipping 2022/01/28 06:36:25 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/28 06:36:26 INFO : three.txt.bak: Deleted 2022/01/28 06:36:26 INFO : three.txt: Moved (server-side) to: three.txt.bak 2022/01/28 06:36:26 DEBUG : three.txt: Starting multipart upload 2022/01/28 06:36:26 DEBUG : three.txt: Uploading segment 0/19 size 19 2022/01/28 06:36:27 DEBUG : three.txt: quickxor = 154aeacc8949062110210d66234319e4500637d0 OK 2022/01/28 06:36:27 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (21.08s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:36:30 DEBUG : dst/one: Starting multipart upload 2022/01/28 06:36:31 DEBUG : dst/one: Uploading segment 0/3 size 3 2022/01/28 06:36:32 DEBUG : dst/two: Starting multipart upload 2022/01/28 06:36:32 DEBUG : dst/two: Uploading segment 0/3 size 3 2022/01/28 06:36:33 DEBUG : dst/three.txt: Starting multipart upload 2022/01/28 06:36:33 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2022/01/28 06:36:34 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-luqopom7vininaj4muvivoc0/dst" 2022/01/28 06:36:36 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/28 06:36:36 INFO : one: Moved (server-side) to: one-2019-01-01 2022/01/28 06:36:36 DEBUG : one: Starting multipart upload 2022/01/28 06:36:37 DEBUG : one: Uploading segment 0/4 size 4 2022/01/28 06:36:37 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2022/01/28 06:36:37 INFO : one: Copied (new) 2022/01/28 06:36:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:36:38 DEBUG : two: Unchanged skipping 2022/01/28 06:36:39 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/28 06:36:39 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/01/28 06:36:39 DEBUG : three.txt: Starting multipart upload 2022/01/28 06:36:40 DEBUG : three.txt: Uploading segment 0/6 size 6 2022/01/28 06:36:40 DEBUG : three.txt: quickxor = 7440831cca508620000000000600000000000000 OK 2022/01/28 06:36:40 INFO : three.txt: Copied (new) 2022/01/28 06:36:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/28 06:36:43 INFO : one-2019-01-01: Deleted 2022/01/28 06:36:43 INFO : one: Moved (server-side) to: one-2019-01-01 2022/01/28 06:36:43 DEBUG : one: Starting multipart upload 2022/01/28 06:36:44 DEBUG : one: Uploading segment 0/5 size 5 2022/01/28 06:36:44 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2022/01/28 06:36:44 INFO : one: Copied (new) 2022/01/28 06:36:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:36:45 DEBUG : two: Unchanged skipping 2022/01/28 06:36:47 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/28 06:36:48 INFO : three-2019-01-01.txt: Deleted 2022/01/28 06:36:49 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2022/01/28 06:36:49 DEBUG : three.txt: Starting multipart upload 2022/01/28 06:36:50 DEBUG : three.txt: Uploading segment 0/19 size 19 2022/01/28 06:36:50 DEBUG : three.txt: quickxor = 154aeacc8949062110210d66234319e4500637d0 OK 2022/01/28 06:36:50 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (24.82s) === RUN TestSyncUTFNorm run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:36:55 DEBUG : Testêé: Starting multipart upload 2022/01/28 06:36:55 DEBUG : Testêé: Uploading segment 0/18 size 18 2022/01/28 06:36:57 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/01/28 06:36:57 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:36:57 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:36:57 DEBUG : Testêé: Starting multipart upload 2022/01/28 06:36:58 DEBUG : Testêé: Uploading segment 0/14 size 14 2022/01/28 06:36:58 DEBUG : Testêé: quickxor = 5440431ae6008234cc0104610e011dca30073a00 OK 2022/01/28 06:36:58 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/01/28 06:36:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (5.32s) === RUN TestSyncImmutable run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:37:01 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:37:01 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:37:01 DEBUG : existing: Starting multipart upload 2022/01/28 06:37:02 DEBUG : existing: Uploading segment 0/6 size 6 2022/01/28 06:37:02 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2022/01/28 06:37:02 INFO : existing: Copied (new) 2022/01/28 06:37:02 DEBUG : Waiting for deletions to finish 2022/01/28 06:37:03 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/01/28 06:37:03 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/01/28 06:37:03 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:37:03 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:37:03 ERROR : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': not deleting files as there were IO errors 2022/01/28 06:37:03 ERROR : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (5.49s) === RUN TestSyncIgnoreCase run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" sync_test.go:1955: Skipping test as local or remote are case-insensitive --- SKIP: TestSyncIgnoreCase (1.04s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (2.60s) --- SKIP: TestMaxTransfer/Hard (0.78s) --- SKIP: TestMaxTransfer/Soft (0.78s) --- SKIP: TestMaxTransfer/Cautious (1.04s) === RUN TestSyncConcurrentDelete run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:37:09 DEBUG : both0: Starting multipart upload 2022/01/28 06:37:10 DEBUG : both0: Uploading segment 0/6 size 6 2022/01/28 06:37:10 DEBUG : only0: Starting multipart upload 2022/01/28 06:37:11 DEBUG : only0: Uploading segment 0/6 size 6 2022/01/28 06:37:12 DEBUG : both1: Starting multipart upload 2022/01/28 06:37:13 DEBUG : both1: Uploading segment 0/6 size 6 2022/01/28 06:37:13 DEBUG : only1: Starting multipart upload 2022/01/28 06:37:14 DEBUG : only1: Uploading segment 0/6 size 6 2022/01/28 06:37:14 DEBUG : both2: Starting multipart upload 2022/01/28 06:37:15 DEBUG : both2: Uploading segment 0/6 size 6 2022/01/28 06:37:16 DEBUG : only2: Starting multipart upload 2022/01/28 06:37:17 DEBUG : only2: Uploading segment 0/6 size 6 2022/01/28 06:37:17 DEBUG : both3: Starting multipart upload 2022/01/28 06:37:18 DEBUG : both3: Uploading segment 0/6 size 6 2022/01/28 06:37:19 DEBUG : only3: Starting multipart upload 2022/01/28 06:37:20 DEBUG : only3: Uploading segment 0/6 size 6 2022/01/28 06:37:20 DEBUG : both4: Starting multipart upload 2022/01/28 06:37:21 DEBUG : both4: Uploading segment 0/6 size 6 2022/01/28 06:37:21 DEBUG : only4: Starting multipart upload 2022/01/28 06:37:22 DEBUG : only4: Uploading segment 0/6 size 6 2022/01/28 06:37:23 DEBUG : both5: Starting multipart upload 2022/01/28 06:37:23 DEBUG : both5: Uploading segment 0/6 size 6 2022/01/28 06:37:24 DEBUG : only5: Starting multipart upload 2022/01/28 06:37:25 DEBUG : only5: Uploading segment 0/6 size 6 2022/01/28 06:37:25 DEBUG : both6: Starting multipart upload 2022/01/28 06:37:26 DEBUG : both6: Uploading segment 0/6 size 6 2022/01/28 06:37:27 DEBUG : only6: Starting multipart upload 2022/01/28 06:37:27 DEBUG : only6: Uploading segment 0/6 size 6 2022/01/28 06:37:28 DEBUG : both7: Starting multipart upload 2022/01/28 06:37:29 DEBUG : both7: Uploading segment 0/6 size 6 2022/01/28 06:37:29 DEBUG : only7: Starting multipart upload 2022/01/28 06:37:30 DEBUG : only7: Uploading segment 0/6 size 6 2022/01/28 06:37:30 DEBUG : both8: Starting multipart upload 2022/01/28 06:37:31 DEBUG : both8: Uploading segment 0/6 size 6 2022/01/28 06:37:32 DEBUG : only8: Starting multipart upload 2022/01/28 06:37:32 DEBUG : only8: Uploading segment 0/6 size 6 2022/01/28 06:37:33 DEBUG : both9: Starting multipart upload 2022/01/28 06:37:34 DEBUG : both9: Uploading segment 0/6 size 6 2022/01/28 06:37:34 DEBUG : only9: Starting multipart upload 2022/01/28 06:37:35 DEBUG : only9: Uploading segment 0/6 size 6 2022/01/28 06:37:35 DEBUG : both10: Starting multipart upload 2022/01/28 06:37:36 DEBUG : both10: Uploading segment 0/6 size 6 2022/01/28 06:37:37 DEBUG : only10: Starting multipart upload 2022/01/28 06:37:37 DEBUG : only10: Uploading segment 0/6 size 6 2022/01/28 06:37:38 DEBUG : both11: Starting multipart upload 2022/01/28 06:37:38 DEBUG : both11: Uploading segment 0/6 size 6 2022/01/28 06:37:39 DEBUG : only11: Starting multipart upload 2022/01/28 06:37:39 DEBUG : only11: Uploading segment 0/6 size 6 2022/01/28 06:37:40 DEBUG : both12: Starting multipart upload 2022/01/28 06:37:41 DEBUG : both12: Uploading segment 0/6 size 6 2022/01/28 06:37:41 DEBUG : only12: Starting multipart upload 2022/01/28 06:37:42 DEBUG : only12: Uploading segment 0/6 size 6 2022/01/28 06:37:42 DEBUG : both13: Starting multipart upload 2022/01/28 06:37:43 DEBUG : both13: Uploading segment 0/6 size 6 2022/01/28 06:37:44 DEBUG : only13: Starting multipart upload 2022/01/28 06:37:44 DEBUG : only13: Uploading segment 0/6 size 6 2022/01/28 06:37:44 DEBUG : both14: Starting multipart upload 2022/01/28 06:37:45 DEBUG : both14: Uploading segment 0/6 size 6 2022/01/28 06:37:46 DEBUG : only14: Starting multipart upload 2022/01/28 06:37:46 DEBUG : only14: Uploading segment 0/6 size 6 2022/01/28 06:37:47 DEBUG : both15: Starting multipart upload 2022/01/28 06:37:47 DEBUG : both15: Uploading segment 0/6 size 6 2022/01/28 06:37:48 DEBUG : only15: Starting multipart upload 2022/01/28 06:37:50 DEBUG : only15: Uploading segment 0/6 size 6 2022/01/28 06:37:50 DEBUG : both16: Starting multipart upload 2022/01/28 06:37:51 DEBUG : both16: Uploading segment 0/6 size 6 2022/01/28 06:37:52 DEBUG : only16: Starting multipart upload 2022/01/28 06:37:52 DEBUG : only16: Uploading segment 0/6 size 6 2022/01/28 06:37:52 DEBUG : both17: Starting multipart upload 2022/01/28 06:37:53 DEBUG : both17: Uploading segment 0/6 size 6 2022/01/28 06:37:54 DEBUG : only17: Starting multipart upload 2022/01/28 06:37:54 DEBUG : only17: Uploading segment 0/6 size 6 2022/01/28 06:37:55 DEBUG : both18: Starting multipart upload 2022/01/28 06:37:55 DEBUG : both18: Uploading segment 0/6 size 6 2022/01/28 06:37:56 DEBUG : only18: Starting multipart upload 2022/01/28 06:37:57 DEBUG : only18: Uploading segment 0/6 size 6 2022/01/28 06:37:57 DEBUG : both19: Starting multipart upload 2022/01/28 06:37:58 DEBUG : both19: Uploading segment 0/6 size 6 2022/01/28 06:37:58 DEBUG : only19: Starting multipart upload 2022/01/28 06:37:59 DEBUG : only19: Uploading segment 0/6 size 6 2022/01/28 06:38:01 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:38:01 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both1: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both12: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both13: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both14: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both15: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both16: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both17: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both18: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both19: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both2: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both3: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both4: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both5: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both6: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both7: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both8: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both9: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both0: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both10: Unchanged skipping 2022/01/28 06:38:01 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:01 DEBUG : both11: Unchanged skipping 2022/01/28 06:38:01 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:38:01 DEBUG : Waiting for deletions to finish 2022/01/28 06:38:01 INFO : only8: Deleted 2022/01/28 06:38:01 INFO : only7: Deleted 2022/01/28 06:38:01 INFO : only9: Deleted 2022/01/28 06:38:01 INFO : only1: Deleted 2022/01/28 06:38:01 INFO : only2: Deleted 2022/01/28 06:38:01 INFO : only15: Deleted 2022/01/28 06:38:01 INFO : only16: Deleted 2022/01/28 06:38:01 INFO : only18: Deleted 2022/01/28 06:38:01 INFO : only19: Deleted 2022/01/28 06:38:01 INFO : only0: Deleted 2022/01/28 06:38:01 INFO : only3: Deleted 2022/01/28 06:38:02 INFO : only4: Deleted 2022/01/28 06:38:02 INFO : only5: Deleted 2022/01/28 06:38:02 INFO : only6: Deleted 2022/01/28 06:38:02 INFO : only17: Deleted 2022/01/28 06:38:02 INFO : only10: Deleted 2022/01/28 06:38:02 INFO : only12: Deleted 2022/01/28 06:38:02 INFO : only11: Deleted 2022/01/28 06:38:02 INFO : only13: Deleted 2022/01/28 06:38:02 INFO : only14: Deleted 2022/01/28 06:38:02 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (56.99s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "One drive root 'rclone-test-luqopom7vininaj4muvivoc0'", Local "Local file system at /tmp/rclone1817180687", Modify Window "1s" 2022/01/28 06:38:06 DEBUG : both0: Starting multipart upload 2022/01/28 06:38:07 DEBUG : both0: Uploading segment 0/6 size 6 2022/01/28 06:38:07 DEBUG : only0: Starting multipart upload 2022/01/28 06:38:08 DEBUG : only0: Uploading segment 0/6 size 6 2022/01/28 06:38:08 DEBUG : both1: Starting multipart upload 2022/01/28 06:38:09 DEBUG : both1: Uploading segment 0/6 size 6 2022/01/28 06:38:09 DEBUG : only1: Starting multipart upload 2022/01/28 06:38:10 DEBUG : only1: Uploading segment 0/6 size 6 2022/01/28 06:38:10 DEBUG : both2: Starting multipart upload 2022/01/28 06:38:11 DEBUG : both2: Uploading segment 0/6 size 6 2022/01/28 06:38:11 DEBUG : only2: Starting multipart upload 2022/01/28 06:38:12 DEBUG : only2: Uploading segment 0/6 size 6 2022/01/28 06:38:13 DEBUG : both3: Starting multipart upload 2022/01/28 06:38:13 DEBUG : both3: Uploading segment 0/6 size 6 2022/01/28 06:38:14 DEBUG : only3: Starting multipart upload 2022/01/28 06:38:14 DEBUG : only3: Uploading segment 0/6 size 6 2022/01/28 06:38:15 DEBUG : both4: Starting multipart upload 2022/01/28 06:38:16 DEBUG : both4: Uploading segment 0/6 size 6 2022/01/28 06:38:16 DEBUG : only4: Starting multipart upload 2022/01/28 06:38:17 DEBUG : only4: Uploading segment 0/6 size 6 2022/01/28 06:38:17 DEBUG : both5: Starting multipart upload 2022/01/28 06:38:18 DEBUG : both5: Uploading segment 0/6 size 6 2022/01/28 06:38:18 DEBUG : only5: Starting multipart upload 2022/01/28 06:38:19 DEBUG : only5: Uploading segment 0/6 size 6 2022/01/28 06:38:19 DEBUG : both6: Starting multipart upload 2022/01/28 06:38:20 DEBUG : both6: Uploading segment 0/6 size 6 2022/01/28 06:38:20 DEBUG : only6: Starting multipart upload 2022/01/28 06:38:21 DEBUG : only6: Uploading segment 0/6 size 6 2022/01/28 06:38:21 DEBUG : both7: Starting multipart upload 2022/01/28 06:38:22 DEBUG : both7: Uploading segment 0/6 size 6 2022/01/28 06:38:22 DEBUG : only7: Starting multipart upload 2022/01/28 06:38:23 DEBUG : only7: Uploading segment 0/6 size 6 2022/01/28 06:38:23 DEBUG : both8: Starting multipart upload 2022/01/28 06:38:24 DEBUG : both8: Uploading segment 0/6 size 6 2022/01/28 06:38:24 DEBUG : only8: Starting multipart upload 2022/01/28 06:38:25 DEBUG : only8: Uploading segment 0/6 size 6 2022/01/28 06:38:26 DEBUG : both9: Starting multipart upload 2022/01/28 06:38:26 DEBUG : both9: Uploading segment 0/6 size 6 2022/01/28 06:38:27 DEBUG : only9: Starting multipart upload 2022/01/28 06:38:27 DEBUG : only9: Uploading segment 0/6 size 6 2022/01/28 06:38:28 DEBUG : both10: Starting multipart upload 2022/01/28 06:38:28 DEBUG : both10: Uploading segment 0/6 size 6 2022/01/28 06:38:29 DEBUG : only10: Starting multipart upload 2022/01/28 06:38:29 DEBUG : only10: Uploading segment 0/6 size 6 2022/01/28 06:38:30 DEBUG : both11: Starting multipart upload 2022/01/28 06:38:31 DEBUG : both11: Uploading segment 0/6 size 6 2022/01/28 06:38:31 DEBUG : only11: Starting multipart upload 2022/01/28 06:38:32 DEBUG : only11: Uploading segment 0/6 size 6 2022/01/28 06:38:32 DEBUG : both12: Starting multipart upload 2022/01/28 06:38:33 DEBUG : both12: Uploading segment 0/6 size 6 2022/01/28 06:38:33 DEBUG : only12: Starting multipart upload 2022/01/28 06:38:34 DEBUG : only12: Uploading segment 0/6 size 6 2022/01/28 06:38:34 DEBUG : both13: Starting multipart upload 2022/01/28 06:38:35 DEBUG : both13: Uploading segment 0/6 size 6 2022/01/28 06:38:35 DEBUG : only13: Starting multipart upload 2022/01/28 06:38:36 DEBUG : only13: Uploading segment 0/6 size 6 2022/01/28 06:38:36 DEBUG : both14: Starting multipart upload 2022/01/28 06:38:37 DEBUG : both14: Uploading segment 0/6 size 6 2022/01/28 06:38:37 DEBUG : only14: Starting multipart upload 2022/01/28 06:38:38 DEBUG : only14: Uploading segment 0/6 size 6 2022/01/28 06:38:38 DEBUG : both15: Starting multipart upload 2022/01/28 06:38:39 DEBUG : both15: Uploading segment 0/6 size 6 2022/01/28 06:38:40 DEBUG : only15: Starting multipart upload 2022/01/28 06:38:41 DEBUG : only15: Uploading segment 0/6 size 6 2022/01/28 06:38:41 DEBUG : both16: Starting multipart upload 2022/01/28 06:38:42 DEBUG : both16: Uploading segment 0/6 size 6 2022/01/28 06:38:42 DEBUG : only16: Starting multipart upload 2022/01/28 06:38:43 DEBUG : only16: Uploading segment 0/6 size 6 2022/01/28 06:38:43 DEBUG : both17: Starting multipart upload 2022/01/28 06:38:44 DEBUG : both17: Uploading segment 0/6 size 6 2022/01/28 06:38:44 DEBUG : only17: Starting multipart upload 2022/01/28 06:38:45 DEBUG : only17: Uploading segment 0/6 size 6 2022/01/28 06:38:46 DEBUG : both18: Starting multipart upload 2022/01/28 06:38:47 DEBUG : both18: Uploading segment 0/6 size 6 2022/01/28 06:38:47 DEBUG : only18: Starting multipart upload 2022/01/28 06:38:48 DEBUG : only18: Uploading segment 0/6 size 6 2022/01/28 06:38:49 DEBUG : both19: Starting multipart upload 2022/01/28 06:38:50 DEBUG : both19: Uploading segment 0/6 size 6 2022/01/28 06:38:50 DEBUG : only19: Starting multipart upload 2022/01/28 06:38:51 DEBUG : only19: Uploading segment 0/6 size 6 2022/01/28 06:38:53 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both0: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both1: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both10: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both11: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both12: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both13: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both14: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both15: Unchanged skipping 2022/01/28 06:38:53 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for checks to finish 2022/01/28 06:38:53 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both17: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both18: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both19: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both2: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both3: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both4: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both5: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both6: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both7: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both8: Unchanged skipping 2022/01/28 06:38:53 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2022/01/28 06:38:53 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : both16: Unchanged skipping 2022/01/28 06:38:53 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only0: Starting singlepart upload 2022/01/28 06:38:53 DEBUG : only10: Starting singlepart upload 2022/01/28 06:38:53 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : only11: Starting singlepart upload 2022/01/28 06:38:53 DEBUG : only12: Starting singlepart upload 2022/01/28 06:38:53 DEBUG : both9: Unchanged skipping 2022/01/28 06:38:53 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/01/28 06:38:53 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Waiting for transfers to finish 2022/01/28 06:38:54 DEBUG : only12: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:54 DEBUG : only12: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:54 ERROR : only12: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:54 INFO : only12: Removing failed copy 2022/01/28 06:38:54 DEBUG : only11: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:54 DEBUG : only11: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:54 ERROR : only11: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:54 INFO : only11: Removing failed copy 2022/01/28 06:38:54 DEBUG : only10: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:54 DEBUG : only10: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:54 ERROR : only10: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:54 INFO : only10: Removing failed copy 2022/01/28 06:38:54 DEBUG : only0: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:54 DEBUG : only0: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:54 ERROR : only0: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:54 INFO : only0: Removing failed copy 2022/01/28 06:38:54 DEBUG : only13: Starting singlepart upload 2022/01/28 06:38:54 DEBUG : only14: Starting singlepart upload 2022/01/28 06:38:55 DEBUG : only16: Starting singlepart upload 2022/01/28 06:38:55 DEBUG : only17: Starting singlepart upload 2022/01/28 06:38:56 DEBUG : only13: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:56 DEBUG : only13: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:56 ERROR : only13: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:56 INFO : only13: Removing failed copy 2022/01/28 06:38:56 DEBUG : only16: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:56 DEBUG : only16: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:56 ERROR : only16: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:56 INFO : only16: Removing failed copy 2022/01/28 06:38:56 DEBUG : only14: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:56 DEBUG : only14: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:56 ERROR : only14: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:56 INFO : only14: Removing failed copy 2022/01/28 06:38:56 DEBUG : only18: Starting singlepart upload 2022/01/28 06:38:56 DEBUG : only19: Starting singlepart upload 2022/01/28 06:38:56 DEBUG : only17: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:56 DEBUG : only17: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:56 ERROR : only17: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:56 INFO : only17: Removing failed copy 2022/01/28 06:38:56 DEBUG : only2: Starting singlepart upload 2022/01/28 06:38:57 DEBUG : only3: Starting singlepart upload 2022/01/28 06:38:57 DEBUG : only2: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:57 DEBUG : only2: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:57 ERROR : only2: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:57 INFO : only2: Removing failed copy 2022/01/28 06:38:57 DEBUG : only3: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:57 DEBUG : only3: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:57 ERROR : only3: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:57 INFO : only3: Removing failed copy 2022/01/28 06:38:57 DEBUG : only19: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:57 DEBUG : only19: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:57 ERROR : only19: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:57 INFO : only19: Removing failed copy 2022/01/28 06:38:58 DEBUG : only18: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:58 DEBUG : only18: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:58 ERROR : only18: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:58 INFO : only18: Removing failed copy 2022/01/28 06:38:58 DEBUG : only4: Starting singlepart upload 2022/01/28 06:38:58 DEBUG : only5: Starting singlepart upload 2022/01/28 06:38:58 DEBUG : only6: Starting singlepart upload 2022/01/28 06:38:58 DEBUG : only7: Starting singlepart upload 2022/01/28 06:38:58 DEBUG : only5: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:58 DEBUG : only5: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:58 ERROR : only5: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:58 INFO : only5: Removing failed copy 2022/01/28 06:38:59 DEBUG : only4: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:59 DEBUG : only4: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:59 ERROR : only4: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:59 INFO : only4: Removing failed copy 2022/01/28 06:38:59 DEBUG : only6: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:59 DEBUG : only6: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:59 ERROR : only6: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:59 INFO : only6: Removing failed copy 2022/01/28 06:38:59 DEBUG : only8: Starting singlepart upload 2022/01/28 06:38:59 DEBUG : only9: Starting singlepart upload 2022/01/28 06:38:59 DEBUG : only1: Starting singlepart upload 2022/01/28 06:38:59 DEBUG : only7: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:38:59 DEBUG : only7: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:38:59 ERROR : only7: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:38:59 INFO : only7: Removing failed copy 2022/01/28 06:39:00 DEBUG : only15: Starting singlepart upload 2022/01/28 06:39:00 DEBUG : only1: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:39:00 DEBUG : only1: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:39:00 ERROR : only1: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:39:00 INFO : only1: Removing failed copy 2022/01/28 06:39:00 DEBUG : only9: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:39:00 DEBUG : only9: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:39:00 ERROR : only9: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:39:00 INFO : only9: Removing failed copy 2022/01/28 06:39:00 DEBUG : only8: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:39:00 DEBUG : only8: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:39:00 ERROR : only8: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:39:00 INFO : only8: Removing failed copy 2022/01/28 06:39:01 DEBUG : only15: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone1817180687) 2022/01/28 06:39:01 DEBUG : only15: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-luqopom7vininaj4muvivoc0') 2022/01/28 06:39:01 ERROR : only15: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2022/01/28 06:39:01 INFO : only15: Removing failed copy 2022/01/28 06:39:01 ERROR : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': not deleting files as there were IO errors 2022/01/28 06:39:01 ERROR : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': not deleting directories as there were IO errors sync_test.go:2056: Error Trace: sync_test.go:2056 sync_test.go:2066 Error: Received unexpected error: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" Test: TestSyncConcurrentTruncate Messages: Sync must not return a error sync_test.go:2057: Error Trace: sync_test.go:2057 sync_test.go:2066 Error: Should be false Test: TestSyncConcurrentTruncate Messages: Low level errors must not have happened fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: fstest.go:306 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2058 sync_test.go:2066 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) fstest.go:204: Not found "only9" fstest.go:204: Not found "only11" fstest.go:204: Not found "only13" fstest.go:204: Not found "only5" fstest.go:204: Not found "only6" fstest.go:204: Not found "only7" fstest.go:204: Not found "only10" fstest.go:204: Not found "only18" fstest.go:204: Not found "only2" fstest.go:204: Not found "only17" fstest.go:204: Not found "only19" fstest.go:204: Not found "only4" fstest.go:204: Not found "only3" fstest.go:204: Not found "only14" fstest.go:204: Not found "only1" fstest.go:204: Not found "only8" fstest.go:204: Not found "only15" fstest.go:204: Not found "only0" fstest.go:204: Not found "only12" fstest.go:204: Not found "only16" fstest.go:207: Error Trace: fstest.go:207 fstest.go:311 fstest.go:339 fstest.go:351 run.go:342 sync_test.go:2058 sync_test.go:2066 Error: Not equal: expected: 0 actual : 20 Test: TestSyncConcurrentTruncate Messages: 20 objects not found --- FAIL: TestSyncConcurrentTruncate (69.00s) FAIL 2022/01/28 06:39:15 DEBUG : One drive root 'rclone-test-luqopom7vininaj4muvivoc0': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose" - Finished ERROR in 11m35.388033812s (try 1/5): exit status 1: Failed [TestSyncConcurrentTruncate]