"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCat$'" - Starting (try 5/5)
2025/08/24 04:29:40 DEBUG : Creating backend with remote "TestPikPak:rclone-test-lugehuy8jovi"
2025/08/24 04:29:40 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/24 04:29:41 DEBUG : Creating backend with remote "/tmp/rclone2016127015"
=== RUN   TestCat
    run.go:180: Remote "PikPak root 'rclone-test-lugehuy8jovi'", Local "Local file system at /tmp/rclone2016127015", Modify Window "876000h0m0s"
2025/08/24 04:29:42 DEBUG : file1: calculating gcid: failed to unwrap object from src: file1
2025/08/24 04:29:42 DEBUG : file1: gcid = 4d8356ed1af6fd7e9537777328c6d176effe38a0
2025/08/24 04:29:43 DEBUG : file2: calculating gcid: failed to unwrap object from src: file2
2025/08/24 04:29:43 DEBUG : file2: gcid = 9e3614f5e363b3d1060c1e4d6738e7c26804ae41
2025/08/24 04:29:52 DEBUG : pacer: low level retry 1/10 (error no link)
2025/08/24 04:29:52 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/08/24 04:29:58 DEBUG : pacer: low level retry 2/10 (error no link)
2025/08/24 04:29:58 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/08/24 04:30:04 DEBUG : pacer: low level retry 3/10 (error no link)
2025/08/24 04:30:04 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/08/24 04:30:05 DEBUG : pacer: Reducing sleep to 600ms
2025/08/24 04:30:06 DEBUG : pacer: Reducing sleep to 450ms
2025/08/24 04:30:12 DEBUG : pacer: low level retry 1/10 (error no link)
2025/08/24 04:30:12 DEBUG : pacer: Rate limited, increasing sleep to 900ms
2025/08/24 04:30:13 DEBUG : pacer: Reducing sleep to 675ms
2025/08/24 04:30:14 DEBUG : pacer: Reducing sleep to 506.25ms
2025/08/24 04:30:15 DEBUG : pacer: Reducing sleep to 379.6875ms
2025/08/24 04:30:16 DEBUG : pacer: Reducing sleep to 284.765625ms
2025/08/24 04:30:17 DEBUG : pacer: Reducing sleep to 213.574218ms
2025/08/24 04:30:23 DEBUG : pacer: low level retry 1/10 (error no link)
2025/08/24 04:30:23 DEBUG : pacer: Rate limited, increasing sleep to 427.148436ms
2025/08/24 04:30:24 DEBUG : pacer: Reducing sleep to 320.361327ms
2025/08/24 04:30:25 DEBUG : pacer: Reducing sleep to 240.270995ms
2025/08/24 04:30:25 DEBUG : pacer: Reducing sleep to 180.203246ms
2025/08/24 04:30:26 DEBUG : pacer: Reducing sleep to 135.152434ms
2025/08/24 04:30:26 DEBUG : pacer: Reducing sleep to 101.364325ms
2025/08/24 04:30:33 DEBUG : pacer: low level retry 1/10 (error no link)
2025/08/24 04:30:33 DEBUG : pacer: Rate limited, increasing sleep to 202.72865ms
2025/08/24 04:30:33 DEBUG : pacer: Reducing sleep to 152.046487ms
2025/08/24 04:30:34 DEBUG : pacer: Reducing sleep to 114.034865ms
2025/08/24 04:30:34 DEBUG : pacer: Reducing sleep to 100ms
2025/08/24 04:30:41 DEBUG : pacer: low level retry 1/10 (error no link)
2025/08/24 04:30:41 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/08/24 04:30:47 DEBUG : pacer: low level retry 2/10 (error no link)
2025/08/24 04:30:47 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/08/24 04:30:54 DEBUG : pacer: low level retry 3/10 (error no link)
2025/08/24 04:30:54 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/08/24 04:31:00 DEBUG : pacer: low level retry 4/10 (error no link)
2025/08/24 04:31:00 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/08/24 04:31:06 DEBUG : pacer: low level retry 5/10 (error no link)
2025/08/24 04:31:06 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/08/24 04:31:13 DEBUG : pacer: low level retry 6/10 (error no link)
2025/08/24 04:31:19 DEBUG : pacer: low level retry 7/10 (error no link)
2025/08/24 04:31:25 DEBUG : pacer: low level retry 8/10 (error no link)
2025/08/24 04:31:32 DEBUG : pacer: low level retry 9/10 (error no link)
2025/08/24 04:31:38 DEBUG : pacer: low level retry 10/10 (error no link)
2025/08/24 04:31:38 ERROR : file2: Failed to open: can't download: no link
    operations_test.go:566: Incorrect output from Cat(1,3,): "BCD"
2025/08/24 04:31:38 DEBUG : pacer: Reducing sleep to 1.5s
2025/08/24 04:31:46 DEBUG : pacer: low level retry 1/10 (error no link)
2025/08/24 04:31:46 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/08/24 04:31:47 DEBUG : pacer: Reducing sleep to 1.5s
2025/08/24 04:31:49 DEBUG : pacer: Reducing sleep to 1.125s
2025/08/24 04:31:56 DEBUG : pacer: low level retry 1/10 (error no link)
2025/08/24 04:31:56 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/08/24 04:32:02 DEBUG : pacer: low level retry 2/10 (error no link)
2025/08/24 04:32:09 DEBUG : pacer: low level retry 3/10 (error no link)
2025/08/24 04:32:15 DEBUG : pacer: low level retry 4/10 (error no link)
2025/08/24 04:32:21 DEBUG : pacer: low level retry 5/10 (error no link)
2025/08/24 04:32:28 DEBUG : pacer: low level retry 6/10 (error no link)
2025/08/24 04:32:34 DEBUG : pacer: low level retry 7/10 (error no link)
2025/08/24 04:32:40 DEBUG : pacer: low level retry 8/10 (error no link)
2025/08/24 04:32:47 DEBUG : pacer: low level retry 9/10 (error no link)
2025/08/24 04:32:47 DEBUG : pacer: Reducing sleep to 1.5s
2025/08/24 04:32:50 DEBUG : pacer: Reducing sleep to 1.125s
2025/08/24 04:32:50 DEBUG : pacer: Reducing sleep to 843.75ms
2025/08/24 04:32:51 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/08/24 04:32:52 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/08/24 04:32:53 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/08/24 04:32:54 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/08/24 04:32:54 DEBUG : pacer: Reducing sleep to 200.225829ms
--- FAIL: TestCat (192.58s)
FAIL
2025/08/24 04:32:54 DEBUG : PikPak root 'rclone-test-lugehuy8jovi': Purge remote
2025/08/24 04:32:54 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/08/24 04:32:55 DEBUG : pacer: Reducing sleep to 112.627028ms
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCat$'" - Finished ERROR in 3m15.280645085s (try 5/5): exit status 1: Failed [TestCat]