"./vfs.test -test.v -test.timeout 1h0m0s -remote TestS3MinioEdge: -verbose -test.run '^(TestDirForgetAll|TestDirHandleMethods|TestDirHandleReaddirnames|TestRcGetVFS|TestVFSNew)$'" - Starting (try 2/5)
2021/07/25 05:47:18 DEBUG : Creating backend with remote "TestS3MinioEdge:rclone-test-yukiyoz1cidefer4tujovib5"
2021/07/25 05:47:18 DEBUG : Setting type="s3" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_TYPE
2021/07/25 05:47:18 DEBUG : Setting provider="Minio" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_PROVIDER
2021/07/25 05:47:18 DEBUG : Setting access_key_id="rclone" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ACCESS_KEY_ID
2021/07/25 05:47:18 DEBUG : Setting secret_access_key="DeniseOxygenEiffel4" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_SECRET_ACCESS_KEY
2021/07/25 05:47:18 DEBUG : Setting endpoint="http://172.17.0.3:9000/" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ENDPOINT
2021/07/25 05:47:18 DEBUG : Setting endpoint="http://172.17.0.3:9000/" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ENDPOINT
2021/07/25 05:47:18 DEBUG : Setting endpoint="http://172.17.0.3:9000/" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ENDPOINT
2021/07/25 05:47:18 DEBUG : Setting endpoint="http://172.17.0.3:9000/" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ENDPOINT
2021/07/25 05:47:18 DEBUG : Setting endpoint="http://172.17.0.3:9000/" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ENDPOINT
2021/07/25 05:47:18 DEBUG : Setting endpoint="http://172.17.0.3:9000/" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ENDPOINT
2021/07/25 05:47:18 DEBUG : Setting endpoint="http://172.17.0.3:9000/" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ENDPOINT
2021/07/25 05:47:18 DEBUG : TestS3MinioEdge: detected overridden config - adding "{Dqa1-}" suffix to name
2021/07/25 05:47:18 DEBUG : Setting provider="Minio" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_PROVIDER
2021/07/25 05:47:18 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2021/07/25 05:47:18 DEBUG : Setting access_key_id="rclone" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ACCESS_KEY_ID
2021/07/25 05:47:18 DEBUG : Setting secret_access_key="DeniseOxygenEiffel4" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_SECRET_ACCESS_KEY
2021/07/25 05:47:18 DEBUG : Setting endpoint="http://172.17.0.3:9000/" for "TestS3MinioEdge" from environment variable RCLONE_CONFIG_TESTS3MINIOEDGE_ENDPOINT
2021/07/25 05:47:18 DEBUG : Creating backend with remote "/tmp/rclone578104470"
=== RUN   TestDirHandleMethods
    run.go:176: Remote "S3 bucket rclone-test-yukiyoz1cidefer4tujovib5", Local "Local file system at /tmp/rclone578104470", Modify Window "1ns"
2021/07/25 05:47:18 INFO  : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: poll-interval is not supported by this remote
2021/07/25 05:47:18 DEBUG : pacer: low level retry 1/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F2899E8A9BDA, host id: )
2021/07/25 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 10ms
2021/07/25 05:47:18 DEBUG : pacer: low level retry 2/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F2899EA2C08E, host id: )
2021/07/25 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2021/07/25 05:47:18 DEBUG : pacer: low level retry 3/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F289A4C49637, host id: )
2021/07/25 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2021/07/25 05:47:18 DEBUG : pacer: low level retry 4/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F289A4EA171F, host id: )
2021/07/25 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2021/07/25 05:47:18 DEBUG : pacer: low level retry 5/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F289A8ABDE87, host id: )
2021/07/25 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2021/07/25 05:47:18 DEBUG : pacer: low level retry 6/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F289AC41DCE0, host id: )
2021/07/25 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2021/07/25 05:47:18 DEBUG : pacer: low level retry 7/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F289B813210C, host id: )
2021/07/25 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2021/07/25 05:47:18 DEBUG : pacer: low level retry 8/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F289C8E3A3EC, host id: )
2021/07/25 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2021/07/25 05:47:19 DEBUG : pacer: low level retry 9/10 (error XMinioServerNotInitialized: Server not initialized, please try again.
	status code: 503, request id: 1694F289EFF963BF, host id: )
2021/07/25 05:47:19 DEBUG : pacer: Rate limited, increasing sleep to 2s
2021/07/25 05:47:20 DEBUG : pacer: Reducing sleep to 1.5s
2021/07/25 05:47:20 INFO  : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: Bucket "rclone-test-yukiyoz1cidefer4tujovib5" created with ACL "private"
2021/07/25 05:47:22 DEBUG : pacer: Reducing sleep to 1.125s
2021/07/25 05:47:24 DEBUG : pacer: Reducing sleep to 843.75ms
2021/07/25 05:47:25 DEBUG : pacer: Reducing sleep to 632.8125ms
2021/07/25 05:47:26 DEBUG : pacer: Reducing sleep to 474.609375ms
2021/07/25 05:47:27 DEBUG : pacer: Reducing sleep to 355.957031ms
2021/07/25 05:47:27 DEBUG : WaitForWriters: timeout=10s
2021/07/25 05:47:27 DEBUG : dir: Looking for writers
2021/07/25 05:47:27 DEBUG : : Looking for writers
2021/07/25 05:47:27 DEBUG : dir: reading active writers
2021/07/25 05:47:27 DEBUG : >WaitForWriters: 
2021/07/25 05:47:27 DEBUG : pacer: Reducing sleep to 266.967773ms
2021/07/25 05:47:27 DEBUG : pacer: Reducing sleep to 200.225829ms
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 150.169371ms
--- PASS: TestDirHandleMethods (9.87s)
=== RUN   TestDirHandleReaddirnames
    run.go:176: Remote "S3 bucket rclone-test-yukiyoz1cidefer4tujovib5", Local "Local file system at /tmp/rclone578104470", Modify Window "1ns"
2021/07/25 05:47:28 INFO  : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: poll-interval is not supported by this remote
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 112.627028ms
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 84.470271ms
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 63.352703ms
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 47.514527ms
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 35.635895ms
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 26.726921ms
2021/07/25 05:47:28 DEBUG : WaitForWriters: timeout=10s
2021/07/25 05:47:28 DEBUG : dir: Looking for writers
2021/07/25 05:47:28 DEBUG : file1: reading active writers
2021/07/25 05:47:28 DEBUG : : Looking for writers
2021/07/25 05:47:28 DEBUG : dir: reading active writers
2021/07/25 05:47:28 DEBUG : >WaitForWriters: 
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 20.04519ms
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 15.033892ms
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 11.275419ms
--- PASS: TestDirHandleReaddirnames (0.74s)
=== RUN   TestDirForgetAll
    run.go:176: Remote "S3 bucket rclone-test-yukiyoz1cidefer4tujovib5", Local "Local file system at /tmp/rclone578104470", Modify Window "1ns"
2021/07/25 05:47:28 INFO  : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: poll-interval is not supported by this remote
2021/07/25 05:47:28 DEBUG : pacer: Reducing sleep to 0s
2021/07/25 05:47:28 DEBUG : dir: forgetting directory cache
2021/07/25 05:47:28 DEBUG : : forgetting directory cache
2021/07/25 05:47:28 DEBUG : dir: forgetting directory cache
2021/07/25 05:47:28 DEBUG : WaitForWriters: timeout=10s
2021/07/25 05:47:28 DEBUG : : Looking for writers
2021/07/25 05:47:28 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetAll (0.04s)
=== RUN   TestRcGetVFS
    run.go:176: Remote "S3 bucket rclone-test-yukiyoz1cidefer4tujovib5", Local "Local file system at /tmp/rclone578104470", Modify Window "1ns"
2021/07/25 05:47:28 INFO  : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: poll-interval is not supported by this remote
2021/07/25 05:47:28 INFO  : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: poll-interval is not supported by this remote
2021/07/25 05:47:28 DEBUG : WaitForWriters: timeout=10s
2021/07/25 05:47:28 DEBUG : : Looking for writers
2021/07/25 05:47:28 DEBUG : >WaitForWriters: 
--- PASS: TestRcGetVFS (0.00s)
=== RUN   TestVFSNew
    run.go:176: Remote "S3 bucket rclone-test-yukiyoz1cidefer4tujovib5", Local "Local file system at /tmp/rclone578104470", Modify Window "1ns"
2021/07/25 05:47:28 INFO  : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: poll-interval is not supported by this remote
2021/07/25 05:47:28 DEBUG : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: Re-using VFS from active cache
2021/07/25 05:47:28 DEBUG : WaitForWriters: timeout=10s
2021/07/25 05:47:28 DEBUG : : Looking for writers
2021/07/25 05:47:28 DEBUG : >WaitForWriters: 
--- PASS: TestVFSNew (0.00s)
PASS
2021/07/25 05:47:28 DEBUG : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: Purge dir ""
2021/07/25 05:47:28 INFO  : S3 bucket rclone-test-yukiyoz1cidefer4tujovib5: Bucket "rclone-test-yukiyoz1cidefer4tujovib5" deleted
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestS3MinioEdge: -verbose -test.run '^(TestDirForgetAll|TestDirHandleMethods|TestDirHandleReaddirnames|TestRcGetVFS|TestVFSNew)$'" - Finished OK in 10.73060724s (try 2/5)