V1.0 CPU Usage

Mon Aug 06, 2012 1:20 pm by pete

After upgrading to V1.0 I am noticing very high CPU usage as soon as I start serviio.

I am running on Ubunut 12.04 with java version "1.7.0_04", Java(TM) SE Runtime Environment (build 1.7.0_04-b20). I have had the server running for several hours and I am pretty confident it has completed index on some of those occasions.

I have turned debugging on and I see when serviio is checking files which I guess is normal. These scanning logs are indicating that the files are already in the library.

But I am seeing high CPU loads even when it appears that it is not updating files to the library.

I have attached visualvm to see what is going on and I see several threads running at 100% (see thread dump below). Memory appears stable and GC activity is very low

So is any one else seeing this behaviour?

Thread dump.

Code:: 2012-08-06 23:17:00 Full thread dump Java HotSpot(TM) 64-Bit Server VM (23.0-b21 mixed mode): "RMI TCP Connection(2)-127.0.0.1" daemon prio=10 tid=0x00007fda08002800 nid=0x5c4f runnable [0x00007fd9b6086000] java.lang.Thread.State: RUNNABLE at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:150) at java.net.SocketInputStream.read(SocketInputStream.java:121) at java.io.BufferedInputStream.fill(BufferedInputStream.java:235) at java.io.BufferedInputStream.read(BufferedInputStream.java:254) - locked <0x00000000dbfa0490> (a java.io.BufferedInputStream) at java.io.FilterInputStream.read(FilterInputStream.java:83) at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535) at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:808) at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:667) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000dbfa0620> (a java.util.concurrent.ThreadPoolExecutor$Worker) "JMX server connection timeout 360" daemon prio=10 tid=0x00000000024ed800 nid=0x5c4d in Object.wait() [0x00007fd9b5e84000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000dbf90088> (a [I) at com.sun.jmx.remote.internal.ServerCommunicatorAdmin$Timeout.run(ServerCommunicatorAdmin.java:168) - locked <0x00000000dbf90088> (a [I) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "RMI Scheduler(0)" daemon prio=10 tid=0x00000000024e7000 nid=0x5c4b waiting on condition [0x00007fd9b6c92000] java.lang.Thread.State: TIMED_WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000dbfafc08> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1090) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "RMI TCP Connection(1)-127.0.0.1" daemon prio=10 tid=0x00007fda08002000 nid=0x5c4a runnable [0x00007fd9b5c82000] java.lang.Thread.State: RUNNABLE at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:150) at java.net.SocketInputStream.read(SocketInputStream.java:121) at java.io.BufferedInputStream.fill(BufferedInputStream.java:235) at java.io.BufferedInputStream.read(BufferedInputStream.java:254) - locked <0x00000000dbfc1a30> (a java.io.BufferedInputStream) at java.io.FilterInputStream.read(FilterInputStream.java:83) at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535) at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:808) at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:667) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000dbfc1c90> (a java.util.concurrent.ThreadPoolExecutor$Worker) "RMI TCP Accept-0" daemon prio=10 tid=0x00007fd9b8038000 nid=0x5c47 runnable [0x00007fd9b5f85000] java.lang.Thread.State: RUNNABLE at java.net.PlainSocketImpl.socketAccept(Native Method) at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398) at java.net.ServerSocket.implAccept(ServerSocket.java:522) at java.net.ServerSocket.accept(ServerSocket.java:490) at sun.management.jmxremote.LocalRMIServerSocketFactory$1.accept(LocalRMIServerSocketFactory.java:52) at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.executeAcceptLoop(TCPTransport.java:387) at sun.rmi.transport.tcp.TCPTransport$AcceptLoop.run(TCPTransport.java:359) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "Attach Listener" daemon prio=10 tid=0x00007fda18001000 nid=0x5c45 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "Restlet-1660076932" daemon prio=10 tid=0x00007fd9f0003000 nid=0x558c waiting on condition [0x00007fd9b5d83000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5987fb8> (a java.util.concurrent.SynchronousQueue$TransferStack) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:458) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:359) at java.util.concurrent.SynchronousQueue.take(SynchronousQueue.java:925) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "pool-6-thread-10" prio=10 tid=0x00000000024dc000 nid=0x551c waiting on condition [0x00007fd9b6187000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "pool-6-thread-9" prio=10 tid=0x00000000024db000 nid=0x551b waiting on condition [0x00007fd9b6389000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ServioThread-22-null" daemon prio=10 tid=0x00007fd9d8107000 nid=0x551a runnable [0x00007fd9b6287000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e7940db0> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-21-null" daemon prio=10 tid=0x00007fd9d8106000 nid=0x5518 runnable [0x00007fd9b6489000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e793fa28> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-20-null" daemon prio=10 tid=0x00007fd9d8105000 nid=0x5515 runnable [0x00007fda34190000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e77d4c78> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-19-null" daemon prio=10 tid=0x00007fd9d8104800 nid=0x54bd runnable [0x00007fd9b688d000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e7686970> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-18-null" daemon prio=10 tid=0x00007fd9d802a000 nid=0x54b9 runnable [0x00007fd9b658a000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e763aa60> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-17-null" daemon prio=10 tid=0x00007fd9d8028800 nid=0x5498 runnable [0x00007fd9b668b000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:171) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e7568050> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-16-null" daemon prio=10 tid=0x00007fd9d8027800 nid=0x5496 runnable [0x00007fd9b698e000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e756a190> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-15-null" daemon prio=10 tid=0x00007fd9d8027000 nid=0x5490 runnable [0x00007fd9b678c000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:171) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e74023c8> (a java.util.concurrent.ThreadPoolExecutor$Worker) "PlaylistMaintainerThread" daemon prio=10 tid=0x00007fd9f0007800 nid=0x5436 waiting on condition [0x00007fd9b78f7000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.library.local.metadata.PlaylistMaintainerThread.run(PlaylistMaintainerThread.java:100) Locked ownable synchronizers: - None "LibraryUpdatesCheckerThread" daemon prio=10 tid=0x00007fd9f0007000 nid=0x5435 waiting on condition [0x00007fd9b76f5000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.library.local.metadata.LibraryUpdatesCheckerThread.run(LibraryUpdatesCheckerThread.java:125) Locked ownable synchronizers: - None "LibraryAdditionsCheckerThread" daemon prio=10 tid=0x00007fd9f0006000 nid=0x5434 waiting on condition [0x00007fd9b7ffe000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.library.local.metadata.LibraryAdditionsCheckerThread.run(LibraryAdditionsCheckerThread.java:98) Locked ownable synchronizers: - None "pool-6-thread-8" prio=10 tid=0x00000000024da000 nid=0x5433 waiting on condition [0x00007fd9b6b91000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "pool-6-thread-7" prio=10 tid=0x00000000024d9800 nid=0x5432 waiting on condition [0x00007fd9b75f4000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ServioThread-13-null" daemon prio=10 tid=0x00007fd9d8025000 nid=0x5430 runnable [0x00007fd9b6a8f000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e721df60> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-12-null" daemon prio=10 tid=0x00007fd9d8024800 nid=0x540b runnable [0x00007fd9b73f1000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e711f180> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-11-null" daemon prio=10 tid=0x00007fd9d8024000 nid=0x5409 runnable [0x00007fd9b74f2000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e711f408> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-10-null" daemon prio=10 tid=0x00007fd9d8161000 nid=0x53ad runnable [0x00007fd9b6d92000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e6cd7360> (a java.util.concurrent.ThreadPoolExecutor$Worker) "ServioThread-9-null" daemon prio=10 tid=0x00007fd9d8175000 nid=0x539c runnable [0x00007fd9b72f0000] java.lang.Thread.State: RUNNABLE at java.util.regex.Pattern$Curly.match1(Pattern.java:4185) at java.util.regex.Pattern$Curly.match(Pattern.java:4134) at java.util.regex.Pattern$Start.match(Pattern.java:3408) at java.util.regex.Matcher.search(Matcher.java:1199) at java.util.regex.Matcher.find(Matcher.java:592) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getCount(DefaultGroovyMethods.java:6256) at org.codehaus.groovy.runtime.DefaultGroovyMethods.getAt(DefaultGroovyMethods.java:6200) at org.codehaus.groovy.runtime.dgm$310.invoke(Unknown Source) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271) at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53) at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116) at Rage.extractUrl(Rage.groovy:173) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:43) at org.serviio.library.online.WebResourceUrlExtractor$2.executePluginMethod(WebResourceUrlExtractor.java:40) at org.serviio.library.online.PluginExecutionProcessor$1.call(PluginExecutionProcessor.java:50) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e68495b0> (a java.util.concurrent.ThreadPoolExecutor$Worker) "DestroyJavaVM" prio=10 tid=0x00007fda30009800 nid=0x5344 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "FeedUpdaterThread" daemon prio=10 tid=0x00007fda305cd800 nid=0x5387 waiting on condition [0x00007fd9b79f8000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.library.online.metadata.FeedUpdaterThread.run(FeedUpdaterThread.java:138) Locked ownable synchronizers: - None "pool-6-thread-6" prio=10 tid=0x00007fd9c0008000 nid=0x5377 waiting on condition [0x00007fd9b77f6000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "pool-6-thread-5" prio=10 tid=0x00007fd9c0006800 nid=0x5371 waiting on condition [0x00007fd9b7af9000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "pool-6-thread-4" prio=10 tid=0x00007fd9c0004000 nid=0x5370 waiting on condition [0x00007fd9b7bfa000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "pool-6-thread-3" prio=10 tid=0x00000000024d8000 nid=0x536f waiting on condition [0x00007fd9b7cfb000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "pool-6-thread-2" prio=10 tid=0x00000000024d6800 nid=0x536e waiting on condition [0x00007fd9b7dfc000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "pool-6-thread-1" prio=10 tid=0x00000000024ce800 nid=0x536d waiting on condition [0x00007fd9b7efd000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5d09cb0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ServioThread-7-RendererExpirationChecker" daemon prio=10 tid=0x00007fda305c9000 nid=0x536b waiting on condition [0x00007fda2c1cf000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.util.ThreadUtils.currentThreadSleep(ThreadUtils.java:16) at org.serviio.renderer.RendererExpirationChecker.run(RendererExpirationChecker.java:87) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ServioThread-6-DiscoveryNotifier" daemon prio=10 tid=0x00007fda305c5000 nid=0x536a waiting on condition [0x00007fda2c2d0000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.util.ThreadUtils.currentThreadSleep(ThreadUtils.java:16) at org.serviio.upnp.discovery.DiscoveryAdvertisementNotifier.run(DiscoveryAdvertisementNotifier.java:109) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ServioThread-5-EventDispatcher" daemon prio=10 tid=0x00007fda305c3000 nid=0x5369 waiting on condition [0x00007fda2c3d1000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.util.ThreadUtils.currentThreadSleep(ThreadUtils.java:16) at org.serviio.upnp.eventing.EventDispatcher.run(EventDispatcher.java:162) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ServioThread-4-SubscriptionExpiryChecker" daemon prio=10 tid=0x00007fda3031c000 nid=0x5368 waiting on condition [0x00007fda2c4d2000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.util.ThreadUtils.currentThreadSleep(ThreadUtils.java:16) at org.serviio.upnp.eventing.EventSubscriptionExpirationChecker.run(EventSubscriptionExpirationChecker.java:105) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ServioThread-3-DiscoverySSDPMessageListener" daemon prio=10 tid=0x00007fda3031b800 nid=0x5367 runnable [0x00007fda2c5d3000] java.lang.Thread.State: RUNNABLE at java.net.PlainDatagramSocketImpl.receive0(Native Method) - locked <0x00000000e5ccf6c0> (a java.net.PlainDatagramSocketImpl) at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:145) - locked <0x00000000e5ccf6c0> (a java.net.PlainDatagramSocketImpl) at java.net.DatagramSocket.receive(DatagramSocket.java:786) - locked <0x00000000dbfb08d8> (a java.net.DatagramPacket) - locked <0x00000000e5ccf6f0> (a java.net.MulticastSocket) at org.serviio.util.MultiCastUtils.receive(MultiCastUtils.java:94) at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.listenForMulticastMessages(DiscoverySSDPMessageListener.java:126) at org.serviio.upnp.discovery.DiscoverySSDPMessageListener.run(DiscoverySSDPMessageListener.java:97) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "WebServer" prio=10 tid=0x00007fda30590800 nid=0x5366 runnable [0x00007fda2ccfb000] java.lang.Thread.State: RUNNABLE at java.net.PlainSocketImpl.socketAccept(Native Method) at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398) at java.net.ServerSocket.implAccept(ServerSocket.java:522) at java.net.ServerSocket.accept(ServerSocket.java:490) at org.serviio.upnp.webserver.WebServer$RequestListenerThread.run(WebServer.java:145) Locked ownable synchronizers: - None "pool-5-thread-1" prio=10 tid=0x00007fda3059d800 nid=0x5360 waiting on condition [0x00007fda2cdfc000] java.lang.Thread.State: TIMED_WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5ca46a8> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1090) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "PluginCompilerThread" daemon prio=10 tid=0x00007fda3059f000 nid=0x535f waiting on condition [0x00007fda2cefd000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.library.online.feed.PluginCompilerThread.run(PluginCompilerThread.java:94) Locked ownable synchronizers: - None "ServioThread-2-CDS library notifier" daemon prio=10 tid=0x00007fda30406800 nid=0x535e waiting on condition [0x00007fda2cffe000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.util.ThreadUtils.currentThreadSleep(ThreadUtils.java:16) at org.serviio.library.metadata.AbstractCDSLibraryIndexingListener$CDSNotifierThread.run(AbstractCDSLibraryIndexingListener.java:139) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ServioThread-1-CDS library notifier" daemon prio=10 tid=0x00007fd9d008c800 nid=0x535c waiting on condition [0x00007fda34292000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.serviio.util.ThreadUtils.currentThreadSleep(ThreadUtils.java:16) at org.serviio.library.metadata.AbstractCDSLibraryIndexingListener$CDSNotifierThread.run(AbstractCDSLibraryIndexingListener.java:139) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "ElementEventQueue.QProcessor-1" daemon prio=10 tid=0x00007fd9d0099000 nid=0x535b in Object.wait() [0x00007fda34393000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e5bb31a8> (a java.lang.Object) at java.lang.Object.wait(Object.java:503) at org.apache.jcs.engine.control.event.ElementEventQueue.take(ElementEventQueue.java:181) - locked <0x00000000e5bb31a8> (a java.lang.Object) at org.apache.jcs.engine.control.event.ElementEventQueue.access$300(ElementEventQueue.java:35) at org.apache.jcs.engine.control.event.ElementEventQueue$QProcessor.run(ElementEventQueue.java:245) Locked ownable synchronizers: - None "pool-4-thread-1" prio=10 tid=0x00007fda307c0800 nid=0x5359 waiting on condition [0x00007fda34595000] java.lang.Thread.State: TIMED_WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000000e5b06f68> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:226) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2082) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1090) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:807) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "Timer-1" daemon prio=10 tid=0x00007fda30791800 nid=0x5358 in Object.wait() [0x00007fda34696000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e5b070f8> (a java.util.TaskQueue) at java.util.TimerThread.mainLoop(Timer.java:552) - locked <0x00000000e5b070f8> (a java.util.TaskQueue) at java.util.TimerThread.run(Timer.java:505) Locked ownable synchronizers: - None "derby.rawStoreDaemon" daemon prio=10 tid=0x00007fda30616000 nid=0x5357 in Object.wait() [0x00007fda3479c000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e59d2178> (a org.apache.derby.impl.services.daemon.BasicDaemon) at org.apache.derby.impl.services.daemon.BasicDaemon.rest(Unknown Source) - locked <0x00000000e59d2178> (a org.apache.derby.impl.services.daemon.BasicDaemon) at org.apache.derby.impl.services.daemon.BasicDaemon.run(Unknown Source) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "Timer-0" daemon prio=10 tid=0x00007fda30555000 nid=0x5356 in Object.wait() [0x00007fda34aa5000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e59d01b0> (a java.util.TaskQueue) at java.lang.Object.wait(Object.java:503) at java.util.TimerThread.mainLoop(Timer.java:526) - locked <0x00000000e59d01b0> (a java.util.TaskQueue) at java.util.TimerThread.run(Timer.java:505) Locked ownable synchronizers: - None "Restlet-956611830" prio=10 tid=0x00007fda303b4000 nid=0x5355 runnable [0x00007fda34ba9000] java.lang.Thread.State: RUNNABLE at java.net.PlainSocketImpl.socketAccept(Native Method) at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398) at java.net.ServerSocket.implAccept(ServerSocket.java:522) at java.net.ServerSocket.accept(ServerSocket.java:490) at org.restlet.engine.http.connector.Acceptor.run(Acceptor.java:91) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e59501e0> (a java.util.concurrent.ThreadPoolExecutor$Worker) "Restlet-490563807" daemon prio=10 tid=0x00007fda303b3000 nid=0x5354 sleeping[0x00007fda34caa000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.restlet.engine.http.connector.Controller.run(Controller.java:250) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e594e230> (a java.util.concurrent.ThreadPoolExecutor$Worker) "Restlet-2015578807" prio=10 tid=0x00007fda3039f000 nid=0x5353 runnable [0x00007fda34dab000] java.lang.Thread.State: RUNNABLE at java.net.PlainSocketImpl.socketAccept(Native Method) at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398) at java.net.ServerSocket.implAccept(ServerSocket.java:522) at java.net.ServerSocket.accept(ServerSocket.java:490) at org.restlet.engine.http.connector.Acceptor.run(Acceptor.java:91) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e5952128> (a java.util.concurrent.ThreadPoolExecutor$Worker) "Restlet-1891737766" daemon prio=10 tid=0x00007fda3039c800 nid=0x5352 sleeping[0x00007fda34eac000] java.lang.Thread.State: TIMED_WAITING (sleeping) at java.lang.Thread.sleep(Native Method) at org.restlet.engine.http.connector.Controller.run(Controller.java:250) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - <0x00000000e5953fe8> (a java.util.concurrent.ThreadPoolExecutor$Worker) "Instance checker" daemon prio=10 tid=0x00007fda302d0000 nid=0x5351 runnable [0x00007fda34fad000] java.lang.Thread.State: RUNNABLE at java.net.PlainSocketImpl.socketAccept(Native Method) at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398) at java.net.ServerSocket.implAccept(ServerSocket.java:522) at java.net.ServerSocket.accept(ServerSocket.java:490) at org.serviio.ApplicationInstanceManager$1.run(ApplicationInstanceManager.java:78) at java.lang.Thread.run(Thread.java:722) Locked ownable synchronizers: - None "Service Thread" daemon prio=10 tid=0x00007fda300ed800 nid=0x534f runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "C2 CompilerThread1" daemon prio=10 tid=0x00007fda300eb000 nid=0x534e waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "C2 CompilerThread0" daemon prio=10 tid=0x00007fda300e8800 nid=0x534d waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "Signal Dispatcher" daemon prio=10 tid=0x00007fda300e6800 nid=0x534c runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "Finalizer" daemon prio=10 tid=0x00007fda3009a000 nid=0x534b in Object.wait() [0x00007fda35dd5000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e58c4850> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:135) - locked <0x00000000e58c4850> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:151) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:177) Locked ownable synchronizers: - None "Reference Handler" daemon prio=10 tid=0x00007fda30098000 nid=0x534a in Object.wait() [0x00007fda35ed6000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e58c31f0> (a java.lang.ref.Reference$Lock) at java.lang.Object.wait(Object.java:503) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:133) - locked <0x00000000e58c31f0> (a java.lang.ref.Reference$Lock) Locked ownable synchronizers: - None "VM Thread" prio=10 tid=0x00007fda30090800 nid=0x5349 runnable "Gang worker#0 (Parallel GC Threads)" prio=10 tid=0x00007fda30012800 nid=0x5345 runnable "Gang worker#1 (Parallel GC Threads)" prio=10 tid=0x00007fda30014800 nid=0x5346 runnable "Gang worker#2 (Parallel GC Threads)" prio=10 tid=0x00007fda30016800 nid=0x5347 runnable "Gang worker#3 (Parallel GC Threads)" prio=10 tid=0x00007fda30018000 nid=0x5348 runnable "VM Periodic Task Thread" prio=10 tid=0x00007fda30100800 nid=0x5350 waiting on condition JNI global references: 209

Mon Aug 06, 2012 1:27 pm by zip

If you enable detailed log is there anything happening at this time?

Mon Aug 06, 2012 1:52 pm by pete

With detailed logging there is nothing happening at that time.

Looking at the thread dump I figured that it might be something to do with the plugin scripts that I have. So stopped the server removed them all and no CPU spike. I repeated the process adding in each script one at a time checking to see if the problem reoccurred. I have managed to add them all back in and the CPU is ok (strange). I haven't checked if they work again, but as I understand it there shouldn't be a reason why they shouldn't.

This is a bit odd as it was systematicly maxing the CPU on each boot of the machine.

I will try a reboot next to try and get the problem to occurs again.

Mon Aug 06, 2012 1:55 pm by zip

the plugins might cause this if they are not properly coded. Also might happen if the plugin takes too long to process data, but Serviio will kill it after 30 secs - obviously if there are a lot of feeds or feed items that migh still block for a long time.

Mon Aug 06, 2012 2:28 pm by pete

This seems like it is the issue. I have about 18 feeds configured mostly the groovy scripts where taken from here: http://forum.serviio.org/viewtopic.php?f=20&t=3820 apart from one. I had this configured http://forum.serviio.org/viewtopic.php?f=20&t=6802&p=48742&hilit=rage#p48742. When I started looking into this problem I removed these feeds as a guess, given that was probably the script least used by other people. Even after removing the feeds (from the console) I still had the issue, which is why I looked further.

So I have added them back in and found that the site (mentioned in the second link) is down. I have no idea for how long or if the server was misbehaving at some stage. At the moment I am not seeing the CPU problem (with these feeds added back in). I can't recall seeing the Exception on the failed attempt to connect to the URL (it could have been lost in the noise).

Unless there is something odd with OnlineLibraryManager cache and a site that becomes unresponsive (I can only guess as to weather the cache is persisted between executions of the server). It would appear that the problem lies with a misbehaving script.

V1.0 CPU Usage

V1.0 CPU Usage

Re: V1.0 CPU Usage

Re: V1.0 CPU Usage

Re: V1.0 CPU Usage

Re: V1.0 CPU Usage

Who is online