"go test -v -timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^TestIntegration$/^FsMkdir$/^(FsMkdirMetadata|FsPutError)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^Internal$/^(TestDirectoryMetadata|TestMetadataMapper|TestReadMetadata|TestReadPermissions|TestServerSideCopyMove|TestUploadSinglePart|TestWritePermissions)$'" - Starting (try 4/5) 2026/01/25 02:46:06 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-becudan2gure" 2026/01/25 02:46:06 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/25 02:46:06 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/25 02:46:09 DEBUG : Creating backend with remote "/tmp/rclone939267748" === RUN TestIntegration fstests.go:438: Using remote "TestOneDrive:" 2026/01/25 02:46:09 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qulumiw6zefe" 2026/01/25 02:46:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/25 02:46:09 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsPutError 2026/01/25 02:46:14 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Starting multipart upload 2026/01/25 02:46:15 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading segment 0/10240 size 10240 2026/01/25 02:46:15 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Cancelling multipart upload: Put "https://my.microsoftpersonalcontent.com/personal/71a96798e7b1d253/_api/v2.0/drives/b!vKC3DXjgjk-Lp7XrVMpysWhcKnHtT8dLkaHuPSl-l2DGmC7WzjOlT7z5hN2HZRn_/items/01C6Y6555QJWIW3YATJRAZQUQX7VUZCBAA/uploadSession?guid='fd7b0ce2-5b9e-4cce-a0aa-70cb9357455f'&overwrite=True&rename=False&dc=0&tempauth=v1e.eyJzaXRlaWQiOiIwZGI3YTBiYy1lMDc4LTRmOGUtOGJhNy1iNWViNTRjYTcyYjEiLCJhcHBfZGlzcGxheW5hbWUiOiJyY2xvbmUiLCJhcHBpZCI6ImIxNTY2NWQ5LWVkYTYtNDA5Mi04NTM5LTBlZWMzNzZhZmQ1OSIsImF1ZCI6IjAwMDAwMDAzLTAwMDAtMGZmMS1jZTAwLTAwMDAwMDAwMDAwMC9teS5taWNyb3NvZnRwZXJzb25hbGNvbnRlbnQuY29tQDkxODgwNDBkLTZjNjctNGM1Yi1iMTEyLTM2YTMwNGI2NmRhZCIsImV4cCI6IjE3NjkzOTU1NzUifQ.b6rqyIcI6tV_cWTrMMWvtH9exC066fbxX-bzxe0zBa6Kcnbg7EvxhUdbDZ76ngQzDF3_0rOJ--THS78mYDvJHm_tiQz1PDaQ1mojy-KUbzJikUiru09EFxh0Grab3xWGeBdE7wAgkVA4rsBfXOjhbvsA46RWyRA1bTSc3IjywQIGgtOCSWfGWVv4aANC8u74LATi52nsEZOk3bkljXNtzD4GJ_C30by0YpO3cNVlmXRkzLo7m5bptR_Pm3_SNu2WSn5PabNB_WW_-fDIBqNki6SHOKgk8QCACB_otlxMF22KClqkWanwFP5a9qH5IgOQqix64ph43cmFglv6HxvYDmd8yFCWW6tU5U_VOmOsGAVFp7BQRQth-Dmx7r3GJmM0orn5cjdlUdJlG0T_Asc0PRc5XQH4y00gY0WcRfIJPGXvFEA3Dcyc_N5gSQc-QbSO6KfJViGfdFRwsx9xrMP-pQ.vxp-KAOeSF8zrcmo8v1RZGzJwADP32Ba_nrpxkJuoIQ": potato 2026/01/25 02:46:15 NOTICE: hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Failed to cancel multipart upload: unauthenticated: Unauthenticated (upload failed due to: Put "https://my.microsoftpersonalcontent.com/personal/71a96798e7b1d253/_api/v2.0/drives/b!vKC3DXjgjk-Lp7XrVMpysWhcKnHtT8dLkaHuPSl-l2DGmC7WzjOlT7z5hN2HZRn_/items/01C6Y6555QJWIW3YATJRAZQUQX7VUZCBAA/uploadSession?guid='fd7b0ce2-5b9e-4cce-a0aa-70cb9357455f'&overwrite=True&rename=False&dc=0&tempauth=v1e.eyJzaXRlaWQiOiIwZGI3YTBiYy1lMDc4LTRmOGUtOGJhNy1iNWViNTRjYTcyYjEiLCJhcHBfZGlzcGxheW5hbWUiOiJyY2xvbmUiLCJhcHBpZCI6ImIxNTY2NWQ5LWVkYTYtNDA5Mi04NTM5LTBlZWMzNzZhZmQ1OSIsImF1ZCI6IjAwMDAwMDAzLTAwMDAtMGZmMS1jZTAwLTAwMDAwMDAwMDAwMC9teS5taWNyb3NvZnRwZXJzb25hbGNvbnRlbnQuY29tQDkxODgwNDBkLTZjNjctNGM1Yi1iMTEyLTM2YTMwNGI2NmRhZCIsImV4cCI6IjE3NjkzOTU1NzUifQ.b6rqyIcI6tV_cWTrMMWvtH9exC066fbxX-bzxe0zBa6Kcnbg7EvxhUdbDZ76ngQzDF3_0rOJ--THS78mYDvJHm_tiQz1PDaQ1mojy-KUbzJikUiru09EFxh0Grab3xWGeBdE7wAgkVA4rsBfXOjhbvsA46RWyRA1bTSc3IjywQIGgtOCSWfGWVv4aANC8u74LATi52nsEZOk3bkljXNtzD4GJ_C30by0YpO3cNVlmXRkzLo7m5bptR_Pm3_SNu2WSn5PabNB_WW_-fDIBqNki6SHOKgk8QCACB_otlxMF22KClqkWanwFP5a9qH5IgOQqix64ph43cmFglv6HxvYDmd8yFCWW6tU5U_VOmOsGAVFp7BQRQth-Dmx7r3GJmM0orn5cjdlUdJlG0T_Asc0PRc5XQH4y00gY0WcRfIJPGXvFEA3Dcyc_N5gSQc-QbSO6KfJViGfdFRwsx9xrMP-pQ.vxp-KAOeSF8zrcmo8v1RZGzJwADP32Ba_nrpxkJuoIQ": potato) 2026/01/25 02:46:15 DEBUG : info from Update error: null fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 1/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 2/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 3/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 4/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 5/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 6/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 7/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 8/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 9/10 fstests.go:143: FsPutError: test object does not exist error: object is present - low level retry 10/10 fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:761 Error: Received unexpected error: object is present Test: TestIntegration/FsMkdir/FsPutError Messages: FsPutError: test object does not exist === RUN TestIntegration/FsMkdir/FsPutFiles 2026/01/25 02:46:38 DEBUG : file name.txt: Starting multipart upload 2026/01/25 02:46:39 DEBUG : file name.txt: Uploading segment 0/100 size 100 2026/01/25 02:46:40 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Starting multipart upload 2026/01/25 02:46:40 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Uploading segment 0/100 size 100 2026/01/25 02:46:41 DEBUG : file name.txt: Starting multipart upload 2026/01/25 02:46:41 DEBUG : file name.txt: skipping unsupported metadata item: rclonetest: potato 2026/01/25 02:46:41 INFO : file name.txt: no writeable metadata found: map[rclonetest:potato] 2026/01/25 02:46:42 DEBUG : file name.txt: Uploading segment 0/100 size 100 === RUN TestIntegration/FsMkdir/FsPutFiles/Internal 2026/01/25 02:46:42 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qukuqom2xica" 2026/01/25 02:46:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/25 02:46:42 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/25 02:46:45 DEBUG : Creating backend with remote "/tmp/rclone1615389791" run.go:185: Remote "OneDrive root 'rclone-test-qukuqom2xica'", Local "Local file system at /tmp/rclone1615389791", Modify Window "1s" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TestWritePermissions 2026/01/25 02:46:46 DEBUG : some file-dewiguc0.txt: Starting multipart upload 2026/01/25 02:46:47 DEBUG : info from Update error: null fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:382 /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:55 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Received unexpected error: invalidRequest: Invalid request Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestWritePermissions Messages: Put 2026/01/25 02:46:47 DEBUG : OneDrive root 'rclone-test-qukuqom2xica': Purge remote 2026/01/25 02:46:48 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-fuhakif1tasu" 2026/01/25 02:46:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/25 02:46:48 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/25 02:46:49 DEBUG : Creating backend with remote "/tmp/rclone720283333" === NAME TestIntegration/FsMkdir/FsPutFiles/Internal run.go:185: Remote "OneDrive root 'rclone-test-fuhakif1tasu'", Local "Local file system at /tmp/rclone720283333", Modify Window "1s" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TestUploadSinglePart 2026/01/25 02:46:50 DEBUG : some file-tokowed6.txt: Starting singlepart upload 2026/01/25 02:46:51 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/AQOgMp2Yb-JHluE1GP1zDsk" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] 2026/01/25 02:46:51 DEBUG : some file-tokowed6.txt: sortPermissions: will add permission: &{ 0xc000161200 [0xc000161380] [] [read] } [read] 2026/01/25 02:46:51 DEBUG : some file-tokowed6.txt: skipping remove permission -- can't remove 'owner' role 2026/01/25 02:46:51 DEBUG : some file-tokowed6.txt: metadata permissions: to add: 1 to update: 0 to remove: 0 2026/01/25 02:46:51 ERROR : some file-tokowed6.txt: Failed to add permission: invalidRequest: RequireSignIn and SendInvitation cannot both be false 2026/01/25 02:46:51 ERROR : some file-tokowed6.txt: error writing permissions: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false 2026/01/25 02:46:51 DEBUG : info from Update error: null fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:382 /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:55 Error: Received unexpected error: failed to fetch and update metadata: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestUploadSinglePart Messages: Put 2026/01/25 02:46:51 DEBUG : OneDrive root 'rclone-test-fuhakif1tasu': Purge remote 2026/01/25 02:46:52 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-qibaluz4qafe" 2026/01/25 02:46:52 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/25 02:46:52 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/25 02:46:54 DEBUG : Creating backend with remote "/tmp/rclone2965688493" === NAME TestIntegration/FsMkdir/FsPutFiles/Internal run.go:185: Remote "OneDrive root 'rclone-test-qibaluz4qafe'", Local "Local file system at /tmp/rclone2965688493", Modify Window "1s" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TestReadPermissions 2026/01/25 02:46:54 DEBUG : some file-koxuqij4.txt: Starting singlepart upload 2026/01/25 02:46:55 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/AcsjdbXP7bZJtyDrrwnSBYQ" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] 2026/01/25 02:46:56 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/AcsjdbXP7bZJtyDrrwnSBYQ" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] 2026/01/25 02:46:56 DEBUG : some file-koxuqij4.txt: Starting singlepart upload 2026/01/25 02:46:57 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/AcsjdbXP7bZJtyDrrwnSBYQ" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] 2026/01/25 02:46:59 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/AcsjdbXP7bZJtyDrrwnSBYQ" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] onedrive_internal_test.go:141: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:141 Error: Should be false Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestReadPermissions Messages: permissions metadata key was unexpectedly found: [{"id":"aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ","grantedTo":{"user":{"displayName":"Nick Craig-Wood","id":"4","email":"nick@craig-wood.com"},"application":{},"device":{},"group":{},"siteGroup":{},"siteUser":{}},"grantedToV2":{"user":{},"application":{},"device":{},"group":{},"siteGroup":{},"siteUser":{"displayName":"Nick Craig-Wood","id":"4","email":"nick@craig-wood.com","loginName":"i:0#.f|membership|nick@craig-wood.com"}},"link":{"webUrl":"https://1drv.ms/t/c/71A96798E7B1D253/AcsjdbXP7bZJtyDrrwnSBYQ"},"roles":["owner"],"shareId":"aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ"}] 2026/01/25 02:46:59 DEBUG : OneDrive root 'rclone-test-qibaluz4qafe': Purge remote 2026/01/25 02:46:59 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-gijupob3putu" 2026/01/25 02:46:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/25 02:46:59 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/25 02:47:01 DEBUG : Creating backend with remote "/tmp/rclone3395748478" === NAME TestIntegration/FsMkdir/FsPutFiles/Internal run.go:185: Remote "OneDrive root 'rclone-test-gijupob3putu'", Local "Local file system at /tmp/rclone3395748478", Modify Window "1s" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TestReadMetadata 2026/01/25 02:47:02 DEBUG : some file-gadabay2.txt: Starting singlepart upload 2026/01/25 02:47:03 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/AVF6rPj70p1BmDMgP311qrg" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] 2026/01/25 02:47:04 DEBUG : some file-gadabay2.txt: sortPermissions: will add permission: &{ 0xc0006aa900 [0xc0006aaa80] [] [write] } [write] 2026/01/25 02:47:04 DEBUG : some file-gadabay2.txt: skipping remove permission -- can't remove 'owner' role 2026/01/25 02:47:04 DEBUG : some file-gadabay2.txt: metadata permissions: to add: 1 to update: 0 to remove: 0 2026/01/25 02:47:05 ERROR : some file-gadabay2.txt: Failed to add permission: invalidRequest: RequireSignIn and SendInvitation cannot both be false 2026/01/25 02:47:05 ERROR : some file-gadabay2.txt: error writing permissions: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false 2026/01/25 02:47:05 DEBUG : info from Update error: null fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:382 /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:156 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Received unexpected error: failed to fetch and update metadata: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestReadMetadata Messages: Put 2026/01/25 02:47:05 DEBUG : OneDrive root 'rclone-test-gijupob3putu': Purge remote 2026/01/25 02:47:05 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-xufayiy9wifi" 2026/01/25 02:47:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/25 02:47:05 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/25 02:47:06 DEBUG : Creating backend with remote "/tmp/rclone3971623568" === NAME TestIntegration/FsMkdir/FsPutFiles/Internal run.go:185: Remote "OneDrive root 'rclone-test-xufayiy9wifi'", Local "Local file system at /tmp/rclone3971623568", Modify Window "1s" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata 2026/01/25 02:47:06 DEBUG : subdir: Making directory with metadata 2026/01/25 02:47:07 DEBUG : subdir: skipping unsupported metadata item: content-type: inode/directory onedrive_internal_test.go:208: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:208 Error: Received unexpected error: resourceModified: The resource has changed since the caller last read it; usually an eTag mismatch Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata onedrive_internal_test.go:209: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:209 Error: Expected value not to be nil. Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata 2026/01/25 02:47:08 DEBUG : OneDrive root 'rclone-test-xufayiy9wifi': Purge remote 2026/01/25 02:47:08 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-majelul4zoya" 2026/01/25 02:47:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/25 02:47:08 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/25 02:47:11 DEBUG : Creating backend with remote "/tmp/rclone3915105512" === NAME TestIntegration/FsMkdir/FsPutFiles/Internal run.go:185: Remote "OneDrive root 'rclone-test-majelul4zoya'", Local "Local file system at /tmp/rclone3915105512", Modify Window "1s" === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TestServerSideCopyMove 2026/01/25 02:47:13 DEBUG : some file-kadifej3.txt: Starting singlepart upload 2026/01/25 02:47:14 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/Ac1Ff_6YDndJqyybVrJBR60" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] 2026/01/25 02:47:15 DEBUG : some file-kadifej3.txt: sortPermissions: will add permission: &{ 0xc000091980 [0xc000091b00] [] [read] } [read] 2026/01/25 02:47:15 DEBUG : some file-kadifej3.txt: skipping remove permission -- can't remove 'owner' role 2026/01/25 02:47:15 DEBUG : some file-kadifej3.txt: metadata permissions: to add: 1 to update: 0 to remove: 0 2026/01/25 02:47:16 ERROR : some file-kadifej3.txt: Failed to add permission: invalidRequest: RequireSignIn and SendInvitation cannot both be false 2026/01/25 02:47:16 ERROR : some file-kadifej3.txt: error writing permissions: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false 2026/01/25 02:47:16 DEBUG : info from Update error: null fstests.go:146: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:162 /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:382 /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:293 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Received unexpected error: failed to fetch and update metadata: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestServerSideCopyMove Messages: Put 2026/01/25 02:47:16 DEBUG : OneDrive root 'rclone-test-majelul4zoya': Purge remote === RUN TestIntegration/FsMkdir/FsPutFiles/Internal/TestMetadataMapper 2026/01/25 02:47:19 DEBUG : some file-lufohaq4.txt: Starting singlepart upload 2026/01/25 02:47:19 DEBUG : Metadata mapper sent: { "SrcFs": "/tmp/rclone3915105512", "SrcFsType": "local", "DstFs": "TestOneDrive:rclone-test-majelul4zoya", "DstFsType": "onedrive", "Remote": "some file-lufohaq4.txt", "Size": 0, "MimeType": "text/plain; charset=utf-8", "ModTime": "2020-02-29T12:34:56.789Z", "IsDir": false, "Metadata": { "atime": "2020-02-29T12:34:56.789Z", "btime": "2026-01-25T02:47:17.542563601Z", "gid": "1000", "mode": "100600", "mtime": "2020-02-29T12:34:56.789Z", "uid": "1000" } } 2026/01/25 02:47:19 DEBUG : some file-lufohaq4.txt: Calling metadata mapper echo "{""Metadata"":{""permissions"":""[{\""grantedToIdentities\"":[{\""user\"":{\""id\"":\""ryan@contoso.com\""}}],\""roles\"":[\""read\""]}]""}}" 2026/01/25 02:47:19 DEBUG : Metadata mapper received: {"Metadata":{"permissions":"[{\"grantedToIdentities\":[{\"user\":{\"id\":\"ryan@contoso.com\"}}],\"roles\":[\"read\"]}]"}} 2026/01/25 02:47:19 DEBUG : some file-lufohaq4.txt: Metadata mapper returned in 2.541641ms 2026/01/25 02:47:20 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/AQsuqD0sUDFEkdO7I5qeSPo" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] 2026/01/25 02:47:21 DEBUG : some file-lufohaq4.txt: sortPermissions: will add permission: &{ [0xc0003ea300] [] [read] } [read] 2026/01/25 02:47:21 DEBUG : some file-lufohaq4.txt: skipping remove permission -- can't remove 'owner' role 2026/01/25 02:47:21 DEBUG : some file-lufohaq4.txt: metadata permissions: to add: 1 to update: 0 to remove: 0 2026/01/25 02:47:21 ERROR : some file-lufohaq4.txt: Failed to add permission: invalidRequest: RequireSignIn and SendInvitation cannot both be false 2026/01/25 02:47:21 ERROR : some file-lufohaq4.txt: error writing permissions: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false 2026/01/25 02:47:21 DEBUG : info from Update error: null 2026/01/25 02:47:21 ERROR : some file-lusapak0.txt: Failed to copy: failed to fetch and update metadata: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false onedrive_internal_test.go:345: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:345 Error: Received unexpected error: failed to fetch and update metadata: failed to process permissions: failed to set permissions: invalidRequest: RequireSignIn and SendInvitation cannot both be false Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestMetadataMapper 2026/01/25 02:47:22 DEBUG : perms: [ { "id": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ", "grantedTo": { "user": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com" }, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": {} }, "grantedToV2": { "user": {}, "application": {}, "device": {}, "group": {}, "siteGroup": {}, "siteUser": { "displayName": "Nick Craig-Wood", "id": "4", "email": "nick@craig-wood.com", "loginName": "i:0#.f|membership|nick@craig-wood.com" } }, "link": { "webUrl": "https://1drv.ms/t/c/71A96798E7B1D253/AQsuqD0sUDFEkdO7I5qeSPo" }, "roles": [ "owner" ], "shareId": "aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ" } ] onedrive_internal_test.go:368: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:368 Error: Should be true Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestMetadataMapper Messages: no permission found with expected role (want: {"Metadata":{"permissions":"[{\"grantedToIdentities\":[{\"user\":{\"id\":\"ryan@contoso.com\"}}],\"roles\":[\"read\"]}]"}} got: map[btime:2026-01-25T02:47:20Z content-type:text/plain created-by-display-name:Nick Craig-Wood created-by-id:71A96798E7B1D253 id:71A96798E7B1D253#71A96798E7B1D253!s3da82e0b502c443191d3bb239a9e48fa last-modified-by-display-name:Nick Craig-Wood last-modified-by-id:71A96798E7B1D253 malware-detected:false mtime:2026-01-25T02:47:20Z permissions:[{"id":"aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ","grantedTo":{"user":{"displayName":"Nick Craig-Wood","id":"4","email":"nick@craig-wood.com"},"application":{},"device":{},"group":{},"siteGroup":{},"siteUser":{}},"grantedToV2":{"user":{},"application":{},"device":{},"group":{},"siteGroup":{},"siteUser":{"displayName":"Nick Craig-Wood","id":"4","email":"nick@craig-wood.com","loginName":"i:0#.f|membership|nick@craig-wood.com"}},"link":{"webUrl":"https://1drv.ms/t/c/71A96798E7B1D253/AQsuqD0sUDFEkdO7I5qeSPo"},"roles":["owner"],"shareId":"aTowIy5mfG1lbWJlcnNoaXB8bmlja0BjcmFpZy13b29kLmNvbQ"}] utime:2026-01-25T02:47:20Z] ) onedrive_internal_test.go:369: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/backend/onedrive/onedrive_internal_test.go:369 Error: Not equal: expected: 1 actual : 0 Test: TestIntegration/FsMkdir/FsPutFiles/Internal/TestMetadataMapper Messages: expected to find exactly 1 match 2026/01/25 02:47:22 DEBUG : OneDrive root 'rclone-test-majelul4zoya': Purge remote 2026/01/25 02:47:22 DEBUG : OneDrive root 'rclone-test-majelul4zoya': Purge remote 2026/01/25 02:47:23 NOTICE: purge failed: directory not found 2026/01/25 02:47:23 DEBUG : OneDrive root 'rclone-test-xufayiy9wifi': Purge remote 2026/01/25 02:47:23 NOTICE: purge failed: directory not found 2026/01/25 02:47:23 DEBUG : OneDrive root 'rclone-test-gijupob3putu': Purge remote 2026/01/25 02:47:24 NOTICE: purge failed: directory not found 2026/01/25 02:47:24 DEBUG : OneDrive root 'rclone-test-qibaluz4qafe': Purge remote 2026/01/25 02:47:24 NOTICE: purge failed: directory not found 2026/01/25 02:47:24 DEBUG : OneDrive root 'rclone-test-fuhakif1tasu': Purge remote 2026/01/25 02:47:24 NOTICE: purge failed: directory not found 2026/01/25 02:47:24 DEBUG : OneDrive root 'rclone-test-qukuqom2xica': Purge remote 2026/01/25 02:47:25 NOTICE: purge failed: directory not found === RUN TestIntegration/FsMkdir/FsMkdirMetadata 2026/01/25 02:47:25 DEBUG : dir-metadata: skipping unsupported metadata item: potato: jersey fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:613 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:2682 Error: Should be true Test: TestIntegration/FsMkdir/FsMkdirMetadata Messages: dir-metadata: Modification time difference too big |-218926h42m19.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-25 02:47:26 +0000 UTC) (precision 1s) fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:613 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:2683 Error: Should be true Test: TestIntegration/FsMkdir/FsMkdirMetadata Messages: dir-metadata: Modification time difference too big |-218926h42m19.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-25 02:47:26 +0000 UTC) (precision 1s) === RUN TestIntegration/FsMkdir/FsMkdirMetadata/Update 2026/01/25 02:47:26 DEBUG : dir-metadata: skipping unsupported metadata item: potato: king edwards === RUN TestIntegration/FsMkdir/FsMkdirMetadata/CheckDirectory 2026/01/25 02:47:28 ERROR : error listing: directory not found --- FAIL: TestIntegration (79.66s) --- FAIL: TestIntegration/FsMkdir (77.37s) --- FAIL: TestIntegration/FsMkdir/FsPutError (26.96s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles (46.79s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal (42.87s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal/TestWritePermissions (2.26s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal/TestUploadSinglePart (2.48s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal/TestReadPermissions (5.04s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal/TestReadMetadata (3.65s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata (1.56s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal/TestServerSideCopyMove (5.58s) --- FAIL: TestIntegration/FsMkdir/FsPutFiles/Internal/TestMetadataMapper (4.73s) --- FAIL: TestIntegration/FsMkdir/FsMkdirMetadata (2.36s) --- PASS: TestIntegration/FsMkdir/FsMkdirMetadata/Update (0.70s) --- PASS: TestIntegration/FsMkdir/FsMkdirMetadata/CheckDirectory (0.00s) FAIL 2026/01/25 02:47:28 DEBUG : OneDrive root 'rclone-test-becudan2gure': Purge remote 2026/01/25 02:47:29 NOTICE: purge failed: directory not found exit status 1 FAIL github.com/rclone/rclone/backend/onedrive 83.525s "go test -v -timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^TestIntegration$/^FsMkdir$/^(FsMkdirMetadata|FsPutError)$|^TestIntegration$/^FsMkdir$/^FsPutFiles$/^Internal$/^(TestDirectoryMetadata|TestMetadataMapper|TestReadMetadata|TestReadPermissions|TestServerSideCopyMove|TestUploadSinglePart|TestWritePermissions)$'" - Finished ERROR in 1m24.485138059s (try 4/5): exit status 1: Failed [TestIntegration/FsMkdir/FsPutError TestIntegration/FsMkdir/FsPutFiles/Internal/TestWritePermissions TestIntegration/FsMkdir/FsPutFiles/Internal/TestUploadSinglePart TestIntegration/FsMkdir/FsPutFiles/Internal/TestReadPermissions TestIntegration/FsMkdir/FsPutFiles/Internal/TestReadMetadata TestIntegration/FsMkdir/FsPutFiles/Internal/TestDirectoryMetadata TestIntegration/FsMkdir/FsPutFiles/Internal/TestServerSideCopyMove TestIntegration/FsMkdir/FsPutFiles/Internal/TestMetadataMapper TestIntegration/FsMkdir/FsMkdirMetadata]