Hi. I'm running Logitech Media Server Version: 7.9.1 - 1515216179 on
Linux Mint 17.
I have LMS set to rescan music folders twice a day.
However, the last morning scan was still running hours after it should
have completed. So I hit "abort scan" and tried to re-run the scan from
the LMS user interface and by logging into the Linux Mint machine via
ssh and running the scanner via the command line. Both efforts failed
with the following messages:
Code:
--------------------
[18-01-09 14:40:54.5286] main::main (205) Starting Logitech Media Server
scanner (v7.9.1, 1515216179, Sat Jan 6 06:03:58 UTC 2018) perl 5.018002
[18-01-09 14:40:54.6869] Slim::Schema::forceCommit (2149) Warning: Trying to
commit transactions before DB is initialized!
[18-01-09 14:40:54.8413] Slim::Music::Import::runImporter (511) Starting
Slim::Media::MediaFolderScan scan
[18-01-09 14:40:54.8417] Slim::Utils::Scanner::Local::rescan (181)
Discovering audio files in /mnt/MUSIC1
[18-01-09 14:40:56.9001] Slim::Utils::SQLiteHelper::updateProgress (446)
Warning: Server aborted scan, shutting down
--------------------
The LMS media server log file is as follows:
Code:
--------------------
2018-01-09 11:17:35 squeezeboxserver_safe started.
[18-01-09 11:17:36.5711] main::init (387) Starting Logitech Media Server
(v7.9.1, 1515216179, Sat Jan 6 06:03:58 UTC 2018) perl 5.018002 -
x86_64-linux-gnu-thread-multi
[18-01-09 11:17:36.7840] Slim::Utils::IPDetect::_init (113) Warning: Couldn't
call connect() - falling back to 127.0.0.1
[18-01-09 11:17:37.3050] Plugins::NPRRadio::Metadata::init (21) Register NPR
meta provider, default icon=
[18-01-09 11:17:37.4567] Slim::Networking::Async::Socket::UDP::mcast_add (48)
Error: While adding multicast membership, UPnP may not work properly: No such
device
[18-01-09 11:17:38.9987] Slim::Utils::Misc::msg (1250) Warning:
[11:17:38.9984] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:38.9992] Slim::Utils::Misc::msg (1250) Warning:
[11:17:38.9990] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:40.9974] Slim::Utils::Misc::msg (1250) Warning:
[11:17:40.9971] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:40.9979] Slim::Utils::Misc::msg (1250) Warning:
[11:17:40.9977] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:45.9978] Slim::Utils::Misc::msg (1250) Warning:
[11:17:45.9975] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:45.9983] Slim::Utils::Misc::msg (1250) Warning:
[11:17:45.9981] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:47.9994] Slim::Utils::Misc::msg (1250) Warning:
[11:17:47.9991] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:47.9997] Slim::Utils::Misc::msg (1250) Warning:
[11:17:47.9996] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:55.9985] Slim::Utils::Misc::msg (1250) Warning:
[11:17:55.9983] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:55.9989] Slim::Utils::Misc::msg (1250) Warning:
[11:17:55.9988] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:58.0006] Slim::Utils::Misc::msg (1250) Warning:
[11:17:57.0002] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:17:58.0016] Slim::Utils::Misc::msg (1250) Warning:
[11:17:58.0012] EV: error in callback (ignoring): pack_sockaddr: invalid host
at /usr/share/squeezeboxserver/lib/AnyEvent/DNS.pm line 1114.
[18-01-09 11:18:01.0594] Slim::Formats::XML::gotErrorViaHTTP (338) Error:
getting http://www.mysqueezebox.com/api/sounds/v1/opml
Couldn't resolve IP address for: www.mysqueezebox.com
[18-01-09 11:18:01.0596] Slim::Plugin::Sounds::Plugin::_gotSoundsError (83)
Error: Unable to cache Sounds & Effects menu from SN: Couldn't resolve IP
address for: www.mysqueezebox.com
[18-01-09 11:18:03.0034] Slim::Formats::XML::gotErrorViaHTTP (338) Error:
getting http://opml.radiotime.com/Index.aspx?partnerId=16
Couldn't resolve IP address for: opml.radiotime.com
[18-01-09 11:18:03.0037] Slim::Plugin::InternetRadio::Plugin::_gotRadioError
(67) Unable to retrieve radio directory from SN: Couldn't resolve IP address
for: opml.radiotime.com
--------------------
This is the complete log file.
After rebooting I run another scan and the scanner runs a seemingly
endless loop attempting to handle a deleted file which was never deleted
from the hard drive.
Code:
--------------------
[18-01-09 14:56:59.7501] main::main (205) Starting Logitech Media Server
scanner (v7.9.1, 1515216179, Sat Jan 6 06:03:58 UTC 2018) perl 5.018002
[18-01-09 14:56:59.9136] Slim::Schema::forceCommit (2149) Warning: Trying to
commit transactions before DB is initialized!
[18-01-09 14:57:00.0688] Slim::Music::Import::runImporter (511) Starting
Slim::Media::MediaFolderScan scan
[18-01-09 14:57:00.0693] Slim::Utils::Scanner::Local::rescan (181)
Discovering audio files in /mnt/MUSIC1
[18-01-09 14:57:41.0078] Slim::Utils::Scanner::Local::__ANON__ (193) Start
processing found tracks
[18-01-09 14:57:41.0082] Slim::Utils::Scanner::Local::__ANON__ (201) Connect
do DB
[18-01-09 14:57:41.0083] Slim::Utils::Scanner::Local::__ANON__ (204) Get
latest ID
[18-01-09 14:57:41.0087] Slim::Utils::Scanner::Local::__ANON__ (226) Delete
temporary table if exists
[18-01-09 14:57:41.0091] Slim::Utils::Scanner::Local::__ANON__ (229) Re-build
temporary table
[18-01-09 14:57:41.9794] Slim::Utils::Scanner::Local::__ANON__ (278) Get
deleted tracks count
[18-01-09 14:57:43.1711] Slim::Utils::Scanner::Local::__ANON__ (285) Get new
tracks count
[18-01-09 14:57:43.1714] Slim::Utils::Scanner::Local::__ANON__ (290) Get
changed tracks count
[18-01-09 14:57:44.5447] Slim::Utils::Scanner::Local::__ANON__ (296) Removing
deleted audio files (1)
[18-01-09 14:57:45.6922] Slim::Utils::Scanner::Local::deleted (828) Handling
deleted file file:///mnt/PATHTOMUSICFILE
[18-01-09 14:57:46.8204] Slim::Utils::Scanner::Local::deleted (828) Handling
deleted file
--------------------
This is just the top of the scanner log - it goes on like this for more
than 100 attempts to "delete" the same file which is still on the hard
drive. Eventually I have to stop LMS manually.
This has happened numerous times - an endless loop of trying to delete a
file from LMS which has not been deleted from my music library. And, LMS
has successfully scanned this mp3 file for about three years. I suppose
something could have happened to it - but I just listened to it, for
whatever that might be worth.
Thanks for your time.
gmachine_24
------------------------------------------------------------------------
gmachine_24's Profile: http://forums.slimdevices.com/member.php?userid=3999
View this thread: http://forums.slimdevices.com/showthread.php?t=108543
_______________________________________________
Squeezecenter mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/squeezecenter
