"go test -v -timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^Internal$/^TestServerSideCopyMove$'" - Starting (try 2/5)
2025/11/24 04:37:12 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-cufokit5zabo"
2025/11/24 04:37:12 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/24 04:37:12 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/11/24 04:37:13 DEBUG : Creating backend with remote "/tmp/rclone2561729"
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestOneDrive:"
2025/11/24 04:37:13 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-juwidih7robu"
2025/11/24 04:37:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/24 04:37:13 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2025/11/24 04:37:15 DEBUG : file name.txt: Starting multipart upload
2025/11/24 04:37:15 DEBUG : file name.txt: Uploading segment 0/100 size 100
2025/11/24 04:37:17 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Starting multipart upload
2025/11/24 04:37:17 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading segment 0/100 size 100
2025/11/24 04:37:18 DEBUG : file name.txt: Starting multipart upload
2025/11/24 04:37:18 DEBUG : file name.txt: skipping unsupported metadata item: rclone-test: potato
2025/11/24 04:37:18 INFO  : file name.txt: no writeable metadata found: map[rclone-test:potato]
2025/11/24 04:37:18 DEBUG : file name.txt: Uploading segment 0/100 size 100
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal
2025/11/24 04:37:18 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-foxufog6hape"
2025/11/24 04:37:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/24 04:37:18 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/11/24 04:37:19 DEBUG : Creating backend with remote "/tmp/rclone3268965537"
    run.go:185: Remote "OneDrive root 'rclone-test-foxufog6hape'", Local "Local file system at /tmp/rclone3268965537", Modify Window "1s"
2025/11/24 04:37:19 DEBUG : OneDrive root 'rclone-test-foxufog6hape': Purge remote
2025/11/24 04:37:20 NOTICE: purge failed: directory not found
2025/11/24 04:37:20 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-xoziyiy1wufe"
2025/11/24 04:37:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/24 04:37:20 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/11/24 04:37:21 DEBUG : Creating backend with remote "/tmp/rclone2842712541"
    run.go:185: Remote "OneDrive root 'rclone-test-xoziyiy1wufe'", Local "Local file system at /tmp/rclone2842712541", Modify Window "1s"
2025/11/24 04:37:21 DEBUG : OneDrive root 'rclone-test-xoziyiy1wufe': Purge remote
2025/11/24 04:37:22 NOTICE: purge failed: directory not found
2025/11/24 04:37:22 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-hifacev9kici"
2025/11/24 04:37:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/24 04:37:22 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/11/24 04:37:22 DEBUG : Creating backend with remote "/tmp/rclone413711924"
    run.go:185: Remote "OneDrive root 'rclone-test-hifacev9kici'", Local "Local file system at /tmp/rclone413711924", Modify Window "1s"
2025/11/24 04:37:22 DEBUG : OneDrive root 'rclone-test-hifacev9kici': Purge remote
2025/11/24 04:37:23 NOTICE: purge failed: directory not found
2025/11/24 04:37:23 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-sekuqul4rofu"
2025/11/24 04:37:23 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/24 04:37:23 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/11/24 04:37:24 DEBUG : Creating backend with remote "/tmp/rclone2315752321"
    run.go:185: Remote "OneDrive root 'rclone-test-sekuqul4rofu'", Local "Local file system at /tmp/rclone2315752321", Modify Window "1s"
2025/11/24 04:37:24 DEBUG : OneDrive root 'rclone-test-sekuqul4rofu': Purge remote
2025/11/24 04:37:25 NOTICE: purge failed: directory not found
2025/11/24 04:37:25 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-ramibaj7wumo"
2025/11/24 04:37:25 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/24 04:37:25 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/11/24 04:37:26 DEBUG : Creating backend with remote "/tmp/rclone2373364073"
    run.go:185: Remote "OneDrive root 'rclone-test-ramibaj7wumo'", Local "Local file system at /tmp/rclone2373364073", Modify Window "1s"
2025/11/24 04:37:26 DEBUG : OneDrive root 'rclone-test-ramibaj7wumo': Purge remote
2025/11/24 04:37:26 NOTICE: purge failed: directory not found
2025/11/24 04:37:26 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-dofuqeq3yegi"
2025/11/24 04:37:26 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/24 04:37:26 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup
2025/11/24 04:37:27 DEBUG : Creating backend with remote "/tmp/rclone3665416186"
    run.go:185: Remote "OneDrive root 'rclone-test-dofuqeq3yegi'", Local "Local file system at /tmp/rclone3665416186", Modify Window "1s"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/TestServerSideCopyMove
2025/11/24 04:37:28 DEBUG : some file-lotisoz1.txt: Starting multipart upload
2025/11/24 04:37:28 DEBUG : some file-lotisoz1.txt: Uploading segment 0/5 size 5
2025/11/24 04:37:29 DEBUG : some file-lotisoz1.txt: metadata permissions: to add: 1 to update: 0 to remove: 0
2025/11/24 04:37:30 DEBUG : some file-lotisoz1.txt: updated permissions (now has 1 permissions)
2025/11/24 04:37:30 DEBUG : perms: 
[
	{
		"id": "qRJaXkG3PuX2YqkNQN9qo09CvzM",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
	}
]
2025/11/24 04:37:32 DEBUG : perms: 
[
	{
		"id": "qRJaXkG3PuX2YqkNQN9qo09CvzM",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
	}
]
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: created-by-id: 71a96798e7b1d253
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: utime: 2025-11-24T04:37:29.067Z
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: id: 71a96798e7b1d253#71A96798E7B1D253!2943144
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: last-modified-by-display-name: Nick Craig-Wood
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: shared-time: 2025-11-24T04:37:30.011Z
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: content-type: text/plain
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: last-modified-by-id: 71a96798e7b1d253
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: shared-owner-id: 71a96798e7b1d253
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: shared-by-id: 
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: shared-scope: users
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: created-by-display-name: Nick Craig-Wood
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: skipping unsupported metadata item: malware-detected: false
2025/11/24 04:37:33 DEBUG : some file-deteqip0.txt: metadata permissions: to add: 1 to update: 0 to remove: 0
2025/11/24 04:37:34 DEBUG : some file-deteqip0.txt: updated permissions (now has 1 permissions)
2025/11/24 04:37:34 DEBUG : perms: 
[
	{
		"id": "G2MkqHC4O_3014PRDVay6xEyn9E",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lxgbPRKbtbipPe6E3-GAg"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lxgbPRKbtbipPe6E3-GAg"
	}
]
2025/11/24 04:37:35 DEBUG : perms: 
[
	{
		"id": "qRJaXkG3PuX2YqkNQN9qo09CvzM",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
	}
]
2025/11/24 04:37:35 DEBUG : perms: 
[
	{
		"id": "qRJaXkG3PuX2YqkNQN9qo09CvzM",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
	}
]
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: id: 71a96798e7b1d253#71A96798E7B1D253!2943144
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: created-by-display-name: Nick Craig-Wood
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: created-by-id: 71a96798e7b1d253
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: last-modified-by-id: 71a96798e7b1d253
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: malware-detected: false
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: shared-owner-id: 71a96798e7b1d253
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: shared-by-id: 
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: shared-scope: users
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: content-type: text/plain
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: utime: 2025-11-24T04:37:29.067Z
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: last-modified-by-display-name: Nick Craig-Wood
2025/11/24 04:37:35 DEBUG : some file-sohugix2.txt: skipping unsupported metadata item: shared-time: 2025-11-24T04:37:30.011Z
2025/11/24 04:37:36 DEBUG : some file-sohugix2.txt: skipping update for invalid roles: [read] (perm ID: qRJaXkG3PuX2YqkNQN9qo09CvzM)
2025/11/24 04:37:36 DEBUG : some file-sohugix2.txt: metadata permissions: to add: 0 to update: 0 to remove: 0
2025/11/24 04:37:36 DEBUG : some file-sohugix2.txt: updated permissions (now has 1 permissions)
2025/11/24 04:37:36 DEBUG : perms: 
[
	{
		"id": "qRJaXkG3PuX2YqkNQN9qo09CvzM",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lxgbPRKJxzkRLnuqVZdXA"
	}
]
2025/11/24 04:37:36 DEBUG : OneDrive root 'rclone-test-dofuqeq3yegi': Purge remote
2025/11/24 04:37:36 DEBUG : OneDrive root 'rclone-test-dofuqeq3yegi': Purge remote
2025/11/24 04:37:37 NOTICE: purge failed: directory not found
2025/11/24 04:37:37 DEBUG : OneDrive root 'rclone-test-dofuqeq3yegi': Purge remote
2025/11/24 04:37:37 NOTICE: purge failed: directory not found
2025/11/24 04:37:37 DEBUG : OneDrive root 'rclone-test-ramibaj7wumo': Purge remote
2025/11/24 04:37:37 NOTICE: purge failed: directory not found
2025/11/24 04:37:37 DEBUG : OneDrive root 'rclone-test-sekuqul4rofu': Purge remote
2025/11/24 04:37:38 NOTICE: purge failed: directory not found
2025/11/24 04:37:38 DEBUG : OneDrive root 'rclone-test-hifacev9kici': Purge remote
2025/11/24 04:37:38 NOTICE: purge failed: directory not found
2025/11/24 04:37:38 DEBUG : OneDrive root 'rclone-test-xoziyiy1wufe': Purge remote
2025/11/24 04:37:39 NOTICE: purge failed: directory not found
2025/11/24 04:37:39 DEBUG : OneDrive root 'rclone-test-foxufog6hape': Purge remote
2025/11/24 04:37:39 NOTICE: purge failed: directory not found
2025/11/24 04:37:39 ERROR : error listing: directory not found
--- PASS: TestIntegration (26.43s)
    --- PASS: TestIntegration/FsMkdir (25.12s)
        --- PASS: TestIntegration/FsMkdir/FsPutFiles (24.30s)
            --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal (20.37s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TestServerSideCopyMove (8.83s)
PASS
2025/11/24 04:37:39 DEBUG : OneDrive root 'rclone-test-cufokit5zabo': Purge remote
2025/11/24 04:37:40 NOTICE: purge failed: directory not found
ok  	github.com/rclone/rclone/backend/onedrive	27.237s
"go test -v -timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^Internal$/^TestServerSideCopyMove$'" - Finished OK in 28.261686971s (try 2/5)