"./sync.test -test.v -test.timeout 1h0m0s -remote TestnStorage: -verbose -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Starting (try 2/5)
2024/04/18 03:26:07 DEBUG : Creating backend with remote "TestnStorage:rclone-test-zurazep2tafasud7nisubeh6"
2024/04/18 03:26:07 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/18 03:26:07 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2024/04/18 03:26:07 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6"
2024/04/18 03:26:07 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-zurazep2tafasud7nisubeh6": object not found
2024/04/18 03:26:07 DEBUG : Creating backend with remote "/tmp/rclone3716184772"
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:180: Remote "https://rclone-nsu.akamaihd.net/1210146/rclone-test-zurazep2tafasud7nisubeh6/", Local "Local file system at /tmp/rclone3716184772", Modify Window "1s"
2024/04/18 03:26:07 DEBUG : Creating backend with remote "TestnStorage:rclone-test-rilelew9joyexex5kefunet3"
2024/04/18 03:26:07 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2024/04/18 03:26:07 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-rilelew9joyexex5kefunet3"
2024/04/18 03:26:07 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3": object not found
2024/04/18 03:26:07 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-zurazep2tafasud7nisubeh6/
2024/04/18 03:26:07 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
2024/04/18 03:26:07 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/potato2"
2024/04/18 03:26:08 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/empty%20space"
2024/04/18 03:26:08 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/potato3"
2024/04/18 03:26:08 DEBUG : tomatoDir: Making directory
2024/04/18 03:26:08 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-zurazep2tafasud7nisubeh6/tomatoDir/
2024/04/18 03:26:08 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/tomatoDir/"
2024/04/18 03:26:08 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6"
2024/04/18 03:26:08 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-zurazep2tafasud7nisubeh60 for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
    sync_test.go:1494: Server side move (if possible) https://rclone-nsu.akamaihd.net/1210146/rclone-test-zurazep2tafasud7nisubeh6/ -> https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3/
2024/04/18 03:26:08 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3/
2024/04/18 03:26:08 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:09 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/empty%20space"
2024/04/18 03:26:09 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/potato3"
2024/04/18 03:26:09 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-rilelew9joyexex5kefunet3"
2024/04/18 03:26:09 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-rilelew9joyexex5kefunet30 for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:10 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:10 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
2024/04/18 03:26:10 DEBUG : potato2: Need to transfer - File not found at Destination
2024/04/18 03:26:10 DEBUG : tomatoDir: Making directory
2024/04/18 03:26:10 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3/tomatoDir/
2024/04/18 03:26:10 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/tomatoDir/"
2024/04/18 03:26:10 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/potato2"
2024/04/18 03:26:10 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/tomatoDir"
2024/04/18 03:26:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s)
2024/04/18 03:26:10 DEBUG : empty space: Unchanged skipping
2024/04/18 03:26:10 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/04/18 03:26:10 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/potato2"
2024/04/18 03:26:10 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/empty%20space"
2024/04/18 03:26:10 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/potato3"
2024/04/18 03:26:10 INFO  : empty space: Deleted
2024/04/18 03:26:10 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3/: Waiting for checks to finish
2024/04/18 03:26:10 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3/: Waiting for transfers to finish
2024/04/18 03:26:10 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/potato3"
2024/04/18 03:26:11 DEBUG : potato2: Dst hash empty - aborting Src hash check
2024/04/18 03:26:11 INFO  : potato2: Copied (new)
2024/04/18 03:26:11 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/potato2"
2024/04/18 03:26:11 DEBUG : potato3: Dst hash empty - aborting Src hash check
2024/04/18 03:26:11 INFO  : potato3: Copied (replaced existing)
2024/04/18 03:26:11 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/potato3"
2024/04/18 03:26:11 INFO  : potato2: Deleted
2024/04/18 03:26:11 INFO  : potato3: Deleted
2024/04/18 03:26:11 INFO  : tomatoDir: Removing directory
2024/04/18 03:26:11 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/tomatoDir"
2024/04/18 03:26:11 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-zurazep2tafasud7nisubeh6/: deleted 1 directories
2024/04/18 03:26:11 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-zurazep2tafasud7nisubeh60 for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
2024/04/18 03:26:12 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-zurazep2tafasud7nisubeh60 for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
2024/04/18 03:26:12 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-rilelew9joyexex5kefunet30 for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:12 DEBUG : Creating backend with remote "TestnStorage:rclone-test-belajez4macivuh6kufiwol6"
2024/04/18 03:26:12 DEBUG : NetStorage NewFS endpoint "https://rclone-nsu.akamaihd.net/1210146/"
2024/04/18 03:26:12 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-belajez4macivuh6kufiwol6"
2024/04/18 03:26:12 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6": object not found
2024/04/18 03:26:12 DEBUG : tomatoDir: Making directory
2024/04/18 03:26:12 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-belajez4macivuh6kufiwol6"
2024/04/18 03:26:12 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:12 DEBUG : NetStorage action stat failed for "https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6": object not found
2024/04/18 03:26:12 DEBUG : empty space: Need to transfer - File not found at Destination
2024/04/18 03:26:12 DEBUG : potato2: Need to transfer - File not found at Destination
2024/04/18 03:26:12 DEBUG : potato3: Need to transfer - File not found at Destination
2024/04/18 03:26:12 DEBUG : tomatoDir: Making directory
2024/04/18 03:26:12 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/
2024/04/18 03:26:12 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/"
2024/04/18 03:26:12 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/empty%20space"
2024/04/18 03:26:12 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/potato2"
2024/04/18 03:26:12 DEBUG : NetStorage API GET call version=1&action=download for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/potato3"
2024/04/18 03:26:13 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/empty%20space"
2024/04/18 03:26:13 INFO  : Implicitly create directory https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/tomatoDir/
2024/04/18 03:26:13 DEBUG : NetStorage API POST call version=1&action=mkdir for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/tomatoDir/"
2024/04/18 03:26:13 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/potato2"
2024/04/18 03:26:13 DEBUG : NetStorage API PUT call version=1&action=upload&sha256=atend&mtime=atend for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/potato3"
2024/04/18 03:26:13 DEBUG : NetStorage API GET call version=1&action=dir&format=xml&encoding=utf-8 for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/tomatoDir"
2024/04/18 03:26:13 DEBUG : potato2: Dst hash empty - aborting Src hash check
2024/04/18 03:26:13 INFO  : potato2: Copied (new)
2024/04/18 03:26:13 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/potato2"
2024/04/18 03:26:13 DEBUG : potato3: Dst hash empty - aborting Src hash check
2024/04/18 03:26:13 INFO  : potato3: Copied (new)
2024/04/18 03:26:13 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/potato3"
2024/04/18 03:26:13 INFO  : potato2: Deleted
2024/04/18 03:26:13 INFO  : potato3: Deleted
2024/04/18 03:26:13 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/: Waiting for checks to finish
2024/04/18 03:26:13 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/: Waiting for transfers to finish
2024/04/18 03:26:13 DEBUG : empty space: Dst hash empty - aborting Src hash check
2024/04/18 03:26:13 INFO  : empty space: Copied (new)
2024/04/18 03:26:13 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/empty%20space"
2024/04/18 03:26:14 INFO  : empty space: Deleted
2024/04/18 03:26:14 INFO  : tomatoDir: Removing directory
2024/04/18 03:26:14 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/tomatoDir"
2024/04/18 03:26:14 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3/: deleted 1 directories
2024/04/18 03:26:14 DEBUG : NetStorage API GET call version=1&action=stat&implicit=yes&format=xml&encoding=utf-8&slash=both for path "/1210146/rclone-test-belajez4macivuh6kufiwol6"
2024/04/18 03:26:14 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-belajez4macivuh6kufiwol60 for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/"
2024/04/18 03:26:14 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-rilelew9joyexex5kefunet30 for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:15 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-rilelew9joyexex5kefunet30 for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:15 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/: Purge remote
2024/04/18 03:26:15 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/"
2024/04/18 03:26:15 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"
2024/04/18 03:26:15 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-belajez4macivuh6kufiwol60 for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/"
2024/04/18 03:26:15 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/: Purge object "empty space"
2024/04/18 03:26:15 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/empty%20space"
2024/04/18 03:26:15 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/: Purge object "potato2"
2024/04/18 03:26:15 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/potato2"
2024/04/18 03:26:15 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/: Purge object "potato3"
2024/04/18 03:26:15 DEBUG : NetStorage API POST call version=1&action=delete for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/potato3"
2024/04/18 03:26:16 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/: Purge dir "tomatoDir"
2024/04/18 03:26:16 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/tomatoDir"
2024/04/18 03:26:16 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-belajez4macivuh6kufiwol6/: Purge dir ""
2024/04/18 03:26:16 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-belajez4macivuh6kufiwol6/"
2024/04/18 03:26:16 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3/: Purge remote
2024/04/18 03:26:16 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:16 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"
2024/04/18 03:26:16 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-rilelew9joyexex5kefunet30 for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:16 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-rilelew9joyexex5kefunet3/: Purge dir ""
2024/04/18 03:26:16 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-rilelew9joyexex5kefunet3/"
2024/04/18 03:26:16 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-zurazep2tafasud7nisubeh60 for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
2024/04/18 03:26:17 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-zurazep2tafasud7nisubeh60 for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (10.01s)
PASS
2024/04/18 03:26:17 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-zurazep2tafasud7nisubeh6/: Purge remote
2024/04/18 03:26:17 DEBUG : NetStorage API POST call version=1&action=quick-delete&quick-delete=imreallyreallysure for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
2024/04/18 03:26:17 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"
2024/04/18 03:26:17 DEBUG : NetStorage API GET call version=1&action=list&mtime_all=yes&format=xml&encoding=utf-8&end=%2F1210146%2Frclone-test-zurazep2tafasud7nisubeh60 for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
2024/04/18 03:26:17 DEBUG : https://rclone-nsu.akamaihd.net/1210146/rclone-test-zurazep2tafasud7nisubeh6/: Purge dir ""
2024/04/18 03:26:17 DEBUG : NetStorage API POST call version=1&action=rmdir for path "/1210146/rclone-test-zurazep2tafasud7nisubeh6/"
"./sync.test -test.v -test.timeout 1h0m0s -remote TestnStorage: -verbose -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Finished OK in 10.842649509s (try 2/5)