"./vfs.test -test.v -test.timeout 1h0m0s -remote TestSFTPRsyncNet: -verbose -test.run '^TestZipManySubDirs$'" - Starting (try 2/5) 2025/09/30 02:05:37 DEBUG : Creating backend with remote "TestSFTPRsyncNet:rclone-test-lecapac4fupi" 2025/09/30 02:05:37 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/30 02:05:39 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: New connection [2a01:4f9:c011:405e::1]:55600->[2001:470:1:eb::9]:22 to "SSH-2.0-OpenSSH_9.9-hpn14v15 FreeBSD-openssh-portable-9.9.p1_1,1" 2025/09/30 02:05:39 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Shell type "unix" from config 2025/09/30 02:05:39 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Relative path resolved to "/data2/home/59018/rclone-test-lecapac4fupi" 2025/09/30 02:05:40 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Using root directory "/data2/home/59018/rclone-test-lecapac4fupi" 2025/09/30 02:05:40 DEBUG : Creating backend with remote "/tmp/rclone3008713815" === RUN TestZipManySubDirs run.go:180: Remote "sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi", Local "Local file system at /tmp/rclone3008713815", Modify Window "1s" 2025/09/30 02:05:40 INFO : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: poll-interval is not supported by this remote 2025/09/30 02:05:46 DEBUG : a/b/c/deep.txt: Open: flags=O_RDONLY 2025/09/30 02:05:46 DEBUG : a/b/c/deep.txt: >Open: fd=a/b/c/deep.txt (r), err= 2025/09/30 02:05:46 DEBUG : a/b/c/deep.txt: ChunkedReader.openRange at 0 length 134217728 2025/09/30 02:05:46 DEBUG : a/b/c/deep.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2025/09/30 02:05:46 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Shell path "/data2/home/59018/rclone-test-lecapac4fupi/a/b/c/deep.txt" 2025/09/30 02:05:46 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Running remote command: md5 -r /data2/home/59018/rclone-test-lecapac4fupi/a/b/c/deep.txt 2025/09/30 02:05:46 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Remote command result: 6627415e807ee33c7302917216e7da68 /data2/home/59018/rclone-test-lecapac4fupi/a/b/c/deep.txt 2025/09/30 02:05:46 DEBUG : a/b/c/deep.txt: Parsed hash: 6627415e807ee33c7302917216e7da68 2025/09/30 02:05:46 DEBUG : a/b/mid.txt: Open: flags=O_RDONLY 2025/09/30 02:05:46 DEBUG : a/b/mid.txt: >Open: fd=a/b/mid.txt (r), err= 2025/09/30 02:05:46 DEBUG : a/b/mid.txt: ChunkedReader.openRange at 0 length 134217728 2025/09/30 02:05:47 DEBUG : a/b/mid.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2025/09/30 02:05:47 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Shell path "/data2/home/59018/rclone-test-lecapac4fupi/a/b/mid.txt" 2025/09/30 02:05:47 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Running remote command: md5 -r /data2/home/59018/rclone-test-lecapac4fupi/a/b/mid.txt 2025/09/30 02:05:47 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Remote command result: 22384709d743fe3c6fb0a4b35b2e10a6 /data2/home/59018/rclone-test-lecapac4fupi/a/b/mid.txt 2025/09/30 02:05:47 DEBUG : a/b/mid.txt: Parsed hash: 22384709d743fe3c6fb0a4b35b2e10a6 2025/09/30 02:05:47 DEBUG : a/top.txt: Open: flags=O_RDONLY 2025/09/30 02:05:47 DEBUG : a/top.txt: >Open: fd=a/top.txt (r), err= 2025/09/30 02:05:47 DEBUG : a/top.txt: ChunkedReader.openRange at 0 length 134217728 2025/09/30 02:05:48 DEBUG : a/top.txt: ChunkedReader.Read at 0 length 32768 chunkOffset 0 chunkSize 134217728 2025/09/30 02:05:48 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Shell path "/data2/home/59018/rclone-test-lecapac4fupi/a/top.txt" 2025/09/30 02:05:48 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Running remote command: md5 -r /data2/home/59018/rclone-test-lecapac4fupi/a/top.txt 2025/09/30 02:05:48 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Remote command result: b28354b543375bfa94dabaeda722927f /data2/home/59018/rclone-test-lecapac4fupi/a/top.txt 2025/09/30 02:05:48 DEBUG : a/top.txt: Parsed hash: b28354b543375bfa94dabaeda722927f 2025/09/30 02:05:48 DEBUG : WaitForWriters: timeout=30s 2025/09/30 02:05:48 DEBUG : a/b/c: Looking for writers 2025/09/30 02:05:48 DEBUG : deep.txt: reading active writers 2025/09/30 02:05:48 DEBUG : a/b: Looking for writers 2025/09/30 02:05:48 DEBUG : c: reading active writers 2025/09/30 02:05:48 DEBUG : mid.txt: reading active writers 2025/09/30 02:05:48 DEBUG : a: Looking for writers 2025/09/30 02:05:48 DEBUG : b: reading active writers 2025/09/30 02:05:48 DEBUG : top.txt: reading active writers 2025/09/30 02:05:48 DEBUG : Looking for writers 2025/09/30 02:05:48 DEBUG : a: reading active writers 2025/09/30 02:05:48 DEBUG : >WaitForWriters: --- PASS: TestZipManySubDirs (14.11s) PASS 2025/09/30 02:05:54 DEBUG : sftp://59018@usw-s009.rsync.net:22/rclone-test-lecapac4fupi: Purge dir "" "./vfs.test -test.v -test.timeout 1h0m0s -remote TestSFTPRsyncNet: -verbose -test.run '^TestZipManySubDirs$'" - Finished OK in 17.466017502s (try 2/5)