PDA

View Full Version : Can't connect to LMS after reboot



MartinK
2017-01-24, 09:25
i've recently updated my old LMS installation to 7.9 on my ubuntu server (16.10) which i've had forever. After doing the installation everything works perfectly - music found, prefs found, player found, scan perfect, everything !! However, when I reboot LMS comes up as LMS_safe and I can no longer connect to port 9000. Although, the server appears to be running. There is no firewall and I can ping the outside world in all directions. I have tried this several times, deleting and purging LMS, deleting the startup script in init.d, deleting the user and group settings everything. Again, after a fresh install everything works until i reboot. Strangely I don't find a log file under /var/logs/ and I have 2 instances of Squeezeboxserver running - one normal one _safe. It almost seems like LMS is crashing immediately and restarting. Shurely that's not right?

I remember from long ago there used to be a page with a detailed installation guide and how to setup init.d and all the rest (including iptables and Sqlite) I wonder where all that has gone?

mherger
2017-01-24, 10:20
> everything !! However, when I reboot LMS comes up as LMS_safe and I can

What is this LMS_safe?

> no longer connect to port 9000. Although, the server appears to be
> running. There is no firewall and I can ping the outside world in all

How do you find out about the server being active?

> directions. I have tried this several times, deleting and purging LMS,
> deleting the startup script in init.d, deleting the user and group
> settings everything. Again, after a fresh install everything works until
> i reboot.

Tell us a little more about your system: what platform, what network
etc. It might be LMS is being started before the network was ready? I
think somebody mentioned somewhere adding a dependency on the network
(somehow, don't remember the details).

--

Michael

drmatt
2017-01-24, 11:34
(I believe LMS_safe is the parent script that kicks off the main daemons. It's what you might see in the process table if LMS main thread fails to start. I've seen this a few times when I was fighting Perl version mismatches.. :) )

MartinK
2017-01-24, 11:41
Sorry for not being more specific.

The system is intel based D510MO board with 2 x1TB disks. LVM configuration. There are no errors in the system logs.

LMS_safe = 'logitechmediaserver' plus the '_safe' suffix. Which apparently is needed to reconnect to mysql if I correctly understood the code in the startup script (the one that goes into init.d). Hence my suspicion that the original server had crashed. But since there is no log file I can't tell for sure.

I can see LMS is running because it shows in htop and after doing a full apt-get purge i see the scanner is running and the data base is being populated. (so i guess the link to mysql is working).

Not sure about the network though, but I have temporarily renamed the startup script in init.d and started LMS manually, alas to no avail. Although starting manually shows a normal version of the server (without the _safe). But still telneting into port 9000 shows connection refused. (9090, 3483s). There is nothing else beside the LMS running on the server except for samba sharing the media directory to my windows machine. Which is no longer connected. And also Webmin is running off port 10000. Which by the way is working. (there is no firewall or iptables settings ).

Maybe the problem is related to the fact the the overall system configuration dates back to an old 11.04 version that has gradually been upgraded. Although everything else seems to be working and I don't have any errors in the log and there is no /var/log/squeezebox.log or similar.

Doing a complete reinstall over an existing installation picks up all the data and gives a system running perfectly normal - until I reboot....

MartinK
2017-01-24, 11:48
(I believe LMS_safe is the parent script that kicks off the main daemons. It's what you might see in the process table if LMS main thread fails to start. I've seen this a few times when I was fighting Perl version mismatches.. :) )

Perl on my system (ubuntu64 16.10)

This is perl 5, version 22, subversion 2 (v5.22.2) built for x86_64-linux-gnu-thread-multi
(with 67 registered patches, see perl -V for more detail)

Copyright 1987-2015, Larry Wall

DJanGo
2017-01-24, 12:46
i've recently updated my old LMS installation to 7.9 on my ubuntu server (16.10) which i've had forever.

Ubuntu 16.10 (Yakkety Yak) was released @ 13.10.2016 your forever seems not long ago?


But still telneting into port 9000 shows connection refused port 9000 is used from the webfrontend not from telnet.



renamed the startup script in init.d ok thats a pretty strange idea - i would not recommend that...

I would say - your system has some nice addons / hacks and its worth to start from scratch without doing such cool things:o

lms runs fine if the operating system is fine.

MartinK
2017-01-24, 13:45
Ubuntu 16.10 (Yakkety Yak) was released @ 13.10.2016 your forever seems not long ago?

port 9000 is used from the webfrontend not from telnet.


ok thats a pretty strange idea - i would not recommend that...

I would say - your system has some nice addons / hacks and its worth to start from scratch without doing such cool things:o

lms runs fine if the operating system is fine.



Sure. Let me clarify >>

But port 9000 should still be accessible right? When it says refused connection there is something not OK. Regardless of whether you can telnet into that port or not.

I upgraded gradually from years back and now I'm on 16.10. Maybe i dragged something along. ??

Renaming logitechmediaserver in init.d was meant to prevent automatic start at boot and allow for manual start to make sure the network was up and running without deleting the original file. I have no idea where the startup script came from. My idea was that LMS installation puts it there. Can't remember I ever put it there.

Apart from that there have been absolutely no hacks on the system. Besides there are no errors in the logs whatsoever....

DJanGo
2017-01-24, 14:48
Apart from that there have been absolutely no hacks on the system.
ok - just thought there is some text like
deleting the user and group settings everything and as admin@work i do really know the stories from the customers who told me "i didnt change anything" so i tought i am prepared when you wrote your story (thats just the start).


Ubuntu / debian 8 didnt use Sysvinit anymore - they use systemd.
If you "hacking" inside /etc/init.d thats not the way.

Whats happen if you reinstall the server, stop the server (not the hardware - only lms) and restart the lms server?

What told you systemctl status ?

i think - fastet way to solve your problem is start from scratch and i would recommend a lts Version of ubuntu like 16.04 instead of a shorttermsupport version like 16.10.

drmatt
2017-01-24, 15:40
Ok I think we are getting away from the point a little here.

A couple of things: init.d still works, systemd is required to run non-compliant init.d scripts just like before. Unless someone has modified the LMS startup to deliberately bolt into systemd then its behaviour will still be dictated by the presence or otherwise of files in init.d.

Second Telnet is more a generic TCP connection method than a terminal. If port 9000 responds to your web browser, it will respond to telnet. If it doesn't respond to telnet you are pretty sure there's nothing listening on port 9000.

Something of course changed, but you need to get a log of a failed LMS startup to debug it.

Fwiw my Debian OS on my server has been continuously upgraded since around 2004, through numerous hardware upgrades and I've never had to even consider a fresh install. (There are orphaned config files from a decade ago, seriously. I know, I should clean up, but I now wear this cruft with some pride! :) ) Dpkg is very good at handling upgrades..

mherger
2017-01-24, 16:23
> LMS_safe = 'logitechmediaserver' plus the '_safe' suffix. Which

The _safe task is a process watcher which would restart the main task if
needed. You should always see both of them, or something's wrong.

I think the LMS executable is stored in /usr/sbin or /usr/local/sbin.
Could you try to run this from the command line with the additional
--d_startup --debug=server parameters? Anything interesting there?

--

Michael

MartinK
2017-01-25, 04:02
ok - just thought there is some text like and as admin@work i do really know the stories from the customers who told me "i didnt change anything" so i tought i am prepared when you wrote your story (thats just the start).


Ubuntu / debian 8 didnt use Sysvinit anymore - they use systemd.
If you "hacking" inside /etc/init.d thats not the way.

Whats happen if you reinstall the server, stop the server (not the hardware - only lms) and restart the lms server?

What told you systemctl status ?

i think - fastet way to solve your problem is start from scratch and i would recommend a lts Version of ubuntu like 16.04 instead of a shorttermsupport version like 16.10.

Thanks for the useful information:

sytemctl status show this:

root@sbox:~# systemctl status
● sbox
State: degraded
Jobs: 0 queued
Failed: 4 units
Since: Wed 2017-01-25 09:51:45 CET; 16min ago
CGroup: /
├─user.slice
│ └─user-0.slice

...

├─ifup@eth1.service
│ └─806 /sbin/dhclient -1 -v -pf /run/dhclient.eth1.pid -lf /var/lib/dhcp/dhclient.eth1.leases -I -df /var/lib/dhcp/dhclient6.eth1.leases eth1
├─rsyslog.service
│ └─656 /usr/sbin/rsyslogd -n
├─logitechmediaserver.service
│ ├─2617 /bin/bash /usr/sbin/squeezeboxserver_safe /usr/sbin/squeezeboxserver --prefsdir /var/lib/squeezeboxserver/prefs --logdir /var/log/squeezeboxserver/ --cachedir /var/lib/squeezeboxserver/cache
│ └─2646 sleep 5
├─systemd-journald.service
│ └─310 /lib/systemd/systemd-journald


When LMS is working after a fresh instalL and I look up the log files I get this:

2017-01-25 11:18:12 squeezeboxserver_safe started.
2017-01-25 11:18:17 Logitech Media Server died. Restarting.
2017-01-25 11:18:22 Logitech Media Server died. Restarting.
2017-01-25 11:18:27 Logitech Media Server died. Restarting.
2017-01-25 11:18:32 Logitech Media Server died. Restarting.
2017-01-25 11:18:37 Logitech Media Server died. Restarting.
2017-01-25 11:18:42 Logitech Media Server died. Restarting.
2017-01-25 11:18:47 Logitech Media Server died. Restarting.
2017-01-25 11:18:52 Logitech Media Server died. Restarting.
2017-01-25 11:18:57 Logitech Media Server died. Restarting.
2017-01-25 11:19:02 Logitech Media Server died. Restarting.
2017-01-25 11:19:07 Logitech Media Server died. Restarting.
2017-01-25 11:19:12 Logitech Media Server died. Restarting.
2017-01-25 11:19:17 Logitech Media Server died. Restarting.
2017-01-25 11:19:23 Logitech Media Server died. Restarting.
2017-01-25 11:19:28 Logitech Media Server died. Restarting.
2017-01-25 11:19:33 Logitech Media Server died. Restarting.
2017-01-25 11:19:38 Logitech Media Server died. Restarting.
2017-01-25 11:19:43 Logitech Media Server died. Restarting.
2017-01-25 11:19:48 Logitech Media Server died. Restarting.
2017-01-25 11:19:53 Logitech Media Server died. Restarting.
2017-01-25 11:19:58 squeezeboxserver_safe stopped.
2017-01-25 11:20:55 squeezeboxserver_safe started.
[17-01-25 11:20:59.7112] main::init (388) Starting Logitech Media Server (v7.9.0, 1484464959, Mon Jan 23 04:38:38 UTC 2017) perl 5.022002 - x86_64-linux-gnu-thread-multi
[17-01-25 11:21:07.3413] Slim::Networking::SqueezeNetwork::_error (561) Unable to login to SN: Invalid mysqueezebox.com username or password.
[17-01-25 11:21:07.3423] Slim::Networking::SqueezeNetwork::_init_error (184) Unable to login to mysqueezebox.com, sync is disabled: Invalid mysqueezebox.com username or password. (http://www.mysqueezebox.com)
[17-01-25 11:21:07.3435] Slim::Networking::SqueezeNetwork::_init_error (200) mysqueezebox.com sync init failed: Invalid mysqueezebox.com username or password., will retry in 8400 (http://www.mysqueezebox.com)
2017-01-25 11:38:30 squeezeboxserver_safe stopped.
2017-01-25 11:38:44 squeezeboxserver_safe started.
[17-01-25 11:38:47.6960] main::init (388) Starting Logitech Media Server (v7.9.0, 1484464959, Mon Jan 23 04:38:38 UTC 2017) perl 5.022002 - x86_64-linux-gnu-thread-multi
[17-01-25 11:38:53.3681] Slim::Networking::SqueezeNetwork::_error (561) Unable to login to SN: Invalid mysqueezebox.com username or password.
[17-01-25 11:38:53.3691] Slim::Networking::SqueezeNetwork::_init_error (184) Unable to login to mysqueezebox.com, sync is disabled: Invalid mysqueezebox.com username or password. (http://www.mysqueezebox.com)
[17-01-25 11:38:53.3703] Slim::Networking::SqueezeNetwork::_init_error (200) mysqueezebox.com sync init failed: Invalid mysqueezebox.com username or password., will retry in 8700 (http://www.mysqueezebox.com)

I am logged on to my squeezebox2 no problem but LMS keeps showing that my credentials are not correct. The player works fine connected to internet and server.

I can start and stop LMS using systemctl start/stop logitechmediaserver and it keeps working perfectly. It's just during boot that LMS is restarted several times (see log). I will try to put a sleep 10 in the init.d script and see if that helps. LMS only stop working after a full hw reboot.

With removing everything I meant removing the user pertaining to LMS and the current startup script in init.d followed by apt-get remove logitechmediaserver. Nothing else changes.

However, reinstalling 7.9 does not create a new init.d script from scratch. 7.9 only replaces an existing init.d/logitechmediaserver file if one existed before. 7.9 does not create a new one if none existed before. Previous versions of LMS (7.7, 7.8) always created a new init.d script and gave the option to keep the previous version. Putting in an empty stub and running the installation correctly creates a new init.d script.

I assume that having different names for the services and the underlying directories comes from the name changes Slimserver has undergone over the years...

MartinK
2017-01-25, 05:38
> LMS_safe = 'logitechmediaserver' plus the '_safe' suffix. Which

The _safe task is a process watcher which would restart the main task if
needed. You should always see both of them, or something's wrong.

I think the LMS executable is stored in /usr/sbin or /usr/local/sbin.
Could you try to run this from the command line with the additional
--d_startup --debug=server parameters? Anything interesting there?

--

Michael


I don't know how to do that. You you mean using a similar command than the one in init.d/logitechmediaserver and run it as a console task without all the extra bits?

mherger
2017-01-25, 06:32
> I don't know how to do that. You you mean using a similar command than
> the one in init.d/logitechmediaserver and run it as a console task
> without all the extra bits?

Yes, just run the command:

/usr/sbin/logitechmediaserver

in a terminal window (please adjust the path and filename accordingly...)

--

Michael

MartinK
2017-01-25, 06:55
I have also noticed that the 7.9 installation script does not generate a new init.d/logitechmediaserver startup script like all the old version used to do (7.7, 7.8). It does however, update an existing file if one exists but it does not generate a new one. As a result I'm not quite sure if the file i'm currently using is the most recent one.

MartinK
2017-01-25, 08:36
I copied the full command string and appended --d_startup --debug >> dump.log


this is dump.log:

Found SELinux - setting security context to: texrel_shlib_t for *.so files.
Got @INC containing:
/usr/share/squeezeboxserver/CPAN
/usr/share/squeezeboxserver
/usr/sbin
/etc/perl
/usr/local/lib/x86_64-linux-gnu/perl/5.22.2
/usr/local/share/perl/5.22.2
/usr/lib/x86_64-linux-gnu/perl5/5.22
/usr/share/perl5
/usr/lib/x86_64-linux-gnu/perl/5.22
/usr/share/perl/5.22
/usr/local/lib/site_perl
/usr/lib/x86_64-linux-gnu/perl-base
.

Extended @INC to contain:
/usr/share/squeezeboxserver/CPAN/arch/5.22/x86_64-linux-thread-multi
/usr/share/squeezeboxserver/CPAN/arch/5.22/x86_64-linux-thread-multi/auto
/usr/share/squeezeboxserver/CPAN/arch/5.22.2/x86_64-linux-gnu-thread-multi
/usr/share/squeezeboxserver/CPAN/arch/5.22.2/x86_64-linux-gnu-thread-multi/auto
/usr/share/squeezeboxserver/CPAN/arch/5.22/x86_64-linux-gnu-thread-multi
/usr/share/squeezeboxserver/CPAN/arch/5.22/x86_64-linux-gnu-thread-multi/auto
/usr/share/squeezeboxserver/CPAN/arch/x86_64-linux-gnu-thread-multi
/usr/share/squeezeboxserver/CPAN/arch/5.22
/usr/share/squeezeboxserver/lib
/usr/share/squeezeboxserver/CPAN
/usr/share/squeezeboxserver
/usr/share/squeezeboxserver/CPAN
/usr/share/squeezeboxserver
/usr/sbin
/etc/perl
/usr/local/lib/x86_64-linux-gnu/perl/5.22.2
/usr/local/share/perl/5.22.2
/usr/lib/x86_64-linux-gnu/perl5/5.22
/usr/share/perl5
/usr/lib/x86_64-linux-gnu/perl/5.22
/usr/share/perl/5.22
/usr/local/lib/site_perl
/usr/lib/x86_64-linux-gnu/perl-base
.

Loaded module: [version] ok!
Loaded module: [Time::HiRes] ok!
Loaded module: [DBI] ok!
Loaded module: [EV] ok!
Loaded module: [XML::Parser::Expat] ok!
Loaded module: [HTML::Parser] ok!
Loaded module: [JSON::XS] ok!
Loaded module: [Digest::SHA1] ok!
Loaded module: [YAML::XS] ok!
Loaded module: [Sub::Name] ok!
The following modules are loaded after the first attempt:
lib.pm => /usr/lib/x86_64-linux-gnu/perl/5.22/lib.pm
locale.pm => /usr/share/perl/5.22/locale.pm
strict.pm => /usr/share/perl/5.22/strict.pm
warnings/register.pm => /usr/share/perl/5.22/warnings/register.pm
....
DynaLoader.pm => /usr/lib/x86_64-linux-gnu/perl/5.22/DynaLoader.pm
YAML/XS.pm => /usr/share/squeezeboxserver/CPAN/YAML/XS.pm
vars.pm => /usr/share/perl/5.22/vars.pm
File/Spec/Functions.pm => /usr/lib/x86_64-linux-gnu/perl/5.22/File/Spec/Functions.pm
common/sense.pm => /usr/share/squeezeboxserver/CPAN/common/sense.pm

The following modules are loaded after the second attempt:
lib.pm => /usr/lib/x86_64-linux-gnu/perl/5.22/lib.pm
locale.pm => /usr/share/perl/5.22/locale.pm
strict.pm => /usr/share/perl/5.22/strict.pm
....
POSIX.pm => /usr/lib/x86_64-linux-gnu/perl/5.22/POSIX.pm
Slim/Utils/OS/Unix.pm => /usr/share/perl5/Slim/Utils/OS/Unix.pm
DynaLoader.pm => /usr/lib/x86_64-linux-gnu/perl/5.22/DynaLoader.pm
YAML/XS.pm => /usr/share/squeezeboxserver/CPAN/YAML/XS.pm
vars.pm => /usr/share/perl/5.22/vars.pm
File/Spec/Functions.pm => /usr/lib/x86_64-linux-gnu/perl/5.22/File/Spec/Functions.pm
common/sense.pm => /usr/share/squeezeboxserver/CPAN/common/sense.pm

[17-01-25 15:40:35.5645] main::init (388) Starting Logitech Media Server (v7.9.0, 1484464959, Mon Jan 23 04:38:38 UTC 2017) perl 5.022002 - x86_64-linux-gnu-thread-multi
[17-01-25 15:40:35.5764] main::changeEffectiveUserAndGroup (1005) Warning: Logitech Media Server must not be run as root! Trying user squeezeboxserver instead.
Loaded module: [DBD::SQLite] ok!
Loaded module: [Template::Stash::XS] ok!
Loaded module: [Slim::Plugin::AppGallery::Plugin] ok!
Loaded module: [Slim::Plugin::CLI::Plugin] ok!
Loaded module: [Slim::Plugin::DateTime::Plugin] ok!
Loaded module: [Slim::Plugin::DigitalInput::Plugin] ok!
Loaded module: [Slim::Plugin::DnDPlay::Plugin] ok!
Loaded module: [Slim::Plugin::DontStopTheMusic::Plugin] ok!
Loaded module: [Slim::Plugin::ExtendedBrowseModes::Plugin] ok!
Loaded module: [Slim::Plugin::Extensions::Plugin] ok!
Loaded module: [Slim::Plugin::Favorites::Plugin] ok!
Loaded module: [Slim::Plugin::FullTextSearch::Plugin] ok!
Loaded module: [Slim::Plugin::InfoBrowser::Plugin] ok!
Loaded module: [Slim::Plugin::InternetRadio::Plugin] ok!
Loaded module: [Slim::Plugin::MOG::Plugin] ok!
Loaded module: [Slim::Plugin::MyApps::Plugin] ok!
Loaded module: [Slim::Plugin::NetTest::Plugin] ok!
Loaded module: [Slim::Plugin::OPMLGeneric::Plugin] ok!
Loaded module: [Plugins::PowerSave::Plugin] ok!
Loaded module: [Slim::Plugin::RandomPlay::Plugin] ok!
Loaded module: [Slim::Plugin::RemoteLibrary::Plugin] ok!
Loaded module: [Slim::Plugin::Rescan::Plugin] ok!
Loaded module: [Slim::Plugin::SN::Plugin] ok!
Loaded module: [Slim::Plugin::SavePlaylist::Plugin] ok!
Loaded module: [Slim::Plugin::SongScanner::Plugin] ok!
Loaded module: [Slim::Plugin::Sounds::Plugin] ok!
Loaded module: [Slim::Plugin::UPnP::Plugin] ok!
Loaded module: [Plugins::UPnPBridge::Plugin] ok!
Loaded module: [Slim::Plugin::Visualizer::Plugin] ok!
Loaded module: [Slim::Plugin::WiMP::Plugin] ok!
Loaded module: [Slim::Plugin::Favorites::OpmlFavorites] ok!

MartinK
2017-01-25, 08:44
21972

this is what I get when LMS runs but I can't connect.

MartinK
2017-01-25, 08:58
the only things awkward are:

Async::HTTP: Unable to load IO::Socket::SSL, will try connecting to SSL servers in non-SSL mode
[17-01-25 16:52:54.3419] main::init (388) Starting Logitech Media Server (v7.9.0, 1484464959, Mon Jan 23 04:38:38 UTC 2017) perl 5.022002 - x86_64-linux-gnu-thread-multi
[17-01-25 16:52:54.3536] main::changeEffectiveUserAndGroup (1005) Warning: Logitech Media Server must not be run as root! Trying user squeezeboxserver instead.

and at the very end of the dump it says:

Can't call method "log" on an undefined value at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender.pm line 189.

Maybe that's why there is no log file in /var/log ... ??

mherger
2017-01-26, 01:36
> I copied the full command string and appended --d_startup --debug >>
> dump.log

And it kept running? It didn't stop? Please run the same command again
without the --daemon or & (if any) so it stays in the terminal window.
Would it just continue to run? Or would you get any failure?

--

Michael

MartinK
2017-01-26, 01:48
Meanwhile I have looked at the installation .deb file and executed all the steps manually. (copied the files and and did all the control instructions). Surprising that gave me a running system. But still it doesn't persist after reboot. Somewhere in the boot process (or shutdown) files from /var/log/ get deleted. I wonder if that is related to CPAM not being able to open an output path and shown in the dump.log. For testing purposes I created to dummy stubs in /var/log. One squeezeboxserver and one logitechmediaserver with the appropriate chmod settings like in the deb control files. After a reboot both files are gone. Seems like some cleanup is happening somewhere.

mherger
2017-01-26, 02:28
> settings like in the deb control files. After a reboot both files are
> gone. Seems like some cleanup is happening somewhere.

But that's something somewhere in your system, not in LMS.

Worst case you could re-direct those folders to some other folder of
your choice, defining the paths in the startup script accordingly.

--
--

Michael

MartinK
2017-01-26, 04:26
I tried to run the server from the console it stops because of some error from CPAN


root@sbox:~# /usr/sbin/squeezeboxserver --prefsdir /var/lib/squeezeboxserver/prefs --logdir /var/log/squeezeboxserver/ --cachedir /var/lib/squeezeboxserver/cache --charset=utf8 --d_server
Async::HTTP: Unable to load IO::Socket::SSL, will try connecting to SSL servers in non-SSL mode
[17-01-26 12:18:17.8296] main::init (388) Starting Logitech Media Server (v7.9.0, 1484464959, Tue Jan 17 04:19:55 UTC 2017) perl 5.022002 - x86_64-linux-gnu-thread-multi
[17-01-26 12:18:17.8782] main::changeEffectiveUserAndGroup (1005) Warning: Logitech Media Server must not be run as root! Trying user squeezeboxserver instead.
Can't call method "log" on an undefined value at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender.pm line 189.

MartinK
2017-01-26, 04:31
this looks like the same problem i'm having??

Thread: Perl problems with LMS 7.9 on Debian sid

mherger
2017-01-26, 05:22
I really think you should start to investigate why your log and cache
folders disappear. LMS failing is just a side-effect.

--

Michael

MartinK
2017-01-26, 05:23
more output:

root@sbox:~# /usr/sbin/squeezeboxserver --prefsdir /var/lib/squeezeboxserver/prefs --logdir /var/log/squeezeboxserver --cachedir /var/lib/squeezeboxserver/cache --charset=utf8 --d_server --debug server
Async::HTTP: Unable to load IO::Socket::SSL, will try connecting to SSL servers in non-SSL mode
[17-01-26 13:18:49.1901] main::init (388) Starting Logitech Media Server (v7.9.0, 1484464959, Tue Jan 17 04:19:55 UTC 2017) perl 5.022002 - x86_64-linux-gnu-thread-multi
[17-01-26 13:18:49.2021] main::init (418) OS Specific init...
[17-01-26 13:18:49.2032] main::init (465) Server settings effective user and group if requested...
[17-01-26 13:18:49.2040] main::changeEffectiveUserAndGroup (1005) Warning: Logitech Media Server must not be run as root! Trying user squeezeboxserver instead.
[17-01-26 13:18:49.2065] main::changeEffectiveUserAndGroup (1076) Running as uid: 107 / gid: 100 100
[17-01-26 13:18:49.2074] main::init (482) Server binary search path init...
[17-01-26 13:18:49.2088] main::init (486) Server PluginManager init...
[17-01-26 13:18:49.2861] main::init (489) Server strings init...
[17-01-26 13:18:49.2906] Slim::Utils::Strings::loadStrings (126) Retrieving string data from string cache: /var/lib/squeezeboxserver/cache/stringcache.x86_64-linux.bin
[17-01-26 13:18:49.3051] main::init (501) Server SQL init (Slim::Utils::SQLiteHelper)...
[17-01-26 13:18:49.3062] main::init (505) Async DNS init...
[17-01-26 13:18:49.3071] main::init (508) Async HTTP init...
[17-01-26 13:18:49.3088] main::init (513) SqueezeNetwork Init...
[17-01-26 13:18:49.3332] main::init (518) Download repositories init...
[17-01-26 13:18:49.3343] main::init (521) Firmware init...
[17-01-26 13:18:49.3360] main::init (524) Server Info init...
[17-01-26 13:18:49.6267] main::init (527) Server IR init...
[17-01-26 13:18:49.6564] main::init (530) Server Request init...
[17-01-26 13:18:49.6755] main::init (533) Server Queries init...
[17-01-26 13:18:49.6766] main::init (536) Server Buttons init...
[17-01-26 13:18:49.6835] main::init (544) UDP init...
[17-01-26 13:18:49.6859] main::init (547) Slimproto Init...
[17-01-26 13:18:49.6875] main::init (550) Cache init...
[17-01-26 13:18:49.9556] main::init (567) Server HTTP init...
[17-01-26 13:18:50.1416] main::init (571) Source conversion init..
[17-01-26 13:18:50.1678] main::init (577) Server Web Settings init...
[17-01-26 13:18:50.2660] main::init (582) Menu init...
[17-01-26 13:18:50.2702] main::init (593) Server Alarms init...
[17-01-26 13:18:50.2712] main::init (597) Server Load Plugins...
[17-01-26 13:18:50.7377] Slim::Utils::Strings::storeExtraStrings (440) Reading extrastrings.json file
[17-01-26 13:18:53.8059] main::init (600) Server Jive init...
Can't call method "log" on an undefined value at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender.pm line 189.
Can't call method "log" on an undefined value at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender.pm line 189.
root@sbox:~#

MartinK
2017-01-27, 14:56
My problem in indeed related to the missing log files. Every time I reboot the directory /var/log/squeezeboxserver disappears. If I manually create a directory and a stub for server.log and start LMS manually everything is fine.

How strange is that? Apart from samba there is nothing running on my server.

If I create additional dummy files in var/log they also get deleted after a reboot. What on earth would cause this to happen?? I don't even know where to look....

DJanGo
2017-01-27, 15:40
My problem in indeed related to the missing log files. Every time I reboot the directory /var/log/squeezeboxserver disappears. If I manually create a directory and a stub for server.log and start LMS manually everything is fine.

How strange is that? Apart from samba there is nothing running on my server.

If I create additional dummy files in var/log they also get deleted after a reboot. What on earth would cause this to happen?? I don't even know where to look....


mount /var/log as tmpfs ?
strange syslog daemon config?
some startupscript ?

MartinK
2017-01-28, 02:29
some obscure idea made me mount /var/log in tmpsfs..... probably when experimenting with my new ssd.

problem solved.