"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCopyDirMetadata|TestCopyURLDownloadHeaders|TestDirMove|TestDirsEqual|TestGetFsInfo|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestParseSumFile|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/08/31 03:00:37 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yihopiz3hivi"
2026/08/31 03:00:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/31 03:00:37 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': Using cached web token
2026/08/31 03:00:38 DEBUG : Creating backend with remote "/tmp/rclone3155512527"
=== RUN   TestParseSumFile
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:00:42 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/08/31 03:00:42 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
2026/08/31 03:00:43 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10)
2026/08/31 03:00:43 DEBUG : test.sum: Trying to read object after upload: try again in 800ms (4/10)
2026/08/31 03:00:44 DEBUG : test.sum: Trying to read object after upload: try again in 1.6s (5/10)
2026/08/31 03:00:46 DEBUG : test.sum: Trying to read object after upload: try again in 3.2s (6/10)
2026/08/31 03:00:50 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1871h50m14.231433805s different from this computer
2026/08/31 03:00:50 NOTICE: test.sum: improperly formatted checksum line 4
2026/08/31 03:00:50 NOTICE: test.sum: improperly formatted checksum line 5
2026/08/31 03:00:50 NOTICE: test.sum: improperly formatted checksum line 6
2026/08/31 03:00:50 NOTICE: test.sum: 2 warning(s) suppressed...
2026/08/31 03:00:50 DEBUG : test.sum: Update: removing old file
2026/08/31 03:00:53 NOTICE: test.sum: improperly formatted checksum line 4
2026/08/31 03:00:53 NOTICE: test.sum: improperly formatted checksum line 5
2026/08/31 03:00:53 NOTICE: test.sum: improperly formatted checksum line 6
2026/08/31 03:00:53 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (17.25s)
=== RUN   TestCopyURLDownloadHeaders
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:00:57 DEBUG : file1: size = 14 OK
--- PASS: TestCopyURLDownloadHeaders (3.72s)
=== RUN   TestMoveFile
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:00:59 DEBUG : file1: Need to transfer - File not found at Destination
2026/08/31 03:01:03 DEBUG : sub/file2: size = 14 OK
2026/08/31 03:01:03 INFO  : file1: Copied (new) to: sub/file2
2026/08/31 03:01:03 INFO  : file1: Deleted
2026/08/31 03:01:04 DEBUG : sub/file2: size = 14 OK
2026/08/31 03:01:04 DEBUG : file1: Sizes identical
2026/08/31 03:01:04 DEBUG : file1: Unchanged skipping
2026/08/31 03:01:04 INFO  : file1: Deleted
2026/08/31 03:01:04 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (9.27s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:01:08 DEBUG : file1: Need to transfer - File not found at Destination
2026/08/31 03:01:10 DEBUG : file1: size = 14 OK
2026/08/31 03:01:10 INFO  : file1: Copied (new)
2026/08/31 03:01:10 INFO  : file1: Deleted
2026/08/31 03:01:11 DEBUG : file1: Destination exists, skipping
2026/08/31 03:01:11 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (4.51s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:01:15 NOTICE: hello: Skipped rename to HELLO as --dry-run is set
--- PASS: TestCaseInsensitiveMoveFileDryRun (4.55s)
=== RUN   TestMoveFileBackupDir
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1064: Skipping test as remote does not support server-side move or copy
--- SKIP: TestMoveFileBackupDir (0.40s)
=== RUN   TestDirMove
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:01:34 INFO  : A1/B2: Making directory
2026/08/31 03:01:35 INFO  : A1/B1/C3: Making directory
2026/08/31 03:01:52 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:01:52 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/31 03:01:52 DEBUG : pacer: Reducing sleep to 300ms
2026/08/31 03:01:52 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:01:52 DEBUG : pacer: Rate limited, increasing sleep to 600ms
2026/08/31 03:01:52 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:01:52 DEBUG : pacer: Rate limited, increasing sleep to 1.2s
2026/08/31 03:01:53 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:01:53 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/31 03:01:54 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:01:58 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/31 03:01:58 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/31 03:02:00 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/31 03:02:01 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/31 03:02:02 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/31 03:02:03 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/31 03:02:03 DEBUG : A2/one: size = 3 OK
2026/08/31 03:02:03 INFO  : A1/one: Copied (new) to: A2/one
2026/08/31 03:02:04 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/31 03:02:05 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/31 03:02:05 DEBUG : pacer: Reducing sleep to 200ms
2026/08/31 03:02:05 DEBUG : A2/B1/C2/five: size = 4 OK
2026/08/31 03:02:05 INFO  : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five
2026/08/31 03:02:05 DEBUG : A2/two: size = 3 OK
2026/08/31 03:02:05 INFO  : A1/two: Copied (new) to: A2/two
2026/08/31 03:02:05 DEBUG : A2/B1/three: size = 5 OK
2026/08/31 03:02:05 INFO  : A1/B1/three: Copied (new) to: A2/B1/three
2026/08/31 03:02:06 INFO  : A1/one: Deleted
2026/08/31 03:02:06 DEBUG : A2/B1/C1/four: size = 4 OK
2026/08/31 03:02:06 INFO  : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four
2026/08/31 03:02:06 INFO  : A1/B1/C2/five: Deleted
2026/08/31 03:02:07 INFO  : A1/two: Deleted
2026/08/31 03:02:07 INFO  : A1/B1/three: Deleted
2026/08/31 03:02:07 INFO  : A1/B1/C1/four: Deleted
2026/08/31 03:02:34 DEBUG : A3/one: size = 3 OK
2026/08/31 03:02:34 INFO  : A2/one: Copied (new) to: A3/one
2026/08/31 03:02:34 DEBUG : A3/two: size = 3 OK
2026/08/31 03:02:34 INFO  : A2/two: Copied (new) to: A3/two
2026/08/31 03:02:34 DEBUG : A3/B1/C1/four: size = 4 OK
2026/08/31 03:02:34 INFO  : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four
2026/08/31 03:02:35 DEBUG : A3/B1/C2/five: size = 4 OK
2026/08/31 03:02:35 INFO  : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five
2026/08/31 03:02:35 DEBUG : A3/B1/three: size = 5 OK
2026/08/31 03:02:35 INFO  : A2/B1/three: Copied (new) to: A3/B1/three
2026/08/31 03:02:35 INFO  : A2/one: Deleted
2026/08/31 03:02:36 INFO  : A2/two: Deleted
2026/08/31 03:02:36 INFO  : A2/B1/C1/four: Deleted
2026/08/31 03:02:36 INFO  : A2/B1/C2/five: Deleted
2026/08/31 03:02:37 INFO  : A2/B1/three: Deleted
2026/08/31 03:02:47 INFO  : Linkbox root 'rclone-test-yihopiz3hivi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/08/31 03:02:55 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:02:55 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/31 03:02:55 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:02:55 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/31 03:02:55 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:02:55 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/31 03:02:56 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:02:56 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/31 03:02:59 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/31 03:03:00 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/31 03:03:03 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/31 03:03:03 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/31 03:03:04 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/31 03:03:04 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/31 03:03:05 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/31 03:03:05 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/31 03:03:05 DEBUG : pacer: Reducing sleep to 200ms
2026/08/31 03:03:10 DEBUG : A4/two: size = 3 OK
2026/08/31 03:03:10 INFO  : A3/two: Copied (new) to: A4/two
2026/08/31 03:03:10 DEBUG : A4/one: size = 3 OK
2026/08/31 03:03:10 INFO  : A3/one: Copied (new) to: A4/one
2026/08/31 03:03:10 DEBUG : A4/B1/three: size = 5 OK
2026/08/31 03:03:10 INFO  : A3/B1/three: Copied (new) to: A4/B1/three
2026/08/31 03:03:11 DEBUG : A4/B1/C1/four: size = 4 OK
2026/08/31 03:03:11 INFO  : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four
2026/08/31 03:03:11 INFO  : A3/two: Deleted
2026/08/31 03:03:11 DEBUG : A4/B1/C2/five: size = 4 OK
2026/08/31 03:03:11 INFO  : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five
2026/08/31 03:03:12 INFO  : A3/one: Deleted
2026/08/31 03:03:12 INFO  : A3/B1/three: Deleted
2026/08/31 03:03:12 INFO  : A3/B1/C1/four: Deleted
2026/08/31 03:03:13 INFO  : A3/B1/C2/five: Deleted
2026/08/31 03:03:31 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:31 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/31 03:03:31 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:31 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/31 03:03:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:32 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/31 03:03:33 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:33 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/31 03:03:34 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:36 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:38 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:40 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:42 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:44 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
    run.go:186: removing dir "A4/B2" failed - try 1/3: sleeping 1s: getting files failed: invalid character '<' looking for beginning of value
2026/08/31 03:03:46 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:48 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:50 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:52 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:54 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:56 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:03:58 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/31 03:04:02 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/31 03:04:02 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/31 03:04:04 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/31 03:04:04 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/31 03:04:06 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/31 03:04:06 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/31 03:04:08 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/31 03:04:08 DEBUG : pacer: Reducing sleep to 200ms
--- PASS: TestDirMove (173.29s)
=== RUN   TestGetFsInfo
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
--- PASS: TestGetFsInfo (0.41s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:04:12 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': File to upload is small (34 bytes), uploading instead of streaming
2026/08/31 03:04:14 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/08/31 03:04:14 NOTICE: Linkbox root 'rclone-test-yihopiz3hivi': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2026/08/31 03:04:14 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/08/31 03:04:14 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/08/31 03:04:16 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/08/31 03:04:16 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-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:04:19 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': File to upload is small (34 bytes), uploading instead of streaming
2026/08/31 03:04:21 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/08/31 03:04:21 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/08/31 03:04:21 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/08/31 03:04:23 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/08/31 03:04:23 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-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:04:26 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': File to upload is small (34 bytes), uploading instead of streaming
2026/08/31 03:04:28 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/08/31 03:04:28 DEBUG : ignore_checksum_small_file_from_pipe: Sizes identical
2026/08/31 03:04:28 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/08/31 03:04:30 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/08/31 03:04:30 DEBUG : ignore_checksum_big_file_from_pipe: Sizes identical
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:04:34 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': File to upload is small (34 bytes), uploading instead of streaming
2026/08/31 03:04:34 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:34 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/31 03:04:34 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:34 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/31 03:04:35 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:35 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/31 03:04:36 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:36 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/31 03:04:37 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:39 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:41 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:43 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:45 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:47 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:47 DEBUG : ignore_checksum_small_file_from_pipe: Received error: Update err in unmarshaling response: invalid character '<' looking for beginning of value - low level retry 1/10
2026/08/31 03:04:49 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:51 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:53 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:55 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:57 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:04:59 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/31 03:05:02 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/31 03:05:04 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/31 03:05:04 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/31 03:05:04 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/08/31 03:05:04 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/08/31 03:05:04 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': Target remote doesn't support streaming uploads, creating temporary local FS to spool file
2026/08/31 03:05:05 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/31 03:05:06 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/31 03:05:07 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/31 03:05:07 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/31 03:05:07 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/08/31 03:05:07 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
2026/08/31 03:05:08 DEBUG : pacer: Reducing sleep to 200ms
--- PASS: TestRcat (59.43s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (7.41s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (7.46s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (7.43s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (37.14s)
=== RUN   TestRcatMetadata
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1564: Skipping as destination doesn't support user metadata
--- SKIP: TestRcatMetadata (0.43s)
=== RUN   TestRcatSize
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:05:13 DEBUG : potato1: size = 60 OK
2026/08/31 03:05:13 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': File to upload is small (60 bytes), uploading instead of streaming
2026/08/31 03:05:15 DEBUG : potato2: size = 60 OK
2026/08/31 03:05:15 DEBUG : potato2: Size of src and dst objects identical
--- PASS: TestRcatSize (7.27s)
=== RUN   TestRcatSizeShortEOF
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:05:19 DEBUG : pacer: low level retry 1/1 (error Put "https://upload-api.linkbox.to/tmp/recycle/1d/user/922460207/d6548b156ea68a4e003e786df99eee76_120?AWSAccessKeyId=ZAUGMKBTPLNX0FBOCABG&Expires=1788152719&Signature=N%2BV1E%2Byy2EcpYPakVY0lNmtbQnI%3D": stream error: stream ID 1; INTERNAL_ERROR; received from peer)
2026/08/31 03:05:19 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/31 03:05:19 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=1788152719&Signature=N%2BV1E%2Byy2EcpYPakVY0lNmtbQnI%3D": stream error: stream ID 1; INTERNAL_ERROR; received from peer
2026/08/31 03:05:20 DEBUG : pacer: Reducing sleep to 300ms
2026/08/31 03:05:20 DEBUG : pacer: Reducing sleep to 225ms
--- PASS: TestRcatSizeShortEOF (1.47s)
=== RUN   TestRcatSizeMetadata
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1657: Skipping as destination doesn't support user metadata
2026/08/31 03:05:20 DEBUG : pacer: Reducing sleep to 200ms
--- SKIP: TestRcatSizeMetadata (0.53s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:05:23 DEBUG : potato1: size = 60 OK
--- PASS: TestRcatSizeUploadHeaders (3.77s)
=== RUN   TestRcatSizeChecksum
=== RUN   TestRcatSizeChecksum/Corrupted
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1794: Skipping as destination doesn't support hashes
=== RUN   TestRcatSizeChecksum/SizeDiffers
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:05:27 DEBUG : potato4: size = 60 (Linkbox root 'rclone-test-yihopiz3hivi')
2026/08/31 03:05:27 DEBUG : potato4: size = 59 (Linkbox root 'rclone-test-yihopiz3hivi')
2026/08/31 03:05:27 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Linkbox root 'rclone-test-yihopiz3hivi') 59
2026/08/31 03:05:27 INFO  : potato4: Removing failed copy
=== RUN   TestRcatSizeChecksum/IgnoreChecksum
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:05:31 DEBUG : potato2: size = 60 OK
=== RUN   TestRcatSizeChecksum/NoHashes
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
2026/08/31 03:05:33 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:33 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/31 03:05:33 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:33 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/31 03:05:33 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:33 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/31 03:05:34 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:34 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/31 03:05:36 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:38 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:40 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:42 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:44 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:46 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:46 ERROR : potato3: Post request put error: Update second step failed: invalid character '<' looking for beginning of value
    operations_test.go:1829: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1829
        	Error:      	Received unexpected error:
        	            	Update second step failed: invalid character '<' looking for beginning of value
        	Test:       	TestRcatSizeChecksum/NoHashes
2026/08/31 03:05:48 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:50 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:52 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:54 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:56 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:05:58 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/31 03:06:00 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/31 03:06:02 DEBUG : pacer: Reducing sleep to 1.125s
--- FAIL: TestRcatSizeChecksum (37.73s)
    --- SKIP: TestRcatSizeChecksum/Corrupted (0.43s)
    --- PASS: TestRcatSizeChecksum/SizeDiffers (3.89s)
    --- PASS: TestRcatSizeChecksum/IgnoreChecksum (3.69s)
    --- FAIL: TestRcatSizeChecksum/NoHashes (29.71s)
=== RUN   TestTouchDir
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1839: Skipping test as remote does not support modtime
2026/08/31 03:06:04 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/31 03:06:05 DEBUG : pacer: Reducing sleep to 632.8125ms
--- SKIP: TestTouchDir (2.64s)
=== RUN   TestMkdirMetadata
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1877: Skipping test as remote does not support MkdirMetadata
2026/08/31 03:06:06 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/31 03:06:06 DEBUG : pacer: Reducing sleep to 355.957031ms
--- SKIP: TestMkdirMetadata (1.47s)
=== RUN   TestMkdirModTime
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1896: Skipping test as remote does not support DirSetModTime or MkdirMetadata
2026/08/31 03:06:07 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/31 03:06:07 DEBUG : pacer: Reducing sleep to 200.225829ms
--- SKIP: TestMkdirModTime (0.83s)
=== RUN   TestCopyDirMetadata
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1917: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
2026/08/31 03:06:07 DEBUG : pacer: Reducing sleep to 200ms
--- SKIP: TestCopyDirMetadata (0.48s)
=== RUN   TestSetDirModTime
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1953: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime
--- SKIP: TestSetDirModTime (0.40s)
=== RUN   TestDirsEqual
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:1997: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
--- SKIP: TestDirsEqual (0.40s)
=== RUN   TestRemoveExisting
    run.go:198: Remote "Linkbox root 'rclone-test-yihopiz3hivi'", Local "Local file system at /tmp/rclone3155512527", Modify Window "876000h0m0s"
    operations_test.go:2066: Skipping as remote can't Move
--- SKIP: TestRemoveExisting (0.40s)
FAIL
2026/08/31 03:06:09 DEBUG : Linkbox root 'rclone-test-yihopiz3hivi': 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|TestParseSumFile|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 ERROR in 5m33.071580258s (try 2/5): exit status 1: Failed [TestRcatSizeChecksum/NoHashes]