"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncAfterChangingFilesSizeOnly|TestSyncWithTrackRenames)$'" - Starting (try 2/5) 2024/09/30 02:05:55 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-vazakud8wofi" 2024/09/30 02:05:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/09/30 02:05:56 DEBUG : Creating backend with remote "/tmp/rclone3206816604" === RUN TestServerSideCopyOverSelf run.go:180: Remote "OneDrive root 'rclone-test-vazakud8wofi'", Local "Local file system at /tmp/rclone3206816604", Modify Window "1ms" 2024/09/30 02:05:57 DEBUG : sub dir/hello world: Starting multipart upload 2024/09/30 02:05:57 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11 2024/09/30 02:05:58 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-xeqifev5bodo" sync_test.go:626: Server side copy (if possible) OneDrive root 'rclone-test-vazakud8wofi' -> OneDrive root 'rclone-test-xeqifev5bodo' 2024/09/30 02:05:58 DEBUG : Added delayed dir = "sub dir", newDst= 2024/09/30 02:05:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/09/30 02:05:58 DEBUG : OneDrive root 'rclone-test-xeqifev5bodo': Waiting for checks to finish 2024/09/30 02:05:58 DEBUG : OneDrive root 'rclone-test-xeqifev5bodo': Waiting for transfers to finish 2024/09/30 02:06:00 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK 2024/09/30 02:06:00 INFO : sub dir/hello world: Copied (server-side copy) 2024/09/30 02:06:00 INFO : sub dir: Set directory modification time (using DirSetModTime) run.go:180: Remote "OneDrive root 'rclone-test-vazakud8wofi'", Local "Local file system at /tmp/rclone3206816604", Modify Window "1ms" 2024/09/30 02:06:01 DEBUG : sub dir/hello world: Starting multipart upload 2024/09/30 02:06:01 DEBUG : sub dir/hello world: Uploading segment 0/17 size 17 2024/09/30 02:06:02 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1ms) 2024/09/30 02:06:02 DEBUG : Added delayed dir = "sub dir", newDst=sub dir 2024/09/30 02:06:02 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2024/09/30 02:06:02 DEBUG : OneDrive root 'rclone-test-xeqifev5bodo': Waiting for checks to finish 2024/09/30 02:06:02 DEBUG : OneDrive root 'rclone-test-xeqifev5bodo': Waiting for transfers to finish 2024/09/30 02:06:03 ERROR : sub dir/hello world: Failed to copy: sub dir/hello world: async operation returned "failed" 2024/09/30 02:06:03 INFO : sub dir: Set directory modification time (using SetModTime) sync_test.go:639: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:639 Error: Received unexpected error: sub dir/hello world: async operation returned "failed" Test: TestServerSideCopyOverSelf 2024/09/30 02:06:03 DEBUG : OneDrive root 'rclone-test-xeqifev5bodo': Purge remote --- FAIL: TestServerSideCopyOverSelf (8.81s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "OneDrive root 'rclone-test-vazakud8wofi'", Local "Local file system at /tmp/rclone3206816604", Modify Window "1ms" 2024/09/30 02:06:05 DEBUG : potato: Starting multipart upload 2024/09/30 02:06:05 DEBUG : potato: Uploading segment 0/60 size 60 2024/09/30 02:06:05 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/09/30 02:06:05 DEBUG : potato: Starting multipart upload 2024/09/30 02:06:05 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Waiting for checks to finish 2024/09/30 02:06:05 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Waiting for transfers to finish 2024/09/30 02:06:06 DEBUG : potato: Uploading segment 0/21 size 21 2024/09/30 02:06:06 DEBUG : potato: quickxor = d26463389bd86e62ce010462bd031d40308730b4 OK 2024/09/30 02:06:06 INFO : potato: Copied (replaced existing) 2024/09/30 02:06:06 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-vazakud8wofi'", Local "Local file system at /tmp/rclone3206816604", Modify Window "1ms" --- PASS: TestSyncAfterChangingFilesSizeOnly (2.03s) === RUN TestSyncWithTrackRenames run.go:180: Remote "OneDrive root 'rclone-test-vazakud8wofi'", Local "Local file system at /tmp/rclone3206816604", Modify Window "1ms" sync_test.go:1538: Can track renames: true 2024/09/30 02:06:07 INFO : OneDrive root 'rclone-test-vazakud8wofi': Making map for --track-renames 2024/09/30 02:06:07 INFO : OneDrive root 'rclone-test-vazakud8wofi': Finished making map for --track-renames 2024/09/30 02:06:07 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Waiting for checks to finish 2024/09/30 02:06:07 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/09/30 02:06:07 DEBUG : potato: Starting multipart upload 2024/09/30 02:06:07 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/09/30 02:06:07 DEBUG : yam: Starting multipart upload 2024/09/30 02:06:07 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Waiting for renames to finish 2024/09/30 02:06:07 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Waiting for transfers to finish 2024/09/30 02:06:07 DEBUG : potato: Uploading segment 0/14 size 14 2024/09/30 02:06:07 DEBUG : yam: Uploading segment 0/11 size 11 2024/09/30 02:06:07 DEBUG : yam: quickxor = 5908431b40308437b8810e657b031d0000000000 OK 2024/09/30 02:06:07 INFO : yam: Copied (new) 2024/09/30 02:06:07 DEBUG : potato: quickxor = 5078031dc24087378060086f7e031dcae0063a00 OK 2024/09/30 02:06:07 INFO : potato: Copied (new) 2024/09/30 02:06:07 DEBUG : Waiting for deletions to finish run.go:180: Remote "OneDrive root 'rclone-test-vazakud8wofi'", Local "Local file system at /tmp/rclone3206816604", Modify Window "1ms" 2024/09/30 02:06:08 DEBUG : potato: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2024/09/30 02:06:08 INFO : OneDrive root 'rclone-test-vazakud8wofi': Making map for --track-renames 2024/09/30 02:06:08 INFO : OneDrive root 'rclone-test-vazakud8wofi': Finished making map for --track-renames 2024/09/30 02:06:08 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Waiting for checks to finish 2024/09/30 02:06:08 DEBUG : potato: Unchanged skipping 2024/09/30 02:06:08 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Waiting for renames to finish 2024/09/30 02:06:08 INFO : yam: Moved (server-side) to: yaml 2024/09/30 02:06:08 INFO : yaml: Renamed from "yam" 2024/09/30 02:06:08 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Waiting for transfers to finish 2024/09/30 02:06:08 DEBUG : Waiting for deletions to finish 2024/09/30 02:06:08 INFO : There was nothing to transfer run.go:180: Remote "OneDrive root 'rclone-test-vazakud8wofi'", Local "Local file system at /tmp/rclone3206816604", Modify Window "1ms" --- PASS: TestSyncWithTrackRenames (2.77s) FAIL 2024/09/30 02:06:10 DEBUG : OneDrive root 'rclone-test-vazakud8wofi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncAfterChangingFilesSizeOnly|TestSyncWithTrackRenames)$'" - Finished ERROR in 14.369601715s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf]