"./operations.test -test.v -test.timeout 1h0m0s -remote TestShade: -verbose -test.run '^TestRmdirsNoLeaveRoot$'" - Starting (try 2/5)
2026/09/14 05:18:06 DEBUG : Creating backend with remote "TestShade:rclone-test-nejojiq9gopu"
2026/09/14 05:18:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/14 05:18:06 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
2026/09/14 05:18:07 DEBUG : Creating backend with remote "/tmp/rclone183236615"
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-nejojiq9gopu", Local "Local file system at /tmp/rclone183236615", Modify Window "876000h0m0s"
2026/09/14 05:18:08 DEBUG : A1/B1/C1/one: multipart upload: starting chunk 0 size 3 offset 0/3
2026/09/14 05:18:09 INFO  : A2: Making directory
2026/09/14 05:18:09 INFO  : A1/B2: Making directory
2026/09/14 05:18:09 INFO  : A1/B2/C2: Making directory
2026/09/14 05:18:10 INFO  : A1/B1/C3: Making directory
2026/09/14 05:18:10 INFO  : A3: Making directory
2026/09/14 05:18:10 INFO  : A3/B3: Making directory
2026/09/14 05:18:10 INFO  : A3/B3/C4: Making directory
2026/09/14 05:18:10 DEBUG : A1/two: multipart upload: starting chunk 0 size 3 offset 0/3
2026/09/14 05:18:12 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/14 05:18:12 DEBUG : pacer: Reducing sleep to 10ms
2026/09/14 05:18:12 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/14 05:18:13 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:13 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/14 05:18:13 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:13 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/14 05:18:13 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:13 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/14 05:18:13 DEBUG : pacer: Reducing sleep to 80ms
2026/09/14 05:18:13 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:13 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/14 05:18:13 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:13 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/14 05:18:13 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:13 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/14 05:18:13 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:13 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/14 05:18:14 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:14 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/14 05:18:15 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/14 05:18:17 DEBUG : pacer: Reducing sleep to 640ms
2026/09/14 05:18:19 DEBUG : pacer: Reducing sleep to 320ms
2026/09/14 05:18:19 DEBUG : pacer: Reducing sleep to 160ms
2026/09/14 05:18:20 DEBUG : pacer: Reducing sleep to 80ms
2026/09/14 05:18:20 DEBUG : removing 1 level 3 directories
2026/09/14 05:18:20 INFO  : A3/B3/C4: Removing directory
2026/09/14 05:18:20 DEBUG : pacer: Reducing sleep to 40ms
2026/09/14 05:18:20 DEBUG : pacer: Reducing sleep to 20ms
2026/09/14 05:18:20 DEBUG : pacer: Reducing sleep to 10ms
2026/09/14 05:18:20 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/14 05:18:20 DEBUG : pacer: Reducing sleep to 10ms
2026/09/14 05:18:21 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:21 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/14 05:18:21 DEBUG : pacer: Reducing sleep to 10ms
2026/09/14 05:18:21 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:21 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/14 05:18:21 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:21 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/14 05:18:21 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:21 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/14 05:18:21 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:21 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/14 05:18:21 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:21 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/14 05:18:21 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:21 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/14 05:18:22 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:22 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/14 05:18:22 DEBUG : pacer: low level retry 10/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:22 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/14 05:18:22 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-nejojiq9gopu: Error from List call: HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND"
2026/09/14 05:18:22 ERROR : A3/B3: error listing: directory not found
2026/09/14 05:18:24 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/14 05:18:26 DEBUG : pacer: Reducing sleep to 640ms
2026/09/14 05:18:28 DEBUG : pacer: Reducing sleep to 320ms
2026/09/14 05:18:28 DEBUG : pacer: Reducing sleep to 160ms
2026/09/14 05:18:28 DEBUG : pacer: Reducing sleep to 80ms
2026/09/14 05:18:29 DEBUG : pacer: Reducing sleep to 40ms
2026/09/14 05:18:29 DEBUG : pacer: Reducing sleep to 20ms
2026/09/14 05:18:29 DEBUG : pacer: Reducing sleep to 10ms
2026/09/14 05:18:29 DEBUG : removing 2 level 3 directories
2026/09/14 05:18:29 INFO  : A1/B2/C2: Removing directory
2026/09/14 05:18:29 INFO  : A1/B1/C3: Removing directory
2026/09/14 05:18:29 DEBUG : removing 2 level 2 directories
2026/09/14 05:18:29 INFO  : A3/B3: Removing directory
2026/09/14 05:18:29 INFO  : A1/B2: Removing directory
2026/09/14 05:18:29 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/14 05:18:29 DEBUG : pacer: Reducing sleep to 10ms
2026/09/14 05:18:29 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/14 05:18:30 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:30 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/14 05:18:30 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:30 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/14 05:18:30 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:30 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/14 05:18:30 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:30 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/14 05:18:30 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:30 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/14 05:18:31 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:31 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/14 05:18:31 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:31 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/14 05:18:32 DEBUG : pacer: low level retry 10/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:32 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/14 05:18:32 ERROR : A1/B2: Failed to rmdir: HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND"
2026/09/14 05:18:32 DEBUG : removing 2 level 1 directories
2026/09/14 05:18:32 INFO  : A3: Removing directory
2026/09/14 05:18:32 INFO  : A2: Removing directory
2026/09/14 05:18:35 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/14 05:18:40 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/14 05:18:43 DEBUG : pacer: Reducing sleep to 640ms
2026/09/14 05:18:44 DEBUG : pacer: Reducing sleep to 320ms
    operations_test.go:709: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:709
        	Error:      	Received unexpected error:
        	            	failed to remove directories: HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND"
        	Test:       	TestRmdirsNoLeaveRoot
2026/09/14 05:18:45 DEBUG : pacer: Reducing sleep to 160ms
2026/09/14 05:18:45 DEBUG : pacer: Reducing sleep to 80ms
2026/09/14 05:18:45 DEBUG : pacer: Reducing sleep to 40ms
2026/09/14 05:18:45 DEBUG : pacer: Reducing sleep to 20ms
2026/09/14 05:18:45 DEBUG : pacer: Reducing sleep to 10ms
2026/09/14 05:18:46 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/14 05:18:46 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:46 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/14 05:18:46 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:46 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/14 05:18:46 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:46 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/14 05:18:47 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:47 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/14 05:18:47 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:47 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/14 05:18:47 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:47 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/14 05:18:48 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:48 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/14 05:18:49 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:49 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/14 05:18:52 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/14 05:18:57 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/14 05:18:59 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:18:59 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/14 05:19:01 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:19:01 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/14 05:19:03 DEBUG : pacer: Reducing sleep to 2.56s
2026/09/14 05:19:08 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/14 05:19:11 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:19:11 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/14 05:19:12 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/14 05:19:15 DEBUG : pacer: Reducing sleep to 640ms
2026/09/14 05:19:16 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:19:16 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/14 05:19:17 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/14 05:19:17 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/14 05:19:18 DEBUG : pacer: Reducing sleep to 1.28s
--- FAIL: TestRmdirsNoLeaveRoot (71.30s)
FAIL
2026/09/14 05:19:20 DEBUG : pacer: Reducing sleep to 640ms
2026/09/14 05:19:20 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-nejojiq9gopu: Purge dir ""
2026/09/14 05:19:22 DEBUG : pacer: Reducing sleep to 320ms
2026/09/14 05:19:22 DEBUG : pacer: Reducing sleep to 160ms
"./operations.test -test.v -test.timeout 1h0m0s -remote TestShade: -verbose -test.run '^TestRmdirsNoLeaveRoot$'" - Finished ERROR in 1m16.02977307s (try 2/5): exit status 1: Failed [TestRmdirsNoLeaveRoot]