"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirCreate|TestDirFileOpen|TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMethods|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleMethodsWrite|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestRWFileHandleWriteAt|TestRWFileModTimeWithOpenWriters|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestVFSMkdir|TestVFSMkdirAll|TestVFSOpenFile|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters)$|^TestFileRename$/^(full,forceCache=false|minimal,forceCache=false|minimal,forceCache=true|off,forceCache=false|writes,forceCache=false|writes,forceCache=true)$|^TestFileSetModTime$/^(cache=full,open=false,write=false|cache=full,open=true,write=false|cache=full,open=true,write=true|cache=off,open=false,write=false|cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Starting (try 5/5)
2025/02/06 01:47:35 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zacirek4sera"
2025/02/06 01:47:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/02/06 01:47:35 NOTICE: Time may be set wrong - time from "www.linkbox.to" is 22h25m17.134946767s different from this computer
2025/02/06 01:47:35 DEBUG : Creating backend with remote "/tmp/rclone2663286652"
=== RUN   TestDirHandleMethods
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:35 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:35 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F0F26AB0D29761D106F18E</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:36 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:37 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:37 DEBUG : : Looking for writers
2025/02/06 01:47:37 DEBUG : >WaitForWriters: 
--- FAIL: TestDirHandleMethods (2.01s)
=== RUN   TestDirHandleReaddir
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:37 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:37 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F0F719B2DCD64CCDD9DB75</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:38 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:38 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:38 DEBUG : : Looking for writers
2025/02/06 01:47:38 DEBUG : >WaitForWriters: 
--- FAIL: TestDirHandleReaddir (1.22s)
=== RUN   TestDirHandleReaddirnames
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:38 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:38 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F0FC23B0DBCB4CC2E698BB</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:39 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:39 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:39 DEBUG : : Looking for writers
2025/02/06 01:47:39 DEBUG : >WaitForWriters: 
--- FAIL: TestDirHandleReaddirnames (1.29s)
=== RUN   TestDirMethods
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:39 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:39 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F100D9B192828E28FCA4C6</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:40 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:40 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:40 DEBUG : : Looking for writers
2025/02/06 01:47:40 DEBUG : >WaitForWriters: 
--- FAIL: TestDirMethods (1.18s)
=== RUN   TestDirForgetAll
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:40 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:40 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F105ACB01DABF685AD8029</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:41 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:41 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:41 DEBUG : : Looking for writers
2025/02/06 01:47:41 DEBUG : >WaitForWriters: 
--- FAIL: TestDirForgetAll (1.25s)
=== RUN   TestDirForgetPath
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:42 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:42 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F10A9CB19B7E7572846492</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:43 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:43 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:43 DEBUG : : Looking for writers
2025/02/06 01:47:43 DEBUG : >WaitForWriters: 
--- FAIL: TestDirForgetPath (1.25s)
=== RUN   TestDirWalk
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:43 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:43 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F10F65B116EF92F13E90A9</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:44 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:44 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:44 DEBUG : : Looking for writers
2025/02/06 01:47:44 DEBUG : >WaitForWriters: 
--- FAIL: TestDirWalk (1.25s)
=== RUN   TestDirSetModTime
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:44 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:44 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F11460B1957F62D9F38A3B</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:45 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:45 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:45 DEBUG : : Looking for writers
2025/02/06 01:47:45 DEBUG : >WaitForWriters: 
--- FAIL: TestDirSetModTime (1.26s)
=== RUN   TestDirStat
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:45 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:45 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1196AB2DE129A61F89E64</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:46 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:47 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:47 DEBUG : : Looking for writers
2025/02/06 01:47:47 DEBUG : >WaitForWriters: 
--- FAIL: TestDirStat (1.29s)
=== RUN   TestDirReadDirAll
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:47 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:47 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F11E71B153EA4B7D879299</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:48 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:48 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:48 DEBUG : : Looking for writers
2025/02/06 01:47:48 DEBUG : >WaitForWriters: 
--- FAIL: TestDirReadDirAll (1.29s)
=== RUN   TestDirOpen
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:48 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:48 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1237DB1598D8A35C856E6</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:49 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:49 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:49 DEBUG : : Looking for writers
2025/02/06 01:47:49 DEBUG : >WaitForWriters: 
--- FAIL: TestDirOpen (1.26s)
=== RUN   TestDirCreate
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:49 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:49 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1286BB11F4662BDE1C3BD</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:50 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:50 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:50 DEBUG : : Looking for writers
2025/02/06 01:47:50 DEBUG : >WaitForWriters: 
--- FAIL: TestDirCreate (1.28s)
=== RUN   TestDirMkdir
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:51 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:51 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F12D52B1958025DBAEC531</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:52 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:52 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:52 DEBUG : : Looking for writers
2025/02/06 01:47:52 DEBUG : >WaitForWriters: 
--- FAIL: TestDirMkdir (1.24s)
=== RUN   TestDirMkdirSub
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:52 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:52 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F13242B0D485B6D0747E51</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:53 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:53 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:53 DEBUG : : Looking for writers
2025/02/06 01:47:53 DEBUG : >WaitForWriters: 
--- FAIL: TestDirMkdirSub (1.27s)
=== RUN   TestDirRemove
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:53 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:53 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F136F2B2DDFA9D5AE59C2F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:54 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:54 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:54 DEBUG : : Looking for writers
2025/02/06 01:47:54 DEBUG : >WaitForWriters: 
--- FAIL: TestDirRemove (1.19s)
=== RUN   TestDirRemoveAll
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:54 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:54 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F13BA4B2DDFB0F5B46E28E</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:55 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:55 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:55 DEBUG : : Looking for writers
2025/02/06 01:47:55 DEBUG : >WaitForWriters: 
--- FAIL: TestDirRemoveAll (1.20s)
=== RUN   TestDirRemoveName
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:56 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:56 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F14097B2DE126165295906</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:56 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:57 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:57 DEBUG : : Looking for writers
2025/02/06 01:47:57 DEBUG : >WaitForWriters: 
--- FAIL: TestDirRemoveName (1.26s)
=== RUN   TestDirRename
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:57 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:57 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F14584B0179BCA8C3E88E2</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:58 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:58 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:58 DEBUG : : Looking for writers
2025/02/06 01:47:58 DEBUG : >WaitForWriters: 
--- FAIL: TestDirRename (1.26s)
=== RUN   TestDirFileOpen
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:58 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:58 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F14A1FB1186B7200B2EC94</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:47:59 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:47:59 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:47:59 DEBUG : : Looking for writers
2025/02/06 01:47:59 DEBUG : >WaitForWriters: 
--- FAIL: TestDirFileOpen (1.21s)
=== RUN   TestFileMethods
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:47:59 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:47:59 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F14EF7B0D3049B9E7DB191</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:00 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:00 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:00 DEBUG : : Looking for writers
2025/02/06 01:48:00 DEBUG : >WaitForWriters: 
--- FAIL: TestFileMethods (1.24s)
=== RUN   TestFileSetModTime
=== RUN   TestFileSetModTime/cache=off,open=false,write=false
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:00 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:00 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F153DEB15B1ACDAB17A48D</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:01 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:02 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:02 DEBUG : : Looking for writers
2025/02/06 01:48:02 DEBUG : >WaitForWriters: 
=== RUN   TestFileSetModTime/cache=off,open=true,write=false
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:02 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:02 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F158A4B11ECDE72C73D841</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:03 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:03 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:03 DEBUG : : Looking for writers
2025/02/06 01:48:03 DEBUG : >WaitForWriters: 
=== RUN   TestFileSetModTime/cache=off,open=true,write=true
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:03 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:03 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F15DA9B15B1AC0AC19BEF8</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:04 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:04 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:04 DEBUG : : Looking for writers
2025/02/06 01:48:04 DEBUG : >WaitForWriters: 
=== RUN   TestFileSetModTime/cache=full,open=false,write=false
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:04 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:04 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:04 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:04 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:04 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:04 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:04 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:04 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:04 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:04 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:04 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1629EB1172A6FDA5734E3</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:05 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:05 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:05 DEBUG : : Looking for writers
2025/02/06 01:48:05 DEBUG : >WaitForWriters: 
2025/02/06 01:48:05 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
=== RUN   TestFileSetModTime/cache=full,open=true,write=false
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:06 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:06 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:06 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:06 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:06 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:06 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:06 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:06 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:06 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:06 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:06 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1679AB15B19BDAD296442</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:06 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:07 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:07 DEBUG : : Looking for writers
2025/02/06 01:48:07 DEBUG : >WaitForWriters: 
2025/02/06 01:48:07 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
=== RUN   TestFileSetModTime/cache=full,open=true,write=true
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:07 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:07 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:07 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:07 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:07 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:07 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:07 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:07 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:07 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:07 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:07 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F16C4FB01A4A78CD0E5599</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:08 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:08 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:08 DEBUG : : Looking for writers
2025/02/06 01:48:08 DEBUG : >WaitForWriters: 
2025/02/06 01:48:08 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestFileSetModTime (7.48s)
    --- FAIL: TestFileSetModTime/cache=off,open=false,write=false (1.26s)
    --- FAIL: TestFileSetModTime/cache=off,open=true,write=false (1.20s)
    --- FAIL: TestFileSetModTime/cache=off,open=true,write=true (1.27s)
    --- FAIL: TestFileSetModTime/cache=full,open=false,write=false (1.28s)
    --- FAIL: TestFileSetModTime/cache=full,open=true,write=false (1.26s)
    --- FAIL: TestFileSetModTime/cache=full,open=true,write=true (1.21s)
=== RUN   TestFileOpenRead
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:08 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:08 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F17081B158570E63AAC49E</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:09 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:09 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:09 DEBUG : : Looking for writers
2025/02/06 01:48:09 DEBUG : >WaitForWriters: 
--- FAIL: TestFileOpenRead (1.11s)
=== RUN   TestFileOpenWrite
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:09 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:09 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1757CB0DA4B023BBECF1B</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:10 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:10 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:10 DEBUG : : Looking for writers
2025/02/06 01:48:10 DEBUG : >WaitForWriters: 
--- FAIL: TestFileOpenWrite (1.25s)
=== RUN   TestFileRemove
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:10 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:10 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F17A47B012635C94FE3DCD</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:11 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:11 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:11 DEBUG : : Looking for writers
2025/02/06 01:48:11 DEBUG : >WaitForWriters: 
--- FAIL: TestFileRemove (1.23s)
=== RUN   TestFileRemoveAll
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:12 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:12 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F17F29B11503E5F1FDE3FE</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:13 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:13 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:13 DEBUG : : Looking for writers
2025/02/06 01:48:13 DEBUG : >WaitForWriters: 
--- FAIL: TestFileRemoveAll (1.27s)
=== RUN   TestFileOpen
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:13 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:13 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F18439B2DF168C0BE80673</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:14 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:14 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:14 DEBUG : : Looking for writers
2025/02/06 01:48:14 DEBUG : >WaitForWriters: 
--- FAIL: TestFileOpen (1.30s)
=== RUN   TestFileRename
=== RUN   TestFileRename/off,forceCache=false
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:14 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:14 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1890EB1943ED3ED39B320</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:15 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:15 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:15 DEBUG : : Looking for writers
2025/02/06 01:48:15 DEBUG : >WaitForWriters: 
=== RUN   TestFileRename/minimal,forceCache=false
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:15 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:15 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:48:15 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:15 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:15 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:15 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:15 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:15 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:15 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:15 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:15 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:15 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F18DEAB0155BED15D2E585</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:16 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:16 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:16 DEBUG : : Looking for writers
2025/02/06 01:48:16 DEBUG : >WaitForWriters: 
2025/02/06 01:48:16 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
=== RUN   TestFileRename/minimal,forceCache=true
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:17 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:17 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:48:17 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:17 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:17 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:17 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:17 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:17 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:17 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:17 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F19298B113EC992C65D4DB</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:17 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:18 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:18 DEBUG : : Looking for writers
2025/02/06 01:48:18 DEBUG : >WaitForWriters: 
2025/02/06 01:48:18 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
=== RUN   TestFileRename/writes,forceCache=false
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:18 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:18 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:18 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:18 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:18 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:18 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:18 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:18 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:18 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1978EB15E53769C873A59</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:19 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:19 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:19 DEBUG : : Looking for writers
2025/02/06 01:48:19 DEBUG : >WaitForWriters: 
2025/02/06 01:48:19 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
=== RUN   TestFileRename/writes,forceCache=true
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:19 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:19 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:19 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:19 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:19 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:19 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:19 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:19 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:19 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:19 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:19 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F19C61B11F45D9C565210C</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:20 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:20 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:20 DEBUG : : Looking for writers
2025/02/06 01:48:20 DEBUG : >WaitForWriters: 
2025/02/06 01:48:20 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
=== RUN   TestFileRename/full,forceCache=false
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:20 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:20 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:20 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:20 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:20 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:20 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:20 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:20 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:20 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:20 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:20 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1A103B0D708F75105198F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:21 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:21 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:21 DEBUG : : Looking for writers
2025/02/06 01:48:21 DEBUG : >WaitForWriters: 
2025/02/06 01:48:21 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestFileRename (7.35s)
    --- FAIL: TestFileRename/off,forceCache=false (1.25s)
    --- FAIL: TestFileRename/minimal,forceCache=false (1.20s)
    --- FAIL: TestFileRename/minimal,forceCache=true (1.21s)
    --- FAIL: TestFileRename/writes,forceCache=false (1.26s)
    --- FAIL: TestFileRename/writes,forceCache=true (1.24s)
    --- FAIL: TestFileRename/full,forceCache=false (1.20s)
=== RUN   TestReadFileHandleMethods
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:21 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:21 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1A600B2D5012CB4944B5C</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:22 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:23 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:23 DEBUG : : Looking for writers
2025/02/06 01:48:23 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleMethods (1.29s)
=== RUN   TestReadFileHandleSeek
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:23 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:23 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1AAD8B2DAE5EA4E20E728</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:24 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:24 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:24 DEBUG : : Looking for writers
2025/02/06 01:48:24 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleSeek (1.25s)
=== RUN   TestReadFileHandleReadAt
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:24 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:24 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1AFACB0D30522A834E566</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:25 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:25 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:25 DEBUG : : Looking for writers
2025/02/06 01:48:25 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleReadAt (1.20s)
=== RUN   TestReadFileHandleFlush
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:25 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:25 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1B445B0179A989B9DCCA8</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:26 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:26 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:26 DEBUG : : Looking for writers
2025/02/06 01:48:26 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleFlush (1.18s)
=== RUN   TestReadFileHandleRelease
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:26 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:26 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1B854B2DB829FFADCB32B</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:27 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:27 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:27 DEBUG : : Looking for writers
2025/02/06 01:48:27 DEBUG : >WaitForWriters: 
--- FAIL: TestReadFileHandleRelease (1.07s)
=== RUN   TestRWFileHandleMethodsRead
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:27 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:27 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:27 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:27 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:27 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:27 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:27 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1BD0CB112B024B54789A0</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:28 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:28 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:28 DEBUG : : Looking for writers
2025/02/06 01:48:28 DEBUG : >WaitForWriters: 
2025/02/06 01:48:28 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleMethodsRead (1.19s)
=== RUN   TestRWFileHandleSeek
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:29 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:29 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:29 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:29 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:29 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:29 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:29 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:29 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:29 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:29 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:29 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1C1D2B2D8C980DF7DD7D5</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:30 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:30 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:30 DEBUG : : Looking for writers
2025/02/06 01:48:30 DEBUG : >WaitForWriters: 
2025/02/06 01:48:30 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleSeek (1.21s)
=== RUN   TestRWFileHandleReadAt
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:30 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:30 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:30 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:30 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:30 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:30 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:30 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:30 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:30 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:30 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:30 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1C66FB01DAB7AA1D229EF</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:31 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:31 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:31 DEBUG : : Looking for writers
2025/02/06 01:48:31 DEBUG : >WaitForWriters: 
2025/02/06 01:48:31 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleReadAt (1.18s)
=== RUN   TestRWFileHandleFlushRead
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:31 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:31 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:31 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:31 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:31 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:31 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:31 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:31 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:31 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:31 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:31 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1CB08B0DC6A232567E911</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:32 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:32 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:32 DEBUG : : Looking for writers
2025/02/06 01:48:32 DEBUG : >WaitForWriters: 
2025/02/06 01:48:32 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleFlushRead (1.18s)
=== RUN   TestRWFileHandleReleaseRead
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:32 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:32 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:32 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:32 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:32 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:32 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:32 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:32 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:32 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:32 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:32 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1CFF8B15D0F5C83B4DCA3</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:33 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:33 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:33 DEBUG : : Looking for writers
2025/02/06 01:48:33 DEBUG : >WaitForWriters: 
2025/02/06 01:48:33 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleReleaseRead (1.27s)
=== RUN   TestRWFileHandleMethodsWrite
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:48:33 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:48:33 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:48:33 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:33 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:33 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:33 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:33 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:33 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:33 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:48:33 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:48:33 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:48:33 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2025/02/06 01:48:34 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:48:34 DEBUG : file1: newRWFileHandle: 
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): openPending: 
2025/02/06 01:48:34 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct)
2025/02/06 01:48:34 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): >openPending: err=<nil>
2025/02/06 01:48:34 DEBUG : file1: >newRWFileHandle: err=<nil>
2025/02/06 01:48:34 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:48:34 DEBUG : file1: >Open: fd=file1 (rw), err=<nil>
2025/02/06 01:48:34 DEBUG : file1: >OpenFile: fd=file1 (rw), err=<nil>
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): _writeAt: size=5, off=0
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): >_writeAt: n=5, err=<nil>
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): _writeAt: size=7, off=5
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): >_writeAt: n=7, err=<nil>
2025/02/06 01:48:34 DEBUG : file1: vfs cache: truncate to size=11
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): close: 
2025/02/06 01:48:34 DEBUG : file1: vfs cache: setting modification time to 2025-02-06 01:48:34.201322895 +0000 UTC m=+59.195972556
2025/02/06 01:48:34 INFO  : file1: vfs cache: queuing for upload in 100ms
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): >close: err=<nil>
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): close: 
2025/02/06 01:48:34 DEBUG : file1(0xc00034d100): >close: err=file already closed
2025/02/06 01:48:34 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:48:34 DEBUG : : Looking for writers
2025/02/06 01:48:34 DEBUG : file1: reading active writers
2025/02/06 01:48:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms
2025/02/06 01:48:34 DEBUG : : Looking for writers
2025/02/06 01:48:34 DEBUG : file1: reading active writers
2025/02/06 01:48:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms
2025/02/06 01:48:34 DEBUG : : Looking for writers
2025/02/06 01:48:34 DEBUG : file1: reading active writers
2025/02/06 01:48:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms
2025/02/06 01:48:34 DEBUG : : Looking for writers
2025/02/06 01:48:34 DEBUG : file1: reading active writers
2025/02/06 01:48:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms
2025/02/06 01:48:34 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:48:34 DEBUG : : Looking for writers
2025/02/06 01:48:34 DEBUG : file1: reading active writers
2025/02/06 01:48:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms
2025/02/06 01:48:34 DEBUG : : Looking for writers
2025/02/06 01:48:34 DEBUG : file1: reading active writers
2025/02/06 01:48:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms
2025/02/06 01:48:34 DEBUG : : Looking for writers
2025/02/06 01:48:34 DEBUG : file1: reading active writers
2025/02/06 01:48:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms
2025/02/06 01:48:35 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1D56BB0D8FAEF7641F7FB</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:35 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:35 ERROR : file1: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1D56BB0D8FAEF7641F7FB</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:35 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:35 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:48:35 DEBUG : : Looking for writers
2025/02/06 01:48:35 DEBUG : file1: reading active writers
2025/02/06 01:48:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:35 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1D8ACB1957E9DE722EF91</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:35 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:35 ERROR : file1: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1D8ACB1957E9DE722EF91</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:35 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:36 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:48:36 DEBUG : : Looking for writers
2025/02/06 01:48:36 DEBUG : file1: reading active writers
2025/02/06 01:48:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:37 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1DD5EB0145811477882E7</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:37 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:37 ERROR : file1: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1DD5EB0145811477882E7</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:37 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:37 DEBUG : : Looking for writers
2025/02/06 01:48:37 DEBUG : file1: reading active writers
2025/02/06 01:48:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:37 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:48:38 DEBUG : : Looking for writers
2025/02/06 01:48:38 DEBUG : file1: reading active writers
2025/02/06 01:48:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:38 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1E39FB0D8FBE778748067</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:38 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:38 ERROR : file1: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1E39FB0D8FBE778748067</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:38 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:39 DEBUG : : Looking for writers
2025/02/06 01:48:39 DEBUG : file1: reading active writers
2025/02/06 01:48:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:40 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:48:40 DEBUG : : Looking for writers
2025/02/06 01:48:40 DEBUG : file1: reading active writers
2025/02/06 01:48:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:41 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1ED41B156A174C7D9FF78</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:41 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:41 ERROR : file1: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1ED41B156A174C7D9FF78</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:41 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:41 DEBUG : : Looking for writers
2025/02/06 01:48:41 DEBUG : file1: reading active writers
2025/02/06 01:48:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:42 DEBUG : : Looking for writers
2025/02/06 01:48:42 DEBUG : file1: reading active writers
2025/02/06 01:48:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:43 DEBUG : : Looking for writers
2025/02/06 01:48:43 DEBUG : file1: reading active writers
2025/02/06 01:48:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:44 DEBUG : : Looking for writers
2025/02/06 01:48:44 DEBUG : file1: reading active writers
2025/02/06 01:48:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:44 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:48:45 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1FC56B113EC41348526E6</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:45 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:45 ERROR : file1: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F1FC56B113EC41348526E6</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:45 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:45 DEBUG : : Looking for writers
2025/02/06 01:48:45 DEBUG : file1: reading active writers
2025/02/06 01:48:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:46 DEBUG : : Looking for writers
2025/02/06 01:48:46 DEBUG : file1: reading active writers
2025/02/06 01:48:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:47 DEBUG : : Looking for writers
2025/02/06 01:48:47 DEBUG : file1: reading active writers
2025/02/06 01:48:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:48 DEBUG : : Looking for writers
2025/02/06 01:48:48 DEBUG : file1: reading active writers
2025/02/06 01:48:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:49 DEBUG : : Looking for writers
2025/02/06 01:48:49 DEBUG : file1: reading active writers
2025/02/06 01:48:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:50 DEBUG : : Looking for writers
2025/02/06 01:48:50 DEBUG : file1: reading active writers
2025/02/06 01:48:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:51 DEBUG : : Looking for writers
2025/02/06 01:48:51 DEBUG : file1: reading active writers
2025/02/06 01:48:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:51 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:48:52 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F21892B01A4A79E9176468</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:52 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:52 ERROR : file1: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F21892B01A4A79E9176468</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:48:52 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:48:52 DEBUG : : Looking for writers
2025/02/06 01:48:52 DEBUG : file1: reading active writers
2025/02/06 01:48:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:53 DEBUG : : Looking for writers
2025/02/06 01:48:53 DEBUG : file1: reading active writers
2025/02/06 01:48:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:54 DEBUG : : Looking for writers
2025/02/06 01:48:54 DEBUG : file1: reading active writers
2025/02/06 01:48:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:55 DEBUG : : Looking for writers
2025/02/06 01:48:55 DEBUG : file1: reading active writers
2025/02/06 01:48:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:56 DEBUG : : Looking for writers
2025/02/06 01:48:56 DEBUG : file1: reading active writers
2025/02/06 01:48:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:57 DEBUG : : Looking for writers
2025/02/06 01:48:57 DEBUG : file1: reading active writers
2025/02/06 01:48:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:58 DEBUG : : Looking for writers
2025/02/06 01:48:58 DEBUG : file1: reading active writers
2025/02/06 01:48:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:48:59 DEBUG : : Looking for writers
2025/02/06 01:48:59 DEBUG : file1: reading active writers
2025/02/06 01:48:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:00 DEBUG : : Looking for writers
2025/02/06 01:49:00 DEBUG : file1: reading active writers
2025/02/06 01:49:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:01 DEBUG : : Looking for writers
2025/02/06 01:49:01 DEBUG : file1: reading active writers
2025/02/06 01:49:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:02 DEBUG : : Looking for writers
2025/02/06 01:49:02 DEBUG : file1: reading active writers
2025/02/06 01:49:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:03 DEBUG : : Looking for writers
2025/02/06 01:49:03 DEBUG : file1: reading active writers
2025/02/06 01:49:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:04 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s
Cache{
	"file1": &{c:0xc0008f2000 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000244c08 notify:{wait:0 notify:0 lock:0 head:<nil> tail:<nil>} checker:824636099648} name:file1 opens:0 downloaders:<nil> o:<nil> fd:<nil> info:{ModTime:{wall:13970486124594852239 ext:59195972556 loc:0x3dad5e0} ATime:{wall:13970486124594864712 ext:59195985029 loc:0x3dad5e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false},
}
2025/02/06 01:49:04 DEBUG : >WaitForWriters: 
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
2025/02/06 01:49:05 DEBUG : file1: vfs cache: starting upload
    fstest.go:295: Flushing the directory cache
2025/02/06 01:49:05 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
2025/02/06 01:49:06 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F24EBDB15203740650C5EE</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:06 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:06 ERROR : file1: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F24EBDB15203740650C5EE</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:06 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
    fstest.go:295: Flushing the directory cache
2025/02/06 01:49:07 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:337
        	Error:      	Should be true
        	Test:       	TestRWFileHandleMethodsWrite
        	Messages:   	listing wrong, want
        	            	  file1 (11) got
        	            	  
    fstest.go:197: Not found "file1"
    fstest.go:200: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:337
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestRWFileHandleMethodsWrite
        	Messages:   	1 objects not found
2025/02/06 01:49:11 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:49:11 DEBUG : : Looking for writers
2025/02/06 01:49:11 DEBUG : file1: reading active writers
2025/02/06 01:49:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms
2025/02/06 01:49:11 DEBUG : : Looking for writers
2025/02/06 01:49:11 DEBUG : file1: reading active writers
2025/02/06 01:49:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms
2025/02/06 01:49:11 DEBUG : : Looking for writers
2025/02/06 01:49:11 DEBUG : file1: reading active writers
2025/02/06 01:49:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms
2025/02/06 01:49:11 DEBUG : : Looking for writers
2025/02/06 01:49:11 DEBUG : file1: reading active writers
2025/02/06 01:49:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms
2025/02/06 01:49:11 DEBUG : : Looking for writers
2025/02/06 01:49:11 DEBUG : file1: reading active writers
2025/02/06 01:49:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms
2025/02/06 01:49:11 DEBUG : : Looking for writers
2025/02/06 01:49:11 DEBUG : file1: reading active writers
2025/02/06 01:49:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms
2025/02/06 01:49:12 DEBUG : : Looking for writers
2025/02/06 01:49:12 DEBUG : file1: reading active writers
2025/02/06 01:49:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms
2025/02/06 01:49:12 DEBUG : : Looking for writers
2025/02/06 01:49:12 DEBUG : file1: reading active writers
2025/02/06 01:49:12 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:13 DEBUG : : Looking for writers
2025/02/06 01:49:13 DEBUG : file1: reading active writers
2025/02/06 01:49:13 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:14 DEBUG : : Looking for writers
2025/02/06 01:49:14 DEBUG : file1: reading active writers
2025/02/06 01:49:14 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:15 DEBUG : : Looking for writers
2025/02/06 01:49:15 DEBUG : file1: reading active writers
2025/02/06 01:49:15 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:16 DEBUG : : Looking for writers
2025/02/06 01:49:16 DEBUG : file1: reading active writers
2025/02/06 01:49:16 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:17 DEBUG : : Looking for writers
2025/02/06 01:49:17 DEBUG : file1: reading active writers
2025/02/06 01:49:17 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:18 DEBUG : : Looking for writers
2025/02/06 01:49:18 DEBUG : file1: reading active writers
2025/02/06 01:49:18 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:19 DEBUG : : Looking for writers
2025/02/06 01:49:19 DEBUG : file1: reading active writers
2025/02/06 01:49:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:20 DEBUG : : Looking for writers
2025/02/06 01:49:20 DEBUG : file1: reading active writers
2025/02/06 01:49:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:21 DEBUG : : Looking for writers
2025/02/06 01:49:21 DEBUG : file1: reading active writers
2025/02/06 01:49:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:22 DEBUG : : Looking for writers
2025/02/06 01:49:22 DEBUG : file1: reading active writers
2025/02/06 01:49:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:23 DEBUG : : Looking for writers
2025/02/06 01:49:23 DEBUG : file1: reading active writers
2025/02/06 01:49:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:24 DEBUG : : Looking for writers
2025/02/06 01:49:24 DEBUG : file1: reading active writers
2025/02/06 01:49:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:25 DEBUG : : Looking for writers
2025/02/06 01:49:25 DEBUG : file1: reading active writers
2025/02/06 01:49:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:26 DEBUG : : Looking for writers
2025/02/06 01:49:26 DEBUG : file1: reading active writers
2025/02/06 01:49:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:27 DEBUG : : Looking for writers
2025/02/06 01:49:27 DEBUG : file1: reading active writers
2025/02/06 01:49:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:28 DEBUG : : Looking for writers
2025/02/06 01:49:28 DEBUG : file1: reading active writers
2025/02/06 01:49:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:29 DEBUG : : Looking for writers
2025/02/06 01:49:29 DEBUG : file1: reading active writers
2025/02/06 01:49:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:30 DEBUG : : Looking for writers
2025/02/06 01:49:30 DEBUG : file1: reading active writers
2025/02/06 01:49:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:31 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:49:31 DEBUG : : Looking for writers
2025/02/06 01:49:31 DEBUG : file1: reading active writers
2025/02/06 01:49:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:32 DEBUG : : Looking for writers
2025/02/06 01:49:32 DEBUG : file1: reading active writers
2025/02/06 01:49:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:33 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2B795B1598E53541CDD9A</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:32 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:33 ERROR : file1: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2B795B1598E53541CDD9A</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:32 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:33 DEBUG : : Looking for writers
2025/02/06 01:49:33 DEBUG : file1: reading active writers
2025/02/06 01:49:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:33 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes
2025/02/06 01:49:33 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11)
2025/02/06 01:49:34 DEBUG : : Looking for writers
2025/02/06 01:49:34 DEBUG : file1: reading active writers
2025/02/06 01:49:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:35 DEBUG : : Looking for writers
2025/02/06 01:49:35 DEBUG : file1: reading active writers
2025/02/06 01:49:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:36 DEBUG : : Looking for writers
2025/02/06 01:49:36 DEBUG : file1: reading active writers
2025/02/06 01:49:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:37 DEBUG : : Looking for writers
2025/02/06 01:49:37 DEBUG : file1: reading active writers
2025/02/06 01:49:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:38 DEBUG : : Looking for writers
2025/02/06 01:49:38 DEBUG : file1: reading active writers
2025/02/06 01:49:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:39 DEBUG : : Looking for writers
2025/02/06 01:49:39 DEBUG : file1: reading active writers
2025/02/06 01:49:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:40 DEBUG : : Looking for writers
2025/02/06 01:49:40 DEBUG : file1: reading active writers
2025/02/06 01:49:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:41 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s
Cache{
	"file1": &{c:0xc0008f2000 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000244c08 notify:{wait:0 notify:0 lock:0 head:<nil> tail:<nil>} checker:824636099648} name:file1 opens:0 downloaders:<nil> o:<nil> fd:<nil> info:{ModTime:{wall:13970486124594852239 ext:59195972556 loc:0x3dad5e0} ATime:{wall:13970486124594864712 ext:59195985029 loc:0x3dad5e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false},
}
2025/02/06 01:49:41 DEBUG : >WaitForWriters: 
2025/02/06 01:49:41 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleMethodsWrite (67.85s)
=== RUN   TestRWFileHandleWriteAt
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:49:41 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:49:41 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:49:41 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:49:41 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:49:41 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:49:41 DEBUG : Config file has changed externally - reloading
2025/02/06 01:49:41 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:49:41 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:49:41 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:49:41 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:49:41 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:49:41 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:49:41 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2025/02/06 01:49:42 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:49:42 DEBUG : file1: newRWFileHandle: 
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): openPending: 
2025/02/06 01:49:42 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct)
2025/02/06 01:49:42 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): >openPending: err=<nil>
2025/02/06 01:49:42 DEBUG : file1: >newRWFileHandle: err=<nil>
2025/02/06 01:49:42 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:49:42 DEBUG : file1: >Open: fd=file1 (rw), err=<nil>
2025/02/06 01:49:42 DEBUG : file1: >OpenFile: fd=file1 (rw), err=<nil>
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): _writeAt: size=7, off=0
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): >_writeAt: n=7, err=<nil>
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): _writeAt: size=6, off=5
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): >_writeAt: n=6, err=<nil>
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): close: 
2025/02/06 01:49:42 DEBUG : file1: vfs cache: setting modification time to 2025-02-06 01:49:42.050280085 +0000 UTC m=+127.044929755
2025/02/06 01:49:42 INFO  : file1: vfs cache: queuing for upload in 100ms
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): >close: err=<nil>
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): _writeAt: size=5, off=0
2025/02/06 01:49:42 DEBUG : file1(0xc0005fc3c0): >_writeAt: n=0, err=file already closed
2025/02/06 01:49:42 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:49:42 DEBUG : : Looking for writers
2025/02/06 01:49:42 DEBUG : file1: reading active writers
2025/02/06 01:49:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms
2025/02/06 01:49:42 DEBUG : : Looking for writers
2025/02/06 01:49:42 DEBUG : file1: reading active writers
2025/02/06 01:49:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms
2025/02/06 01:49:42 DEBUG : : Looking for writers
2025/02/06 01:49:42 DEBUG : file1: reading active writers
2025/02/06 01:49:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms
2025/02/06 01:49:42 DEBUG : : Looking for writers
2025/02/06 01:49:42 DEBUG : file1: reading active writers
2025/02/06 01:49:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms
2025/02/06 01:49:42 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:49:42 DEBUG : : Looking for writers
2025/02/06 01:49:42 DEBUG : file1: reading active writers
2025/02/06 01:49:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms
2025/02/06 01:49:42 DEBUG : : Looking for writers
2025/02/06 01:49:42 DEBUG : file1: reading active writers
2025/02/06 01:49:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms
2025/02/06 01:49:42 DEBUG : : Looking for writers
2025/02/06 01:49:42 DEBUG : file1: reading active writers
2025/02/06 01:49:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms
2025/02/06 01:49:43 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2DF06B15D0FC5A1BBD361</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:43 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:43 ERROR : file1: vfs cache: failed to upload try #1, will retry in 200ms: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2DF06B15D0FC5A1BBD361</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:43 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:43 DEBUG : : Looking for writers
2025/02/06 01:49:43 DEBUG : file1: reading active writers
2025/02/06 01:49:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:43 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:49:44 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2E2D5B0D3044EC1F3CE86</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:44 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:44 ERROR : file1: vfs cache: failed to upload try #2, will retry in 400ms: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2E2D5B0D3044EC1F3CE86</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:44 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:44 DEBUG : : Looking for writers
2025/02/06 01:49:44 DEBUG : file1: reading active writers
2025/02/06 01:49:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:44 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:49:45 DEBUG : : Looking for writers
2025/02/06 01:49:45 DEBUG : file1: reading active writers
2025/02/06 01:49:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:45 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2E787B19440BF095BE124</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:45 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:45 ERROR : file1: vfs cache: failed to upload try #3, will retry in 800ms: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2E787B19440BF095BE124</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:45 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:46 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:49:46 DEBUG : : Looking for writers
2025/02/06 01:49:46 DEBUG : file1: reading active writers
2025/02/06 01:49:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:46 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2EDF4B19A70BBA2B0F383</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:46 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:46 ERROR : file1: vfs cache: failed to upload try #4, will retry in 1.6s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2EDF4B19A70BBA2B0F383</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:46 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:47 DEBUG : : Looking for writers
2025/02/06 01:49:47 DEBUG : file1: reading active writers
2025/02/06 01:49:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:48 DEBUG : : Looking for writers
2025/02/06 01:49:48 DEBUG : file1: reading active writers
2025/02/06 01:49:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:48 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:49:49 DEBUG : : Looking for writers
2025/02/06 01:49:49 DEBUG : file1: reading active writers
2025/02/06 01:49:49 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:49 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2F767B2DB84261672B1C2</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:49 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:49 ERROR : file1: vfs cache: failed to upload try #5, will retry in 3.2s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F2F767B2DB84261672B1C2</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:49 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:50 DEBUG : : Looking for writers
2025/02/06 01:49:50 DEBUG : file1: reading active writers
2025/02/06 01:49:50 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:51 DEBUG : : Looking for writers
2025/02/06 01:49:51 DEBUG : file1: reading active writers
2025/02/06 01:49:51 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:52 DEBUG : : Looking for writers
2025/02/06 01:49:52 DEBUG : file1: reading active writers
2025/02/06 01:49:52 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:52 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:49:53 DEBUG : : Looking for writers
2025/02/06 01:49:53 DEBUG : file1: reading active writers
2025/02/06 01:49:53 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:53 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F30737B0DC69F9419817FE</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:53 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:53 ERROR : file1: vfs cache: failed to upload try #6, will retry in 6.4s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F30737B0DC69F9419817FE</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:49:53 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:49:54 DEBUG : : Looking for writers
2025/02/06 01:49:54 DEBUG : file1: reading active writers
2025/02/06 01:49:54 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:55 DEBUG : : Looking for writers
2025/02/06 01:49:55 DEBUG : file1: reading active writers
2025/02/06 01:49:55 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:56 DEBUG : : Looking for writers
2025/02/06 01:49:56 DEBUG : file1: reading active writers
2025/02/06 01:49:56 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:57 DEBUG : : Looking for writers
2025/02/06 01:49:57 DEBUG : file1: reading active writers
2025/02/06 01:49:57 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:58 DEBUG : : Looking for writers
2025/02/06 01:49:58 DEBUG : file1: reading active writers
2025/02/06 01:49:58 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:59 DEBUG : : Looking for writers
2025/02/06 01:49:59 DEBUG : file1: reading active writers
2025/02/06 01:49:59 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:49:59 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:50:00 DEBUG : : Looking for writers
2025/02/06 01:50:00 DEBUG : file1: reading active writers
2025/02/06 01:50:00 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:00 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F32384B11F463FE546893A</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:00 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:50:00 ERROR : file1: vfs cache: failed to upload try #7, will retry in 12.8s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F32384B11F463FE546893A</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:00 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:50:01 DEBUG : : Looking for writers
2025/02/06 01:50:01 DEBUG : file1: reading active writers
2025/02/06 01:50:01 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:02 DEBUG : : Looking for writers
2025/02/06 01:50:02 DEBUG : file1: reading active writers
2025/02/06 01:50:02 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:03 DEBUG : : Looking for writers
2025/02/06 01:50:03 DEBUG : file1: reading active writers
2025/02/06 01:50:03 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:04 DEBUG : : Looking for writers
2025/02/06 01:50:04 DEBUG : file1: reading active writers
2025/02/06 01:50:04 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:05 DEBUG : : Looking for writers
2025/02/06 01:50:05 DEBUG : file1: reading active writers
2025/02/06 01:50:05 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:06 DEBUG : : Looking for writers
2025/02/06 01:50:06 DEBUG : file1: reading active writers
2025/02/06 01:50:06 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:07 DEBUG : : Looking for writers
2025/02/06 01:50:07 DEBUG : file1: reading active writers
2025/02/06 01:50:07 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:08 DEBUG : : Looking for writers
2025/02/06 01:50:08 DEBUG : file1: reading active writers
2025/02/06 01:50:08 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:09 DEBUG : : Looking for writers
2025/02/06 01:50:09 DEBUG : file1: reading active writers
2025/02/06 01:50:09 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:10 DEBUG : : Looking for writers
2025/02/06 01:50:10 DEBUG : file1: reading active writers
2025/02/06 01:50:10 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:11 DEBUG : : Looking for writers
2025/02/06 01:50:11 DEBUG : file1: reading active writers
2025/02/06 01:50:11 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:12 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s
Cache{
	"file1": &{c:0xc00029ac30 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000600508 notify:{wait:0 notify:0 lock:0 head:<nil> tail:<nil>} checker:824640013632} name:file1 opens:0 downloaders:<nil> o:<nil> fd:<nil> info:{ModTime:{wall:13970486197458253461 ext:127044929755 loc:0x3dad5e0} ATime:{wall:13970486197458266946 ext:127044943241 loc:0x3dad5e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false},
}
2025/02/06 01:50:12 DEBUG : >WaitForWriters: 
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:50:13 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
2025/02/06 01:50:13 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:50:14 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F359A4B0D6EF7A02EB87D7</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:14 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:50:14 ERROR : file1: vfs cache: failed to upload try #8, will retry in 25.6s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F359A4B0D6EF7A02EB87D7</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:14 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
    fstest.go:295: Flushing the directory cache
2025/02/06 01:50:15 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:387
        	Error:      	Should be true
        	Test:       	TestRWFileHandleWriteAt
        	Messages:   	listing wrong, want
        	            	  file1 (11) got
        	            	  
    fstest.go:197: Not found "file1"
    fstest.go:200: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:387
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestRWFileHandleWriteAt
        	Messages:   	1 objects not found
2025/02/06 01:50:19 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:50:19 DEBUG : : Looking for writers
2025/02/06 01:50:19 DEBUG : file1: reading active writers
2025/02/06 01:50:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 10ms
2025/02/06 01:50:19 DEBUG : : Looking for writers
2025/02/06 01:50:19 DEBUG : file1: reading active writers
2025/02/06 01:50:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 20ms
2025/02/06 01:50:19 DEBUG : : Looking for writers
2025/02/06 01:50:19 DEBUG : file1: reading active writers
2025/02/06 01:50:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 40ms
2025/02/06 01:50:19 DEBUG : : Looking for writers
2025/02/06 01:50:19 DEBUG : file1: reading active writers
2025/02/06 01:50:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 80ms
2025/02/06 01:50:19 DEBUG : : Looking for writers
2025/02/06 01:50:19 DEBUG : file1: reading active writers
2025/02/06 01:50:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 160ms
2025/02/06 01:50:19 DEBUG : : Looking for writers
2025/02/06 01:50:19 DEBUG : file1: reading active writers
2025/02/06 01:50:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 320ms
2025/02/06 01:50:19 DEBUG : : Looking for writers
2025/02/06 01:50:19 DEBUG : file1: reading active writers
2025/02/06 01:50:19 DEBUG : Still 0 writers active and 1 cache items in use, waiting 640ms
2025/02/06 01:50:20 DEBUG : : Looking for writers
2025/02/06 01:50:20 DEBUG : file1: reading active writers
2025/02/06 01:50:20 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:21 DEBUG : : Looking for writers
2025/02/06 01:50:21 DEBUG : file1: reading active writers
2025/02/06 01:50:21 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:22 DEBUG : : Looking for writers
2025/02/06 01:50:22 DEBUG : file1: reading active writers
2025/02/06 01:50:22 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:23 DEBUG : : Looking for writers
2025/02/06 01:50:23 DEBUG : file1: reading active writers
2025/02/06 01:50:23 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:24 DEBUG : : Looking for writers
2025/02/06 01:50:24 DEBUG : file1: reading active writers
2025/02/06 01:50:24 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:25 DEBUG : : Looking for writers
2025/02/06 01:50:25 DEBUG : file1: reading active writers
2025/02/06 01:50:25 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:26 DEBUG : : Looking for writers
2025/02/06 01:50:26 DEBUG : file1: reading active writers
2025/02/06 01:50:26 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:27 DEBUG : : Looking for writers
2025/02/06 01:50:27 DEBUG : file1: reading active writers
2025/02/06 01:50:27 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:28 DEBUG : : Looking for writers
2025/02/06 01:50:28 DEBUG : file1: reading active writers
2025/02/06 01:50:28 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:29 DEBUG : : Looking for writers
2025/02/06 01:50:29 DEBUG : file1: reading active writers
2025/02/06 01:50:29 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:30 DEBUG : : Looking for writers
2025/02/06 01:50:30 DEBUG : file1: reading active writers
2025/02/06 01:50:30 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:31 DEBUG : : Looking for writers
2025/02/06 01:50:31 DEBUG : file1: reading active writers
2025/02/06 01:50:31 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:32 DEBUG : : Looking for writers
2025/02/06 01:50:32 DEBUG : file1: reading active writers
2025/02/06 01:50:32 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:33 DEBUG : : Looking for writers
2025/02/06 01:50:33 DEBUG : file1: reading active writers
2025/02/06 01:50:33 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:34 DEBUG : : Looking for writers
2025/02/06 01:50:34 DEBUG : file1: reading active writers
2025/02/06 01:50:34 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:35 DEBUG : : Looking for writers
2025/02/06 01:50:35 DEBUG : file1: reading active writers
2025/02/06 01:50:35 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:36 DEBUG : : Looking for writers
2025/02/06 01:50:36 DEBUG : file1: reading active writers
2025/02/06 01:50:36 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:37 DEBUG : : Looking for writers
2025/02/06 01:50:37 DEBUG : file1: reading active writers
2025/02/06 01:50:37 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:38 DEBUG : : Looking for writers
2025/02/06 01:50:38 DEBUG : file1: reading active writers
2025/02/06 01:50:38 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:39 DEBUG : : Looking for writers
2025/02/06 01:50:39 DEBUG : file1: reading active writers
2025/02/06 01:50:39 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:40 DEBUG : file1: vfs cache: starting upload
2025/02/06 01:50:40 DEBUG : : Looking for writers
2025/02/06 01:50:40 DEBUG : file1: reading active writers
2025/02/06 01:50:40 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:41 ERROR : file1: Failed to copy: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F3C26FB1172A5A0A3E6BF5</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:41 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:50:41 ERROR : file1: vfs cache: failed to upload try #9, will retry in 51.2s: vfs cache: failed to transfer file from cache to remote: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F3C26FB1172A5A0A3E6BF5</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:41 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:50:41 DEBUG : : Looking for writers
2025/02/06 01:50:41 DEBUG : file1: reading active writers
2025/02/06 01:50:41 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:41 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item file1 not removed, freed 0 bytes
2025/02/06 01:50:41 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 1, uploading 0, total size 11 (was 11)
2025/02/06 01:50:42 DEBUG : : Looking for writers
2025/02/06 01:50:42 DEBUG : file1: reading active writers
2025/02/06 01:50:42 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:43 DEBUG : : Looking for writers
2025/02/06 01:50:43 DEBUG : file1: reading active writers
2025/02/06 01:50:43 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:44 DEBUG : : Looking for writers
2025/02/06 01:50:44 DEBUG : file1: reading active writers
2025/02/06 01:50:44 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:45 DEBUG : : Looking for writers
2025/02/06 01:50:45 DEBUG : file1: reading active writers
2025/02/06 01:50:45 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:46 DEBUG : : Looking for writers
2025/02/06 01:50:46 DEBUG : file1: reading active writers
2025/02/06 01:50:46 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:47 DEBUG : : Looking for writers
2025/02/06 01:50:47 DEBUG : file1: reading active writers
2025/02/06 01:50:47 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:48 DEBUG : : Looking for writers
2025/02/06 01:50:48 DEBUG : file1: reading active writers
2025/02/06 01:50:48 DEBUG : Still 0 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:49 ERROR : Exiting even though 0 writers active and 1 cache items in use after 30s
Cache{
	"file1": &{c:0xc00029ac30 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000600508 notify:{wait:0 notify:0 lock:0 head:<nil> tail:<nil>} checker:824640013632} name:file1 opens:0 downloaders:<nil> o:<nil> fd:<nil> info:{ModTime:{wall:13970486197458253461 ext:127044929755 loc:0x3dad5e0} ATime:{wall:13970486197458266946 ext:127044943241 loc:0x3dad5e0} Size:11 Rs:[{Pos:0 Size:11}] Fingerprint: Dirty:true} writeBackID:1 pendingAccesses:0 modified:false beingReset:false},
}
2025/02/06 01:50:49 DEBUG : >WaitForWriters: 
2025/02/06 01:50:49 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleWriteAt (67.51s)
=== RUN   TestRWFileHandleSizeTruncateExisting
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:50:49 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:50:49 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:50:49 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:49 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:49 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:49 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:50:49 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:49 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:49 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:50:49 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:49 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F3E722B01DAB0AF4FFC766</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:50 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:50:50 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:50:50 DEBUG : : Looking for writers
2025/02/06 01:50:50 DEBUG : >WaitForWriters: 
2025/02/06 01:50:50 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleSizeTruncateExisting (1.64s)
=== RUN   TestRWFileHandleSizeCreateExisting
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:50:51 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:50:51 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:50:51 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:51 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:51 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:51 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:50:51 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:51 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:51 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:50:51 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:51 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
    run.go:285: Failed to put "dir/file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F3EBE0B0DBCB960716E824</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:51 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:50:51 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:50:51 DEBUG : : Looking for writers
2025/02/06 01:50:51 DEBUG : >WaitForWriters: 
2025/02/06 01:50:51 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileHandleSizeCreateExisting (1.21s)
=== RUN   TestRWFileModTimeWithOpenWriters
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:50:52 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:50:52 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: root is "/home/rclone/.cache/rclone"
2025/02/06 01:50:52 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:52 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:52 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:52 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:50:52 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfs/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:52 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:52 DEBUG : :local: detected overridden config - adding "{bxYPm}" suffix to name
2025/02/06 01:50:52 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot':/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera" to be canonical ":local{bxYPm}:/home/rclone/.cache/rclone/vfsMeta/TestLinkbox/rclone-test-zacirek4sera"
2025/02/06 01:50:52 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:50:52 INFO  : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0)
2025/02/06 01:50:52 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:50:52 DEBUG : file1: newRWFileHandle: 
2025/02/06 01:50:52 DEBUG : file1(0xc00050f940): openPending: 
2025/02/06 01:50:52 DEBUG : file1: vfs cache: truncate to size=0 (not needed as size correct)
2025/02/06 01:50:52 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:50:52 DEBUG : file1(0xc00050f940): >openPending: err=<nil>
2025/02/06 01:50:52 DEBUG : file1: >newRWFileHandle: err=<nil>
2025/02/06 01:50:52 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:50:52 DEBUG : file1: >Open: fd=file1 (rw), err=<nil>
2025/02/06 01:50:52 DEBUG : file1: >OpenFile: fd=file1 (rw), err=<nil>
    run.go:285: Failed to put "time_test" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F3F181B0D30460DA0FB3F8</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:50:53 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:50:53 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:50:53 DEBUG : : Looking for writers
2025/02/06 01:50:53 DEBUG : file1: reading active writers
2025/02/06 01:50:53 DEBUG : file1: active writers 1
2025/02/06 01:50:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 10ms
2025/02/06 01:50:53 DEBUG : : Looking for writers
2025/02/06 01:50:53 DEBUG : file1: reading active writers
2025/02/06 01:50:53 DEBUG : file1: active writers 1
2025/02/06 01:50:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 20ms
2025/02/06 01:50:53 DEBUG : : Looking for writers
2025/02/06 01:50:53 DEBUG : file1: reading active writers
2025/02/06 01:50:53 DEBUG : file1: active writers 1
2025/02/06 01:50:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 40ms
2025/02/06 01:50:53 DEBUG : : Looking for writers
2025/02/06 01:50:53 DEBUG : file1: reading active writers
2025/02/06 01:50:53 DEBUG : file1: active writers 1
2025/02/06 01:50:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 80ms
2025/02/06 01:50:53 DEBUG : : Looking for writers
2025/02/06 01:50:53 DEBUG : file1: reading active writers
2025/02/06 01:50:53 DEBUG : file1: active writers 1
2025/02/06 01:50:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 160ms
2025/02/06 01:50:53 DEBUG : : Looking for writers
2025/02/06 01:50:53 DEBUG : file1: reading active writers
2025/02/06 01:50:53 DEBUG : file1: active writers 1
2025/02/06 01:50:53 DEBUG : Still 1 writers active and 1 cache items in use, waiting 320ms
2025/02/06 01:50:54 DEBUG : : Looking for writers
2025/02/06 01:50:54 DEBUG : file1: reading active writers
2025/02/06 01:50:54 DEBUG : file1: active writers 1
2025/02/06 01:50:54 DEBUG : Still 1 writers active and 1 cache items in use, waiting 640ms
2025/02/06 01:50:54 DEBUG : : Looking for writers
2025/02/06 01:50:54 DEBUG : file1: reading active writers
2025/02/06 01:50:54 DEBUG : file1: active writers 1
2025/02/06 01:50:54 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:55 DEBUG : : Looking for writers
2025/02/06 01:50:55 DEBUG : file1: reading active writers
2025/02/06 01:50:55 DEBUG : file1: active writers 1
2025/02/06 01:50:55 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:56 DEBUG : : Looking for writers
2025/02/06 01:50:56 DEBUG : file1: reading active writers
2025/02/06 01:50:56 DEBUG : file1: active writers 1
2025/02/06 01:50:56 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:57 DEBUG : : Looking for writers
2025/02/06 01:50:57 DEBUG : file1: reading active writers
2025/02/06 01:50:57 DEBUG : file1: active writers 1
2025/02/06 01:50:57 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:58 DEBUG : : Looking for writers
2025/02/06 01:50:58 DEBUG : file1: reading active writers
2025/02/06 01:50:58 DEBUG : file1: active writers 1
2025/02/06 01:50:58 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:50:59 DEBUG : : Looking for writers
2025/02/06 01:50:59 DEBUG : file1: reading active writers
2025/02/06 01:50:59 DEBUG : file1: active writers 1
2025/02/06 01:50:59 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:00 DEBUG : : Looking for writers
2025/02/06 01:51:00 DEBUG : file1: reading active writers
2025/02/06 01:51:00 DEBUG : file1: active writers 1
2025/02/06 01:51:00 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:01 DEBUG : : Looking for writers
2025/02/06 01:51:01 DEBUG : file1: reading active writers
2025/02/06 01:51:01 DEBUG : file1: active writers 1
2025/02/06 01:51:01 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:02 DEBUG : : Looking for writers
2025/02/06 01:51:02 DEBUG : file1: reading active writers
2025/02/06 01:51:02 DEBUG : file1: active writers 1
2025/02/06 01:51:02 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:03 DEBUG : : Looking for writers
2025/02/06 01:51:03 DEBUG : file1: reading active writers
2025/02/06 01:51:03 DEBUG : file1: active writers 1
2025/02/06 01:51:03 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:04 DEBUG : : Looking for writers
2025/02/06 01:51:04 DEBUG : file1: reading active writers
2025/02/06 01:51:04 DEBUG : file1: active writers 1
2025/02/06 01:51:04 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:05 DEBUG : : Looking for writers
2025/02/06 01:51:05 DEBUG : file1: reading active writers
2025/02/06 01:51:05 DEBUG : file1: active writers 1
2025/02/06 01:51:05 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:06 DEBUG : : Looking for writers
2025/02/06 01:51:06 DEBUG : file1: reading active writers
2025/02/06 01:51:06 DEBUG : file1: active writers 1
2025/02/06 01:51:06 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:07 DEBUG : : Looking for writers
2025/02/06 01:51:07 DEBUG : file1: reading active writers
2025/02/06 01:51:07 DEBUG : file1: active writers 1
2025/02/06 01:51:07 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:08 DEBUG : : Looking for writers
2025/02/06 01:51:08 DEBUG : file1: reading active writers
2025/02/06 01:51:08 DEBUG : file1: active writers 1
2025/02/06 01:51:08 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:09 DEBUG : : Looking for writers
2025/02/06 01:51:09 DEBUG : file1: reading active writers
2025/02/06 01:51:09 DEBUG : file1: active writers 1
2025/02/06 01:51:09 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:10 DEBUG : : Looking for writers
2025/02/06 01:51:10 DEBUG : file1: reading active writers
2025/02/06 01:51:10 DEBUG : file1: active writers 1
2025/02/06 01:51:10 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:11 DEBUG : : Looking for writers
2025/02/06 01:51:11 DEBUG : file1: reading active writers
2025/02/06 01:51:11 DEBUG : file1: active writers 1
2025/02/06 01:51:11 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:12 DEBUG : : Looking for writers
2025/02/06 01:51:12 DEBUG : file1: reading active writers
2025/02/06 01:51:12 DEBUG : file1: active writers 1
2025/02/06 01:51:12 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:13 DEBUG : : Looking for writers
2025/02/06 01:51:13 DEBUG : file1: reading active writers
2025/02/06 01:51:13 DEBUG : file1: active writers 1
2025/02/06 01:51:13 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:14 DEBUG : : Looking for writers
2025/02/06 01:51:14 DEBUG : file1: reading active writers
2025/02/06 01:51:14 DEBUG : file1: active writers 1
2025/02/06 01:51:14 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:15 DEBUG : : Looking for writers
2025/02/06 01:51:15 DEBUG : file1: reading active writers
2025/02/06 01:51:15 DEBUG : file1: active writers 1
2025/02/06 01:51:15 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:16 DEBUG : : Looking for writers
2025/02/06 01:51:16 DEBUG : file1: reading active writers
2025/02/06 01:51:16 DEBUG : file1: active writers 1
2025/02/06 01:51:16 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:17 DEBUG : : Looking for writers
2025/02/06 01:51:17 DEBUG : file1: reading active writers
2025/02/06 01:51:17 DEBUG : file1: active writers 1
2025/02/06 01:51:17 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:18 DEBUG : : Looking for writers
2025/02/06 01:51:18 DEBUG : file1: reading active writers
2025/02/06 01:51:18 DEBUG : file1: active writers 1
2025/02/06 01:51:18 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:19 DEBUG : : Looking for writers
2025/02/06 01:51:19 DEBUG : file1: reading active writers
2025/02/06 01:51:19 DEBUG : file1: active writers 1
2025/02/06 01:51:19 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:20 DEBUG : : Looking for writers
2025/02/06 01:51:20 DEBUG : file1: reading active writers
2025/02/06 01:51:20 DEBUG : file1: active writers 1
2025/02/06 01:51:20 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:21 DEBUG : : Looking for writers
2025/02/06 01:51:21 DEBUG : file1: reading active writers
2025/02/06 01:51:21 DEBUG : file1: active writers 1
2025/02/06 01:51:21 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:22 DEBUG : : Looking for writers
2025/02/06 01:51:22 DEBUG : file1: reading active writers
2025/02/06 01:51:22 DEBUG : file1: active writers 1
2025/02/06 01:51:22 DEBUG : Still 1 writers active and 1 cache items in use, waiting 1s
2025/02/06 01:51:23 ERROR : Exiting even though 1 writers active and 1 cache items in use after 30s
Cache{
	"file1": &{c:0xc0008f20f0 mu:{state:0 sema:0} cond:{noCopy:{} L:0xc000cc4808 notify:{wait:0 notify:0 lock:0 head:<nil> tail:<nil>} checker:824647108672} name:file1 opens:1 downloaders:<nil> o:<nil> fd:0xc0007aa2c8 info:{ModTime:{wall:13970486272978218682 ext:197402967307 loc:0x3dad5e0} ATime:{wall:13970486272978218682 ext:197402967307 loc:0x3dad5e0} Size:0 Rs:[] Fingerprint: Dirty:true} writeBackID:0 pendingAccesses:0 modified:true beingReset:false},
}
2025/02/06 01:51:23 DEBUG : >WaitForWriters: 
2025/02/06 01:51:23 DEBUG : Linkbox root 'rclone-test-zacirek4sera': vfs cache: cleaner exiting
--- FAIL: TestRWFileModTimeWithOpenWriters (31.44s)
=== RUN   TestUnicodeNormalization
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
    run.go:285: Failed to put "normal name with no special characters.txt" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F46B6EB11F46A5FD7651C3</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:51:24 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
--- FAIL: TestUnicodeNormalization (1.20s)
=== RUN   TestVFSStat
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:51:24 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:51:24 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F47064B15D0F98C3F57AEB</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:51:25 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:51:25 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:51:25 DEBUG : : Looking for writers
2025/02/06 01:51:25 DEBUG : >WaitForWriters: 
--- FAIL: TestVFSStat (1.29s)
=== RUN   TestVFSStatParent
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:51:26 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:51:26 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F47569B2DDFA02A20A83C0</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:51:27 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:51:27 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:51:27 DEBUG : : Looking for writers
2025/02/06 01:51:27 DEBUG : >WaitForWriters: 
--- FAIL: TestVFSStatParent (1.28s)
=== RUN   TestVFSOpenFile
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:51:27 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:51:27 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
    run.go:285: Failed to put "file1" to "Linkbox root 'rclone-test-zacirek4sera'": update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F47A0EB15F0408C34EABBC</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:51:28 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:51:28 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:51:28 DEBUG : : Looking for writers
2025/02/06 01:51:28 DEBUG : >WaitForWriters: 
--- FAIL: TestVFSOpenFile (1.18s)
=== RUN   TestVFSMkdir
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:51:28 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:51:28 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:51:29 DEBUG : : Added virtual directory entry vAddDir: "/"
2025/02/06 01:51:29 DEBUG : : Added virtual directory entry vAddDir: "a"
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:51:30 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:51:32 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:383
        	Error:      	Not equal: 
        	            	expected: []string{"a"}
        	            	actual  : []string{}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,3 +1,2 @@
        	            	-([]string) (len=1) {
        	            	- (string) (len=1) "a"
        	            	+([]string) {
        	            	 }
        	Test:       	TestVFSMkdir
        	Messages:   	directories
2025/02/06 01:51:36 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:51:37 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:51:39 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:389
        	Error:      	Not equal: 
        	            	expected: []string{"a"}
        	            	actual  : []string{}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,3 +1,2 @@
        	            	-([]string) (len=1) {
        	            	- (string) (len=1) "a"
        	            	+([]string) {
        	            	 }
        	Test:       	TestVFSMkdir
        	Messages:   	directories
2025/02/06 01:51:44 DEBUG : : Added virtual directory entry vAddDir: "b"
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:51:45 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:51:47 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:395
        	Error:      	Not equal: 
        	            	expected: []string{"a", "b"}
        	            	actual  : []string{}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,4 +1,2 @@
        	            	-([]string) (len=2) {
        	            	- (string) (len=1) "a",
        	            	- (string) (len=1) "b"
        	            	+([]string) {
        	            	 }
        	Test:       	TestVFSMkdir
        	Messages:   	directories
2025/02/06 01:51:52 DEBUG : : Added virtual directory entry vAddDir: "c"
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:51:53 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:51:55 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:401
        	Error:      	Not equal: 
        	            	expected: []string{"a", "b", "c"}
        	            	actual  : []string{}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,5 +1,2 @@
        	            	-([]string) (len=3) {
        	            	- (string) (len=1) "a",
        	            	- (string) (len=1) "b",
        	            	- (string) (len=1) "c"
        	            	+([]string) {
        	            	 }
        	Test:       	TestVFSMkdir
        	Messages:   	directories
2025/02/06 01:52:00 DEBUG : : Added virtual directory entry vAddDir: "d"
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:01 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:03 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:407
        	Error:      	Not equal: 
        	            	expected: []string{"a", "b", "c", "d"}
        	            	actual  : []string{}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,6 +1,2 @@
        	            	-([]string) (len=4) {
        	            	- (string) (len=1) "a",
        	            	- (string) (len=1) "b",
        	            	- (string) (len=1) "c",
        	            	- (string) (len=1) "d"
        	            	+([]string) {
        	            	 }
        	Test:       	TestVFSMkdir
        	Messages:   	directories
2025/02/06 01:52:07 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:52:07 DEBUG : : Looking for writers
2025/02/06 01:52:07 DEBUG : a: Looking for writers
2025/02/06 01:52:07 DEBUG : b: Looking for writers
2025/02/06 01:52:07 DEBUG : c: Looking for writers
2025/02/06 01:52:07 DEBUG : d: Looking for writers
2025/02/06 01:52:07 DEBUG : : Looking for writers
2025/02/06 01:52:07 DEBUG : /: reading active writers
2025/02/06 01:52:07 DEBUG : a: reading active writers
2025/02/06 01:52:07 DEBUG : b: reading active writers
2025/02/06 01:52:07 DEBUG : c: reading active writers
2025/02/06 01:52:07 DEBUG : d: reading active writers
2025/02/06 01:52:07 DEBUG : >WaitForWriters: 
2025/02/06 01:52:07 ERROR : : error listing: directory not found
--- FAIL: TestVFSMkdir (39.01s)
=== RUN   TestVFSMkdirAll
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:52:07 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:52:07 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:52:07 ERROR : : error listing: directory not found
2025/02/06 01:52:08 ERROR : : error listing: directory not found
2025/02/06 01:52:09 DEBUG : : Added virtual directory entry vAddDir: "a"
2025/02/06 01:52:09 DEBUG : a: Added virtual directory entry vAddDir: "b"
2025/02/06 01:52:10 DEBUG : a/b: Added virtual directory entry vAddDir: "c"
2025/02/06 01:52:10 DEBUG : a/b/c: Added virtual directory entry vAddDir: "d"
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:12 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:14 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:428
        	Error:      	Not equal: 
        	            	expected: []string{"a", "a/b", "a/b/c", "a/b/c/d"}
        	            	actual  : []string{}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,6 +1,2 @@
        	            	-([]string) (len=4) {
        	            	- (string) (len=1) "a",
        	            	- (string) (len=3) "a/b",
        	            	- (string) (len=5) "a/b/c",
        	            	- (string) (len=7) "a/b/c/d"
        	            	+([]string) {
        	            	 }
        	Test:       	TestVFSMkdirAll
        	Messages:   	directories
2025/02/06 01:52:18 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:19 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:21 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:434
        	Error:      	Not equal: 
        	            	expected: []string{"a", "a/b", "a/b/c", "a/b/c/d"}
        	            	actual  : []string{}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,6 +1,2 @@
        	            	-([]string) (len=4) {
        	            	- (string) (len=1) "a",
        	            	- (string) (len=3) "a/b",
        	            	- (string) (len=5) "a/b/c",
        	            	- (string) (len=7) "a/b/c/d"
        	            	+([]string) {
        	            	 }
        	Test:       	TestVFSMkdirAll
        	Messages:   	directories
2025/02/06 01:52:25 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:26 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:28 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:321: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:440
        	Error:      	Not equal: 
        	            	expected: []string{"a", "a/b", "a/b/c", "a/b/c/d"}
        	            	actual  : []string{}
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,6 +1,2 @@
        	            	-([]string) (len=4) {
        	            	- (string) (len=1) "a",
        	            	- (string) (len=3) "a/b",
        	            	- (string) (len=5) "a/b/c",
        	            	- (string) (len=7) "a/b/c/d"
        	            	+([]string) {
        	            	 }
        	Test:       	TestVFSMkdirAll
        	Messages:   	directories
2025/02/06 01:52:32 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:52:32 DEBUG : a/b/c/d: Looking for writers
2025/02/06 01:52:32 DEBUG : a/b/c: Looking for writers
2025/02/06 01:52:32 DEBUG : d: reading active writers
2025/02/06 01:52:32 DEBUG : a/b: Looking for writers
2025/02/06 01:52:32 DEBUG : c: reading active writers
2025/02/06 01:52:32 DEBUG : a: Looking for writers
2025/02/06 01:52:32 DEBUG : b: reading active writers
2025/02/06 01:52:32 DEBUG : : Looking for writers
2025/02/06 01:52:32 DEBUG : a: reading active writers
2025/02/06 01:52:32 DEBUG : >WaitForWriters: 
2025/02/06 01:52:32 ERROR : : error listing: directory not found
--- FAIL: TestVFSMkdirAll (24.66s)
=== RUN   TestWriteFileHandleMethods
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:52:32 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:52:32 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:52:32 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:52:32 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:52:32 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:52:32 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/02/06 01:52:32 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/02/06 01:52:32 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:52:32 ERROR : file1: WriteFileHandle: Read: Can't read and write to file without --vfs-cache-mode >= minimal
2025/02/06 01:52:32 ERROR : file1: WriteFileHandle: ReadAt: Can't read and write to file without --vfs-cache-mode >= minimal
2025/02/06 01:52:32 ERROR : file1: WriteFileHandle: Truncate: Can't change size without --vfs-cache-mode >= writes
2025/02/06 01:52:32 DEBUG : Linkbox root 'rclone-test-zacirek4sera': File to upload is small (5 bytes), uploading instead of streaming
2025/02/06 01:52:33 ERROR : file1: WriteFileHandle.New Rcat failed: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F579D7B19E18460ECE6DC4</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:52:33 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:52:33 DEBUG : file1: Remove: 
2025/02/06 01:52:33 DEBUG : : Added virtual directory entry vDel: "file1"
2025/02/06 01:52:33 DEBUG : file1: >Remove: err=<nil>
    write_test.go:144: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:144
        	Error:      	Received unexpected error:
        	            	update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F579D7B19E18460ECE6DC4</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:52:33 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
        	Test:       	TestWriteFileHandleMethods
    dir_test.go:247: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:247
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:153
        	Error:      	Not equal: 
        	            	expected: []string{"file1,5,false"}
        	            	actual  : []string(nil)
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,4 +1,2 @@
        	            	-([]string) (len=1) {
        	            	- (string) (len=13) "file1,5,false"
        	            	-}
        	            	+([]string) <nil>
        	            	 
        	Test:       	TestWriteFileHandleMethods
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:34 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:52:36 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:157
        	Error:      	Should be true
        	Test:       	TestWriteFileHandleMethods
        	Messages:   	listing wrong, want
        	            	  file1 (5) got
        	            	  
    fstest.go:197: Not found "file1"
    fstest.go:200: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:157
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestWriteFileHandleMethods
        	Messages:   	1 objects not found
2025/02/06 01:52:40 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:52:40 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:52:40 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:52:40 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/02/06 01:52:40 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/02/06 01:52:40 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:52:40 DEBUG : Linkbox root 'rclone-test-zacirek4sera': File to upload is small (0 bytes), uploading instead of streaming
2025/02/06 01:52:41 ERROR : file1: WriteFileHandle.New Rcat failed: can't upload empty files to this remote
2025/02/06 01:52:41 DEBUG : file1: Remove: 
2025/02/06 01:52:41 DEBUG : : Added virtual directory entry vDel: "file1"
2025/02/06 01:52:41 DEBUG : file1: >Remove: err=<nil>
    write_test.go:163: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:163
        	Error:      	Received unexpected error:
        	            	can't upload empty files to this remote
        	Test:       	TestWriteFileHandleMethods
    dir_test.go:247: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:247
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:164
        	Error:      	Not equal: 
        	            	expected: []string{"file1,5,false"}
        	            	actual  : []string(nil)
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,4 +1,2 @@
        	            	-([]string) (len=1) {
        	            	- (string) (len=13) "file1,5,false"
        	            	-}
        	            	+([]string) <nil>
        	            	 
        	Test:       	TestWriteFileHandleMethods
2025/02/06 01:52:41 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:52:41 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:52:41 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:52:41 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/02/06 01:52:41 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/02/06 01:52:41 DEBUG : : Added virtual directory entry vAddFile: "file1"
    write_test.go:171: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:171
        	Error:      	Not equal: 
        	            	expected: *errors.errorString(&errors.errorString{s:"permission denied"})
        	            	actual  : <nil>(<nil>)
        	Test:       	TestWriteFileHandleMethods
2025/02/06 01:52:41 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:52:41 DEBUG : : Looking for writers
2025/02/06 01:52:41 DEBUG : file1: reading active writers
2025/02/06 01:52:41 DEBUG : file1: active writers 1
2025/02/06 01:52:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 10ms
2025/02/06 01:52:41 DEBUG : : Looking for writers
2025/02/06 01:52:41 DEBUG : file1: reading active writers
2025/02/06 01:52:41 DEBUG : file1: active writers 1
2025/02/06 01:52:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 20ms
2025/02/06 01:52:41 DEBUG : : Looking for writers
2025/02/06 01:52:41 DEBUG : file1: reading active writers
2025/02/06 01:52:41 DEBUG : file1: active writers 1
2025/02/06 01:52:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 40ms
2025/02/06 01:52:41 DEBUG : : Looking for writers
2025/02/06 01:52:41 DEBUG : file1: reading active writers
2025/02/06 01:52:41 DEBUG : file1: active writers 1
2025/02/06 01:52:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 80ms
2025/02/06 01:52:41 DEBUG : : Looking for writers
2025/02/06 01:52:41 DEBUG : file1: reading active writers
2025/02/06 01:52:41 DEBUG : file1: active writers 1
2025/02/06 01:52:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 160ms
2025/02/06 01:52:41 DEBUG : : Looking for writers
2025/02/06 01:52:41 DEBUG : file1: reading active writers
2025/02/06 01:52:41 DEBUG : file1: active writers 1
2025/02/06 01:52:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 320ms
2025/02/06 01:52:41 DEBUG : : Looking for writers
2025/02/06 01:52:41 DEBUG : file1: reading active writers
2025/02/06 01:52:41 DEBUG : file1: active writers 1
2025/02/06 01:52:41 DEBUG : Still 1 writers active and 0 cache items in use, waiting 640ms
2025/02/06 01:52:42 DEBUG : : Looking for writers
2025/02/06 01:52:42 DEBUG : file1: reading active writers
2025/02/06 01:52:42 DEBUG : file1: active writers 1
2025/02/06 01:52:42 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:43 DEBUG : : Looking for writers
2025/02/06 01:52:43 DEBUG : file1: reading active writers
2025/02/06 01:52:43 DEBUG : file1: active writers 1
2025/02/06 01:52:43 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:44 DEBUG : : Looking for writers
2025/02/06 01:52:44 DEBUG : file1: reading active writers
2025/02/06 01:52:44 DEBUG : file1: active writers 1
2025/02/06 01:52:44 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:45 DEBUG : : Looking for writers
2025/02/06 01:52:45 DEBUG : file1: reading active writers
2025/02/06 01:52:45 DEBUG : file1: active writers 1
2025/02/06 01:52:45 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:46 DEBUG : : Looking for writers
2025/02/06 01:52:46 DEBUG : file1: reading active writers
2025/02/06 01:52:46 DEBUG : file1: active writers 1
2025/02/06 01:52:46 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:47 DEBUG : : Looking for writers
2025/02/06 01:52:47 DEBUG : file1: reading active writers
2025/02/06 01:52:47 DEBUG : file1: active writers 1
2025/02/06 01:52:47 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:48 DEBUG : : Looking for writers
2025/02/06 01:52:48 DEBUG : file1: reading active writers
2025/02/06 01:52:48 DEBUG : file1: active writers 1
2025/02/06 01:52:48 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:49 DEBUG : : Looking for writers
2025/02/06 01:52:49 DEBUG : file1: reading active writers
2025/02/06 01:52:49 DEBUG : file1: active writers 1
2025/02/06 01:52:49 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:50 DEBUG : : Looking for writers
2025/02/06 01:52:50 DEBUG : file1: reading active writers
2025/02/06 01:52:50 DEBUG : file1: active writers 1
2025/02/06 01:52:50 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:51 DEBUG : : Looking for writers
2025/02/06 01:52:51 DEBUG : file1: reading active writers
2025/02/06 01:52:51 DEBUG : file1: active writers 1
2025/02/06 01:52:51 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:52 DEBUG : : Looking for writers
2025/02/06 01:52:52 DEBUG : file1: reading active writers
2025/02/06 01:52:52 DEBUG : file1: active writers 1
2025/02/06 01:52:52 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:53 DEBUG : : Looking for writers
2025/02/06 01:52:53 DEBUG : file1: reading active writers
2025/02/06 01:52:53 DEBUG : file1: active writers 1
2025/02/06 01:52:53 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:54 DEBUG : : Looking for writers
2025/02/06 01:52:54 DEBUG : file1: reading active writers
2025/02/06 01:52:54 DEBUG : file1: active writers 1
2025/02/06 01:52:54 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:55 DEBUG : : Looking for writers
2025/02/06 01:52:55 DEBUG : file1: reading active writers
2025/02/06 01:52:55 DEBUG : file1: active writers 1
2025/02/06 01:52:55 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:56 DEBUG : : Looking for writers
2025/02/06 01:52:56 DEBUG : file1: reading active writers
2025/02/06 01:52:56 DEBUG : file1: active writers 1
2025/02/06 01:52:56 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:57 DEBUG : : Looking for writers
2025/02/06 01:52:57 DEBUG : file1: reading active writers
2025/02/06 01:52:57 DEBUG : file1: active writers 1
2025/02/06 01:52:57 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:58 DEBUG : : Looking for writers
2025/02/06 01:52:58 DEBUG : file1: reading active writers
2025/02/06 01:52:58 DEBUG : file1: active writers 1
2025/02/06 01:52:58 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:52:59 DEBUG : : Looking for writers
2025/02/06 01:52:59 DEBUG : file1: reading active writers
2025/02/06 01:52:59 DEBUG : file1: active writers 1
2025/02/06 01:52:59 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:00 DEBUG : : Looking for writers
2025/02/06 01:53:00 DEBUG : file1: reading active writers
2025/02/06 01:53:00 DEBUG : file1: active writers 1
2025/02/06 01:53:00 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:01 DEBUG : : Looking for writers
2025/02/06 01:53:01 DEBUG : file1: reading active writers
2025/02/06 01:53:01 DEBUG : file1: active writers 1
2025/02/06 01:53:01 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:02 DEBUG : : Looking for writers
2025/02/06 01:53:02 DEBUG : file1: reading active writers
2025/02/06 01:53:02 DEBUG : file1: active writers 1
2025/02/06 01:53:02 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:03 DEBUG : : Looking for writers
2025/02/06 01:53:03 DEBUG : file1: reading active writers
2025/02/06 01:53:03 DEBUG : file1: active writers 1
2025/02/06 01:53:03 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:04 DEBUG : : Looking for writers
2025/02/06 01:53:04 DEBUG : file1: reading active writers
2025/02/06 01:53:04 DEBUG : file1: active writers 1
2025/02/06 01:53:04 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:05 DEBUG : : Looking for writers
2025/02/06 01:53:05 DEBUG : file1: reading active writers
2025/02/06 01:53:05 DEBUG : file1: active writers 1
2025/02/06 01:53:05 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:06 DEBUG : : Looking for writers
2025/02/06 01:53:06 DEBUG : file1: reading active writers
2025/02/06 01:53:06 DEBUG : file1: active writers 1
2025/02/06 01:53:06 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:07 DEBUG : : Looking for writers
2025/02/06 01:53:07 DEBUG : file1: reading active writers
2025/02/06 01:53:07 DEBUG : file1: active writers 1
2025/02/06 01:53:07 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:08 DEBUG : : Looking for writers
2025/02/06 01:53:08 DEBUG : file1: reading active writers
2025/02/06 01:53:08 DEBUG : file1: active writers 1
2025/02/06 01:53:08 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:09 DEBUG : : Looking for writers
2025/02/06 01:53:09 DEBUG : file1: reading active writers
2025/02/06 01:53:09 DEBUG : file1: active writers 1
2025/02/06 01:53:09 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:10 DEBUG : : Looking for writers
2025/02/06 01:53:10 DEBUG : file1: reading active writers
2025/02/06 01:53:10 DEBUG : file1: active writers 1
2025/02/06 01:53:10 DEBUG : Still 1 writers active and 0 cache items in use, waiting 1s
2025/02/06 01:53:11 ERROR : Exiting even though 1 writers active and 0 cache items in use after 30s
Cache: <nil>
2025/02/06 01:53:11 DEBUG : >WaitForWriters: 
--- FAIL: TestWriteFileHandleMethods (39.12s)
=== RUN   TestWriteFileHandleWriteAt
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:53:11 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:53:11 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:53:11 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:53:11 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:53:11 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:53:11 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/02/06 01:53:11 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/02/06 01:53:11 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:53:11 DEBUG : file1: waiting for in-sequence write to 100 for 1s
2025/02/06 01:53:12 DEBUG : file1: aborting in-sequence write wait, off=100
2025/02/06 01:53:12 DEBUG : file1: failed to wait for in-sequence write to 100
2025/02/06 01:53:12 ERROR : file1: WriteFileHandle.Write: can't seek in file without --vfs-cache-mode >= writes
2025/02/06 01:53:12 DEBUG : Linkbox root 'rclone-test-zacirek4sera': File to upload is small (11 bytes), uploading instead of streaming
2025/02/06 01:53:13 ERROR : file1: WriteFileHandle.New Rcat failed: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F61439B0179B9248E4E63F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:13 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:53:13 DEBUG : file1: Remove: 
2025/02/06 01:53:13 DEBUG : : Added virtual directory entry vDel: "file1"
2025/02/06 01:53:13 DEBUG : file1: >Remove: err=<nil>
    write_test.go:221: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:221
        	Error:      	Received unexpected error:
        	            	update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F61439B0179B9248E4E63F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:13 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
        	Test:       	TestWriteFileHandleWriteAt
2025/02/06 01:53:13 ERROR : file1: WriteFileHandle.Write: error: Bad file descriptor
    dir_test.go:247: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:247
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:231
        	Error:      	Not equal: 
        	            	expected: []string{"file1,11,false"}
        	            	actual  : []string(nil)
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,4 +1,2 @@
        	            	-([]string) (len=1) {
        	            	- (string) (len=14) "file1,11,false"
        	            	-}
        	            	+([]string) <nil>
        	            	 
        	Test:       	TestWriteFileHandleWriteAt
    fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:53:14 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3
    fstest.go:295: Flushing the directory cache
2025/02/06 01:53:16 ERROR : : error listing: directory not found
    fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3
    fstest.go:295: Flushing the directory cache
    fstest.go:299: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:235
        	Error:      	Should be true
        	Test:       	TestWriteFileHandleWriteAt
        	Messages:   	listing wrong, want
        	            	  file1 (11) got
        	            	  
    fstest.go:197: Not found "file1"
    fstest.go:200: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304
        	            				/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:235
        	Error:      	Not equal: 
        	            	expected: 0
        	            	actual  : 1
        	Test:       	TestWriteFileHandleWriteAt
        	Messages:   	1 objects not found
2025/02/06 01:53:20 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:53:20 DEBUG : : Looking for writers
2025/02/06 01:53:20 DEBUG : >WaitForWriters: 
2025/02/06 01:53:20 ERROR : : error listing: directory not found
--- FAIL: TestWriteFileHandleWriteAt (9.13s)
=== RUN   TestWriteFileHandleFlush
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:53:20 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:53:20 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:53:20 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:53:20 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:53:20 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:53:20 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/02/06 01:53:20 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/02/06 01:53:20 DEBUG : file1: WriteFileHandle.Flush unwritten handle, writing 0 bytes to avoid race conditions
2025/02/06 01:53:20 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:53:20 DEBUG : Linkbox root 'rclone-test-zacirek4sera': File to upload is small (5 bytes), uploading instead of streaming
2025/02/06 01:53:22 ERROR : file1: WriteFileHandle.New Rcat failed: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F63674B11C5D4EE11FA83F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:22 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:53:22 DEBUG : file1: Remove: 
2025/02/06 01:53:22 DEBUG : : Added virtual directory entry vDel: "file1"
2025/02/06 01:53:22 DEBUG : file1: >Remove: err=<nil>
2025/02/06 01:53:22 ERROR : file1: WriteFileHandle.Flush error: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F63674B11C5D4EE11FA83F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:22 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
    write_test.go:256: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:256
        	Error:      	Received unexpected error:
        	            	update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F63674B11C5D4EE11FA83F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:22 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
        	Test:       	TestWriteFileHandleFlush
2025/02/06 01:53:22 DEBUG : file1: WriteFileHandle.Flush nothing to do
    dir_test.go:247: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:247
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:267
        	Error:      	Not equal: 
        	            	expected: []string{"file1,5,false"}
        	            	actual  : []string(nil)
        	            	
        	            	Diff:
        	            	--- Expected
        	            	+++ Actual
        	            	@@ -1,4 +1,2 @@
        	            	-([]string) (len=1) {
        	            	- (string) (len=13) "file1,5,false"
        	            	-}
        	            	+([]string) <nil>
        	            	 
        	Test:       	TestWriteFileHandleFlush
2025/02/06 01:53:22 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:53:22 DEBUG : : Looking for writers
2025/02/06 01:53:22 DEBUG : >WaitForWriters: 
--- FAIL: TestWriteFileHandleFlush (1.84s)
=== RUN   TestWriteFileModTimeWithOpenWriters
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:53:22 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:53:22 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:53:22 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:53:22 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:53:22 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:53:22 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/02/06 01:53:22 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/02/06 01:53:22 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:53:22 DEBUG : Linkbox root 'rclone-test-zacirek4sera': File to upload is small (2 bytes), uploading instead of streaming
2025/02/06 01:53:23 ERROR : file1: WriteFileHandle.New Rcat failed: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F63BD7B0179B724EF2E8F9</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:23 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:53:23 DEBUG : file1: Remove: 
2025/02/06 01:53:23 DEBUG : : Added virtual directory entry vDel: "file1"
2025/02/06 01:53:23 DEBUG : file1: >Remove: err=<nil>
    write_test.go:333: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:333
        	Error:      	Received unexpected error:
        	            	update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F63BD7B0179B724EF2E8F9</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:23 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
        	Test:       	TestWriteFileModTimeWithOpenWriters
2025/02/06 01:53:23 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:53:23 DEBUG : : Looking for writers
2025/02/06 01:53:23 DEBUG : >WaitForWriters: 
--- FAIL: TestWriteFileModTimeWithOpenWriters (1.38s)
=== RUN   TestFileReadAtNonZeroLength
    run.go:180: Remote "Linkbox root 'rclone-test-zacirek4sera'", Local "Local file system at /tmp/rclone2663286652", Modify Window "876000h0m0s"
2025/02/06 01:53:23 INFO  : Linkbox root 'rclone-test-zacirek4sera': poll-interval is not supported by this remote
2025/02/06 01:53:23 NOTICE: Linkbox root 'rclone-test-zacirek4sera': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2025/02/06 01:53:23 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx
2025/02/06 01:53:23 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE
2025/02/06 01:53:23 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:53:23 DEBUG : file1: >Open: fd=file1 (w), err=<nil>
2025/02/06 01:53:23 DEBUG : file1: >OpenFile: fd=file1 (w), err=<nil>
2025/02/06 01:53:23 DEBUG : : Added virtual directory entry vAddFile: "file1"
2025/02/06 01:53:23 DEBUG : Linkbox root 'rclone-test-zacirek4sera': File to upload is small (100 bytes), uploading instead of streaming
2025/02/06 01:53:24 ERROR : file1: WriteFileHandle.New Rcat failed: update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F64156B11ECE3A91B1E00F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:24 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
2025/02/06 01:53:24 DEBUG : file1: Remove: 
2025/02/06 01:53:24 DEBUG : : Added virtual directory entry vDel: "file1"
2025/02/06 01:53:24 DEBUG : file1: >Remove: err=<nil>
    write_test.go:360: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:360
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384
        	Error:      	Received unexpected error:
        	            	update err in uploading file: HTTP error 403 (403 Forbidden) returned body: "<?xml version=\"1.0\" encoding=\"UTF-8\" standalone=\"yes\"?><Error><Code>RequestTimeTooSkewed</Code><Message>Request has expired.</Message><RequestId>00000194D8F64156B11ECE3A91B1E00F</RequestId><HostId>Z9v+cC1sRnaWw6x0vi8pxxYA0YVnKxbYHUPAFpnxkX8sLV44u5b02Z+ailn2wCnR</HostId><ServerTime>Thu, 06 Feb 2025 01:53:24 GMT</ServerTime><Deadline>Wed, 05 Feb 2025 05:22:20 GMT</Deadline></Error>"
        	Test:       	TestFileReadAtNonZeroLength
2025/02/06 01:53:24 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=----------
2025/02/06 01:53:24 DEBUG : file1: >OpenFile: fd=<nil>, err=file does not exist
    write_test.go:365: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:365
        	            				/home/rclone/go/src/github.com/rclone/rclone/vfs/write_test.go:384
        	Error:      	Received unexpected error:
        	            	file does not exist
        	Test:       	TestFileReadAtNonZeroLength
2025/02/06 01:53:24 DEBUG : WaitForWriters: timeout=30s
2025/02/06 01:53:24 DEBUG : : Looking for writers
2025/02/06 01:53:24 DEBUG : >WaitForWriters: 
--- FAIL: TestFileReadAtNonZeroLength (1.43s)
FAIL
2025/02/06 01:53:25 DEBUG : Linkbox root 'rclone-test-zacirek4sera': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirCreate|TestDirFileOpen|TestDirForgetAll|TestDirForgetPath|TestDirHandleMethods|TestDirHandleReaddir|TestDirHandleReaddirnames|TestDirMethods|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods|TestFileOpen|TestFileOpenRead|TestFileOpenWrite|TestFileReadAtNonZeroLength|TestFileRemove|TestFileRemoveAll|TestRWFileHandleFlushRead|TestRWFileHandleMethodsRead|TestRWFileHandleMethodsWrite|TestRWFileHandleReadAt|TestRWFileHandleReleaseRead|TestRWFileHandleSeek|TestRWFileHandleSizeCreateExisting|TestRWFileHandleSizeTruncateExisting|TestRWFileHandleWriteAt|TestRWFileModTimeWithOpenWriters|TestReadFileHandleFlush|TestReadFileHandleMethods|TestReadFileHandleReadAt|TestReadFileHandleRelease|TestReadFileHandleSeek|TestUnicodeNormalization|TestVFSMkdir|TestVFSMkdirAll|TestVFSOpenFile|TestVFSStat|TestVFSStatParent|TestWriteFileHandleFlush|TestWriteFileHandleMethods|TestWriteFileHandleWriteAt|TestWriteFileModTimeWithOpenWriters)$|^TestFileRename$/^(full,forceCache=false|minimal,forceCache=false|minimal,forceCache=true|off,forceCache=false|writes,forceCache=false|writes,forceCache=true)$|^TestFileSetModTime$/^(cache=full,open=false,write=false|cache=full,open=true,write=false|cache=full,open=true,write=true|cache=off,open=false,write=false|cache=off,open=true,write=false|cache=off,open=true,write=true)$'" - Finished ERROR in 5m50.381170588s (try 5/5): exit status 1: Failed [TestDirHandleMethods TestDirHandleReaddir TestDirHandleReaddirnames TestDirMethods TestDirForgetAll TestDirForgetPath TestDirWalk TestDirSetModTime TestDirStat TestDirReadDirAll TestDirOpen TestDirCreate TestDirMkdir TestDirMkdirSub TestDirRemove TestDirRemoveAll TestDirRemoveName TestDirRename TestDirFileOpen TestFileMethods TestFileSetModTime/cache=off,open=false,write=false TestFileSetModTime/cache=off,open=true,write=false TestFileSetModTime/cache=off,open=true,write=true TestFileSetModTime/cache=full,open=false,write=false TestFileSetModTime/cache=full,open=true,write=false TestFileSetModTime/cache=full,open=true,write=true TestFileOpenRead TestFileOpenWrite TestFileRemove TestFileRemoveAll TestFileOpen TestFileRename/off,forceCache=false TestFileRename/minimal,forceCache=false TestFileRename/minimal,forceCache=true TestFileRename/writes,forceCache=false TestFileRename/writes,forceCache=true TestFileRename/full,forceCache=false TestReadFileHandleMethods TestReadFileHandleSeek TestReadFileHandleReadAt TestReadFileHandleFlush TestReadFileHandleRelease TestRWFileHandleMethodsRead TestRWFileHandleSeek TestRWFileHandleReadAt TestRWFileHandleFlushRead TestRWFileHandleReleaseRead TestRWFileHandleMethodsWrite TestRWFileHandleWriteAt TestRWFileHandleSizeTruncateExisting TestRWFileHandleSizeCreateExisting TestRWFileModTimeWithOpenWriters TestUnicodeNormalization TestVFSStat TestVFSStatParent TestVFSOpenFile TestVFSMkdir TestVFSMkdirAll TestWriteFileHandleMethods TestWriteFileHandleWriteAt TestWriteFileHandleFlush TestWriteFileModTimeWithOpenWriters TestFileReadAtNonZeroLength]