"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCat|TestCheckSumDownload|TestParseSumFile)$'" - Starting (try 4/5) 2025/08/24 04:19:05 DEBUG : Creating backend with remote "TestPikPak:rclone-test-qudureh9mole" 2025/08/24 04:19:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/24 04:19:07 DEBUG : Creating backend with remote "/tmp/rclone2199092024" === RUN TestParseSumFile run.go:180: Remote "PikPak root 'rclone-test-qudureh9mole'", Local "Local file system at /tmp/rclone2199092024", Modify Window "876000h0m0s" 2025/08/24 04:19:08 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:19:08 DEBUG : test.sum: gcid = 7a6c4fea12fa7bf66997c8b984733019679b2f9f 2025/08/24 04:19:15 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:19:15 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/08/24 04:19:22 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:19:22 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/08/24 04:19:23 DEBUG : pacer: Reducing sleep to 300ms 2025/08/24 04:19:23 DEBUG : pacer: Reducing sleep to 225ms 2025/08/24 04:19:23 NOTICE: test.sum: improperly formatted checksum line 4 2025/08/24 04:19:23 NOTICE: test.sum: improperly formatted checksum line 5 2025/08/24 04:19:23 NOTICE: test.sum: improperly formatted checksum line 6 2025/08/24 04:19:23 NOTICE: test.sum: 2 warning(s) suppressed... 2025/08/24 04:19:24 DEBUG : pacer: Reducing sleep to 168.75ms 2025/08/24 04:19:24 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:19:24 DEBUG : test.sum: gcid = bc9d7ca23a449ced8d5f88b9712e89097981d783 2025/08/24 04:19:25 DEBUG : pacer: Reducing sleep to 126.5625ms 2025/08/24 04:19:25 DEBUG : pacer: Reducing sleep to 100ms 2025/08/24 04:19:33 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:19:33 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/08/24 04:19:39 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:19:39 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/08/24 04:19:40 DEBUG : pacer: Reducing sleep to 300ms 2025/08/24 04:19:41 DEBUG : pacer: Reducing sleep to 225ms 2025/08/24 04:19:41 NOTICE: test.sum: improperly formatted checksum line 4 2025/08/24 04:19:41 NOTICE: test.sum: improperly formatted checksum line 5 2025/08/24 04:19:41 NOTICE: test.sum: improperly formatted checksum line 6 2025/08/24 04:19:41 NOTICE: test.sum: 2 warning(s) suppressed... 2025/08/24 04:19:41 DEBUG : pacer: Reducing sleep to 168.75ms 2025/08/24 04:19:42 DEBUG : pacer: Reducing sleep to 126.5625ms 2025/08/24 04:19:42 DEBUG : pacer: Reducing sleep to 100ms --- PASS: TestParseSumFile (36.02s) === RUN TestCheckSumDownload run.go:180: Remote "PikPak root 'rclone-test-qudureh9mole'", Local "Local file system at /tmp/rclone2199092024", Modify Window "876000h0m0s" 2025/08/24 04:19:43 DEBUG : Creating backend with remote "TestPikPak:rclone-test-qudureh9mole/data" 2025/08/24 04:19:46 DEBUG : data/banana: calculating gcid: failed to unwrap object from src: data/banana 2025/08/24 04:19:46 DEBUG : data/banana: gcid = 5a20dd2421f633d21da85b8911f9f37677f212d5 2025/08/24 04:19:47 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:19:47 DEBUG : test.sum: gcid = a5968cd7981dde739b5ed764a5ed354ef42c42b2 === RUN TestCheckSumDownload/subtest1 2025/08/24 04:20:06 DEBUG : pacer: Reducing sleep to 100ms 2025/08/24 04:20:06 DEBUG : data/potato: calculating gcid: failed to unwrap object from src: data/potato 2025/08/24 04:20:06 DEBUG : data/potato: gcid = 7a3c15f717358c93392def5ff0639db84c0d363b 2025/08/24 04:20:08 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:20:08 DEBUG : test.sum: gcid = a5968cd7981dde739b5ed764a5ed354ef42c42b2 === RUN TestCheckSumDownload/subtest2 2025/08/24 04:20:34 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:20:34 DEBUG : test.sum: gcid = f3a0ff69df1baee079fa197e53142fe40231e82b === RUN TestCheckSumDownload/subtest3 2025/08/24 04:20:48 DEBUG : pacer: Reducing sleep to 100ms 2025/08/24 04:20:48 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:20:48 DEBUG : test.sum: gcid = 4432a75a15f9e866fc8b0361de0580e4ab920bea === RUN TestCheckSumDownload/subtest4 2025/08/24 04:20:55 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:20:55 DEBUG : test.sum: gcid = c2c6d29ce2f4e1f6e3e830802ef4a15c32af89c4 === RUN TestCheckSumDownload/subtest5 2025/08/24 04:21:41 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:21:41 DEBUG : test.sum: gcid = 903d92580da389428c3eace6c6dce692463e75e8 === RUN TestCheckSumDownload/subtest6 2025/08/24 04:22:22 DEBUG : data/banana: calculating gcid: failed to unwrap object from src: data/banana 2025/08/24 04:22:22 DEBUG : data/banana: gcid = 5a20dd2421f633d21da85b8911f9f37677f212d5 2025/08/24 04:22:25 DEBUG : data/potato: calculating gcid: failed to unwrap object from src: data/potato 2025/08/24 04:22:25 DEBUG : data/potato: gcid = 7a3c15f717358c93392def5ff0639db84c0d363b 2025/08/24 04:22:28 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/08/24 04:22:28 DEBUG : test.sum: gcid = 551b7ddca65803810ea668343a66646180a0c0b7 === RUN TestCheckSumDownload/subtest7 2025/08/24 04:23:16 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/08/24 04:23:16 DEBUG : pacer: Reducing sleep to 100ms 2025/08/24 04:23:20 DEBUG : data: Rmdir: contains trashed file: "potato" 2025/08/24 04:23:20 DEBUG : data: Rmdir: contains trashed file: "banana" 2025/08/24 04:23:20 DEBUG : data: Rmdir: contains trashed file: "potato" 2025/08/24 04:23:20 DEBUG : data: Rmdir: contains trashed file: "banana" --- PASS: TestCheckSumDownload (218.69s) --- PASS: TestCheckSumDownload/subtest1 (17.10s) --- PASS: TestCheckSumDownload/subtest2 (22.60s) --- PASS: TestCheckSumDownload/subtest3 (10.64s) --- PASS: TestCheckSumDownload/subtest4 (4.47s) --- PASS: TestCheckSumDownload/subtest5 (43.24s) --- PASS: TestCheckSumDownload/subtest6 (38.03s) --- PASS: TestCheckSumDownload/subtest7 (43.46s) === RUN TestCat run.go:180: Remote "PikPak root 'rclone-test-qudureh9mole'", Local "Local file system at /tmp/rclone2199092024", Modify Window "876000h0m0s" 2025/08/24 04:23:22 DEBUG : file1: calculating gcid: failed to unwrap object from src: file1 2025/08/24 04:23:22 DEBUG : file1: gcid = 4d8356ed1af6fd7e9537777328c6d176effe38a0 2025/08/24 04:23:23 DEBUG : file2: calculating gcid: failed to unwrap object from src: file2 2025/08/24 04:23:23 DEBUG : file2: gcid = 9e3614f5e363b3d1060c1e4d6738e7c26804ae41 2025/08/24 04:23:31 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:23:31 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/08/24 04:23:38 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:23:38 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/08/24 04:23:44 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:23:44 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/08/24 04:23:44 DEBUG : pacer: Reducing sleep to 600ms 2025/08/24 04:23:46 DEBUG : pacer: Reducing sleep to 450ms 2025/08/24 04:23:52 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:23:52 DEBUG : pacer: Rate limited, increasing sleep to 900ms 2025/08/24 04:23:59 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:23:59 DEBUG : pacer: Rate limited, increasing sleep to 1.8s 2025/08/24 04:24:05 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:24:05 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/08/24 04:24:06 DEBUG : pacer: Reducing sleep to 1.5s 2025/08/24 04:24:08 DEBUG : pacer: Reducing sleep to 1.125s 2025/08/24 04:24:09 DEBUG : pacer: Reducing sleep to 843.75ms 2025/08/24 04:24:10 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/08/24 04:24:11 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/08/24 04:24:18 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:24:18 DEBUG : pacer: Rate limited, increasing sleep to 949.21875ms 2025/08/24 04:24:24 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:24:24 DEBUG : pacer: Rate limited, increasing sleep to 1.8984375s 2025/08/24 04:24:30 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:24:30 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/08/24 04:24:36 DEBUG : pacer: low level retry 4/10 (error no link) 2025/08/24 04:24:43 DEBUG : pacer: low level retry 5/10 (error no link) 2025/08/24 04:24:43 DEBUG : pacer: Reducing sleep to 1.5s 2025/08/24 04:24:45 DEBUG : pacer: Reducing sleep to 1.125s 2025/08/24 04:24:46 DEBUG : pacer: Reducing sleep to 843.75ms 2025/08/24 04:24:54 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:24:54 DEBUG : pacer: Rate limited, increasing sleep to 1.6875s 2025/08/24 04:25:00 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:25:00 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/08/24 04:25:06 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:25:13 DEBUG : pacer: low level retry 4/10 (error no link) 2025/08/24 04:25:19 DEBUG : pacer: low level retry 5/10 (error no link) 2025/08/24 04:25:25 DEBUG : pacer: low level retry 6/10 (error no link) 2025/08/24 04:25:31 DEBUG : pacer: low level retry 7/10 (error no link) 2025/08/24 04:25:38 DEBUG : pacer: low level retry 8/10 (error no link) 2025/08/24 04:25:44 DEBUG : pacer: low level retry 9/10 (error no link) 2025/08/24 04:25:50 DEBUG : pacer: low level retry 10/10 (error no link) 2025/08/24 04:25:50 ERROR : file1: Failed to open: can't download: no link 2025/08/24 04:25:57 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:26:03 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:26:09 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:26:16 DEBUG : pacer: low level retry 4/10 (error no link) 2025/08/24 04:26:22 DEBUG : pacer: low level retry 5/10 (error no link) 2025/08/24 04:26:28 DEBUG : pacer: low level retry 6/10 (error no link) 2025/08/24 04:26:34 DEBUG : pacer: low level retry 7/10 (error no link) 2025/08/24 04:26:41 DEBUG : pacer: low level retry 8/10 (error no link) 2025/08/24 04:26:47 DEBUG : pacer: low level retry 9/10 (error no link) 2025/08/24 04:26:53 DEBUG : pacer: low level retry 10/10 (error no link) 2025/08/24 04:26:53 ERROR : file2: Failed to open: can't download: no link operations_test.go:566: Incorrect output from Cat(-3,-1,): "" 2025/08/24 04:26:54 DEBUG : pacer: Reducing sleep to 1.5s 2025/08/24 04:27:02 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:27:02 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/08/24 04:27:08 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:27:08 DEBUG : pacer: Reducing sleep to 1.5s 2025/08/24 04:27:11 DEBUG : pacer: Reducing sleep to 1.125s 2025/08/24 04:27:12 DEBUG : pacer: Reducing sleep to 843.75ms 2025/08/24 04:27:14 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/08/24 04:27:14 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/08/24 04:27:21 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:27:21 DEBUG : pacer: Rate limited, increasing sleep to 949.21875ms 2025/08/24 04:27:27 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:27:27 DEBUG : pacer: Rate limited, increasing sleep to 1.8984375s 2025/08/24 04:27:33 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:27:33 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/08/24 04:27:40 DEBUG : pacer: low level retry 4/10 (error no link) 2025/08/24 04:27:46 DEBUG : pacer: low level retry 5/10 (error no link) 2025/08/24 04:27:52 DEBUG : pacer: low level retry 6/10 (error no link) 2025/08/24 04:27:59 DEBUG : pacer: low level retry 7/10 (error no link) 2025/08/24 04:28:05 DEBUG : pacer: low level retry 8/10 (error no link) 2025/08/24 04:28:05 DEBUG : pacer: Reducing sleep to 1.5s 2025/08/24 04:28:08 DEBUG : pacer: Reducing sleep to 1.125s 2025/08/24 04:28:09 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/08/24 04:28:16 DEBUG : pacer: low level retry 1/10 (error no link) 2025/08/24 04:28:16 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/08/24 04:28:22 DEBUG : pacer: low level retry 2/10 (error no link) 2025/08/24 04:28:28 DEBUG : pacer: low level retry 3/10 (error no link) 2025/08/24 04:28:35 DEBUG : pacer: low level retry 4/10 (error no link) 2025/08/24 04:28:41 DEBUG : pacer: low level retry 5/10 (error no link) 2025/08/24 04:28:47 DEBUG : pacer: low level retry 6/10 (error no link) 2025/08/24 04:28:48 DEBUG : pacer: Reducing sleep to 1.5s 2025/08/24 04:28:50 DEBUG : pacer: Reducing sleep to 1.125s 2025/08/24 04:28:51 DEBUG : pacer: Reducing sleep to 843.75ms 2025/08/24 04:28:52 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/08/24 04:28:53 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/08/24 04:28:54 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/08/24 04:28:54 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/08/24 04:28:55 DEBUG : pacer: Reducing sleep to 200.225829ms --- FAIL: TestCat (333.38s) FAIL 2025/08/24 04:28:55 DEBUG : PikPak root 'rclone-test-qudureh9mole': Purge remote 2025/08/24 04:28:55 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/08/24 04:28:56 DEBUG : pacer: Reducing sleep to 112.627028ms "./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCat|TestCheckSumDownload|TestParseSumFile)$'" - Finished ERROR in 9m50.585855556s (try 4/5): exit status 1: Failed [TestCat]