Stuttering and Sync Issues

For help and support with PS3 Media Server in general
Forum rules
Please make sure you follow the Problem Reporting Guidelines before posting if you want a reply.

Stuttering and Sync Issues

Postby sangeek » Wed Jul 29, 2009 4:31 pm

I have a couple of issues. Async, Stuttering and only Mencoder works

1- your computer specs (cpu, memory) and the precise server version you're using
Dual Core Opteron 2.6ghz, 4gb memory, vista 7 64bit, quadro fx1500

2- the network connection you are using for streaming your files (wifi, homeplugs, ethernet 100Mbits, or gigabit)

Gigabit

3- detailed infos on what's not working
XBOX360
softwares used (Fresh OS and software install)
ps3ms beta 1.11.350
haali 1.9.42.1
ffdshow rev 3014 (tried also with latest rev)
coreavc 1.9.0 pro with cuda
avisynth 2.5.7
CCCP

Only works when i use Mencoder, all else crashes Mencoder with a Code 1 (most times). when i select Avisynth/Mencoder always fails... Sound is out of sync after some times. 720p does not stutter but 1080p fills up the bufffer quick then you get the stutter. I also noticed that when the bitrate goes above 98000 then it stutters as well. I think that's a limitation of the Xbox 360 since it only has a Fast Ethernet port (100mbps). So what i did is limit the bitrate to 95. that seemed to help.


4- if you're transcoding some videos, which engine are you using ? (mencoder, avisynth/mencoder, tsmuxer)

Mencoder is the only encoder that works. avisynth/mencode (which i used in the past when i had Windows XP on my machine) does not work.

5- a debug.log file excerpt, or you can zip the whole file. this file can be found in the application directory, or if you have UAC and Vista activated, likely found in the Windows temp directory
I will clear my debug log and recreate the issue so i can post the problem....

6- if you are streaming to anything other than a PS3, please mention it
XBOX360. I dont have a PS3


Other interesting stuff
I cannot insert the CoreAVC Filter to replace the "Microsoft DTV-DVD VIdeo Decoder" that came with my WIndows 7 install
Image
sangeek
 
Posts: 14
Joined: Thu Jul 16, 2009 3:20 pm

Re: Stuttering and Sync Issues

Postby shagrath » Wed Jul 29, 2009 6:44 pm

the a/v sync should be fixed in the upcoming beta... and 1080p transcoding on Xbox is difficult to achieve without a very powerful cpu
shagrath
Project Lead
 
Posts: 2667
Joined: Wed Jan 14, 2009 1:39 pm

Re: Stuttering and Sync Issues

Postby sangeek » Thu Jul 30, 2009 4:31 am

This is what happens when I try using AviSynth/Mencoder. Any Thoughts?

When do you expect the next Beta Release?

Trace
[Thread-150] TRACE 23:01:25.076 Starting transcode/remux of The.Dark.Knight.720p.mkv
[win32/mencoder.exe] TRACE 23:01:42.342 Process C:\Program Files (x86)\PS3 Media Server\win32\mencoder.exe has a return code of 1! Maybe an error occured... check the log file
[Thread-150] TRACE 23:01:42.420 Exception in thread "Thread-150" java.lang.ArrayIndexOutOfBoundsException
[Thread-150] TRACE 23:01:42.420 at java.lang.System.arraycopy(Native Method)
[Thread-150] TRACE 23:01:42.420 at net.pms.io.BufferedOutputFile.read(BufferedOutputFile.java:436)
[Thread-150] TRACE 23:01:42.420 at net.pms.io.BufferedOutputFile.access$100(BufferedOutputFile.java:31)
[Thread-150] TRACE 23:01:42.420 at net.pms.io.BufferedOutputFile$WaitBufferedInputStream.read(BufferedOutputFile.java:54)
[Thread-150] TRACE 23:01:42.420 at net.pms.network.Request.sendBytes(Request.java:470)
[Thread-150] TRACE 23:01:42.420 at net.pms.network.Request.answer(Request.java:431)
[Thread-150] TRACE 23:01:42.420 at net.pms.network.RequestHandler.run(RequestHandler.java:126)
[Thread-150] TRACE 23:01:42.420 at java.lang.Thread.run(Unknown Source)

Debug.log
[Thread-150] DEBUG 23:01:25.060 Opened handler on socket Socket[addr=/192.168.1.201,port=45321,localport=6001]
[Thread-150] DEBUG 23:01:25.060 Received on socket: GET /get/0$0$1$4$14$1/The.Dark.Knight.720p.mkv HTTP/1.1
[Thread-150] DEBUG 23:01:25.060 Received on socket: User-Agent: Xenon
[Thread-150] DEBUG 23:01:25.076 Received on socket: Connection: Keep-alive
[Thread-150] DEBUG 23:01:25.076 Received on socket: Host:192.168.1.21
[Thread-150] INFO 23:01:25.076 HTTP: get/0$0$1$4$14$1/The.Dark.Knight.720p.mkv / 0-0
[Thread-150] DEBUG 23:01:25.076 Wrote on socket: HTTP/1.1 200 OK
[Thread-150] DEBUG 23:01:25.076 Searching for objectId: 0$0$1$4$14$1 with children option: false
[Thread-150] DEBUG 23:01:25.076 Asked stream chunk [0-0] timeseek: 0.0 of The.Dark.Knight.720p.mkv and player Avisynth/MEncoder
[Thread-150] TRACE 23:01:25.076 Starting transcode/remux of The.Dark.Knight.720p.mkv
[Thread-150] DEBUG 23:01:25.076 Searching an audio track with lang: eng
[Thread-150] DEBUG 23:01:25.076 Matched audio track: Audio: DTS / lang: eng / ID: 0
[Thread-150] INFO 23:01:25.107 Creating pipe \\.\pipe\mencoder1248922885107
[Thread-151] INFO 23:01:25.232 Waiting for pipe connection \\.\pipe\mencoder1248922885107
[Timer-21] DEBUG 23:01:25.232 Buffered Space: 0 bytes / inputs: 0
[win32/mencoder.exe] INFO 23:01:25.294 Starting C:\Program Files (x86)\PS3 Media Server\win32\mencoder.exe -ss 0 -quiet C:\Users\Luis\AppData\Local\Temp\javaps3media\pms-avs-The.Dark.Knight.720p.mkv.avs -quiet -quiet -oac lavc -of lavf -lavfopts format=asf -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64 -ovc lavc -channels 2 -lavdopts debug=0:threads=2 -lavcopts autoaspect=1:vcodec=wmv2:acodec=wmav2:abitrate=256:threads=1:keyint=1:vqscale=1:vqmin=2:vrc_maxrate=60000:vrc_buf_size=1835 -quiet -quiet -subdelay 20000 -fps 24000/1001 -ofps 24000/1001 -quiet -quiet -mc 0 -noskip -af lavcresample=48000 -srate 48000 -o \\.\pipe\mencoder1248922885107
[win32/mencoder.exe] INFO 23:01:25.638 Reading pipe: \\.\pipe\mencoder1248922885107
[win32/mencoder.exe] INFO 23:01:25.638 Attaching thread: win32/mencoder.exe
[Thread-154] DEBUG 23:01:25.669 MEncoder Sherpya-SVN-r28311-4.2.5 (C) 2000-2009 MPlayer Team
[Thread-154] DEBUG 23:01:25.669 CPU: Dual-Core AMD Opteron(tm) Processor 1218 (Family: 15, Model: 67, Stepping: 2)
[Thread-154] DEBUG 23:01:25.669 CPUflags: Type: 15 MMX: 1 MMX2: 1 3DNow: 1 3DNow2: 1 SSE: 1 SSE2: 1
[Thread-154] DEBUG 23:01:25.669 Compiled with runtime CPU detection.
[Thread-154] DEBUG 23:01:25.669 success: format: 0 data: 0x0 - 0x2b7
[Timer-21] DEBUG 23:01:27.232 Buffered Space: 0 bytes / inputs: 0
[Thread-8] DEBUG 23:01:27.248 Receiving a M-SEARCH from [192.168.1.98:1026]
[Thread-8] DEBUG 23:01:27.248 Sending this reply [192.168.1.98:1026]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:27 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:27.248 Receiving a M-SEARCH from [192.168.1.98:1026]
[Thread-8] DEBUG 23:01:27.248 Sending this reply [192.168.1.98:1026]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:27 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:29.216 Receiving a M-SEARCH from [192.168.1.100:1026]
[Thread-8] DEBUG 23:01:29.216 Sending this reply [192.168.1.100:1026]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:29 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:29.216 Receiving a M-SEARCH from [192.168.1.100:1026]
[Thread-8] DEBUG 23:01:29.216 Sending this reply [192.168.1.100:1026]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:29 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Timer-21] DEBUG 23:01:29.232 Buffered Space: 0 bytes / inputs: 0
[Thread-8] DEBUG 23:01:30.123 Receiving a M-SEARCH from [192.168.1.98:1027]
[Thread-8] DEBUG 23:01:30.123 Sending this reply [192.168.1.98:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:30 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:30.123 Receiving a M-SEARCH from [192.168.1.98:1027]
[Thread-8] DEBUG 23:01:30.123 Sending this reply [192.168.1.98:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:30 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:30.451 Receiving a M-SEARCH from [192.168.1.100:1027]
[Thread-8] DEBUG 23:01:30.451 Sending this reply [192.168.1.100:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:30 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:30.451 Receiving a M-SEARCH from [192.168.1.100:1027]
[Thread-8] DEBUG 23:01:30.451 Sending this reply [192.168.1.100:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:30 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Timer-21] DEBUG 23:01:31.232 Buffered Space: 0 bytes / inputs: 0
[Thread-150] DEBUG 23:01:31.404 Wrote on socket: Content-Type: video/x-ms-wmv
[Thread-150] DEBUG 23:01:31.404 Wrote on socket: Accept-Ranges: bytes
[Thread-150] DEBUG 23:01:31.404 Wrote on socket: Connection: keep-alive
[Thread-150] DEBUG 23:01:31.404 Wrote on socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51
[Thread-150] DEBUG 23:01:31.404 Wrote on socket:
[Thread-150] DEBUG 23:01:31.404 Suspend Read: readCount=0 / writeCount=0
[Timer-21] DEBUG 23:01:33.232 Buffered Space: 0 bytes / inputs: 1
[Timer-21] DEBUG 23:01:35.232 Buffered Space: 0 bytes / inputs: 1
[Thread-8] DEBUG 23:01:35.244 Receiving a M-SEARCH from [192.168.1.98:1026]
[Thread-8] DEBUG 23:01:35.245 Sending this reply [192.168.1.98:1026]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:35 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:35.246 Receiving a M-SEARCH from [192.168.1.98:1026]
[Thread-8] DEBUG 23:01:35.247 Sending this reply [192.168.1.98:1026]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:35 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Timer-21] DEBUG 23:01:37.232 Buffered Space: 0 bytes / inputs: 1
[Thread-8] DEBUG 23:01:37.320 Receiving a M-SEARCH from [192.168.1.100:1026]
[Thread-8] DEBUG 23:01:37.320 Sending this reply [192.168.1.100:1026]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:37 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:37.320 Receiving a M-SEARCH from [192.168.1.100:1026]
[Thread-8] DEBUG 23:01:37.321 Sending this reply [192.168.1.100:1026]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:37 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:37.364 Receiving a NOTIFY from [192.168.1.50:1708]
[Thread-8] DEBUG 23:01:37.365 Receiving a NOTIFY from [192.168.1.50:1708]
[Thread-8] DEBUG 23:01:37.366 Receiving a NOTIFY from [192.168.1.50:1709]
[Thread-8] DEBUG 23:01:37.368 Receiving a NOTIFY from [192.168.1.50:1709]
[Thread-8] DEBUG 23:01:37.369 Receiving a NOTIFY from [192.168.1.50:1710]
[Thread-8] DEBUG 23:01:37.370 Receiving a NOTIFY from [192.168.1.50:1710]
[Thread-8] DEBUG 23:01:37.371 Receiving a NOTIFY from [192.168.1.50:1711]
[Thread-8] DEBUG 23:01:37.371 Receiving a NOTIFY from [192.168.1.50:1711]
[Thread-8] DEBUG 23:01:37.373 Receiving a NOTIFY from [192.168.1.50:1712]
[Thread-8] DEBUG 23:01:37.373 Receiving a NOTIFY from [192.168.1.50:1712]
[Thread-8] DEBUG 23:01:37.374 Receiving a NOTIFY from [192.168.1.50:1713]
[Thread-8] DEBUG 23:01:37.375 Receiving a NOTIFY from [192.168.1.50:1713]
[Thread-8] DEBUG 23:01:37.437 Receiving a M-SEARCH from [192.168.1.98:1027]
[Thread-8] DEBUG 23:01:37.437 Sending this reply [192.168.1.98:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:37 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:37.438 Receiving a M-SEARCH from [192.168.1.98:1027]
[Thread-8] DEBUG 23:01:37.438 Sending this reply [192.168.1.98:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:37 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:38.739 Receiving a NOTIFY from [192.168.1.50:1732]
[Thread-8] DEBUG 23:01:38.740 Receiving a NOTIFY from [192.168.1.50:1732]
[Thread-8] DEBUG 23:01:38.741 Receiving a NOTIFY from [192.168.1.50:1733]
[Thread-8] DEBUG 23:01:38.741 Receiving a NOTIFY from [192.168.1.50:1733]
[Thread-8] DEBUG 23:01:38.743 Receiving a NOTIFY from [192.168.1.50:1734]
[Thread-8] DEBUG 23:01:38.745 Receiving a NOTIFY from [192.168.1.50:1734]
[Thread-8] DEBUG 23:01:38.746 Receiving a NOTIFY from [192.168.1.50:1735]
[Thread-8] DEBUG 23:01:38.746 Receiving a NOTIFY from [192.168.1.50:1735]
[Thread-8] DEBUG 23:01:38.748 Receiving a NOTIFY from [192.168.1.50:1736]
[Thread-8] DEBUG 23:01:38.748 Receiving a NOTIFY from [192.168.1.50:1736]
[Thread-8] DEBUG 23:01:38.750 Receiving a NOTIFY from [192.168.1.50:1737]
[Thread-8] DEBUG 23:01:38.750 Receiving a NOTIFY from [192.168.1.50:1737]
[Thread-8] DEBUG 23:01:38.874 Receiving a M-SEARCH from [192.168.1.100:1027]
[Thread-8] DEBUG 23:01:38.874 Sending this reply [192.168.1.100:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:38 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-8] DEBUG 23:01:38.875 Receiving a M-SEARCH from [192.168.1.100:1027]
[Thread-8] DEBUG 23:01:38.875 Sending this reply [192.168.1.100:1027]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Thu, 30 Jul 2009 03:01:38 GMT<CRLF>LOCATION: http://192.168.1.21:6001/description/fetch<CRLF>SERVER: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.10.51<CRLF>ST: upnp:rootdevice<CRLF>EXT: <CRLF>USN: uuid:171d3062-6c55-3386-8a5b-84eb514f9dd7::upnp:rootdevice<CRLF>Content-Length: 0<CRLF><CRLF>
[Thread-150] DEBUG 23:01:38.905 Resume Read: readCount=0 / writeCount=0
[Thread-150] DEBUG 23:01:38.905 Suspend Read: readCount=32768 / writeCount=0
[Timer-21] DEBUG 23:01:39.232 Buffered Space: -32768 bytes / inputs: 1
[Timer-21] DEBUG 23:01:41.232 Buffered Space: -32768 bytes / inputs: 1
[Thread-153] DEBUG 23:01:42.327 ============ Sorry, this file format is not recognized/supported =============
[Thread-154] DEBUG 23:01:42.327 Exiting...
[Thread-153] DEBUG 23:01:42.327 === If this file is an AVI, ASF or MPEG stream, please contact the author! ===
[Thread-153] DEBUG 23:01:42.327 Cannot open demuxer.
[win32/mencoder.exe] DEBUG 23:01:42.342 EOF
[win32/mencoder.exe] TRACE 23:01:42.342 Process C:\Program Files (x86)\PS3 Media Server\win32\mencoder.exe has a return code of 1! Maybe an error occured... check the log file
[Thread-150] DEBUG 23:01:42.420 Resume Read: readCount=32768 / writeCount=0
[Thread-150] DEBUG 23:01:42.420 Ready to Stop: true
[Thread-150] DEBUG 23:01:42.420 Close Connection
[Thread-150] TRACE 23:01:42.420 Exception in thread "Thread-150" java.lang.ArrayIndexOutOfBoundsException
[Thread-150] TRACE 23:01:42.420 at java.lang.System.arraycopy(Native Method)
[Thread-150] TRACE 23:01:42.420 at net.pms.io.BufferedOutputFile.read(BufferedOutputFile.java:436)
[Thread-150] TRACE 23:01:42.420 at net.pms.io.BufferedOutputFile.access$100(BufferedOutputFile.java:31)
[Thread-150] TRACE 23:01:42.420 at net.pms.io.BufferedOutputFile$WaitBufferedInputStream.read(BufferedOutputFile.java:54)
[Thread-150] TRACE 23:01:42.420 at net.pms.network.Request.sendBytes(Request.java:470)
[Thread-150] TRACE 23:01:42.420 at net.pms.network.Request.answer(Request.java:431)
[Thread-150] TRACE 23:01:42.420 at net.pms.network.RequestHandler.run(RequestHandler.java:126)
[Thread-150] TRACE 23:01:42.420 at java.lang.Thread.run(Unknown Source)
[Timer-21] DEBUG 23:01:43.233 Buffered Space: 0 bytes / inputs: 0
sangeek
 
Posts: 14
Joined: Thu Jul 16, 2009 3:20 pm

Re: Stuttering and Sync Issues

Postby shagrath » Thu Jul 30, 2009 8:18 am

obviously an Avisynth install issue, mencoder does not recognize the script as a valid video.
shagrath
Project Lead
 
Posts: 2667
Joined: Wed Jan 14, 2009 1:39 pm

Re: Stuttering and Sync Issues

Postby sangeek » Fri Jul 31, 2009 6:11 am

The AVsync issues seems to have gone away with the latest beta... thx shagrath

I followed the guide posted by Otmanix to force CoreAVC as the default decoder in Windows 7... Thx

I uninstalled, re-downloaded and re-installed AviSynth and still getting the Code 1 error from Mencoder in the logs when i try to use Avisynth/Mencoder... What the hell i my doing wrong?

Debug.log
[New I/O server worker #2-2] DEBUG 00:39:25.468 Opened handler on socket /192.168.1.201:25656 // Xbox360
[New I/O server worker #2-2] DEBUG 00:39:25.468 Handler infos: HTTP/1.1 : GET : get/0$0$1$2/300.720p.mkv
[New I/O server worker #2-2] DEBUG 00:39:25.468 Received on socket: Connection: Keep-alive
[New I/O server worker #2-2] DEBUG 00:39:25.468 Received on socket: Host: 192.168.1.21
[New I/O server worker #2-2] DEBUG 00:39:25.468 Received on socket: User-Agent: Xenon
[New I/O server worker #2-2] INFO 00:39:25.468 HTTP: get/0$0$1$2/300.720p.mkv / 0-0
[New I/O server worker #2-2] DEBUG 00:39:25.468 Searching for objectId: 0$0$1$2 with children option: false
[New I/O server worker #2-2] DEBUG 00:39:25.468 Asked stream chunk [0-0] timeseek: 0.0 of 300.720p.mkv and player Avisynth/MEncoder
[New I/O server worker #2-2] TRACE 00:39:25.484 Starting transcode/remux of 300.720p.mkv
[New I/O server worker #2-2] DEBUG 00:39:25.484 Searching an audio track with lang: eng
[New I/O server worker #2-2] DEBUG 00:39:25.500 Searching an audio track with lang: fre
[New I/O server worker #2-2] DEBUG 00:39:25.500 Searching an audio track with lang: jpn
[New I/O server worker #2-2] DEBUG 00:39:25.500 Searching an audio track with lang: ger
[New I/O server worker #2-2] DEBUG 00:39:25.500 Choosed a default audio track: Audio: AC3 / lang: und / ID: 0
[New I/O server worker #2-2] INFO 00:39:25.562 Creating pipe \\.\pipe\mencoder1249015165562
[Timer-24] DEBUG 00:39:25.734 Buffered Space: 0 bytes / inputs: 0
[Thread-131] INFO 00:39:25.734 Waiting for pipe connection \\.\pipe\mencoder1249015165562
[win32/mencoder.exe] INFO 00:39:25.796 Starting C:\Program Files (x86)\PS3 Media Server\win32\mencoder.exe -ss 0 -quiet C:\Users\Luis\AppData\Local\Temp\javaps3media\pms-avs-300.720p.mkv.avs -quiet -quiet -oac lavc -of lavf -lavfopts format=asf -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64 -ovc lavc -channels 2 -lavdopts debug=0:threads=2 -lavcopts autoaspect=1:vcodec=wmv2:acodec=wmav2:abitrate=256:threads=1:keyint=1:vqscale=1:vqmin=2:vrc_maxrate=30000:vrc_buf_size=1835 -quiet -quiet -subdelay 20000 -fps 24000/1001 -ofps 24000/1001 -quiet -quiet -mc 0 -noskip -af lavcresample=44100 -srate 44100 -o \\.\pipe\mencoder1249015165562
[win32/mencoder.exe] INFO 00:39:25.796 Reading pipe: \\.\pipe\mencoder1249015165562
[win32/mencoder.exe] INFO 00:39:25.796 Attaching thread: win32/mencoder.exe
[Thread-135] DEBUG 00:39:26.406 MEncoder Sherpya-SVN-r28311-4.2.5 (C) 2000-2009 MPlayer Team
[Thread-135] DEBUG 00:39:26.406 CPU: Dual-Core AMD Opteron(tm) Processor 1218 (Family: 15, Model: 67, Stepping: 2)
[Thread-135] DEBUG 00:39:26.406 CPUflags: Type: 15 MMX: 1 MMX2: 1 3DNow: 1 3DNow2: 1 SSE: 1 SSE2: 1
[Thread-135] DEBUG 00:39:26.406 Compiled with runtime CPU detection.
[Thread-135] DEBUG 00:39:26.406 success: format: 0 data: 0x0 - 0x293
[Timer-24] DEBUG 00:39:27.734 Buffered Space: 0 bytes / inputs: 0
[Thread-135] DEBUG 00:39:27.960 AVS file format detected.
[Thread-135] DEBUG 00:39:27.960 VIDEO: [YV12] 1280x528 12bpp 23.976 fps 0.0 kbps ( 0.0 kbyte/s)
[Thread-135] DEBUG 00:39:27.960 [V] filefmt:38 fourcc:0x32315659 size:1280x528 fps:23.976 ftime:=0.0417
[Thread-135] DEBUG 00:39:27.960 Input fps will be interpreted as 23.976 instead.
[Thread-135] DEBUG 00:39:27.960 ==========================================================================
[Thread-135] DEBUG 00:39:27.960 Opening audio decoder: [pcm] Uncompressed PCM audio decoder
[Thread-135] DEBUG 00:39:27.960 AUDIO: 48000 Hz, 6 ch, s16le, 4608.0 kbit/100.00% (ratio: 576000->576000)
[Thread-135] DEBUG 00:39:27.960 Selected audio codec: [pcm] afm: pcm (Uncompressed PCM)
[Thread-135] DEBUG 00:39:27.960 ==========================================================================
[Thread-134] DEBUG 00:39:27.960 ** MUXER_LAVF *****************************************************************
[Thread-131] INFO 00:39:27.960 Result of \\.\pipe\mencoder1249015165562 : true
[Thread-134] DEBUG 00:39:27.960 REMEMBER: MEncoder's libavformat muxing is presently broken and can generate
[Thread-134] DEBUG 00:39:27.960 INCORRECT files in the presence of B-frames. Moreover, due to bugs MPlayer
[Thread-134] DEBUG 00:39:27.960 will play these INCORRECT files as if nothing were wrong!
[Thread-134] DEBUG 00:39:27.960 *******************************************************************************
[Thread-135] DEBUG 00:39:27.960 OK, exit.
[Thread-135] DEBUG 00:39:27.960 Opening video filter: [expand osd=1]
[Thread-135] DEBUG 00:39:27.960 Expand: -1 x -1, -1 ; -1, osd: 1, aspect: 0.000000, round: 1
[Thread-135] DEBUG 00:39:27.960 ==========================================================================
[Thread-135] DEBUG 00:39:27.960 Opening video decoder: [raw] RAW Uncompressed Video
[Thread-135] DEBUG 00:39:27.960 VDec: vo config request - 1280 x 528 (preferred colorspace: Planar YV12)
[Thread-135] DEBUG 00:39:27.960 VDec: using Planar YV12 as output csp (no 0)
[Thread-135] DEBUG 00:39:27.960 Movie-Aspect is undefined - no prescaling applied.
[Thread-135] DEBUG 00:39:27.960 videocodec: libavcodec (1280x528 fourcc=32564d57 [WMV2])
[Thread-135] DEBUG 00:39:27.960 [VE_LAVC] Using constant qscale = 1.000000 (VBR).
[Thread-135] DEBUG 00:39:27.960 Selected video codec: [rawyv12] vfm: raw (RAW YV12)
[Thread-135] DEBUG 00:39:27.960 ==========================================================================
[Thread-135] DEBUG 00:39:27.976 Exiting...
[Thread-134] DEBUG 00:39:27.976 Couldn't open codec wmav2, br=256.
[Thread-131] DEBUG 00:39:27.992 EOF
[win32/mencoder.exe] DEBUG 00:39:28.007 EOF
[win32/mencoder.exe] TRACE 00:39:28.007 Process C:\Program Files (x86)\PS3 Media Server\win32\mencoder.exe has a return code of 1! Maybe an error occured... check the log file
[Timer-24] DEBUG 00:39:29.742 Buffered Space: 0 bytes / inputs: 0
[Timer-24] DEBUG 00:39:31.742 Buffered Space: 0 bytes / inputs: 0
[New I/O server worker #2-2] DEBUG 00:39:31.914 future: wrote the last chunk, remaining-1
[New I/O server worker #2-2] DEBUG 00:39:31.914 Ready to Stop: true
[New I/O server worker #2-2] DEBUG 00:39:31.914 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #2-2] DEBUG 00:39:31.914 Sent to socket: Connection: keep-alive
[New I/O server worker #2-2] DEBUG 00:39:31.914 Sent to socket: Content-Type: video/x-ms-wmv
[New I/O server worker #2-2] DEBUG 00:39:31.914 Sent to socket: Server: Windows_Vista-x86-6.1, UPnP/1.0, PMS/1.11.356
[Timer-24] DEBUG 00:39:33.742 Buffered Space: 0 bytes / inputs: 0
[Thread-136] INFO 00:39:34.414 Stopping process: win32/mencoder.exe
[Thread-136] INFO 00:39:34.414 Destroying buffer
sangeek
 
Posts: 14
Joined: Thu Jul 16, 2009 3:20 pm

Re: Stuttering and Sync Issues

Postby shagrath » Fri Jul 31, 2009 8:30 am

I haven't launch the xbox since ages, it's time to power it on I guess :p
shagrath
Project Lead
 
Posts: 2667
Joined: Wed Jan 14, 2009 1:39 pm

Re: Stuttering and Sync Issues

Postby sangeek » Fri Jul 31, 2009 6:25 pm

Let me know what you find. Please remember that I followed the procedure below to the "T"... Things run really smooth on the PC (even 1080p only use about 30-45% CPU) but as you already know Avisynth/Mencoder crashes Mencoder everytime when playing through PS3 Media Server and my Xbox360...

http://windows7news.com/2009/07/22/ulti ... ia-center/

One more question. is it normal that when using Mencoder transcoding, CoreAVC FFMpeg Halii Splitter etc. are not activated? When I play a file on the PC all the icons activate in the system tray, but as i mentioned above not through PS3 Media Server...
sangeek
 
Posts: 14
Joined: Thu Jul 16, 2009 3:20 pm


Return to General Help and Support

Who is online

Users browsing this forum: No registered users and 9 guests