Code: Select all
TRACE 2013-11-26 08:38:18.732 [New I/O server boss #17] Creating new pipeline
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Opened request handler on socket /192.168.10.30:50132
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Request: HTTP/1.0 : HEAD : get/0$9$1$23$4/Very+Bad+Trip+3.m2ts
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Received on socket: Accept-Encoding: identity
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Received on socket: Cache-Control: no-cache
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Received on socket: Connection: close
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Recognized media renderer: PlayStation 3
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] HTTP: get/0$9$1$23$4/Very+Bad+Trip+3.m2ts / 0-0
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] discover 0 refresh forced: false
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] discover 0$9 refresh forced: false
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] discover 0$9$1 refresh forced: false
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] discover 0$9$1$23 refresh forced: false
TRACE 2013-11-26 08:38:18.733 [New I/O worker #1] Asked stream chunk : TimeRange [start=null, end=null] of Very Bad Trip 3.m2ts and player MEncoder
DEBUG 2013-11-26 08:38:18.734 [New I/O worker #1] Starting transcode/remux of Very Bad Trip 3.m2ts with media info: container: mkv, bitrate: 11347150, size: 8523162003, video codec: h264, duration: 01:40:09,00, width: 1920, height: 800, frame rate: 23.976, thumb size : 11135, muxing mode: null, mime type: video/x-matroska
Audio track id: 0, lang: fr, flavor: french ac3 640, audio codec: AC3, sample frequency:48000, number of channels: 6, bits per sample: 16, artist: , album: , song name: , year: 0, track: 0
Audio track id: 1, lang: en, flavor: english dts 1509, audio codec: DTS, sample frequency:48000, number of channels: 6, bits per sample: 24, artist: , album: , song name: , year: 0, track: 0
Subtitle track id: 0, type: SUBRIP, flavor: french_forced, lang: fr, externalFileCharacterSet: null
Subtitle track id: 1, type: SUBRIP, flavor: french, lang: fr, externalFileCharacterSet: null
TRACE 2013-11-26 08:38:18.734 [New I/O worker #1] Looking for an audio track with lang: fre
TRACE 2013-11-26 08:38:18.734 [New I/O worker #1] Matched audio track: id: 0, lang: fr, flavor: french ac3 640, audio codec: AC3, sample frequency:48000, number of channels: 6, bits per sample: 16, artist: , album: , song name: , year: 0, track: 0
TRACE 2013-11-26 08:38:18.734 [New I/O worker #1] Searching for a match for: fr with fr and off
TRACE 2013-11-26 08:38:18.734 [New I/O worker #1] Disabled the subtitles: id: 0, type: UNKNOWN, flavor: null, lang: off, externalFileCharacterSet: null
TRACE 2013-11-26 08:38:18.737 [New I/O worker #1] Forcing preferred subtitles : fr/french_forced
TRACE 2013-11-26 08:38:18.737 [New I/O worker #1] Forced subtitles track : id: 0, type: SUBRIP, flavor: french_forced, lang: fr, externalFileCharacterSet: null
TRACE 2013-11-26 08:38:18.737 [New I/O worker #1] channels=6
DEBUG 2013-11-26 08:38:18.743 [New I/O worker #1] Creating pipe \\.\pipe\mencoder1385451498743
TRACE 2013-11-26 08:38:18.760 [New I/O worker #1] Successfully initialized buffer to 52 428 800 bytes.
TRACE 2013-11-26 08:38:18.761 [Timer-30] buffered: 0 bytes / inputs: 0
DEBUG 2013-11-26 08:38:18.761 [mencoder.exe-30] Starting "D:\Image & Video (x86)\Universal Media Server\win32\mencoder.exe" -ss 0 "G:\The.Hangover.Part.III.2013.MULTi.TRUEFRENCH.1080p.BluRay.x264-LOST .mkv" -msglevel statusline=2 -oac copy -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=640:threads=8:keyint=25:vqscale=1:vqmin=2:vqmax=3:vrc_maxrate=499000:vrc_buf_size=7000 -ass -ass-color ffffff00 -ass-border-color 00000000 -ass-font-scale 1.4 -font C:\Windows\Fonts\Arial.ttf -ass-force-style FontName=C:\Windows\Fonts\Arial.ttf,Outline=1,Shadow=1,MarginV=10 -fontconfig -aid 0 -noautosub -sid 0 -ofps 24000/1001 -lavdopts fast -mc 0 -noskip -o \\.\pipe\mencoder1385451498743
DEBUG 2013-11-26 08:38:18.761 [Thread-137] Waiting for pipe connection \\.\pipe\mencoder1385451498743
TRACE 2013-11-26 08:38:18.761 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57355]
TRACE 2013-11-26 08:38:18.762 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
DEBUG 2013-11-26 08:38:18.764 [mencoder.exe-30] Reading pipe: \\.\pipe\mencoder1385451498743
DEBUG 2013-11-26 08:38:18.764 [mencoder.exe-30] Attaching thread: mencoder.exe-30
DEBUG 2013-11-26 08:38:18.779 [Thread-141] MEncoder SB53 (C) 2000-2013 MPlayer Team
DEBUG 2013-11-26 08:38:18.779 [Thread-141] Custom build by SubJunk, http://www.spirton.com
DEBUG 2013-11-26 08:38:18.779 [Thread-141]
TRACE 2013-11-26 08:38:18.781 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57356]
TRACE 2013-11-26 08:38:18.782 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
DEBUG 2013-11-26 08:38:18.782 [Thread-141] success: format: 0 data: 0x0 - 0xfc052193
DEBUG 2013-11-26 08:38:18.782 [Thread-141] libavformat version 55.21.100 (internal)
DEBUG 2013-11-26 08:38:18.785 [Thread-141] libavformat file format detected.
DEBUG 2013-11-26 08:38:18.792 [Thread-141] [lavf] stream 0: video (h264), -vid 0
DEBUG 2013-11-26 08:38:18.792 [Thread-141] [lavf] stream 1: audio (ac3), -aid 0, -alang fre, French AC3 640
DEBUG 2013-11-26 08:38:18.792 [Thread-141] [lavf] stream 2: audio (dca), -aid 1, -alang eng, English DTS 1509
DEBUG 2013-11-26 08:38:18.792 [Thread-141] [lavf] stream 3: subtitle (subrip), -sid 0, -slang fre, French_forced
DEBUG 2013-11-26 08:38:18.792 [Thread-141] [lavf] stream 4: subtitle (subrip), -sid 1, -slang fre, French
DEBUG 2013-11-26 08:38:18.792 [Thread-141] VIDEO: [H264] 1920x800 0bpp 23.976 fps 0.0 kbps ( 0.0 kbyte/s)
DEBUG 2013-11-26 08:38:18.792 [Thread-141] [V] filefmt:44 fourcc:0x34363248 size:1920x800 fps:23.976 ftime:=0.0417
DEBUG 2013-11-26 08:38:18.792 [Thread-137] Result of \\.\pipe\mencoder1385451498743 : true
DEBUG 2013-11-26 08:38:18.793 [Thread-141] PACKET SIZE: 2048 bytes, deltascr: 884
DEBUG 2013-11-26 08:38:18.793 [Thread-141] libavcodec version 55.41.100 (internal)
DEBUG 2013-11-26 08:38:18.793 [Thread-141] Opening video filter: [ass auto=1]
DEBUG 2013-11-26 08:38:18.793 [Thread-141] [ass] auto-open
TRACE 2013-11-26 08:38:18.802 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57357]
TRACE 2013-11-26 08:38:18.803 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
DEBUG 2013-11-26 08:38:18.810 [Thread-141] ==========================================================================
DEBUG 2013-11-26 08:38:18.810 [Thread-141] Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family
DEBUG 2013-11-26 08:38:18.811 [Thread-141] Selected video codec: [ffh264] vfm: ffmpeg (FFmpeg H.264)
DEBUG 2013-11-26 08:38:18.811 [Thread-141] ==========================================================================
DEBUG 2013-11-26 08:38:18.812 [Thread-141] audiocodec: framecopy (format=2000 chans=6 rate=48000 bits=0 B/s=80000 sample-0)
DEBUG 2013-11-26 08:38:18.812 [Thread-140] Limiting audio preload to 0.4s.
DEBUG 2013-11-26 08:38:18.812 [Thread-140] Increasing audio density to 4.
TRACE 2013-11-26 08:38:18.822 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57358]
TRACE 2013-11-26 08:38:18.823 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
DEBUG 2013-11-26 08:38:18.825 [Thread-141] Movie-Aspect is undefined - no prescaling applied.
DEBUG 2013-11-26 08:38:18.828 [Thread-141] videocodec: libavcodec (1920x800 fourcc=3267706d [mpg2])
DEBUG 2013-11-26 08:38:18.828 [Thread-141] [VE_LAVC] Using constant qscale = 1.000000 (VBR).
TRACE 2013-11-26 08:38:18.842 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57359]
TRACE 2013-11-26 08:38:18.843 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
DEBUG 2013-11-26 08:38:18.849 [Thread-141] Writing header...
DEBUG 2013-11-26 08:38:18.849 [Thread-141] INITV: 0.200, 0.158, fps: 23.976
DEBUG 2013-11-26 08:38:18.849 [Thread-141]
INFO 2013-11-26 08:38:18.861 [StartPlaying Event] Started playing Very Bad Trip 3.m2ts on your PlayStation 3
DEBUG 2013-11-26 08:38:18.862 [StartPlaying Event] The full filename of which is: G:\The.Hangover.Part.III.2013.MULTi.TRUEFRENCH.1080p.BluRay.x264-LOST .mkv and the address of the renderer is: 192.168.10.30
TRACE 2013-11-26 08:38:18.863 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57360]
TRACE 2013-11-26 08:38:18.864 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:18.885 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57361]
TRACE 2013-11-26 08:38:18.886 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:18.908 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57362]
TRACE 2013-11-26 08:38:18.910 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:18.930 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57363]
TRACE 2013-11-26 08:38:18.932 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:18.945 [New I/O worker #1] Matched support line f:mpegps v:mpeg1|mpeg2|mp4|h264 a:ac3|lpcm m:video/mpeg
TRACE 2013-11-26 08:38:18.946 [New I/O worker #1] Ready to Stop: true
TRACE 2013-11-26 08:38:18.946 [New I/O worker #1] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-26 08:38:18.946 [New I/O worker #1] Sent to socket: Connection: keep-alive
TRACE 2013-11-26 08:38:18.946 [New I/O worker #1] Sent to socket: Content-Type: video/mpeg
TRACE 2013-11-26 08:38:18.946 [New I/O worker #1] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.3
TRACE 2013-11-26 08:38:18.950 [New I/O server boss #17] Creating new pipeline
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Opened request handler on socket /192.168.10.30:50131
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Request: HTTP/1.0 : GET : get/0$9$1$23$4/Very+Bad+Trip+3.m2ts
TRACE 2013-11-26 08:38:18.951 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57364]
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Received on socket: Accept-Encoding: identity
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Received on socket: Cache-Control: no-cache
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Received on socket: Connection: close
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Recognized media renderer: PlayStation 3
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] HTTP: get/0$9$1$23$4/Very+Bad+Trip+3.m2ts / 0-0
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] discover 0 refresh forced: false
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] discover 0$9 refresh forced: false
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] discover 0$9$1 refresh forced: false
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] discover 0$9$1$23 refresh forced: false
TRACE 2013-11-26 08:38:18.951 [New I/O worker #2] Asked stream chunk : TimeRange [start=null, end=null] of Very Bad Trip 3.m2ts and player MEncoder
TRACE 2013-11-26 08:38:18.952 [New I/O worker #2] Ready to Stop: false
TRACE 2013-11-26 08:38:18.952 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:18.972 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57365]
TRACE 2013-11-26 08:38:18.974 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:18.993 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57366]
TRACE 2013-11-26 08:38:18.994 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.015 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57367]
TRACE 2013-11-26 08:38:19.016 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.036 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57368]
TRACE 2013-11-26 08:38:19.037 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.057 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57369]
TRACE 2013-11-26 08:38:19.059 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.075 [New I/O worker #2] Matched support line f:mpegps v:mpeg1|mpeg2|mp4|h264 a:ac3|lpcm m:video/mpeg
TRACE 2013-11-26 08:38:19.076 [New I/O worker #2] Suspend Read: readCount=0 / writeCount=155648
TRACE 2013-11-26 08:38:19.079 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57370]
TRACE 2013-11-26 08:38:19.080 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.082 [New I/O server boss #17] Creating new pipeline
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Opened request handler on socket /192.168.10.30:50130
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Request: HTTP/1.1 : GET : get/0$9$1$23$4/Very+Bad+Trip+3.m2ts
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Received on socket: Accept-Encoding: identity
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Received on socket: Connection: Keep-Alive
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Received on socket: TimeSeekRange.dlna.org: npt=0.000-
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Received on socket: transferMode.dlna.org: Streaming
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-26 08:38:19.082 [New I/O worker #3] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment"; mn="PLAYSTATION 3"; mv="1.0";
TRACE 2013-11-26 08:38:19.083 [New I/O worker #3] Recognized media renderer: PlayStation 3
TRACE 2013-11-26 08:38:19.083 [New I/O worker #3] HTTP: get/0$9$1$23$4/Very+Bad+Trip+3.m2ts / 0-0
TRACE 2013-11-26 08:38:19.083 [New I/O worker #3] discover 0 refresh forced: false
TRACE 2013-11-26 08:38:19.083 [New I/O worker #3] discover 0$9 refresh forced: false
TRACE 2013-11-26 08:38:19.083 [New I/O worker #3] discover 0$9$1 refresh forced: false
TRACE 2013-11-26 08:38:19.083 [New I/O worker #3] discover 0$9$1$23 refresh forced: false
TRACE 2013-11-26 08:38:19.083 [New I/O worker #3] Asked stream chunk : TimeRange [start=0.0, end=null] of Very Bad Trip 3.m2ts and player MEncoder
TRACE 2013-11-26 08:38:19.100 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57371]
TRACE 2013-11-26 08:38:19.102 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.121 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57372]
TRACE 2013-11-26 08:38:19.123 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.143 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57373]
TRACE 2013-11-26 08:38:19.144 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.154 [New I/O worker #3] Matched support line f:mpegps v:mpeg1|mpeg2|mp4|h264 a:ac3|lpcm m:video/mpeg
TRACE 2013-11-26 08:38:19.155 [New I/O worker #3] Suspend Read: readCount=0 / writeCount=155648
TRACE 2013-11-26 08:38:19.164 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57374]
TRACE 2013-11-26 08:38:19.165 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.185 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57375]
TRACE 2013-11-26 08:38:19.186 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.207 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57376]
TRACE 2013-11-26 08:38:19.208 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.228 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57377]
TRACE 2013-11-26 08:38:19.228 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.249 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57378]
TRACE 2013-11-26 08:38:19.250 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.270 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57379]
TRACE 2013-11-26 08:38:19.271 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.291 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57380]
TRACE 2013-11-26 08:38:19.293 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.312 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57381]
TRACE 2013-11-26 08:38:19.313 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.333 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57382]
TRACE 2013-11-26 08:38:19.335 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.355 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57383]
TRACE 2013-11-26 08:38:19.356 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.376 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57384]
TRACE 2013-11-26 08:38:19.378 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:19.576 [New I/O worker #2] Resume Read: readCount=0 / writeCount=1523712
TRACE 2013-11-26 08:38:19.576 [New I/O worker #2] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-26 08:38:19.576 [New I/O worker #2] Sent to socket: Connection: keep-alive
TRACE 2013-11-26 08:38:19.576 [New I/O worker #2] Sent to socket: Content-Type: video/mpeg
TRACE 2013-11-26 08:38:19.576 [New I/O worker #2] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.3
TRACE 2013-11-26 08:38:19.577 [New I/O worker #2] Ready to Stop: true
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Ready to Stop: false
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Resume Read: readCount=0 / writeCount=1523712
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Sent to socket: Connection: keep-alive
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Sent to socket: Content-Type: video/mpeg
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.3
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Sent to socket: TimeSeekRange.dlna.org: npt=00:00:00.00-01:40:09,00/01:40:09,00
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Sent to socket: TransferMode.DLNA.ORG: Streaming
TRACE 2013-11-26 08:38:19.655 [New I/O worker #3] Sent to socket: X-Seek-Range: npt=00:00:00.00-01:40:09,00/01:40:09,00
TRACE 2013-11-26 08:38:19.676 [New I/O worker #3] Suspend Read: readCount=524288 / writeCount=1523712
TRACE 2013-11-26 08:38:20.176 [New I/O worker #3] Resume Read: readCount=524288 / writeCount=4292608
TRACE 2013-11-26 08:38:20.761 [Timer-30] buffered: 6 993 919 bytes / inputs: 1
TRACE 2013-11-26 08:38:22.024 [New I/O worker #3] Ready to Stop: true
TRACE 2013-11-26 08:38:22.025 [New I/O server boss #17] Creating new pipeline
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Opened request handler on socket /192.168.10.30:50126
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Request: HTTP/1.1 : GET : get/0$9$1$23$4/Very+Bad+Trip+3.m2ts
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Received on socket: Accept-Encoding: identity
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Received on socket: Connection: Keep-Alive
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Received on socket: TimeSeekRange.dlna.org: npt=0.000-
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Received on socket: transferMode.dlna.org: Streaming
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment"; mn="PLAYSTATION 3"; mv="1.0";
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Recognized media renderer: PlayStation 3
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] HTTP: get/0$9$1$23$4/Very+Bad+Trip+3.m2ts / 0-0
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] discover 0 refresh forced: false
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] discover 0$9 refresh forced: false
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] discover 0$9$1 refresh forced: false
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] discover 0$9$1$23 refresh forced: false
TRACE 2013-11-26 08:38:22.025 [New I/O worker #4] Asked stream chunk : TimeRange [start=0.0, end=null] of Very Bad Trip 3.m2ts and player MEncoder
TRACE 2013-11-26 08:38:22.026 [New I/O worker #4] Ready to Stop: false
TRACE 2013-11-26 08:38:22.108 [New I/O worker #4] Matched support line f:mpegps v:mpeg1|mpeg2|mp4|h264 a:ac3|lpcm m:video/mpeg
TRACE 2013-11-26 08:38:22.109 [New I/O worker #4] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-26 08:38:22.109 [New I/O worker #4] Sent to socket: Connection: keep-alive
TRACE 2013-11-26 08:38:22.109 [New I/O worker #4] Sent to socket: Content-Type: video/mpeg
TRACE 2013-11-26 08:38:22.109 [New I/O worker #4] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.3
TRACE 2013-11-26 08:38:22.109 [New I/O worker #4] Sent to socket: TimeSeekRange.dlna.org: npt=00:00:00.00-01:40:09,00/01:40:09,00
TRACE 2013-11-26 08:38:22.109 [New I/O worker #4] Sent to socket: TransferMode.DLNA.ORG: Streaming
TRACE 2013-11-26 08:38:22.109 [New I/O worker #4] Sent to socket: X-Seek-Range: npt=00:00:00.00-01:40:09,00/01:40:09,00
TRACE 2013-11-26 08:38:22.876 [Timer-30] buffered: 11 243 519 bytes / inputs: 1
DEBUG 2013-11-26 08:38:24.159 [UPNP-AliveMessageSender] Sending ALIVE...
TRACE 2013-11-26 08:38:24.207 [UPNP-AliveMessageSender] Sending message from multicast socket on network interface: name:null
TRACE 2013-11-26 08:38:24.208 [UPNP-AliveMessageSender] Multicast socket is on interface: /0.0.0.0
TRACE 2013-11-26 08:38:24.208 [UPNP-AliveMessageSender] Socket Timeout: 0
TRACE 2013-11-26 08:38:24.208 [UPNP-AliveMessageSender] Socket TTL: 32
TRACE 2013-11-26 08:38:24.208 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57387]
TRACE 2013-11-26 08:38:24.208 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57387]
TRACE 2013-11-26 08:38:24.208 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57387]
TRACE 2013-11-26 08:38:24.208 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57387]
TRACE 2013-11-26 08:38:24.208 [UPNPHelper] Receiving a NOTIFY from [192.168.10.10:57387]
TRACE 2013-11-26 08:38:24.209 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:24.211 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:24.211 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:24.211 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:24.212 [UPNPHelper] Receiving a NOTIFY from [192.168.27.14:43997]
TRACE 2013-11-26 08:38:24.213 [New I/O server boss #17] Creating new pipeline
TRACE 2013-11-26 08:38:24.876 [Timer-30] buffered: 18 989 055 bytes / inputs: 1
TRACE 2013-11-26 08:38:25.411 [New I/O server boss #17] Creating new pipeline
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] Opened request handler on socket /192.168.10.30:50125
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] Request: HTTP/1.1 : GET : description/fetch
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] Received on socket: Date: Tue, 26 Nov 2013 07:38:29 GMT
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] Received on socket: User-Agent: UPnP/1.0
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment Inc."; mn="PLAYSTATION 3"; mv="1.0";
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] Recognized media renderer: PlayStation 3
TRACE 2013-11-26 08:38:25.412 [New I/O worker #6] HTTP: description/fetch / 0-0
TRACE 2013-11-26 08:38:25.413 [New I/O worker #6] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-26 08:38:25.413 [New I/O worker #6] Sent to socket: Cache-Control: no-cache
TRACE 2013-11-26 08:38:25.413 [New I/O worker #6] Sent to socket: Connection: keep-alive
TRACE 2013-11-26 08:38:25.413 [New I/O worker #6] Sent to socket: Content-Length: 2957
TRACE 2013-11-26 08:38:25.413 [New I/O worker #6] Sent to socket: Content-Type: text/xml; charset="utf-8"
TRACE 2013-11-26 08:38:25.413 [New I/O worker #6] Sent to socket: Expires: 0
TRACE 2013-11-26 08:38:25.413 [New I/O worker #6] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.3
TRACE 2013-11-26 08:38:26.878 [Timer-30] buffered: 26 613 759 bytes / inputs: 1
TRACE 2013-11-26 08:38:28.010 [Thread-137] Successfully grown buffer from 52 428 800 bytes to 209 715 200 bytes.
TRACE 2013-11-26 08:38:28.878 [Timer-30] buffered: 36 169 727 bytes / inputs: 1
TRACE 2013-11-26 08:38:29.055 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-26 08:38:29.096 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-26 08:38:29.096 [UPNPHelper] Sending this reply [192.168.10.10:52100]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Tue, 26 Nov 2013 07:38:29 GMT<CRLF>LOCATION: http://192.168.10.10:5001/description/fetch<CRLF>SERVER: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.3<CRLF>ST: urn:schemas-upnp-org:device:MediaServer:1<CRLF>EXT: <CRLF>USN: uuid:cf939cd0-d1e8-47a1-8ed9-223ec5ba8dae::urn:schemas-upnp-org:device:MediaServer:1<CRLF>Content-Length: 0<CRLF><CRLF>
TRACE 2013-11-26 08:38:29.137 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-26 08:38:29.181 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-26 08:38:29.222 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-26 08:38:29.222 [UPNPHelper] Sending this reply [192.168.10.10:52100]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Tue, 26 Nov 2013 07:38:29 GMT<CRLF>LOCATION: http://192.168.10.10:5001/description/fetch<CRLF>SERVER: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.3<CRLF>ST: urn:schemas-upnp-org:device:MediaServer:1<CRLF>EXT: <CRLF>USN: uuid:cf939cd0-d1e8-47a1-8ed9-223ec5ba8dae::urn:schemas-upnp-org:device:MediaServer:1<CRLF>Content-Length: 0<CRLF><CRLF>
TRACE 2013-11-26 08:38:29.264 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-26 08:38:30.878 [Timer-30] buffered: 36 732 927 bytes / inputs: 1
TRACE 2013-11-26 08:38:31.515 [New I/O worker #4] Ready to Stop: true
TRACE 2013-11-26 08:38:32.148 [New I/O server boss #17] Creating new pipeline
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Opened request handler on socket /192.168.10.30:50124
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Request: HTTP/1.1 : GET : get/0$9$1$23$4/thumbnail0000Very+Bad+Trip+3.m2ts
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Received on socket: Accept-Encoding: identity
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Received on socket: Connection: Keep-Alive
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Received on socket: transferMode.dlna.org: Interactive
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment"; mn="PLAYSTATION 3"; mv="1.0";
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] Recognized media renderer: PlayStation 3
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] HTTP: get/0$9$1$23$4/thumbnail0000Very+Bad+Trip+3.m2ts / 0-0
TRACE 2013-11-26 08:38:32.149 [New I/O worker #7] discover 0 refresh forced: false
TRACE 2013-11-26 08:38:32.150 [New I/O worker #7] discover 0$9 refresh forced: false
TRACE 2013-11-26 08:38:32.150 [New I/O worker #7] discover 0$9$1 refresh forced: false
TRACE 2013-11-26 08:38:32.150 [New I/O worker #7] discover 0$9$1$23 refresh forced: false
INFO 2013-11-26 08:38:32.150 [New I/O worker #7] <XBMCLog> - Thumb : The.Hangover.Part.III.2013.MULTi.TRUEFRENCH.1080p.BluRay.x264-LOST -poster.jpg
TRACE 2013-11-26 08:38:32.170 [New I/O worker #7] Available Content-Length: 15227
TRACE 2013-11-26 08:38:32.171 [New I/O worker #7] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-26 08:38:32.171 [New I/O worker #7] Sent to socket: Connection: keep-alive
TRACE 2013-11-26 08:38:32.171 [New I/O worker #7] Sent to socket: Content-Length: 15227
TRACE 2013-11-26 08:38:32.171 [New I/O worker #7] Sent to socket: Content-Type: image/jpeg
TRACE 2013-11-26 08:38:32.171 [New I/O worker #7] Sent to socket: Expires: Sat, 22 Mar 2014 01:25:12 GMT
TRACE 2013-11-26 08:38:32.171 [New I/O worker #7] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.3
TRACE 2013-11-26 08:38:32.171 [New I/O worker #7] Sent to socket: TransferMode.DLNA.ORG: Interactive
TRACE 2013-11-26 08:38:32.885 [Timer-30] buffered: 69 597 184 bytes / inputs: 0
DEBUG 2013-11-26 08:38:34.016 [Buffered IO End Checker] Stopping process: mencoder.exe-30
TRACE 2013-11-26 08:38:34.016 [Buffered IO End Checker] Destroying buffer
TRACE 2013-11-26 08:38:34.018 [Thread-137] EOF
TRACE 2013-11-26 08:38:34.030 [mencoder.exe-30] EOF
INFO 2013-11-26 08:38:35.517 [StopPlaying Event] Stopped playing Very Bad Trip 3.m2ts on your PlayStation 3
DEBUG 2013-11-26 08:38:35.518 [StopPlaying Event] The full filename of which is: G:\The.Hangover.Part.III.2013.MULTi.TRUEFRENCH.1080p.BluRay.x264-LOST .mkv and the address of the renderer is: 192.168.10.30
DEBUG 2013-11-26 08:38:35.518 [StopPlaying Event] add MapFile [name=Very Bad Trip 3.m2ts, id=null, format=MKV, children=[]] to last played [id=23, name=V, full path=0$9$1$23, ext=null, discovered=true] RecentlyPlayed [id=1, name=Recently Played, full path=0$1, ext=null, discovered=false]
INFO 2013-11-26 08:38:35.518 [StopPlaying Event] Exception in thread "StopPlaying Event" java.lang.NullPointerException
INFO 2013-11-26 08:38:35.518 [StopPlaying Event] at net.pms.dlna.RecentlyPlayed.add(RecentlyPlayed.java:107)
INFO 2013-11-26 08:38:35.519 [StopPlaying Event] at net.pms.dlna.RootFolder.stopPlaying(RootFolder.java:1324)
INFO 2013-11-26 08:38:35.519 [StopPlaying Event] at net.pms.dlna.DLNAResource.internalStop(DLNAResource.java:3183)
INFO 2013-11-26 08:38:35.519 [StopPlaying Event] at net.pms.dlna.DLNAResource.access$400(DLNAResource.java:68)
INFO 2013-11-26 08:38:35.519 [StopPlaying Event] at net.pms.dlna.DLNAResource$2$1.run(DLNAResource.java:2194)
INFO 2013-11-26 08:38:35.520 [StopPlaying Event] at java.lang.Thread.run(Unknown Source)