"./vfs.test -test.v -test.timeout 1h0m0s -remote TestFTPVsftpd: -verbose -test.run '^TestDirEntryModTimeInvalidation$'" - Starting (try 4/5) 2025/02/21 05:26:44 DEBUG : Creating backend with remote "TestFTPVsftpd:rclone-test-zivixuy7qofu" 2025/02/21 05:26:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/02/21 05:26:44 DEBUG : Setting type="ftp" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_TYPE 2025/02/21 05:26:44 DEBUG : Setting host="172.17.0.3" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_HOST 2025/02/21 05:26:44 DEBUG : Setting user="rclone" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_USER 2025/02/21 05:26:44 DEBUG : Setting pass="3q4xSF64wprh89msrcXwmtlM-ii9d8msWTe4LSgu6czH" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_PASS 2025/02/21 05:26:44 DEBUG : Setting writing_mdtm="true" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_WRITING_MDTM 2025/02/21 05:26:44 DEBUG : Setting encoding="Ctl,LeftPeriod,Slash" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_ENCODING 2025/02/21 05:26:44 DEBUG : TestFTPVsftpd: detected overridden config - adding "{MA6Z6}" suffix to name 2025/02/21 05:26:44 DEBUG : Setting host="172.17.0.3" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_HOST 2025/02/21 05:26:44 DEBUG : Setting user="rclone" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_USER 2025/02/21 05:26:44 DEBUG : Setting pass="3q4xSF64wprh89msrcXwmtlM-ii9d8msWTe4LSgu6czH" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_PASS 2025/02/21 05:26:44 DEBUG : Setting writing_mdtm="true" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_WRITING_MDTM 2025/02/21 05:26:44 DEBUG : Setting encoding="Ctl,LeftPeriod,Slash" for "TestFTPVsftpd" from environment variable RCLONE_CONFIG_TESTFTPVSFTPD_ENCODING 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: Connecting to FTP server 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21105") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : Creating backend with remote "/tmp/rclone2065559546" === RUN TestDirEntryModTimeInvalidation run.go:180: Remote "ftp://172.17.0.3:21/rclone-test-zivixuy7qofu", Local "Local file system at /tmp/rclone2065559546", Modify Window "1s" 2025/02/21 05:26:44 INFO : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: poll-interval is not supported by this remote 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21100") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21104") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21104") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21105") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21107") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21107") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21103") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21108") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21101") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : : Re-reading directory (78.280337ms old) 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21107") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= dir_test.go:687: ModTime not invalidated 2025/02/21 05:26:44 DEBUG : WaitForWriters: timeout=30s 2025/02/21 05:26:44 DEBUG : dir: Looking for writers 2025/02/21 05:26:44 DEBUG : : Looking for writers 2025/02/21 05:26:44 DEBUG : dir: reading active writers 2025/02/21 05:26:44 DEBUG : >WaitForWriters: 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21107") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21107") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21109") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21107") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21104") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21107") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= --- FAIL: TestDirEntryModTimeInvalidation (0.09s) FAIL 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21108") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: dial("tcp","172.17.0.3:21109") 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: > dial: conn=*fshttp.timeoutConn, err= 2025/02/21 05:26:44 DEBUG : ftp://172.17.0.3:21/rclone-test-zivixuy7qofu: Purge dir "" "./vfs.test -test.v -test.timeout 1h0m0s -remote TestFTPVsftpd: -verbose -test.run '^TestDirEntryModTimeInvalidation$'" - Finished ERROR in 125.067417ms (try 4/5): exit status 1: Failed [TestDirEntryModTimeInvalidation]