"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutError$'" - Starting (try 2/5)
=== RUN   TestIntegration
    fstests.go:438: Using remote "TestFTPProftpd:"
2026/06/27 01:01:48 NOTICE: TestFTPProftpd: Starting server
2026/06/27 01:01:49 NOTICE: TestFTPProftpd: Attempting to connect to "172.17.0.4:21" try 1/100
2026/06/27 01:01:49 DEBUG : TestFTPProftpd: Read 1, error: <nil>
2026/06/27 01:01:49 NOTICE: TestFTPProftpd: Started server and connected to "172.17.0.4:21"
2026/06/27 01:01:49 DEBUG : Creating backend with remote "TestFTPProftpd:rclone-test-tupixur3naxa"
2026/06/27 01:01:49 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/06/27 01:01:49 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE
2026/06/27 01:01:49 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST
2026/06/27 01:01:49 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER
2026/06/27 01:01:49 DEBUG : Setting pass="VU3Ww0MFNWjyYEeJLHunJjWIeqyzgWjpq11cxdor3ytWoQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS
2026/06/27 01:01:49 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING
2026/06/27 01:01:49 DEBUG : TestFTPProftpd: detected overridden config - adding "{cHbD3}" suffix to name
2026/06/27 01:01:49 DEBUG : Setting host="172.17.0.4" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_HOST
2026/06/27 01:01:49 DEBUG : Setting user="rclone" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_USER
2026/06/27 01:01:49 DEBUG : Setting pass="VU3Ww0MFNWjyYEeJLHunJjWIeqyzgWjpq11cxdor3ytWoQ" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_PASS
2026/06/27 01:01:49 DEBUG : Setting encoding="Asterisk,Ctl,Dot,Slash" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_ENCODING
2026/06/27 01:01:49 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: Connecting to FTP server
2026/06/27 01:01:49 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:21")
2026/06/27 01:01:49 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:58434->172.17.0.4:21, err=<nil>
2026/06/27 01:01:50 DEBUG : Setting type="ftp" for "TestFTPProftpd" from environment variable RCLONE_CONFIG_TESTFTPPROFTPD_TYPE
2026/06/27 01:01:50 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:20873")
2026/06/27 01:01:50 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:49406->172.17.0.4:20873, err=<nil>
=== RUN   TestIntegration/FsMkdir
2026/06/27 01:01:50 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:57943")
2026/06/27 01:01:50 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:54416->172.17.0.4:57943, err=<nil>
=== RUN   TestIntegration/FsMkdir/FsPutError
2026/06/27 01:01:50 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:43748")
2026/06/27 01:01:50 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:35408->172.17.0.4:43748, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: Connecting to FTP server
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:21")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:58440->172.17.0.4:21, err=<nil>
2026/06/27 01:01:51 DEBUG : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠/z.txt: Removed after failed upload: 1 error occurred:
	* potato

2026/06/27 01:01:51 DEBUG : Waiting for deletions to finish
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:58940")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:43634->172.17.0.4:58940, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:22349")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:51692->172.17.0.4:22349, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:17444")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:55440->172.17.0.4:17444, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:42338")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:51978->172.17.0.4:42338, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:60075")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:42098->172.17.0.4:60075, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:38714")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:45352->172.17.0.4:38714, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:61671")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:49718->172.17.0.4:61671, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:6424")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:58136->172.17.0.4:6424, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:19377")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:41158->172.17.0.4:19377, err=<nil>
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:34019")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:45124->172.17.0.4:34019, err=<nil>
2026/06/27 01:01:51 DEBUG : removing 1 level 4 directories
2026/06/27 01:01:51 INFO  : hello? sausage/êé/Hello, 世界/ " ' @ < > & ? + ≠: Removing directory
2026/06/27 01:01:51 DEBUG : removing 1 level 3 directories
2026/06/27 01:01:51 INFO  : hello? sausage/êé/Hello, 世界: Removing directory
2026/06/27 01:01:51 DEBUG : removing 1 level 2 directories
2026/06/27 01:01:51 INFO  : hello? sausage/êé: Removing directory
2026/06/27 01:01:51 DEBUG : removing 1 level 1 directories
2026/06/27 01:01:51 INFO  : hello? sausage: Removing directory
2026/06/27 01:01:51 DEBUG : removing 1 level 0 directories
2026/06/27 01:01:51 INFO  : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: Removing directory
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:42725")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:43984->172.17.0.4:42725, err=<nil>
2026/06/27 01:01:51 ERROR : error listing: directory not found
2026/06/27 01:01:51 DEBUG : Waiting for deletions to finish
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:48512")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:38014->172.17.0.4:48512, err=<nil>
2026/06/27 01:01:51 ERROR : error listing: directory not found
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: dial("tcp","172.17.0.4:34413")
2026/06/27 01:01:51 DEBUG : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: > dial: conn=172.17.0.1:42870->172.17.0.4:34413, err=<nil>
2026/06/27 01:01:51 ERROR : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: Failed to list "": directory not found
2026/06/27 01:01:51 DEBUG : removing 1 level 0 directories
2026/06/27 01:01:51 INFO  : ftp://172.17.0.4:21/rclone-test-tupixur3naxa: Removing directory
2026/06/27 01:01:51 ERROR : Failed to rmdir: directory not found
2026/06/27 01:01:51 NOTICE: TestFTPProftpd: Stopping server
--- PASS: TestIntegration (2.32s)
    --- PASS: TestIntegration/FsMkdir (1.05s)
        --- PASS: TestIntegration/FsMkdir/FsPutError (1.02s)
PASS
ok  	github.com/rclone/rclone/backend/ftp	2.332s
"go test -v -timeout 1h0m0s -remote TestFTPProftpd: -verbose -test.run '^TestIntegration$/^FsMkdir$/^FsPutError$'" - Finished OK in 3.131845842s (try 2/5)