Почему не логинится roundcube?

Question

[Максим]

Есть сервер на Freebsd с:
Dovecot
Postfix
PHP5.6-FPM
Nginx
MySQL
PosfixAdmin
phpMyAdmin

Все запущено, однако когда логинишся в roundcube вылетает 504 Gateway Time-out.
В логах nginx:

1  2017/03/07 02:25:46 [error] 86004#100091: *1 upstream timed out (60: Operation timed out) while reading response header from upstream, client: 185.40.77.13, server: dataharbour.tk, request: "POST /installer/index.php?_step=3 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php-fpm.sock", host: "dataharbour.tk", referrer: "http://dataharbour.tk/installer/index.php?_step=3"
     2  2017/03/07 02:37:05 [error] 86004#100091: *24 upstream timed out (60: Operation timed out) while reading response header from upstream, client: 185.40.77.13, server: dataharbour.tk, request: "POST /?_task=login HTTP/1.1", upstream: "fastcgi://unix:/var/run/php-fpm.sock", host: "dataharbour.tk", referrer: "http://dataharbour.tk/"

Все конфиги могу выложить, говорите какие нужны.

Comments

[Андрей Михалёв]

смотри логи php - скорее всего там ошибки. + покажи конфиг nginx для roundcube

[Максим]

1

server {
     2          listen   80;
     3          root /usr/local/www/roundcube;
     4          server_name dataharbour.tk www.dataharbour.tk;
     5          index   index.php index.html index.htm;
     6          location / {
     7                  try_files $uri $uri/ index.php;
     8          }
     9          location ~ \.php$ {
    10                  try_files $uri =404;
    11                  fastcgi_split_path_info ^(.+\.php)(/.+)$;
    12                  fastcgi_pass   unix:/var/run/php-fpm.sock;
    13                  fastcgi_index  index.php;
    14                  fastcgi_param  SCRIPT_FILENAME  $document_root$fastcgi_script_name;
    15                  include        fastcgi_params;
    16          }
    17  }

[Максим]

Андрей Михалёв: В логах PHP Только включение выключение сервера.

[Андрей Михалёв]

Максим: а в access.log что?

[Максим]

Андрей Михалёв:

406  23.101.61.176 - - [07/Mar/2017:02:27:16 +0300] "GET / HTTP/1.1" 200 7053 "-" "Mozilla/5.0 (Windows NT 6.1; WOW64) SkypeUriPreview Preview/0.5"
   407  23.101.61.176 - - [07/Mar/2017:02:27:16 +0300] "GET /skins/larry/images/favicon.ico HTTP/1.1" 200 34494 "-" "Mozilla/5.0 (Windows NT 6.1; WOW64) SkypeUriPreview Preview/0.5"
   408  23.101.61.176 - - [07/Mar/2017:02:27:43 +0300] "GET /installer HTTP/1.1" 301 185 "-" "Mozilla/5.0 (Windows NT 6.1; WOW64) SkypeUriPreview Preview/0.5"
   409  23.101.61.176 - - [07/Mar/2017:02:27:43 +0300] "GET /installer/ HTTP/1.1" 302 5 "-" "Mozilla/5.0 (Windows NT 6.1; WOW64) SkypeUriPreview Preview/0.5"
   410  23.101.61.176 - - [07/Mar/2017:02:27:43 +0300] "GET /installer/?_step=1 HTTP/1.1" 200 1053 "-" "Mozilla/5.0 (Windows NT 6.1; WOW64) SkypeUriPreview Preview/0.5"
   411  23.101.61.176 - - [07/Mar/2017:02:27:43 +0300] "GET /favicon.ico HTTP/1.1" 404 169 "-" "Mozilla/5.0 (Windows NT 6.1; WOW64) SkypeUriPreview Preview/0.5"
   412  185.40.77.13 - - [07/Mar/2017:02:28:14 +0300] "GET / HTTP/1.1" 200 3313 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36"
   413  185.40.77.13 - - [07/Mar/2017:02:31:01 +0300] "GET / HTTP/1.1" 200 3320 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36"
   414  185.40.77.13 - - [07/Mar/2017:02:31:02 +0300] "GET /favicon.ico HTTP/1.1" 200 22486 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36"
   415  185.40.77.13 - - [07/Mar/2017:02:36:02 +0300] "GET / HTTP/1.1" 200 7053 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36"
   416  185.40.77.13 - - [07/Mar/2017:02:37:05 +0300] "POST /?_task=login HTTP/1.1" 504 585 "http://dataharbour.tk/" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36"
   417  185.40.77.13 - - [07/Mar/2017:02:48:14 +0300] "GET / HTTP/1.1" 200 7053 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36"
   418  52.5.98.73 - - [07/Mar/2017:02:48:24 +0300] "GET / HTTP/1.1" 200 7053 "-" "Mozilla/5.0 (compatible)"
   419  52.5.98.73 - - [07/Mar/2017:02:48:24 +0300] "GET / HTTP/1.1" 200 7053 "-" "Mozilla/5.0 (compatible)"
   420  52.5.98.73 - - [07/Mar/2017:02:48:24 +0300] "GET /installer/index.php?_step=3 HTTP/1.1" 200 1053 "-" "Mozilla/5.0 (compatible)"
   421  185.40.77.13 - - [07/Mar/2017:02:49:22 +0300] "POST /?_task=login HTTP/1.1" 504 585 "http://dataharbour.tk/" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36"
   422  192.228.159.80 - - [07/Mar/2017:02:50:16 +0300] "GET / HTTP/1.0" 200 7034 "-" "-"
   423  185.40.77.13 - - [07/Mar/2017:05:34:35 +0300] "GET / HTTP/1.1" 200 7053 "-" "Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/56.0.2924.87 Safari/537.36"
   424  13.66.59.75 - - [07/Mar/2017:05:38:17 +0300] "HEAD / HTTP/1.1" 200 0 "-" "-"
   425  95.234.254.181 - - [07/Mar/2017:05:46:32 +0300] "GET / HTTP/1.0" 200 7034 "-" "-"

Answer 1

[Axian Ltd.]

Так оно же пишет что проблема с fastcgi. У вас процесс php запущен? Указанный сокет создан?

Comments

[Максим]

Естественно, PHP-FPM запущен, сокет создан, postfixadmin и phpmyadmin работают нормально и полностью, и roundcube страницу логина отображает нормально, но когда вводишь любой логин пароль, и нажимаешь войти, он призадумывается секунд на 20-30 и выдает 504.

[Axian Ltd.]

Права на сокет у nginx есть?

[Axian Ltd.]

Вот эта строка подозрительная - "POST /installer/index.php?_step=3", установка не закончена что ли?

[Максим]

Axian Ltd.: все права на сокет у пользователя www, php и nginx тоже запущены этим пользователем

[Максим]

Axian Ltd.: он не проходит последний тест на логин

[Axian Ltd.]

А что в логах php?

[Максим]

Axian Ltd.: только включение и выключение сервера

[Axian Ltd.]

BTW если ввести dataharbour.tk/installer/index.php?_step=3 оно у вас пишет что установщик запрещен. Так как оно закончит последний тест на логин если установщик уже запрещен.

[Axian Ltd.]

В php отладку включить бы и посмотреть как к нему обращаются. Что-то он не может сделать.

[Максим]

Axian Ltd.: перейдите по dataharbour.tk/installer/ и нажмите step3, все нормально работает, пока не нажмешь кнопку check login

[Axian Ltd.]

The installer is disabled!

To enable it again, set $config['enable_installer'] = true; in RCUBE_CONFIG_DIR/config.inc.php

Ку?

[Максим]

Axian Ltd.: meson.ad-l.ink/7XsRMMZGM/image.png у меня заходит

[Axian Ltd.]

А меня нет ;-)

[Максим]

Axian Ltd.: могу поставить в конфиге чтобы у вас зашло, но есть ли смысл?

[Axian Ltd.]

Не знаю. Мы же ищем причину.

[Axian Ltd.]

А аутентификацию roundcube через что задана?

[Максим]

Axian Ltd.: попробуйте снова

[Максим]

Axian Ltd.: через imap в dovecot

[Axian Ltd.]

А в dovecot по IMAP можно подключиться?

[Максим]

Axian Ltd.: незнаю а как можно проверить?

[Axian Ltd.]

Попробовал, те же яйца что и у вас.

[Axian Ltd.]

Взять почтовый клиент и подключить IMAP уч. запись. Ну или руками через telnet, только тогда придется взять шпаргалку по командам IMAP

[Axian Ltd.]

Dovecot случайно не на SSL настроен?

[Максим]

Axian Ltd.: без ssl по телнету пишет conected

[Axian Ltd.]

А залогинится дает с тем именем и паролем что вы пробуете в roundcube?

[Максим]

Axian Ltd.: нет просто написано conected логин пароль не спрашивает

[Axian Ltd.]

Оно не так работает. Нужно ввести команду LOGIN

[Axian Ltd.]

https://tools.ietf.org/html/rfc1730

[Максим]

Axian Ltd.: помоему ни чего не происходит

:~ # telnet 127.0.0.1 143
Trying 127.0.0.1...
Connected to localhost.
Escape character is '^]'.
LOGIN [e-mail] test123
SELECT INBOX

[Андрей Михалёв]

Максим: netstat -ntl. возможно банально не запущен или не правильно настроен dovecot.

[Максим]

Андрей Михалёв: netstat -ntl у меня такого нету, а dovecot запущен, ошибок при проверке конфига нет.

:~ # service -e
/etc/rc.d/hostid
/etc/rc.d/hostid_save
/etc/rc.d/cleanvar
/etc/rc.d/ip6addrctl
/etc/rc.d/devd
/etc/rc.d/ipfw
/etc/rc.d/newsyslog
/etc/rc.d/syslogd
/etc/rc.d/dmesg
/etc/rc.d/virecover
/etc/rc.d/motd
/etc/rc.d/ntpd
/usr/local/etc/rc.d/php-fpm
/usr/local/etc/rc.d/nginx
/usr/local/etc/rc.d/mysql-server
/usr/local/etc/rc.d/dovecot
/etc/rc.d/sshd
/etc/rc.d/cron
/etc/rc.d/postfix
/etc/rc.d/mixer
/etc/rc.d/gptboot
/etc/rc.d/bgfsck

[Axian Ltd.]

Максим: Это как раз нормально, вы вошли и вас просят выбрать почтовый ящик. Значит dovecot мы исключаем, он работает. Значит залипука в roundcube который почему-то не может пройти аутентификацию в dovecot

[Максим]

Axian Ltd.: select inbox это я написал в надежде на ответ

[Axian Ltd.]

Это не правильно. Возвращаемся к dovecot. Должно быть так

telnet 143
Trying ...
Connected to
Escape character is '^]'.
* OK IMAP4 server ready
a login @
a OK login successful

[Axian Ltd.]

https://busylog.net/telnet-imap-commands-note/#22

[Axian Ltd.]

telnet SERVER-IP 143
Trying SERVER-IP...
Connected to SERVER-IP
Escape character is '^]'.
* OK IMAP4 server ready
a login username@domain password
a OK login successful
----------
Хабр стрелочки съел

[Axian Ltd.]

dovecot откуда пользователей берет?

[Максим]

Axian Ltd.: ладно, это так не работает, вот конфиг dovecot:
rgho.st/6dwyMMdDT

[Максим]

Axian Ltd.: пользователи по идее из mysql

[Максим]

Максим: а создаются в postfixadmin

[Андрей Михалёв]

Максим: и что что он запущен? конфиг по умолчанию! там нет ни слова где брать пароли пользователей!

[Axian Ltd.]

Максим: Это только по идее. Реально у вас dovecot не настроен совсем. Так что стоит начать с него. После того как он начнет откликаться на пользователей, можно будет переходить к roundcube.