"./operations.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestCopyDirMetadata|TestCopyFileMaxTransfer|TestDirsEqual|TestMkdirMetadata|TestMkdirModTime)$'" - Starting (try 2/5) 2026/01/26 01:49:18 DEBUG : Creating backend with remote "TestOneDrive:rclone-test-temameb7pune" 2026/01/26 01:49:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/26 01:49:18 DEBUG : TestOneDrive: Set overridden config ["user_agent"] for backend startup 2026/01/26 01:49:19 DEBUG : Creating backend with remote "/tmp/rclone1968429727" === RUN TestCopyFileMaxTransfer run.go:185: Remote "OneDrive root 'rclone-test-temameb7pune'", Local "Local file system at /tmp/rclone1968429727", Modify Window "1s" 2026/01/26 01:49:20 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/01/26 01:49:21 DEBUG : TestCopyFileMaxTransfer/file1: Starting multipart upload 2026/01/26 01:49:22 DEBUG : TestCopyFileMaxTransfer/file1: Uploading segment 0/14 size 14 2026/01/26 01:49:23 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/01/26 01:49:23 DEBUG : TestCopyFileMaxTransfer/file1: quickxor = 6648031bca1003108ce10d6eae4319dc40873900 OK 2026/01/26 01:49:23 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/01/26 01:49:24 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/01/26 01:49:24 DEBUG : TestCopyFileMaxTransfer/file2: Starting multipart upload 2026/01/26 01:49:25 DEBUG : TestCopyFileMaxTransfer/file2: Uploading segment 0/2062 size 2062 2026/01/26 01:49:25 DEBUG : TestCopyFileMaxTransfer/file2: Cancelling multipart upload: Put "https://my.microsoftpersonalcontent.com/personal/71a96798e7b1d253/_api/v2.0/drives/b!vKC3DXjgjk-Lp7XrVMpysWhcKnHtT8dLkaHuPSl-l2DGmC7WzjOlT7z5hN2HZRn_/items/01C6Y6553OHM2KDQCJ25DIHP7VAR242EFC/uploadSession?guid='83aaa52d-10bd-4310-b2b2-1a90c4c3edb4'&overwrite=True&rename=False&dc=0&tempauth=v1e.eyJzaXRlaWQiOiIwZGI3YTBiYy1lMDc4LTRmOGUtOGJhNy1iNWViNTRjYTcyYjEiLCJhcHBfZGlzcGxheW5hbWUiOiJyY2xvbmUiLCJhcHBpZCI6ImIxNTY2NWQ5LWVkYTYtNDA5Mi04NTM5LTBlZWMzNzZhZmQ1OSIsImF1ZCI6IjAwMDAwMDAzLTAwMDAtMGZmMS1jZTAwLTAwMDAwMDAwMDAwMC9teS5taWNyb3NvZnRwZXJzb25hbGNvbnRlbnQuY29tQDkxODgwNDBkLTZjNjctNGM1Yi1iMTEyLTM2YTMwNGI2NmRhZCIsImV4cCI6IjE3Njk0Nzg1NjUifQ.1X6HqngoczQCUkKrYguR5TXVYn6yrTNMZ_PyQL5jOWqLVgIeLGP5Skmom6qmADz6uk7WRhLRiThzkX_KIuDTAFcL2ZL7FMAwsQXWm_p80fbVNfotHfRGDRXLeWJBwa7D7eN6ruXYNqUGfNhpNsxslmDSBFi58G6CL22Yo_rYyRJKq9dwBZ8Ks-f9SYwRNwQU-8NzvieIvIOunuxvLeVcZhbCMk5RAgbZkXnMQWpViQgLWZOhaHRC6jfcRPipd70yby6qmp4_n6ZuXjotTs9htxROtZeDrCUtl2b9JuVJqS8PLBZRWW9-xB_neSuQbGODN2XSzCnLNDGX5VZ03qpqij67jcNtjJZd3PsLiGZFSGC_-NtgyF2VF154MfXbADixE1_9TqhDZ703DaoaNuIPm1nywtFLd3qHumAEOkR4IpIlzxwxnc75bOwqhqq6ng1wY8f2R5ygkFPvAKpByV3Wdg.g0cfz2UvhLJAQVcgdbLS1QZAiQ7aW_FDD9WGFVlrxd8": max transfer limit reached as set by --max-transfer 2026/01/26 01:49:25 NOTICE: TestCopyFileMaxTransfer/file2: 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/01C6Y6553OHM2KDQCJ25DIHP7VAR242EFC/uploadSession?guid='83aaa52d-10bd-4310-b2b2-1a90c4c3edb4'&overwrite=True&rename=False&dc=0&tempauth=v1e.eyJzaXRlaWQiOiIwZGI3YTBiYy1lMDc4LTRmOGUtOGJhNy1iNWViNTRjYTcyYjEiLCJhcHBfZGlzcGxheW5hbWUiOiJyY2xvbmUiLCJhcHBpZCI6ImIxNTY2NWQ5LWVkYTYtNDA5Mi04NTM5LTBlZWMzNzZhZmQ1OSIsImF1ZCI6IjAwMDAwMDAzLTAwMDAtMGZmMS1jZTAwLTAwMDAwMDAwMDAwMC9teS5taWNyb3NvZnRwZXJzb25hbGNvbnRlbnQuY29tQDkxODgwNDBkLTZjNjctNGM1Yi1iMTEyLTM2YTMwNGI2NmRhZCIsImV4cCI6IjE3Njk0Nzg1NjUifQ.1X6HqngoczQCUkKrYguR5TXVYn6yrTNMZ_PyQL5jOWqLVgIeLGP5Skmom6qmADz6uk7WRhLRiThzkX_KIuDTAFcL2ZL7FMAwsQXWm_p80fbVNfotHfRGDRXLeWJBwa7D7eN6ruXYNqUGfNhpNsxslmDSBFi58G6CL22Yo_rYyRJKq9dwBZ8Ks-f9SYwRNwQU-8NzvieIvIOunuxvLeVcZhbCMk5RAgbZkXnMQWpViQgLWZOhaHRC6jfcRPipd70yby6qmp4_n6ZuXjotTs9htxROtZeDrCUtl2b9JuVJqS8PLBZRWW9-xB_neSuQbGODN2XSzCnLNDGX5VZ03qpqij67jcNtjJZd3PsLiGZFSGC_-NtgyF2VF154MfXbADixE1_9TqhDZ703DaoaNuIPm1nywtFLd3qHumAEOkR4IpIlzxwxnc75bOwqhqq6ng1wY8f2R5ygkFPvAKpByV3Wdg.g0cfz2UvhLJAQVcgdbLS1QZAiQ7aW_FDD9WGFVlrxd8": max transfer limit reached as set by --max-transfer) 2026/01/26 01:49:25 DEBUG : info from Update error: null 2026/01/26 01:49:25 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Put "https://my.microsoftpersonalcontent.com/personal/71a96798e7b1d253/_api/v2.0/drives/b!vKC3DXjgjk-Lp7XrVMpysWhcKnHtT8dLkaHuPSl-l2DGmC7WzjOlT7z5hN2HZRn_/items/01C6Y6553OHM2KDQCJ25DIHP7VAR242EFC/uploadSession?guid='83aaa52d-10bd-4310-b2b2-1a90c4c3edb4'&overwrite=True&rename=False&dc=0&tempauth=v1e.eyJzaXRlaWQiOiIwZGI3YTBiYy1lMDc4LTRmOGUtOGJhNy1iNWViNTRjYTcyYjEiLCJhcHBfZGlzcGxheW5hbWUiOiJyY2xvbmUiLCJhcHBpZCI6ImIxNTY2NWQ5LWVkYTYtNDA5Mi04NTM5LTBlZWMzNzZhZmQ1OSIsImF1ZCI6IjAwMDAwMDAzLTAwMDAtMGZmMS1jZTAwLTAwMDAwMDAwMDAwMC9teS5taWNyb3NvZnRwZXJzb25hbGNvbnRlbnQuY29tQDkxODgwNDBkLTZjNjctNGM1Yi1iMTEyLTM2YTMwNGI2NmRhZCIsImV4cCI6IjE3Njk0Nzg1NjUifQ.1X6HqngoczQCUkKrYguR5TXVYn6yrTNMZ_PyQL5jOWqLVgIeLGP5Skmom6qmADz6uk7WRhLRiThzkX_KIuDTAFcL2ZL7FMAwsQXWm_p80fbVNfotHfRGDRXLeWJBwa7D7eN6ruXYNqUGfNhpNsxslmDSBFi58G6CL22Yo_rYyRJKq9dwBZ8Ks-f9SYwRNwQU-8NzvieIvIOunuxvLeVcZhbCMk5RAgbZkXnMQWpViQgLWZOhaHRC6jfcRPipd70yby6qmp4_n6ZuXjotTs9htxROtZeDrCUtl2b9JuVJqS8PLBZRWW9-xB_neSuQbGODN2XSzCnLNDGX5VZ03qpqij67jcNtjJZd3PsLiGZFSGC_-NtgyF2VF154MfXbADixE1_9TqhDZ703DaoaNuIPm1nywtFLd3qHumAEOkR4IpIlzxwxnc75bOwqhqq6ng1wY8f2R5ygkFPvAKpByV3Wdg.g0cfz2UvhLJAQVcgdbLS1QZAiQ7aW_FDD9WGFVlrxd8": max transfer limit reached as set by --max-transfer fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:507 Error: Should be true Test: TestCopyFileMaxTransfer Messages: listing wrong, want TestCopyFileMaxTransfer/file1 (14) got TestCopyFileMaxTransfer/file1 (14), TestCopyFileMaxTransfer/file2 (0) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:507 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "TestCopyFileMaxTransfer/file2" 2026/01/26 01:49:35 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:518 Error: Should be true Test: TestCopyFileMaxTransfer Messages: listing wrong, want TestCopyFileMaxTransfer/file1 (14) got TestCopyFileMaxTransfer/file1 (14), TestCopyFileMaxTransfer/file2 (0) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:518 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "TestCopyFileMaxTransfer/file2" 2026/01/26 01:49:44 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/01/26 01:49:45 DEBUG : TestCopyFileMaxTransfer/file4: Starting multipart upload 2026/01/26 01:49:45 DEBUG : TestCopyFileMaxTransfer/file4: Uploading segment 0/2062 size 2062 2026/01/26 01:49:46 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/01/26 01:49:46 DEBUG : TestCopyFileMaxTransfer/file4: quickxor = 0230cac51f2d0724c0707d01fe1e01efb952c67d OK 2026/01/26 01:49:46 INFO : TestCopyFileMaxTransfer/file4: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:533 Error: Should be true Test: TestCopyFileMaxTransfer Messages: listing wrong, want TestCopyFileMaxTransfer/file1 (14), TestCopyFileMaxTransfer/file4 (2062) got TestCopyFileMaxTransfer/file1 (14), TestCopyFileMaxTransfer/file2 (0), TestCopyFileMaxTransfer/file4 (2062) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:533 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestCopyFileMaxTransfer Messages: Unexpected file "TestCopyFileMaxTransfer/file2" --- FAIL: TestCopyFileMaxTransfer (38.49s) === RUN TestMkdirMetadata run.go:185: Remote "OneDrive root 'rclone-test-temameb7pune'", Local "Local file system at /tmp/rclone1968429727", Modify Window "1s" 2026/01/26 01:49:57 DEBUG : dir with metadata: Making directory with metadata 2026/01/26 01:49:57 DEBUG : dir with metadata: skipping unsupported metadata item: potato: jersey 2026/01/26 01:49:58 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 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/fs/operations/operations_test.go:1721 Error: Should be true Test: TestMkdirMetadata Messages: dir with metadata: Modification time difference too big |-218949h44m51.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-26 01:49:58 +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/fs/operations/operations_test.go:1722 Error: Should be true Test: TestMkdirMetadata Messages: dir with metadata: Modification time difference too big |-218949h44m51.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-26 01:49:58 +0000 UTC) (precision 1s) --- FAIL: TestMkdirMetadata (2.47s) === RUN TestMkdirModTime run.go:185: Remote "OneDrive root 'rclone-test-temameb7pune'", Local "Local file system at /tmp/rclone1968429727", Modify Window "1s" 2026/01/26 01:50:00 DEBUG : directory with modtime: Making directory with metadata 2026/01/26 01:50:01 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 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:639 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1738 Error: Should be true Test: TestMkdirModTime Messages: directory with modtime: Modification time difference too big |-123492h50m1.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-26 01:50:01 +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:639 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1740 Error: Should be true Test: TestMkdirModTime Messages: directory with modtime: Modification time difference too big |-123492h50m1.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2026-01-26 01:50:01 +0000 UTC) (precision 1s) --- FAIL: TestMkdirModTime (2.42s) === RUN TestCopyDirMetadata run.go:185: Remote "OneDrive root 'rclone-test-temameb7pune'", Local "Local file system at /tmp/rclone1968429727", Modify Window "1s" 2026/01/26 01:50:02 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/01/26 01:50:02 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/26 01:50:02 DEBUG : non existent directory: skipping unsupported metadata item: potato: jersey 2026/01/26 01:50:02 DEBUG : non existent directory: skipping unsupported metadata item: mode: 40775 2026/01/26 01:50:02 DEBUG : non existent directory: skipping unsupported metadata item: uid: 1000 2026/01/26 01:50:02 DEBUG : non existent directory: skipping unsupported metadata item: gid: 1000 2026/01/26 01:50:02 DEBUG : non existent directory: skipping unsupported metadata item: atime: 2001-02-03T04:05:06.499999999Z 2026/01/26 01:50:03 INFO : non existent directory: Updated directory metadata 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/fs/operations/operations_test.go:1765 Error: Should be true Test: TestCopyDirMetadata Messages: non existent directory: Modification time difference too big |-218949h44m56.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-26 01:50:03 +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/fs/operations/operations_test.go:1766 Error: Should be true Test: TestCopyDirMetadata Messages: non existent directory: Modification time difference too big |-218949h44m57.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-01-26 01:50:04 +0000 UTC) (precision 1s) 2026/01/26 01:50:05 DEBUG : existing directory: skipping unsupported metadata item: uid: 1000 2026/01/26 01:50:05 DEBUG : existing directory: skipping unsupported metadata item: gid: 1000 2026/01/26 01:50:05 DEBUG : existing directory: skipping unsupported metadata item: atime: 2001-02-03T04:05:06.499999999Z 2026/01/26 01:50:05 DEBUG : existing directory: skipping unsupported metadata item: potato: jersey 2026/01/26 01:50:05 DEBUG : existing directory: skipping unsupported metadata item: mode: 40775 2026/01/26 01:50:05 INFO : existing directory: Updated directory metadata --- FAIL: TestCopyDirMetadata (4.43s) === RUN TestDirsEqual run.go:185: Remote "OneDrive root 'rclone-test-temameb7pune'", Local "Local file system at /tmp/rclone1968429727", Modify Window "1s" 2026/01/26 01:50:07 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/01/26 01:50:07 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/01/26 01:50:07 DEBUG : dst: Making directory with metadata 2026/01/26 01:50:07 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) operations_test.go:1855: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1855 Error: Should be true Test: TestDirsEqual 2026/01/26 01:50:08 INFO : dst: Set directory modification time (using SetModTime) 2026/01/26 01:50:08 INFO : dst: Set directory modification time (using SetModTime) 2026/01/26 01:50:08 DEBUG : dst: Directory modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/01/26 01:50:09 INFO : dst: Set directory modification time (using SetModTime) 2026/01/26 01:50:09 DEBUG : dst: Destination directory is newer than source, skipping --- FAIL: TestDirsEqual (3.21s) FAIL 2026/01/26 01:50:10 DEBUG : OneDrive root 'rclone-test-temameb7pune': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestOneDrive: -verbose -test.run '^(TestCopyDirMetadata|TestCopyFileMaxTransfer|TestDirsEqual|TestMkdirMetadata|TestMkdirModTime)$'" - Finished ERROR in 52.54634841s (try 2/5): exit status 1: Failed [TestCopyFileMaxTransfer TestMkdirMetadata TestMkdirModTime TestCopyDirMetadata TestDirsEqual]