MKV Slowdown.

For help and support with Universal Media Server
Forum rules
Please make sure you follow the Problem Reporting Guidelines before posting if you want a reply
Tyestor
Posts: 66
Joined: Fri Oct 05, 2012 6:19 pm
Location: Brisbane, Australia
Contact:

MKV Slowdown.

Post by Tyestor »

Hi. I posted this on the PMS forums as I'm having the exact same problem with UMS as I'm having with PMS. I honestly have no idea what's going on.Thanks.
Hello. I have a problem trying to playback some matroska files. This is puzzling for me as PMS usually works fine but lately it's just not working right. Basically, when trying to play an mkv file the audio slows down massively and the video becomes very choppy. This is weird as the mkvs I have tried so far are only 720p whereas I have had no problems playing 1080p video back previously. The files also play 100% perfectly on my PC so it definitely can't be the files. I noticed that enabling the "definitely disable subtitles" option helps somewhat but it is still unwatchable. Please help me =/

This is one of the videos in question:

Image

CPU: Intel Corei5 2500k @ 3.8ghz
GPU: AMD (Gigabyte) HD6950 @ 840mhz/1350mhz
MoBo: Gigabyte GA-Z77X-D3H
RAM: G.Skill Ripjaws 12gb DDR3 1600mhz
OS: Windows 8 x64 Professional
Java: Java 7u7 x64

Playback files I currently use are as follows and are not up for replacement:

MPC-HC JanWillem32 x86 AVX build.
MadVR.
MadFLAC.
xy-VSFilter.
LAV Filters.
Haali Media Splitter (mkv only).

This plays everything perfectly, including hi10p 1080p FLAC bluray encodes. I don't know why PMS is being so dodgy all of a sudden...

On a side note, is there any way I can use any of the above with PMS?

EDIT: Just managed to play back 1080p Hi10p AAC episode of Hellsing Ultimate. Wtf is going on, I should be able to play this fine.
EDIT 2: Just downloaded Serviio and the suspect files play perfectly fine there. The only problem is that serviio doesn't support .ass external subs or subs of any format inside a container (i.e MKV)....sigh. I need to know how to fix this. It's definitely something to do with PMS.
Last edited by Tyestor on Fri Oct 12, 2012 1:03 am, edited 2 times in total.
User avatar
SubJunk
Lead Developer
Posts: 3705
Joined: Sun May 27, 2012 4:12 pm

Re: MKV Slowdown.

Post by SubJunk »

Please post your debug.log :)
Tyestor
Posts: 66
Joined: Fri Oct 05, 2012 6:19 pm
Location: Brisbane, Australia
Contact:

Re: MKV Slowdown.

Post by Tyestor »

Did a full clean install of 1.5.1. Deleted the UMS Folder in ProgramData, Uninstalled UMS and Avisynth, CCleaner, installed UMS as admin (left heap size at 768, usually have it at 4096), installed avisynth and ran UMS as admin.

Code: Select all

DEBUG 2012-10-06 13:41:55.960 [main] My PID is 4992
DEBUG 2012-10-06 13:41:55.964 [main] Java version: 1.7.0_07
DEBUG 2012-10-06 13:41:55.964 [main] Java vendor: Oracle Corporation
DEBUG 2012-10-06 13:41:55.964 [main] Java class path: update.jar;ums.jar
DEBUG 2012-10-06 13:41:55.966 [main] Operating system name: Windows 8
DEBUG 2012-10-06 13:41:55.966 [main] Operating system architecture: amd64
DEBUG 2012-10-06 13:41:55.966 [main] Operating system version: 6.2
DEBUG 2012-10-06 13:41:56.018 [main] SUN 1.7: SUN (DSA key/parameter generation; DSA signing; SHA-1, MD5 digests; SecureRandom; X.509 certificates; JKS keystore; PKIX CertPathValidator; PKIX CertPathBuilder; LDAP, Collection CertStores, JavaPolicy Policy; JavaLoginConfig Configuration)
DEBUG 2012-10-06 13:41:56.018 [main] SunRsaSign 1.7: Sun RSA signature provider
DEBUG 2012-10-06 13:41:56.018 [main] SunEC 1.7: Sun Elliptic Curve provider (EC, ECDSA, ECDH)
DEBUG 2012-10-06 13:41:56.018 [main] SunJSSE 1.7: Sun JSSE provider(PKCS12, SunX509 key/trust factories, SSLv3, TLSv1)
DEBUG 2012-10-06 13:41:56.018 [main] SunJCE 1.7: SunJCE Provider (implements RSA, DES, Triple DES, AES, Blowfish, ARCFOUR, RC2, PBE, Diffie-Hellman, HMAC)
DEBUG 2012-10-06 13:41:56.018 [main] SunJGSS 1.7: Sun (Kerberos v5, SPNEGO)
DEBUG 2012-10-06 13:41:56.020 [main] SunSASL 1.7: Sun SASL provider(implements client mechanisms for: DIGEST-MD5, GSSAPI, EXTERNAL, PLAIN, CRAM-MD5, NTLM; server mechanisms for: DIGEST-MD5, GSSAPI, CRAM-MD5, NTLM)
DEBUG 2012-10-06 13:41:56.020 [main] XMLDSig 1.0: XMLDSig (DOM XMLSignatureFactory; DOM KeyInfoFactory)
DEBUG 2012-10-06 13:41:56.020 [main] SunPCSC 1.7: Sun PC/SC provider
DEBUG 2012-10-06 13:41:56.020 [main] SunMSCAPI 1.7: Sun's Microsoft Crypto API provider
DEBUG 2012-10-06 13:41:56.022 [main] Set parameter http.useragent = Jakarta Commons-HttpClient/3.1
DEBUG 2012-10-06 13:41:56.022 [main] Set parameter http.protocol.version = HTTP/1.1
DEBUG 2012-10-06 13:41:56.022 [main] Set parameter http.connection-manager.class = class org.apache.commons.httpclient.SimpleHttpConnectionManager
DEBUG 2012-10-06 13:41:56.022 [main] Set parameter http.protocol.cookie-policy = default
DEBUG 2012-10-06 13:41:56.022 [main] Set parameter http.protocol.element-charset = US-ASCII
DEBUG 2012-10-06 13:41:56.022 [main] Set parameter http.protocol.content-charset = ISO-8859-1
DEBUG 2012-10-06 13:41:56.024 [main] Set parameter http.method.retry-handler = org.apache.commons.httpclient.DefaultHttpMethodRetryHandler@3c9c87e4
DEBUG 2012-10-06 13:41:56.024 [main] Set parameter http.dateparser.patterns = [EEE, dd MMM yyyy HH:mm:ss zzz, EEEE, dd-MMM-yy HH:mm:ss zzz, EEE MMM d HH:mm:ss yyyy, EEE, dd-MMM-yyyy HH:mm:ss z, EEE, dd-MMM-yyyy HH-mm-ss z, EEE, dd MMM yy HH:mm:ss z, EEE dd-MMM-yyyy HH:mm:ss z, EEE dd MMM yyyy HH:mm:ss z, EEE dd-MMM-yyyy HH-mm-ss z, EEE dd-MMM-yy HH:mm:ss z, EEE dd MMM yy HH:mm:ss z, EEE,dd-MMM-yy HH:mm:ss z, EEE,dd-MMM-yyyy HH:mm:ss z, EEE, dd-MM-yyyy HH:mm:ss z]
DEBUG 2012-10-06 13:41:56.162 [pool-2-thread-1] Open connection to raw.github.com:443
INFO  2012-10-06 13:41:56.650 [main] Starting Universal Media Server 1.5.1
INFO  2012-10-06 13:41:56.658 [main] Based on PS3 Media Server (ps3mediaserver.org) by shagrath, copyright 2008-2012
INFO  2012-10-06 13:41:56.660 [main] http://www.universalmediaserver.com
INFO  2012-10-06 13:41:56.662 [main] 
INFO  2012-10-06 13:41:56.662 [main] Build: 562ec8ab3 (2012-09-19)
INFO  2012-10-06 13:41:56.662 [main] Java: 1.7.0_07-Oracle Corporation
INFO  2012-10-06 13:41:56.664 [main] OS: Windows 8 64-bit 6.2
INFO  2012-10-06 13:41:56.664 [main] Encoding: UTF-8
INFO  2012-10-06 13:41:56.666 [main] Memory: 682 MB
INFO  2012-10-06 13:41:56.666 [main] 
INFO  2012-10-06 13:41:56.666 [main] Working directory: C:\Program Files (x86)\Universal Media Server
INFO  2012-10-06 13:41:56.672 [main] Temp folder: C:\Users\Josh\AppData\Local\Temp\ps3mediaserver
INFO  2012-10-06 13:41:56.674 [main] Logging config file: C:\Program Files (x86)\Universal Media Server\logback.xml
INFO  2012-10-06 13:41:56.674 [main] debug.log: C:\Program Files (x86)\Universal Media Server\debug.log
INFO  2012-10-06 13:41:56.674 [main] 
INFO  2012-10-06 13:41:56.674 [main] Profile directory: C:\ProgramData\UMS
INFO  2012-10-06 13:41:56.676 [main] Profile path: C:\ProgramData\UMS\UMS.conf
INFO  2012-10-06 13:41:56.676 [main] Profile status: rw
INFO  2012-10-06 13:41:56.676 [main] Profile name: HIGHTOWER
INFO  2012-10-06 13:41:56.676 [main] 
INFO  2012-10-06 13:41:56.680 [main] Loading MediaInfo library
INFO  2012-10-06 13:41:56.748 [main] Loaded MediaInfoLib - v0.7.60
INFO  2012-10-06 13:41:56.750 [main] Loading renderer configurations from C:\Program Files (x86)\Universal Media Server\renderers
INFO  2012-10-06 13:41:56.752 [main] Loading configuration file: AirPlayer.conf
DEBUG 2012-10-06 13:41:56.752 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/AirPlayer.conf
INFO  2012-10-06 13:41:56.754 [main] Loading configuration file: Android.conf
DEBUG 2012-10-06 13:41:56.754 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Android.conf
INFO  2012-10-06 13:41:56.756 [main] Loading configuration file: Bravia4500.conf
DEBUG 2012-10-06 13:41:56.756 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Bravia4500.conf
INFO  2012-10-06 13:41:56.758 [main] Loading configuration file: Bravia5500.conf
DEBUG 2012-10-06 13:41:56.758 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Bravia5500.conf
INFO  2012-10-06 13:41:56.760 [main] Loading configuration file: BraviaEX.conf
DEBUG 2012-10-06 13:41:56.762 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/BraviaEX.conf
INFO  2012-10-06 13:41:56.764 [main] Loading configuration file: DirecTVHR.conf
DEBUG 2012-10-06 13:41:56.766 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/DirecTVHR.conf
INFO  2012-10-06 13:41:56.768 [main] Loading configuration file: DLinkDSM510.conf
DEBUG 2012-10-06 13:41:56.768 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/DLinkDSM510.conf
INFO  2012-10-06 13:41:56.770 [main] Loading configuration file: FreeboxHD.conf
DEBUG 2012-10-06 13:41:56.770 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/FreeboxHD.conf
INFO  2012-10-06 13:41:56.772 [main] Loading configuration file: FreecomMusicPal.conf
DEBUG 2012-10-06 13:41:56.772 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/FreecomMusicPal.conf
INFO  2012-10-06 13:41:56.774 [main] Loading configuration file: iPad-iPhone.conf
DEBUG 2012-10-06 13:41:56.774 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/iPad-iPhone.conf
INFO  2012-10-06 13:41:56.776 [main] Loading configuration file: Kuro.conf
DEBUG 2012-10-06 13:41:56.778 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Kuro.conf
INFO  2012-10-06 13:41:56.780 [main] Loading configuration file: LGST600.conf
DEBUG 2012-10-06 13:41:56.780 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/LGST600.conf
INFO  2012-10-06 13:41:56.782 [main] Loading configuration file: N900.conf
DEBUG 2012-10-06 13:41:56.782 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/N900.conf
INFO  2012-10-06 13:41:56.784 [main] Loading configuration file: OPPOBDP83.conf
DEBUG 2012-10-06 13:41:56.784 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/OPPOBDP83.conf
INFO  2012-10-06 13:41:56.786 [main] Loading configuration file: OPPOBDP93.conf
DEBUG 2012-10-06 13:41:56.786 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/OPPOBDP93.conf
INFO  2012-10-06 13:41:56.788 [main] Loading configuration file: Panasonic.conf
DEBUG 2012-10-06 13:41:56.788 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Panasonic.conf
INFO  2012-10-06 13:41:56.790 [main] Loading configuration file: Philips.conf
DEBUG 2012-10-06 13:41:56.792 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Philips.conf
INFO  2012-10-06 13:41:56.792 [main] Loading configuration file: PhilipsPFL.conf
DEBUG 2012-10-06 13:41:56.792 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/PhilipsPFL.conf
INFO  2012-10-06 13:41:56.796 [main] Loading configuration file: PopcornHour.conf
DEBUG 2012-10-06 13:41:56.798 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/PopcornHour.conf
INFO  2012-10-06 13:41:56.798 [main] Loading configuration file: PS3.conf
DEBUG 2012-10-06 13:41:56.798 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/PS3.conf
INFO  2012-10-06 13:41:56.802 [main] Loading configuration file: Realtek.conf
DEBUG 2012-10-06 13:41:56.804 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Realtek.conf
INFO  2012-10-06 13:41:56.804 [main] Loading configuration file: SamsungAllShare.conf
DEBUG 2012-10-06 13:41:56.806 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/SamsungAllShare.conf
INFO  2012-10-06 13:41:56.806 [main] Loading configuration file: SamsungWiseLink.conf
DEBUG 2012-10-06 13:41:56.806 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/SamsungWiseLink.conf
INFO  2012-10-06 13:41:56.808 [main] Loading configuration file: Showtime3.conf
DEBUG 2012-10-06 13:41:56.808 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Showtime3.conf
INFO  2012-10-06 13:41:56.810 [main] Loading configuration file: SMP-N100.conf
DEBUG 2012-10-06 13:41:56.810 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/SMP-N100.conf
INFO  2012-10-06 13:41:56.812 [main] Loading configuration file: SonyBluray.conf
DEBUG 2012-10-06 13:41:56.812 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/SonyBluray.conf
INFO  2012-10-06 13:41:56.814 [main] Loading configuration file: Streamium.conf
DEBUG 2012-10-06 13:41:56.814 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Streamium.conf
INFO  2012-10-06 13:41:56.814 [main] Loading configuration file: WDTVLive.conf
DEBUG 2012-10-06 13:41:56.816 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/WDTVLive.conf
INFO  2012-10-06 13:41:56.816 [main] Loading configuration file: WMP.conf
DEBUG 2012-10-06 13:41:56.816 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/WMP.conf
INFO  2012-10-06 13:41:56.818 [main] Loading configuration file: XBMC.conf
DEBUG 2012-10-06 13:41:56.818 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/XBMC.conf
INFO  2012-10-06 13:41:56.818 [main] Loading configuration file: XBOX360.conf
DEBUG 2012-10-06 13:41:56.818 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/XBOX360.conf
INFO  2012-10-06 13:41:56.820 [main] Loading configuration file: YamahaRXA1010.conf
DEBUG 2012-10-06 13:41:56.820 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/YamahaRXA1010.conf
INFO  2012-10-06 13:41:56.822 [main] Loading configuration file: YamahaRXV671.conf
DEBUG 2012-10-06 13:41:56.822 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/YamahaRXV671.conf
INFO  2012-10-06 13:41:56.822 [main] Checking MPlayer font cache. It can take a minute or so.
DEBUG 2012-10-06 13:41:56.822 [main] launching: win32/mplayer.exe
DEBUG 2012-10-06 13:41:56.902 [main] launching: win32/mplayer.exe
INFO  2012-10-06 13:41:56.976 [main] Done!
INFO  2012-10-06 13:41:56.978 [main] Found AviSynth plugins dir: C:\Program Files (x86)\AviSynth\plugins
INFO  2012-10-06 13:41:56.978 [main] VSFilter.dll is not in the AviSynth plugins directory. This can cause problems when trying to play subtitled videos with AviSynth
INFO  2012-10-06 13:41:56.980 [main] Searching for plugins in C:\Program Files (x86)\Universal Media Server\plugins
INFO  2012-10-06 13:41:56.982 [main] No plugins found
INFO  2012-10-06 13:41:56.990 [main] Registering transcoding engine: FFmpeg/AviSynth
INFO  2012-10-06 13:41:56.992 [main] Registering transcoding engine: FFmpeg Audio
INFO  2012-10-06 13:41:56.994 [main] Registering transcoding engine: MEncoder
INFO  2012-10-06 13:41:56.994 [main] Registering transcoding engine: AviSynth/MEncoder
INFO  2012-10-06 13:41:56.996 [main] Registering transcoding engine: FFmpeg
INFO  2012-10-06 13:41:56.996 [main] Registering transcoding engine: MPlayer Audio
INFO  2012-10-06 13:41:56.996 [main] Registering transcoding engine: FFmpeg Web Video
INFO  2012-10-06 13:41:56.996 [main] Registering transcoding engine: MEncoder Web
INFO  2012-10-06 13:41:56.996 [main] Registering transcoding engine: MPlayer Video Dump
INFO  2012-10-06 13:41:56.996 [main] Registering transcoding engine: MPlayer Web
INFO  2012-10-06 13:41:56.998 [main] Registering transcoding engine: tsMuxeR
INFO  2012-10-06 13:41:56.998 [main] Registering transcoding engine: Audio High Fidelity
INFO  2012-10-06 13:41:56.998 [main] Executable of transcoder profile VLC Audio Streaming not found
INFO  2012-10-06 13:41:57.000 [main] Executable of transcoder profile VLC Video Streaming not found
INFO  2012-10-06 13:41:57.000 [main] Registering transcoding engine: FFmpeg DVR-MS Remux
INFO  2012-10-06 13:41:57.000 [main] Registering transcoding engine: dcraw Thumbnailer
INFO  2012-10-06 13:41:57.072 [main] Using address /192.168.1.135 found on network interface: name:eth4 (Qualcomm Atheros AR8151 PCI-E Gigabit Ethernet Controller (NDIS 6.30))
INFO  2012-10-06 13:41:57.072 [main] Created socket: /192.168.1.135:5001
DEBUG 2012-10-06 13:41:57.169 [main] Sending ALIVE...
INFO  2012-10-06 13:41:57.185 [main] Using the following UUID configured in UMS.conf: 736ee105-0c27-4fd7-81ab-e8332eef2ea4
DEBUG 2012-10-06 13:41:57.477 [pool-2-thread-1] >> "GET /UniversalMediaServer/UniversalMediaServer/master/src/main/external-resources/update/latest_version.properties HTTP/1.1[\r][\n]"
DEBUG 2012-10-06 13:41:57.478 [pool-2-thread-1] Adding Host request header
DEBUG 2012-10-06 13:41:57.483 [pool-2-thread-1] >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]"
DEBUG 2012-10-06 13:41:57.484 [pool-2-thread-1] >> "Host: raw.github.com[\r][\n]"
DEBUG 2012-10-06 13:41:57.484 [pool-2-thread-1] >> "[\r][\n]"
INFO  2012-10-06 13:41:58.207 [New I/O server worker #1-1] No IP filter specified, access granted to /192.168.1.108
INFO  2012-10-06 13:41:58.212 [pool-7-thread-1] Checking IP: 192.168.1.108 for PlayStation 3
INFO  2012-10-06 13:41:58.796 [New I/O server worker #1-7] No IP filter specified, access granted to /192.168.1.106
INFO  2012-10-06 13:41:58.798 [pool-7-thread-2] Checking IP: 192.168.1.106 for WMP
INFO  2012-10-06 13:41:58.800 [pool-7-thread-2] Renderer WMP found on this address: HIGHTOWER-TWO (192.168.1.106)
DEBUG 2012-10-06 13:41:58.804 [pool-7-thread-2] Starting ping -n 3 -l 64000 192.168.1.106
INFO  2012-10-06 13:41:59.206 [New I/O server worker #1-2] No IP filter specified, access granted to /192.168.1.117
INFO  2012-10-06 13:41:59.208 [New I/O server worker #1-2] Another renderer like WMP was found!
INFO  2012-10-06 13:41:59.209 [pool-7-thread-3] Checking IP: 192.168.1.117 for WMP
INFO  2012-10-06 13:41:59.211 [pool-7-thread-3] Renderer WMP found on this address: LARRYLAPTOP (192.168.1.117)
DEBUG 2012-10-06 13:41:59.211 [pool-7-thread-3] Starting ping -n 3 -l 64000 192.168.1.117
INFO  2012-10-06 13:42:00.034 [main] The server should now appear on your renderer
DEBUG 2012-10-06 13:42:00.642 [pool-2-thread-1] << "HTTP/1.1 200 OK[\r][\n]"
DEBUG 2012-10-06 13:42:00.642 [pool-2-thread-1] << "HTTP/1.1 200 OK[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Server: nginx[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Date: Sat, 06 Oct 2012 03:42:04 GMT[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Content-Type: text/plain; charset=utf-8[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Connection: keep-alive[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Status: 200 OK[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Content-Transfer-Encoding: binary[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Content-Disposition: inline[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "X-RateLimit-Limit: 100[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "X-Frame-Options: deny[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "X-Runtime: 11[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Content-Length: 109[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "X-RateLimit-Remaining: 100[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "Cache-Control: private[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "X-Content-Type-Options: nosniff[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "ETag: "e18656c2cfe4f0150f866c8ce13106f7"[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "[\r][\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] Buffering response body
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "LatestVersion="1.5.2"[\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] << "DownloadUrl.windows="http://universal-media-server.googlecode.com/files/UMS-1.5.2.exe"[\n]"
DEBUG 2012-10-06 13:42:00.644 [pool-2-thread-1] Should NOT close connection in response to directive: keep-alive
DEBUG 2012-10-06 13:42:00.646 [pool-2-thread-1] Releasing connection back to connection manager.
DEBUG 2012-10-06 13:42:00.806 [SpeedStats Failsafe] Stopping process: ping-0
DEBUG 2012-10-06 13:42:01.213 [SpeedStats Failsafe] Stopping process: ping-1
INFO  2012-10-06 13:42:01.213 [pool-7-thread-3] Address /192.168.1.117 has an estimated network speed of: 35 Mb/s
INFO  2012-10-06 13:42:03.808 [pool-7-thread-1] Renderer PlayStation 3 found on this address: 192.168.1.108
DEBUG 2012-10-06 13:42:03.808 [pool-7-thread-1] Starting ping -n 3 -l 64000 192.168.1.108
DEBUG 2012-10-06 13:42:05.809 [SpeedStats Failsafe] Stopping process: ping-2
DEBUG 2012-10-06 13:42:10.034 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-10-06 13:42:13.915 [New I/O server worker #1-1] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -ss 2 -i "E:\Camera Demonstration.mp4" -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-10-06 13:42:13.920 [New I/O server worker #1-1] Attaching thread: ffmpeg.exe-3
DEBUG 2012-10-06 13:42:16.536 [Buffered IO End Checker] Stopping process: ffmpeg.exe-3
DEBUG 2012-10-06 13:42:23.848 [New I/O server worker #1-7] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -ss 2 -i "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv" -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-10-06 13:42:23.850 [New I/O server worker #1-7] Attaching thread: ffmpeg.exe-4
DEBUG 2012-10-06 13:42:24.635 [New I/O server worker #1-5] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -ss 2 -i "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 2.mkv" -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-10-06 13:42:24.639 [New I/O server worker #1-5] Attaching thread: ffmpeg.exe-5
DEBUG 2012-10-06 13:42:25.021 [New I/O server worker #1-6] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -ss 2 -i "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 3.mkv" -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-10-06 13:42:25.037 [New I/O server worker #1-6] Attaching thread: ffmpeg.exe-6
DEBUG 2012-10-06 13:42:26.538 [Buffered IO End Checker] Stopping process: ffmpeg.exe-4
INFO  2012-10-06 13:42:26.574 [New I/O server worker #1-7] Starting transcode/remux of No-Laughing Airport - Part 1.mkv
DEBUG 2012-10-06 13:42:26.576 [New I/O server worker #1-7] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -i "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv" -vframes 1 -vcodec copy -f h264 -vbsf h264_mp4toannexb -an -y pipe:
DEBUG 2012-10-06 13:42:26.578 [New I/O server worker #1-7] Attaching thread: ffmpeg.exe-7
DEBUG 2012-10-06 13:42:26.610 [Thread-36] ffmpeg version N-44459-g8bdba0b Copyright (c) 2000-2012 the FFmpeg developers
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   built on Sep 14 2012 18:35:56 with gcc 4.7.1 (GCC)
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   configuration: --enable-gpl --enable-version3 --disable-pthreads --enable-runtime-cpudetect --enable-avisynth --enable-bzlib --enable-frei0r --enable-libass --enable-libcelt --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libfreetype --enable-libgsm --enable-libmp3lame --enable-libnut --enable-libopenjpeg --enable-librtmp --enable-libschroedinger --enable-libspeex --enable-libtheora --enable-libutvideo --enable-libvo-aacenc --enable-libvo-amrwbenc --enable-libvorbis --enable-libvpx --enable-libx264 --enable-libxavs --enable-libxvid --enable-zlib
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   libavutil      51. 73.100 / 51. 73.100
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   libavcodec     54. 55.100 / 54. 55.100
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   libavformat    54. 27.100 / 54. 27.100
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   libavdevice    54.  2.100 / 54.  2.100
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   libavfilter     3. 16.103 /  3. 16.103
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   libswscale      2.  1.101 /  2.  1.101
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   libswresample   0. 15.100 /  0. 15.100
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   libpostproc    52.  0.100 / 52.  0.100
DEBUG 2012-10-06 13:42:26.610 [Thread-36] Input #0, matroska,webm, from 'E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv':
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   Metadata:
DEBUG 2012-10-06 13:42:26.610 [Thread-36]     creation_time   : 2012-10-03 09:04:09
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   Duration: 01:41:00.56, start: 0.000000, bitrate: 2592 kb/s
DEBUG 2012-10-06 13:42:26.610 [Thread-36]     Stream #0:0(jpn): Audio: aac, 48000 Hz, stereo, s16 (default)
DEBUG 2012-10-06 13:42:26.610 [Thread-36]     Metadata:
DEBUG 2012-10-06 13:42:26.610 [Thread-36]       title           : Japanese
DEBUG 2012-10-06 13:42:26.610 [Thread-36]     Stream #0:1: Video: h264 (High), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 29.97 fps, 29.97 tbr, 1k tbn, 59.94 tbc (default)
DEBUG 2012-10-06 13:42:26.610 [Thread-36]     Stream #0:2(eng): Subtitle: ssa (default)
DEBUG 2012-10-06 13:42:26.610 [Thread-36]     Metadata:
DEBUG 2012-10-06 13:42:26.610 [Thread-36]       title           : English
DEBUG 2012-10-06 13:42:26.610 [Thread-36] Output #0, h264, to 'pipe:':
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   Metadata:
DEBUG 2012-10-06 13:42:26.610 [Thread-36]     encoder         : Lavf54.27.100
DEBUG 2012-10-06 13:42:26.610 [Thread-36]     Stream #0:0: Video: h264, yuv420p, 1280x720 [SAR 1:1 DAR 16:9], q=2-31, 29.97 fps, 90k tbn, 29.97 tbc (default)
DEBUG 2012-10-06 13:42:26.610 [Thread-36] Stream mapping:
DEBUG 2012-10-06 13:42:26.610 [Thread-36]   Stream #0:1 -> #0:0 (copy)
DEBUG 2012-10-06 13:42:26.610 [Thread-36] Press [q] to stop, [?] for help
DEBUG 2012-10-06 13:42:26.610 [Thread-36] frame=    1 fps=0.0 q=-1.0 Lsize=      88kB time=00:00:00.00 bitrate=   0.0kbits/s    
DEBUG 2012-10-06 13:42:26.610 [Thread-36] 
DEBUG 2012-10-06 13:42:26.612 [Thread-36] video:88kB audio:0kB subtitle:0 global headers:0kB muxing overhead 0.055272%
DEBUG 2012-10-06 13:42:26.648 [New I/O server worker #1-7] Creating pipe \\.\pipe\1349494946648ffmpegvideo
DEBUG 2012-10-06 13:42:26.652 [New I/O server worker #1-7] Creating pipe \\.\pipe\1349494946648videoout
DEBUG 2012-10-06 13:42:26.652 [Thread-40] Waiting for pipe connection \\.\pipe\1349494946648ffmpegvideo
DEBUG 2012-10-06 13:42:26.652 [Thread-41] Waiting for pipe connection \\.\pipe\1349494946648videoout
DEBUG 2012-10-06 13:42:26.652 [New I/O server worker #1-7] Creating pipe \\.\pipe\1349494946652ffmpegaudio01
DEBUG 2012-10-06 13:42:26.654 [New I/O server worker #1-7] Creating pipe \\.\pipe\1349494946652audioout
DEBUG 2012-10-06 13:42:26.654 [Thread-44] Waiting for pipe connection \\.\pipe\1349494946652ffmpegaudio01
DEBUG 2012-10-06 13:42:26.654 [Thread-45] Waiting for pipe connection \\.\pipe\1349494946652audioout
DEBUG 2012-10-06 13:42:26.656 [New I/O server worker #1-7] Creating pipe \\.\pipe\1349494946656tsmuxerout.ts
DEBUG 2012-10-06 13:42:26.656 [Thread-47] Waiting for pipe connection \\.\pipe\1349494946656tsmuxerout.ts
DEBUG 2012-10-06 13:42:26.756 [mencoder.exe-8] Starting "C:\Program Files (x86)\Universal Media Server\win32\mencoder.exe" -ss 0 "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv" -quiet -quiet -really-quiet -msglevel statusline=2 -ovc copy -nosound -mc 0 -noskip -of rawvideo -o \\.\pipe\1349494946648ffmpegvideo
DEBUG 2012-10-06 13:42:26.758 [mencoder.exe-8] Attaching thread: mencoder.exe-8
DEBUG 2012-10-06 13:42:26.792 [Thread-40] Result of \\.\pipe\1349494946648ffmpegvideo : true
DEBUG 2012-10-06 13:42:26.856 [mencoder.exe-9] Starting "C:\Program Files (x86)\Universal Media Server\win32\mencoder.exe" -ss 0 "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv" -quiet -quiet -really-quiet -msglevel statusline=2 -channels 6 -ovc copy -of rawaudio -mc 0 -noskip -oac lavc -quiet -quiet -lavcopts acodec=ac3:abitrate=448 -af lavcresample=48000 -srate 48000 -quiet -quiet -o \\.\pipe\1349494946652ffmpegaudio01
DEBUG 2012-10-06 13:42:26.858 [mencoder.exe-9] Attaching thread: mencoder.exe-9
DEBUG 2012-10-06 13:42:26.893 [Thread-44] Result of \\.\pipe\1349494946652ffmpegaudio01 : true
DEBUG 2012-10-06 13:42:26.956 [tsMuxeR.exe-10] Starting "C:\Program Files (x86)\Universal Media Server\win32\tsMuxeR.exe" C:\Users\Josh\AppData\Local\Temp\ps3mediaserver\pms-tsmuxer.meta \\.\pipe\1349494946656tsmuxerout.ts
DEBUG 2012-10-06 13:42:26.958 [tsMuxeR.exe-10] Reading pipe: \\.\pipe\1349494946656tsmuxerout.ts
DEBUG 2012-10-06 13:42:26.969 [tsMuxeR.exe-10] Attaching thread: tsMuxeR.exe-10
DEBUG 2012-10-06 13:42:26.989 [Thread-58] SmartLabs tsMuxeR.  Version 1.10.6  http://www.smlabs.net
DEBUG 2012-10-06 13:42:27.190 [Thread-41] Forced reconnection of \\.\pipe\1349494946648videoout with result : true
DEBUG 2012-10-06 13:42:27.190 [Thread-45] Forced reconnection of \\.\pipe\1349494946652audioout with result : true
DEBUG 2012-10-06 13:42:27.190 [Thread-41] Result of \\.\pipe\1349494946648videoout : true
DEBUG 2012-10-06 13:42:27.190 [Thread-45] Result of \\.\pipe\1349494946652audioout : true
DEBUG 2012-10-06 13:42:27.190 [Thread-41] Disconnected pipe: \\.\pipe\1349494946648videoout
DEBUG 2012-10-06 13:42:27.191 [Thread-47] Result of \\.\pipe\1349494946656tsmuxerout.ts : true
DEBUG 2012-10-06 13:42:27.191 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:27.192 [Thread-58] Processed 0 video frames
DEBUG 2012-10-06 13:42:27.192 [Thread-58] Decoding AC3 stream (track 2): Bitrate: 448Kbps Sample Rate: 48KHz Channels: 2
DEBUG 2012-10-06 13:42:27.308 [Buffered IO End Checker] Stopping process: ffmpeg.exe-5
INFO  2012-10-06 13:42:27.458 [StartPlaying Event] renderer: 192.168.1.108, file: E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv
DEBUG 2012-10-06 13:42:27.462 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:27.705 [Buffered IO End Checker] Stopping process: ffmpeg.exe-6
DEBUG 2012-10-06 13:42:27.728 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:27.794 [Thread-40] Error: Pipe closed
DEBUG 2012-10-06 13:42:27.974 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:28.218 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:28.452 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:28.704 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:28.973 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:29.112 [Buffered IO End Checker] Stopping process: ffmpeg.exe-7
DEBUG 2012-10-06 13:42:29.248 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:29.522 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:29.787 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:30.051 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:30.321 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:30.583 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:30.846 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:31.099 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:31.342 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:31.584 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:31.830 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:32.068 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:32.318 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:32.567 [Thread-58] 100.0% complete
DEBUG 2012-10-06 13:42:32.791 [Buffered IO End Checker] Stopping process: tsMuxeR.exe-10
DEBUG 2012-10-06 13:42:32.791 [Buffered IO End Checker] Stopping process: mencoder.exe-8
DEBUG 2012-10-06 13:42:32.791 [Thread-43] Error :null
DEBUG 2012-10-06 13:42:32.792 [Thread-45] Disconnected pipe: \\.\pipe\1349494946652audioout
DEBUG 2012-10-06 13:42:32.792 [Thread-44] Error: Pipe closed
DEBUG 2012-10-06 13:42:32.792 [Buffered IO End Checker] Stopping process: mencoder.exe-9
INFO  2012-10-06 13:42:34.292 [StopPlaying Event] renderer: 192.168.1.108, file: E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv
DEBUG 2012-10-06 13:42:35.218 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-10-06 13:43:22.896 [UPNP-AliveMessageSender] Error while sending periodic alive message: sleep interrupted
INFO  2012-10-06 13:43:22.896 [PMS Listeners Stopper] Sending BYEBYE...
DEBUG 2012-10-06 13:43:26.786 [PMS Listeners Stopper] Forcing shutdown of all active processes
INFO  2012-10-06 13:43:26.786 [PMS Listeners Stopper] Stopping server on host 192.168.1.135 and port 5001...
Tried to play the same MKV as before "Data type unsupported" error. That usually comes up when trying to playing that MKV after re-installing UMS without restarting. I'll go restart my PC now but odds are it won't even start now.

EDIT: Welp, UMS did actually open this time. Maybe it has something to do with the fact that it's 1.5.1? I usually use 1.5.2 or 1.6.0.
Tyestor
Posts: 66
Joined: Fri Oct 05, 2012 6:19 pm
Location: Brisbane, Australia
Contact:

Re: MKV Slowdown.

Post by Tyestor »

I had to make another post because of the character limit.

This is the debug.log where the file actually plays, the video is about 25-30 seconds behind the sound not only that the video and sound are pretty laggy too.

Code: Select all

DEBUG 2012-10-06 14:01:58.584 [main] My PID is 5196
DEBUG 2012-10-06 14:01:58.588 [main] Java version: 1.7.0_07
DEBUG 2012-10-06 14:01:58.588 [main] Java vendor: Oracle Corporation
DEBUG 2012-10-06 14:01:58.588 [main] Java class path: update.jar;ums.jar
DEBUG 2012-10-06 14:01:58.588 [main] Operating system name: Windows 8
DEBUG 2012-10-06 14:01:58.588 [main] Operating system architecture: amd64
DEBUG 2012-10-06 14:01:58.588 [main] Operating system version: 6.2
DEBUG 2012-10-06 14:01:58.642 [main] SUN 1.7: SUN (DSA key/parameter generation; DSA signing; SHA-1, MD5 digests; SecureRandom; X.509 certificates; JKS keystore; PKIX CertPathValidator; PKIX CertPathBuilder; LDAP, Collection CertStores, JavaPolicy Policy; JavaLoginConfig Configuration)
DEBUG 2012-10-06 14:01:58.643 [main] SunRsaSign 1.7: Sun RSA signature provider
DEBUG 2012-10-06 14:01:58.643 [main] SunEC 1.7: Sun Elliptic Curve provider (EC, ECDSA, ECDH)
DEBUG 2012-10-06 14:01:58.643 [main] SunJSSE 1.7: Sun JSSE provider(PKCS12, SunX509 key/trust factories, SSLv3, TLSv1)
DEBUG 2012-10-06 14:01:58.643 [main] SunJCE 1.7: SunJCE Provider (implements RSA, DES, Triple DES, AES, Blowfish, ARCFOUR, RC2, PBE, Diffie-Hellman, HMAC)
DEBUG 2012-10-06 14:01:58.643 [main] SunJGSS 1.7: Sun (Kerberos v5, SPNEGO)
DEBUG 2012-10-06 14:01:58.643 [main] SunSASL 1.7: Sun SASL provider(implements client mechanisms for: DIGEST-MD5, GSSAPI, EXTERNAL, PLAIN, CRAM-MD5, NTLM; server mechanisms for: DIGEST-MD5, GSSAPI, CRAM-MD5, NTLM)
DEBUG 2012-10-06 14:01:58.643 [main] XMLDSig 1.0: XMLDSig (DOM XMLSignatureFactory; DOM KeyInfoFactory)
DEBUG 2012-10-06 14:01:58.643 [main] SunPCSC 1.7: Sun PC/SC provider
DEBUG 2012-10-06 14:01:58.643 [main] SunMSCAPI 1.7: Sun's Microsoft Crypto API provider
DEBUG 2012-10-06 14:01:58.645 [main] Set parameter http.useragent = Jakarta Commons-HttpClient/3.1
DEBUG 2012-10-06 14:01:58.646 [main] Set parameter http.protocol.version = HTTP/1.1
DEBUG 2012-10-06 14:01:58.646 [main] Set parameter http.connection-manager.class = class org.apache.commons.httpclient.SimpleHttpConnectionManager
DEBUG 2012-10-06 14:01:58.646 [main] Set parameter http.protocol.cookie-policy = default
DEBUG 2012-10-06 14:01:58.647 [main] Set parameter http.protocol.element-charset = US-ASCII
DEBUG 2012-10-06 14:01:58.647 [main] Set parameter http.protocol.content-charset = ISO-8859-1
DEBUG 2012-10-06 14:01:58.647 [main] Set parameter http.method.retry-handler = org.apache.commons.httpclient.DefaultHttpMethodRetryHandler@3c9c87e4
DEBUG 2012-10-06 14:01:58.647 [main] Set parameter http.dateparser.patterns = [EEE, dd MMM yyyy HH:mm:ss zzz, EEEE, dd-MMM-yy HH:mm:ss zzz, EEE MMM d HH:mm:ss yyyy, EEE, dd-MMM-yyyy HH:mm:ss z, EEE, dd-MMM-yyyy HH-mm-ss z, EEE, dd MMM yy HH:mm:ss z, EEE dd-MMM-yyyy HH:mm:ss z, EEE dd MMM yyyy HH:mm:ss z, EEE dd-MMM-yyyy HH-mm-ss z, EEE dd-MMM-yy HH:mm:ss z, EEE dd MMM yy HH:mm:ss z, EEE,dd-MMM-yy HH:mm:ss z, EEE,dd-MMM-yyyy HH:mm:ss z, EEE, dd-MM-yyyy HH:mm:ss z]
DEBUG 2012-10-06 14:01:58.786 [pool-2-thread-1] Open connection to raw.github.com:443
DEBUG 2012-10-06 14:01:59.183 [pool-2-thread-1] >> "GET /UniversalMediaServer/UniversalMediaServer/master/src/main/external-resources/update/latest_version.properties HTTP/1.1[\r][\n]"
DEBUG 2012-10-06 14:01:59.183 [pool-2-thread-1] Adding Host request header
DEBUG 2012-10-06 14:01:59.189 [pool-2-thread-1] >> "User-Agent: Jakarta Commons-HttpClient/3.1[\r][\n]"
DEBUG 2012-10-06 14:01:59.189 [pool-2-thread-1] >> "Host: raw.github.com[\r][\n]"
DEBUG 2012-10-06 14:01:59.189 [pool-2-thread-1] >> "[\r][\n]"
INFO  2012-10-06 14:01:59.264 [main] Starting Universal Media Server 1.5.1
INFO  2012-10-06 14:01:59.271 [main] Based on PS3 Media Server (ps3mediaserver.org) by shagrath, copyright 2008-2012
INFO  2012-10-06 14:01:59.274 [main] http://www.universalmediaserver.com
INFO  2012-10-06 14:01:59.275 [main] 
INFO  2012-10-06 14:01:59.275 [main] Build: 562ec8ab3 (2012-09-19)
INFO  2012-10-06 14:01:59.275 [main] Java: 1.7.0_07-Oracle Corporation
INFO  2012-10-06 14:01:59.276 [main] OS: Windows 8 64-bit 6.2
INFO  2012-10-06 14:01:59.277 [main] Encoding: UTF-8
INFO  2012-10-06 14:01:59.278 [main] Memory: 682 MB
INFO  2012-10-06 14:01:59.278 [main] 
INFO  2012-10-06 14:01:59.278 [main] Working directory: C:\Program Files (x86)\Universal Media Server
INFO  2012-10-06 14:01:59.284 [main] Temp folder: C:\Users\Josh\AppData\Local\Temp\ps3mediaserver
INFO  2012-10-06 14:01:59.286 [main] Logging config file: C:\Program Files (x86)\Universal Media Server\logback.xml
INFO  2012-10-06 14:01:59.286 [main] debug.log: C:\Program Files (x86)\Universal Media Server\debug.log
INFO  2012-10-06 14:01:59.286 [main] 
INFO  2012-10-06 14:01:59.287 [main] Profile directory: C:\ProgramData\UMS
INFO  2012-10-06 14:01:59.287 [main] Profile path: C:\ProgramData\UMS\UMS.conf
INFO  2012-10-06 14:01:59.287 [main] Profile status: rw
INFO  2012-10-06 14:01:59.288 [main] Profile name: HIGHTOWER
INFO  2012-10-06 14:01:59.289 [main] 
INFO  2012-10-06 14:01:59.293 [main] Loading MediaInfo library
INFO  2012-10-06 14:01:59.361 [main] Loaded MediaInfoLib - v0.7.60
INFO  2012-10-06 14:01:59.365 [main] Loading renderer configurations from C:\Program Files (x86)\Universal Media Server\renderers
INFO  2012-10-06 14:01:59.366 [main] Loading configuration file: AirPlayer.conf
DEBUG 2012-10-06 14:01:59.366 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/AirPlayer.conf
INFO  2012-10-06 14:01:59.369 [main] Loading configuration file: Android.conf
DEBUG 2012-10-06 14:01:59.369 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Android.conf
INFO  2012-10-06 14:01:59.370 [main] Loading configuration file: Bravia4500.conf
DEBUG 2012-10-06 14:01:59.370 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Bravia4500.conf
INFO  2012-10-06 14:01:59.372 [main] Loading configuration file: Bravia5500.conf
DEBUG 2012-10-06 14:01:59.373 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Bravia5500.conf
INFO  2012-10-06 14:01:59.375 [main] Loading configuration file: BraviaEX.conf
DEBUG 2012-10-06 14:01:59.375 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/BraviaEX.conf
INFO  2012-10-06 14:01:59.378 [main] Loading configuration file: DirecTVHR.conf
DEBUG 2012-10-06 14:01:59.379 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/DirecTVHR.conf
INFO  2012-10-06 14:01:59.382 [main] Loading configuration file: DLinkDSM510.conf
DEBUG 2012-10-06 14:01:59.382 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/DLinkDSM510.conf
INFO  2012-10-06 14:01:59.384 [main] Loading configuration file: FreeboxHD.conf
DEBUG 2012-10-06 14:01:59.385 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/FreeboxHD.conf
INFO  2012-10-06 14:01:59.386 [main] Loading configuration file: FreecomMusicPal.conf
DEBUG 2012-10-06 14:01:59.387 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/FreecomMusicPal.conf
INFO  2012-10-06 14:01:59.388 [main] Loading configuration file: iPad-iPhone.conf
DEBUG 2012-10-06 14:01:59.389 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/iPad-iPhone.conf
INFO  2012-10-06 14:01:59.391 [main] Loading configuration file: Kuro.conf
DEBUG 2012-10-06 14:01:59.392 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Kuro.conf
INFO  2012-10-06 14:01:59.394 [main] Loading configuration file: LGST600.conf
DEBUG 2012-10-06 14:01:59.394 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/LGST600.conf
INFO  2012-10-06 14:01:59.397 [main] Loading configuration file: N900.conf
DEBUG 2012-10-06 14:01:59.397 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/N900.conf
INFO  2012-10-06 14:01:59.399 [main] Loading configuration file: OPPOBDP83.conf
DEBUG 2012-10-06 14:01:59.399 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/OPPOBDP83.conf
INFO  2012-10-06 14:01:59.400 [main] Loading configuration file: OPPOBDP93.conf
DEBUG 2012-10-06 14:01:59.401 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/OPPOBDP93.conf
INFO  2012-10-06 14:01:59.402 [main] Loading configuration file: Panasonic.conf
DEBUG 2012-10-06 14:01:59.402 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Panasonic.conf
INFO  2012-10-06 14:01:59.405 [main] Loading configuration file: Philips.conf
DEBUG 2012-10-06 14:01:59.405 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Philips.conf
INFO  2012-10-06 14:01:59.406 [main] Loading configuration file: PhilipsPFL.conf
DEBUG 2012-10-06 14:01:59.407 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/PhilipsPFL.conf
INFO  2012-10-06 14:01:59.411 [main] Loading configuration file: PopcornHour.conf
DEBUG 2012-10-06 14:01:59.411 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/PopcornHour.conf
INFO  2012-10-06 14:01:59.412 [main] Loading configuration file: PS3.conf
DEBUG 2012-10-06 14:01:59.413 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/PS3.conf
INFO  2012-10-06 14:01:59.417 [main] Loading configuration file: Realtek.conf
DEBUG 2012-10-06 14:01:59.418 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Realtek.conf
INFO  2012-10-06 14:01:59.419 [main] Loading configuration file: SamsungAllShare.conf
DEBUG 2012-10-06 14:01:59.419 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/SamsungAllShare.conf
INFO  2012-10-06 14:01:59.420 [main] Loading configuration file: SamsungWiseLink.conf
DEBUG 2012-10-06 14:01:59.421 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/SamsungWiseLink.conf
INFO  2012-10-06 14:01:59.422 [main] Loading configuration file: Showtime3.conf
DEBUG 2012-10-06 14:01:59.422 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Showtime3.conf
INFO  2012-10-06 14:01:59.424 [main] Loading configuration file: SMP-N100.conf
DEBUG 2012-10-06 14:01:59.424 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/SMP-N100.conf
INFO  2012-10-06 14:01:59.426 [main] Loading configuration file: SonyBluray.conf
DEBUG 2012-10-06 14:01:59.427 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/SonyBluray.conf
INFO  2012-10-06 14:01:59.429 [main] Loading configuration file: Streamium.conf
DEBUG 2012-10-06 14:01:59.429 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/Streamium.conf
INFO  2012-10-06 14:01:59.430 [main] Loading configuration file: WDTVLive.conf
DEBUG 2012-10-06 14:01:59.430 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/WDTVLive.conf
INFO  2012-10-06 14:01:59.431 [main] Loading configuration file: WMP.conf
DEBUG 2012-10-06 14:01:59.432 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/WMP.conf
INFO  2012-10-06 14:01:59.433 [main] Loading configuration file: XBMC.conf
DEBUG 2012-10-06 14:01:59.433 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/XBMC.conf
INFO  2012-10-06 14:01:59.434 [main] Loading configuration file: XBOX360.conf
DEBUG 2012-10-06 14:01:59.434 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/XBOX360.conf
INFO  2012-10-06 14:01:59.436 [main] Loading configuration file: YamahaRXA1010.conf
DEBUG 2012-10-06 14:01:59.436 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/YamahaRXA1010.conf
INFO  2012-10-06 14:01:59.437 [main] Loading configuration file: YamahaRXV671.conf
DEBUG 2012-10-06 14:01:59.437 [main] Base path set to file:///C:/Program%20Files%20(x86)/Universal%20Media%20Server/renderers/YamahaRXV671.conf
INFO  2012-10-06 14:01:59.438 [main] Checking MPlayer font cache. It can take a minute or so.
DEBUG 2012-10-06 14:01:59.438 [main] launching: win32/mplayer.exe
DEBUG 2012-10-06 14:01:59.518 [main] launching: win32/mplayer.exe
INFO  2012-10-06 14:01:59.591 [main] Done!
INFO  2012-10-06 14:01:59.592 [main] Found AviSynth plugins dir: C:\Program Files (x86)\AviSynth\plugins
INFO  2012-10-06 14:01:59.592 [main] VSFilter.dll is not in the AviSynth plugins directory. This can cause problems when trying to play subtitled videos with AviSynth
INFO  2012-10-06 14:01:59.595 [main] Searching for plugins in C:\Program Files (x86)\Universal Media Server\plugins
INFO  2012-10-06 14:01:59.596 [main] No plugins found
INFO  2012-10-06 14:01:59.604 [main] Registering transcoding engine: FFmpeg/AviSynth
INFO  2012-10-06 14:01:59.605 [main] Registering transcoding engine: FFmpeg Audio
INFO  2012-10-06 14:01:59.608 [main] Registering transcoding engine: MEncoder
INFO  2012-10-06 14:01:59.608 [main] Registering transcoding engine: AviSynth/MEncoder
INFO  2012-10-06 14:01:59.609 [main] Registering transcoding engine: FFmpeg
INFO  2012-10-06 14:01:59.610 [main] Registering transcoding engine: MPlayer Audio
INFO  2012-10-06 14:01:59.610 [main] Registering transcoding engine: FFmpeg Web Video
INFO  2012-10-06 14:01:59.610 [main] Registering transcoding engine: MEncoder Web
INFO  2012-10-06 14:01:59.611 [main] Registering transcoding engine: MPlayer Video Dump
INFO  2012-10-06 14:01:59.611 [main] Registering transcoding engine: MPlayer Web
INFO  2012-10-06 14:01:59.612 [main] Registering transcoding engine: tsMuxeR
INFO  2012-10-06 14:01:59.612 [main] Registering transcoding engine: Audio High Fidelity
INFO  2012-10-06 14:01:59.613 [main] Executable of transcoder profile VLC Audio Streaming not found
INFO  2012-10-06 14:01:59.613 [main] Executable of transcoder profile VLC Video Streaming not found
INFO  2012-10-06 14:01:59.614 [main] Registering transcoding engine: FFmpeg DVR-MS Remux
INFO  2012-10-06 14:01:59.614 [main] Registering transcoding engine: dcraw Thumbnailer
INFO  2012-10-06 14:01:59.683 [main] Using address /192.168.1.135 found on network interface: name:eth4 (Qualcomm Atheros AR8151 PCI-E Gigabit Ethernet Controller (NDIS 6.30))
INFO  2012-10-06 14:01:59.684 [main] Created socket: /192.168.1.135:5001
DEBUG 2012-10-06 14:01:59.729 [main] Using database URL: jdbc:h2:database/medias
INFO  2012-10-06 14:01:59.729 [main] Using database located at: C:\Program Files (x86)\Universal Media Server\database
DEBUG 2012-10-06 14:01:59.889 [main] Database file count: 0
DEBUG 2012-10-06 14:01:59.889 [main] Database version: 1.5.1
INFO  2012-10-06 14:01:59.894 [main] A tiny cache admin interface is available at: http://192.168.1.135:5001/console/home
DEBUG 2012-10-06 14:01:59.938 [main] Sending ALIVE...
INFO  2012-10-06 14:01:59.955 [main] Using the following UUID configured in UMS.conf: 736ee105-0c27-4fd7-81ab-e8332eef2ea4
DEBUG 2012-10-06 14:02:00.092 [pool-2-thread-1] << "HTTP/1.1 200 OK[\r][\n]"
DEBUG 2012-10-06 14:02:00.092 [pool-2-thread-1] << "HTTP/1.1 200 OK[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Server: nginx[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Date: Sat, 06 Oct 2012 04:02:05 GMT[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Content-Type: text/plain; charset=utf-8[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Connection: keep-alive[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Status: 200 OK[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Content-Transfer-Encoding: binary[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "X-Content-Type-Options: nosniff[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Content-Disposition: inline[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "X-RateLimit-Remaining: 100[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "X-RateLimit-Limit: 100[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Content-Length: 109[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "X-Runtime: 20[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "ETag: "e18656c2cfe4f0150f866c8ce13106f7"[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "X-Frame-Options: deny[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "Cache-Control: private[\r][\n]"
DEBUG 2012-10-06 14:02:00.093 [pool-2-thread-1] << "[\r][\n]"
DEBUG 2012-10-06 14:02:00.094 [pool-2-thread-1] Buffering response body
DEBUG 2012-10-06 14:02:00.094 [pool-2-thread-1] << "LatestVersion="1.5.2"[\n]"
DEBUG 2012-10-06 14:02:00.094 [pool-2-thread-1] << "DownloadUrl.windows="http://universal-media-server.googlecode.com/files/UMS-1.5.2.exe"[\n]"
DEBUG 2012-10-06 14:02:00.095 [pool-2-thread-1] Should NOT close connection in response to directive: keep-alive
DEBUG 2012-10-06 14:02:00.095 [pool-2-thread-1] Releasing connection back to connection manager.
INFO  2012-10-06 14:02:01.861 [New I/O server worker #1-1] No IP filter specified, access granted to /192.168.1.108
INFO  2012-10-06 14:02:01.866 [pool-7-thread-1] Checking IP: 192.168.1.108 for PlayStation 3
INFO  2012-10-06 14:02:04.509 [New I/O server worker #1-1] No IP filter specified, access granted to /192.168.1.106
INFO  2012-10-06 14:02:04.512 [pool-7-thread-2] Checking IP: 192.168.1.106 for WMP
INFO  2012-10-06 14:02:04.515 [pool-7-thread-2] Renderer WMP found on this address: HIGHTOWER-TWO (192.168.1.106)
DEBUG 2012-10-06 14:02:04.519 [pool-7-thread-2] Starting ping -n 3 -l 64000 192.168.1.106
INFO  2012-10-06 14:02:04.743 [New I/O server worker #1-3] No IP filter specified, access granted to /192.168.1.117
INFO  2012-10-06 14:02:04.745 [New I/O server worker #1-3] Another renderer like WMP was found!
INFO  2012-10-06 14:02:04.746 [pool-7-thread-3] Checking IP: 192.168.1.117 for WMP
INFO  2012-10-06 14:02:04.748 [pool-7-thread-3] Renderer WMP found on this address: LARRYLAPTOP (192.168.1.117)
DEBUG 2012-10-06 14:02:04.748 [pool-7-thread-3] Starting ping -n 3 -l 64000 192.168.1.117
INFO  2012-10-06 14:02:05.292 [main] The server should now appear on your renderer
INFO  2012-10-06 14:02:06.470 [pool-7-thread-1] Renderer PlayStation 3 found on this address: 192.168.1.108
DEBUG 2012-10-06 14:02:06.470 [pool-7-thread-1] Starting ping -n 3 -l 64000 192.168.1.108
DEBUG 2012-10-06 14:02:06.520 [SpeedStats Failsafe] Stopping process: ping-0
DEBUG 2012-10-06 14:02:06.749 [SpeedStats Failsafe] Stopping process: ping-1
INFO  2012-10-06 14:02:06.751 [pool-7-thread-3] Address /192.168.1.117 has an estimated network speed of: 35 Mb/s
DEBUG 2012-10-06 14:02:08.470 [SpeedStats Failsafe] Stopping process: ping-2
DEBUG 2012-10-06 14:02:15.292 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-10-06 14:02:33.379 [New I/O server worker #1-1] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -ss 2 -i "E:\Camera Demonstration.mp4" -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-10-06 14:02:33.384 [New I/O server worker #1-1] Attaching thread: ffmpeg.exe-3
DEBUG 2012-10-06 14:02:36.004 [Buffered IO End Checker] Stopping process: ffmpeg.exe-3
DEBUG 2012-10-06 14:02:38.568 [UPNP-AliveMessageSender] Sending ALIVE...
DEBUG 2012-10-06 14:02:39.736 [New I/O server worker #1-8] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -ss 2 -i "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv" -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-10-06 14:02:39.738 [New I/O server worker #1-8] Attaching thread: ffmpeg.exe-4
DEBUG 2012-10-06 14:02:40.583 [New I/O server worker #1-5] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -ss 2 -i "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 2.mkv" -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-10-06 14:02:40.585 [New I/O server worker #1-5] Attaching thread: ffmpeg.exe-5
DEBUG 2012-10-06 14:02:41.021 [New I/O server worker #1-6] Starting "C:\Program Files (x86)\Universal Media Server\win32\ffmpeg.exe" -ss 2 -i "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 3.mkv" -an -an -s 320x180 -vframes 1 -f image2 pipe:
DEBUG 2012-10-06 14:02:41.023 [New I/O server worker #1-6] Attaching thread: ffmpeg.exe-6
INFO  2012-10-06 14:02:41.621 [New I/O server worker #1-7] Starting transcode/remux of No-Laughing Airport - Part 1.mkv
DEBUG 2012-10-06 14:02:41.665 [New I/O server worker #1-7] Creating pipe \\.\pipe\mencoder1349496161664
DEBUG 2012-10-06 14:02:41.679 [Thread-39] Waiting for pipe connection \\.\pipe\mencoder1349496161664
DEBUG 2012-10-06 14:02:41.731 [mencoder.exe-7] Starting "C:\Program Files (x86)\Universal Media Server\win32\mencoder.exe" -ss 0 "E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv" -msglevel statusline=2 -oac lavc -of mpeg -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64 -ovc lavc -channels 6 -lavdopts debug=0:threads=4 -lavcopts autoaspect=1:vcodec=mpeg2video:acodec=ac3:abitrate=448:threads=4:keyint=5:vqscale=1:vqmin=2:vrc_maxrate=54000:vrc_buf_size=7000 -ass -fontconfig -sid 0 -ofps 24000/1001 -lavdopts fast -mc 0 -noskip -af lavcresample=48000 -srate 48000 -o \\.\pipe\mencoder1349496161664
DEBUG 2012-10-06 14:02:41.733 [mencoder.exe-7] Reading pipe: \\.\pipe\mencoder1349496161664
DEBUG 2012-10-06 14:02:41.733 [mencoder.exe-7] Attaching thread: mencoder.exe-7
DEBUG 2012-10-06 14:02:41.744 [Thread-43] MEncoder SB38 (C) 2000-2012 MPlayer Team
DEBUG 2012-10-06 14:02:41.744 [Thread-43] Custom build by SubJunk, http://www.spirton.com
DEBUG 2012-10-06 14:02:41.745 [Thread-43] 
DEBUG 2012-10-06 14:02:41.747 [Thread-43] success: format: 0  data: 0x0 - 0x75159d3e
DEBUG 2012-10-06 14:02:41.747 [Thread-43] libavformat version 54.27.101 (internal)
DEBUG 2012-10-06 14:02:41.748 [Thread-43] libavformat file format detected.
DEBUG 2012-10-06 14:02:41.767 [Thread-43] [lavf] stream 0: audio (aac), -aid 0, -alang jpn, Japanese
DEBUG 2012-10-06 14:02:41.767 [Thread-43] [lavf] stream 1: video (h264), -vid 0
DEBUG 2012-10-06 14:02:41.767 [Thread-43] [lavf] stream 2: subtitle (ass), -sid 0, -slang eng, English
DEBUG 2012-10-06 14:02:41.767 [Thread-43] VIDEO:  [H264]  1280x720  0bpp  29.970 fps    0.0 kbps ( 0.0 kbyte/s)
DEBUG 2012-10-06 14:02:41.773 [Thread-43] [V] filefmt:44  fourcc:0x34363248  size:1280x720  fps:29.970  ftime:=0.0334
DEBUG 2012-10-06 14:02:41.773 [Thread-43] ==========================================================================
DEBUG 2012-10-06 14:02:41.773 [Thread-43] Opening audio decoder: [ffmpeg] FFmpeg/libavcodec audio decoders
DEBUG 2012-10-06 14:02:41.774 [Thread-43] libavcodec version 54.56.100 (internal)
DEBUG 2012-10-06 14:02:41.775 [Thread-43] AUDIO: 48000 Hz, 2 ch, s16le, 0.0 kbit/0.00% (ratio: 0->192000)
DEBUG 2012-10-06 14:02:41.775 [Thread-43] Selected audio codec: [ffaac] afm: ffmpeg (FFmpeg AAC (MPEG-2/MPEG-4 Audio))
DEBUG 2012-10-06 14:02:41.775 [Thread-43] ==========================================================================
DEBUG 2012-10-06 14:02:41.775 [Thread-39] Result of \\.\pipe\mencoder1349496161664 : true
DEBUG 2012-10-06 14:02:41.775 [Thread-43] PACKET SIZE: 2048 bytes, deltascr: 884
DEBUG 2012-10-06 14:02:41.776 [Thread-43] Opening video filter: [ass auto=1]
DEBUG 2012-10-06 14:02:41.776 [Thread-43] [ass] auto-open
DEBUG 2012-10-06 14:02:41.783 [Thread-43] ==========================================================================
DEBUG 2012-10-06 14:02:41.783 [Thread-43] Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family
DEBUG 2012-10-06 14:02:41.784 [Thread-43] Selected video codec: [ffh264] vfm: ffmpeg (FFmpeg H.264)
DEBUG 2012-10-06 14:02:41.784 [Thread-43] ==========================================================================
DEBUG 2012-10-06 14:02:41.784 [Thread-42] [ac3 @ 0119f580]channel_layout not specified
DEBUG 2012-10-06 14:02:41.785 [Thread-42] [ac3 @ 0119f580]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
DEBUG 2012-10-06 14:02:41.785 [Thread-42] Limiting audio preload to 0.4s.
DEBUG 2012-10-06 14:02:41.785 [Thread-42] Increasing audio density to 4.
DEBUG 2012-10-06 14:02:41.786 [Thread-42] Unsupported PixelFormat 61
DEBUG 2012-10-06 14:02:41.786 [Thread-42] Unsupported PixelFormat 53
DEBUG 2012-10-06 14:02:41.786 [Thread-42] Unsupported PixelFormat 81
DEBUG 2012-10-06 14:02:41.789 [Thread-43] Movie-Aspect is 1.78:1 - prescaling to correct movie aspect.
DEBUG 2012-10-06 14:02:41.789 [Thread-43] videocodec: libavcodec (1280x720 fourcc=3267706d [mpg2])
DEBUG 2012-10-06 14:02:41.789 [Thread-43] [VE_LAVC] Using constant qscale = 1.000000 (VBR).
DEBUG 2012-10-06 14:02:41.800 [Thread-43] Movie-Aspect is 1.78:1 - prescaling to correct movie aspect.
DEBUG 2012-10-06 14:02:41.831 [Thread-43] Writing header...
DEBUG 2012-10-06 14:02:41.831 [Thread-43] INITV: 0.200, 0.158, fps: 23.976
DEBUG 2012-10-06 14:02:41.831 [Thread-43] 
INFO  2012-10-06 14:02:41.832 [StartPlaying Event] renderer: 192.168.1.108, file: E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv
DEBUG 2012-10-06 14:02:42.424 [Buffered IO End Checker] Stopping process: ffmpeg.exe-4
DEBUG 2012-10-06 14:02:43.256 [Buffered IO End Checker] Stopping process: ffmpeg.exe-5
DEBUG 2012-10-06 14:02:43.697 [Buffered IO End Checker] Stopping process: ffmpeg.exe-6
DEBUG 2012-10-06 14:02:44.010 [Thread-42] [ass] fontconfig: Selected font is not the requested one: 'Verdana Negreta' != 'Polo'
DEBUG 2012-10-06 14:02:44.053 [Thread-42] [mpeg2video @ 0119f580]rc buffer underflow
DEBUG 2012-10-06 14:02:44.064 [Thread-42] [mpeg2video @ 0119f580]rc buffer underflow
DEBUG 2012-10-06 14:02:44.074 [Thread-42] [mpeg2video @ 0119f580]rc buffer underflow
DEBUG 2012-10-06 14:02:44.084 [Thread-42] [mpeg2video @ 0119f580]rc buffer underflow
DEBUG 2012-10-06 14:02:44.093 [Thread-42] [mpeg2video @ 0119f580]rc buffer underflow
DEBUG 2012-10-06 14:03:11.274 [New I/O server worker #1-3] Setting margin to 2Mb
DEBUG 2012-10-06 14:04:49.524 [Buffered IO End Checker] Stopping process: mencoder.exe-7
INFO  2012-10-06 14:04:51.023 [StopPlaying Event] renderer: 192.168.1.108, file: E:\TV Shows\Downtown no Gaki no Tsukai ya Arahende!!\Batsu Games\(2011) No-Laughing Airport\No-Laughing Airport - Part 1.mkv
DEBUG 2012-10-06 14:05:05.950 [UPNP-AliveMessageSender] Error while sending periodic alive message: sleep interrupted
INFO  2012-10-06 14:05:05.950 [PMS Listeners Stopper] Sending BYEBYE...
DEBUG 2012-10-06 14:05:08.589 [PMS Listeners Stopper] Forcing shutdown of all active processes
INFO  2012-10-06 14:05:08.589 [PMS Listeners Stopper] Stopping server on host 192.168.1.135 and port 5001...
EDIT: If it matters, my router is a Netgear WNDR3700.
Tyestor
Posts: 66
Joined: Fri Oct 05, 2012 6:19 pm
Location: Brisbane, Australia
Contact:

Re: MKV Slowdown.

Post by Tyestor »

Can anyone help? :S - Looked at the 2nd log and noticed "buffer underflow", is that the problem?
Tyestor
Posts: 66
Joined: Fri Oct 05, 2012 6:19 pm
Location: Brisbane, Australia
Contact:

Re: MKV Slowdown.

Post by Tyestor »

Anybody?
User avatar
SubJunk
Lead Developer
Posts: 3705
Joined: Sun May 27, 2012 4:12 pm

Re: MKV Slowdown.

Post by SubJunk »

Where can I get the video file from? Feel free to PM me a link
Tyestor
Posts: 66
Joined: Fri Oct 05, 2012 6:19 pm
Location: Brisbane, Australia
Contact:

Re: MKV Slowdown.

Post by Tyestor »

PM'd - I appreciate your help.
Tyestor
Posts: 66
Joined: Fri Oct 05, 2012 6:19 pm
Location: Brisbane, Australia
Contact:

Re: MKV Slowdown.

Post by Tyestor »

Any news Subjunk?
Last edited by Tyestor on Tue Oct 23, 2012 5:48 pm, edited 1 time in total.
User avatar
SubJunk
Lead Developer
Posts: 3705
Joined: Sun May 27, 2012 4:12 pm

Re: MKV Slowdown.

Post by SubJunk »

It looks like it's a bug in MKVMerge that causes it to flag the framerate as being "variable" type instead of "constant", even though the input video is a constant framerate. Setting the framerate in MKVMerge doesn't seem to change that.
You could open a bug report with them.
Post Reply