expectingtofly wrote:
> There may be a small defect with the metadata fetching code in version
> 1.5.4.5
>
> From the logs I can see that a couple of radios were continuously (and
> erroneously) requesting metadata every 30 seconds throughout the night,
> event though the radios were off.
>
> >
Code:
--------------------
> >
> [17-09-05 05:25:03.7396] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 33596 streamtime=1504551923
> [17-09-05 05:25:33.7680] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 33626 streamtime=1504551923
> [17-09-05 05:25:33.7813] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 33626 streamtime=1504551923
>
--------------------
> >
bpa wrote:
> To fix the problem, I need to reproduce the issues and so I need as much
> info as possible on how you got to this state.
> The log messages in this routine are in reply to a LMS request to
> display metadata. So LMS does not think the stream has stopped and radio
> is off (i.e. LMS may think it is on & paused).
> A few months ago, another user had this issue a while ago but the
> message occurred when the radio was turned on after being off. From the
> log are the messages continuous or did they start at some point in the
> night ?
>
> What were you playing Stream or On-Demand ?
> I'm assuming you were playing a stream - did you pause stream and then
> power off or just power off ?
> What are the "power" settings for the radio in the LMS Settings Player
> tab ?
>
> edit:
>
> From the Streamtime value - it looks like the stream was paused on
> Monday 20:02:23 - does this seem right ?
>
> If the Radio setting "powrer on Resume " is set for a "Pause on Power
> off/ Resume on Power on " or "Pause on Power off / Reamin Paused on
> Power on" - the the stream/file is just paused (this is true for ALL
> stream notjust BBCiplayer) - the radio is not powered off - it just has
> a "power off" screen rather than "now Playing".
>
> Change setting to one of the "Stop at Power off" if you really want
> playing to stop when you power off - probably "Stop at Power off/
> Restart song at Power on" might be more suitable. In the meantime I'll
> probaly change code to stop stream that have been paused for too long.
Hi, sorry its taken a while to get back to you. I still have this
issue, it still happens every day, but I couldn't recreate it just by
starting and stopping the streams. So, at the end of the day yesterday
I restarted LMS to clear the problem (it was present at that time) and
I looked at the logs this morning :
Code:
--------------------
[17-09-18 23:16:17.6350] main::init (387) Starting Logitech Media Server
(v7.9.1, 1499358182, Sat Jul 8 04:09:05 UTC 2017) perl 5.024000 -
arm-linux-gnueabihf-thread-multi-64int
[17-09-18 23:16:50.7776] Plugins::BBCiPlayer::DASH::__ANON__ (779) Chunk
fetch status 200 Long chunk fetch time 1166 1000
[17-09-18 23:16:50.7884] Plugins::BBCiPlayer::DASH::__ANON__ (779) Chunk
fetch status 200 Long chunk fetch time 1183 1000
[17-09-19 03:46:08.9065] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 10983 streamtime=1505778201
[17-09-19 03:46:08.9225] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 10983 streamtime=1505778201
[17-09-19 03:56:08.9554] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 11583 streamtime=1505778201
[17-09-19 03:56:08.9716] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 11583 streamtime=1505778201
[17-09-19 04:06:09.0046] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 12184 streamtime=1505778201
[17-09-19 04:06:09.0205] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 12184 streamtime=1505778201
[17-09-19 04:16:09.0536] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 12784 streamtime=1505778201
[17-09-19 04:16:09.0704] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 12784 streamtime=1505778201
[17-09-19 04:26:09.1035] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 13384 streamtime=1505778201
[17-09-19 04:26:09.1193] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 13384 streamtime=1505778201
[17-09-19 04:36:09.1526] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 13984 streamtime=1505778201
[17-09-19 04:36:09.1685] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 13984 streamtime=1505778201
[17-09-19 04:46:09.2018] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 14584 streamtime=1505778201
[17-09-19 04:46:09.2177] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 14584 streamtime=1505778201
[17-09-19 04:56:09.2508] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 15184 streamtime=1505778201
[17-09-19 04:56:09.2665] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 15184 streamtime=1505778201
[17-09-19 05:06:09.2998] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 15784 streamtime=1505778201
[17-09-19 05:06:09.3155] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 15784 streamtime=1505778201
[17-09-19 05:16:09.3482] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 16384 streamtime=1505778201
[17-09-19 05:16:09.3639] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 16384 streamtime=1505778201
[17-09-19 05:26:09.3964] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 16984 streamtime=1505778201
[17-09-19 05:26:09.4122] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 16984 streamtime=1505778201
[17-09-19 05:36:09.4456] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 17584 streamtime=1505778201
[17-09-19 05:36:09.4614] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 17584 streamtime=1505778201
[17-09-19 05:46:09.4945] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 18184 streamtime=1505778201
[17-09-19 05:46:09.5103] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 18184 streamtime=1505778201
[17-09-19 05:56:09.5437] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 18784 streamtime=1505778201
[17-09-19 05:56:09.5595] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 18784 streamtime=1505778201
[17-09-19 06:06:09.5923] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 19384 streamtime=1505778201
[17-09-19 06:06:09.6083] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 19384 streamtime=1505778201
[17-09-19 06:16:09.6411] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 19984 streamtime=1505778201
[17-09-19 06:16:09.6567] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 19984 streamtime=1505778201
[17-09-19 06:26:09.6903] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 20584 streamtime=1505778201
[17-09-19 06:26:09.7063] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 20584 streamtime=1505778201
[17-09-19 06:36:09.7393] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 21184 streamtime=1505778201
[17-09-19 06:36:09.7549] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 21184 streamtime=1505778201
[17-09-19 06:46:09.7883] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 21784 streamtime=1505778201
[17-09-19 06:46:09.8040] Plugins::BBCiPlayer::iPlayer::getMetadataFor (567)
Invalid estimated delay 21784 streamtime=1505778201
--------------------
As you can see, it started again at 3:45 am in the morning with 2
happening every 10 minutes with the streamtime Epoch time being reported
as (1505778201) Tuesday, 19 September 2017 00:43:21 GMT+01:00 DST .
There was nothing unusual that occurred apart from the radio was put on
when I went to bed with "sleep", which I guess would have happened
around that time So I would speculate that the metadata request is not
being destroyed when the radio goes into sleep. Interestingly the
problem went away again when the alarm came on at 6:50 AM.
Does that help?
------------------------------------------------------------------------
expectingtofly's Profile: http://forums.slimdevices.com/member.php?userid=63263
View this thread: http://forums.slimdevices.com/showthread.php?t=106409
_______________________________________________
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins
