"./vfs.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestDirHandleReaddirnames$'" - Starting (try 2/5)
2025/12/17 02:32:59 DEBUG : Creating backend with remote "TestBox:rclone-test-takudeq4have"
2025/12/17 02:32:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/12/17 02:33:44 DEBUG : pacer: low level retry 1/10 (error Error "unavailable" (503): Service is temporarily unavailable)
2025/12/17 02:33:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/12/17 02:34:09 DEBUG : pacer: Reducing sleep to 15ms
2025/12/17 02:34:09 DEBUG : box root 'rclone-test-takudeq4have': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/12/17 02:34:16 DEBUG : pacer: Reducing sleep to 11.25ms
2025/12/17 02:34:16 DEBUG : Creating backend with remote "/tmp/rclone3794921802"
=== RUN   TestDirHandleReaddirnames
    run.go:185: Remote "box root 'rclone-test-takudeq4have'", Local "Local file system at /tmp/rclone3794921802", Modify Window "1s"
2025/12/17 02:34:17 DEBUG : pacer: Reducing sleep to 10ms
2025/12/17 02:34:24 DEBUG : box root 'rclone-test-takudeq4have': Ignoring "Shared folder — ForNick" - unknown type "web_link"
2025/12/17 02:35:17 DEBUG : box root 'rclone-test-takudeq4have': Checking for changes on remote (next_stream_position: "30401063423603957")
2025/12/17 02:35:17 DEBUG : box root 'rclone-test-takudeq4have': ["potato"(0)|file|ITEM_UPLOAD|2077319984871|902aa369f0eeebdbe92ae9a59b46ff6610fddfb2] new parent not found
2025/12/17 02:35:17 DEBUG : box root 'rclone-test-takudeq4have': ["sub dir"(0)|folder|ITEM_CREATE|356311050794|00b7d779573ca181fc80b8843d56f48911abb91b] new parent not found
2025/12/17 02:35:17 DEBUG : box root 'rclone-test-takudeq4have': ["hello world"(0)|file|ITEM_UPLOAD|2077321796983|42a8e0116b8e9fca7b89d05a753117846b064f56] new parent not found
2025/12/17 02:35:17 DEBUG : box root 'rclone-test-takudeq4have': ["rclone-test-takudeq4have"(0)|folder|ITEM_CREATE|356310287198|7ff5009a8e69d7df2038bf17921e5f824db7fab3] new parent not found
2025/12/17 02:35:17 DEBUG : box root 'rclone-test-takudeq4have': Received 4 events, resulting in 0 paths and 0 notifications
2025/12/17 02:35:17 DEBUG : box root 'rclone-test-takudeq4have': Checking for changes on remote (next_stream_position: "30401063423664215")
2025/12/17 02:36:09 DEBUG : WaitForWriters: timeout=30s
2025/12/17 02:36:09 DEBUG : dir: Looking for writers
2025/12/17 02:36:09 DEBUG : file1: reading active writers
2025/12/17 02:36:09 DEBUG : Looking for writers
2025/12/17 02:36:09 DEBUG : dir: reading active writers
2025/12/17 02:36:09 DEBUG : >WaitForWriters: 
2025/12/17 02:36:30 DEBUG : Couldn't decode error response: invalid character 'u' looking for beginning of value
2025/12/17 02:36:30 DEBUG : pacer: low level retry 1/10 (error Error "503 Service Unavailable" (503))
2025/12/17 02:36:30 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/12/17 02:36:51 DEBUG : pacer: Reducing sleep to 15ms
2025/12/17 02:37:20 DEBUG : pacer: low level retry 1/10 (error Error "unavailable" (503): Service is temporarily unavailable)
2025/12/17 02:37:20 DEBUG : pacer: Rate limited, increasing sleep to 30ms
2025/12/17 02:37:28 DEBUG : pacer: low level retry 2/10 (error Error "unavailable" (503): Service is temporarily unavailable)
2025/12/17 02:37:28 DEBUG : pacer: Rate limited, increasing sleep to 60ms
2025/12/17 02:37:42 DEBUG : pacer: Reducing sleep to 45ms
2025/12/17 02:37:43 DEBUG : pacer: Reducing sleep to 33.75ms
--- PASS: TestDirHandleReaddirnames (206.17s)
PASS
2025/12/17 02:37:43 DEBUG : box root 'rclone-test-takudeq4have': Purge remote
2025/12/17 02:39:03 DEBUG : pacer: Reducing sleep to 25.3125ms
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestDirHandleReaddirnames$'" - Finished OK in 6m3.846091298s (try 2/5)