"./vfs.test -test.v -test.timeout 30m0s -remote TestS3DigitalOcean: -verbose -test.run '^(TestFileRename|TestVFSStatfs)$/^CacheModeFull$'" - Starting (try 2/5)
=== RUN   TestFileRename
=== RUN   TestFileRename/CacheModeFull
2020/04/15 05:26:25 INFO  : S3 bucket rclone-test-baquxel9noqezav9duculat8: poll-interval is not supported by this remote
2020/04/15 05:26:25 DEBUG : vfs cache root is "/home/rclone/.cache/rclone/vfs/TestS3DigitalOcean/rclone-test-baquxel9noqezav9duculat8"
2020/04/15 05:26:25 DEBUG : Adding path "vfs/forget" to remote control registry
2020/04/15 05:26:25 DEBUG : Adding path "vfs/refresh" to remote control registry
2020/04/15 05:26:25 DEBUG : Adding path "vfs/poll-interval" to remote control registry
2020/04/15 05:26:27 INFO  : S3 bucket rclone-test-baquxel9noqezav9duculat8: Bucket "rclone-test-baquxel9noqezav9duculat8" created with ACL "private"
2020/04/15 05:26:27 DEBUG : dir/file1: Open: flags=O_RDONLY
2020/04/15 05:26:27 DEBUG : dir/file1: >Open: fd=dir/file1 (rw), err=<nil>
2020/04/15 05:26:27 DEBUG : dir/file1: Need to transfer - File not found at Destination
2020/04/15 05:26:27 DEBUG : dir/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2020/04/15 05:26:27 INFO  : dir/file1: Copied (new)
2020/04/15 05:26:27 DEBUG : dir/file1(0xc0005c0b40): Opening cached copy with flags=O_RDONLY
2020/04/15 05:26:27 DEBUG : dir/file1(0xc0005c0b40): close: 
2020/04/15 05:26:27 DEBUG : dir/file1(0xc0005c0b40): >close: err=<nil>
2020/04/15 05:26:27 INFO  : dir/file1: Renamed in cache
2020/04/15 05:26:28 DEBUG : dir/file1: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2020/04/15 05:26:28 INFO  : dir/file1: Copied (server side copy)
2020/04/15 05:26:28 INFO  : dir/file1: Deleted
2020/04/15 05:26:28 DEBUG : dir/file1: Updating file with newLeaf 0xc00031e000
2020/04/15 05:26:28 INFO  : newLeaf: Renamed in cache
2020/04/15 05:26:28 DEBUG : newLeaf: MD5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2020/04/15 05:26:28 INFO  : newLeaf: Copied (server side copy)
2020/04/15 05:26:28 INFO  : newLeaf: Deleted
2020/04/15 05:26:28 DEBUG : newLeaf: Updating file with dir/file1 0xc00031e000
2020/04/15 05:26:28 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC
2020/04/15 05:26:28 DEBUG : dir/file1(0xc000081bc0): Opening cached copy with flags=O_WRONLY|O_TRUNC
2020/04/15 05:26:28 DEBUG : dir/file1: >Open: fd=dir/file1 (rw), err=<nil>
2020/04/15 05:26:28 INFO  : dir/file1: Renamed in cache
2020/04/15 05:26:28 DEBUG : dir/file1: File is currently open, delaying rename 0xc00031e000
2020/04/15 05:26:28 DEBUG : newLeaf(0xc000081bc0): close: 
2020/04/15 05:26:28 DEBUG : newLeaf: Sizes differ (src 25 vs dst 14)
2020/04/15 05:26:28 DEBUG : newLeaf: MD5 = c7247bead226dfd7bd970c17229f4044 OK
2020/04/15 05:26:28 INFO  : newLeaf: Copied (replaced existing)
2020/04/15 05:26:28 DEBUG : dir/file1: transferred to remote
2020/04/15 05:26:28 DEBUG : dir/file1: Running delayed rename now
2020/04/15 05:26:28 DEBUG : pacer: low level retry 1/10 (error SlowDown: Please reduce your request rate.
	status code: 503, request id: , host id: )
2020/04/15 05:26:28 DEBUG : pacer: Rate limited, increasing sleep to 10ms
2020/04/15 05:26:28 DEBUG : pacer: low level retry 2/10 (error SlowDown: Please reduce your request rate.
	status code: 503, request id: , host id: )
2020/04/15 05:26:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2020/04/15 05:26:28 DEBUG : pacer: Reducing sleep to 15ms
2020/04/15 05:26:28 DEBUG : pacer: Reducing sleep to 11.25ms
2020/04/15 05:26:28 DEBUG : dir/file1: MD5 = c7247bead226dfd7bd970c17229f4044 OK
2020/04/15 05:26:28 INFO  : dir/file1: Copied (server side copy)
2020/04/15 05:26:28 DEBUG : pacer: Reducing sleep to 0s
2020/04/15 05:26:28 INFO  : dir/file1: Deleted
2020/04/15 05:26:28 DEBUG : dir/file1: Updating file with newLeaf 0xc00031e000
2020/04/15 05:26:28 DEBUG : newLeaf(0xc000081bc0): >close: err=<nil>
--- PASS: TestFileRename (3.84s)
    --- PASS: TestFileRename/CacheModeFull (3.84s)
        run.go:176: Remote "S3 bucket rclone-test-baquxel9noqezav9duculat8", Local "Local file system at /tmp/rclone929516976", Modify Window "1ns"
=== RUN   TestVFSStatfs
2020/04/15 05:26:29 INFO  : S3 bucket rclone-test-baquxel9noqezav9duculat8: poll-interval is not supported by this remote
2020/04/15 05:26:29 DEBUG : Adding path "vfs/forget" to remote control registry
2020/04/15 05:26:29 DEBUG : Adding path "vfs/refresh" to remote control registry
2020/04/15 05:26:29 DEBUG : Adding path "vfs/poll-interval" to remote control registry
--- FAIL: TestVFSStatfs (0.11s)
    run.go:176: Remote "S3 bucket rclone-test-baquxel9noqezav9duculat8", Local "Local file system at /tmp/rclone929516976", Modify Window "1ns"
    vfs_test.go:282: 
        	Error Trace:	vfs_test.go:282
        	Error:      	Not equal: 
        	            	expected: -1
        	            	actual  : 1125899906842624
        	Test:       	TestVFSStatfs
    vfs_test.go:283: 
        	Error Trace:	vfs_test.go:283
        	Error:      	Not equal: 
        	            	expected: -1
        	            	actual  : 1125899906842624
        	Test:       	TestVFSStatfs
    vfs_test.go:284: 
        	Error Trace:	vfs_test.go:284
        	Error:      	Not equal: 
        	            	expected: -1
        	            	actual  : 0
        	Test:       	TestVFSStatfs
FAIL
2020/04/15 05:26:29 DEBUG : S3 bucket rclone-test-baquxel9noqezav9duculat8: Purge dir ""
2020/04/15 05:26:31 INFO  : S3 bucket rclone-test-baquxel9noqezav9duculat8: Bucket "rclone-test-baquxel9noqezav9duculat8" deleted
"./vfs.test -test.v -test.timeout 30m0s -remote TestS3DigitalOcean: -verbose -test.run '^(TestFileRename|TestVFSStatfs)$/^CacheModeFull$'" - Finished ERROR in 6.49770431s (try 2/5): exit status 1: Failed [TestVFSStatfs]