Page 1 of 2

Unexpected stopping playing LG 42LM640T

PostPosted: Tue Apr 30, 2013 8:46 pm
by subora
Serviio 1.2.1 from NAS ds413 java 1.7.0.21
router dlink dir826l
static ip addresses fro NAS & LG TV
Unexpected stopping playing LG 42LM640T with error:
  Code:
2013-04-30 22:40:31,016 INFO  [UpdateChecker] Checking if a new version is available
2013-04-30 23:01:46,021 INFO  [DiscoveryAdvertisementNotifier] Leaving DiscoveryAdvertisementNotifier
2013-04-30 23:01:46,021 INFO  [WebServer] WebServer shutting down
2013-04-30 23:01:46,022 INFO  [DiscoverySSDPMessageListener] Leaving DiscoverySSDPMessageListener
2013-04-30 23:01:46,022 INFO  [EventDispatcher] Leaving EventDispatcher
2013-04-30 23:01:46,022 INFO  [EventSubscriptionExpirationChecker] Leaving EventSubscriptionExpirationChecker, removing all event subscriptions
2013-04-30 23:01:48,123 INFO  [WebServer] WebServer starting on port 8895
2013-04-30 23:01:48,129 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth0 (eth0) and address 192.168.0.11, timeout = 0
2013-04-30 23:01:48,130 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2013-04-30 23:01:48,130 INFO  [EventDispatcher] Starting EventDispatcher
2013-04-30 23:01:48,131 INFO  [RendererSearchSender] Searching for Renderer devices
2013-04-30 23:01:48,132 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2013-04-30 23:02:49,074 INFO  [RendererManager] Stored a new renderer: uuid='89a20a13-f984-45c4-bb76-40e730241434', name = 'LG TV / player', ipAddress='192.168.0.102', profile = '8'
2013-04-30 23:09:28,646 INFO  [RendererManager] Stored a new renderer: uuid='21f92a6c-1dd2-11b2-bd93-bd06b9aaff47', name = 'LG TV', ipAddress='192.168.0.102', profile = '1'
2013-04-30 23:22:34,328 INFO  [RendererManager] Stored a new renderer: uuid='f6acb4a7-b135-4af9-9036-21248125d582', name = 'Unrecognized device', ipAddress='192.168.0.109', profile = '1'
2013-04-30 23:23:46,093 WARN  [ResourceDeliveryProcessor] Invalid request, sending back 400 error
org.serviio.upnp.protocol.http.transport.InvalidResourceRequestException: Invalid incoming request: /resource/114/MEDIA_ITEM/MATROSKA-0
        at org.serviio.upnp.protocol.http.transport.RequestedResourceDescriptor.<init>(RequestedResourceDescriptor.java:73)
        at org.serviio.upnp.protocol.http.transport.AbstractProtocolHandler.getRequestedResourceDescription(AbstractProtocolHandler.java:35)
        at org.serviio.delivery.ResourceDeliveryProcessor.deliverContent(ResourceDeliveryProcessor.java:65)
        at org.serviio.upnp.webserver.ResourceTransportRequestHandler.handleRequest(ResourceTransportRequestHandler.java:85)
        at org.serviio.upnp.webserver.AbstractRequestHandler.handle(AbstractRequestHandler.java:61)
        at org.apache.http.protocol.HttpService.doService(HttpService.java:293)
        at org.serviio.upnp.webserver.ServiioHttpService.doService(ServiioHttpService.java:77)
        at org.apache.http.protocol.HttpService.handleRequest(HttpService.java:212)
        at org.serviio.upnp.webserver.WebServer$WorkerThread.run(WebServer.java:192)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 3
        at org.serviio.upnp.protocol.http.transport.RequestedResourceDescriptor.<init>(RequestedResourceDescriptor.java:70)
        ... 8 more
2013-04-30 23:29:38,439 INFO  [RendererManager] Stored a new renderer: uuid='dbac87fc-7419-433b-93d9-96b753f2e4f8', name = 'LG TV / player', ipAddress='192.168.0.102', profile = '8'
2013-04-30 23:30:02,108 WARN  [EventDispatcher] Couldn't send event message for subscription b3f9da24-8712-4ce1-8480-7595e54691a1, will keep trying until subscription expires
2013-04-30 23:30:23,111 WARN  [EventDispatcher] Couldn't send event message for subscription 894e67ac-ac2d-44b5-8176-295491fa36c5, will keep trying until subscription expires
2013-04-30 23:30:32,504 INFO  [RendererManager] Stored a new renderer: uuid='21f92a6c-1dd2-11b2-bd93-bd06b9aaff47', name = 'LG TV', ipAddress='192.168.0.102', profile = '1'
2013-04-30 23:30:43,123 INFO  [WebServer] WebServer shutting down
2013-04-30 23:30:43,124 INFO  [DiscoveryAdvertisementNotifier] Leaving DiscoveryAdvertisementNotifier
2013-04-30 23:30:43,131 INFO  [EventSubscriptionExpirationChecker] Leaving EventSubscriptionExpirationChecker, removing all event subscriptions
2013-04-30 23:30:43,131 INFO  [EventDispatcher] Leaving EventDispatcher
2013-04-30 23:30:43,132 INFO  [DiscoverySSDPMessageListener] Leaving DiscoverySSDPMessageListener
2013-04-30 23:30:43,134 INFO  [WebServer] WebServer starting on port 8895
2013-04-30 23:30:43,135 INFO  [EventSubscriptionExpirationChecker] Starting EventSubscriptionExpirationChecker
2013-04-30 23:30:43,136 INFO  [DiscoverySSDPMessageListener] Starting DiscoverySSDPMessageListener using interface eth0 (eth0) and address 192.168.0.11, timeout = 0
2013-04-30 23:30:43,141 INFO  [DiscoveryAdvertisementNotifier] Starting DiscoveryAdvertisementNotifier
2013-04-30 23:30:43,142 INFO  [RendererSearchSender] Searching for Renderer devices
2013-04-30 23:30:43,142 INFO  [EventDispatcher] Starting EventDispatcher
2013-04-30 23:31:05,142 WARN  [EventDispatcher] Couldn't send event message for subscription f9e62cb9-11fd-4dd2-abf4-c9c28f72e867, will keep trying until subscription expires
2013-04-30 23:37:28,266 INFO  [RendererManager] Stored a new renderer: uuid='b40160ce-6f39-a0c4-ffff-ffffebb1d932', name = 'BubbleUPnP Media Renderer', ipAddress='192.168.0.109', profile = '1'
2013-04-30 23:59:27,114 WARN  [EventDispatcher] Couldn't send event message for subscription f9e62cb9-11fd-4dd2-abf4-c9c28f72e867, will keep trying until subscription expires
2013-04-30 23:59:27,119 WARN  [EventDispatcher] Couldn't send event message for subscription 28be1921-c196-4bdd-9074-a9444bc73205, will keep trying until subscription expired

also unrecognized ip address 10.150.160.149 while my networl is 192.168.0.* with mac filtering

no problem with playing on android ainol novo7. problems only with LG TV...

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Wed May 01, 2013 5:13 am
by subora
only 1 error while stopping:
  Code:
2013-05-01 09:07:06,377 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-01 09:07:06,377 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-01 09:07:49,540 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (eth0) and address 192.168.0.11, timeout = 0
2013-05-01 09:07:49,541 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 91e97f41-67af-3556-9b8f-4aa7316b17d4
2013-05-01 09:07:51,345 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:03:30 (advertisement duration is 1800 sec.)
2013-05-01 09:07:51,781 DEBUG [WebServer] I/O error: Connection reset
2013-05-01 09:08:06,378 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-01 09:08:06,383 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-01 09:09:05,764 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-01 09:09:05,958 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-01 09:09:06,158 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-01 09:09:06,258 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-01 09:09:06,384 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-01 09:09:06,384 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-01 09:09:06,459 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-01 09:09:06,659 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-01 09:09:06,759 DEBUG [RendererDAOImpl] Retrieving list of all stored renderers
2013-05-01 09:10:06,385 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-01 09:10:06,385 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Thu May 09, 2013 11:35 pm
by jayhawk81
I have been battling the issue for a while with my samsung TV, blu Ray and philips TV. I have opened a tread but so far no one has been able to pin point the issue. Our logs look identical. After serviio generate multicast message, the server connection is reset and the next movie start playing. My router is a linksys E4200 and I have Multicast disabled as suggested in other threads. I hope someone come through with a way to help.

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Fri May 10, 2013 4:31 pm
by zip
Can you see if the same happens with other server (eg PS3MS) after the advertisement is sent?

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Sat May 11, 2013 1:46 pm
by jayhawk81
Thanks for chiming in Zip. I have been using Twonky media server and it does not happen. I would rather use serviio though. I thought mine originally only affected playback of my server files, but last night while watching F1 practice on Iplayer.

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Mon May 13, 2013 12:48 pm
by subora
just disabling router ipv6 multicast + using wi-fi 802.11n connection with LG TV so no such problems yet. LAN connection was not tested by me...

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Mon May 13, 2013 3:48 pm
by jayhawk81
I have IPv6 disabled on my router and have tried both wireless and Ethernet without any success. Keep us posted whether your changes keep helping in the long term.
Thanks

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Tue May 14, 2013 3:38 am
by subora
Sorry! Also Dserviio.advertisementDuration was changed to 864000. Disable ipv6 + using wifi + changing Dserviio.advertisementDuration no more actions...

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Fri May 17, 2013 4:31 pm
by zip
jayhawk81 wrote:Thanks for chiming in Zip. I have been using Twonky media server and it does not happen. I would rather use serviio though. I thought mine originally only affected playback of my server files, but last night while watching F1 practice on Iplayer.

It'd be cool if you could capture the packets from Serviio and the other server when this happens via Wireshark (just the SSDP traffic on UDP 1900), there might be something in the message Serviio sends that the TV doesn't like. It might be a big task to undertake though.

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Sat May 18, 2013 2:16 am
by jayhawk81
zip wrote:
jayhawk81 wrote:Thanks for chiming in Zip. I have been using Twonky media server and it does not happen. I would rather use serviio though. I thought mine originally only affected playback of my server files, but last night while watching F1 practice on Iplayer.

It'd be cool if you could capture the packets from Serviio and the other server when this happens via Wireshark (just the SSDP traffic on UDP 1900), there might be something in the message Serviio sends that the TV doesn't like. It might be a big task to undertake though.


Ok started watching a movie (Arthur Christmas.mkv) from my NAS via serviio shortly after after 20:15, at 20:26 started sniffing packets using tcpdump. Everything was going great until 21:02:01 when serviio cut out Arthur Christmas and automatically started the next movie which is Cars 2. I reached for the remote and manually stopped Cars 2 to restart Arthur Christmas for my son at 21:02:22. Used the time seek feature on my samsung to FF movie to where it cut out (random spot) and after that we finished it without interruptions. Attached are a couple files, one is the serviio log capturing when the movie cut out,and the remaining file is an excel export of TCPdump on my entire LAN. This only contains SSDP and I was surprised at how many they are. I added a column to the file describing what the device is. For instance IP 192.168.10.27 is my WD MBL with Twonky server enabled. My serviio server which is on IP 192.168.10.9 is not captured, but I think it is because I increased Dserviio.advertisementDuration to 864000.

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Wed May 22, 2013 1:36 pm
by zip
Thanks. What I need, though, is the content of Twonky SSDP messages (ie headers) to compare them with what Serviio sends.

Also, do you have any other device accessing the server? At the time there is an incoming browse reuest from a Samsung..?

  Code:
2013-05-17 21:02:01,176 DEBUG [ServiioHttpService] Incoming request from /192.168.10.12:38408: POST /serviceControl HTTP/1.0, headers = [HOST: 192.168.10.9:8895,CONTENT-LENGTH: 671,CONTENT-TYPE: text/xml;charset="utf-8",USER-AGENT: DLNADOC/1.50 SEC_HHP_Living Room SmartTV/1.0,SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"]]


The disconnect happens during this communication with the Samsung.

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Thu May 23, 2013 3:02 am
by jayhawk81
I unfortunately do not know how to interpret wireshark very well, below is the raw capture file. I had to change the extensions from pcap to txt to be able to upload it here, so you will need to change it back after you download it. I don't have anything other devices accessing the serviio server, in fact last night I turned off my wireless printers, rokus and other devices just to see if it would help, but no luck.
Hopefully you find what you need on the raw file, which I filtered to just show ssdp broadcast from my twonky server, if not let me know and I will run more captures to get you what you need.

Thanks.

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Thu May 23, 2013 2:21 pm
by zip
nothing weird there

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Thu May 23, 2013 5:19 pm
by jayhawk81
Is that what you needed from Twonky?

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Thu May 23, 2013 9:10 pm
by zip
I think so, the messages seem to be the same as Serviio's.

What is this device?

  Code:
Incoming request from /192.168.10.12:41191: POST /serviceControl HTTP/1.0, headers = [HOST: 192.168.10.9:8895,CONTENT-LENGTH: 362,CONTENT-TYPE: text/xml;charset="utf-8",USER-AGENT: DLNADOC/1.50 SEC_HHP_Living Room SmartTV/1.0,SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#X_SetBookmark"]]

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Thu May 23, 2013 9:57 pm
by jayhawk81
The device is a western digital my book live with twonky 7 on it. My serviio server is an Ubuntu box. I enabled twonky until the issue with my disconnect is resolved but normally I don't have serviio and twonky running at the same time.

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Sun May 26, 2013 8:07 pm
by zip
No, it's a Samsung device (a TV). Do you have it on your network?

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Sun May 26, 2013 8:21 pm
by jayhawk81
Sorry, I thought you asked about my twonky server. The device above is my samsung un46d6300. The same thing also happens with my samsung BD5700 and Philips pfl4706

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Sun May 26, 2013 8:24 pm
by zip
So first you said you only have problem with LG tv, now it's also Samsung and Philips? It that right?

Re: Unexpected stopping playing LG 42LM640T

PostPosted: Sun May 26, 2013 8:41 pm
by jayhawk81
Did not really mean to hijack this thread, I was following it since I have the same problem. The owner o the thread has this issue resolved by setting serviio advertisement to 24 hours and using wireless N. I had a post open for mine though.