Apache2 запускается, но выдаёт ошибку. Как найти ошибку?

Question

[Axwellweb]

Добрый день. Проблема открылась при отсутсвии ротации логов.
Логи пишутся в файл *.log.1
При этом команда service apache2 stop|start возвращает ошибку, но апач запускается всё равно.
Перезапустить апач без ошибок удаётся только командой apache2ctl start|stop

Выдержка из journalctl -xe

Aug 23 13:49:30 ij systemd[1]: apache2.service: Control process exited, code=exited status=1
Aug 23 13:49:30 ij systemd[1]: Failed to start LSB: Apache2 web server.
-- Subject: Unit apache2.service has failed
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- Unit apache2.service has failed.
--
-- The result is failed.
Aug 23 13:49:30 ij systemd[1]: apache2.service: Unit entered failed state.
Aug 23 13:49:30 ij systemd[1]: apache2.service: Failed with result 'exit-code'.

Выдержка из systemctl status apache2.service

* apache2.service - LSB: Apache2 web server
   Loaded: loaded (/etc/init.d/apache2; bad; vendor preset: enabled)
  Drop-In: /lib/systemd/system/apache2.service.d
           `-apache2-systemd.conf
   Active: failed (Result: exit-code) since Wed 2023-08-23 13:49:30 MSK; 6min ago
     Docs: man:systemd-sysv-generator(8)
  Process: 13850 ExecStop=/etc/init.d/apache2 stop (code=exited, status=0/SUCCESS)
  Process: 15315 ExecStart=/etc/init.d/apache2 start (code=exited, status=1/FAILURE)
   CGroup: /system.slice/apache2.service
           |-15330 /usr/sbin/apache2 -k start
           |-15333 /usr/sbin/apache2 -k start
           |-15336 /usr/sbin/apache2 -k start
           |-15339 /usr/sbin/apache2 -k start
           |-15340 /usr/sbin/apache2 -k start
           `-15392 /usr/sbin/apache2 -k start

Aug 23 13:49:10 ij systemd[1]: Starting LSB: Apache2 web server...
Aug 23 13:49:10 ij apache2[15315]:  * Starting Apache httpd web server apache2
Aug 23 13:49:30 ij apache2[15315]:  *
Aug 23 13:49:30 ij apache2[15315]:  * The apache2 instance did not start within 20 seconds. Please read the log files to discover problems
Aug 23 13:49:30 ij systemd[1]: apache2.service: Control process exited, code=exited status=1
Aug 23 13:49:30 ij systemd[1]: Failed to start LSB: Apache2 web server.
Aug 23 13:49:30 ij systemd[1]: apache2.service: Unit entered failed state.
Aug 23 13:49:30 ij systemd[1]: apache2.service: Failed with result 'exit-code'.

Файл error.log

[Wed Aug 23 13:49:30.901608 2023] [mpm_prefork:notice] [pid 15167] AH00163: Apache/2.4.18 (Ubuntu) OpenSSL/1.0.2g configured -- resuming normal operations
[Wed Aug 23 13:49:30.902457 2023] [core:notice] [pid 15167] AH00094: Command line: '/usr/sbin/apache2'

Не понимаю в чём ошибка... С виду ошибка в том что апач запускается дольше 20 секунд.
Как вернуть нормальную работоспособность и правильную ротацию логов?

Answer 1

[AUser0]

Проверьте, что Apache не висит в памяти работающим зомби процессом. Команда ps axf покажет все запущенные процессы, ищите там apache.

Comments

[Axwellweb]

В ps axf апач не обнаружен.

[AUser0]

Axwellweb, тогда можно сделать ручной запуск apache, с указанием debug logging. Разумеется с аргументами "не уходить в background" и указанием рабочего конфига из /etc/apache/.

[Axwellweb]

AUser0, запустил с LogLevel debug

Вот из error.log

[Wed Aug 23 14:37:19.538551 2023] [ssl:info] [pid 16087] AH01887: Init: Initializing (virtual) servers for SSL
[Wed Aug 23 14:37:19.540135 2023] [ssl:info] [pid 16087] AH01876: mod_ssl/2.4.18 compiled against Server: Apache/2.4.18, Library: OpenSSL/1.0.2g
[Wed Aug 23 14:37:19.592728 2023] [socache_shmcb:debug] [pid 16088] mod_socache_shmcb.c(397): AH00821: shmcb_init allocated 512000 bytes of shared memory
[Wed Aug 23 14:37:19.592761 2023] [socache_shmcb:debug] [pid 16088] mod_socache_shmcb.c(413): AH00822: for 511912 bytes (512000 including header), recommending 32 subcaches, 88 indexes each
[Wed Aug 23 14:37:19.592766 2023] [socache_shmcb:debug] [pid 16088] mod_socache_shmcb.c(446): AH00824: shmcb_init_memory choices follow
[Wed Aug 23 14:37:19.592777 2023] [socache_shmcb:debug] [pid 16088] mod_socache_shmcb.c(448): AH00825: subcache_num = 32
[Wed Aug 23 14:37:19.592781 2023] [socache_shmcb:debug] [pid 16088] mod_socache_shmcb.c(450): AH00826: subcache_size = 15992
[Wed Aug 23 14:37:19.592785 2023] [socache_shmcb:debug] [pid 16088] mod_socache_shmcb.c(452): AH00827: subcache_data_offset = 2128
[Wed Aug 23 14:37:19.592789 2023] [socache_shmcb:debug] [pid 16088] mod_socache_shmcb.c(454): AH00828: subcache_data_size = 13864
[Wed Aug 23 14:37:19.592793 2023] [socache_shmcb:debug] [pid 16088] mod_socache_shmcb.c(456): AH00829: index_num = 88
[Wed Aug 23 14:37:19.592957 2023] [socache_shmcb:info] [pid 16088] AH00830: Shared memory socache initialised
[Wed Aug 23 14:37:19.592962 2023] [ssl:info] [pid 16088] AH01887: Init: Initializing (virtual) servers for SSL
[Wed Aug 23 14:37:19.594459 2023] [ssl:info] [pid 16088] AH01876: mod_ssl/2.4.18 compiled against Server: Apache/2.4.18, Library: OpenSSL/1.0.2g
[Wed Aug 23 14:37:19.596212 2023] [mpm_prefork:notice] [pid 16088] AH00163: Apache/2.4.18 (Ubuntu) OpenSSL/1.0.2g configured -- resuming normal operations
[Wed Aug 23 14:37:19.596233 2023] [mpm_prefork:info] [pid 16088] AH00164: Server built: 2020-08-12T21:35:50
[Wed Aug 23 14:37:19.596247 2023] [core:notice] [pid 16088] AH00094: Command line: '/usr/sbin/apache2'
[Wed Aug 23 14:37:19.596251 2023] [core:debug] [pid 16088] log.c(1546): AH02639: Using SO_REUSEPORT: yes (1)
[Wed Aug 23 14:37:19.596256 2023] [mpm_prefork:debug] [pid 16088] prefork.c(1028): AH00165: Accept mutex: sysvsem (default: sysvsem)

А вот с параметром -X

[Wed Aug 23 14:41:59.185922 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module access_compat_module from /usr/lib/apache2/modules/mod_access_compat.so
[Wed Aug 23 14:41:59.186122 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module alias_module from /usr/lib/apache2/modules/mod_alias.so
[Wed Aug 23 14:41:59.186278 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module auth_basic_module from /usr/lib/apache2/modules/mod_auth_basic.so
[Wed Aug 23 14:41:59.186445 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module authn_core_module from /usr/lib/apache2/modules/mod_authn_core.so
[Wed Aug 23 14:41:59.186573 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module authn_file_module from /usr/lib/apache2/modules/mod_authn_file.so
[Wed Aug 23 14:41:59.186735 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module authz_core_module from /usr/lib/apache2/modules/mod_authz_core.so
[Wed Aug 23 14:41:59.186848 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module authz_host_module from /usr/lib/apache2/modules/mod_authz_host.so
[Wed Aug 23 14:41:59.186942 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module authz_user_module from /usr/lib/apache2/modules/mod_authz_user.so
[Wed Aug 23 14:41:59.187061 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module autoindex_module from /usr/lib/apache2/modules/mod_autoindex.so
[Wed Aug 23 14:41:59.187267 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module deflate_module from /usr/lib/apache2/modules/mod_deflate.so
[Wed Aug 23 14:41:59.187396 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module dir_module from /usr/lib/apache2/modules/mod_dir.so
[Wed Aug 23 14:41:59.187520 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module env_module from /usr/lib/apache2/modules/mod_env.so
[Wed Aug 23 14:41:59.187644 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module filter_module from /usr/lib/apache2/modules/mod_filter.so
[Wed Aug 23 14:41:59.187754 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module mime_module from /usr/lib/apache2/modules/mod_mime.so
[Wed Aug 23 14:41:59.187915 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module mpm_prefork_module from /usr/lib/apache2/modules/mod_mpm_prefork.so
[Wed Aug 23 14:41:59.188034 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module negotiation_module from /usr/lib/apache2/modules/mod_negotiation.so
[Wed Aug 23 14:41:59.195729 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module php7_module from /usr/lib/apache2/modules/libphp7.3.so
[Wed Aug 23 14:41:59.196900 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module remoteip_module from /usr/lib/apache2/modules/mod_remoteip.so
[Wed Aug 23 14:41:59.197099 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module rewrite_module from /usr/lib/apache2/modules/mod_rewrite.so
[Wed Aug 23 14:41:59.197240 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module rpaf_module from /usr/lib/apache2/modules/mod_rpaf.so
[Wed Aug 23 14:41:59.197394 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module setenvif_module from /usr/lib/apache2/modules/mod_setenvif.so
[Wed Aug 23 14:41:59.197600 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module socache_shmcb_module from /usr/lib/apache2/modules/mod_socache_shmcb.so
[Wed Aug 23 14:41:59.200315 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module ssl_module from /usr/lib/apache2/modules/mod_ssl.so
[Wed Aug 23 14:41:59.200789 2023] [so:debug] [pid 16277] mod_so.c(266): AH01575: loaded module status_module from /usr/lib/apache2/modules/mod_status.so

[AUser0]

Axwellweb, хммм, запуск как запуск, без ошибок. Используется конфиг из /etc/apache2/?

[Axwellweb]

AUser0, Да. Проверка конфига тоже ошибки не выдаёт.
Пробовал перезапустить сервер, может ещё какой процесс влияет - тоже не дало результата.

[AUser0]

Axwellweb, systemctl-ом запускаете с LogLevel debug? Вдруг хоть что-нибудь да всплывёт?

[Axwellweb]

AUser0, запустил systemctl-ом - все логи идентичные уже полученным.

Answer 2

[Алексей Черемисин]

Что выдает sudo apachectl configtest?

Comments

[Axwellweb]

Syntax OK

Timeout 300
KeepAlive On
MaxKeepAliveRequests 100
KeepAliveTimeout 2
User www-data
Group www-data

HostnameLookups Off
ErrorLog ${APACHE_LOG_DIR}/error.log

LogLevel warn


IncludeOptional mods-enabled/*.load
IncludeOptional mods-enabled/*.conf

Include ports.conf


<Directory />
	Options FollowSymLinks
	AllowOverride None
	Require all denied
</Directory>

<Directory /usr/share>
	AllowOverride None
	Require all granted
</Directory>

<Directory /var/www/>
	Options Indexes FollowSymLinks
	AllowOverride None
	Require all granted
</Directory>

AccessFileName .htaccess

<FilesMatch "^\.ht">
	Require all denied
</FilesMatch>

<ifmodule mod_deflate.c>
    AddOutputFilterByType DEFLATE text/html text/plain text/xml text/css text/javascript application/javascript
    DeflateCompressionLevel 8
  </ifmodule>

<IfModule mpm_prefork_module>
	StartServers 1
	MinSpareServers 3
	MaxSpareServers 5
	MaxConnectionsPerChild 256	
	MaxRequestWorkers 256
</IfModule>

LogFormat "%v:%p %h %l %u %t \"%r\" %>s %O \"%{Referer}i\" \"%{User-Agent}i\"" vhost_combined
LogFormat "%a %l %u %t \"%r\" %>s %O \"%{Referer}i\" \"%{User-Agent}i\"" combined
LogFormat "%h %l %u %t \"%r\" %>s %O" common
LogFormat "%{Referer}i -> %U" referer
LogFormat "%{User-agent}i" agent

IncludeOptional conf-enabled/*.conf

IncludeOptional sites-enabled/*.conf

Include /etc/phpmyadmin/apache.conf