I've got two Radios on WiFi that both have occasional connection issues
with LMS on a Win10 PC.
Don't know when it started, maybe some time after updating LMS to
7.9.0?
The symptoms are:
- the local music I've selected to play is playing for maybe 10 or 15
mins and then stops
- the now playing screen shows the artist/album/artwork from the
previous playlist
- the now playing screen doesn't indicate that anything is playing (no
progress, no play symbol)
There are two workarounds that fix this behavior:
- rebooting the Radio, or
- switching to MYSB and then back to LMS from the Radio's menu
It doesn't seem to be a WiFi/network issue.
If the problem occurs, then:
- from the Radio's Settings/Advanced/Diagnosis/ServerInformation screen
both MYSB and LMS connections are shown as OK for all ports
- from the LMS WebUI the correct playlist for the player is shown and it
shows progress
- WiFi signal is more than 60%
Unfortunately I couldn't find any pattern for the occurances.
For some days all works fine and then for some days the problem
appears.
I typically find this problem in the morning after switching the Radio
on, but it seems to occur also during the day, even with radio stations
(according to family members).
Maybe it occurs when the server PC is woken up from energy saving mode?
During the night WiFi is switched off and the LMS server PC is sleeping
in energy saving mode and the Radio is in standby (displaying the
clock). But in the morning when I turn the Radio on, WiFi is already on
and the LMS PC is usually up and running (woken up by the Windows
scheduler; in cases when the PC is sleeping, the Radio wakes it up
promptly). I do it this way since years.
BTW, LMS runs as a Windows service and the music is stored on a local
drive.
Here's the server log. Maybe the "errorNeedsClient" message is relevant
here?
Code:
--------------------
[17-05-11 21:35:27.5707] main::init (388) Starting Logitech Media Server
(v7.9.0, 1488878280, Wed Mar 8 14:37:44 CUT 2017) perl 5.014001 -
MSWin32-x86-multi-thread
[17-05-11 21:35:30.1959] Slim::Utils::SQLiteHelper::postConnect (374)
Optimizing DB because of missing or empty sqlite_stat1 table
[17-05-11 21:35:30.2754] Slim::Schema::forceCommit (2149) Warning: Trying to
commit transactions before DB is initialized!
[17-05-11 21:35:31.4304] Slim::Utils::SQLiteHelper::postConnect (374)
Optimizing DB because of missing or empty sqlite_stat1 table
[17-05-11 21:35:31.5016] Slim::Schema::forceCommit (2149) Warning: Trying to
commit transactions before DB is initialized!
[17-05-11 21:35:32.0084] main::checkDataSource (1111) Warning: Schema updated
or no media found in the database, initiating scan.
[17-05-11 21:49:17.0420] main::init (388) Starting Logitech Media Server
(v7.9.0, 1488878280, Wed Mar 8 14:37:44 CUT 2017) perl 5.014001 -
MSWin32-x86-multi-thread
[17-05-12 05:44:37.7391] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:xx:xx:xx, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
[17-05-12 06:00:40.7148] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:yy:yy:yy, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
[17-05-13 08:17:05.2652] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:yy:yy:yy, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
[17-05-13 08:17:05.5287] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:xx:xx:xx, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
[17-05-14 09:25:25.9819] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:yy:yy:yy, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
[17-05-14 09:25:26.8255] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:xx:xx:xx, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
[17-05-15 06:40:35.5276] Slim::Utils::Misc::msg (1244) Warning:
[06:40:35.5255] Error: Unable to read at least 16 bytes from file (only read
13).
[17-05-16 05:44:35.8359] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:yy:yy:yy, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
[17-05-18 02:35:58.3184] Slim::Web::JSONRPC::requestMethod (443)
00:04:20:xx:xx:xx: request not dispatchable!
[17-05-18 02:35:58.3235] Slim::Web::JSONRPC::requestMethod (443)
00:04:20:xx:xx:xx: request not dispatchable!
[17-05-18 02:36:04.3356] Slim::Web::JSONRPC::requestMethod (443)
00:04:20:xx:xx:xx: request not dispatchable!
[17-05-18 02:36:04.3398] Slim::Web::JSONRPC::requestMethod (443)
00:04:20:xx:xx:xx: request not dispatchable!
[17-05-18 02:36:10.3479] Slim::Web::JSONRPC::requestMethod (443)
00:04:20:xx:xx:xx: request not dispatchable!
[17-05-18 02:36:10.3521] Slim::Web::JSONRPC::requestMethod (443)
00:04:20:xx:xx:xx: request not dispatchable!
[17-05-19 05:44:36.0257] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:xx:xx:xx, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
[17-05-20 08:32:08.7240] Slim::Web::Cometd::handler (422) errorNeedsClient:
00:04:20:xx:xx:xx, status, -, 10, menu:menu, useContextMenu:1, subscribe:600
--------------------
I also checked the /var/log/messages file on the Radio and it shows
network errors but I can't tell yet whether they are relevant or normal
when LMS server is sleeping.
I also increased debug log levels for several categories but couldn't
separate the wheat from the chaff.
Any advise here?
------------------------------------------------------------------------
reinholdk's Profile: http://forums.slimdevices.com/member.php?userid=36070
View this thread: http://forums.slimdevices.com/showthread.php?t=107435
_______________________________________________
Radio mailing list
Radio@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/radio
