"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose" - Starting (try 1/5) 2021/12/23 05:29:35 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6" 2021/12/23 05:29:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/12/23 05:29:36 DEBUG : Creating backend with remote "/tmp/rclone3530588970" === 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-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:29:37 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:29:37 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:29:37 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) --- PASS: TestCopyWithDryRun (2.20s) === RUN TestCopy run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:29:38 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:29:38 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:29:39 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:29:39 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:29:40 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:29:40 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (4.53s) === RUN TestCopyMissingDirectory run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:29:42 DEBUG : Creating backend with remote "/non-existing" 2021/12/23 05:29:43 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2021/12/23 05:29:43 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:29:43 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (1.07s) === RUN TestCopyNoTraverse run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:29:44 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:29:44 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:29:45 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:29:45 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:29:46 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:29:46 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (4.76s) === RUN TestCopyCheckFirst run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:29:48 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Running all checks before starting transfers 2021/12/23 05:29:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:29:49 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Checks finished, now starting transfers 2021/12/23 05:29:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:29:50 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:29:50 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:29:51 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:29:51 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (4.92s) === RUN TestSyncNoTraverse run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:29:53 ERROR : Ignoring --no-traverse with sync 2021/12/23 05:29:53 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:29:53 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:29:54 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:29:55 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:29:55 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:29:55 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:29:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (5.08s) === RUN TestCopyWithDepth run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:29:59 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:29:59 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:29:59 DEBUG : hello world2: Starting multipart upload 2021/12/23 05:30:00 DEBUG : hello world2: Uploading segment 0/12 size 12 2021/12/23 05:30:00 DEBUG : hello world2: quickxor = 6828031bd8f00610dce10d726c03196400000000 OK 2021/12/23 05:30:00 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (3.22s) === RUN TestCopyWithFilesFrom run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:01 DEBUG : hello world2: Excluded 2021/12/23 05:30:02 DEBUG : potato2: Starting multipart upload 2021/12/23 05:30:02 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:02 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:02 DEBUG : potato2: Uploading segment 0/11 size 11 2021/12/23 05:30:03 DEBUG : potato2: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:30:03 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.79s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:05 DEBUG : potato2: Starting multipart upload 2021/12/23 05:30:05 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:05 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:06 DEBUG : potato2: Uploading segment 0/11 size 11 2021/12/23 05:30:06 DEBUG : potato2: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:30:06 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (3.18s) === RUN TestCopyEmptyDirectories run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:07 DEBUG : sub dir2: Making directory 2021/12/23 05:30:08 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:08 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:09 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:30:09 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:30:10 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:30:10 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:30:10 DEBUG : sub dir2: Making directory 2021/12/23 05:30:11 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': copied 1 directories --- PASS: TestCopyEmptyDirectories (6.26s) === RUN TestMoveEmptyDirectories run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:14 DEBUG : sub dir2: Making directory 2021/12/23 05:30:14 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:14 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:15 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:30:15 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:30:16 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:30:16 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:30:16 INFO : sub dir/hello world: Deleted 2021/12/23 05:30:16 DEBUG : sub dir2: Making directory 2021/12/23 05:30:17 DEBUG : sub dir: Making directory 2021/12/23 05:30:17 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': copied 2 directories --- PASS: TestMoveEmptyDirectories (5.83s) === RUN TestSyncEmptyDirectories run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:20 DEBUG : sub dir2: Making directory 2021/12/23 05:30:20 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:20 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:20 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:30:21 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:30:22 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:30:22 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:30:22 DEBUG : sub dir2: Making directory 2021/12/23 05:30:22 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': copied 1 directories 2021/12/23 05:30:22 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (7.03s) === RUN TestServerSideCopy run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:27 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:30:28 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:30:29 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-xeyuper4tikikeb8nitahoz2" sync_test.go:275: Server side copy (if possible) One drive root 'rclone-test-fogafix4peceluf6hapotiy6' -> One drive root 'rclone-test-xeyuper4tikikeb8nitahoz2' 2021/12/23 05:30:31 DEBUG : One drive root 'rclone-test-xeyuper4tikikeb8nitahoz2': Waiting for checks to finish 2021/12/23 05:30:31 DEBUG : One drive root 'rclone-test-xeyuper4tikikeb8nitahoz2': Waiting for transfers to finish 2021/12/23 05:30:37 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:30:37 INFO : sub dir/hello world: Copied (server-side copy) 2021/12/23 05:30:37 DEBUG : One drive root 'rclone-test-xeyuper4tikikeb8nitahoz2': Purge remote --- PASS: TestServerSideCopy (12.49s) === RUN TestCopyAfterDelete run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:40 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:30:40 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:30:41 ERROR : : error listing: directory not found 2021/12/23 05:30:41 DEBUG : Local file system at /tmp/rclone3530588970: Making directory 2021/12/23 05:30:42 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:42 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (5.02s) === RUN TestCopyRedownload run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:45 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:30:46 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:30:47 DEBUG : Local file system at /tmp/rclone3530588970: Waiting for checks to finish 2021/12/23 05:30:47 DEBUG : Local file system at /tmp/rclone3530588970: Waiting for transfers to finish 2021/12/23 05:30:48 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:30:48 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (5.51s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:50 DEBUG : check sum: Starting multipart upload 2021/12/23 05:30:50 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:50 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:51 DEBUG : check sum: Uploading segment 0/1 size 1 2021/12/23 05:30:51 DEBUG : check sum: quickxor = 2d00000000000000000000000100000000000000 OK 2021/12/23 05:30:51 INFO : check sum: Copied (new) 2021/12/23 05:30:51 DEBUG : Waiting for deletions to finish 2021/12/23 05:30:52 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:52 DEBUG : check sum: quickxor = 2d00000000000000000000000100000000000000 OK 2021/12/23 05:30:52 DEBUG : check sum: Size and quickxor of src and dst objects identical 2021/12/23 05:30:52 DEBUG : check sum: Unchanged skipping 2021/12/23 05:30:52 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:52 DEBUG : Waiting for deletions to finish 2021/12/23 05:30:52 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (3.34s) === RUN TestSyncSizeOnly run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:54 DEBUG : sizeonly: Starting multipart upload 2021/12/23 05:30:54 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:54 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:54 DEBUG : sizeonly: Uploading segment 0/6 size 6 2021/12/23 05:30:55 DEBUG : sizeonly: quickxor = 7078031dc2408737000000000600000000000000 OK 2021/12/23 05:30:55 INFO : sizeonly: Copied (new) 2021/12/23 05:30:55 DEBUG : Waiting for deletions to finish 2021/12/23 05:30:56 DEBUG : sizeonly: Sizes identical 2021/12/23 05:30:56 DEBUG : sizeonly: Unchanged skipping 2021/12/23 05:30:56 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:56 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:30:56 DEBUG : Waiting for deletions to finish 2021/12/23 05:30:56 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (5.25s) === RUN TestSyncIgnoreSize run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:30:59 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:30:59 DEBUG : ignore-size: Starting multipart upload 2021/12/23 05:30:59 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:07 DEBUG : ignore-size: Uploading segment 0/8 size 8 2021/12/23 05:31:07 DEBUG : ignore-size: quickxor = 6378831be8500637d0610e000800000000000000 OK 2021/12/23 05:31:07 INFO : ignore-size: Copied (new) 2021/12/23 05:31:07 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:08 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:08 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:31:08 DEBUG : ignore-size: Unchanged skipping 2021/12/23 05:31:08 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:08 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:08 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (11.94s) === RUN TestSyncIgnoreTimes run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:10 DEBUG : existing: Starting multipart upload 2021/12/23 05:31:11 DEBUG : existing: Uploading segment 0/6 size 6 2021/12/23 05:31:12 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:31:12 DEBUG : existing: Unchanged skipping 2021/12/23 05:31:12 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:12 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:12 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:12 INFO : There was nothing to transfer 2021/12/23 05:31:12 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/12/23 05:31:12 DEBUG : existing: Starting multipart upload 2021/12/23 05:31:12 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:12 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:13 DEBUG : existing: Uploading segment 0/6 size 6 2021/12/23 05:31:14 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2021/12/23 05:31:14 INFO : existing: Copied (replaced existing) 2021/12/23 05:31:14 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (4.47s) === RUN TestSyncIgnoreExisting run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:15 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:15 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:15 DEBUG : existing: Starting multipart upload 2021/12/23 05:31:15 DEBUG : existing: Uploading segment 0/6 size 6 2021/12/23 05:31:16 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2021/12/23 05:31:16 INFO : existing: Copied (new) 2021/12/23 05:31:16 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:17 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:17 DEBUG : existing: Destination exists, skipping 2021/12/23 05:31:17 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:17 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:17 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (3.72s) === RUN TestSyncIgnoreErrors run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:19 DEBUG : b/potato: Starting multipart upload 2021/12/23 05:31:20 DEBUG : b/potato: Uploading segment 0/21 size 21 2021/12/23 05:31:21 DEBUG : c/non empty space: Starting multipart upload 2021/12/23 05:31:21 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2021/12/23 05:31:22 DEBUG : d: Making directory 2021/12/23 05:31:25 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:31:25 DEBUG : c/non empty space: Unchanged skipping 2021/12/23 05:31:25 DEBUG : a/potato2: Starting multipart upload 2021/12/23 05:31:26 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:26 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:26 DEBUG : a/potato2: Uploading segment 0/60 size 60 2021/12/23 05:31:26 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2021/12/23 05:31:26 INFO : a/potato2: Copied (new) 2021/12/23 05:31:26 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:27 INFO : b/potato: Deleted 2021/12/23 05:31:27 INFO : d: Removing directory 2021/12/23 05:31:27 INFO : b: Removing directory 2021/12/23 05:31:28 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': deleted 2 directories --- PASS: TestSyncIgnoreErrors (12.25s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:31 DEBUG : empty space: Starting multipart upload 2021/12/23 05:31:31 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:31:32 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 2021/12/23 05:31:32 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:32 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2021/12/23 05:31:32 NOTICE: empty space: Skipped update modification time as --dry-run is set (size 1) 2021/12/23 05:31:32 DEBUG : empty space: Unchanged skipping 2021/12/23 05:31:32 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:32 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:32 INFO : There was nothing to transfer 2021/12/23 05:31:33 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 2021/12/23 05:31:33 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:33 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2021/12/23 05:31:33 INFO : empty space: Updated modification time in destination 2021/12/23 05:31:33 DEBUG : empty space: Unchanged skipping 2021/12/23 05:31:33 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:33 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:33 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnly (4.25s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:35 DEBUG : empty space: Starting multipart upload 2021/12/23 05:31:36 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:31:37 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 2021/12/23 05:31:37 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:37 DEBUG : empty space: quickxor = 2d00000000000000000000000100000000000000 OK 2021/12/23 05:31:37 DEBUG : empty space: Unchanged skipping 2021/12/23 05:31:37 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:37 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:37 INFO : There was nothing to transfer --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (3.10s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:38 DEBUG : foo: Starting multipart upload 2021/12/23 05:31:38 DEBUG : foo: Uploading segment 0/3 size 3 2021/12/23 05:31:40 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 2021/12/23 05:31:40 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:40 DEBUG : foo: quickxor = 6678c31b00000000000000000300000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:31:40 DEBUG : foo: quickxor = 6208831c00000000000000000300000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:31:40 DEBUG : foo: quickxor differ 2021/12/23 05:31:40 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:40 DEBUG : foo: Starting multipart upload 2021/12/23 05:31:40 DEBUG : foo: Uploading segment 0/3 size 3 2021/12/23 05:31:41 DEBUG : foo: quickxor = 6678c31b00000000000000000300000000000000 OK 2021/12/23 05:31:41 INFO : foo: Copied (replaced existing) 2021/12/23 05:31:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (3.72s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:42 DEBUG : empty space: Starting multipart upload 2021/12/23 05:31:42 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:31:43 DEBUG : potato: Starting multipart upload 2021/12/23 05:31:43 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:31:43 DEBUG : empty space: Unchanged skipping 2021/12/23 05:31:43 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:43 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:44 DEBUG : potato: Uploading segment 0/60 size 60 2021/12/23 05:31:44 DEBUG : potato: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2021/12/23 05:31:44 INFO : potato: Copied (new) 2021/12/23 05:31:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (3.97s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:46 DEBUG : potato: Starting multipart upload 2021/12/23 05:31:46 DEBUG : potato: Uploading segment 0/60 size 60 2021/12/23 05:31:47 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/12/23 05:31:47 DEBUG : potato: Starting multipart upload 2021/12/23 05:31:47 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:47 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:48 DEBUG : potato: Uploading segment 0/21 size 21 2021/12/23 05:31:48 DEBUG : potato: quickxor = d26463389bd86e62ce010462bd031d40308730b4 OK 2021/12/23 05:31:48 INFO : potato: Copied (replaced existing) 2021/12/23 05:31:48 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (3.80s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:49 DEBUG : potato: Starting multipart upload 2021/12/23 05:31:50 DEBUG : potato: Uploading segment 0/21 size 21 2021/12/23 05:31:51 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 2021/12/23 05:31:51 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:51 DEBUG : potato: quickxor = f2606230dad02c724c010442bd02154030852034 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:31:51 DEBUG : potato: quickxor = d26463389bd86e62ce010462bd031d40308730b4 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:31:51 DEBUG : potato: quickxor differ 2021/12/23 05:31:51 DEBUG : potato: Starting multipart upload 2021/12/23 05:31:51 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:51 DEBUG : potato: Uploading segment 0/21 size 21 2021/12/23 05:31:52 DEBUG : potato: quickxor = f2606230dad02c724c010442bd02154030852034 OK 2021/12/23 05:31:52 INFO : potato: Copied (replaced existing) 2021/12/23 05:31:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (3.53s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:53 DEBUG : potato: Starting multipart upload 2021/12/23 05:31:54 DEBUG : potato: Uploading segment 0/21 size 21 2021/12/23 05:31:54 DEBUG : empty space: Starting multipart upload 2021/12/23 05:31:55 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:31:55 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/12/23 05:31:55 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:31:55 DEBUG : empty space: Unchanged skipping 2021/12/23 05:31:55 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:55 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:31:55 DEBUG : Waiting for deletions to finish 2021/12/23 05:31:55 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (3.75s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:31:57 DEBUG : potato: Starting multipart upload 2021/12/23 05:31:57 DEBUG : potato: Uploading segment 0/21 size 21 2021/12/23 05:31:58 DEBUG : empty space: Starting multipart upload 2021/12/23 05:31:58 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:31:59 DEBUG : potato2: Starting multipart upload 2021/12/23 05:31:59 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:31:59 DEBUG : empty space: Unchanged skipping 2021/12/23 05:31:59 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:31:59 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:32:00 DEBUG : potato2: Uploading segment 0/60 size 60 2021/12/23 05:32:00 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2021/12/23 05:32:00 INFO : potato2: Copied (new) 2021/12/23 05:32:00 DEBUG : Waiting for deletions to finish 2021/12/23 05:32:01 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (5.13s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:32:02 DEBUG : b/potato: Starting multipart upload 2021/12/23 05:32:03 DEBUG : b/potato: Uploading segment 0/21 size 21 2021/12/23 05:32:04 DEBUG : c/non empty space: Starting multipart upload 2021/12/23 05:32:05 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2021/12/23 05:32:06 DEBUG : d: Making directory 2021/12/23 05:32:06 DEBUG : d/e: Making directory 2021/12/23 05:32:08 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:32:08 DEBUG : c/non empty space: Unchanged skipping 2021/12/23 05:32:08 DEBUG : a/potato2: Starting multipart upload 2021/12/23 05:32:09 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:32:09 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:32:09 DEBUG : a/potato2: Uploading segment 0/60 size 60 2021/12/23 05:32:09 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2021/12/23 05:32:09 INFO : a/potato2: Copied (new) 2021/12/23 05:32:09 DEBUG : Waiting for deletions to finish 2021/12/23 05:32:10 INFO : b/potato: Deleted 2021/12/23 05:32:10 INFO : d/e: Removing directory 2021/12/23 05:32:10 INFO : d: Removing directory 2021/12/23 05:32:10 DEBUG : Too many requests. Trying again in 63 seconds. 2021/12/23 05:32:10 DEBUG : pacer: low level retry 1/10 (error activityLimitReached: throttledRequest: The request has been throttled) 2021/12/23 05:32:10 DEBUG : pacer: Rate limited, increasing sleep to 1m3s 2021/12/23 05:32:11 DEBUG : pacer: Reducing sleep to 47.25s 2021/12/23 05:33:14 DEBUG : pacer: Reducing sleep to 35.4375s 2021/12/23 05:33:14 INFO : b: Removing directory 2021/12/23 05:34:01 DEBUG : pacer: Reducing sleep to 26.578125s 2021/12/23 05:34:37 DEBUG : pacer: Reducing sleep to 19.93359375s 2021/12/23 05:34:37 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': deleted 3 directories 2021/12/23 05:35:03 DEBUG : pacer: Reducing sleep to 14.950195312s 2021/12/23 05:35:23 DEBUG : pacer: Reducing sleep to 11.212646484s 2021/12/23 05:35:38 DEBUG : pacer: Reducing sleep to 8.409484863s 2021/12/23 05:35:49 DEBUG : pacer: Reducing sleep to 6.307113647s 2021/12/23 05:35:58 DEBUG : pacer: Reducing sleep to 4.730335235s 2021/12/23 05:36:04 DEBUG : pacer: Reducing sleep to 3.547751426s 2021/12/23 05:36:09 DEBUG : pacer: Reducing sleep to 2.660813569s 2021/12/23 05:36:12 DEBUG : pacer: Reducing sleep to 1.995610176s 2021/12/23 05:36:15 DEBUG : pacer: Reducing sleep to 1.496707632s 2021/12/23 05:36:17 DEBUG : pacer: Reducing sleep to 1.122530724s 2021/12/23 05:36:18 DEBUG : pacer: Reducing sleep to 841.898043ms 2021/12/23 05:36:20 DEBUG : pacer: Reducing sleep to 631.423532ms 2021/12/23 05:36:20 DEBUG : pacer: Reducing sleep to 473.567649ms --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (258.54s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:36:21 DEBUG : pacer: Reducing sleep to 355.175736ms 2021/12/23 05:36:22 DEBUG : pacer: Reducing sleep to 266.381802ms 2021/12/23 05:36:22 DEBUG : b/potato: Starting multipart upload 2021/12/23 05:36:23 DEBUG : pacer: Reducing sleep to 199.786351ms 2021/12/23 05:36:23 DEBUG : b/potato: Uploading segment 0/21 size 21 2021/12/23 05:36:24 DEBUG : pacer: Reducing sleep to 149.839763ms 2021/12/23 05:36:24 DEBUG : pacer: Reducing sleep to 112.379822ms 2021/12/23 05:36:25 DEBUG : pacer: Reducing sleep to 84.284866ms 2021/12/23 05:36:25 DEBUG : c/non empty space: Starting multipart upload 2021/12/23 05:36:25 DEBUG : pacer: Reducing sleep to 63.213649ms 2021/12/23 05:36:25 DEBUG : c/non empty space: Uploading segment 0/5 size 5 2021/12/23 05:36:26 DEBUG : pacer: Reducing sleep to 47.410236ms 2021/12/23 05:36:26 DEBUG : d: Making directory 2021/12/23 05:36:26 DEBUG : pacer: Reducing sleep to 35.557677ms 2021/12/23 05:36:27 DEBUG : pacer: Reducing sleep to 26.668257ms 2021/12/23 05:36:28 DEBUG : pacer: Reducing sleep to 20.001192ms 2021/12/23 05:36:28 DEBUG : pacer: Reducing sleep to 15.000894ms 2021/12/23 05:36:28 DEBUG : pacer: Reducing sleep to 11.25067ms 2021/12/23 05:36:28 DEBUG : pacer: Reducing sleep to 10ms 2021/12/23 05:36:29 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:36:29 DEBUG : c/non empty space: Unchanged skipping 2021/12/23 05:36:30 DEBUG : a/potato2: Starting multipart upload 2021/12/23 05:36:30 DEBUG : a/potato2: Uploading segment 0/60 size 60 2021/12/23 05:36:30 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:36:30 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:36:31 DEBUG : a/potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2021/12/23 05:36:31 INFO : a/potato2: Copied (new) 2021/12/23 05:36:31 ERROR : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': not deleting files as there were IO errors 2021/12/23 05:36:31 ERROR : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (14.77s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:36:35 DEBUG : potato: Starting multipart upload 2021/12/23 05:36:36 DEBUG : potato: Uploading segment 0/21 size 21 2021/12/23 05:36:36 DEBUG : empty space: Starting multipart upload 2021/12/23 05:36:37 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:36:38 DEBUG : Waiting for deletions to finish 2021/12/23 05:36:38 DEBUG : potato2: Starting multipart upload 2021/12/23 05:36:38 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:36:38 DEBUG : empty space: Unchanged skipping 2021/12/23 05:36:38 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:36:38 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:36:39 INFO : potato: Deleted 2021/12/23 05:36:39 DEBUG : potato2: Uploading segment 0/60 size 60 2021/12/23 05:36:40 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2021/12/23 05:36:40 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (5.75s) === RUN TestSyncDeleteBefore run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:36:41 DEBUG : potato: Starting multipart upload 2021/12/23 05:36:41 DEBUG : potato: Uploading segment 0/21 size 21 2021/12/23 05:36:42 DEBUG : empty space: Starting multipart upload 2021/12/23 05:36:43 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:36:44 DEBUG : Waiting for deletions to finish 2021/12/23 05:36:44 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:36:44 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:36:45 INFO : potato: Deleted 2021/12/23 05:36:45 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:36:45 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:36:45 DEBUG : potato2: Starting multipart upload 2021/12/23 05:36:45 DEBUG : empty space: Unchanged skipping 2021/12/23 05:36:45 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:36:45 DEBUG : potato2: Uploading segment 0/60 size 60 2021/12/23 05:36:46 DEBUG : potato2: quickxor = 4001dad296b6b4a52d6d694b67dad296b6b4a52d OK 2021/12/23 05:36:46 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (6.16s) === RUN TestCopyDeleteBefore run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:36:47 DEBUG : potato: Starting multipart upload 2021/12/23 05:36:48 DEBUG : potato: Uploading segment 0/21 size 21 2021/12/23 05:36:49 DEBUG : potato2: Starting multipart upload 2021/12/23 05:36:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:36:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:36:50 DEBUG : potato2: Uploading segment 0/19 size 19 2021/12/23 05:36:50 DEBUG : potato2: quickxor = e9742354497b863ab0810d7913c118de00873494 OK 2021/12/23 05:36:50 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (5.65s) === RUN TestSyncWithExclude run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:36:53 DEBUG : potato2: Starting multipart upload 2021/12/23 05:36:53 DEBUG : potato2: Uploading segment 0/60 size 60 2021/12/23 05:36:54 DEBUG : empty space: Starting multipart upload 2021/12/23 05:36:55 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:36:56 DEBUG : enormous: Excluded 2021/12/23 05:36:56 DEBUG : potato2: Excluded 2021/12/23 05:36:56 DEBUG : potato2: Excluded 2021/12/23 05:36:56 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:36:56 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:36:56 DEBUG : empty space: Unchanged skipping 2021/12/23 05:36:56 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:36:56 DEBUG : Waiting for deletions to finish 2021/12/23 05:36:56 INFO : There was nothing to transfer 2021/12/23 05:36:57 DEBUG : enormous: Excluded 2021/12/23 05:36:57 DEBUG : potato2: Excluded 2021/12/23 05:36:57 DEBUG : potato2: Excluded 2021/12/23 05:36:57 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2021/12/23 05:36:57 DEBUG : empty space: Unchanged skipping 2021/12/23 05:36:57 DEBUG : Local file system at /tmp/rclone3530588970: Waiting for checks to finish 2021/12/23 05:36:57 DEBUG : Local file system at /tmp/rclone3530588970: Waiting for transfers to finish 2021/12/23 05:36:57 DEBUG : Waiting for deletions to finish 2021/12/23 05:36:57 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (6.55s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:36:59 DEBUG : potato2: Starting multipart upload 2021/12/23 05:37:00 DEBUG : potato2: Uploading segment 0/60 size 60 2021/12/23 05:37:00 DEBUG : empty space: Starting multipart upload 2021/12/23 05:37:01 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:37:02 DEBUG : enormous: Starting multipart upload 2021/12/23 05:37:03 DEBUG : enormous: Uploading segment 0/100 size 100 2021/12/23 05:37:04 DEBUG : enormous: Excluded 2021/12/23 05:37:04 DEBUG : potato2: Excluded 2021/12/23 05:37:04 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:04 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:37:04 DEBUG : empty space: Unchanged skipping 2021/12/23 05:37:04 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:04 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:04 INFO : enormous: Deleted 2021/12/23 05:37:05 INFO : potato2: Deleted 2021/12/23 05:37:05 INFO : There was nothing to transfer 2021/12/23 05:37:05 DEBUG : Local file system at /tmp/rclone3530588970: Waiting for checks to finish 2021/12/23 05:37:05 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2021/12/23 05:37:05 DEBUG : empty space: Unchanged skipping 2021/12/23 05:37:05 DEBUG : Local file system at /tmp/rclone3530588970: Waiting for transfers to finish 2021/12/23 05:37:05 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:05 INFO : enormous: Deleted 2021/12/23 05:37:05 INFO : potato2: Deleted 2021/12/23 05:37:05 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (7.38s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:37:07 DEBUG : one: Starting multipart upload 2021/12/23 05:37:07 DEBUG : one: Uploading segment 0/3 size 3 2021/12/23 05:37:08 DEBUG : two: Starting multipart upload 2021/12/23 05:37:09 DEBUG : two: Uploading segment 0/3 size 3 2021/12/23 05:37:09 DEBUG : three: Starting multipart upload 2021/12/23 05:37:10 DEBUG : three: Uploading segment 0/5 size 5 2021/12/23 05:37:10 DEBUG : four: Starting multipart upload 2021/12/23 05:37:11 DEBUG : four: Uploading segment 0/8 size 8 2021/12/23 05:37:12 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/12/23 05:37:12 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/12/23 05:37:12 DEBUG : one: Destination is newer than source, skipping 2021/12/23 05:37:12 DEBUG : three: Sizes identical 2021/12/23 05:37:12 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/12/23 05:37:12 DEBUG : four: Starting multipart upload 2021/12/23 05:37:12 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:12 DEBUG : five: Starting multipart upload 2021/12/23 05:37:12 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:37:12 DEBUG : two: quickxor = 54b8c21300000000000000000300000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:37:12 DEBUG : two: quickxor differ 2021/12/23 05:37:12 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:12 DEBUG : two: Starting multipart upload 2021/12/23 05:37:12 DEBUG : four: Uploading segment 0/4 size 4 2021/12/23 05:37:13 DEBUG : five: Uploading segment 0/4 size 4 2021/12/23 05:37:13 DEBUG : four: quickxor = 6678431de4000000000000000400000000000000 OK 2021/12/23 05:37:13 INFO : four: Copied (replaced existing) 2021/12/23 05:37:13 DEBUG : two: Uploading segment 0/3 size 3 2021/12/23 05:37:14 DEBUG : five: quickxor = 6648831dca000000000000000400000000000000 OK 2021/12/23 05:37:14 INFO : five: Copied (new) 2021/12/23 05:37:14 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2021/12/23 05:37:14 INFO : two: Copied (replaced existing) 2021/12/23 05:37:14 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:15 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:15 DEBUG : one: Destination is newer than source, skipping 2021/12/23 05:37:15 DEBUG : four: quickxor = 6678431de4000000000000000400000000000000 OK 2021/12/23 05:37:15 DEBUG : three: quickxor = 7440831cca500600000000000500000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:37:15 DEBUG : four: Size and quickxor of src and dst objects identical 2021/12/23 05:37:15 DEBUG : three: quickxor = 544082148a500400000000000500000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:37:15 DEBUG : five: quickxor = 6648831dca000000000000000400000000000000 OK 2021/12/23 05:37:15 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping 2021/12/23 05:37:15 DEBUG : five: Size and quickxor of src and dst objects identical 2021/12/23 05:37:15 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping 2021/12/23 05:37:15 DEBUG : three: quickxor differ 2021/12/23 05:37:15 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring 2021/12/23 05:37:15 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2021/12/23 05:37:15 DEBUG : two: Size and quickxor of src and dst objects identical 2021/12/23 05:37:15 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping 2021/12/23 05:37:15 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:15 DEBUG : three: Starting multipart upload 2021/12/23 05:37:15 DEBUG : three: Uploading segment 0/5 size 5 2021/12/23 05:37:16 DEBUG : three: quickxor = 7440831cca500600000000000500000000000000 OK 2021/12/23 05:37:16 INFO : three: Copied (replaced existing) 2021/12/23 05:37:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithUpdateOlder (11.03s) === 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-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" sync_test.go:1058: Can track renames: true 2021/12/23 05:37:18 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Making map for --track-renames 2021/12/23 05:37:18 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Finished making map for --track-renames 2021/12/23 05:37:18 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:18 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for renames to finish 2021/12/23 05:37:18 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:18 DEBUG : potato: Starting multipart upload 2021/12/23 05:37:18 DEBUG : yam: Starting multipart upload 2021/12/23 05:37:18 DEBUG : potato: Uploading segment 0/14 size 14 2021/12/23 05:37:19 DEBUG : yam: Uploading segment 0/11 size 11 2021/12/23 05:37:19 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2021/12/23 05:37:19 INFO : potato: Copied (new) 2021/12/23 05:37:19 DEBUG : yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2021/12/23 05:37:19 INFO : yam: Copied (new) 2021/12/23 05:37:19 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:20 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Making map for --track-renames 2021/12/23 05:37:20 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Finished making map for --track-renames 2021/12/23 05:37:20 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:20 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:37:20 DEBUG : potato: Unchanged skipping 2021/12/23 05:37:20 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for renames to finish 2021/12/23 05:37:21 INFO : yam: Moved (server-side) to: yaml 2021/12/23 05:37:21 INFO : yaml: Renamed from "yam" 2021/12/23 05:37:21 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:21 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:21 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenames (4.99s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" sync_test.go:1127: Can track renames: true 2021/12/23 05:37:23 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Making map for --track-renames 2021/12/23 05:37:23 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Finished making map for --track-renames 2021/12/23 05:37:23 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:23 DEBUG : potato: Starting multipart upload 2021/12/23 05:37:23 DEBUG : yam: Starting multipart upload 2021/12/23 05:37:23 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for renames to finish 2021/12/23 05:37:23 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:24 DEBUG : yam: Uploading segment 0/11 size 11 2021/12/23 05:37:24 DEBUG : potato: Uploading segment 0/14 size 14 2021/12/23 05:37:24 DEBUG : yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2021/12/23 05:37:24 INFO : yam: Copied (new) 2021/12/23 05:37:25 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2021/12/23 05:37:25 INFO : potato: Copied (new) 2021/12/23 05:37:25 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:26 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:37:26 DEBUG : potato: Unchanged skipping 2021/12/23 05:37:26 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Making map for --track-renames 2021/12/23 05:37:26 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Finished making map for --track-renames 2021/12/23 05:37:26 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:26 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for renames to finish 2021/12/23 05:37:27 INFO : yam: Moved (server-side) to: yaml 2021/12/23 05:37:27 INFO : yaml: Renamed from "yam" 2021/12/23 05:37:27 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:27 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:27 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyModtime (5.79s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" sync_test.go:1163: Can track renames: true 2021/12/23 05:37:29 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Making map for --track-renames 2021/12/23 05:37:29 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Finished making map for --track-renames 2021/12/23 05:37:29 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:29 DEBUG : potato: Starting multipart upload 2021/12/23 05:37:29 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for renames to finish 2021/12/23 05:37:29 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:29 DEBUG : sub/yam: Starting multipart upload 2021/12/23 05:37:30 DEBUG : potato: Uploading segment 0/14 size 14 2021/12/23 05:37:30 DEBUG : sub/yam: Uploading segment 0/11 size 11 2021/12/23 05:37:30 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2021/12/23 05:37:30 INFO : potato: Copied (new) 2021/12/23 05:37:30 DEBUG : sub/yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2021/12/23 05:37:30 INFO : sub/yam: Copied (new) 2021/12/23 05:37:30 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:31 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:37:31 DEBUG : potato: Unchanged skipping 2021/12/23 05:37:31 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Making map for --track-renames 2021/12/23 05:37:31 INFO : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Finished making map for --track-renames 2021/12/23 05:37:31 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:31 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for renames to finish 2021/12/23 05:37:32 INFO : sub/yam: Moved (server-side) to: yam 2021/12/23 05:37:32 INFO : yam: Renamed from "sub/yam" 2021/12/23 05:37:32 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:32 DEBUG : Waiting for deletions to finish 2021/12/23 05:37:32 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyLeaf (6.45s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:37:35 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:35 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:36 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:37:36 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:37:37 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:37:37 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:37:37 INFO : sub dir/hello world: Deleted 2021/12/23 05:37:37 DEBUG : nested/sub dir/file: Starting multipart upload 2021/12/23 05:37:38 DEBUG : nested/sub dir/file: Uploading segment 0/6 size 6 2021/12/23 05:37:38 DEBUG : nested/sub dir/file: quickxor = 6e28c31ce8500632000000000600000000000000 OK 2021/12/23 05:37:38 INFO : nested/sub dir/file: Copied (new) 2021/12/23 05:37:38 INFO : nested/sub dir/file: Deleted 2021/12/23 05:37:38 INFO : sub dir: Removing directory 2021/12/23 05:37:38 INFO : nested/sub dir: Removing directory 2021/12/23 05:37:38 INFO : nested: Removing directory 2021/12/23 05:37:38 DEBUG : Local file system at /tmp/rclone3530588970: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (7.24s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:37:42 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:42 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:43 DEBUG : sub dir/hello world: Starting multipart upload 2021/12/23 05:37:44 DEBUG : nested/sub dir/file: Starting multipart upload 2021/12/23 05:37:45 DEBUG : nested/sub dir/file: Uploading segment 0/6 size 6 2021/12/23 05:37:45 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2021/12/23 05:37:45 DEBUG : nested/sub dir/file: quickxor = 6e28c31ce8500632000000000600000000000000 OK 2021/12/23 05:37:45 INFO : nested/sub dir/file: Copied (new) 2021/12/23 05:37:45 INFO : nested/sub dir/file: Deleted 2021/12/23 05:37:45 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2021/12/23 05:37:45 INFO : sub dir/hello world: Copied (new) 2021/12/23 05:37:45 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (7.09s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:37:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:49 DEBUG : existing: Starting multipart upload 2021/12/23 05:37:49 DEBUG : existing-b: Starting multipart upload 2021/12/23 05:37:50 DEBUG : existing-b: Uploading segment 0/6 size 6 2021/12/23 05:37:50 DEBUG : existing: Uploading segment 0/6 size 6 2021/12/23 05:37:51 DEBUG : existing-b: quickxor = 7478431bc2408737000000000600000000000000 OK 2021/12/23 05:37:51 INFO : existing-b: Copied (new) 2021/12/23 05:37:51 INFO : existing-b: Deleted 2021/12/23 05:37:51 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2021/12/23 05:37:51 INFO : existing: Copied (new) 2021/12/23 05:37:51 INFO : existing: Deleted 2021/12/23 05:37:51 DEBUG : existing: Destination exists, skipping 2021/12/23 05:37:51 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2021/12/23 05:37:51 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:37:51 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:37:51 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (3.16s) === RUN TestServerSideMove run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:37:52 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-sokemib9ganihor0yubokoj5" 2021/12/23 05:37:53 DEBUG : potato2: Starting multipart upload 2021/12/23 05:37:54 DEBUG : potato2: Uploading segment 0/60 size 60 2021/12/23 05:37:54 DEBUG : empty space: Starting multipart upload 2021/12/23 05:37:55 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:37:55 DEBUG : potato3: Starting multipart upload 2021/12/23 05:37:56 DEBUG : potato3: Uploading segment 0/68 size 68 sync_test.go:1215: Server side move (if possible) One drive root 'rclone-test-fogafix4peceluf6hapotiy6' -> One drive root 'rclone-test-sokemib9ganihor0yubokoj5' 2021/12/23 05:37:57 DEBUG : empty space: Starting multipart upload 2021/12/23 05:37:58 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:37:58 DEBUG : potato3: Starting multipart upload 2021/12/23 05:37:59 DEBUG : potato3: Uploading segment 0/60 size 60 2021/12/23 05:38:00 DEBUG : One drive root 'rclone-test-sokemib9ganihor0yubokoj5': Using server-side directory move 2021/12/23 05:38:00 INFO : One drive root 'rclone-test-sokemib9ganihor0yubokoj5': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2021/12/23 05:38:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2021/12/23 05:38:00 DEBUG : empty space: Unchanged skipping 2021/12/23 05:38:00 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/23 05:38:00 DEBUG : One drive root 'rclone-test-sokemib9ganihor0yubokoj5': Waiting for checks to finish 2021/12/23 05:38:00 INFO : empty space: Deleted 2021/12/23 05:38:00 DEBUG : One drive root 'rclone-test-sokemib9ganihor0yubokoj5': Waiting for transfers to finish 2021/12/23 05:38:00 INFO : potato3: Deleted 2021/12/23 05:38:00 INFO : potato2: Moved (server-side) 2021/12/23 05:38:01 INFO : potato3: Moved (server-side) 2021/12/23 05:38:01 INFO : There was nothing to transfer 2021/12/23 05:38:01 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-necotib9safiqat3nisihik8" 2021/12/23 05:38:02 DEBUG : One drive root 'rclone-test-necotib9safiqat3nisihik8': Using server-side directory move 2021/12/23 05:38:03 INFO : One drive root 'rclone-test-necotib9safiqat3nisihik8': Server side directory move succeeded 2021/12/23 05:38:04 ERROR : : error listing: directory not found 2021/12/23 05:38:04 DEBUG : One drive root 'rclone-test-necotib9safiqat3nisihik8': Purge remote 2021/12/23 05:38:04 DEBUG : One drive root 'rclone-test-sokemib9ganihor0yubokoj5': Purge remote 2021/12/23 05:38:04 purge failed: directory not found --- PASS: TestServerSideMove (12.54s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:38:05 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-gasezus0fovurap4mevoxad4" 2021/12/23 05:38:06 DEBUG : potato2: Starting multipart upload 2021/12/23 05:38:06 DEBUG : potato2: Uploading segment 0/60 size 60 2021/12/23 05:38:07 DEBUG : empty space: Starting multipart upload 2021/12/23 05:38:07 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:38:08 DEBUG : potato3: Starting multipart upload 2021/12/23 05:38:09 DEBUG : potato3: Uploading segment 0/68 size 68 sync_test.go:1215: Server side move (if possible) One drive root 'rclone-test-fogafix4peceluf6hapotiy6' -> One drive root 'rclone-test-gasezus0fovurap4mevoxad4' 2021/12/23 05:38:10 DEBUG : empty space: Starting multipart upload 2021/12/23 05:38:11 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:38:11 DEBUG : potato3: Starting multipart upload 2021/12/23 05:38:12 DEBUG : potato3: Uploading segment 0/60 size 60 2021/12/23 05:38:13 DEBUG : empty space: Excluded 2021/12/23 05:38:13 DEBUG : empty space: Excluded 2021/12/23 05:38:13 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/23 05:38:13 DEBUG : One drive root 'rclone-test-gasezus0fovurap4mevoxad4': Waiting for checks to finish 2021/12/23 05:38:13 DEBUG : One drive root 'rclone-test-gasezus0fovurap4mevoxad4': Waiting for transfers to finish 2021/12/23 05:38:13 INFO : potato3: Deleted 2021/12/23 05:38:13 INFO : potato2: Moved (server-side) 2021/12/23 05:38:14 INFO : potato3: Moved (server-side) 2021/12/23 05:38:14 INFO : There was nothing to transfer 2021/12/23 05:38:14 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-rumubob6hafabuk5xemapis5" 2021/12/23 05:38:15 DEBUG : empty space: Excluded 2021/12/23 05:38:15 DEBUG : One drive root 'rclone-test-rumubob6hafabuk5xemapis5': Waiting for checks to finish 2021/12/23 05:38:15 DEBUG : One drive root 'rclone-test-rumubob6hafabuk5xemapis5': Waiting for transfers to finish 2021/12/23 05:38:16 INFO : potato2: Moved (server-side) 2021/12/23 05:38:16 INFO : potato3: Moved (server-side) 2021/12/23 05:38:16 INFO : There was nothing to transfer 2021/12/23 05:38:17 DEBUG : One drive root 'rclone-test-rumubob6hafabuk5xemapis5': Purge remote 2021/12/23 05:38:17 DEBUG : One drive root 'rclone-test-gasezus0fovurap4mevoxad4': Purge remote --- PASS: TestServerSideMoveWithFilter (13.14s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:38:18 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-socazuy3kafujip1qikidey7" 2021/12/23 05:38:19 DEBUG : potato2: Starting multipart upload 2021/12/23 05:38:19 DEBUG : potato2: Uploading segment 0/60 size 60 2021/12/23 05:38:20 DEBUG : empty space: Starting multipart upload 2021/12/23 05:38:20 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:38:21 DEBUG : potato3: Starting multipart upload 2021/12/23 05:38:21 DEBUG : potato3: Uploading segment 0/68 size 68 2021/12/23 05:38:22 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) One drive root 'rclone-test-fogafix4peceluf6hapotiy6' -> One drive root 'rclone-test-socazuy3kafujip1qikidey7' 2021/12/23 05:38:24 DEBUG : empty space: Starting multipart upload 2021/12/23 05:38:24 DEBUG : empty space: Uploading segment 0/1 size 1 2021/12/23 05:38:25 DEBUG : potato3: Starting multipart upload 2021/12/23 05:38:25 DEBUG : potato3: Uploading segment 0/60 size 60 2021/12/23 05:38:26 DEBUG : One drive root 'rclone-test-socazuy3kafujip1qikidey7': Using server-side directory move 2021/12/23 05:38:26 INFO : One drive root 'rclone-test-socazuy3kafujip1qikidey7': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2021/12/23 05:38:27 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2021/12/23 05:38:27 DEBUG : empty space: Unchanged skipping 2021/12/23 05:38:27 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/12/23 05:38:27 DEBUG : One drive root 'rclone-test-socazuy3kafujip1qikidey7': Waiting for checks to finish 2021/12/23 05:38:27 INFO : empty space: Deleted 2021/12/23 05:38:27 DEBUG : One drive root 'rclone-test-socazuy3kafujip1qikidey7': Waiting for transfers to finish 2021/12/23 05:38:27 INFO : potato3: Deleted 2021/12/23 05:38:27 INFO : potato2: Moved (server-side) 2021/12/23 05:38:27 INFO : potato3: Moved (server-side) 2021/12/23 05:38:27 INFO : tomatoDir: Removing directory 2021/12/23 05:38:28 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': deleted 1 directories 2021/12/23 05:38:28 INFO : There was nothing to transfer 2021/12/23 05:38:28 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fenuqad5cezufuc1vaqozaz6" 2021/12/23 05:38:29 DEBUG : tomatoDir: Making directory 2021/12/23 05:38:30 DEBUG : One drive root 'rclone-test-fenuqad5cezufuc1vaqozaz6': Using server-side directory move 2021/12/23 05:38:31 INFO : One drive root 'rclone-test-fenuqad5cezufuc1vaqozaz6': Server side directory move succeeded 2021/12/23 05:38:33 ERROR : : error listing: directory not found 2021/12/23 05:38:33 ERROR : : error listing: directory not found 2021/12/23 05:38:33 DEBUG : One drive root 'rclone-test-fenuqad5cezufuc1vaqozaz6': Purge remote 2021/12/23 05:38:33 DEBUG : One drive root 'rclone-test-socazuy3kafujip1qikidey7': Purge remote 2021/12/23 05:38:33 purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (15.86s) === RUN TestServerSideMoveOverlap run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" sync_test.go:1399: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.53s) === RUN TestSyncOverlap run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:38:34 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/rclone-sync-test" --- PASS: TestSyncOverlap (2.25s) === RUN TestSyncCompareDest run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:38:37 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dst" 2021/12/23 05:38:38 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/CompareDest" 2021/12/23 05:38:41 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:38:41 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:38:41 DEBUG : one: Starting multipart upload 2021/12/23 05:38:42 DEBUG : one: Uploading segment 0/3 size 3 2021/12/23 05:38:42 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK 2021/12/23 05:38:42 INFO : one: Copied (new) 2021/12/23 05:38:42 DEBUG : Waiting for deletions to finish 2021/12/23 05:38:44 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:38:44 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/23 05:38:44 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:38:44 DEBUG : one: Starting multipart upload 2021/12/23 05:38:45 DEBUG : one: Uploading segment 0/5 size 5 2021/12/23 05:38:45 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2021/12/23 05:38:45 INFO : one: Copied (replaced existing) 2021/12/23 05:38:45 DEBUG : Waiting for deletions to finish 2021/12/23 05:38:46 DEBUG : dst/one: Starting multipart upload 2021/12/23 05:38:46 DEBUG : dst/one: Uploading segment 0/3 size 3 2021/12/23 05:38:48 DEBUG : CompareDest/one: Starting multipart upload 2021/12/23 05:38:48 DEBUG : CompareDest/one: Uploading segment 0/5 size 5 2021/12/23 05:38:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:38:50 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:38:50 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 05:38:50 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:38:50 DEBUG : Waiting for deletions to finish 2021/12/23 05:38:50 INFO : There was nothing to transfer 2021/12/23 05:38:51 DEBUG : CompareDest/two: Starting multipart upload 2021/12/23 05:38:51 DEBUG : CompareDest/two: Uploading segment 0/3 size 3 2021/12/23 05:38:53 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:38:53 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/23 05:38:53 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:38:54 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:38:54 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 05:38:54 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:38:54 DEBUG : Waiting for deletions to finish 2021/12/23 05:38:54 INFO : There was nothing to transfer 2021/12/23 05:38:55 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:38:55 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/23 05:38:55 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:38:56 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:38:56 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 05:38:56 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:38:56 DEBUG : Waiting for deletions to finish 2021/12/23 05:38:56 INFO : There was nothing to transfer 2021/12/23 05:38:58 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC 2021/12/23 05:38:58 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2021/12/23 05:38:58 DEBUG : two: Destination found in --compare-dest, skipping 2021/12/23 05:38:58 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:38:58 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:38:58 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 05:38:58 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:38:58 DEBUG : Waiting for deletions to finish 2021/12/23 05:38:58 INFO : There was nothing to transfer 2021/12/23 05:39:01 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/12/23 05:39:01 DEBUG : two: Starting multipart upload 2021/12/23 05:39:01 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:39:01 DEBUG : two: Uploading segment 0/5 size 5 2021/12/23 05:39:01 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:01 DEBUG : one: Destination found in --compare-dest, skipping 2021/12/23 05:39:01 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:39:02 DEBUG : two: quickxor = 74b8c31be8300300000000000500000000000000 OK 2021/12/23 05:39:02 INFO : two: Copied (new) 2021/12/23 05:39:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (28.17s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:39:05 DEBUG : pre-dest1/1: Starting multipart upload 2021/12/23 05:39:06 DEBUG : pre-dest1/1: Uploading segment 0/1 size 1 2021/12/23 05:39:07 DEBUG : pre-dest2/2: Starting multipart upload 2021/12/23 05:39:08 DEBUG : pre-dest2/2: Uploading segment 0/1 size 1 2021/12/23 05:39:09 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dest" 2021/12/23 05:39:10 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/pre-dest1" 2021/12/23 05:39:11 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/pre-dest2" 2021/12/23 05:39:13 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:39:13 DEBUG : 1: Destination found in --compare-dest, skipping 2021/12/23 05:39:14 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:39:14 DEBUG : 2: Destination found in --compare-dest, skipping 2021/12/23 05:39:15 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dest': Waiting for checks to finish 2021/12/23 05:39:15 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dest': Waiting for transfers to finish 2021/12/23 05:39:16 DEBUG : 3: Starting multipart upload 2021/12/23 05:39:16 DEBUG : 3: Uploading segment 0/1 size 1 2021/12/23 05:39:17 DEBUG : 3: quickxor = 3300000000000000000000000100000000000000 OK 2021/12/23 05:39:17 INFO : 3: Copied (new) 2021/12/23 05:39:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (16.91s) === RUN TestSyncCopyDest run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:39:22 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dst" 2021/12/23 05:39:23 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/CopyDest" 2021/12/23 05:39:26 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:39:26 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:39:26 DEBUG : one: Starting multipart upload 2021/12/23 05:39:27 DEBUG : one: Uploading segment 0/3 size 3 2021/12/23 05:39:27 DEBUG : one: quickxor = 6f70431900000000000000000300000000000000 OK 2021/12/23 05:39:27 INFO : one: Copied (new) 2021/12/23 05:39:27 DEBUG : Waiting for deletions to finish 2021/12/23 05:39:29 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:39:30 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/23 05:39:30 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:39:30 DEBUG : one: Starting multipart upload 2021/12/23 05:39:30 DEBUG : one: Uploading segment 0/5 size 5 2021/12/23 05:39:30 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2021/12/23 05:39:30 INFO : one: Copied (replaced existing) 2021/12/23 05:39:30 DEBUG : Waiting for deletions to finish 2021/12/23 05:39:31 DEBUG : dst/one: Starting multipart upload 2021/12/23 05:39:31 DEBUG : dst/one: Uploading segment 0/3 size 3 2021/12/23 05:39:32 DEBUG : CopyDest/one: Starting multipart upload 2021/12/23 05:39:33 DEBUG : CopyDest/one: Uploading segment 0/5 size 5 2021/12/23 05:39:34 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/BackupDir" 2021/12/23 05:39:36 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:39:37 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/12/23 05:39:39 INFO : one: Moved (server-side) 2021/12/23 05:39:42 DEBUG : one: quickxor = 6f704319e8200300000000000500000000000000 OK 2021/12/23 05:39:42 INFO : one: Copied (server-side copy) 2021/12/23 05:39:42 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/12/23 05:39:42 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:39:42 DEBUG : Waiting for deletions to finish 2021/12/23 05:39:42 DEBUG : CopyDest/two: Starting multipart upload 2021/12/23 05:39:43 DEBUG : CopyDest/two: Uploading segment 0/3 size 3 2021/12/23 05:39:45 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:49 DEBUG : two: quickxor = 74b8c31b00000000000000000300000000000000 OK 2021/12/23 05:39:49 INFO : two: Copied (server-side copy) 2021/12/23 05:39:49 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/12/23 05:39:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:39:49 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:49 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:49 DEBUG : one: Unchanged skipping 2021/12/23 05:39:49 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:39:49 DEBUG : Waiting for deletions to finish 2021/12/23 05:39:50 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:39:51 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:51 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:51 DEBUG : one: Unchanged skipping 2021/12/23 05:39:52 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:52 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:52 DEBUG : two: Unchanged skipping 2021/12/23 05:39:52 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:39:52 DEBUG : Waiting for deletions to finish 2021/12/23 05:39:52 INFO : There was nothing to transfer 2021/12/23 05:39:53 DEBUG : CopyDest/three: Starting multipart upload 2021/12/23 05:39:53 DEBUG : CopyDest/three: Uploading segment 0/5 size 5 2021/12/23 05:39:55 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/12/23 05:39:55 DEBUG : three: Destination not found in --copy-dest 2021/12/23 05:39:55 DEBUG : three: Starting multipart upload 2021/12/23 05:39:55 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:39:56 DEBUG : three: Uploading segment 0/7 size 7 2021/12/23 05:39:56 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:56 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:56 DEBUG : one: Unchanged skipping 2021/12/23 05:39:56 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:56 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/12/23 05:39:56 DEBUG : two: Unchanged skipping 2021/12/23 05:39:56 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:39:56 DEBUG : three: quickxor = 7440831cca50063acc0000000700000000000000 OK 2021/12/23 05:39:56 INFO : three: Copied (new) 2021/12/23 05:39:56 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (38.92s) === RUN TestSyncBackupDir run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:40:01 DEBUG : dst/one: Starting multipart upload 2021/12/23 05:40:02 DEBUG : dst/one: Uploading segment 0/3 size 3 2021/12/23 05:40:03 DEBUG : dst/two: Starting multipart upload 2021/12/23 05:40:03 DEBUG : dst/two: Uploading segment 0/3 size 3 2021/12/23 05:40:04 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:40:04 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2021/12/23 05:40:05 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dst" 2021/12/23 05:40:06 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/backup" 2021/12/23 05:40:08 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 05:40:08 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:40:08 DEBUG : two: Unchanged skipping 2021/12/23 05:40:08 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:40:10 INFO : one: Moved (server-side) 2021/12/23 05:40:10 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:40:10 DEBUG : one: Starting multipart upload 2021/12/23 05:40:10 DEBUG : one: Uploading segment 0/4 size 4 2021/12/23 05:40:11 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2021/12/23 05:40:11 INFO : one: Copied (new) 2021/12/23 05:40:11 DEBUG : Waiting for deletions to finish 2021/12/23 05:40:12 INFO : three.txt: Moved (server-side) 2021/12/23 05:40:12 INFO : three.txt: Moved into backup dir 2021/12/23 05:40:13 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:40:13 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2021/12/23 05:40:15 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 05:40:15 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:40:15 DEBUG : two: Unchanged skipping 2021/12/23 05:40:15 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:40:16 INFO : one: Deleted 2021/12/23 05:40:16 INFO : one: Moved (server-side) 2021/12/23 05:40:16 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:40:16 DEBUG : one: Starting multipart upload 2021/12/23 05:40:17 DEBUG : one: Uploading segment 0/5 size 5 2021/12/23 05:40:17 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2021/12/23 05:40:17 INFO : one: Copied (new) 2021/12/23 05:40:17 DEBUG : Waiting for deletions to finish 2021/12/23 05:40:18 INFO : three.txt: Deleted 2021/12/23 05:40:18 INFO : three.txt: Moved (server-side) 2021/12/23 05:40:18 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (21.42s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:40:24 DEBUG : dst/one: Starting multipart upload 2021/12/23 05:40:24 DEBUG : dst/one: Uploading segment 0/3 size 3 2021/12/23 05:40:25 DEBUG : dst/two: Starting multipart upload 2021/12/23 05:40:25 DEBUG : dst/two: Uploading segment 0/3 size 3 2021/12/23 05:40:26 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:40:27 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2021/12/23 05:40:28 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dst" 2021/12/23 05:40:30 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/backup" 2021/12/23 05:40:35 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 05:40:35 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:40:35 DEBUG : two: Unchanged skipping 2021/12/23 05:40:35 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:40:37 INFO : one: Moved (server-side) to: one.bak 2021/12/23 05:40:37 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:40:37 DEBUG : one: Starting multipart upload 2021/12/23 05:40:38 DEBUG : one: Uploading segment 0/4 size 4 2021/12/23 05:40:39 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2021/12/23 05:40:39 INFO : one: Copied (new) 2021/12/23 05:40:39 DEBUG : Waiting for deletions to finish 2021/12/23 05:40:40 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/12/23 05:40:40 INFO : three.txt: Moved into backup dir 2021/12/23 05:40:41 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:40:42 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2021/12/23 05:40:45 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 05:40:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:40:45 DEBUG : two: Unchanged skipping 2021/12/23 05:40:45 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:40:46 INFO : one.bak: Deleted 2021/12/23 05:40:47 INFO : one: Moved (server-side) to: one.bak 2021/12/23 05:40:47 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:40:47 DEBUG : one: Starting multipart upload 2021/12/23 05:40:48 DEBUG : one: Uploading segment 0/5 size 5 2021/12/23 05:40:48 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2021/12/23 05:40:48 INFO : one: Copied (new) 2021/12/23 05:40:48 DEBUG : Waiting for deletions to finish 2021/12/23 05:40:49 INFO : three.txt.bak: Deleted 2021/12/23 05:40:49 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/12/23 05:40:49 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (31.07s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:40:54 DEBUG : dst/one: Starting multipart upload 2021/12/23 05:40:55 DEBUG : dst/one: Uploading segment 0/3 size 3 2021/12/23 05:40:55 DEBUG : dst/two: Starting multipart upload 2021/12/23 05:40:56 DEBUG : dst/two: Uploading segment 0/3 size 3 2021/12/23 05:40:56 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:40:57 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2021/12/23 05:40:59 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dst" 2021/12/23 05:41:00 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/backup" 2021/12/23 05:41:03 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 05:41:03 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:41:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:41:03 DEBUG : two: Unchanged skipping 2021/12/23 05:41:05 INFO : one: Moved (server-side) to: one-2019-01-01 2021/12/23 05:41:05 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:41:05 DEBUG : one: Starting multipart upload 2021/12/23 05:41:06 DEBUG : one: Uploading segment 0/4 size 4 2021/12/23 05:41:07 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2021/12/23 05:41:07 INFO : one: Copied (new) 2021/12/23 05:41:07 DEBUG : Waiting for deletions to finish 2021/12/23 05:41:08 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/12/23 05:41:08 INFO : three.txt: Moved into backup dir 2021/12/23 05:41:09 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:41:10 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2021/12/23 05:41:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 05:41:12 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:41:12 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:41:12 DEBUG : two: Unchanged skipping 2021/12/23 05:41:14 INFO : one-2019-01-01: Deleted 2021/12/23 05:41:14 INFO : one: Moved (server-side) to: one-2019-01-01 2021/12/23 05:41:14 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:41:14 DEBUG : one: Starting multipart upload 2021/12/23 05:41:15 DEBUG : one: Uploading segment 0/5 size 5 2021/12/23 05:41:16 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2021/12/23 05:41:16 INFO : one: Copied (new) 2021/12/23 05:41:16 DEBUG : Waiting for deletions to finish 2021/12/23 05:41:17 INFO : three-2019-01-01.txt: Deleted 2021/12/23 05:41:18 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/12/23 05:41:18 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (29.88s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:41:24 DEBUG : dst/one: Starting multipart upload 2021/12/23 05:41:25 DEBUG : dst/one: Uploading segment 0/3 size 3 2021/12/23 05:41:25 DEBUG : dst/two: Starting multipart upload 2021/12/23 05:41:26 DEBUG : dst/two: Uploading segment 0/3 size 3 2021/12/23 05:41:27 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:41:28 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2021/12/23 05:41:29 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dst" 2021/12/23 05:41:31 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 05:41:31 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:41:31 DEBUG : two: Unchanged skipping 2021/12/23 05:41:31 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:41:33 INFO : one: Moved (server-side) to: one.bak 2021/12/23 05:41:33 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:41:33 DEBUG : one: Starting multipart upload 2021/12/23 05:41:34 DEBUG : one: Uploading segment 0/4 size 4 2021/12/23 05:41:35 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2021/12/23 05:41:35 INFO : one: Copied (new) 2021/12/23 05:41:35 DEBUG : Waiting for deletions to finish 2021/12/23 05:41:36 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/12/23 05:41:36 INFO : three.txt: Moved into backup dir 2021/12/23 05:41:37 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:41:38 DEBUG : dst/three.txt: Uploading segment 0/6 size 6 2021/12/23 05:41:40 DEBUG : one.bak: Excluded 2021/12/23 05:41:40 DEBUG : three.txt.bak: Excluded 2021/12/23 05:41:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 05:41:40 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:41:40 DEBUG : two: Unchanged skipping 2021/12/23 05:41:40 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for checks to finish 2021/12/23 05:41:43 INFO : one.bak: Deleted 2021/12/23 05:41:43 INFO : one: Moved (server-side) to: one.bak 2021/12/23 05:41:43 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6/dst': Waiting for transfers to finish 2021/12/23 05:41:43 DEBUG : one: Starting multipart upload 2021/12/23 05:41:44 DEBUG : one: Uploading segment 0/5 size 5 2021/12/23 05:41:44 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2021/12/23 05:41:44 INFO : one: Copied (new) 2021/12/23 05:41:44 DEBUG : Waiting for deletions to finish 2021/12/23 05:41:46 INFO : three.txt.bak: Deleted 2021/12/23 05:41:46 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/12/23 05:41:46 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (27.48s) === RUN TestSyncSuffix run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:41:51 DEBUG : dst/one: Starting multipart upload 2021/12/23 05:41:52 DEBUG : dst/one: Uploading segment 0/3 size 3 2021/12/23 05:41:52 DEBUG : dst/two: Starting multipart upload 2021/12/23 05:41:53 DEBUG : dst/two: Uploading segment 0/3 size 3 2021/12/23 05:41:54 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:41:55 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2021/12/23 05:41:56 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dst" 2021/12/23 05:41:58 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 05:42:00 INFO : one: Moved (server-side) to: one.bak 2021/12/23 05:42:00 DEBUG : one: Starting multipart upload 2021/12/23 05:42:00 DEBUG : one: Uploading segment 0/4 size 4 2021/12/23 05:42:01 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2021/12/23 05:42:01 INFO : one: Copied (new) 2021/12/23 05:42:01 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:42:01 DEBUG : two: Unchanged skipping 2021/12/23 05:42:03 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/12/23 05:42:04 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/12/23 05:42:04 DEBUG : three.txt: Starting multipart upload 2021/12/23 05:42:05 DEBUG : three.txt: Uploading segment 0/6 size 6 2021/12/23 05:42:06 DEBUG : three.txt: quickxor = 7440831cca508620000000000600000000000000 OK 2021/12/23 05:42:06 INFO : three.txt: Copied (new) 2021/12/23 05:42:09 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 05:42:10 INFO : one.bak: Deleted 2021/12/23 05:42:11 INFO : one: Moved (server-side) to: one.bak 2021/12/23 05:42:11 DEBUG : one: Starting multipart upload 2021/12/23 05:42:11 DEBUG : one: Uploading segment 0/5 size 5 2021/12/23 05:42:12 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2021/12/23 05:42:12 INFO : one: Copied (new) 2021/12/23 05:42:13 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:42:13 DEBUG : two: Unchanged skipping 2021/12/23 05:42:13 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/12/23 05:42:14 INFO : three.txt.bak: Deleted 2021/12/23 05:42:14 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/12/23 05:42:14 DEBUG : three.txt: Starting multipart upload 2021/12/23 05:42:15 DEBUG : three.txt: Uploading segment 0/19 size 19 2021/12/23 05:42:16 DEBUG : three.txt: quickxor = 154aeacc8949062110210d66234319e4500637d0 OK 2021/12/23 05:42:16 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (28.08s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:42:19 DEBUG : dst/one: Starting multipart upload 2021/12/23 05:42:20 DEBUG : dst/one: Uploading segment 0/3 size 3 2021/12/23 05:42:20 DEBUG : dst/two: Starting multipart upload 2021/12/23 05:42:21 DEBUG : dst/two: Uploading segment 0/3 size 3 2021/12/23 05:42:22 DEBUG : dst/three.txt: Starting multipart upload 2021/12/23 05:42:22 DEBUG : dst/three.txt: Uploading segment 0/5 size 5 2021/12/23 05:42:24 DEBUG : Creating backend with remote "TestOneDriveBusiness:rclone-test-fogafix4peceluf6hapotiy6/dst" 2021/12/23 05:42:26 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/12/23 05:42:27 INFO : one: Moved (server-side) to: one-2019-01-01 2021/12/23 05:42:27 DEBUG : one: Starting multipart upload 2021/12/23 05:42:27 DEBUG : one: Uploading segment 0/4 size 4 2021/12/23 05:42:28 DEBUG : one: quickxor = 6f70431982000000000000000400000000000000 OK 2021/12/23 05:42:28 INFO : one: Copied (new) 2021/12/23 05:42:28 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:42:28 DEBUG : two: Unchanged skipping 2021/12/23 05:42:29 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/12/23 05:42:30 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/12/23 05:42:30 DEBUG : three.txt: Starting multipart upload 2021/12/23 05:42:31 DEBUG : three.txt: Uploading segment 0/6 size 6 2021/12/23 05:42:32 DEBUG : three.txt: quickxor = 7440831cca508620000000000600000000000000 OK 2021/12/23 05:42:32 INFO : three.txt: Copied (new) 2021/12/23 05:42:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/12/23 05:42:35 INFO : one-2019-01-01: Deleted 2021/12/23 05:42:36 INFO : one: Moved (server-side) to: one-2019-01-01 2021/12/23 05:42:36 DEBUG : one: Starting multipart upload 2021/12/23 05:42:37 DEBUG : one: Uploading segment 0/5 size 5 2021/12/23 05:42:37 DEBUG : one: quickxor = 6f70431984200400000000000500000000000000 OK 2021/12/23 05:42:37 INFO : one: Copied (new) 2021/12/23 05:42:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:42:38 DEBUG : two: Unchanged skipping 2021/12/23 05:42:39 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/12/23 05:42:39 INFO : three-2019-01-01.txt: Deleted 2021/12/23 05:42:40 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/12/23 05:42:40 DEBUG : three.txt: Starting multipart upload 2021/12/23 05:42:40 DEBUG : three.txt: Uploading segment 0/19 size 19 2021/12/23 05:42:41 DEBUG : three.txt: quickxor = 154aeacc8949062110210d66234319e4500637d0 OK 2021/12/23 05:42:41 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (25.04s) === RUN TestSyncUTFNorm run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:42:44 DEBUG : Testêé: Starting multipart upload 2021/12/23 05:42:44 DEBUG : Testêé: Uploading segment 0/18 size 18 2021/12/23 05:42:45 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/12/23 05:42:45 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:42:45 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:42:45 DEBUG : Testêé: Starting multipart upload 2021/12/23 05:42:46 DEBUG : Testêé: Uploading segment 0/14 size 14 2021/12/23 05:42:46 DEBUG : Testêé: quickxor = 5440431ae6008234cc0104610e011dca30073a00 OK 2021/12/23 05:42:46 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/12/23 05:42:46 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.77s) === RUN TestSyncImmutable run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:42:48 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:42:48 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:42:48 DEBUG : existing: Starting multipart upload 2021/12/23 05:42:48 DEBUG : existing: Uploading segment 0/6 size 6 2021/12/23 05:42:49 DEBUG : existing: quickxor = 7078031dc2408737000000000600000000000000 OK 2021/12/23 05:42:49 INFO : existing: Copied (new) 2021/12/23 05:42:49 DEBUG : Waiting for deletions to finish 2021/12/23 05:42:50 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/12/23 05:42:50 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/12/23 05:42:50 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:42:50 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:42:50 ERROR : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': not deleting files as there were IO errors 2021/12/23 05:42:50 ERROR : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (3.39s) === RUN TestSyncIgnoreCase run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" sync_test.go:1955: Skipping test as local or remote are case-insensitive --- SKIP: TestSyncIgnoreCase (0.59s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", 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-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", 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-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (1.86s) --- SKIP: TestMaxTransfer/Hard (0.54s) --- SKIP: TestMaxTransfer/Soft (0.54s) --- SKIP: TestMaxTransfer/Cautious (0.78s) === RUN TestSyncConcurrentDelete run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:42:53 DEBUG : both0: Starting multipart upload 2021/12/23 05:42:54 DEBUG : both0: Uploading segment 0/6 size 6 2021/12/23 05:42:55 DEBUG : only0: Starting multipart upload 2021/12/23 05:42:55 DEBUG : only0: Uploading segment 0/6 size 6 2021/12/23 05:42:56 DEBUG : both1: Starting multipart upload 2021/12/23 05:42:56 DEBUG : both1: Uploading segment 0/6 size 6 2021/12/23 05:42:57 DEBUG : only1: Starting multipart upload 2021/12/23 05:42:57 DEBUG : only1: Uploading segment 0/6 size 6 2021/12/23 05:42:58 DEBUG : both2: Starting multipart upload 2021/12/23 05:42:59 DEBUG : both2: Uploading segment 0/6 size 6 2021/12/23 05:42:59 DEBUG : only2: Starting multipart upload 2021/12/23 05:43:00 DEBUG : only2: Uploading segment 0/6 size 6 2021/12/23 05:43:00 DEBUG : both3: Starting multipart upload 2021/12/23 05:43:01 DEBUG : both3: Uploading segment 0/6 size 6 2021/12/23 05:43:01 DEBUG : only3: Starting multipart upload 2021/12/23 05:43:02 DEBUG : only3: Uploading segment 0/6 size 6 2021/12/23 05:43:03 DEBUG : both4: Starting multipart upload 2021/12/23 05:43:03 DEBUG : both4: Uploading segment 0/6 size 6 2021/12/23 05:43:04 DEBUG : only4: Starting multipart upload 2021/12/23 05:43:05 DEBUG : only4: Uploading segment 0/6 size 6 2021/12/23 05:43:05 DEBUG : both5: Starting multipart upload 2021/12/23 05:43:06 DEBUG : both5: Uploading segment 0/6 size 6 2021/12/23 05:43:06 DEBUG : only5: Starting multipart upload 2021/12/23 05:43:07 DEBUG : only5: Uploading segment 0/6 size 6 2021/12/23 05:43:07 DEBUG : both6: Starting multipart upload 2021/12/23 05:43:08 DEBUG : both6: Uploading segment 0/6 size 6 2021/12/23 05:43:08 DEBUG : only6: Starting multipart upload 2021/12/23 05:43:09 DEBUG : only6: Uploading segment 0/6 size 6 2021/12/23 05:43:09 DEBUG : both7: Starting multipart upload 2021/12/23 05:43:10 DEBUG : both7: Uploading segment 0/6 size 6 2021/12/23 05:43:10 DEBUG : only7: Starting multipart upload 2021/12/23 05:43:11 DEBUG : only7: Uploading segment 0/6 size 6 2021/12/23 05:43:11 DEBUG : both8: Starting multipart upload 2021/12/23 05:43:12 DEBUG : both8: Uploading segment 0/6 size 6 2021/12/23 05:43:13 DEBUG : only8: Starting multipart upload 2021/12/23 05:43:13 DEBUG : only8: Uploading segment 0/6 size 6 2021/12/23 05:43:14 DEBUG : both9: Starting multipart upload 2021/12/23 05:43:14 DEBUG : both9: Uploading segment 0/6 size 6 2021/12/23 05:43:15 DEBUG : only9: Starting multipart upload 2021/12/23 05:43:15 DEBUG : only9: Uploading segment 0/6 size 6 2021/12/23 05:43:16 DEBUG : both10: Starting multipart upload 2021/12/23 05:43:16 DEBUG : both10: Uploading segment 0/6 size 6 2021/12/23 05:43:17 DEBUG : only10: Starting multipart upload 2021/12/23 05:43:18 DEBUG : only10: Uploading segment 0/6 size 6 2021/12/23 05:43:18 DEBUG : both11: Starting multipart upload 2021/12/23 05:43:19 DEBUG : both11: Uploading segment 0/6 size 6 2021/12/23 05:43:19 DEBUG : only11: Starting multipart upload 2021/12/23 05:43:20 DEBUG : only11: Uploading segment 0/6 size 6 2021/12/23 05:43:20 DEBUG : both12: Starting multipart upload 2021/12/23 05:43:21 DEBUG : both12: Uploading segment 0/6 size 6 2021/12/23 05:43:22 DEBUG : only12: Starting multipart upload 2021/12/23 05:43:22 DEBUG : only12: Uploading segment 0/6 size 6 2021/12/23 05:43:23 DEBUG : both13: Starting multipart upload 2021/12/23 05:43:23 DEBUG : both13: Uploading segment 0/6 size 6 2021/12/23 05:43:24 DEBUG : only13: Starting multipart upload 2021/12/23 05:43:24 DEBUG : only13: Uploading segment 0/6 size 6 2021/12/23 05:43:25 DEBUG : both14: Starting multipart upload 2021/12/23 05:43:26 DEBUG : both14: Uploading segment 0/6 size 6 2021/12/23 05:43:26 DEBUG : only14: Starting multipart upload 2021/12/23 05:43:27 DEBUG : only14: Uploading segment 0/6 size 6 2021/12/23 05:43:27 DEBUG : both15: Starting multipart upload 2021/12/23 05:43:28 DEBUG : both15: Uploading segment 0/6 size 6 2021/12/23 05:43:28 DEBUG : only15: Starting multipart upload 2021/12/23 05:43:29 DEBUG : only15: Uploading segment 0/6 size 6 2021/12/23 05:43:29 DEBUG : both16: Starting multipart upload 2021/12/23 05:43:30 DEBUG : both16: Uploading segment 0/6 size 6 2021/12/23 05:43:30 DEBUG : only16: Starting multipart upload 2021/12/23 05:43:31 DEBUG : only16: Uploading segment 0/6 size 6 2021/12/23 05:43:32 DEBUG : both17: Starting multipart upload 2021/12/23 05:43:32 DEBUG : both17: Uploading segment 0/6 size 6 2021/12/23 05:43:33 DEBUG : only17: Starting multipart upload 2021/12/23 05:43:33 DEBUG : only17: Uploading segment 0/6 size 6 2021/12/23 05:43:34 DEBUG : both18: Starting multipart upload 2021/12/23 05:43:34 DEBUG : both18: Uploading segment 0/6 size 6 2021/12/23 05:43:35 DEBUG : only18: Starting multipart upload 2021/12/23 05:43:35 DEBUG : only18: Uploading segment 0/6 size 6 2021/12/23 05:43:36 DEBUG : both19: Starting multipart upload 2021/12/23 05:43:36 DEBUG : both19: Uploading segment 0/6 size 6 2021/12/23 05:43:37 DEBUG : only19: Starting multipart upload 2021/12/23 05:43:37 DEBUG : only19: Uploading segment 0/6 size 6 2021/12/23 05:43:39 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:43:39 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both1: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both0: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both10: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both11: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both13: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both12: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both16: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both17: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both18: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both19: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both2: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both3: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both4: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both14: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both6: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both5: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both8: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both15: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both7: Unchanged skipping 2021/12/23 05:43:39 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:43:39 DEBUG : both9: Unchanged skipping 2021/12/23 05:43:39 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:43:39 DEBUG : Waiting for deletions to finish 2021/12/23 05:43:39 INFO : only8: Deleted 2021/12/23 05:43:39 INFO : only3: Deleted 2021/12/23 05:43:39 INFO : only1: Deleted 2021/12/23 05:43:39 INFO : only18: Deleted 2021/12/23 05:43:39 INFO : only10: Deleted 2021/12/23 05:43:39 INFO : only14: Deleted 2021/12/23 05:43:40 INFO : only2: Deleted 2021/12/23 05:43:40 INFO : only9: Deleted 2021/12/23 05:43:40 INFO : only6: Deleted 2021/12/23 05:43:40 INFO : only4: Deleted 2021/12/23 05:43:40 INFO : only12: Deleted 2021/12/23 05:43:40 INFO : only13: Deleted 2021/12/23 05:43:40 INFO : only15: Deleted 2021/12/23 05:43:40 INFO : only16: Deleted 2021/12/23 05:43:40 INFO : only19: Deleted 2021/12/23 05:43:40 INFO : only17: Deleted 2021/12/23 05:43:40 INFO : only0: Deleted 2021/12/23 05:43:40 INFO : only11: Deleted 2021/12/23 05:43:40 INFO : only5: Deleted 2021/12/23 05:43:40 INFO : only7: Deleted 2021/12/23 05:43:40 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (51.80s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "One drive root 'rclone-test-fogafix4peceluf6hapotiy6'", Local "Local file system at /tmp/rclone3530588970", Modify Window "1s" 2021/12/23 05:43:45 DEBUG : both0: Starting multipart upload 2021/12/23 05:43:46 DEBUG : both0: Uploading segment 0/6 size 6 2021/12/23 05:43:46 DEBUG : only0: Starting multipart upload 2021/12/23 05:43:47 DEBUG : only0: Uploading segment 0/6 size 6 2021/12/23 05:43:47 DEBUG : both1: Starting multipart upload 2021/12/23 05:43:48 DEBUG : both1: Uploading segment 0/6 size 6 2021/12/23 05:43:48 DEBUG : only1: Starting multipart upload 2021/12/23 05:43:49 DEBUG : only1: Uploading segment 0/6 size 6 2021/12/23 05:43:49 DEBUG : both2: Starting multipart upload 2021/12/23 05:43:50 DEBUG : both2: Uploading segment 0/6 size 6 2021/12/23 05:43:50 DEBUG : only2: Starting multipart upload 2021/12/23 05:43:51 DEBUG : only2: Uploading segment 0/6 size 6 2021/12/23 05:43:51 DEBUG : both3: Starting multipart upload 2021/12/23 05:43:52 DEBUG : both3: Uploading segment 0/6 size 6 2021/12/23 05:43:52 DEBUG : only3: Starting multipart upload 2021/12/23 05:43:53 DEBUG : only3: Uploading segment 0/6 size 6 2021/12/23 05:43:54 DEBUG : both4: Starting multipart upload 2021/12/23 05:43:54 DEBUG : both4: Uploading segment 0/6 size 6 2021/12/23 05:43:55 DEBUG : only4: Starting multipart upload 2021/12/23 05:43:55 DEBUG : only4: Uploading segment 0/6 size 6 2021/12/23 05:43:56 DEBUG : both5: Starting multipart upload 2021/12/23 05:43:56 DEBUG : both5: Uploading segment 0/6 size 6 2021/12/23 05:43:57 DEBUG : only5: Starting multipart upload 2021/12/23 05:43:57 DEBUG : only5: Uploading segment 0/6 size 6 2021/12/23 05:43:58 DEBUG : both6: Starting multipart upload 2021/12/23 05:43:58 DEBUG : both6: Uploading segment 0/6 size 6 2021/12/23 05:43:59 DEBUG : only6: Starting multipart upload 2021/12/23 05:43:59 DEBUG : only6: Uploading segment 0/6 size 6 2021/12/23 05:44:00 DEBUG : both7: Starting multipart upload 2021/12/23 05:44:01 DEBUG : both7: Uploading segment 0/6 size 6 2021/12/23 05:44:01 DEBUG : only7: Starting multipart upload 2021/12/23 05:44:02 DEBUG : only7: Uploading segment 0/6 size 6 2021/12/23 05:44:02 DEBUG : both8: Starting multipart upload 2021/12/23 05:44:03 DEBUG : both8: Uploading segment 0/6 size 6 2021/12/23 05:44:03 DEBUG : only8: Starting multipart upload 2021/12/23 05:44:04 DEBUG : only8: Uploading segment 0/6 size 6 2021/12/23 05:44:05 DEBUG : both9: Starting multipart upload 2021/12/23 05:44:06 DEBUG : both9: Uploading segment 0/6 size 6 2021/12/23 05:44:06 DEBUG : only9: Starting multipart upload 2021/12/23 05:44:07 DEBUG : only9: Uploading segment 0/6 size 6 2021/12/23 05:44:07 DEBUG : both10: Starting multipart upload 2021/12/23 05:44:09 DEBUG : both10: Uploading segment 0/6 size 6 2021/12/23 05:44:09 DEBUG : only10: Starting multipart upload 2021/12/23 05:44:10 DEBUG : only10: Uploading segment 0/6 size 6 2021/12/23 05:44:10 DEBUG : both11: Starting multipart upload 2021/12/23 05:44:11 DEBUG : both11: Uploading segment 0/6 size 6 2021/12/23 05:44:12 DEBUG : only11: Starting multipart upload 2021/12/23 05:44:13 DEBUG : only11: Uploading segment 0/6 size 6 2021/12/23 05:44:14 DEBUG : both12: Starting multipart upload 2021/12/23 05:44:15 DEBUG : both12: Uploading segment 0/6 size 6 2021/12/23 05:44:15 DEBUG : only12: Starting multipart upload 2021/12/23 05:44:16 DEBUG : only12: Uploading segment 0/6 size 6 2021/12/23 05:44:17 DEBUG : both13: Starting multipart upload 2021/12/23 05:44:17 DEBUG : both13: Uploading segment 0/6 size 6 2021/12/23 05:44:18 DEBUG : only13: Starting multipart upload 2021/12/23 05:44:19 DEBUG : only13: Uploading segment 0/6 size 6 2021/12/23 05:44:19 DEBUG : both14: Starting multipart upload 2021/12/23 05:44:20 DEBUG : both14: Uploading segment 0/6 size 6 2021/12/23 05:44:21 DEBUG : only14: Starting multipart upload 2021/12/23 05:44:22 DEBUG : only14: Uploading segment 0/6 size 6 2021/12/23 05:44:22 DEBUG : both15: Starting multipart upload 2021/12/23 05:44:23 DEBUG : both15: Uploading segment 0/6 size 6 2021/12/23 05:44:23 DEBUG : only15: Starting multipart upload 2021/12/23 05:44:24 DEBUG : only15: Uploading segment 0/6 size 6 2021/12/23 05:44:25 DEBUG : both16: Starting multipart upload 2021/12/23 05:44:26 DEBUG : both16: Uploading segment 0/6 size 6 2021/12/23 05:44:26 DEBUG : only16: Starting multipart upload 2021/12/23 05:44:27 DEBUG : only16: Uploading segment 0/6 size 6 2021/12/23 05:44:27 DEBUG : both17: Starting multipart upload 2021/12/23 05:44:28 DEBUG : both17: Uploading segment 0/6 size 6 2021/12/23 05:44:29 DEBUG : only17: Starting multipart upload 2021/12/23 05:44:30 DEBUG : only17: Uploading segment 0/6 size 6 2021/12/23 05:44:30 DEBUG : both18: Starting multipart upload 2021/12/23 05:44:32 DEBUG : both18: Uploading segment 0/6 size 6 2021/12/23 05:44:32 DEBUG : only18: Starting multipart upload 2021/12/23 05:44:33 DEBUG : only18: Uploading segment 0/6 size 6 2021/12/23 05:44:34 DEBUG : both19: Starting multipart upload 2021/12/23 05:44:34 DEBUG : both19: Uploading segment 0/6 size 6 2021/12/23 05:44:35 DEBUG : only19: Starting multipart upload 2021/12/23 05:44:36 DEBUG : only19: Uploading segment 0/6 size 6 2021/12/23 05:44:38 DEBUG : both0: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both0: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both1: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both10: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both10: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both11: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both11: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both12: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both12: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both13: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both13: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both14: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both14: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both15: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both15: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both16: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both16: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both17: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both17: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both18: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both18: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both19: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both19: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both2: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both3: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both3: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both4: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both4: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both5: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both5: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both6: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both6: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both7: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both7: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both8: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both8: Unchanged skipping 2021/12/23 05:44:38 DEBUG : both9: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/12/23 05:44:38 DEBUG : both9: Unchanged skipping 2021/12/23 05:44:38 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only0: Starting singlepart upload 2021/12/23 05:44:38 DEBUG : only1: Starting singlepart upload 2021/12/23 05:44:38 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2021/12/23 05:44:38 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for checks to finish 2021/12/23 05:44:38 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Waiting for transfers to finish 2021/12/23 05:44:38 DEBUG : only10: Starting singlepart upload 2021/12/23 05:44:38 DEBUG : only11: Starting singlepart upload 2021/12/23 05:44:39 DEBUG : only10: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:39 DEBUG : only10: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:39 ERROR : only10: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:39 INFO : only10: Removing failed copy 2021/12/23 05:44:39 DEBUG : only1: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:39 DEBUG : only1: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:39 ERROR : only1: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:39 INFO : only1: Removing failed copy 2021/12/23 05:44:39 DEBUG : only11: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:39 DEBUG : only11: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:39 ERROR : only11: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:39 INFO : only11: Removing failed copy 2021/12/23 05:44:39 DEBUG : only0: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:39 DEBUG : only0: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:39 ERROR : only0: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:39 INFO : only0: Removing failed copy 2021/12/23 05:44:40 DEBUG : only12: Starting singlepart upload 2021/12/23 05:44:40 DEBUG : only13: Starting singlepart upload 2021/12/23 05:44:40 DEBUG : only14: Starting singlepart upload 2021/12/23 05:44:40 DEBUG : only15: Starting singlepart upload 2021/12/23 05:44:41 DEBUG : only12: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:41 DEBUG : only12: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:41 ERROR : only12: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:41 INFO : only12: Removing failed copy 2021/12/23 05:44:41 DEBUG : only13: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:41 DEBUG : only13: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:41 ERROR : only13: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:41 INFO : only13: Removing failed copy 2021/12/23 05:44:41 DEBUG : only14: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:41 DEBUG : only14: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:41 ERROR : only14: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:41 INFO : only14: Removing failed copy 2021/12/23 05:44:41 DEBUG : only15: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:41 DEBUG : only15: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:41 ERROR : only15: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:41 INFO : only15: Removing failed copy 2021/12/23 05:44:41 DEBUG : only16: Starting singlepart upload 2021/12/23 05:44:42 DEBUG : only17: Starting singlepart upload 2021/12/23 05:44:42 DEBUG : only18: Starting singlepart upload 2021/12/23 05:44:42 DEBUG : only19: Starting singlepart upload 2021/12/23 05:44:43 DEBUG : only17: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:43 DEBUG : only17: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:43 ERROR : only17: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:43 INFO : only17: Removing failed copy 2021/12/23 05:44:43 DEBUG : only16: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:43 DEBUG : only16: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:43 ERROR : only16: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:43 INFO : only16: Removing failed copy 2021/12/23 05:44:43 DEBUG : only19: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:43 DEBUG : only19: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:43 ERROR : only19: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:43 INFO : only19: Removing failed copy 2021/12/23 05:44:43 DEBUG : only2: Starting singlepart upload 2021/12/23 05:44:43 DEBUG : only18: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:43 DEBUG : only18: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:43 ERROR : only18: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:43 INFO : only18: Removing failed copy 2021/12/23 05:44:43 DEBUG : only3: Starting singlepart upload 2021/12/23 05:44:44 DEBUG : only4: Starting singlepart upload 2021/12/23 05:44:44 DEBUG : only5: Starting singlepart upload 2021/12/23 05:44:44 DEBUG : only2: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:44 DEBUG : only2: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:44 ERROR : only2: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:44 INFO : only2: Removing failed copy 2021/12/23 05:44:44 DEBUG : only4: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:44 DEBUG : only4: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:44 ERROR : only4: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:44 INFO : only4: Removing failed copy 2021/12/23 05:44:45 DEBUG : only3: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:45 DEBUG : only3: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:45 ERROR : only3: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:45 INFO : only3: Removing failed copy 2021/12/23 05:44:45 DEBUG : only6: Starting singlepart upload 2021/12/23 05:44:45 DEBUG : only7: Starting singlepart upload 2021/12/23 05:44:45 DEBUG : only5: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:45 DEBUG : only5: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:45 ERROR : only5: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:45 INFO : only5: Removing failed copy 2021/12/23 05:44:45 DEBUG : only8: Starting singlepart upload 2021/12/23 05:44:46 DEBUG : only9: Starting singlepart upload 2021/12/23 05:44:46 DEBUG : only7: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:46 DEBUG : only7: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:46 ERROR : only7: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:46 INFO : only7: Removing failed copy 2021/12/23 05:44:46 DEBUG : only6: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:46 DEBUG : only6: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:46 ERROR : only6: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:46 INFO : only6: Removing failed copy 2021/12/23 05:44:46 DEBUG : only8: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:46 DEBUG : only8: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:46 ERROR : only8: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:46 INFO : only8: Removing failed copy 2021/12/23 05:44:47 DEBUG : only9: quickxor = 0000000000000000000000000000000000000000 (Local file system at /tmp/rclone3530588970) 2021/12/23 05:44:47 DEBUG : only9: quickxor = 7078031dc2408737000000000600000000000000 (One drive root 'rclone-test-fogafix4peceluf6hapotiy6') 2021/12/23 05:44:47 ERROR : only9: corrupted on transfer: quickxor hash differ "0000000000000000000000000000000000000000" vs "7078031dc2408737000000000600000000000000" 2021/12/23 05:44:47 INFO : only9: Removing failed copy 2021/12/23 05:44:47 ERROR : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': not deleting files as there were IO errors 2021/12/23 05:44:47 ERROR : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': 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 "only17" fstest.go:204: Not found "only0" fstest.go:204: Not found "only3" fstest.go:204: Not found "only10" fstest.go:204: Not found "only16" fstest.go:204: Not found "only2" fstest.go:204: Not found "only15" fstest.go:204: Not found "only19" fstest.go:204: Not found "only5" fstest.go:204: Not found "only7" fstest.go:204: Not found "only9" fstest.go:204: Not found "only13" fstest.go:204: Not found "only14" fstest.go:204: Not found "only6" fstest.go:204: Not found "only11" fstest.go:204: Not found "only12" fstest.go:204: Not found "only4" fstest.go:204: Not found "only18" fstest.go:204: Not found "only1" fstest.go:204: Not found "only8" 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 (77.59s) FAIL 2021/12/23 05:45:03 DEBUG : One drive root 'rclone-test-fogafix4peceluf6hapotiy6': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDriveBusiness: -verbose" - Finished ERROR in 15m28.554735935s (try 1/5): exit status 1: Failed [TestSyncConcurrentTruncate]