"./sync.test -test.v -test.timeout 1h0m0s -remote TestShade: -verbose -test.run '^TestSyncWithExcludeAndDeleteExcluded$'" - Starting (try 4/5)
2026/09/23 03:41:54 DEBUG : Creating backend with remote "TestShade:rclone-test-yoranus0wayu"
2026/09/23 03:41:54 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/23 03:41:54 DEBUG : Creating new ShadeFS backend with drive: cf193d00-c783-4bab-aa7a-fd84b3700d27
2026/09/23 03:41:55 DEBUG : Creating backend with remote "/tmp/rclone881451806"
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:198: Remote "Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-yoranus0wayu", Local "Local file system at /tmp/rclone881451806", Modify Window "876000h0m0s"
2026/09/23 03:41:55 DEBUG : potato2: multipart upload: starting chunk 0 size 60 offset 0/60
2026/09/23 03:41:57 DEBUG : empty space: multipart upload: starting chunk 0 size 1 offset 0/1
2026/09/23 03:42:00 DEBUG : enormous: multipart upload: starting chunk 0 size 100 offset 0/100
2026/09/23 03:42:01 DEBUG : enormous: Excluded (Size Filter)
2026/09/23 03:42:01 DEBUG : enormous: Excluded
2026/09/23 03:42:01 DEBUG : potato2: Excluded (Size Filter)
2026/09/23 03:42:01 DEBUG : potato2: Excluded
2026/09/23 03:42:02 DEBUG : empty space: size = 1 OK
2026/09/23 03:42:02 DEBUG : empty space: Sizes identical
2026/09/23 03:42:02 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-yoranus0wayu: Waiting for checks to finish
2026/09/23 03:42:02 DEBUG : empty space: Unchanged skipping
2026/09/23 03:42:02 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-yoranus0wayu: Waiting for transfers to finish
2026/09/23 03:42:02 DEBUG : Waiting for deletions to finish
2026/09/23 03:42:02 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:02 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/23 03:42:02 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:02 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/23 03:42:02 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:02 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/23 03:42:02 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:02 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/23 03:42:02 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:02 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/23 03:42:02 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:02 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/23 03:42:02 DEBUG : pacer: Reducing sleep to 320ms
2026/09/23 03:42:02 INFO  : enormous: Deleted
2026/09/23 03:42:03 DEBUG : pacer: Reducing sleep to 160ms
2026/09/23 03:42:03 INFO  : potato2: Deleted
2026/09/23 03:42:03 INFO  : There was nothing to transfer
2026/09/23 03:42:03 DEBUG : pacer: Reducing sleep to 80ms
2026/09/23 03:42:03 DEBUG : pacer: Reducing sleep to 40ms
2026/09/23 03:42:03 DEBUG : empty space: size = 1 OK
2026/09/23 03:42:03 DEBUG : empty space: Sizes identical
2026/09/23 03:42:03 DEBUG : empty space: Unchanged skipping
2026/09/23 03:42:03 DEBUG : Local file system at /tmp/rclone881451806: Waiting for checks to finish
2026/09/23 03:42:03 DEBUG : Local file system at /tmp/rclone881451806: Waiting for transfers to finish
2026/09/23 03:42:03 DEBUG : Waiting for deletions to finish
2026/09/23 03:42:03 INFO  : potato2: Deleted
2026/09/23 03:42:03 INFO  : enormous: Deleted
2026/09/23 03:42:03 INFO  : There was nothing to transfer
2026/09/23 03:42:03 DEBUG : pacer: Reducing sleep to 20ms
2026/09/23 03:42:04 DEBUG : pacer: Reducing sleep to 10ms
2026/09/23 03:42:04 DEBUG : pacer: low level retry 1/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/09/23 03:42:04 DEBUG : pacer: low level retry 2/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:04 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/09/23 03:42:04 DEBUG : pacer: low level retry 3/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:04 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/09/23 03:42:04 DEBUG : pacer: low level retry 4/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:04 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/09/23 03:42:04 DEBUG : pacer: low level retry 5/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:04 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/09/23 03:42:05 DEBUG : pacer: low level retry 6/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:05 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/09/23 03:42:05 DEBUG : pacer: low level retry 7/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:05 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/09/23 03:42:06 DEBUG : pacer: low level retry 8/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:06 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/09/23 03:42:07 DEBUG : pacer: low level retry 9/10 (error HTTP error 500 (500 Internal Server Error) returned body: "ENTRY_NOT_FOUND")
2026/09/23 03:42:07 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/09/23 03:42:09 DEBUG : pacer: Reducing sleep to 2.56s
--- PASS: TestSyncWithExcludeAndDeleteExcluded (14.63s)
PASS
2026/09/23 03:42:14 DEBUG : pacer: Reducing sleep to 1.28s
2026/09/23 03:42:14 DEBUG : Shade drive cf193d00-c783-4bab-aa7a-fd84b3700d27 path rclone-test-yoranus0wayu: Purge dir ""
2026/09/23 03:42:17 DEBUG : pacer: Reducing sleep to 640ms
2026/09/23 03:42:18 DEBUG : pacer: Reducing sleep to 320ms
"./sync.test -test.v -test.timeout 1h0m0s -remote TestShade: -verbose -test.run '^TestSyncWithExcludeAndDeleteExcluded$'" - Finished OK in 23.876789836s (try 4/5)