"./sync.test -test.v -test.timeout 1h0m0s -remote TestnStorage: -verbose -test.run '^TestSyncCompareDest$'" - Starting (try 2/5)
2026/02/15 03:06:53 DEBUG : Creating backend with remote "TestnStorage:rclone-test-vozabel7mani"
2026/02/15 03:06:53 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/15 03:06:53 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/15 03:06:53 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani"
2026/02/15 03:06:53 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani": object not found
2026/02/15 03:06:53 DEBUG : Creating backend with remote "/tmp/rclone4239120831"
=== RUN   TestSyncCompareDest
    run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/", Local "Local file system at /tmp/rclone4239120831", Modify Window "1s"
2026/02/15 03:06:53 DEBUG : Creating backend with remote "TestnStorage:rclone-test-vozabel7mani/dst"
2026/02/15 03:06:53 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/15 03:06:53 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/dst"
2026/02/15 03:06:53 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst": object not found
2026/02/15 03:06:53 DEBUG : Creating backend with remote "TestnStorage:rclone-test-vozabel7mani/CompareDest"
2026/02/15 03:06:53 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/15 03:06:53 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/CompareDest"
2026/02/15 03:06:53 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest": object not found
2026/02/15 03:06:53 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/dst"
2026/02/15 03:06:53 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst": object not found
2026/02/15 03:06:53 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:06:54 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/one": object not found
2026/02/15 03:06:54 DEBUG : one: Need to transfer - File not found at Destination
2026/02/15 03:06:54 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/
2026/02/15 03:06:54 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:54 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for checks to finish
2026/02/15 03:06:54 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for transfers to finish
2026/02/15 03:06:54 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/
2026/02/15 03:06:54 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-vozabel7mani/dst/"
2026/02/15 03:06:54 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-vozabel7mani/dst/one"
2026/02/15 03:06:54 DEBUG : one: size = 3 OK
2026/02/15 03:06:54 DEBUG : one: Dst hash empty - aborting Src hash check
2026/02/15 03:06:54 INFO  : one: Copied (new)
2026/02/15 03:06:54 DEBUG : Waiting for deletions to finish
2026/02/15 03:06:54 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani"
2026/02/15 03:06:54 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:55 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:55 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/dst"
2026/02/15 03:06:55 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-vozabel7mani/dst/"
2026/02/15 03:06:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone4239120831)
2026/02/15 03:06:55 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/)
2026/02/15 03:06:55 DEBUG : one: Sizes differ
2026/02/15 03:06:55 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:06:55 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for checks to finish
2026/02/15 03:06:55 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/one": object not found
2026/02/15 03:06:55 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for transfers to finish
2026/02/15 03:06:55 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-vozabel7mani/dst/one"
2026/02/15 03:06:55 DEBUG : one: size = 5 OK
2026/02/15 03:06:55 DEBUG : one: Dst hash empty - aborting Src hash check
2026/02/15 03:06:55 INFO  : one: Copied (replaced existing)
2026/02/15 03:06:55 DEBUG : Waiting for deletions to finish
2026/02/15 03:06:55 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:56 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/
2026/02/15 03:06:56 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-vozabel7mani/dst/"
2026/02/15 03:06:56 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-vozabel7mani/dst/one"
2026/02/15 03:06:56 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/
2026/02/15 03:06:56 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-vozabel7mani/CompareDest/"
2026/02/15 03:06:56 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:06:56 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:56 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-vozabel7mani/dst/"
2026/02/15 03:06:56 DEBUG : one: size = 5 (Local file system at /tmp/rclone4239120831)
2026/02/15 03:06:56 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/)
2026/02/15 03:06:56 DEBUG : one: Sizes differ
2026/02/15 03:06:56 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for checks to finish
2026/02/15 03:06:56 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:06:57 DEBUG : one: size = 5 OK
2026/02/15 03:06:57 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/15 03:06:57 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:06:57 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for transfers to finish
2026/02/15 03:06:57 DEBUG : Waiting for deletions to finish
2026/02/15 03:06:57 INFO  : There was nothing to transfer
2026/02/15 03:06:57 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:57 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-vozabel7mani/CompareDest/two"
2026/02/15 03:06:57 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:57 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-vozabel7mani/dst/"
2026/02/15 03:06:57 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/CompareDest/two"
2026/02/15 03:06:57 DEBUG : one: size = 5 (Local file system at /tmp/rclone4239120831)
2026/02/15 03:06:57 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/)
2026/02/15 03:06:57 DEBUG : one: Sizes differ
2026/02/15 03:06:57 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:06:57 DEBUG : one: size = 5 OK
2026/02/15 03:06:57 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/15 03:06:57 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:06:57 DEBUG : two: size = 3 OK
2026/02/15 03:06:57 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/15 03:06:57 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/15 03:06:57 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for checks to finish
2026/02/15 03:06:57 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for transfers to finish
2026/02/15 03:06:57 DEBUG : Waiting for deletions to finish
2026/02/15 03:06:57 INFO  : There was nothing to transfer
2026/02/15 03:06:57 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:58 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-vozabel7mani/dst/"
2026/02/15 03:06:58 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/two"
2026/02/15 03:06:58 DEBUG : two: size = 3 OK
2026/02/15 03:06:58 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/15 03:06:58 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/15 03:06:58 DEBUG : one: size = 5 (Local file system at /tmp/rclone4239120831)
2026/02/15 03:06:58 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/)
2026/02/15 03:06:58 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for checks to finish
2026/02/15 03:06:58 DEBUG : one: Sizes differ
2026/02/15 03:06:58 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:06:58 DEBUG : one: size = 5 OK
2026/02/15 03:06:58 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/15 03:06:58 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:06:58 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for transfers to finish
2026/02/15 03:06:58 DEBUG : Waiting for deletions to finish
2026/02/15 03:06:58 INFO  : There was nothing to transfer
2026/02/15 03:06:58 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:58 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-vozabel7mani/dst/one"
2026/02/15 03:06:58 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-vozabel7mani/dst/"
2026/02/15 03:06:58 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/two"
2026/02/15 03:06:58 DEBUG : two: size = 3 OK
2026/02/15 03:06:58 DEBUG : two: Modification times differ by -120h0m0s: 2011-12-30 12:59:59 +0000 UTC, 2011-12-25 12:59:59 +0000 UTC
2026/02/15 03:06:58 DEBUG : one: size = 5 (Local file system at /tmp/rclone4239120831)
2026/02/15 03:06:58 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/)
2026/02/15 03:06:58 DEBUG : one: Sizes differ
2026/02/15 03:06:58 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:06:58 DEBUG : one: size = 5 OK
2026/02/15 03:06:58 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/15 03:06:58 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:06:58 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2026/02/15 03:06:58 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/15 03:06:58 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for checks to finish
2026/02/15 03:06:58 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for transfers to finish
2026/02/15 03:06:58 DEBUG : Waiting for deletions to finish
2026/02/15 03:06:58 INFO  : There was nothing to transfer
2026/02/15 03:06:58 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:59 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:59 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-vozabel7mani/dst/"
2026/02/15 03:06:59 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/two"
2026/02/15 03:06:59 DEBUG : two: size = 5 (Local file system at /tmp/rclone4239120831)
2026/02/15 03:06:59 DEBUG : two: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/)
2026/02/15 03:06:59 DEBUG : two: Sizes differ
2026/02/15 03:06:59 DEBUG : two: Need to transfer - File not found at Destination
2026/02/15 03:06:59 DEBUG : one: size = 5 (Local file system at /tmp/rclone4239120831)
2026/02/15 03:06:59 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/)
2026/02/15 03:06:59 DEBUG : one: Sizes differ
2026/02/15 03:06:59 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for checks to finish
2026/02/15 03:06:59 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:06:59 DEBUG : one: size = 5 OK
2026/02/15 03:06:59 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/15 03:06:59 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/15 03:06:59 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/dst/: Waiting for transfers to finish
2026/02/15 03:06:59 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-vozabel7mani/dst/two"
2026/02/15 03:06:59 DEBUG : two: size = 5 OK
2026/02/15 03:06:59 DEBUG : two: Dst hash empty - aborting Src hash check
2026/02/15 03:06:59 INFO  : two: Copied (new)
2026/02/15 03:06:59 DEBUG : Waiting for deletions to finish
2026/02/15 03:06:59 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:06:59 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:07:00 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-vozabel7mani/CompareDest/one"
2026/02/15 03:07:00 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-vozabel7mani/CompareDest/two"
2026/02/15 03:07:00 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-vozabel7mani/dst/one"
2026/02/15 03:07:00 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-vozabel7mani/dst/two"
2026/02/15 03:07:00 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-vozabel7mani/dst"
2026/02/15 03:07:00 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-vozabel7mani/CompareDest"
2026/02/15 03:07:00 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
--- PASS: TestSyncCompareDest (7.73s)
PASS
2026/02/15 03:07:01 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/: Purge remote
2026/02/15 03:07:01 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:07:01 NOTICE: Purge using quick-delete failed, fallback on recursive delete: failed to call NetStorage API: HTTP error 403 (403 Forbidden) returned body: "Feature not enabled for this request.\n"
2026/02/15 03:07:01 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-vozabel7mani0 for path "/1210146/rclone-test-vozabel7mani/"
2026/02/15 03:07:01 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-vozabel7mani/: Purge dir ""
2026/02/15 03:07:01 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-vozabel7mani/"
"./sync.test -test.v -test.timeout 1h0m0s -remote TestnStorage: -verbose -test.run '^TestSyncCompareDest$'" - Finished OK in 8.788902503s (try 2/5)