"./sync.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs)$'" - Starting (try 2/5)
2024/11/14 18:30:43 DEBUG : Creating backend with remote "TestStorj:rclone-test-cecusem9hiso"
2024/11/14 18:30:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/11/14 18:30:43 DEBUG : FS sj://rclone-test-cecusem9hiso: connecting...
2024/11/14 18:30:43 DEBUG : FS sj://rclone-test-cecusem9hiso: connected: <nil>
2024/11/14 18:30:43 DEBUG : Creating backend with remote "/tmp/rclone3984519866"
=== RUN   TestServerSideMove
    run.go:180: Remote "FS sj://rclone-test-cecusem9hiso", Local "Local file system at /tmp/rclone3984519866", Modify Window "1ns"
2024/11/14 18:30:43 DEBUG : Creating backend with remote "TestStorj:rclone-test-wajuxon6pano"
2024/11/14 18:30:43 DEBUG : FS sj://rclone-test-wajuxon6pano: connecting...
2024/11/14 18:30:43 DEBUG : FS sj://rclone-test-wajuxon6pano: connected: <nil>
2024/11/14 18:30:43 DEBUG : FS sj://rclone-test-cecusem9hiso: mkdir -p ./
2024/11/14 18:30:44 DEBUG : FS sj://rclone-test-cecusem9hiso: cp input ./potato2 # [] 60
2024/11/14 18:30:45 DEBUG : FS sj://rclone-test-cecusem9hiso: cp input ./empty space # [] 1
2024/11/14 18:30:45 DEBUG : FS sj://rclone-test-cecusem9hiso: cp input ./potato3 # [] 68
2024/11/14 18:30:45 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:45 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
    sync_test.go:1603: Server side move (if possible) FS sj://rclone-test-cecusem9hiso -> FS sj://rclone-test-wajuxon6pano
2024/11/14 18:30:45 DEBUG : FS sj://rclone-test-wajuxon6pano: mkdir -p ./
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: cp input ./empty space # [] 1
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: cp input ./potato3 # [] 60
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: ls -R ./
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: OBJ ls -R ./ ("rclone-test-wajuxon6pano", "")
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: ls ./
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: OBJ ls ./ ("rclone-test-wajuxon6pano", "")
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-cecusem9hiso: ls ./
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls ./ ("rclone-test-cecusem9hiso", "")
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-cecusem9hiso: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:46 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/14 18:30:46 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/14 18:30:46 DEBUG : empty space: Unchanged skipping
2024/11/14 18:30:46 DEBUG : empty space: rm sj://rclone-test-cecusem9hiso/empty space
2024/11/14 18:30:46 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/11/14 18:30:46 DEBUG : potato3: rm sj://rclone-test-wajuxon6pano/potato3
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: Waiting for checks to finish
2024/11/14 18:30:46 INFO  : empty space: Deleted
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: Waiting for transfers to finish
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: stat ./potato2
2024/11/14 18:30:46 INFO  : potato3: Deleted
2024/11/14 18:30:46 INFO  : potato2: Moved (server-side)
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-wajuxon6pano: stat ./potato3
2024/11/14 18:30:46 INFO  : potato3: Moved (server-side)
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:46 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-wajuxon6pano: ls -R ./
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-wajuxon6pano: OBJ ls -R ./ ("rclone-test-wajuxon6pano", "")
2024/11/14 18:30:47 DEBUG : Creating backend with remote "TestStorj:rclone-test-heluloz8haqa"
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-heluloz8haqa: connecting...
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-heluloz8haqa: connected: <nil>
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-heluloz8haqa: ls ./
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-heluloz8haqa: OBJ ls ./ ("rclone-test-heluloz8haqa", "")
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-wajuxon6pano: ls ./
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-wajuxon6pano: OBJ ls ./ ("rclone-test-wajuxon6pano", "")
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-heluloz8haqa: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-wajuxon6pano: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:47 DEBUG : empty space: Need to transfer - File not found at Destination
2024/11/14 18:30:47 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/14 18:30:47 DEBUG : potato3: Need to transfer - File not found at Destination
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-heluloz8haqa: Waiting for checks to finish
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-heluloz8haqa: Waiting for transfers to finish
2024/11/14 18:30:47 DEBUG : FS sj://rclone-test-heluloz8haqa: stat ./potato3
2024/11/14 18:30:47 INFO  : potato3: Moved (server-side)
2024/11/14 18:30:48 DEBUG : FS sj://rclone-test-heluloz8haqa: stat ./empty space
2024/11/14 18:30:48 INFO  : empty space: Moved (server-side)
2024/11/14 18:30:48 DEBUG : FS sj://rclone-test-heluloz8haqa: stat ./potato2
2024/11/14 18:30:48 INFO  : potato2: Moved (server-side)
2024/11/14 18:30:48 DEBUG : FS sj://rclone-test-heluloz8haqa: ls -R ./
2024/11/14 18:30:48 DEBUG : FS sj://rclone-test-heluloz8haqa: OBJ ls -R ./ ("rclone-test-heluloz8haqa", "")
2024/11/14 18:30:48 DEBUG : FS sj://rclone-test-wajuxon6pano: ls -R ./
2024/11/14 18:30:48 DEBUG : FS sj://rclone-test-wajuxon6pano: OBJ ls -R ./ ("rclone-test-wajuxon6pano", "")
2024/11/14 18:30:48 DEBUG : FS sj://rclone-test-heluloz8haqa: Purge remote
2024/11/14 18:30:48 DEBUG : FS sj://rclone-test-wajuxon6pano: Purge remote
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
--- PASS: TestServerSideMove (5.48s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:180: Remote "FS sj://rclone-test-cecusem9hiso", Local "Local file system at /tmp/rclone3984519866", Modify Window "1ns"
2024/11/14 18:30:49 DEBUG : Creating backend with remote "TestStorj:rclone-test-jitokaz3xage"
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-jitokaz3xage: connecting...
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-jitokaz3xage: connected: <nil>
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: cp input ./potato2 # [] 60
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: cp input ./empty space # [] 1
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: cp input ./potato3 # [] 68
2024/11/14 18:30:49 DEBUG : tomatoDir: Making directory
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: mkdir -p ./tomatoDir
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
    sync_test.go:1603: Server side move (if possible) FS sj://rclone-test-cecusem9hiso -> FS sj://rclone-test-jitokaz3xage
2024/11/14 18:30:49 DEBUG : FS sj://rclone-test-jitokaz3xage: mkdir -p ./
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-jitokaz3xage: cp input ./empty space # [] 1
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-jitokaz3xage: cp input ./potato3 # [] 60
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-jitokaz3xage: ls -R ./
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-jitokaz3xage: OBJ ls -R ./ ("rclone-test-jitokaz3xage", "")
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-jitokaz3xage: ls ./
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-jitokaz3xage: OBJ ls ./ ("rclone-test-jitokaz3xage", "")
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-cecusem9hiso: ls ./
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-jitokaz3xage: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls ./ ("rclone-test-cecusem9hiso", "")
2024/11/14 18:30:50 DEBUG : FS sj://rclone-test-cecusem9hiso: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:51 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/14 18:30:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2024/11/14 18:30:51 DEBUG : empty space: Unchanged skipping
2024/11/14 18:30:51 DEBUG : empty space: rm sj://rclone-test-cecusem9hiso/empty space
2024/11/14 18:30:51 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2024/11/14 18:30:51 DEBUG : potato3: rm sj://rclone-test-jitokaz3xage/potato3
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: Waiting for checks to finish
2024/11/14 18:30:51 INFO  : empty space: Deleted
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: Waiting for transfers to finish
2024/11/14 18:30:51 INFO  : potato3: Deleted
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: stat ./potato2
2024/11/14 18:30:51 INFO  : potato2: Moved (server-side)
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: stat ./potato3
2024/11/14 18:30:51 INFO  : potato3: Moved (server-side)
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: ls -R ./
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: OBJ ls -R ./ ("rclone-test-jitokaz3xage", "")
2024/11/14 18:30:51 DEBUG : Creating backend with remote "TestStorj:rclone-test-tegubif2rewu"
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-tegubif2rewu: connecting...
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-tegubif2rewu: connected: <nil>
2024/11/14 18:30:51 DEBUG : tomatoDir: Making directory
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: mkdir -p ./tomatoDir
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: ls ./
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: OBJ ls ./ ("rclone-test-jitokaz3xage", "")
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-jitokaz3xage: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-tegubif2rewu: ls ./
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-tegubif2rewu: OBJ ls ./ ("rclone-test-tegubif2rewu", "")
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-tegubif2rewu: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2024/11/14 18:30:51 DEBUG : empty space: Need to transfer - File not found at Destination
2024/11/14 18:30:51 DEBUG : potato2: Need to transfer - File not found at Destination
2024/11/14 18:30:51 DEBUG : potato3: Need to transfer - File not found at Destination
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-tegubif2rewu: Waiting for checks to finish
2024/11/14 18:30:51 DEBUG : FS sj://rclone-test-tegubif2rewu: Waiting for transfers to finish
2024/11/14 18:30:51 ERROR : empty space: Couldn't move: rename object failed: uplink: bucket not found ("rclone-test-tegubif2rewu")
2024/11/14 18:30:51 ERROR : potato3: Couldn't move: rename object failed: uplink: bucket not found ("rclone-test-tegubif2rewu")
2024/11/14 18:30:52 DEBUG : FS sj://rclone-test-tegubif2rewu: stat ./potato2
2024/11/14 18:30:52 INFO  : potato2: Moved (server-side)
    sync_test.go:1643: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1643
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1799
        	Error:      	Received unexpected error:
        	            	rename object failed: uplink: bucket not found ("rclone-test-tegubif2rewu")
        	Test:       	TestServerSideMoveDeleteEmptySourceDirs
2024/11/14 18:30:52 DEBUG : FS sj://rclone-test-tegubif2rewu: Purge remote
2024/11/14 18:30:53 DEBUG : FS sj://rclone-test-jitokaz3xage: Purge remote
2024/11/14 18:30:53 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:53 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
2024/11/14 18:30:54 DEBUG : FS sj://rclone-test-cecusem9hiso: ls -R ./
2024/11/14 18:30:54 DEBUG : FS sj://rclone-test-cecusem9hiso: OBJ ls -R ./ ("rclone-test-cecusem9hiso", "")
--- FAIL: TestServerSideMoveDeleteEmptySourceDirs (4.68s)
FAIL
2024/11/14 18:30:54 DEBUG : FS sj://rclone-test-cecusem9hiso: Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs)$'" - Finished ERROR in 10.668830655s (try 2/5): exit status 1: Failed [TestServerSideMoveDeleteEmptySourceDirs]