"go test -v -timeout 1h0m0s -remote TestCache: -verbose -test.run '^TestInternalCachedUpdatedContentMatches$'" - Starting (try 2/5)
2026/04/02 01:50:38 NOTICE: Running with the following params: 
 remote: TestInternalCache
2026/04/02 01:50:38 NOTICE: Temp Upload Dir: /tmp/rclonecache-tmp1985043953
=== RUN   TestInternalCachedUpdatedContentMatches
2026/04/02 01:50:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/04/02 01:50:38 NOTICE: WARNING: Cache backend is deprecated and may be removed in future. Please use VFS instead.
2026/04/02 01:50:38 DEBUG : Creating backend with remote "TestInternalCache-local:/tmp/TestInternalCache-local/ticucm1775094638"
2026/04/02 01:50:38 DEBUG : TestInternalCache: wrapped TestInternalCache-local:/tmp/TestInternalCache-local/ticucm1775094638 at root ticucm1775094638
2026/04/02 01:50:38 INFO  : TestInternalCache: Cache DB path: /home/rclone/.cache/rclone/cache-backend/TestInternalCache.db
2026/04/02 01:50:38 INFO  : TestInternalCache: Cache chunk path: /home/rclone/.cache/rclone/cache-backend/TestInternalCache
2026/04/02 01:50:38 INFO  : TestInternalCache: Chunk Memory: true
2026/04/02 01:50:38 INFO  : TestInternalCache: Chunk Size: 5Mi
2026/04/02 01:50:38 INFO  : TestInternalCache: Chunk Total Size: 10Gi
2026/04/02 01:50:38 INFO  : TestInternalCache: Chunk Clean Interval: 1m0s
2026/04/02 01:50:38 INFO  : TestInternalCache: Workers: 4
2026/04/02 01:50:38 INFO  : TestInternalCache: File Age: 6h0m0s
2026/04/02 01:50:38 DEBUG : Waiting for deletions to finish
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: list ''
2026/04/02 01:50:38 DEBUG : list: error: couldn't open bucket (ticucm1775094638)
2026/04/02 01:50:38 ERROR : error listing: directory not found
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: list ''
2026/04/02 01:50:38 DEBUG : list: error: couldn't open bucket (ticucm1775094638)
2026/04/02 01:50:38 ERROR : Cache remote TestInternalCache:ticucm1775094638: Failed to list "": directory not found
2026/04/02 01:50:38 DEBUG : removing 1 level 0 directories
2026/04/02 01:50:38 INFO  : Cache remote TestInternalCache:ticucm1775094638: Removing directory
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: rmdir ''
2026/04/02 01:50:38 ERROR : Failed to rmdir: stat /tmp/TestInternalCache-local/ticucm1775094638: no such file or directory
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: mkdir ''
2026/04/02 01:50:38 DEBUG : mkdir: created dir in source fs
2026/04/02 01:50:38 DEBUG : mkdir: added to cache
2026/04/02 01:50:38 DEBUG : cache: expired ticucm1775094638
2026/04/02 01:50:38 INFO  : mkdir: cache expired
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: put data at 'data.bin'
2026/04/02 01:50:38 DEBUG : data.bin: put: uploaded to remote fs
2026/04/02 01:50:38 DEBUG : data.bin: put: added to cache
2026/04/02 01:50:38 DEBUG : cache: expired ticucm1775094638
2026/04/02 01:50:38 INFO  : put: cache expired
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: new object 'data.bin'
2026/04/02 01:50:38 DEBUG : data.bin: find: warm object: data.bin, expiring on: 2026-04-02 07:50:38.126163306 +0000 UTC
2026/04/02 01:50:38 DEBUG : data.bin: updating object contents with size 200
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: new object 'data.bin'
2026/04/02 01:50:38 DEBUG : data.bin: find: warm object: data.bin, expiring on: 2026-04-02 07:50:38.131763261 +0000 UTC
2026/04/02 01:50:38 DEBUG : ticucm1775094638/data.bin: moving offset set from 0 to 0
2026/04/02 01:50:38 DEBUG : worker-1 <data.bin>: partial downloaded chunk 0
2026/04/02 01:50:38 DEBUG : ticucm1775094638/data.bin: 0: chunk retry storage: 0
2026/04/02 01:50:38 DEBUG : ticucm1775094638/data.bin: cache reader closed 200
2026/04/02 01:50:38 DEBUG : Waiting for deletions to finish
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: list ''
2026/04/02 01:50:38 DEBUG : list: cold listing: 2026-04-01 19:50:38.128276043 +0000 UTC
2026/04/02 01:50:38 DEBUG : list: read 1 from source
2026/04/02 01:50:38 DEBUG : list: source entries: [data.bin]
2026/04/02 01:50:38 DEBUG : list: cached object: data.bin
2026/04/02 01:50:38 DEBUG : list: cached directories: 0
2026/04/02 01:50:38 DEBUG : list: cached dir: 'ticucm1775094638', cache ts: 2026-04-02 01:50:38.638397081 +0000 UTC m=+0.543161731
2026/04/02 01:50:38 DEBUG : data.bin: removing object
2026/04/02 01:50:38 DEBUG : cache: expired ticucm1775094638
2026/04/02 01:50:38 INFO  : data.bin: Deleted
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: list ''
2026/04/02 01:50:38 DEBUG : list: cold listing: 2026-04-01 19:50:38.641219485 +0000 UTC
2026/04/02 01:50:38 DEBUG : list: read 0 from source
2026/04/02 01:50:38 DEBUG : list: source entries: []
2026/04/02 01:50:38 DEBUG : list: cached directories: 0
2026/04/02 01:50:38 DEBUG : list: cached dir: 'ticucm1775094638', cache ts: 2026-04-02 01:50:38.642227667 +0000 UTC m=+0.546992317
2026/04/02 01:50:38 DEBUG : removing 1 level 0 directories
2026/04/02 01:50:38 INFO  : Cache remote TestInternalCache:ticucm1775094638: Removing directory
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: rmdir ''
2026/04/02 01:50:38 DEBUG : rmdir: removed dir in source fs
2026/04/02 01:50:38 DEBUG : rmdir: removed from cache
2026/04/02 01:50:38 INFO  : rmdir: cache expired
2026/04/02 01:50:38 DEBUG : Cache remote TestInternalCache:ticucm1775094638: Services stopped
--- PASS: TestInternalCachedUpdatedContentMatches (0.54s)
PASS
ok  	github.com/rclone/rclone/backend/cache	0.559s
"go test -v -timeout 1h0m0s -remote TestCache: -verbose -test.run '^TestInternalCachedUpdatedContentMatches$'" - Finished OK in 1.664781388s (try 2/5)