and a small follow up to my previous post :
after some testing I've noticed that this issue mostly (if not only) happening with HD channels like National Geographic HD or Animal Planet HD (both streaming in 1080i/p). Also, when the channels finally starting playing, in around 10-20 minutes the playback just hangs and ffmpeg quit and my TV hangs as well
It happens with HD and SD channels so this might be the buffering issue? is there any setting that i can tweak in this line to fix the issue? :
Code: Select all
/Applications/Universal Media Server.app/Contents/Resources/Java/osx/ffmpeg, -v, 0, -y, -threads, 2, -i, http://95.188.102.231:1234/udp/233.7.70.133:5000, -threads, 2, -target, ntsc-dvd, /private/var/folders/p1/zkn1q6n53kqbwtkrj4l1mcvm0000gn/T/universalmediaserver/pmsencoder_transcoder_out_1354106923770_121
UPDATE : adding the debug.log to show what's happening when the stream just hangs (
exact time : 16:27:41) and hanging my TV as well (have to power off and back on the TV) :
Code: Select all
INFO 2012-11-28 15:36:44.505 [New I/O server worker #2-4] Checking shared folder: /Users/me/Movies
INFO 2012-11-28 15:36:44.506 [New I/O server worker #2-4] Checking shared folder: /Volumes/Elements/Video
INFO 2012-11-28 15:36:44.510 [New I/O server worker #2-4] Checking shared folder: /Volumes/Elements/Photos
DEBUG 2012-11-28 15:36:44.525 [New I/O server worker #2-4] Node(net.pms.external.infidel.jumpy.scriptFolder) already has an ID=0$5, which is overridden now. The previous parent node was:RootFolder[[MapFile [name=Movies, id=0$1, format=null, children=[]], MapFile [name=Video, id=0$2, format=null, children=[]], MapFile [name=Photos, id=0$3, format=null, children=[]], VirtualFolder [id=4, name=Web, full path=0$4, ext=null, discovered=false]]]
INFO 2012-11-28 15:36:53.328 [New I/O server worker #2-2] Starting transcode/remux of THT
DEBUG 2012-11-28 15:36:53.339 [mkfifo-7] Starting mkfifo -m 777 /var/folders/p1/zkn1q6n53kqbwtkrj4l1mcvm0000gn/T/universalmediaserver/pmsencoder_transcoder_out_1354113413329_487
DEBUG 2012-11-28 15:36:53.371 [mkfifo-7] Unix process ID (mkfifo): 3008
DEBUG 2012-11-28 15:36:53.576 [osx/ffmpeg] Starting /Applications/Universal Media Server.app/Contents/Resources/Java/osx/ffmpeg -v 0 -y -threads 2 -i http://77.106.112.11:8001/tnt_2 -threads 2 -target ntsc-dvd /private/var/folders/p1/zkn1q6n53kqbwtkrj4l1mcvm0000gn/T/universalmediaserver/pmsencoder_transcoder_out_1354113413329_487
DEBUG 2012-11-28 15:36:53.602 [osx/ffmpeg] Reading pipe: /var/folders/p1/zkn1q6n53kqbwtkrj4l1mcvm0000gn/T/universalmediaserver/pmsencoder_transcoder_out_1354113413329_487
DEBUG 2012-11-28 15:37:01.626 [osx/ffmpeg] Attaching thread: osx/ffmpeg
INFO 2012-11-28 15:37:01.783 [StartPlaying Event] Started playing THT on your Philips 3xx7
DEBUG 2012-11-28 15:37:01.784 [StartPlaying Event] The full filename of which is: http://77.106.112.11:8001/tnt_2and the address of the renderer is: 10.0.10.3
DEBUG 2012-11-28 15:37:03.035 [New I/O server worker #2-1] Setting InputStream new position to: 99,999,485,952
INFO 2012-11-28 15:37:10.549 [New I/O server worker #2-1] Buffer read ArrayIndexOutOfBoundsException error:
INFO 2012-11-28 15:37:10.550 [New I/O server worker #2-1] readCount: "99999485952"
INFO 2012-11-28 15:37:10.551 [New I/O server worker #2-1] maxMemorySize: "419430400"
INFO 2012-11-28 15:37:18.066 [New I/O server worker #2-1] Buffer read ArrayIndexOutOfBoundsException error:
INFO 2012-11-28 15:37:18.070 [New I/O server worker #2-1] readCount: "99999485952"
INFO 2012-11-28 15:37:18.076 [New I/O server worker #2-1] maxMemorySize: "419430400"
DEBUG 2012-11-28 15:37:18.642 [New I/O server worker #2-2] Setting InputStream new position to: 99,999,485,952
INFO 2012-11-28 15:37:26.157 [New I/O server worker #2-2] Buffer read ArrayIndexOutOfBoundsException error:
INFO 2012-11-28 15:37:26.158 [New I/O server worker #2-2] readCount: "99999485952"
INFO 2012-11-28 15:37:26.159 [New I/O server worker #2-2] maxMemorySize: "419430400"
INFO 2012-11-28 15:37:33.672 [New I/O server worker #2-2] Buffer read ArrayIndexOutOfBoundsException error:
INFO 2012-11-28 15:37:33.674 [New I/O server worker #2-2] readCount: "99999485952"
INFO 2012-11-28 15:37:33.674 [New I/O server worker #2-2] maxMemorySize: "419430400"
DEBUG 2012-11-28 15:37:59.602 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 15:38:59.176 [New I/O server worker #2-1] Setting margin to 2Mb
DEBUG 2012-11-28 15:41:03.845 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 15:44:08.140 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 15:47:12.525 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 15:50:17.175 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 15:53:21.407 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 15:56:26.485 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 15:59:31.430 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:02:34.432 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:04:56.280 [New I/O server worker #2-2] Caught exception
java.lang.NullPointerException: null
at net.pms.network.RequestV2.answer(RequestV2.java:736) ~[ums-2.1.1.jar:na]
at net.pms.network.RequestHandlerV2.writeResponse(RequestHandlerV2.java:312) ~[ums-2.1.1.jar:na]
at net.pms.network.RequestHandlerV2.messageReceived(RequestHandlerV2.java:269) ~[ums-2.1.1.jar:na]
at org.jboss.netty.handler.stream.ChunkedWriteHandler.handleUpstream(ChunkedWriteHandler.java:149) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.handler.codec.http.HttpChunkAggregator.messageReceived(HttpChunkAggregator.java:116) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:302) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.handler.codec.replay.ReplayingDecoder.unfoldAndFireMessageReceived(ReplayingDecoder.java:527) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.handler.codec.replay.ReplayingDecoder.callDecode(ReplayingDecoder.java:506) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.handler.codec.replay.ReplayingDecoder.messageReceived(ReplayingDecoder.java:443) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:274) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:261) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:351) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.socket.nio.NioWorker.processSelectedKeys(NioWorker.java:282) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:202) ~[netty-3.2.7.Final.jar:na]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [na:1.6.0_37]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [na:1.6.0_37]
at java.lang.Thread.run(Thread.java:680) [na:1.6.0_37]
DEBUG 2012-11-28 16:04:56.280 [New I/O server worker #2-3] Caught exception
java.lang.NullPointerException: null
at net.pms.network.RequestV2.answer(RequestV2.java:736) ~[ums-2.1.1.jar:na]
at net.pms.network.RequestHandlerV2.writeResponse(RequestHandlerV2.java:312) ~[ums-2.1.1.jar:na]
at net.pms.network.RequestHandlerV2.messageReceived(RequestHandlerV2.java:269) ~[ums-2.1.1.jar:na]
at org.jboss.netty.handler.stream.ChunkedWriteHandler.handleUpstream(ChunkedWriteHandler.java:149) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.handler.codec.http.HttpChunkAggregator.messageReceived(HttpChunkAggregator.java:116) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:302) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.handler.codec.replay.ReplayingDecoder.unfoldAndFireMessageReceived(ReplayingDecoder.java:527) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.handler.codec.replay.ReplayingDecoder.callDecode(ReplayingDecoder.java:506) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.handler.codec.replay.ReplayingDecoder.messageReceived(ReplayingDecoder.java:443) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:274) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:261) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:351) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.socket.nio.NioWorker.processSelectedKeys(NioWorker.java:282) ~[netty-3.2.7.Final.jar:na]
at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:202) ~[netty-3.2.7.Final.jar:na]
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [na:1.6.0_37]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [na:1.6.0_37]
at java.lang.Thread.run(Thread.java:680) [na:1.6.0_37]
DEBUG 2012-11-28 16:05:39.104 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:08:42.062 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:11:47.278 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:14:52.945 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:17:56.203 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:21:01.121 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:24:03.732 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:27:08.941 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-11-28 16:27:41.352 [osx/ffmpeg] Stopping Unix process 3008: mkfifo-7
DEBUG 2012-11-28 16:27:41.770 [New I/O server worker #2-4] Setting InputStream new position to: 1,557,662,720
DEBUG 2012-11-28 16:27:42.437 [New I/O server worker #2-1] Setting InputStream new position to: 1,557,662,720
DEBUG 2012-11-28 16:27:42.598 [New I/O server worker #2-2] Setting InputStream new position to: 1,557,903,360
DEBUG 2012-11-28 16:27:44.185 [Buffered IO End Checker] Stopping process: osx/ffmpeg
INFO 2012-11-28 16:27:46.602 [StopPlaying Event] Stopped playing THT on your Philips 3xx7
DEBUG 2012-11-28 16:27:46.603 [StopPlaying Event] The full filename of which is: http://77.106.112.11:8001/tnt_2and the address of the renderer is: 10.0.10.3
and here's the pmsencoder.log :
Code: Select all
2012-11-28 15:36:53 [INFO|PMSEncoder|launchTranscode|New I/O server worker #2-2]: invoking matcher for: http://77.106.112.11:8001/tnt_2
2012-11-28 15:36:53 [INFO|PMSEncoder|launchTranscode|New I/O server worker #2-2]: 0 matches for: http://77.106.112.11:8001/tnt_2
2012-11-28 15:36:53 [INFO|ProcessManager|handleTranscode|New I/O server worker #2-2]: transcoder command: [/Applications/Universal Media Server.app/Contents/Resources/Java/osx/ffmpeg, -v, 0, -y, -threads, 2, -i, http://77.106.112.11:8001/tnt_2, -threads, 2, -target, ntsc-dvd, /private/var/folders/p1/zkn1q6n53kqbwtkrj4l1mcvm0000gn/T/universalmediaserver/pmsencoder_transcoder_out_1354113413329_487]
Another update (sorry for making it that long)
Ive figured it out why it stop and the reason is pretty simple: the buffer just runs out. As soon as the buffer gets 0mb (usually its about 10-20mb) the playback stops as seen above in the logs
now the question is, why does it stops the steam and hangs the tv instead of just buffering a bit more of the stream? ( thats what xbmc does when it runs out of buffer)