"./sync.test -test.v -test.timeout 1h0m0s -remote TestnStorage: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveOverSelf)$'" - Starting (try 2/5) 2026/02/23 02:15:08 DEBUG : Creating backend with remote "TestnStorage:rclone-test-quzupup9siwo" 2026/02/23 02:15:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/23 02:15:08 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/" 2026/02/23 02:15:08 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-quzupup9siwo" 2026/02/23 02:15:08 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo": object not found 2026/02/23 02:15:08 DEBUG : Creating backend with remote "/tmp/rclone3842353707" === RUN TestServerSideCopyOverSelf run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/", Local "Local file system at /tmp/rclone3842353707", Modify Window "1s" 2026/02/23 02:15:08 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/ 2026/02/23 02:15:08 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:09 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/sub%20dir/ 2026/02/23 02:15:09 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/" 2026/02/23 02:15:09 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:09 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-quzupup9siwo" 2026/02/23 02:15:09 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:10 DEBUG : Creating backend with remote "TestnStorage:rclone-test-qonezuc2fowa" 2026/02/23 02:15:10 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/" 2026/02/23 02:15:10 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-qonezuc2fowa" 2026/02/23 02:15:10 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa": object not found sync_test.go:656: Server side copy (if possible) https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/ -> https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/ 2026/02/23 02:15:10 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-qonezuc2fowa" 2026/02/23 02:15:10 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:10 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa": object not found 2026/02/23 02:15:10 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/sub%20dir" 2026/02/23 02:15:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/23 02:15:10 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/: Waiting for checks to finish 2026/02/23 02:15:10 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/: Waiting for transfers to finish 2026/02/23 02:15:10 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:10 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/ 2026/02/23 02:15:10 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:10 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/sub%20dir/ 2026/02/23 02:15:10 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-qonezuc2fowa/sub%20dir/" 2026/02/23 02:15:11 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-qonezuc2fowa/sub%20dir/hello%20world" 2026/02/23 02:15:11 DEBUG : sub dir/hello world: size = 11 OK 2026/02/23 02:15:11 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/02/23 02:15:11 INFO : sub dir/hello world: Copied (new) 2026/02/23 02:15:11 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-qonezuc2fowa" 2026/02/23 02:15:11 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-qonezuc2fowa0 for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:11 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-qonezuc2fowa0 for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:11 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:11 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:12 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:12 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:12 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-qonezuc2fowa/sub%20dir" 2026/02/23 02:15:12 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/sub%20dir" 2026/02/23 02:15:12 DEBUG : sub dir/hello world: size = 17 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/) 2026/02/23 02:15:12 DEBUG : sub dir/hello world: size = 11 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/) 2026/02/23 02:15:12 DEBUG : sub dir/hello world: Sizes differ 2026/02/23 02:15:12 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:12 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/: Waiting for checks to finish 2026/02/23 02:15:12 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/: Waiting for transfers to finish 2026/02/23 02:15:12 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-qonezuc2fowa/sub%20dir/hello%20world" 2026/02/23 02:15:12 DEBUG : sub dir/hello world: size = 17 OK 2026/02/23 02:15:12 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/02/23 02:15:12 INFO : sub dir/hello world: Copied (replaced existing) 2026/02/23 02:15:12 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-qonezuc2fowa0 for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:12 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-qonezuc2fowa0 for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:12 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/: Purge remote 2026/02/23 02:15:12 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:12 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/23 02:15:12 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-qonezuc2fowa0 for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:13 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/: Purge object "sub dir/hello world" 2026/02/23 02:15:13 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-qonezuc2fowa/sub%20dir/hello%20world" 2026/02/23 02:15:13 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/: Purge dir "sub dir" 2026/02/23 02:15:13 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-qonezuc2fowa/sub%20dir" 2026/02/23 02:15:13 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-qonezuc2fowa/: Purge dir "" 2026/02/23 02:15:13 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-qonezuc2fowa/" 2026/02/23 02:15:13 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:14 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:14 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-quzupup9siwo/sub%20dir" 2026/02/23 02:15:14 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" --- PASS: TestServerSideCopyOverSelf (5.46s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/", Local "Local file system at /tmp/rclone3842353707", Modify Window "1s" 2026/02/23 02:15:14 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/sub%20dir/ 2026/02/23 02:15:14 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/" 2026/02/23 02:15:14 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:14 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:14 DEBUG : Creating backend with remote "TestnStorage:rclone-test-tunuqik9fira" 2026/02/23 02:15:14 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/" 2026/02/23 02:15:14 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tunuqik9fira" 2026/02/23 02:15:15 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira": object not found sync_test.go:701: Server side copy (if possible) https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/ -> https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/ 2026/02/23 02:15:15 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tunuqik9fira" 2026/02/23 02:15:15 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:15 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira": object not found 2026/02/23 02:15:15 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/sub%20dir" 2026/02/23 02:15:15 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/02/23 02:15:15 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:15 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Waiting for checks to finish 2026/02/23 02:15:15 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Waiting for transfers to finish 2026/02/23 02:15:15 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/ 2026/02/23 02:15:15 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:15 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/sub%20dir/ 2026/02/23 02:15:15 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-tunuqik9fira/sub%20dir/" 2026/02/23 02:15:16 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tunuqik9fira/sub%20dir/hello%20world" 2026/02/23 02:15:16 DEBUG : sub dir/hello world: size = 11 OK 2026/02/23 02:15:16 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/02/23 02:15:16 INFO : sub dir/hello world: Copied (new) 2026/02/23 02:15:16 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-tunuqik9fira" 2026/02/23 02:15:16 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tunuqik9fira0 for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:16 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tunuqik9fira0 for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:16 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:17 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:17 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:17 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:17 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tunuqik9fira/sub%20dir" 2026/02/23 02:15:17 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/sub%20dir" 2026/02/23 02:15:17 DEBUG : sub dir/hello world: size = 17 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/) 2026/02/23 02:15:17 DEBUG : sub dir/hello world: size = 11 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/) 2026/02/23 02:15:17 DEBUG : sub dir/hello world: Sizes differ 2026/02/23 02:15:17 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:17 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Waiting for checks to finish 2026/02/23 02:15:17 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Waiting for transfers to finish 2026/02/23 02:15:17 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tunuqik9fira/sub%20dir/hello%20world" 2026/02/23 02:15:17 DEBUG : sub dir/hello world: size = 17 OK 2026/02/23 02:15:17 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/02/23 02:15:17 INFO : sub dir/hello world: Copied (replaced existing) 2026/02/23 02:15:17 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:17 INFO : sub dir/hello world: Deleted 2026/02/23 02:15:17 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:17 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tunuqik9fira0 for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:18 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:18 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:18 DEBUG : testing file moves 2026/02/23 02:15:18 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:18 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:18 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-tunuqik9fira/sub%20dir" 2026/02/23 02:15:18 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/sub%20dir" 2026/02/23 02:15:18 DEBUG : sub dir/hello world: size = 24 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/) 2026/02/23 02:15:18 DEBUG : sub dir/hello world: size = 17 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/) 2026/02/23 02:15:18 DEBUG : sub dir/hello world: Sizes differ 2026/02/23 02:15:18 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Waiting for checks to finish 2026/02/23 02:15:18 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Waiting for transfers to finish 2026/02/23 02:15:18 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:18 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-tunuqik9fira/sub%20dir/hello%20world" 2026/02/23 02:15:18 DEBUG : sub dir/hello world: size = 24 OK 2026/02/23 02:15:18 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2026/02/23 02:15:18 INFO : sub dir/hello world: Copied (replaced existing) 2026/02/23 02:15:18 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-quzupup9siwo/sub%20dir/hello%20world" 2026/02/23 02:15:19 INFO : sub dir/hello world: Deleted 2026/02/23 02:15:19 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tunuqik9fira0 for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:19 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:19 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tunuqik9fira0 for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:19 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Purge remote 2026/02/23 02:15:19 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:19 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/23 02:15:19 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-tunuqik9fira0 for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:20 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Purge object "sub dir/hello world" 2026/02/23 02:15:20 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-tunuqik9fira/sub%20dir/hello%20world" 2026/02/23 02:15:20 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Purge dir "sub dir" 2026/02/23 02:15:20 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tunuqik9fira/sub%20dir" 2026/02/23 02:15:20 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-tunuqik9fira/: Purge dir "" 2026/02/23 02:15:20 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-tunuqik9fira/" 2026/02/23 02:15:20 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:20 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-quzupup9siwo/sub%20dir" 2026/02/23 02:15:20 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" --- PASS: TestServerSideMoveOverSelf (6.46s) === RUN TestServerSideMove run.go:185: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/", Local "Local file system at /tmp/rclone3842353707", Modify Window "1s" 2026/02/23 02:15:20 DEBUG : Creating backend with remote "TestnStorage:rclone-test-yahipix5gubo" 2026/02/23 02:15:20 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/" 2026/02/23 02:15:20 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-yahipix5gubo" 2026/02/23 02:15:21 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo": object not found 2026/02/23 02:15:21 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-quzupup9siwo/potato2" 2026/02/23 02:15:21 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-quzupup9siwo/empty%20space" 2026/02/23 02:15:21 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-quzupup9siwo/potato3" 2026/02/23 02:15:21 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" sync_test.go:1736: Server side move (if possible) https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/ -> https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo/ 2026/02/23 02:15:21 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo/ 2026/02/23 02:15:21 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-yahipix5gubo/" 2026/02/23 02:15:22 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-yahipix5gubo/empty%20space" 2026/02/23 02:15:22 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-yahipix5gubo/potato3" 2026/02/23 02:15:22 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-yahipix5gubo" 2026/02/23 02:15:22 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-yahipix5gubo0 for path "/1210146/rclone-test-yahipix5gubo/" 2026/02/23 02:15:22 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-yahipix5gubo/" 2026/02/23 02:15:22 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:23 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/23 02:15:23 DEBUG : empty space: size = 1 OK 2026/02/23 02:15:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/02/23 02:15:23 DEBUG : empty space: Unchanged skipping 2026/02/23 02:15:23 DEBUG : potato3: size = 68 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/) 2026/02/23 02:15:23 DEBUG : potato3: size = 60 (https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo/) 2026/02/23 02:15:23 DEBUG : potato3: Sizes differ 2026/02/23 02:15:23 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-quzupup9siwo/empty%20space" 2026/02/23 02:15:23 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo/: Waiting for checks to finish 2026/02/23 02:15:23 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-quzupup9siwo/potato2" 2026/02/23 02:15:23 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-quzupup9siwo/potato3" 2026/02/23 02:15:23 INFO : empty space: Deleted 2026/02/23 02:15:23 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo/: Waiting for transfers to finish 2026/02/23 02:15:23 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-yahipix5gubo/potato3" 2026/02/23 02:15:23 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-yahipix5gubo/potato2" 2026/02/23 02:15:23 DEBUG : potato3: size = 68 OK 2026/02/23 02:15:23 DEBUG : potato3: Dst hash empty - aborting Src hash check 2026/02/23 02:15:23 INFO : potato3: Copied (replaced existing) 2026/02/23 02:15:23 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-quzupup9siwo/potato3" 2026/02/23 02:15:23 DEBUG : potato2: size = 60 OK 2026/02/23 02:15:23 DEBUG : potato2: Dst hash empty - aborting Src hash check 2026/02/23 02:15:23 INFO : potato2: Copied (new) 2026/02/23 02:15:23 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-quzupup9siwo/potato2" 2026/02/23 02:15:23 INFO : potato3: Deleted 2026/02/23 02:15:24 INFO : potato2: Deleted 2026/02/23 02:15:24 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:24 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-yahipix5gubo0 for path "/1210146/rclone-test-yahipix5gubo/" 2026/02/23 02:15:24 DEBUG : Creating backend with remote "TestnStorage:rclone-test-doyiwuv1nevu" 2026/02/23 02:15:24 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/" 2026/02/23 02:15:24 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-doyiwuv1nevu" 2026/02/23 02:15:24 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu": object not found 2026/02/23 02:15:24 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-doyiwuv1nevu" 2026/02/23 02:15:24 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-yahipix5gubo/" 2026/02/23 02:15:24 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu": object not found 2026/02/23 02:15:24 DEBUG : empty space: Need to transfer - File not found at Destination 2026/02/23 02:15:24 DEBUG : potato2: Need to transfer - File not found at Destination 2026/02/23 02:15:24 DEBUG : potato3: Need to transfer - File not found at Destination 2026/02/23 02:15:24 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-yahipix5gubo/empty%20space" 2026/02/23 02:15:24 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/: Waiting for checks to finish 2026/02/23 02:15:24 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/: Waiting for transfers to finish 2026/02/23 02:15:24 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-yahipix5gubo/potato2" 2026/02/23 02:15:24 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-yahipix5gubo/potato3" 2026/02/23 02:15:24 INFO : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/ 2026/02/23 02:15:24 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-doyiwuv1nevu/" 2026/02/23 02:15:24 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-doyiwuv1nevu/potato3" 2026/02/23 02:15:25 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-doyiwuv1nevu/potato2" 2026/02/23 02:15:25 DEBUG : potato3: size = 68 OK 2026/02/23 02:15:25 DEBUG : potato3: Dst hash empty - aborting Src hash check 2026/02/23 02:15:25 INFO : potato3: Copied (new) 2026/02/23 02:15:25 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-yahipix5gubo/potato3" 2026/02/23 02:15:25 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-doyiwuv1nevu/empty%20space" 2026/02/23 02:15:25 DEBUG : potato2: size = 60 OK 2026/02/23 02:15:25 DEBUG : potato2: Dst hash empty - aborting Src hash check 2026/02/23 02:15:25 INFO : potato2: Copied (new) 2026/02/23 02:15:25 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-yahipix5gubo/potato2" 2026/02/23 02:15:25 INFO : potato3: Deleted 2026/02/23 02:15:25 INFO : potato2: Deleted 2026/02/23 02:15:25 DEBUG : NetStorage action upload failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/empty%20space": HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n" 2026/02/23 02:15:25 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-doyiwuv1nevu/empty%20space" 2026/02/23 02:15:25 DEBUG : NetStorage action delete failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/empty%20space": object not found 2026/02/23 02:15:25 ERROR : empty space: Failed to copy: failed to call NetStorage API upload: HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n" 2026/02/23 02:15:25 ERROR : empty space: Not deleting source as copy failed: failed to call NetStorage API upload: HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n" sync_test.go:1776: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1776 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1912 Error: Received unexpected error: failed to call NetStorage API upload: HTTP error 400 (400 Bad Request) returned body: "XAAA invalid\n" Test: TestServerSideMove 2026/02/23 02:15:25 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/: Purge remote 2026/02/23 02:15:25 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-doyiwuv1nevu/" 2026/02/23 02:15:26 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/23 02:15:26 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-doyiwuv1nevu" 2026/02/23 02:15:26 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-doyiwuv1nevu0 for path "/1210146/rclone-test-doyiwuv1nevu/" 2026/02/23 02:15:26 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/: Purge object "potato2" 2026/02/23 02:15:26 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-doyiwuv1nevu/potato2" 2026/02/23 02:15:26 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/: Purge object "potato3" 2026/02/23 02:15:26 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-doyiwuv1nevu/potato3" 2026/02/23 02:15:27 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-doyiwuv1nevu/: Purge dir "" 2026/02/23 02:15:27 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-doyiwuv1nevu/" 2026/02/23 02:15:27 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo/: Purge remote 2026/02/23 02:15:27 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-yahipix5gubo/" 2026/02/23 02:15:27 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/23 02:15:27 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-yahipix5gubo0 for path "/1210146/rclone-test-yahipix5gubo/" 2026/02/23 02:15:27 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo/: Purge object "empty space" 2026/02/23 02:15:27 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-yahipix5gubo/empty%20space" 2026/02/23 02:15:27 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-yahipix5gubo/: Purge dir "" 2026/02/23 02:15:27 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-yahipix5gubo/" 2026/02/23 02:15:27 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:28 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" --- FAIL: TestServerSideMove (7.36s) FAIL 2026/02/23 02:15:28 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/: Purge remote 2026/02/23 02:15:28 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:28 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/23 02:15:28 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-quzupup9siwo0 for path "/1210146/rclone-test-quzupup9siwo/" 2026/02/23 02:15:28 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-quzupup9siwo/: Purge dir "" 2026/02/23 02:15:28 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-quzupup9siwo/" "./sync.test -test.v -test.timeout 1h0m0s -remote TestnStorage: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMove|TestServerSideMoveOverSelf)$'" - Finished ERROR in 20.23779258s (try 2/5): exit status 1: Failed [TestServerSideMove]