"./sync.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Starting (try 2/5)
2022/05/18 05:45:17 DEBUG : Creating backend with remote "TestStorj:rclone-test-ricujaf0kujawac4fudequh2"
2022/05/18 05:45:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/05/18 05:45:17 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: connecting...
2022/05/18 05:45:17 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: connected: <nil>
2022/05/18 05:45:17 DEBUG : Creating backend with remote "/tmp/rclone2692402843"
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:181: Remote "FS sj://rclone-test-ricujaf0kujawac4fudequh2", Local "Local file system at /tmp/rclone2692402843", Modify Window "1ns"
2022/05/18 05:45:17 DEBUG : Creating backend with remote "TestStorj:rclone-test-qegesuc8taqanog6huhapev4"
2022/05/18 05:45:17 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: connecting...
2022/05/18 05:45:17 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: connected: <nil>
2022/05/18 05:45:17 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: mkdir -p ./
2022/05/18 05:45:17 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: cp input ./potato2 # [] 60
2022/05/18 05:45:17 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: cp input ./empty space # [] 1
2022/05/18 05:45:17 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: cp input ./potato3 # [] 68
2022/05/18 05:45:18 DEBUG : tomatoDir: Making directory
2022/05/18 05:45:18 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: mkdir -p ./tomatoDir
2022/05/18 05:45:18 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: ls -R ./
2022/05/18 05:45:18 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: OBJ ls -R ./ ("rclone-test-ricujaf0kujawac4fudequh2", "")
    sync_test.go:1215: Server side move (if possible) FS sj://rclone-test-ricujaf0kujawac4fudequh2 -> FS sj://rclone-test-qegesuc8taqanog6huhapev4
2022/05/18 05:45:18 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: mkdir -p ./
2022/05/18 05:45:18 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: cp input ./empty space # [] 1
2022/05/18 05:45:18 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: cp input ./potato3 # [] 60
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: ls -R ./
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: OBJ ls -R ./ ("rclone-test-qegesuc8taqanog6huhapev4", "")
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: ls ./
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: OBJ ls ./ ("rclone-test-qegesuc8taqanog6huhapev4", "")
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: ls ./
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: OBJ ls ./ ("rclone-test-ricujaf0kujawac4fudequh2", "")
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2022/05/18 05:45:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/05/18 05:45:19 DEBUG : empty space: Unchanged skipping
2022/05/18 05:45:19 DEBUG : empty space: rm sj://rclone-test-ricujaf0kujawac4fudequh2/empty space
2022/05/18 05:45:19 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/05/18 05:45:19 DEBUG : potato3: rm sj://rclone-test-qegesuc8taqanog6huhapev4/potato3
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: Waiting for checks to finish
2022/05/18 05:45:19 INFO  : empty space: Deleted
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: Waiting for transfers to finish
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: stat ./potato2
2022/05/18 05:45:19 INFO  : potato2: Moved (server-side)
2022/05/18 05:45:19 INFO  : potato3: Deleted
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: stat ./potato3
2022/05/18 05:45:19 INFO  : potato3: Moved (server-side)
2022/05/18 05:45:19 INFO  : There was nothing to transfer
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: ls -R ./
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: OBJ ls -R ./ ("rclone-test-ricujaf0kujawac4fudequh2", "")
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: ls -R ./
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: OBJ ls -R ./ ("rclone-test-ricujaf0kujawac4fudequh2", "")
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: ls -R ./
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: OBJ ls -R ./ ("rclone-test-qegesuc8taqanog6huhapev4", "")
2022/05/18 05:45:19 DEBUG : Creating backend with remote "TestStorj:rclone-test-vujuqis5hutetoc3huwurel4"
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: connecting...
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: connected: <nil>
2022/05/18 05:45:19 DEBUG : tomatoDir: Making directory
2022/05/18 05:45:19 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: mkdir -p ./tomatoDir
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: ls ./
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: OBJ ls ./ ("rclone-test-vujuqis5hutetoc3huwurel4", "")
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: ls ./
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: OBJ ls ./ ("rclone-test-qegesuc8taqanog6huhapev4", "")
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: Waiting for checks to finish
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: Waiting for transfers to finish
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: stat ./empty space
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: stat ./potato3
2022/05/18 05:45:20 INFO  : empty space: Moved (server-side)
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: stat ./potato2
2022/05/18 05:45:20 INFO  : potato3: Moved (server-side)
2022/05/18 05:45:20 INFO  : potato2: Moved (server-side)
2022/05/18 05:45:20 INFO  : There was nothing to transfer
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: ls -R ./
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: OBJ ls -R ./ ("rclone-test-vujuqis5hutetoc3huwurel4", "")
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: ls -R ./
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: OBJ ls -R ./ ("rclone-test-qegesuc8taqanog6huhapev4", "")
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: ls -R ./
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: OBJ ls -R ./ ("rclone-test-qegesuc8taqanog6huhapev4", "")
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: ls -R ./
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: OBJ ls -R ./ ("rclone-test-vujuqis5hutetoc3huwurel4", "")
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: Purge object "potato2"
2022/05/18 05:45:20 DEBUG : potato2: rm sj://rclone-test-vujuqis5hutetoc3huwurel4/potato2
2022/05/18 05:45:20 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: Purge object "empty space"
2022/05/18 05:45:20 DEBUG : empty space: rm sj://rclone-test-vujuqis5hutetoc3huwurel4/empty space
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: Purge object "potato3"
2022/05/18 05:45:21 DEBUG : potato3: rm sj://rclone-test-vujuqis5hutetoc3huwurel4/potato3
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: Purge dir ""
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-vujuqis5hutetoc3huwurel4: rmdir ./
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: ls -R ./
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: OBJ ls -R ./ ("rclone-test-qegesuc8taqanog6huhapev4", "")
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: Purge dir ""
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-qegesuc8taqanog6huhapev4: rmdir ./
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: ls -R ./
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: OBJ ls -R ./ ("rclone-test-ricujaf0kujawac4fudequh2", "")
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: ls -R ./
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: OBJ ls -R ./ ("rclone-test-ricujaf0kujawac4fudequh2", "")
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (4.45s)
PASS
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: ls -R ./
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: OBJ ls -R ./ ("rclone-test-ricujaf0kujawac4fudequh2", "")
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: Purge dir ""
2022/05/18 05:45:21 DEBUG : FS sj://rclone-test-ricujaf0kujawac4fudequh2: rmdir ./
"./sync.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -test.run '^TestServerSideMoveDeleteEmptySourceDirs$'" - Finished OK in 4.689266154s (try 2/5)