"./sync.test -test.v -test.timeout 1h0m0s -remote TestnStorage: -verbose -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncCompareDest|TestSyncMultipleCompareDest|TestSyncWithUpdateOlder)$'" - Starting (try 2/5)
2026/02/24 02:26:34 DEBUG : Creating backend with remote "TestnStorage:rclone-test-tukiput0fuma"
2026/02/24 02:26:34 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/24 02:26:34 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/24 02:26:34 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma"
2026/02/24 02:26:34 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma": object not found
2026/02/24 02:26:34 DEBUG : Creating backend with remote "/tmp/rclone2807697152"
=== RUN   TestServerSideMoveOverSelf
    run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/", Local "Local file system at /tmp/rclone2807697152", Modify Window "1s"
2026/02/24 02:26:34 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/
2026/02/24 02:26:34 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:34 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/sub%20dir/
2026/02/24 02:26:34 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/"
2026/02/24 02:26:34 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/hello%20world"
2026/02/24 02:26:35 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma"
2026/02/24 02:26:35 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:35 DEBUG : Creating backend with remote "TestnStorage:rclone-test-lafetit8tubu"
2026/02/24 02:26:35 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/24 02:26:35 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-lafetit8tubu"
2026/02/24 02:26:35 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu": object not found
    sync_test.go:701: Server side copy (if possible) https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/ -> https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/
2026/02/24 02:26:35 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-lafetit8tubu"
2026/02/24 02:26:35 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:36 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu": object not found
2026/02/24 02:26:36 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/sub%20dir"
2026/02/24 02:26:36 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2026/02/24 02:26:36 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/hello%20world"
2026/02/24 02:26:36 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Waiting for checks to finish
2026/02/24 02:26:36 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Waiting for transfers to finish
2026/02/24 02:26:36 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/
2026/02/24 02:26:36 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:36 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/sub%20dir/
2026/02/24 02:26:36 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-lafetit8tubu/sub%20dir/"
2026/02/24 02:26:36 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-lafetit8tubu/sub%20dir/hello%20world"
2026/02/24 02:26:36 DEBUG : sub dir/hello world: size = 11 OK
2026/02/24 02:26:36 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2026/02/24 02:26:36 INFO  : sub dir/hello world: Copied (new)
2026/02/24 02:26:36 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-lafetit8tubu"
2026/02/24 02:26:37 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-lafetit8tubu0 for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:37 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-lafetit8tubu0 for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:37 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/hello%20world"
2026/02/24 02:26:37 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:37 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:37 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:37 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-lafetit8tubu/sub%20dir"
2026/02/24 02:26:37 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/sub%20dir"
2026/02/24 02:26:37 DEBUG : sub dir/hello world: size = 17 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/)
2026/02/24 02:26:37 DEBUG : sub dir/hello world: size = 11 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/)
2026/02/24 02:26:37 DEBUG : sub dir/hello world: Sizes differ
2026/02/24 02:26:37 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Waiting for checks to finish
2026/02/24 02:26:37 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Waiting for transfers to finish
2026/02/24 02:26:37 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/hello%20world"
2026/02/24 02:26:37 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-lafetit8tubu/sub%20dir/hello%20world"
2026/02/24 02:26:38 DEBUG : sub dir/hello world: size = 17 OK
2026/02/24 02:26:38 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2026/02/24 02:26:38 INFO  : sub dir/hello world: Copied (replaced existing)
2026/02/24 02:26:38 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/hello%20world"
2026/02/24 02:26:38 INFO  : sub dir/hello world: Deleted
2026/02/24 02:26:38 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:38 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-lafetit8tubu0 for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:38 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/hello%20world"
2026/02/24 02:26:38 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:38 DEBUG : testing file moves
2026/02/24 02:26:38 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:38 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:38 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/sub%20dir"
2026/02/24 02:26:38 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-lafetit8tubu/sub%20dir"
2026/02/24 02:26:38 DEBUG : sub dir/hello world: size = 24 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/)
2026/02/24 02:26:38 DEBUG : sub dir/hello world: size = 17 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/)
2026/02/24 02:26:38 DEBUG : sub dir/hello world: Sizes differ
2026/02/24 02:26:38 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/hello%20world"
2026/02/24 02:26:38 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Waiting for checks to finish
2026/02/24 02:26:38 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Waiting for transfers to finish
2026/02/24 02:26:39 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-lafetit8tubu/sub%20dir/hello%20world"
2026/02/24 02:26:39 DEBUG : sub dir/hello world: size = 24 OK
2026/02/24 02:26:39 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2026/02/24 02:26:39 INFO  : sub dir/hello world: Copied (replaced existing)
2026/02/24 02:26:39 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/sub%20dir/hello%20world"
2026/02/24 02:26:39 INFO  : sub dir/hello world: Deleted
2026/02/24 02:26:39 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-lafetit8tubu0 for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:39 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:39 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-lafetit8tubu0 for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:39 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Purge remote
2026/02/24 02:26:39 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:39 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/24 02:26:39 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-lafetit8tubu0 for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:40 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Purge object "sub dir/hello world"
2026/02/24 02:26:40 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-lafetit8tubu/sub%20dir/hello%20world"
2026/02/24 02:26:40 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Purge dir "sub dir"
2026/02/24 02:26:40 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-lafetit8tubu/sub%20dir"
2026/02/24 02:26:40 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-lafetit8tubu/: Purge dir ""
2026/02/24 02:26:40 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-lafetit8tubu/"
2026/02/24 02:26:40 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:40 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/sub%20dir"
2026/02/24 02:26:40 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
--- PASS: TestServerSideMoveOverSelf (6.42s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/", Local "Local file system at /tmp/rclone2807697152", Modify Window "1s"
2026/02/24 02:26:41 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/b/
2026/02/24 02:26:41 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/b/"
2026/02/24 02:26:41 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/b/potato"
2026/02/24 02:26:41 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/c/
2026/02/24 02:26:41 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/c/"
2026/02/24 02:26:41 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/c/non%20empty%20space"
2026/02/24 02:26:41 INFO  : d: Making directory
2026/02/24 02:26:41 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/d/
2026/02/24 02:26:41 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/d/"
2026/02/24 02:26:41 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:41 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:42 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/d"
2026/02/24 02:26:42 DEBUG : a/potato2: Need to transfer - File not found at Destination
2026/02/24 02:26:42 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/a/
2026/02/24 02:26:42 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/c"
2026/02/24 02:26:42 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/b"
2026/02/24 02:26:42 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/a/"
2026/02/24 02:26:42 DEBUG : c/non empty space: size = 5 OK
2026/02/24 02:26:42 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/24 02:26:42 DEBUG : c/non empty space: Unchanged skipping
2026/02/24 02:26:42 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/a/potato2"
2026/02/24 02:26:42 DEBUG : a/potato2: size = 60 OK
2026/02/24 02:26:42 DEBUG : a/potato2: Dst hash empty - aborting Src hash check
2026/02/24 02:26:42 INFO  : a/potato2: Copied (new)
2026/02/24 02:26:42 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: Waiting for checks to finish
2026/02/24 02:26:42 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: Waiting for transfers to finish
2026/02/24 02:26:42 ERROR : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: not deleting files as there were IO errors
2026/02/24 02:26:42 ERROR : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: not deleting directories as there were IO errors
2026/02/24 02:26:42 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:42 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:42 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/a/potato2"
2026/02/24 02:26:42 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/b/potato"
2026/02/24 02:26:42 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/c/non%20empty%20space"
2026/02/24 02:26:43 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/d"
2026/02/24 02:26:43 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/c"
2026/02/24 02:26:43 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/b"
2026/02/24 02:26:43 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/a"
2026/02/24 02:26:43 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.69s)
=== RUN   TestSyncWithUpdateOlder
    run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/", Local "Local file system at /tmp/rclone2807697152", Modify Window "1s"
2026/02/24 02:26:43 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/one"
2026/02/24 02:26:44 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/two"
2026/02/24 02:26:44 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/three"
2026/02/24 02:26:44 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/four"
2026/02/24 02:26:44 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:44 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:44 DEBUG : five: Need to transfer - File not found at Destination
2026/02/24 02:26:44 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/five"
2026/02/24 02:26:44 DEBUG : four: size = 4 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:44 DEBUG : four: size = 8 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/)
2026/02/24 02:26:44 DEBUG : four: Sizes differ
2026/02/24 02:26:44 DEBUG : one: Destination is newer than source, skipping
2026/02/24 02:26:44 DEBUG : three: size = 5 OK
2026/02/24 02:26:44 DEBUG : three: Sizes identical
2026/02/24 02:26:44 DEBUG : two: size = 3 OK
2026/02/24 02:26:44 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2026/02/24 02:26:44 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2026/02/24 02:26:44 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: Waiting for checks to finish
2026/02/24 02:26:44 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:44 DEBUG : two: md5 = 0f82d86afa0f5dc965c5c15aca58dcfb (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/)
2026/02/24 02:26:44 DEBUG : two: md5 differ
2026/02/24 02:26:44 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: Waiting for transfers to finish
2026/02/24 02:26:44 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/four"
2026/02/24 02:26:44 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/two"
2026/02/24 02:26:44 DEBUG : five: size = 4 OK
2026/02/24 02:26:44 DEBUG : five: Dst hash empty - aborting Src hash check
2026/02/24 02:26:44 INFO  : five: Copied (new)
2026/02/24 02:26:45 DEBUG : four: size = 4 OK
2026/02/24 02:26:45 DEBUG : four: Dst hash empty - aborting Src hash check
2026/02/24 02:26:45 INFO  : four: Copied (replaced existing)
2026/02/24 02:26:45 DEBUG : two: size = 3 OK
2026/02/24 02:26:45 DEBUG : two: Dst hash empty - aborting Src hash check
2026/02/24 02:26:45 INFO  : two: Copied (replaced existing)
2026/02/24 02:26:45 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:45 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:45 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:45 DEBUG : five: size = 4 OK
2026/02/24 02:26:45 DEBUG : four: size = 4 OK
2026/02/24 02:26:45 DEBUG : three: size = 5 OK
2026/02/24 02:26:45 DEBUG : one: Destination is newer than source, skipping
2026/02/24 02:26:45 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: Waiting for checks to finish
2026/02/24 02:26:45 DEBUG : two: size = 3 OK
2026/02/24 02:26:45 DEBUG : five: md5 = 30056e1cab7a61d256fc8edd970d14f5 OK
2026/02/24 02:26:45 DEBUG : five: Size and md5 of src and dst objects identical
2026/02/24 02:26:45 DEBUG : five: Destination mod time is within 1s of source and files identical, skipping
2026/02/24 02:26:45 DEBUG : four: md5 = 8cbad96aced40b3838dd9f07f6ef5772 OK
2026/02/24 02:26:45 DEBUG : four: Size and md5 of src and dst objects identical
2026/02/24 02:26:45 DEBUG : four: Destination mod time is within 1s of source and files identical, skipping
2026/02/24 02:26:45 DEBUG : three: md5 = 35d6d33467aae9a2e3dccb4b6b027878 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:45 DEBUG : three: md5 = 413af0de1f97a2155acf2b8b26ab36e2 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/)
2026/02/24 02:26:45 DEBUG : three: md5 differ
2026/02/24 02:26:45 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2026/02/24 02:26:45 DEBUG : two: Size and md5 of src and dst objects identical
2026/02/24 02:26:45 DEBUG : three: Destination mod time is within 1s of source but files differ, transferring
2026/02/24 02:26:45 DEBUG : two: Destination mod time is within 1s of source and files identical, skipping
2026/02/24 02:26:45 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: Waiting for transfers to finish
2026/02/24 02:26:45 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/three"
2026/02/24 02:26:45 DEBUG : three: size = 5 OK
2026/02/24 02:26:45 DEBUG : three: Dst hash empty - aborting Src hash check
2026/02/24 02:26:45 INFO  : three: Copied (replaced existing)
2026/02/24 02:26:45 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:45 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:46 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:46 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/five"
2026/02/24 02:26:46 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/four"
2026/02/24 02:26:46 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/one"
2026/02/24 02:26:46 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/three"
2026/02/24 02:26:46 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/two"
2026/02/24 02:26:46 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
--- PASS: TestSyncWithUpdateOlder (3.13s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/", Local "Local file system at /tmp/rclone2807697152", Modify Window "1s"
2026/02/24 02:26:46 DEBUG : Creating backend with remote "TestnStorage:rclone-test-riqujar9jaqu"
2026/02/24 02:26:46 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/24 02:26:46 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-riqujar9jaqu"
2026/02/24 02:26:47 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-riqujar9jaqu": object not found
2026/02/24 02:26:47 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/potato2"
2026/02/24 02:26:47 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/empty%20space"
2026/02/24 02:26:47 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/potato3"
2026/02/24 02:26:47 INFO  : tomatoDir: Making directory
2026/02/24 02:26:47 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/tomatoDir/
2026/02/24 02:26:47 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/tomatoDir/"
2026/02/24 02:26:47 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
    sync_test.go:1736: Server side move (if possible) https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/ -> https://rclone-nsu.akamaihd.net/1210146/rclone-test-riqujar9jaqu/
2026/02/24 02:26:47 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-riqujar9jaqu/
2026/02/24 02:26:47 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-riqujar9jaqu/"
2026/02/24 02:26:48 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-riqujar9jaqu/empty%20space"
2026/02/24 02:26:48 DEBUG : NetStorage action upload failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-riqujar9jaqu/empty%20space": HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n"
2026/02/24 02:26:48 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-riqujar9jaqu/empty%20space"
2026/02/24 02:26:48 DEBUG : NetStorage action delete failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-riqujar9jaqu/empty%20space": object not found
    run.go:290: Failed to put "empty space" to "https://rclone-nsu.akamaihd.net/1210146/rclone-test-riqujar9jaqu/": failed to call NetStorage API upload: HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n"
2026/02/24 02:26:48 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-riqujar9jaqu/: Purge remote
2026/02/24 02:26:48 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-riqujar9jaqu/"
2026/02/24 02:26:49 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/24 02:26:49 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-riqujar9jaqu"
2026/02/24 02:26:49 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-riqujar9jaqu0 for path "/1210146/rclone-test-riqujar9jaqu/"
2026/02/24 02:26:49 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-riqujar9jaqu/: Purge dir ""
2026/02/24 02:26:49 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-riqujar9jaqu/"
2026/02/24 02:26:49 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:49 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/empty%20space"
2026/02/24 02:26:49 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/potato2"
2026/02/24 02:26:50 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/potato3"
2026/02/24 02:26:50 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/tomatoDir"
2026/02/24 02:26:50 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
--- FAIL: TestServerSideMoveDeleteEmptySourceDirs (3.59s)
=== RUN   TestSyncCompareDest
    run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/", Local "Local file system at /tmp/rclone2807697152", Modify Window "1s"
2026/02/24 02:26:50 DEBUG : Creating backend with remote "TestnStorage:rclone-test-tukiput0fuma/dst"
2026/02/24 02:26:50 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/24 02:26:50 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/dst"
2026/02/24 02:26:50 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst": object not found
2026/02/24 02:26:50 DEBUG : Creating backend with remote "TestnStorage:rclone-test-tukiput0fuma/CompareDest"
2026/02/24 02:26:50 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/24 02:26:50 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/CompareDest"
2026/02/24 02:26:51 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest": object not found
2026/02/24 02:26:51 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/dst"
2026/02/24 02:26:51 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst": object not found
2026/02/24 02:26:51 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:51 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/one": object not found
2026/02/24 02:26:51 DEBUG : one: Need to transfer - File not found at Destination
2026/02/24 02:26:51 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for checks to finish
2026/02/24 02:26:51 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for transfers to finish
2026/02/24 02:26:51 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/
2026/02/24 02:26:51 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:51 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/
2026/02/24 02:26:51 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/dst/"
2026/02/24 02:26:51 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/dst/one"
2026/02/24 02:26:52 DEBUG : one: size = 3 OK
2026/02/24 02:26:52 DEBUG : one: Dst hash empty - aborting Src hash check
2026/02/24 02:26:52 INFO  : one: Copied (new)
2026/02/24 02:26:52 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:52 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:52 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:52 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/dst"
2026/02/24 02:26:52 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/dst/"
2026/02/24 02:26:52 DEBUG : one: size = 5 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:52 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/)
2026/02/24 02:26:52 DEBUG : one: Sizes differ
2026/02/24 02:26:52 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for checks to finish
2026/02/24 02:26:52 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:52 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/one": object not found
2026/02/24 02:26:52 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for transfers to finish
2026/02/24 02:26:52 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/dst/one"
2026/02/24 02:26:52 DEBUG : one: size = 5 OK
2026/02/24 02:26:52 DEBUG : one: Dst hash empty - aborting Src hash check
2026/02/24 02:26:52 INFO  : one: Copied (replaced existing)
2026/02/24 02:26:52 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:52 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:52 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/
2026/02/24 02:26:52 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/dst/"
2026/02/24 02:26:52 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/dst/one"
2026/02/24 02:26:53 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/
2026/02/24 02:26:53 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/CompareDest/"
2026/02/24 02:26:53 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:53 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:53 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/dst/"
2026/02/24 02:26:53 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for checks to finish
2026/02/24 02:26:53 DEBUG : one: size = 5 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:53 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/)
2026/02/24 02:26:53 DEBUG : one: Sizes differ
2026/02/24 02:26:53 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:53 DEBUG : one: size = 5 OK
2026/02/24 02:26:53 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/24 02:26:53 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/24 02:26:53 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for transfers to finish
2026/02/24 02:26:53 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:53 INFO  : There was nothing to transfer
2026/02/24 02:26:53 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:53 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/CompareDest/two"
2026/02/24 02:26:54 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:54 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/dst/"
2026/02/24 02:26:54 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/CompareDest/two"
2026/02/24 02:26:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:54 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/)
2026/02/24 02:26:54 DEBUG : one: Sizes differ
2026/02/24 02:26:54 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:54 DEBUG : one: size = 5 OK
2026/02/24 02:26:54 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/24 02:26:54 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/24 02:26:54 DEBUG : two: size = 3 OK
2026/02/24 02:26:54 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/24 02:26:54 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/24 02:26:54 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for checks to finish
2026/02/24 02:26:54 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for transfers to finish
2026/02/24 02:26:54 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:54 INFO  : There was nothing to transfer
2026/02/24 02:26:54 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:54 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/dst/"
2026/02/24 02:26:54 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/two"
2026/02/24 02:26:54 DEBUG : two: size = 3 OK
2026/02/24 02:26:54 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/24 02:26:54 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/24 02:26:54 DEBUG : one: size = 5 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:54 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/)
2026/02/24 02:26:54 DEBUG : one: Sizes differ
2026/02/24 02:26:54 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:54 DEBUG : one: size = 5 OK
2026/02/24 02:26:54 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/24 02:26:54 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/24 02:26:54 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for checks to finish
2026/02/24 02:26:54 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for transfers to finish
2026/02/24 02:26:54 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:54 INFO  : There was nothing to transfer
2026/02/24 02:26:54 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:54 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/dst/one"
2026/02/24 02:26:54 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/dst/"
2026/02/24 02:26:55 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/two"
2026/02/24 02:26:55 DEBUG : two: size = 3 OK
2026/02/24 02:26:55 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/24 02:26:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:55 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/)
2026/02/24 02:26:55 DEBUG : one: Sizes differ
2026/02/24 02:26:55 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:55 DEBUG : one: size = 5 OK
2026/02/24 02:26:55 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/24 02:26:55 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/24 02:26:55 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2026/02/24 02:26:55 DEBUG : two: Destination found in --compare-dest, skipping
2026/02/24 02:26:55 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for checks to finish
2026/02/24 02:26:55 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for transfers to finish
2026/02/24 02:26:55 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:55 INFO  : There was nothing to transfer
2026/02/24 02:26:55 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:55 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:55 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tukiput0fuma/dst/"
2026/02/24 02:26:55 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/two"
2026/02/24 02:26:55 DEBUG : two: size = 5 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:55 DEBUG : two: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/)
2026/02/24 02:26:55 DEBUG : two: Sizes differ
2026/02/24 02:26:55 DEBUG : two: Need to transfer - File not found at Destination
2026/02/24 02:26:55 DEBUG : one: size = 5 (Local file system at /tmp/rclone2807697152)
2026/02/24 02:26:55 DEBUG : one: size = 3 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/)
2026/02/24 02:26:55 DEBUG : one: Sizes differ
2026/02/24 02:26:55 DEBUG : NetStorage stat cache hit for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:55 DEBUG : one: size = 5 OK
2026/02/24 02:26:55 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/02/24 02:26:55 DEBUG : one: Destination found in --compare-dest, skipping
2026/02/24 02:26:55 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/dst/two"
2026/02/24 02:26:55 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for checks to finish
2026/02/24 02:26:55 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dst/: Waiting for transfers to finish
2026/02/24 02:26:55 DEBUG : two: size = 5 OK
2026/02/24 02:26:55 DEBUG : two: Dst hash empty - aborting Src hash check
2026/02/24 02:26:55 INFO  : two: Copied (new)
2026/02/24 02:26:55 DEBUG : Waiting for deletions to finish
2026/02/24 02:26:55 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:55 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:55 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/CompareDest/one"
2026/02/24 02:26:55 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/CompareDest/two"
2026/02/24 02:26:56 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/dst/one"
2026/02/24 02:26:56 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/dst/two"
2026/02/24 02:26:56 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/dst"
2026/02/24 02:26:56 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/CompareDest"
2026/02/24 02:26:56 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
--- PASS: TestSyncCompareDest (6.20s)
=== RUN   TestSyncMultipleCompareDest
    run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/", Local "Local file system at /tmp/rclone2807697152", Modify Window "1s"
2026/02/24 02:26:56 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/pre-dest1/
2026/02/24 02:26:56 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/pre-dest1/"
2026/02/24 02:26:56 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/pre-dest1/1"
2026/02/24 02:26:56 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/pre-dest2/
2026/02/24 02:26:56 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/pre-dest2/"
2026/02/24 02:26:57 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/pre-dest2/2"
2026/02/24 02:26:57 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:57 DEBUG : Creating backend with remote "TestnStorage:rclone-test-tukiput0fuma/dest"
2026/02/24 02:26:57 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/24 02:26:57 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/dest"
2026/02/24 02:26:57 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest": object not found
2026/02/24 02:26:57 DEBUG : Creating backend with remote "TestnStorage:rclone-test-tukiput0fuma/pre-dest1"
2026/02/24 02:26:57 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/24 02:26:57 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/pre-dest1"
2026/02/24 02:26:57 DEBUG : Creating backend with remote "TestnStorage:rclone-test-tukiput0fuma/pre-dest2"
2026/02/24 02:26:57 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2026/02/24 02:26:57 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/pre-dest2"
2026/02/24 02:26:58 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/dest"
2026/02/24 02:26:58 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest": object not found
2026/02/24 02:26:58 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/pre-dest1/1"
2026/02/24 02:26:58 DEBUG : 1: size = 1 OK
2026/02/24 02:26:58 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/02/24 02:26:58 DEBUG : 1: Destination found in --compare-dest, skipping
2026/02/24 02:26:58 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/pre-dest1/2"
2026/02/24 02:26:58 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/pre-dest1/2": object not found
2026/02/24 02:26:58 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/pre-dest2/2"
2026/02/24 02:26:58 DEBUG : 2: size = 1 OK
2026/02/24 02:26:58 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/02/24 02:26:58 DEBUG : 2: Destination found in --compare-dest, skipping
2026/02/24 02:26:58 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/pre-dest1/3"
2026/02/24 02:26:58 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/pre-dest1/3": object not found
2026/02/24 02:26:58 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tukiput0fuma/pre-dest2/3"
2026/02/24 02:26:58 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/pre-dest2/3": object not found
2026/02/24 02:26:58 DEBUG : 3: Need to transfer - File not found at Destination
2026/02/24 02:26:58 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/
2026/02/24 02:26:58 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:58 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest/: Waiting for checks to finish
2026/02/24 02:26:58 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest/: Waiting for transfers to finish
2026/02/24 02:26:59 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest/
2026/02/24 02:26:59 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tukiput0fuma/dest/"
2026/02/24 02:26:59 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tukiput0fuma/dest/3"
2026/02/24 02:26:59 DEBUG : NetStorage action upload failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest/3": HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n"
2026/02/24 02:26:59 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/dest/3"
2026/02/24 02:26:59 DEBUG : NetStorage action delete failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest/3": object not found
2026/02/24 02:26:59 ERROR : 3: Failed to copy: failed to call NetStorage API upload: HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n"
2026/02/24 02:26:59 ERROR : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest/: not deleting files as there were IO errors
2026/02/24 02:26:59 ERROR : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/dest/: not deleting directories as there were IO errors
    sync_test.go:2249: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2249
        	Error:      	Received unexpected error:
        	            	failed to call NetStorage API upload: HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n"
        	Test:       	TestSyncMultipleCompareDest
2026/02/24 02:26:59 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:26:59 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/pre-dest1/1"
2026/02/24 02:26:59 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tukiput0fuma/pre-dest2/2"
2026/02/24 02:27:00 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/pre-dest2"
2026/02/24 02:27:00 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/pre-dest1"
2026/02/24 02:27:00 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/dest"
2026/02/24 02:27:00 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
--- FAIL: TestSyncMultipleCompareDest (3.88s)
FAIL
2026/02/24 02:27:00 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: Purge remote
2026/02/24 02:27:00 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:27:00 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/24 02:27:00 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tukiput0fuma0 for path "/1210146/rclone-test-tukiput0fuma/"
2026/02/24 02:27:00 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tukiput0fuma/: Purge dir ""
2026/02/24 02:27:00 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tukiput0fuma/"
"./sync.test -test.v -test.timeout 1h0m0s -remote TestnStorage: -verbose -test.run '^(TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveOverSelf|TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors|TestSyncCompareDest|TestSyncMultipleCompareDest|TestSyncWithUpdateOlder)$'" - Finished ERROR in 26.823917207s (try 2/5): exit status 1: Failed [TestServerSideMoveDeleteEmptySourceDirs TestSyncMultipleCompareDest]