"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestSyncMultipleCompareDest$'" - Starting (try 4/5)
2026/07/10 05:07:06 DEBUG : Creating backend with remote "TestGoFile:rclone-test-bosiyev1coja"
2026/07/10 05:07:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/10 05:07:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:06 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/07/10 05:07:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:06 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:06 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/07/10 05:07:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:06 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:06 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/07/10 05:07:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:06 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:06 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/07/10 05:07:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:07 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/07/10 05:07:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:07 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:07 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/07/10 05:07:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:07 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:07 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/07/10 05:07:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:08 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:08 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/07/10 05:07:09 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:09 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:09 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/07/10 05:07:11 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:11 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:11 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/07/10 05:07:17 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:17 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:17 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/07/10 05:07:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:27 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:07:47 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/07/10 05:07:47 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/07/10 05:08:07 DEBUG : pacer: Reducing sleep to 10s
2026/07/10 05:08:07 DEBUG : Creating backend with remote "/tmp/rclone3819617374"
=== RUN TestSyncMultipleCompareDest
run.go:198: Remote "gofile root 'rclone-test-bosiyev1coja'", Local "Local file system at /tmp/rclone3819617374", Modify Window "1s"
2026/07/10 05:08:27 DEBUG : pacer: Reducing sleep to 5s
2026/07/10 05:08:37 DEBUG : pacer: Reducing sleep to 2.5s
2026/07/10 05:08:42 DEBUG : pacer: Reducing sleep to 1.25s
2026/07/10 05:08:44 DEBUG : pacer: Reducing sleep to 625ms
2026/07/10 05:08:46 DEBUG : pacer: Reducing sleep to 312.5ms
2026/07/10 05:08:46 DEBUG : pacer: Reducing sleep to 156.25ms
2026/07/10 05:08:47 DEBUG : pacer: Reducing sleep to 78.125ms
2026/07/10 05:08:47 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/07/10 05:08:47 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/07/10 05:08:47 DEBUG : pacer: Reducing sleep to 10ms
2026/07/10 05:08:47 DEBUG : Creating backend with remote "TestGoFile:rclone-test-bosiyev1coja/dest"
2026/07/10 05:08:48 DEBUG : Creating backend with remote "TestGoFile:rclone-test-bosiyev1coja/pre-dest1"
2026/07/10 05:08:48 DEBUG : Creating backend with remote "TestGoFile:rclone-test-bosiyev1coja/pre-dest2"
2026/07/10 05:08:48 DEBUG : 1: size = 1 OK
2026/07/10 05:08:48 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/07/10 05:08:48 DEBUG : 1: Destination found in --compare-dest, skipping
2026/07/10 05:08:49 DEBUG : 2: size = 1 OK
2026/07/10 05:08:49 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/07/10 05:08:49 DEBUG : 2: Destination found in --compare-dest, skipping
2026/07/10 05:08:49 DEBUG : 3: Need to transfer - File not found at Destination
2026/07/10 05:08:49 DEBUG : gofile root 'rclone-test-bosiyev1coja/dest': Waiting for checks to finish
2026/07/10 05:08:49 DEBUG : gofile root 'rclone-test-bosiyev1coja/dest': Waiting for transfers to finish
2026/07/10 05:08:49 DEBUG : 3: size = 1 OK
2026/07/10 05:08:49 DEBUG : 3: md5 = eccbc87e4b5ce2fe28308fd9f2a7baf3 OK
2026/07/10 05:08:49 INFO : 3: Copied (new)
2026/07/10 05:08:49 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncMultipleCompareDest (43.16s)
PASS
2026/07/10 05:08:50 DEBUG : gofile root 'rclone-test-bosiyev1coja': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose -test.run '^TestSyncMultipleCompareDest$'" - Finished OK in 1m44.55139047s (try 4/5)