"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose -test.run '^TestVFSMkdir$'" - Starting (try 5/5) 2025/08/05 01:50:10 DEBUG : Creating backend with remote "TestFTPPureftpd:rclone-test-getixab1bili" 2025/08/05 01:50:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/08/05 01:50:10 DEBUG : Setting type="ftp" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_TYPE 2025/08/05 01:50:10 DEBUG : Setting host="172.17.0.2" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/05 01:50:10 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/05 01:50:10 DEBUG : Setting pass="Sut5YWZcqLDAlplzjG3TpSB1ZIslvITUK-mWYQ2lwC4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/05 01:50:10 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/05 01:50:10 DEBUG : TestFTPPureftpd: detected overridden config - adding "{voAWo}" suffix to name 2025/08/05 01:50:10 DEBUG : Setting host="172.17.0.2" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_HOST 2025/08/05 01:50:10 DEBUG : Setting user="rclone" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_USER 2025/08/05 01:50:10 DEBUG : Setting pass="Sut5YWZcqLDAlplzjG3TpSB1ZIslvITUK-mWYQ2lwC4" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_PASS 2025/08/05 01:50:10 DEBUG : Setting encoding="BackSlash,Ctl,Del,Dot,RightSpace,Slash,SquareBracket" for "TestFTPPureftpd" from environment variable RCLONE_CONFIG_TESTFTPPUREFTPD_ENCODING 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: Connecting to FTP server 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:21") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:34916->172.17.0.2:21, err= 2025/08/05 01:50:10 DEBUG : Creating backend with remote "/tmp/rclone391820588" === RUN TestVFSMkdir run.go:180: Remote "ftp://172.17.0.2:21/rclone-test-getixab1bili", Local "Local file system at /tmp/rclone391820588", Modify Window "1s" 2025/08/05 01:50:10 INFO : ftp://172.17.0.2:21/rclone-test-getixab1bili: poll-interval is not supported by this remote 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:32181") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:50048->172.17.0.2:32181, err= 2025/08/05 01:50:10 ERROR : error listing: directory not found 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:30829") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:40060->172.17.0.2:30829, err= 2025/08/05 01:50:10 DEBUG : Added virtual directory entry vAddDir: "/" 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:30572") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:34604->172.17.0.2:30572, err= 2025/08/05 01:50:10 DEBUG : Added virtual directory entry vAddDir: "a" 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:31207") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:43440->172.17.0.2:31207, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:37107") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:60580->172.17.0.2:37107, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:39959") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:47278->172.17.0.2:39959, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:39685") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:53542->172.17.0.2:39685, err= 2025/08/05 01:50:10 DEBUG : Added virtual directory entry vAddDir: "b" 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:32949") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:34720->172.17.0.2:32949, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: Connecting to FTP server 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:21") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:34926->172.17.0.2:21, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:34998") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:54732->172.17.0.2:34998, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:33410") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:54408->172.17.0.2:33410, err= 2025/08/05 01:50:10 DEBUG : Added virtual directory entry vAddDir: "c" 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:35086") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:60190->172.17.0.2:35086, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: Connecting to FTP server 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:21") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:30769") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:31860") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:34942->172.17.0.2:21, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:57730->172.17.0.2:30769, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:54702->172.17.0.2:31860, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:31112") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:44504->172.17.0.2:31112, err= 2025/08/05 01:50:10 DEBUG : Added virtual directory entry vAddDir: "d" 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:35552") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:33872->172.17.0.2:35552, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: Connecting to FTP server 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:21") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:36933") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:38768") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:31160") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:34480->172.17.0.2:38768, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:40638->172.17.0.2:31160, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:34952->172.17.0.2:21, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:47496->172.17.0.2:36933, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:38804") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:48372->172.17.0.2:38804, err= 2025/08/05 01:50:10 DEBUG : WaitForWriters: timeout=30s 2025/08/05 01:50:10 DEBUG : Looking for writers 2025/08/05 01:50:10 DEBUG : a: Looking for writers 2025/08/05 01:50:10 DEBUG : b: Looking for writers 2025/08/05 01:50:10 DEBUG : c: Looking for writers 2025/08/05 01:50:10 DEBUG : d: Looking for writers 2025/08/05 01:50:10 DEBUG : Looking for writers 2025/08/05 01:50:10 DEBUG : /: reading active writers 2025/08/05 01:50:10 DEBUG : a: reading active writers 2025/08/05 01:50:10 DEBUG : b: reading active writers 2025/08/05 01:50:10 DEBUG : c: reading active writers 2025/08/05 01:50:10 DEBUG : d: reading active writers 2025/08/05 01:50:10 DEBUG : >WaitForWriters: 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:32513") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:36298->172.17.0.2:32513, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:35516") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:37998") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:44944->172.17.0.2:35516, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:34467") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:58014->172.17.0.2:34467, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:56038->172.17.0.2:37998, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:32098") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:51240->172.17.0.2:32098, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:33191") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:40186->172.17.0.2:33191, err= --- PASS: TestVFSMkdir (0.09s) PASS 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: dial("tcp","172.17.0.2:33054") 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: > dial: conn=172.17.0.1:37488->172.17.0.2:33054, err= 2025/08/05 01:50:10 DEBUG : ftp://172.17.0.2:21/rclone-test-getixab1bili: Purge dir "" "./vfs.test -test.v -test.timeout 1h0m0s -remote TestFTPPureftpd: -verbose -test.run '^TestVFSMkdir$'" - Finished OK in 168.351005ms (try 5/5)