"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFTPVsftpd: -verbose -test.run '^(TestVFSMkdir|TestVFSMkdirAll)$'" - Starting (try 2/5) 2024/10/11 03:25:20 DEBUG : Creating backend with remote "TestFTPVsftpd:rclone-test-ponizac7macu" 2024/10/11 03:25:20 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/11 03:25:20 DEBUG : Setting type="ftp" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_TYPE 2024/10/11 03:25:20 DEBUG : Setting host="172.17.0.2" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_HOST 2024/10/11 03:25:20 DEBUG : Setting user="rclone" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_USER 2024/10/11 03:25:20 DEBUG : Setting pass="7eGqar1DPmccDA1maDKzqs9jdT1GxksUYSOq8FXgnhs2" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_PASS 2024/10/11 03:25:20 DEBUG : Setting writing_mdtm="true" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_WRITING_MDTM 2024/10/11 03:25:20 DEBUG : Setting encoding="Ctl,LeftPeriod,Slash" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_ENCODING 2024/10/11 03:25:20 DEBUG : TestFTPVsftpd: detected overridden config - adding "{B2Y_n}" suffix to name 2024/10/11 03:25:20 DEBUG : Setting host="172.17.0.2" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_HOST 2024/10/11 03:25:20 DEBUG : Setting user="rclone" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_USER 2024/10/11 03:25:20 DEBUG : Setting pass="7eGqar1DPmccDA1maDKzqs9jdT1GxksUYSOq8FXgnhs2" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_PASS 2024/10/11 03:25:20 DEBUG : Setting writing_mdtm="true" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_WRITING_MDTM 2024/10/11 03:25:20 DEBUG : Setting encoding="Ctl,LeftPeriod,Slash" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_ENCODING 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: Connecting to FTP server 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21109") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : Creating backend with remote "/tmp/rclone337538135" === RUN TestVFSMkdir run.go:180: Remote "ftp://172.17.0.2:21/rclone-test-ponizac7macu", Local "Local file system at /tmp/rclone337538135", Modify Window "1s" 2024/10/11 03:25:20 INFO : ftp://172.17.0.2:21/rclone-test-ponizac7macu: poll-interval is not supported by this remote 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 ERROR : : error listing: directory not found 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : : Added virtual directory entry vAddDir: "/" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21105") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : : Added virtual directory entry vAddDir: "a" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21101") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21103") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : : Added virtual directory entry vAddDir: "b" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21109") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: Connecting to FTP server 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21103") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21105") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21101") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : : Added virtual directory entry vAddDir: "c" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: Connecting to FTP server 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21105") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : : Added virtual directory entry vAddDir: "d" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21109") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: Connecting to FTP server 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21109") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21109") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21109") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : WaitForWriters: timeout=30s 2024/10/11 03:25:20 DEBUG : c: Looking for writers 2024/10/11 03:25:20 DEBUG : d: Looking for writers 2024/10/11 03:25:20 DEBUG : : Looking for writers 2024/10/11 03:25:20 DEBUG : a: Looking for writers 2024/10/11 03:25:20 DEBUG : b: Looking for writers 2024/10/11 03:25:20 DEBUG : : Looking for writers 2024/10/11 03:25:20 DEBUG : /: reading active writers 2024/10/11 03:25:20 DEBUG : a: reading active writers 2024/10/11 03:25:20 DEBUG : b: reading active writers 2024/10/11 03:25:20 DEBUG : c: reading active writers 2024/10/11 03:25:20 DEBUG : d: reading active writers 2024/10/11 03:25:20 DEBUG : >WaitForWriters: 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21105") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21101") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21103") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21109") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= --- PASS: TestVFSMkdir (0.09s) === RUN TestVFSMkdirAll run.go:180: Remote "ftp://172.17.0.2:21/rclone-test-ponizac7macu", Local "Local file system at /tmp/rclone337538135", Modify Window "1s" 2024/10/11 03:25:20 INFO : ftp://172.17.0.2:21/rclone-test-ponizac7macu: poll-interval is not supported by this remote 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21101") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21107") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : : Added virtual directory entry vAddDir: "a" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21103") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21105") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : a: Added virtual directory entry vAddDir: "b" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21103") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : a/b: Added virtual directory entry vAddDir: "c" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21103") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : a/b/c: Added virtual directory entry vAddDir: "d" 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21109") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21101") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21105") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21104") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21110") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : WaitForWriters: timeout=30s 2024/10/11 03:25:20 DEBUG : a/b/c/d: Looking for writers 2024/10/11 03:25:20 DEBUG : a/b/c: Looking for writers 2024/10/11 03:25:20 DEBUG : d: reading active writers 2024/10/11 03:25:20 DEBUG : a/b: Looking for writers 2024/10/11 03:25:20 DEBUG : c: reading active writers 2024/10/11 03:25:20 DEBUG : a: Looking for writers 2024/10/11 03:25:20 DEBUG : b: reading active writers 2024/10/11 03:25:20 DEBUG : : Looking for writers 2024/10/11 03:25:20 DEBUG : a: reading active writers 2024/10/11 03:25:20 DEBUG : >WaitForWriters: 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21106") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21108") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21105") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21102") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= --- PASS: TestVFSMkdirAll (0.04s) PASS 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21100") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: dial("tcp","172.17.0.2:21103") 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: > dial: conn=*fshttp.timeoutConn, err= 2024/10/11 03:25:20 DEBUG : ftp://172.17.0.2:21/rclone-test-ponizac7macu: Purge dir "" "./vfs.test -test.v -test.timeout 1h0m0s -remote TestFTPVsftpd: -verbose -test.run '^(TestVFSMkdir|TestVFSMkdirAll)$'" - Finished OK in 195.341417ms (try 2/5)