Не создаётся индивидуальное хранилище на FTP-сервере.

Thread needs solution
Dmitry Shubertov
Beginner
Posts: 1
Comments: 1

Приветствую!

При попытке создать в консоли Acronis Backup индивидуальное хранилище на FTP-сервере в соответствии с подсказкой (в "Путь:" пишу `ftp://disk3.home.lan' и жму на зелёную кнопку-стрелку "Перейти", чтобы "Просмотреть содержимое FTP-сервера") и ничего видимого не происходит.

После включения отладочных протоколов по KB2465 степень тупизны Acronis Backup 11.5 update 6 (build 43994) поражает воображение:

--- Начало ftp-2015-07-30.log ---
New log entry started on 2015-07-30 16:08:03
Connected to disk3.home.lan
<- 220 ProFTPD 1.3.5a Server (disk3 FTP Server) [::ffff:172.16.6.50]
-> FEAT
<- 211-Features:
<- TVFS
-> USER anonymous
<- MFMT
<- SIZE
<- SITE MKDIR
<- MFF modify;UNIX.group;UNIX.mode;
<- REST STREAM
<- MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.mode*;UNIX.owner*;
<- UTF8
<- LANG en-US*
<- EPRT
<- SITE SYMLINK
<- EPSV
<- SITE UTIME
<- MDTM
<- SITE RMDIR
<- 211 End
Disconnected from disk3.home.lan

New log entry started on 2015-07-30 16:10:03
Connected to disk3.home.lan
<- 220 ProFTPD 1.3.5a Server (disk3 FTP Server) [::ffff:172.16.6.50]
-> FEAT
<- 211-Features:
<- TVFS
-> USER anonymous
<- MFMT
<- SIZE
<- SITE MKDIR
<- MFF modify;UNIX.group;UNIX.mode;
<- REST STREAM
<- MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.mode*;UNIX.owner*;
<- UTF8
<- LANG en-US*
<- EPRT
<- SITE SYMLINK
<- EPSV
<- SITE UTIME
<- MDTM
<- SITE RMDIR
<- 211 End
Disconnected from disk3.home.lan

New log entry started on 2015-07-30 16:12:03
Connected to disk3.home.lan
<- 220 ProFTPD 1.3.5a Server (disk3 FTP Server) [::ffff:172.16.6.50]
-> FEAT
<- 211-Features:
<- TVFS
-> USER anonymous
Disconnected from disk3.home.lan

New log entry started on 2015-07-30 16:14:49
Connected to disk3.home.lan
<- 220 ProFTPD 1.3.5a Server (disk3 FTP Server) [::ffff:172.16.6.50]
-> FEAT
<- 211-Features:
<- TVFS
-> USER father
<- MFMT
<- SIZE
<- SITE MKDIR
<- MFF modify;UNIX.group;UNIX.mode;
<- REST STREAM
<- MLST modify*;perm*;size*;type*;unique*;UNIX.group*;UNIX.mode*;UNIX.owner*;
<- UTF8
<- LANG en-US*
<- EPRT
<- SITE SYMLINK
<- EPSV
<- SITE UTIME
<- MDTM
<- SITE RMDIR
<- 211 End
Disconnected from disk3.home.lan

New log entry started on 2015-07-30 16:16:49
Connected to disk3.home.lan
<- 220 ProFTPD 1.3.5a Server (disk3 FTP Server) [::ffff:172.16.6.50]
-> FEAT
<- 211-Features:
<- TVFS
--- Конец ftp-2015-07-30.log ---

Констатирую следующие факты:
Backup спрашивает FTP-сервер про возможности (FEAT), и, получив 1-ю строку ответа, не дожидаясь ответа конца описания возможностей (211 End), пробует войти анонимно (USER). Сервер не принимает новую команду, он ещё не закончил ответ. В итоге, Backup получает ответ в буфер, но после чего на 2 минуты впадает в ступор, после которых отключается и переподключается. Следующие 2 минуты происходит тоже самое. И последующие -- то же.

Потом он как-то соображает, что анонимного входа нет, и спрашивает в окне имя пользователя и пароль для доступа к FTP-серверу. После чего тупняк у Backup продолжается с той разницей, что вместо anonymous он пихает в сервер реальное имя father.

Правда, здорово продукт работает?

Thu, 07/30/2015 - 14:29
0 Users found this helpful
Andrey.Galkin
Forum Member
Posts: 0
Comments: 70

Здравствуйте Дмитрий,

Судя по логу, который вы предоставили, у меня пока 2 подозрения:

1. Гонка потоков, по которому поток с аутентификацией влез не вовремя в только инициализирующуюся фтп сессию. 

2. Проблема с выводом FEAT команды от вашего ФТП сервера.

Я сделал быстрый тест на нашем фтп сервере и получил следующий вывод:

Connected to cftp.acronis.com

<- 220---------- Welcome to Pure-FTPd [privsep] [TLS] ----------

-> FEAT

<- 211-Extensions supported:

<-  EPRT

<-  IDLE

<-  MDTM

<-  SIZE

<-  REST STREAM

<-  MLST type*;size*;sizd*;modify*;UNIX.mode*;UNIX.uid*;UNIX.gid*;unique*;

<-  MLSD

<-  AUTH TLS

<-  PBSZ

<-  PROT

<-  UTF8

<-  ESTA

<-  PASV

<-  EPSV

<-  SPSV

<-  ESTP

<- 211 End.

-> USER *******

Т.е. с нашим фтп все работает хорошо. Судя по RFC 2389 https://tools.ietf.org/html/rfc2389#section-3.2 каждая из FEATs должна быть выделена своим отдельным пробелом вначале в выводе. Возможно дело в том, что в вашем выводе не хватает пробелов. Так же понимаю, что скорей всего пробелы были "съедены" форматированием форума.

Поэтому предлагаю вам для начала прикрепить наш ФТП лог в том виде, в котором он есть сюда, либо обратиться в техническую поддержку с ссылкой на данный форумный тред и там уже продожить детальное расследование.

Я заодно попрошу наш QA дополнительно протестировать нашу работу с ProFTPD 1.3.5a Server.

Fri, 07/31/2015 - 18:18
Dmitry Shubertov
Beginner
Posts: 1
Comments: 1

Добрый день, Андрей!

Вы правы, форум "съел" форматирование, и оригинальный протокол я прикрепил. Для ускорения работы вашего QA также информирую, что ProFTPD 1.3.5a Server испрользую из "коробочного" решения NAS4Free (FreeBSD 10.1 x64), которое может быть загружено по ссылке: http://sourceforge.net/projects/nas4free/files/NAS4Free-10.1.0.2/10.1.0…

Полагаю, конфигурация хранилища не важна, но, тем не менее, это устаревший компьютер HP Compaq dx2200 Microtower (Pentium 4 3ГГц, 2 Гб ОЗУ DDR2-533).

Желаю удачи!

P.S. Короме того, полагаю, что QA также обратит внимание на неработоспособность (невозможность создать) индивидуальных SFTP хранилищ также c NAS4Free версии 10.1.0.2.1731, сервер: SSH-2.0-OpenSSH_6.8-hpn14v5 FreeBSD-openssh-portable-6.8.p1_8,1

Attachment Size
286110-121045.log 1.95 KB
Mon, 08/03/2015 - 08:33
Andrey.Galkin
Forum Member
Posts: 0
Comments: 70

 Здравствуйте Дмитрий,

Наши QA воспроизвели проблемы работы с FTP и SFTP NAS4Free. Пока трудно сказать проблемами с чьей стороны они вызваны. Пока предлагаю вам попробовать использовать другой FTP сервер, типа PureFTPd, который у нас.

Thu, 08/06/2015 - 08:20
Evgeniy Derkach
Beginner
Posts: 0
Comments: 1

<p>Доброго времени.&nbsp;</p>

<p>Не хочу плодить темы, решил написать сюда. Столкнулся с похожей проблемой. Делаю резервную копию системного диска на FTP сервер. Ровно в одном и том же месте, я так думаю, что после окончания копирования, получаю одну и ту же ошибку. Если делать резервную копию не на FTP сервер, то всё ок. На многих машинах такая же ситуёвина. Подробности в логе.

На всякий случай, полный лог в атаче.

FTP сервер есть в логе. А резервную копию делаю Acronis Backup 11.7.50058.

</p>

<p>&nbsp;</p>

New log entry started on 2017-01-08 23:12:30
Connected to 192.168.1.1
<- 220 Serv-U FTP Server v15.1 ready...
-> FEAT
<- 211-Extensions supported
<-  UTF8
<-  OPTS MODE;MLST;UTF8
<-  CLNT
<-  CSID Name; Version;
<-  HOST domain
<-  SITE PSWD;SET;ZONE;CHMOD;MSG;EXEC;HELP
<-  AUTH TLS;SSL;TLS-C;TLS-P;
<-  PBSZ
<-  PROT
<-  CCC
<-  SSCN
<-  RMDA directoryname
<-  DSIZ
<-  AVBL
<-  EPRT
<-  EPSV
<-  MODE Z
<-  THMB BMP|JPEG|GIF|TIFF|PNG max_width max_height pathname
<-  REST STREAM
<-  SIZE
<-  MDTM
<-  MDTM YYYYMMDDHHMMSS[+-TZ];filename
<-  MFMT
<-  MFCT
<-  MFF Create;Modify;
<-  XCRC filename;start;end
<-  XMD5 filename;start;end
<-  XSHA1 filename;start;end
<-  XSHA256 filename;start;end
<-  XSHA512 filename;start;end
<-  COMB target;source_list
<-  MLST Type*;Size*;Create;Modify*;Perm;Win32.ea;Win32.dt;Win32.dl
<- 211 End (for details use "HELP commmand" where command is the command of interest)
-> USER user1863109
<- 331 User name okay, need password.
-> PASS *****
<- 230 User logged in, proceed.
-> SYST
<- 215 UNIX Type: L8
-> PWD
<- 257 "/" is current directory.
-> TYPE A
<- 200 Type set to A.
-> PASV
<- 227 Entering Passive Mode (192,168,1,1,82,211)
-> MLSD /backups/C
<- 150 Opening BINARY mode data connection for MLSD.
<- Type=cdir;Modify=20170108201232.110; .
<- Type=file;Size=1162;Modify=20170108200301.980; (C).xml
<- Type=file;Size=14329902694;Modify=20170108201230.766; (C)_2017_01_08_23_03_02_382D.TIB
<- 226 Transfer complete. 184 bytes transferred. 0,18 KB/sec.
-> OPTS MLST Size;Modify;Create;Type;
<- 200 MLST OPTS Size;Modify;Create;Type;
-> MLST /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 250-Listing /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<-      Type=file;Size=14329902694;Create=20170108200345.205;Modify=20170108201230.766; (C)_2017_01_08_23_03_02_382D.TIB
<- 250 End
-> TYPE I
<- 200 Type set to I.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 213 14329902694
-> PASV
<- 227 Entering Passive Mode (192,168,1,1,82,212)
-> RETR /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 150 Opening BINARY mode data connection for (C)_2017_01_08_23_03_02_382D.TIB (14329902694 Bytes).
<- 426 Data connection unexpectedly closed, file transfer /backups/C/(C)_2017_01_08_23_03_02_382D.TIB aborted by client.
-> PASV
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
-> SIZE /backups/C/(C)_2017_01_08_23_03_02_382D1.TIB
<- 451 Another command is currently pending, please try again later.
Disconnected from 192.168.1.1
Disconnected from 192.168.1.1
Disconnected from 192.168.1.1
Disconnected from 192.168.1.1
Disconnected from 192.168.1.1
Disconnected from 192.168.1.1

<p>&nbsp;</p>
 

Attachment Size
402012-135997.log 246.17 KB
Sun, 01/08/2017 - 23:44