"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestCopyOverSelf|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter)$'" - Starting (try 2/5)
2024/10/04 02:45:25 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-wifeyah1reke"
2024/10/04 02:45:25 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/10/04 02:45:25 DEBUG : Creating backend with remote "/tmp/rclone1552823782"
=== RUN   TestCopyOverSelf
    run.go:180: Remote "OneDrive root 'rclone-test-wifeyah1reke'", Local "Local file system at /tmp/rclone1552823782", Modify Window "1s"
2024/10/04 02:45:26 DEBUG : sub dir/hello world: Starting multipart upload
2024/10/04 02:45:26 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11
2024/10/04 02:45:27 DEBUG : sub dir: Directory modification time the same (differ by -856.792082ms, within tolerance 1s)
2024/10/04 02:45:27 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/10/04 02:45:27 DEBUG : OneDrive root 'rclone-test-wifeyah1reke': Waiting for checks to finish
2024/10/04 02:45:27 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/10/04 02:45:27 DEBUG : OneDrive root 'rclone-test-wifeyah1reke': Waiting for transfers to finish
2024/10/04 02:45:27 DEBUG : sub dir/hello world: Starting multipart upload
2024/10/04 02:45:27 DEBUG : sub dir/hello world: Uploading segment 0/17 size 17
2024/10/04 02:45:27 DEBUG : sub dir/hello world: quickxor = 49256d1bd8f00610dce10d727103194010863384 OK
2024/10/04 02:45:27 INFO  : sub dir/hello world: Copied (replaced existing)
2024/10/04 02:45:28 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "OneDrive root 'rclone-test-wifeyah1reke'", Local "Local file system at /tmp/rclone1552823782", Modify Window "1s"
--- PASS: TestCopyOverSelf (3.85s)
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "OneDrive root 'rclone-test-wifeyah1reke'", Local "Local file system at /tmp/rclone1552823782", Modify Window "1s"
2024/10/04 02:45:29 DEBUG : sub dir/hello world: Starting multipart upload
2024/10/04 02:45:30 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11
2024/10/04 02:45:30 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-hiwawoz7nuri"
    sync_test.go:626: Server side copy (if possible) OneDrive root 'rclone-test-wifeyah1reke' -> OneDrive root 'rclone-test-hiwawoz7nuri'
2024/10/04 02:45:31 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/10/04 02:45:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/10/04 02:45:31 DEBUG : OneDrive root 'rclone-test-hiwawoz7nuri': Waiting for checks to finish
2024/10/04 02:45:31 DEBUG : OneDrive root 'rclone-test-hiwawoz7nuri': Waiting for transfers to finish
2024/10/04 02:45:33 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK
2024/10/04 02:45:33 INFO  : sub dir/hello world: Copied (server-side copy)
2024/10/04 02:45:33 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "OneDrive root 'rclone-test-wifeyah1reke'", Local "Local file system at /tmp/rclone1552823782", Modify Window "1s"
2024/10/04 02:45:33 DEBUG : sub dir/hello world: Starting multipart upload
2024/10/04 02:45:33 DEBUG : sub dir/hello world: Uploading segment 0/17 size 17
2024/10/04 02:45:34 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1s)
2024/10/04 02:45:34 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/10/04 02:45:34 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/10/04 02:45:34 DEBUG : OneDrive root 'rclone-test-hiwawoz7nuri': Waiting for checks to finish
2024/10/04 02:45:34 DEBUG : OneDrive root 'rclone-test-hiwawoz7nuri': Waiting for transfers to finish
2024/10/04 02:45:37 ERROR : sub dir/hello world: Failed to copy: sub dir/hello world: async operation returned "failed"
2024/10/04 02:45:37 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/10/04 02:45:37 DEBUG : OneDrive root 'rclone-test-hiwawoz7nuri': Purge remote
--- FAIL: TestServerSideCopyOverSelf (9.28s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "OneDrive root 'rclone-test-wifeyah1reke'", Local "Local file system at /tmp/rclone1552823782", Modify Window "1s"
2024/10/04 02:45:39 DEBUG : sub dir/hello world: Starting multipart upload
2024/10/04 02:45:39 DEBUG : sub dir/hello world: Uploading segment 0/11 size 11
2024/10/04 02:45:39 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-fukicij1covu"
    sync_test.go:671: Server side copy (if possible) OneDrive root 'rclone-test-wifeyah1reke' -> OneDrive root 'rclone-test-fukicij1covu'
2024/10/04 02:45:40 DEBUG : Added delayed dir = "sub dir", newDst=<nil>
2024/10/04 02:45:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2024/10/04 02:45:40 DEBUG : OneDrive root 'rclone-test-fukicij1covu': Waiting for checks to finish
2024/10/04 02:45:40 DEBUG : OneDrive root 'rclone-test-fukicij1covu': Waiting for transfers to finish
2024/10/04 02:45:41 DEBUG : sub dir/hello world: quickxor = 6828031bd8f00610dce10d726b03190000000000 OK
2024/10/04 02:45:41 INFO  : sub dir/hello world: Copied (server-side copy)
2024/10/04 02:45:41 INFO  : sub dir: Set directory modification time (using DirSetModTime)
    run.go:180: Remote "OneDrive root 'rclone-test-wifeyah1reke'", Local "Local file system at /tmp/rclone1552823782", Modify Window "1s"
2024/10/04 02:45:42 DEBUG : sub dir/hello world: Starting multipart upload
2024/10/04 02:45:42 DEBUG : sub dir/hello world: Uploading segment 0/17 size 17
2024/10/04 02:45:42 DEBUG : OneDrive root 'rclone-test-fukicij1covu': Using server-side directory move
2024/10/04 02:45:42 INFO  : OneDrive root 'rclone-test-fukicij1covu': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/10/04 02:45:43 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1s)
2024/10/04 02:45:43 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/10/04 02:45:43 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2024/10/04 02:45:43 DEBUG : OneDrive root 'rclone-test-fukicij1covu': Waiting for checks to finish
2024/10/04 02:45:43 DEBUG : OneDrive root 'rclone-test-fukicij1covu': Waiting for transfers to finish
2024/10/04 02:45:43 INFO  : sub dir/hello world: Deleted
2024/10/04 02:45:43 INFO  : sub dir/hello world: Moved (server-side)
2024/10/04 02:45:43 INFO  : sub dir: Set directory modification time (using SetModTime)
2024/10/04 02:45:44 DEBUG : sub dir/hello world: Starting multipart upload
2024/10/04 02:45:44 DEBUG : sub dir/hello world: Uploading segment 0/24 size 24
2024/10/04 02:45:45 DEBUG : testing file moves
2024/10/04 02:45:45 DEBUG : sub dir: Directory modification time the same (differ by 0s, within tolerance 1s)
2024/10/04 02:45:45 DEBUG : Added delayed dir = "sub dir", newDst=sub dir
2024/10/04 02:45:45 DEBUG : OneDrive root 'rclone-test-fukicij1covu': Waiting for checks to finish
2024/10/04 02:45:45 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2024/10/04 02:45:45 DEBUG : OneDrive root 'rclone-test-fukicij1covu': Waiting for transfers to finish
2024/10/04 02:45:45 INFO  : sub dir/hello world: Deleted
2024/10/04 02:45:45 INFO  : sub dir/hello world: Moved (server-side)
2024/10/04 02:45:46 INFO  : sub dir: Set directory modification time (using SetModTime)
    run.go:180: Remote "OneDrive root 'rclone-test-wifeyah1reke'", Local "Local file system at /tmp/rclone1552823782", Modify Window "1s"
2024/10/04 02:45:46 DEBUG : OneDrive root 'rclone-test-fukicij1covu': Purge remote
--- PASS: TestServerSideMoveOverSelf (9.24s)
=== RUN   TestServerSideMoveWithFilter
    run.go:180: Remote "OneDrive root 'rclone-test-wifeyah1reke'", Local "Local file system at /tmp/rclone1552823782", Modify Window "1s"
2024/10/04 02:45:48 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-cazifaw1nite"
2024/10/04 02:45:48 DEBUG : potato2: Starting multipart upload
2024/10/04 02:45:48 DEBUG : potato2: Uploading segment 0/60 size 60
2024/10/04 02:45:49 DEBUG : empty space: Starting multipart upload
2024/10/04 02:45:49 DEBUG : empty space: Uploading segment 0/1 size 1
2024/10/04 02:45:49 DEBUG : potato3: Starting multipart upload
2024/10/04 02:45:49 DEBUG : potato3: Uploading segment 0/68 size 68
    sync_test.go:1705: Server side move (if possible) OneDrive root 'rclone-test-wifeyah1reke' -> OneDrive root 'rclone-test-cazifaw1nite'
2024/10/04 02:45:50 DEBUG : empty space: Starting multipart upload
2024/10/04 02:45:50 DEBUG : empty space: Uploading segment 0/1 size 1
2024/10/04 02:45:50 DEBUG : potato3: Starting multipart upload
2024/10/04 02:45:50 DEBUG : potato3: Uploading segment 0/60 size 60
2024/10/04 02:45:51 DEBUG : empty space: Excluded (Size Filter)
2024/10/04 02:45:51 DEBUG : empty space: Excluded
2024/10/04 02:45:51 DEBUG : empty space: Excluded (Size Filter)
2024/10/04 02:45:51 DEBUG : empty space: Excluded
2024/10/04 02:45:51 DEBUG : potato2: Need to transfer - File not found at Destination
2024/10/04 02:45:51 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/10/04 02:45:51 DEBUG : OneDrive root 'rclone-test-cazifaw1nite': Waiting for checks to finish
2024/10/04 02:45:51 DEBUG : OneDrive root 'rclone-test-cazifaw1nite': Waiting for transfers to finish
2024/10/04 02:45:51 INFO  : potato3: Deleted
2024/10/04 02:45:51 INFO  : potato2: Moved (server-side)
2024/10/04 02:45:52 INFO  : potato3: Moved (server-side)
2024/10/04 02:45:52 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-botobey7cunu"
2024/10/04 02:45:53 DEBUG : empty space: Excluded (Size Filter)
2024/10/04 02:45:53 DEBUG : empty space: Excluded
2024/10/04 02:45:53 DEBUG : potato2: Need to transfer - File not found at Destination
2024/10/04 02:45:53 DEBUG : potato3: Need to transfer - File not found at Destination
2024/10/04 02:45:53 DEBUG : OneDrive root 'rclone-test-botobey7cunu': Waiting for checks to finish
2024/10/04 02:45:53 DEBUG : OneDrive root 'rclone-test-botobey7cunu': Waiting for transfers to finish
2024/10/04 02:45:53 INFO  : potato2: Moved (server-side)
2024/10/04 02:45:53 INFO  : potato3: Moved (server-side)
2024/10/04 02:45:54 DEBUG : OneDrive root 'rclone-test-botobey7cunu': Purge remote
2024/10/04 02:45:54 DEBUG : OneDrive root 'rclone-test-cazifaw1nite': Purge remote
--- PASS: TestServerSideMoveWithFilter (6.86s)
FAIL
2024/10/04 02:45:55 DEBUG : OneDrive root 'rclone-test-wifeyah1reke': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestCopyOverSelf|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestServerSideMoveWithFilter)$'" - Finished ERROR in 29.914275599s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf]