"./sync.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Starting (try 3/5)
2024/11/14 18:30:54 DEBUG : Creating backend with remote "TestStorj:rclone-test-hequneb5sipo"
2024/11/14 18:30:54 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/14 18:30:54 DEBUG : FS sj://rclone-test-hequneb5sipo: connecting...
2024/11/14 18:30:54 DEBUG : FS sj://rclone-test-hequneb5sipo: connected: <nil>
2024/11/14 18:30:54 DEBUG : Creating backend with remote "/tmp/rclone523115831"
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:180: Remote "FS sj://rclone-test-hequneb5sipo", Local "Local file system at /tmp/rclone523115831", Modify Window "1ns"
2024/11/14 18:30:54 DEBUG : Creating backend with remote "TestStorj:rclone-test-letedar8quza"
2024/11/14 18:30:54 DEBUG : FS sj://rclone-test-letedar8quza: connecting...
2024/11/14 18:30:54 DEBUG : FS sj://rclone-test-letedar8quza: connected: <nil>
2024/11/14 18:30:54 DEBUG : FS sj://rclone-test-hequneb5sipo: mkdir -p ./
2024/11/14 18:30:55 DEBUG : FS sj://rclone-test-hequneb5sipo: cp input ./potato2 # [] 60
2024/11/14 18:30:55 DEBUG : FS sj://rclone-test-hequneb5sipo: cp input ./empty space # [] 1
2024/11/14 18:30:55 DEBUG : FS sj://rclone-test-hequneb5sipo: cp input ./potato3 # [] 68
2024/11/14 18:30:55 DEBUG : tomatoDir: Making directory
2024/11/14 18:30:55 DEBUG : FS sj://rclone-test-hequneb5sipo: mkdir -p ./tomatoDir
2024/11/14 18:30:55 DEBUG : FS sj://rclone-test-hequneb5sipo: ls -R ./
2024/11/14 18:30:55 DEBUG : FS sj://rclone-test-hequneb5sipo: OBJ ls -R ./ ("rclone-test-hequneb5sipo", "")
    sync_test.go:1603: Server side move (if possible) FS sj://rclone-test-hequneb5sipo -> FS sj://rclone-test-letedar8quza
2024/11/14 18:30:55 DEBUG : FS sj://rclone-test-letedar8quza: mkdir -p ./
2024/11/14 18:30:56 DEBUG : FS sj://rclone-test-letedar8quza: cp input ./empty space # [] 1
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: cp input ./potato3 # [] 60
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: ls -R ./
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: OBJ ls -R ./ ("rclone-test-letedar8quza", "")
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: ls ./
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: OBJ ls ./ ("rclone-test-letedar8quza", "")
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-hequneb5sipo: ls ./
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-hequneb5sipo: OBJ ls ./ ("rclone-test-hequneb5sipo", "")
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-hequneb5sipo: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:57 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/14 18:30:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/14 18:30:57 DEBUG : empty space: Unchanged skipping
2024/11/14 18:30:57 DEBUG : empty space: rm sj://rclone-test-hequneb5sipo/empty space
2024/11/14 18:30:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/11/14 18:30:57 DEBUG : potato3: rm sj://rclone-test-letedar8quza/potato3
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: Waiting for checks to finish
2024/11/14 18:30:57 INFO  : empty space: Deleted
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: Waiting for transfers to finish
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: stat ./potato2
2024/11/14 18:30:57 INFO  : potato3: Deleted
2024/11/14 18:30:57 INFO  : potato2: Moved (server-side)
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: stat ./potato3
2024/11/14 18:30:57 INFO  : potato3: Moved (server-side)
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-hequneb5sipo: ls -R ./
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-hequneb5sipo: OBJ ls -R ./ ("rclone-test-hequneb5sipo", "")
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-hequneb5sipo: ls -R ./
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-hequneb5sipo: OBJ ls -R ./ ("rclone-test-hequneb5sipo", "")
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: ls -R ./
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: OBJ ls -R ./ ("rclone-test-letedar8quza", "")
2024/11/14 18:30:57 DEBUG : Creating backend with remote "TestStorj:rclone-test-goholes7baba"
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-goholes7baba: connecting...
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-goholes7baba: connected: <nil>
2024/11/14 18:30:57 DEBUG : tomatoDir: Making directory
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: mkdir -p ./tomatoDir
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-goholes7baba: ls ./
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-goholes7baba: OBJ ls ./ ("rclone-test-goholes7baba", "")
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-goholes7baba: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: ls ./
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: OBJ ls ./ ("rclone-test-letedar8quza", "")
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-letedar8quza: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:57 DEBUG : empty space: Need to transfer - File not found at Destination
2024/11/14 18:30:57 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/14 18:30:57 DEBUG : potato3: Need to transfer - File not found at Destination
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-goholes7baba: Waiting for checks to finish
2024/11/14 18:30:57 DEBUG : FS sj://rclone-test-goholes7baba: Waiting for transfers to finish
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-goholes7baba: stat ./potato2
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-goholes7baba: stat ./potato3
2024/11/14 18:30:58 INFO  : potato2: Moved (server-side)
2024/11/14 18:30:58 INFO  : potato3: Moved (server-side)
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-goholes7baba: stat ./empty space
2024/11/14 18:30:58 INFO  : empty space: Moved (server-side)
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-goholes7baba: ls -R ./
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-goholes7baba: OBJ ls -R ./ ("rclone-test-goholes7baba", "")
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-letedar8quza: ls -R ./
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-letedar8quza: OBJ ls -R ./ ("rclone-test-letedar8quza", "")
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-letedar8quza: ls -R ./
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-letedar8quza: OBJ ls -R ./ ("rclone-test-letedar8quza", "")
2024/11/14 18:30:58 DEBUG : FS sj://rclone-test-goholes7baba: Purge remote
2024/11/14 18:30:59 DEBUG : FS sj://rclone-test-letedar8quza: Purge remote
2024/11/14 18:30:59 DEBUG : FS sj://rclone-test-hequneb5sipo: ls -R ./
2024/11/14 18:30:59 DEBUG : FS sj://rclone-test-hequneb5sipo: OBJ ls -R ./ ("rclone-test-hequneb5sipo", "")
2024/11/14 18:30:59 DEBUG : FS sj://rclone-test-hequneb5sipo: ls -R ./
2024/11/14 18:30:59 DEBUG : FS sj://rclone-test-hequneb5sipo: OBJ ls -R ./ ("rclone-test-hequneb5sipo", "")
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (5.29s)
PASS
2024/11/14 18:30:59 DEBUG : FS sj://rclone-test-hequneb5sipo: Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Finished OK in 5.846141709s (try 3/5)