"go test -v -timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^Internal$/^(TestDirectoryMetadata|TestWritePermissions)$'" - Starting (try 2/5)
2024/11/06 01:16:55 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-puyanic3loca"
2024/11/06 01:16:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/06 01:16:55 DEBUG : Creating backend with remote "/tmp/rclone1835451758"
=== RUN   TestIntegration
    fstests.go:442: Using remote "TestOneDrive:"
2024/11/06 01:16:55 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-ripovoq9fepu"
2024/11/06 01:16:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
=== RUN   TestIntegration/FsMkdir
=== RUN   TestIntegration/FsMkdir/FsPutFiles
2024/11/06 01:16:57 DEBUG : file name.txt: Starting multipart upload
2024/11/06 01:16:57 DEBUG : file name.txt: Uploading segment 0/100 size 100
2024/11/06 01:16:59 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Starting multipart upload
2024/11/06 01:16:59 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading segment 0/100 size 100
2024/11/06 01:16:59 DEBUG : file name.txt: Starting multipart upload
2024/11/06 01:16:59 DEBUG : file name.txt: skipping unsupported metadata item: rclone-test: potato
2024/11/06 01:16:59 INFO  : file name.txt: no writeable metadata found: map[rclone-test:potato]
2024/11/06 01:16:59 DEBUG : file name.txt: Uploading segment 0/100 size 100
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal
2024/11/06 01:17:00 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-forawas2hahu"
2024/11/06 01:17:00 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/06 01:17:00 DEBUG : Creating backend with remote "/tmp/rclone1933845022"
    run.go:180: Remote "OneDrive root 'rclone-test-forawas2hahu'", Local "Local file system at /tmp/rclone1933845022", Modify Window "1s"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/TestWritePermissions
2024/11/06 01:17:01 DEBUG : some file-jetayaf3.txt: Starting multipart upload
2024/11/06 01:17:01 DEBUG : some file-jetayaf3.txt: Uploading segment 0/5 size 5
2024/11/06 01:17:02 DEBUG : some file-jetayaf3.txt: metadata permissions: to add: 1 to update: 0 to remove: 0
2024/11/06 01:17:02 DEBUG : some file-jetayaf3.txt: updated permissions (now has 1 permissions)
2024/11/06 01:17:03 DEBUG : perms: 
[
	{
		"id": "d48J0gXhKu7ky0uEf32Ujg-blZA",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw"
	}
]
2024/11/06 01:17:03 DEBUG : some file-jetayaf3.txt: Starting multipart upload
2024/11/06 01:17:03 DEBUG : some file-jetayaf3.txt: Uploading segment 0/5 size 5
2024/11/06 01:17:03 DEBUG : perms: 
[
	{
		"id": "d48J0gXhKu7ky0uEf32Ujg-blZA",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw"
	}
]
2024/11/06 01:17:04 DEBUG : some file-jetayaf3.txt: sortPermissions: will update role to [write]
2024/11/06 01:17:04 DEBUG : some file-jetayaf3.txt: metadata permissions: to add: 0 to update: 1 to remove: 0
2024/11/06 01:17:04 DEBUG : some file-jetayaf3.txt: updated permissions (now has 1 permissions)
2024/11/06 01:17:04 DEBUG : perms: 
[
	{
		"id": "d48J0gXhKu7ky0uEf32Ujg-blZA",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw"
		},
		"roles": [
			"write"
		],
		"shareId": "s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw"
	}
]
2024/11/06 01:17:04 DEBUG : some file-jetayaf3.txt: Starting multipart upload
2024/11/06 01:17:04 DEBUG : some file-jetayaf3.txt: Uploading segment 0/5 size 5
2024/11/06 01:17:05 DEBUG : perms: 
[
	{
		"id": "d48J0gXhKu7ky0uEf32Ujg-blZA",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/t/s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw"
		},
		"roles": [
			"write"
		],
		"shareId": "s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw"
	}
]
2024/11/06 01:17:05 DEBUG : some file-jetayaf3.txt: sortPermissions: will remove permission: &{d48J0gXhKu7ky0uEf32Ujg-blZA 0xc000003200 [] <nil> [] 0xc000496340 <nil> 0xc00041a960 [write] s!BFPSseeYZ6lx78RVxgmcJqwucVT4fw} [write]  (perm ID: d48J0gXhKu7ky0uEf32Ujg-blZA)
2024/11/06 01:17:05 DEBUG : some file-jetayaf3.txt: metadata permissions: to add: 0 to update: 0 to remove: 1
2024/11/06 01:17:05 DEBUG : some file-jetayaf3.txt: updated permissions (now has 0 permissions)
2024/11/06 01:17:06 DEBUG : OneDrive root 'rclone-test-forawas2hahu': Purge remote
2024/11/06 01:17:06 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-yobusak1holi"
2024/11/06 01:17:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/06 01:17:06 DEBUG : Creating backend with remote "/tmp/rclone185756829"
=== NAME  TestIntegration/FsMkdir/FsPutFiles/Internal
    run.go:180: Remote "OneDrive root 'rclone-test-yobusak1holi'", Local "Local file system at /tmp/rclone185756829", Modify Window "1s"
2024/11/06 01:17:06 DEBUG : OneDrive root 'rclone-test-yobusak1holi': Purge remote
2024/11/06 01:17:07 NOTICE: purge failed: directory not found
2024/11/06 01:17:07 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-hulafag6jocu"
2024/11/06 01:17:07 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/06 01:17:07 DEBUG : Creating backend with remote "/tmp/rclone530135850"
    run.go:180: Remote "OneDrive root 'rclone-test-hulafag6jocu'", Local "Local file system at /tmp/rclone530135850", Modify Window "1s"
2024/11/06 01:17:07 DEBUG : OneDrive root 'rclone-test-hulafag6jocu': Purge remote
2024/11/06 01:17:08 NOTICE: purge failed: directory not found
2024/11/06 01:17:08 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-gapatoq5zaka"
2024/11/06 01:17:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/06 01:17:08 DEBUG : Creating backend with remote "/tmp/rclone3316397836"
    run.go:180: Remote "OneDrive root 'rclone-test-gapatoq5zaka'", Local "Local file system at /tmp/rclone3316397836", Modify Window "1s"
2024/11/06 01:17:08 DEBUG : OneDrive root 'rclone-test-gapatoq5zaka': Purge remote
2024/11/06 01:17:08 NOTICE: purge failed: directory not found
2024/11/06 01:17:08 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-gohexaj5wede"
2024/11/06 01:17:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/06 01:17:09 DEBUG : Creating backend with remote "/tmp/rclone1786868283"
    run.go:180: Remote "OneDrive root 'rclone-test-gohexaj5wede'", Local "Local file system at /tmp/rclone1786868283", Modify Window "1s"
=== RUN   TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata
2024/11/06 01:17:09 DEBUG : subdir: Making directory with metadata
2024/11/06 01:17:09 DEBUG : subdir: skipping unsupported metadata item: content-type: inode/directory
2024/11/06 01:17:10 DEBUG : subdir: metadata permissions: to add: 1 to update: 0 to remove: 0
2024/11/06 01:17:11 DEBUG : subdir: updated permissions (now has 1 permissions)
2024/11/06 01:17:11 INFO  : subdir: Made directory with metadata (mtime=2023-08-26T23:13:06.499Z)
2024/11/06 01:17:11 DEBUG : perms: 
[
	{
		"id": "x9zqJFFmURJK4D2SqBXAXhAaksA",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/f/s!BFPSseeYZ6lx78RXENSKMV2UlHZTOA"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lx78RXENSKMV2UlHZTOA"
	}
]
    onedrive_internal_test.go:218: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:218
        	Error:      	Not equal: 
        	            	expected: time.Date(2023, time.August, 26, 23, 13, 6, 0, time.UTC)
        	            	actual  : time.Date(2023, time.August, 26, 23, 13, 6, 499000000, time.UTC)
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,2 +1,2 @@
        	            	-(time.Time) 2023-08-26 23:13:06 +0000 UTC
        	            	+(time.Time) 2023-08-26 23:13:06.499 +0000 UTC
        	            	 
        	Test:       	TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata
2024/11/06 01:17:11 INFO  : subdir: Set directory modification time (using SetModTime)
    onedrive_internal_test.go:222: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:222
        	Error:      	Not equal: 
        	            	expected: time.Date(2020, time.February, 29, 12, 34, 56, 0, time.UTC)
        	            	actual  : time.Date(2020, time.February, 29, 12, 34, 56, 789000000, time.UTC)
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,2 +1,2 @@
        	            	-(time.Time) 2020-02-29 12:34:56 +0000 UTC
        	            	+(time.Time) 2020-02-29 12:34:56.789 +0000 UTC
        	            	 
        	Test:       	TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata
2024/11/06 01:17:11 DEBUG : perms: 
[
	{
		"id": "x9zqJFFmURJK4D2SqBXAXhAaksA",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/f/s!BFPSseeYZ6lx78RXENSKMV2UlHZTOA"
		},
		"roles": [
			"read"
		],
		"shareId": "s!BFPSseeYZ6lx78RXENSKMV2UlHZTOA"
	}
]
2024/11/06 01:17:11 DEBUG : subdir: Making directory with metadata
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: utime: 2024-11-06T01:17:09.96Z
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: created-by-display-name: Nick Craig-Wood
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: created-by-id: 71a96798e7b1d253
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: shared-time: 2024-11-06T01:17:10.818Z
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: last-modified-by-id: 71a96798e7b1d253
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: malware-detected: false
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: content-type: inode/directory
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: last-modified-by-display-name: Nick Craig-Wood
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: id: 71a96798e7b1d253#71A96798E7B1D253!1827415
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: shared-by-id: 
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: shared-scope: users
2024/11/06 01:17:11 DEBUG : subdir: skipping unsupported metadata item: shared-owner-id: 71a96798e7b1d253
2024/11/06 01:17:12 DEBUG : subdir: metadata permissions: to add: 1 to update: 0 to remove: 0
2024/11/06 01:17:13 DEBUG : subdir: updated permissions (now has 1 permissions)
2024/11/06 01:17:13 INFO  : subdir: Made directory with metadata (mtime=2023-08-26T23:13:06.499Z)
2024/11/06 01:17:13 DEBUG : perms: 
[
	{
		"id": "x9zqJFFmURJK4D2SqBXAXhAaksA",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/f/s!BFPSseeYZ6lx78RXENSKMV2UlHZTOA"
		},
		"roles": [
			"write"
		],
		"shareId": "s!BFPSseeYZ6lx78RXENSKMV2UlHZTOA"
	}
]
2024/11/06 01:17:13 DEBUG : perms: 
[
	{
		"id": "x9zqJFFmURJK4D2SqBXAXhAaksA",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/f/s!BFPSseeYZ6lx78RXENSKMV2UlHZTOA"
		},
		"roles": [
			"write"
		],
		"shareId": "s!BFPSseeYZ6lx78RXENSKMV2UlHZTOA"
	}
]
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: content-type: inode/directory
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: created-by-id: 71a96798e7b1d253
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: id: 71a96798e7b1d253#71A96798E7B1D253!1827415
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: malware-detected: false
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: shared-time: 2024-11-06T01:17:10.818Z
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: utime: 2024-11-06T01:17:09.96Z
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: last-modified-by-display-name: Nick Craig-Wood
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: last-modified-by-id: 71a96798e7b1d253
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: shared-scope: users
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: shared-owner-id: 71a96798e7b1d253
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: shared-by-id: 
2024/11/06 01:17:13 DEBUG : subdir2: skipping unsupported metadata item: created-by-display-name: Nick Craig-Wood
2024/11/06 01:17:14 DEBUG : subdir2: metadata permissions: to add: 1 to update: 0 to remove: 0
2024/11/06 01:17:14 DEBUG : subdir2: updated permissions (now has 1 permissions)
2024/11/06 01:17:14 INFO  : subdir2: Updated directory metadata
2024/11/06 01:17:15 DEBUG : perms: 
[
	{
		"id": "PDT2zMXxD7CntCi2RJJw2JpAHbg",
		"grantedTo": {
			"user": {
				"id": "ryan@contoso.com"
			},
			"application": {},
			"device": {},
			"group": {},
			"siteGroup": {},
			"siteUser": {}
		},
		"invitation": {
			"email": "ryan@contoso.com"
		},
		"link": {
			"webUrl": "https://1drv.ms/f/s!BFPSseeYZ6lx78RY2Jggp2u5WseEMg"
		},
		"roles": [
			"write"
		],
		"shareId": "s!BFPSseeYZ6lx78RY2Jggp2u5WseEMg"
	}
]
2024/11/06 01:17:15 DEBUG : subdir2/some file-huyelov7.txt: Starting multipart upload
2024/11/06 01:17:15 DEBUG : subdir2/some file-huyelov7.txt: Uploading segment 0/8 size 8
2024/11/06 01:17:15 DEBUG : OneDrive root 'rclone-test-gohexaj5wede': Purge remote
2024/11/06 01:17:16 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-tepeted0wadi"
2024/11/06 01:17:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/06 01:17:16 DEBUG : Creating backend with remote "/tmp/rclone357616200"
=== NAME  TestIntegration/FsMkdir/FsPutFiles/Internal
    run.go:180: Remote "OneDrive root 'rclone-test-tepeted0wadi'", Local "Local file system at /tmp/rclone357616200", Modify Window "1s"
2024/11/06 01:17:16 DEBUG : OneDrive root 'rclone-test-tepeted0wadi': Purge remote
2024/11/06 01:17:16 NOTICE: purge failed: directory not found
2024/11/06 01:17:16 DEBUG : OneDrive root 'rclone-test-tepeted0wadi': Purge remote
2024/11/06 01:17:17 NOTICE: purge failed: directory not found
2024/11/06 01:17:17 DEBUG : OneDrive root 'rclone-test-tepeted0wadi': Purge remote
2024/11/06 01:17:17 NOTICE: purge failed: directory not found
2024/11/06 01:17:17 DEBUG : OneDrive root 'rclone-test-gohexaj5wede': Purge remote
2024/11/06 01:17:17 NOTICE: purge failed: directory not found
2024/11/06 01:17:17 DEBUG : OneDrive root 'rclone-test-gapatoq5zaka': Purge remote
2024/11/06 01:17:17 NOTICE: purge failed: directory not found
2024/11/06 01:17:17 DEBUG : OneDrive root 'rclone-test-hulafag6jocu': Purge remote
2024/11/06 01:17:17 NOTICE: purge failed: directory not found
2024/11/06 01:17:17 DEBUG : OneDrive root 'rclone-test-yobusak1holi': Purge remote
2024/11/06 01:17:17 NOTICE: purge failed: directory not found
2024/11/06 01:17:17 DEBUG : OneDrive root 'rclone-test-forawas2hahu': Purge remote
2024/11/06 01:17:18 NOTICE: purge failed: directory not found
2024/11/06 01:17:18 ERROR : : error listing: directory not found
--- FAIL: TestIntegration (23.04s)
    --- FAIL: TestIntegration/FsMkdir (21.76s)
        --- FAIL: TestIntegration/FsMkdir/FsPutFiles (21.03s)
            --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal (17.64s)
                --- PASS: TestIntegration/FsMkdir/FsPutFiles/Internal/TestWritePermissions (5.16s)
                --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata (6.52s)
FAIL
2024/11/06 01:17:18 DEBUG : OneDrive root 'rclone-test-puyanic3loca': Purge remote
2024/11/06 01:17:18 NOTICE: purge failed: directory not found
exit status 1
FAIL	github.com/rclone/rclone/backend/onedrive	23.795s
"go test -v -timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutFiles$/^Internal$/^(TestDirectoryMetadata|TestWritePermissions)$'" - Finished ERROR in 24.681211752s (try 2/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata]