FAQ  •  Register  •  Login

Serviio periodically slows down and stutters

<<

Natpred

Serviio newbie

Posts: 2

Joined: Wed May 15, 2013 10:21 am

Post Wed May 15, 2013 10:51 pm

Serviio periodically slows down and stutters

Hi,

I am a huge fan of this media server and have been using it successfully for about 18 months. A problem has started occurring over the last few weeks. Every now and again while watching a high quality movie serviio starts to stutter and lag. This occurs in movies that I have previously watched with absolutely no problems. Pausing or rewinding seems to fix the problem for about twenty minutes and then it starts again.
I do not have transcoding enabled, and never did, and I have never had this problem before.
The media library is is on external harddrives connected to my PC which is connected by cables to a router and by cables to my LG Bluray player.
I cannot seem to attach the log file to this message for some reason so I have copied and pasted the log from a period when the problem appears to be occuring if that is any help.

Many many thanks for in advance for any help that can be offered.

java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:42:57,687 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:42:58,703 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:42:59,703 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:43:00,718 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:43:01,718 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:43:02,734 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:43:03,734 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:43:04,734 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
2013-05-15 23:43:05,750 ERROR [DiscoverySSDPMessageListener] Cannot open multicast socket,will try again
java.net.BindException: Address already in use: Cannot bind
at java.net.TwoStacksPlainDatagramSocketImpl.bind0(Native Method)
at java.net.AbstractPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.TwoStacksPlainDatagramSocketImpl.bind(Unknown Source)
at java.net.DatagramSocket.bind(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at java.net.MulticastSocket.<init>(Unknown Source)
at org.serviio.util.MultiCastUtils.startMultiCastSocketForListening(MultiCastUtils.java:42)
at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:86)
at java.lang.Thread.run(Unknown Source)
<<

Natpred

Serviio newbie

Posts: 2

Joined: Wed May 15, 2013 10:21 am

Post Sat May 18, 2013 10:23 am

Re: Serviio periodically slows down and stutters

Hi,
Using netstat and tasklist I was able to identify processes that were running on port 1900 and kill them/change port and now the "cannot bind" and "multicast socket" errors are not appearing on the log. The problem is still occuring, but less frequently. Here is the portion of the log that was recorded while another one of these stutter events occurred.

The problem does not seem isolated to the LG Player but also to the xbox.

2013-05-18 11:10:13,968 DEBUG [MediaItemDAOImpl] Media item Planet.Earth.EP10.Seasonal.Forests.2006.720p.HDDVD.x264.Sample-ESiR.mkv already exists in DB
2013-05-18 11:10:13,968 DEBUG [LibraryAdditionsCheckerThread] Found file 'Ramakrishna.Paramahansa.A.Documentary.By.Tony.avi', checking if it's already in the Library
2013-05-18 11:10:13,968 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item Ramakrishna.Paramahansa.A.Documentary.By.Tony.avi
2013-05-18 11:10:13,968 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: H:\Documentaries\Ramakrishna.Paramahansa.A.Documentary.By.Tony.avi, ignore case: true
2013-05-18 11:10:13,968 DEBUG [MediaItemDAOImpl] Media item Ramakrishna.Paramahansa.A.Documentary.By.Tony.avi already exists in DB
2013-05-18 11:10:13,968 DEBUG [LibraryAdditionsCheckerThread] Looking for files to share in folder: H:\Documentaries\War.Made.Easy.2007.BlackVinta
2013-05-18 11:10:13,984 DEBUG [LibraryAdditionsCheckerThread] Found file 'War Made Easy [2007].avi', checking if it's already in the Library
2013-05-18 11:10:13,984 DEBUG [MediaItemDAOImpl] Checking if DB already contains media item War Made Easy [2007].avi
2013-05-18 11:10:13,984 DEBUG [MediaItemDAOImpl] Looking up a media item for file path: H:\Documentaries\War.Made.Easy.2007.BlackVinta\War Made Easy [2007].avi, ignore case: true
2013-05-18 11:10:13,984 DEBUG [MediaItemDAOImpl] Media item War Made Easy [2007].avi already exists in DB
2013-05-18 11:10:13,984 DEBUG [RepositoryDAOImpl] Marking Repository 3 as scanned with current timestamp
2013-05-18 11:10:15,703 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:10:15,703 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:10:15,703 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:10:15,703 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:10:15,703 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:10:15,703 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:10:45,656 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:10:45,656 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:10:45,656 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:10:45,656 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:10:45,671 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:10:45,671 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:11:08,671 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-18 11:11:08,671 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-18 11:11:08,687 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 1)
2013-05-18 11:11:15,687 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:11:15,687 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:11:15,687 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:11:15,687 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:11:15,687 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:11:15,687 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:11:41,187 DEBUG [DiscoveryAdvertisementNotifier] Multicasting SSDP alive using interface eth0 (Broadcom 440x 10/100 Integrated Controller - Packet Scheduler Miniport) and address 192.168.1.3, timeout = 0
2013-05-18 11:11:41,187 DEBUG [DiscoveryAdvertisementNotifier] Sending 6 'alive' messages describing device 92364609-eb74-3e48-a97f-fb1c988d0a03
2013-05-18 11:11:42,375 DEBUG [ServiioHttpService] Incoming request from /192.168.1.4:33738: GET /deviceDescription/92364609-eb74-3e48-a97f-fb1c988d0a03 HTTP/1.1, headers = [Host: 192.168.1.3:8895,Connection: Keep-Alive,User-Agent: Linux/3.0.31-889555 UPnP/1.0 4thLine-Cling/1.1]]
2013-05-18 11:11:42,375 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.4
2013-05-18 11:11:42,375 DEBUG [DeviceDescriptionRequestHandler] DeviceDescription request received for device 92364609-eb74-3e48-a97f-fb1c988d0a03 from 192.168.1.4 (headers = [Host: 192.168.1.3:8895,Connection: Keep-Alive,User-Agent: Linux/3.0.31-889555 UPnP/1.0 4thLine-Cling/1.1])
2013-05-18 11:11:42,375 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.4
2013-05-18 11:11:42,375 DEBUG [RendererManager] Looking for a renderer profile for Http headers: [Host: 192.168.1.3:8895,Connection: Keep-Alive,User-Agent: Linux/3.0.31-889555 UPnP/1.0 4thLine-Cling/1.1]
2013-05-18 11:11:42,375 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.4
2013-05-18 11:11:42,437 DEBUG [DeviceDescriptionRequestHandler] Sending DeviceDescription XML back using profile 'LG TV / player'
2013-05-18 11:11:42,843 DEBUG [ServiioHttpService] Incoming request from /192.168.1.4:33738: GET /serviceDescription/ConnectionManager HTTP/1.1, headers = [Host: 192.168.1.3:8895,Connection: Keep-Alive,User-Agent: Linux/3.0.31-889555 UPnP/1.0 4thLine-Cling/1.1]]
2013-05-18 11:11:42,843 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.4
2013-05-18 11:11:42,843 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ConnectionManager
2013-05-18 11:11:42,875 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-18 11:11:43,140 DEBUG [DiscoveryAdvertisementNotifier] Will advertise again in 00:03:22 (advertisement duration is 1800 sec.)
2013-05-18 11:11:43,140 DEBUG [ServiioHttpService] Incoming request from /192.168.1.4:33738: GET /serviceDescription/ContentDirectory HTTP/1.1, headers = [Host: 192.168.1.3:8895,Connection: Keep-Alive,User-Agent: Linux/3.0.31-889555 UPnP/1.0 4thLine-Cling/1.1]]
2013-05-18 11:11:43,140 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.4
2013-05-18 11:11:43,140 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service ContentDirectory
2013-05-18 11:11:43,203 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-18 11:11:43,546 DEBUG [ServiioHttpService] Incoming request from /192.168.1.4:33738: GET /serviceDescription/X_MS_MediaReceiverRegistrar HTTP/1.1, headers = [Host: 192.168.1.3:8895,Connection: Keep-Alive,User-Agent: Linux/3.0.31-889555 UPnP/1.0 4thLine-Cling/1.1]]
2013-05-18 11:11:43,546 DEBUG [RendererDAOImpl] Reading a Renderer with ip address 192.168.1.4
2013-05-18 11:11:43,562 DEBUG [ServiceDescriptionRequestHandler] ServiceDescription request received for service X_MS_MediaReceiverRegistrar
2013-05-18 11:11:43,578 DEBUG [ServiceDescriptionRequestHandler] Sending ServiceDescription XML back
2013-05-18 11:11:45,671 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:11:45,671 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:11:45,671 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:11:45,687 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:11:45,687 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:11:45,687 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:12:08,687 DEBUG [FeedUpdaterThread] Checking for new and expired online resources
2013-05-18 11:12:08,687 DEBUG [OnlineRepositoryDAOImpl] Reading all OnlineRepositories
2013-05-18 11:12:08,703 DEBUG [OnlineRepositoryDAOImpl] Reading an OnlineRepository (id = 1)
2013-05-18 11:12:15,640 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:12:15,656 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:12:15,656 DEBUG [DiscoverySSDPMessageListener] Received a valid M-SEARCH message for search target ssdp:all from address /192.168.1.9:59000s
2013-05-18 11:12:15,656 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:12:15,656 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
2013-05-18 11:12:15,656 DEBUG [DiscoverySearchResponder] Sending 6 M-SEARCH response message(s) to /192.168.1.9:59000
<<

zip

User avatar

Serviio developer / Site Admin

Posts: 17212

Joined: Sat Oct 24, 2009 12:24 pm

Location: London, UK

Post Wed May 22, 2013 1:41 pm

Re: Serviio periodically slows down and stutters

Log looks fine. It could be network / IO related (considering there is no transcoding going on)

Return to LG

Who is online

Users browsing this forum: No registered users and 11 guests

Powered by phpBB © 2000, 2002, 2005, 2007 phpBB Group.
Designed by ST Software for PTF.