"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCopyDirMetadata|TestCopyURLDownloadHeaders|TestDirMove|TestDirsEqual|TestGetFsInfo|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeShortEOF|TestRcatSizeUploadHeaders|TestRemoveExisting|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$|^TestRcatSizeChecksum$/^(Corrupted|IgnoreChecksum|NoHashes|SizeDiffers)$'" - Starting (try 2/5) 2026/09/07 03:24:00 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zegebug5jaca" 2026/09/07 03:24:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/07 03:24:00 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': Using cached web token 2026/09/07 03:24:01 DEBUG : Creating backend with remote "/tmp/rclone3130536824" === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:24:05 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (5.49s) === RUN TestMoveFile run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:24:07 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/07 03:24:11 DEBUG : sub/file2: size = 14 OK 2026/09/07 03:24:11 INFO : file1: Copied (new) to: sub/file2 2026/09/07 03:24:11 INFO : file1: Deleted 2026/09/07 03:24:11 DEBUG : sub/file2: size = 14 OK 2026/09/07 03:24:11 DEBUG : file1: Sizes identical 2026/09/07 03:24:11 DEBUG : file1: Unchanged skipping 2026/09/07 03:24:11 INFO : file1: Deleted 2026/09/07 03:24:12 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (8.83s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:24:16 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/07 03:24:18 DEBUG : file1: size = 14 OK 2026/09/07 03:24:18 INFO : file1: Copied (new) 2026/09/07 03:24:18 INFO : file1: Deleted 2026/09/07 03:24:18 DEBUG : file1: Destination exists, skipping 2026/09/07 03:24:18 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (4.63s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:24:23 NOTICE: hello: Skipped rename to HELLO as --dry-run is set --- PASS: TestCaseInsensitiveMoveFileDryRun (4.59s) === RUN TestMoveFileBackupDir run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1067: Skipping test as remote does not support server-side move or copy --- SKIP: TestMoveFileBackupDir (0.42s) === RUN TestDirMove run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:24:41 INFO : A1/B2: Making directory 2026/09/07 03:24:43 INFO : A1/B1/C3: Making directory 2026/09/07 03:24:58 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 2085h53m26.113478154s different from this computer 2026/09/07 03:25:02 DEBUG : A2/one: size = 3 OK 2026/09/07 03:25:02 INFO : A1/one: Copied (new) to: A2/one 2026/09/07 03:25:02 DEBUG : A2/two: size = 3 OK 2026/09/07 03:25:02 INFO : A1/two: Copied (new) to: A2/two 2026/09/07 03:25:03 DEBUG : A2/B1/C1/four: size = 4 OK 2026/09/07 03:25:03 INFO : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four 2026/09/07 03:25:03 DEBUG : A2/B1/three: size = 5 OK 2026/09/07 03:25:03 INFO : A1/B1/three: Copied (new) to: A2/B1/three 2026/09/07 03:25:03 INFO : A1/one: Deleted 2026/09/07 03:25:03 DEBUG : A2/B1/C2/five: size = 4 OK 2026/09/07 03:25:03 INFO : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five 2026/09/07 03:25:04 INFO : A1/two: Deleted 2026/09/07 03:25:04 INFO : A1/B1/C1/four: Deleted 2026/09/07 03:25:05 INFO : A1/B1/three: Deleted 2026/09/07 03:25:05 INFO : A1/B1/C2/five: Deleted 2026/09/07 03:25:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/07 03:25:30 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/07 03:25:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/07 03:25:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/09/07 03:25:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/07 03:25:30 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/09/07 03:25:31 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/07 03:25:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/09/07 03:25:33 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/09/07 03:25:35 DEBUG : pacer: Reducing sleep to 1.5s 2026/09/07 03:25:37 DEBUG : pacer: Reducing sleep to 1.125s 2026/09/07 03:25:38 DEBUG : pacer: Reducing sleep to 843.75ms 2026/09/07 03:25:40 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/09/07 03:25:41 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/09/07 03:25:42 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/09/07 03:25:43 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/09/07 03:25:43 DEBUG : A3/one: size = 3 OK 2026/09/07 03:25:43 INFO : A2/one: Copied (new) to: A3/one 2026/09/07 03:25:43 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/09/07 03:25:43 DEBUG : pacer: Reducing sleep to 200ms 2026/09/07 03:25:43 DEBUG : A3/two: size = 3 OK 2026/09/07 03:25:43 INFO : A2/two: Copied (new) to: A3/two 2026/09/07 03:25:43 DEBUG : A3/B1/three: size = 5 OK 2026/09/07 03:25:43 INFO : A2/B1/three: Copied (new) to: A3/B1/three 2026/09/07 03:25:44 DEBUG : A3/B1/C1/four: size = 4 OK 2026/09/07 03:25:44 INFO : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four 2026/09/07 03:25:44 DEBUG : A3/B1/C2/five: size = 4 OK 2026/09/07 03:25:44 INFO : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five 2026/09/07 03:25:45 INFO : A2/one: Deleted 2026/09/07 03:25:45 INFO : A2/two: Deleted 2026/09/07 03:25:45 INFO : A2/B1/three: Deleted 2026/09/07 03:25:46 INFO : A2/B1/C1/four: Deleted 2026/09/07 03:25:46 INFO : A2/B1/C2/five: Deleted 2026/09/07 03:25:56 INFO : Linkbox root 'rclone-test-zegebug5jaca': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/09/07 03:26:12 DEBUG : A4/one: size = 3 OK 2026/09/07 03:26:12 INFO : A3/one: Copied (new) to: A4/one 2026/09/07 03:26:13 DEBUG : A4/two: size = 3 OK 2026/09/07 03:26:13 INFO : A3/two: Copied (new) to: A4/two 2026/09/07 03:26:13 DEBUG : A4/B1/three: size = 5 OK 2026/09/07 03:26:13 INFO : A3/B1/three: Copied (new) to: A4/B1/three 2026/09/07 03:26:13 DEBUG : A4/B1/C2/five: size = 4 OK 2026/09/07 03:26:13 INFO : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five 2026/09/07 03:26:14 DEBUG : A4/B1/C1/four: size = 4 OK 2026/09/07 03:26:14 INFO : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four 2026/09/07 03:26:14 INFO : A3/one: Deleted 2026/09/07 03:26:14 INFO : A3/two: Deleted 2026/09/07 03:26:15 INFO : A3/B1/three: Deleted 2026/09/07 03:26:15 INFO : A3/B1/C2/five: Deleted 2026/09/07 03:26:15 INFO : A3/B1/C1/four: Deleted --- PASS: TestDirMove (138.10s) === RUN TestGetFsInfo run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:26:43 DEBUG : Config file has changed externally - reloading --- PASS: TestGetFsInfo (0.41s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:26:44 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': File to upload is small (34 bytes), uploading instead of streaming 2026/09/07 03:26:46 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/09/07 03:26:46 NOTICE: Linkbox root 'rclone-test-zegebug5jaca': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/09/07 03:26:46 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/07 03:26:46 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/09/07 03:26:48 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/09/07 03:26:48 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:26:52 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': File to upload is small (34 bytes), uploading instead of streaming 2026/09/07 03:26:54 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/09/07 03:26:54 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/07 03:26:54 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/09/07 03:26:56 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/09/07 03:26:56 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:26:59 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': File to upload is small (34 bytes), uploading instead of streaming 2026/09/07 03:27:01 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/07 03:27:01 DEBUG : ignore_checksum_small_file_from_pipe: Sizes identical 2026/09/07 03:27:01 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/09/07 03:27:03 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/07 03:27:03 DEBUG : ignore_checksum_big_file_from_pipe: Sizes identical === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:27:07 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': File to upload is small (34 bytes), uploading instead of streaming 2026/09/07 03:27:09 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/07 03:27:09 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/07 03:27:09 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/09/07 03:27:11 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/07 03:27:11 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (30.14s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (7.67s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (7.51s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (7.49s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (7.47s) === RUN TestRcatMetadata run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1567: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.41s) === RUN TestRcatSize run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:27:17 DEBUG : potato1: size = 60 OK 2026/09/07 03:27:17 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': File to upload is small (60 bytes), uploading instead of streaming 2026/09/07 03:27:19 DEBUG : potato2: size = 60 OK 2026/09/07 03:27:19 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (7.40s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:27:23 DEBUG : pacer: low level retry 1/1 (error Put "https://upload-api.linkbox.to/tmp/recycle/1d/user/922460207/d6548b156ea68a4e003e786df99eee76_120?AWSAccessKeyId=ZAUGMKBTPLNX0FBOCABG&Expires=1788758842&Signature=BvySN8AkZn7zYY2vYX1CQcnbwLw%3D": stream error: stream ID 1; INTERNAL_ERROR; received from peer) 2026/09/07 03:27:23 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/09/07 03:27:23 ERROR : potato1: Post request put error: update err in uploading file: Put "https://upload-api.linkbox.to/tmp/recycle/1d/user/922460207/d6548b156ea68a4e003e786df99eee76_120?AWSAccessKeyId=ZAUGMKBTPLNX0FBOCABG&Expires=1788758842&Signature=BvySN8AkZn7zYY2vYX1CQcnbwLw%3D": stream error: stream ID 1; INTERNAL_ERROR; received from peer 2026/09/07 03:27:23 DEBUG : pacer: Reducing sleep to 300ms 2026/09/07 03:27:23 DEBUG : pacer: Reducing sleep to 225ms --- PASS: TestRcatSizeShortEOF (1.69s) === RUN TestRcatSizeMetadata run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1660: Skipping as destination doesn't support user metadata 2026/09/07 03:27:24 DEBUG : pacer: Reducing sleep to 200ms --- SKIP: TestRcatSizeMetadata (0.53s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:27:26 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (3.74s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1797: Skipping as destination doesn't support hashes === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:27:30 DEBUG : potato4: size = 60 (Linkbox root 'rclone-test-zegebug5jaca') 2026/09/07 03:27:30 DEBUG : potato4: size = 59 (Linkbox root 'rclone-test-zegebug5jaca') 2026/09/07 03:27:30 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Linkbox root 'rclone-test-zegebug5jaca') 59 2026/09/07 03:27:30 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:27:34 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" 2026/09/07 03:27:38 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (11.96s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.41s) --- PASS: TestRcatSizeChecksum/SizeDiffers (4.02s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (3.73s) --- PASS: TestRcatSizeChecksum/NoHashes (3.80s) === RUN TestTouchDir run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1842: Skipping test as remote does not support modtime --- SKIP: TestTouchDir (0.41s) === RUN TestMkdirMetadata run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1880: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.41s) === RUN TestMkdirModTime run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1901: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.43s) === RUN TestCopyDirMetadata run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1945: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.41s) === RUN TestSetDirModTime run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:1983: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.42s) === RUN TestDirsEqual run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:2034: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.41s) === RUN TestRemoveExisting run.go:198: Remote "Linkbox root 'rclone-test-zegebug5jaca'", Local "Local file system at /tmp/rclone3130536824", Modify Window "876000h0m0s" operations_test.go:2103: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.51s) PASS 2026/09/07 03:27:43 DEBUG : Linkbox root 'rclone-test-zegebug5jaca': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCopyDirMetadata|TestCopyURLDownloadHeaders|TestDirMove|TestDirsEqual|TestGetFsInfo|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeShortEOF|TestRcatSizeUploadHeaders|TestRemoveExisting|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$|^TestRcatSizeChecksum$/^(Corrupted|IgnoreChecksum|NoHashes|SizeDiffers)$'" - Finished OK in 3m43.782464055s (try 2/5)