All,
I ran icecast on openSUSE 12.1 and upgraded to 13.1. I now get timeout messages of icecast.
First: this is my environment:
schuurpc:/var/log/icecast # rpm -qa icecast
icecast-2.3.3-2.8.1.i586
schuurpc:/var/log/icecast # uname -a
Linux schuurpc 3.11.10-21-default #1 SMP Mon Jul 21 15:28:46 UTC 2014 (9a9565d) i686 i686 i386 GNU/Linux
schuurpc:/var/log/icecast # cat /etc/os-release
NAME=openSUSE
VERSION="13.1 (Bottle)"
VERSION_ID="13.1"
PRETTY_NAME="openSUSE 13.1 (Bottle) (i586)"
ID=opensuse
ANSI_COLOR="0;32"
CPE_NAME="cpe:/o:opensuse:opensuse:13.1"
BUG_REPORT_URL="https://bugs.opensuse.org"
HOME_URL="https://opensuse.org/"
ID_LIKE="suse"
schuurpc:/var/log/icecast
To get some data out of it, I used two terminal windows. One to start icecast and the other one to trace the log and process-id. I added some timestamps to be able to sync the two terminal windows.
Here is the first terminal output:
schuurpc:/var/log/icecast # date
Mon Nov 10 19:38:35 CET 2014
schuurpc:/var/log/icecast # ps -u icecast
PID TTY TIME CMD
schuurpc:/var/log/icecast # date
Mon Nov 10 19:38:58 CET 2014
schuurpc:/var/log/icecast # systemctl start icecast
Job for icecast.service failed. See 'systemctl status icecast.service' and 'journalctl -xn' for details.
schuurpc:/var/log/icecast # date
Mon Nov 10 19:40:39 CET 2014
schuurpc:/var/log/icecast # systemctl status icecast.service
icecast.service - Icecast Server
Loaded: loaded (/usr/lib/systemd/system/icecast.service; enabled)
Active: failed (Result: timeout) since Mon 2014-11-10 19:40:36 CET; 10s ago
Process: 7908 ExecStart=/usr/bin/icecast -b -c /etc/icecast.xml (code=exited, status=0/SUCCESS)
Nov 10 19:39:06 schuurpc icecast[7908]: Starting icecast2
Nov 10 19:39:06 schuurpc icecast[7908]: Detaching from the console
Nov 10 19:39:06 schuurpc systemd[1]: PID file /var/run/icecast.pid not readable (yet?) after start.
Nov 10 19:40:36 schuurpc systemd[1]: icecast.service operation timed out. Terminating.
Nov 10 19:40:36 schuurpc systemd[1]: Failed to start Icecast Server.
Nov 10 19:40:36 schuurpc systemd[1]: Unit icecast.service entered failed state.
schuurpc:/var/log/icecast #
As you can see it received a timeout. And the second terminal tracing process-id and log:
schuurpc:/var/log/icecast # date
Mon Nov 10 19:38:44 CET 2014
schuurpc:/var/log/icecast # ls -l
total 0
-rw-r--r-- 1 icecast icecast 0 Nov 10 19:25 access.log
-rw-r--r-- 1 icecast icecast 0 Nov 10 19:37 error.log
schuurpc:/var/log/icecast # date
Mon Nov 10 19:39:13 CET 2014
schuurpc:/var/log/icecast # cat error.log
[2014-11-10 19:39:06] INFO main/main Icecast 2.3.3 server started
[2014-11-10 19:39:06] DBUG yp/yp_recheck_config Updating YP configuration
[2014-11-10 19:39:06] INFO stats/_stats_thread stats thread started
[2014-11-10 19:39:06] INFO connection/get_ssl_certificate No SSL capability on any configured ports
[2014-11-10 19:39:06] INFO yp/yp_update_thread YP update thread started
[2014-11-10 19:39:07] DBUG slave/_slave_thread checking master stream list
schuurpc:/var/log/icecast # ps -u icecast
PID TTY TIME CMD
7909 ? 00:00:00 icecast
schuurpc:/var/log/icecast # date
Mon Nov 10 19:40:51 CET 2014
schuurpc:/var/log/icecast # ps -u icecast
PID TTY TIME CMD
schuurpc:/var/log/icecast # date
Mon Nov 10 19:41:00 CET 2014
schuurpc:/var/log/icecast # cat error.log
[2014-11-10 19:39:06] INFO main/main Icecast 2.3.3 server started
[2014-11-10 19:39:06] DBUG yp/yp_recheck_config Updating YP configuration
[2014-11-10 19:39:06] INFO stats/_stats_thread stats thread started
[2014-11-10 19:39:06] INFO connection/get_ssl_certificate No SSL capability on any configured ports
[2014-11-10 19:39:06] INFO yp/yp_update_thread YP update thread started
[2014-11-10 19:39:07] DBUG slave/_slave_thread checking master stream list
**[2014-11-10 19:40:36] INFO sighandler/_sig_die Caught signal 15, shutting down...**
[2014-11-10 19:40:36] INFO main/main Shutting down
[2014-11-10 19:40:36] INFO fserve/fserve_shutdown file serving stopped
[2014-11-10 19:40:36] DBUG slave/slave_shutdown waiting for slave thread
[2014-11-10 19:40:36] INFO slave/_slave_thread shutting down current relays
[2014-11-10 19:40:36] INFO slave/_slave_thread Slave thread shutdown complete
[2014-11-10 19:40:36] INFO auth/auth_shutdown Auth shutdown
[2014-11-10 19:40:36] INFO yp/yp_shutdown YP thread down
[2014-11-10 19:40:36] INFO stats/stats_shutdown stats thread finished
schuurpc:/var/log/icecast #
My conclusion is that it did get started … but somehow it was killed, see bold line, for some reason (by systemd???)
Just to be sure that icecast worked as expected I cleaned the error.log and started icecast from the command line in a third terminal window.
Here is the output
schuurpc:/var/log # date
Mon Nov 10 19:48:43 CET 2014
schuurpc:/var/log # /usr/bin/icecast -b -c /etc/icecast.xml
Starting icecast2
Detaching from the console
schuurpc:/var/log # Changed groupid to 481.
Changed userid to 482.
date
Mon Nov 10 19:48:57 CET 2014
schuurpc:/var/log #
In the other terminal I traced the log and process-id
schuurpc:/var/log/icecast # date
Mon Nov 10 19:48:39 CET 2014
schuurpc:/var/log/icecast # ls -l
total 0
-rw-r--r-- 1 icecast icecast 0 Nov 10 19:25 access.log
-rw-r--r-- 1 icecast icecast 0 Nov 10 19:47 error.log
schuurpc:/var/log/icecast # date
Mon Nov 10 19:49:03 CET 2014
schuurpc:/var/log/icecast # ls -l
total 4
-rw-r--r-- 1 icecast icecast 0 Nov 10 19:25 access.log
-rw-r--r-- 1 icecast icecast 461 Nov 10 19:48 error.log
schuurpc:/var/log/icecast # cat error.log
[2014-11-10 19:48:46] INFO main/main Icecast 2.3.3 server started
[2014-11-10 19:48:46] DBUG yp/yp_recheck_config Updating YP configuration
[2014-11-10 19:48:46] INFO stats/_stats_thread stats thread started
[2014-11-10 19:48:46] INFO connection/get_ssl_certificate No SSL capability on any configured ports
[2014-11-10 19:48:46] INFO yp/yp_update_thread YP update thread started
[2014-11-10 19:48:47] DBUG slave/_slave_thread checking master stream list
schuurpc:/var/log/icecast # date
Mon Nov 10 19:49:20 CET 2014
schuurpc:/var/log/icecast # ps -u icecast
PID TTY TIME CMD
8350 pts/4 00:00:00 icecast
schuurpc:/var/log/icecast #
I closed the 3th terminal (from which icecast was started) and went back to the terminal to trace the log and process-id. Here’s the output
schuurpc:/var/log/icecast # date
Mon Nov 10 19:51:42 CET 2014
schuurpc:/var/log/icecast # ps -u icecast
PID TTY TIME CMD
8350 ? 00:00:00 icecast
schuurpc:/var/log/icecast # cat error.log
[2014-11-10 19:48:46] INFO main/main Icecast 2.3.3 server started
[2014-11-10 19:48:46] DBUG yp/yp_recheck_config Updating YP configuration
[2014-11-10 19:48:46] INFO stats/_stats_thread stats thread started
[2014-11-10 19:48:46] INFO connection/get_ssl_certificate No SSL capability on any configured ports
[2014-11-10 19:48:46] INFO yp/yp_update_thread YP update thread started
[2014-11-10 19:48:47] DBUG slave/_slave_thread checking master stream list
[2014-11-10 19:50:47] DBUG slave/_slave_thread checking master stream list
schuurpc:/var/log/icecast #
Conclusion: Icecast does work as expected if it’s started from the command line but NOT if it’s started by systemd.
Anybody any idea what’s going?? Any tips/trics are appreciated!!