Version 3.1.2 is released

Announcements about Universal Media Server
User avatar
SubJunk
Lead Developer
Posts: 3754
Joined: Sun May 27, 2012 4:12 pm

Version 3.1.2 is released

Post by SubJunk »

General:

You can download it from the front page.

This release fixes video compatibility bugs, improves stability when using the resume feature, has minor GUI improvements and other bug fixes.


Changes since 3.1.1:
  • General:
    • Added tooltips to more options
    • Changed the appearance of tooltips
    • Removed the ability to set both FFmpeg muxing options at the same time in the GUI
    • Minor GUI improvements
    • Made the resume feature more stable
    • Fixed video compatibility bugs
    • Fixed FFmpeg on Linux systems which do not have FFmpeg installed

Upgrade Notes:

For 3.0.0-b1 and newer:
No action necessary.

For all versions below 3.0.0-b1:
It is recommended to delete your UMS.conf file prior to installing this version. Windows users can choose the "clean install" option while installing instead.
D-Kalck
Posts: 15
Joined: Wed May 22, 2013 11:02 am

Re: Version 3.1.2 is released

Post by D-Kalck »

The duration is still not fixed on PS3.
User avatar
SubJunk
Lead Developer
Posts: 3754
Joined: Sun May 27, 2012 4:12 pm

Re: Version 3.1.2 is released

Post by SubJunk »

D-Kalck wrote:The duration is still not fixed on PS3.
linky
D-Kalck
Posts: 15
Joined: Wed May 22, 2013 11:02 am

Re: Version 3.1.2 is released

Post by D-Kalck »

So here is the log :

Code: Select all

TRACE 2013-11-25 14:26:02.968 [New I/O server boss #34] Creating new pipeline
TRACE 2013-11-25 14:26:02.968 [New I/O worker #33] Opened request handler on socket /192.168.10.30:50534
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Request: HTTP/1.0 : HEAD : get/0$4$5$5$33/Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Received on socket: Accept-Encoding: identity
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Received on socket: Cache-Control: no-cache
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Received on socket: Connection: close
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Recognized media renderer: PlayStation 3
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] HTTP: get/0$4$5$5$33/Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv / 0-0
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] discover 0 refresh forced: false
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] discover 0$4 refresh forced: false
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] discover 0$4$5 refresh forced: false
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] discover 0$4$5$5 refresh forced: false
TRACE 2013-11-25 14:26:02.969 [New I/O worker #33] Asked stream chunk : TimeRange [start=null, end=null] of Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv and player MEncoder
DEBUG 2013-11-25 14:26:02.970 [New I/O worker #33] Starting transcode/remux of Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv with media info: container: mkv, bitrate: 4475246, size: 1388436070, video codec: h264, duration: 00:41:21,00, width: 1280, height: 720, frame rate: 25.000, thumb size : 8205, muxing mode: null, mime type: video/x-matroska
	Audio track id: 0, lang: fr, flavor: castle.s05e12.french.720p.web-dl.h264-mind, audio codec: AC3, sample frequency:48000, number of channels: 2, bits per sample: 16
TRACE 2013-11-25 14:26:02.970 [New I/O worker #33] Looking for an audio track with lang: fre
TRACE 2013-11-25 14:26:02.970 [New I/O worker #33] Matched audio track: id: 0, lang: fr, flavor: castle.s05e12.french.720p.web-dl.h264-mind, audio codec: AC3, sample frequency:48000, number of channels: 2, bits per sample: 16
TRACE 2013-11-25 14:26:02.970 [New I/O worker #33] Searching for a match for: fr with fr and off
TRACE 2013-11-25 14:26:02.970 [New I/O worker #33]  Disabled the subtitles: id: 0, type: UNKNOWN, flavor: null, lang: off, externalFileCharacterSet: null
DEBUG 2013-11-25 14:26:02.972 [New I/O worker #33] Starting "D:\Image & Video (x86)\Universal Media Server\win32\ffmpeg.exe" -i "F:\Castle\Saison 5\Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv" -vframes 1 -c:v copy -f h264 -bsf h264_mp4toannexb -an -y pipe:
TRACE 2013-11-25 14:26:02.974 [New I/O worker #33] Successfully initialized buffer to 1 048 576 bytes.
DEBUG 2013-11-25 14:26:02.974 [New I/O worker #33] Attaching thread: ffmpeg.exe-34
DEBUG 2013-11-25 14:26:02.996 [Thread-160] ffmpeg version N-53724-g716dbc7 Copyright (c) 2000-2013 the FFmpeg developers
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   built on May 31 2013 23:33:02 with gcc 4.8.0 (GCC)
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   configuration: --enable-gpl --enable-version3 --disable-w32threads --enable-avisynth --enable-bzlib --enable-fontconfig --enable-frei0r --enable-gnutls --enable-iconv --enable-libass --enable-libbluray --enable-libcaca --enable-libfreetype --enable-libgsm --enable-libilbc --enable-libmodplug --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-libopus --enable-librtmp --enable-libschroedinger --enable-libsoxr --enable-libspeex --enable-libtheora --enable-libtwolame --enable-libvo-aacenc --enable-libvo-amrwbenc --enable-libvorbis --enable-libvpx --enable-libx264 --enable-libxavs --enable-libxvid --enable-zlib
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   libavutil      52. 34.100 / 52. 34.100
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   libavcodec     55. 12.102 / 55. 12.102
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   libavformat    55.  8.102 / 55.  8.102
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   libavdevice    55.  2.100 / 55.  2.100
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   libavfilter     3. 73.100 /  3. 73.100
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   libswscale      2.  3.100 /  2.  3.100
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   libswresample   0. 17.102 /  0. 17.102
DEBUG 2013-11-25 14:26:02.996 [Thread-160]   libpostproc    52.  3.100 / 52.  3.100
DEBUG 2013-11-25 14:26:03.020 [Thread-160] Input #0, matroska,webm, from 'F:\Castle\Saison 5\Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv':
DEBUG 2013-11-25 14:26:03.020 [Thread-160]   Metadata:
DEBUG 2013-11-25 14:26:03.020 [Thread-160]     title           : Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND
DEBUG 2013-11-25 14:26:03.021 [Thread-160]     creation_time   : 2013-09-09 06:20:59
DEBUG 2013-11-25 14:26:03.021 [Thread-160]   Duration: 00:41:21.98, start: 0.000000, bitrate: 4475 kb/s
DEBUG 2013-11-25 14:26:03.021 [Thread-160]     Stream #0:0(fre): Video: h264 (High), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 25 fps, 25 tbr, 1k tbn, 2k tbc (default)
DEBUG 2013-11-25 14:26:03.021 [Thread-160]     Metadata:
DEBUG 2013-11-25 14:26:03.021 [Thread-160]       title           : Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND
DEBUG 2013-11-25 14:26:03.021 [Thread-160]     Stream #0:1(fre): Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s (default)
DEBUG 2013-11-25 14:26:03.021 [Thread-160]     Metadata:
DEBUG 2013-11-25 14:26:03.021 [Thread-160]       title           : Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND
DEBUG 2013-11-25 14:26:03.022 [Thread-160] Output #0, h264, to 'pipe:':
DEBUG 2013-11-25 14:26:03.022 [Thread-160]   Metadata:
DEBUG 2013-11-25 14:26:03.022 [Thread-160]     title           : Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND
DEBUG 2013-11-25 14:26:03.022 [Thread-160]     encoder         : Lavf55.8.102
DEBUG 2013-11-25 14:26:03.022 [Thread-160]     Stream #0:0(fre): Video: h264, yuv420p, 1280x720 [SAR 1:1 DAR 16:9], q=2-31, 25 fps, 90k tbn, 1k tbc (default)
DEBUG 2013-11-25 14:26:03.022 [Thread-160]     Metadata:
DEBUG 2013-11-25 14:26:03.022 [Thread-160]       title           : Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND
DEBUG 2013-11-25 14:26:03.023 [Thread-160] Stream mapping:
DEBUG 2013-11-25 14:26:03.023 [Thread-160]   Stream #0:0 -> #0:0 (copy)
DEBUG 2013-11-25 14:26:03.023 [Thread-160] Press [q] to stop, [?] for help
DEBUG 2013-11-25 14:26:03.023 [Thread-160] frame=    1 fps=0.0 q=-1.0 Lsize=       3kB time=00:00:00.-3 bitrate=N/A    
DEBUG 2013-11-25 14:26:03.023 [Thread-160] 
DEBUG 2013-11-25 14:26:03.023 [Thread-160] video:3kB audio:0kB subtitle:0 global headers:0kB muxing overhead 2.710945%
TRACE 2013-11-25 14:26:03.025 [New I/O worker #33] EOF
TRACE 2013-11-25 14:26:03.025 [New I/O worker #33] Ready to Stop: true
TRACE 2013-11-25 14:26:03.025 [New I/O worker #33] Format "mpegts" failed to match supported line f:mpegps     v:mpeg1|mpeg2|mp4|h264   a:ac3|lpcm           m:video/mpeg
TRACE 2013-11-25 14:26:03.026 [New I/O worker #33] Matched support line f:mpegts     v:mpeg1|mpeg2|mp4|h264   a:ac3|lpcm|mpa       m:video/mpeg
TRACE 2013-11-25 14:26:03.029 [New I/O worker #33] Searching for a match for: fr with fr and off
TRACE 2013-11-25 14:26:03.029 [New I/O worker #33]  Disabled the subtitles: id: 0, type: UNKNOWN, flavor: null, lang: off, externalFileCharacterSet: null
DEBUG 2013-11-25 14:26:03.030 [New I/O worker #33] Creating pipe \\.\pipe\1385385963030ffmpegvideo
DEBUG 2013-11-25 14:26:03.031 [New I/O worker #33] Creating pipe \\.\pipe\1385385963030videoout
DEBUG 2013-11-25 14:26:03.031 [Thread-164] Waiting for pipe connection \\.\pipe\1385385963030ffmpegvideo
DEBUG 2013-11-25 14:26:03.031 [Thread-165] Waiting for pipe connection \\.\pipe\1385385963030videoout
DEBUG 2013-11-25 14:26:03.031 [New I/O worker #33] Creating pipe \\.\pipe\1385385963031ffmpegaudio01
DEBUG 2013-11-25 14:26:03.031 [New I/O worker #33] Creating pipe \\.\pipe\1385385963031audioout
TRACE 2013-11-25 14:26:03.031 [New I/O worker #33] Matched support line f:mpegps     v:mpeg1|mpeg2|mp4|h264   a:ac3|lpcm           m:video/mpeg
DEBUG 2013-11-25 14:26:03.032 [Thread-169] Waiting for pipe connection \\.\pipe\1385385963031audioout
DEBUG 2013-11-25 14:26:03.032 [Thread-168] Waiting for pipe connection \\.\pipe\1385385963031ffmpegaudio01
TRACE 2013-11-25 14:26:03.032 [New I/O worker #33] Matched support line f:mpegps     v:mpeg1|mpeg2|mp4|h264   a:ac3|lpcm           m:video/mpeg
DEBUG 2013-11-25 14:26:03.032 [New I/O worker #33] Creating pipe \\.\pipe\1385385963032tsmuxerout.ts
DEBUG 2013-11-25 14:26:03.033 [Thread-171] Waiting for pipe connection \\.\pipe\1385385963032tsmuxerout.ts
DEBUG 2013-11-25 14:26:03.133 [mencoder.exe-35] Starting "D:\Image & Video (x86)\Universal Media Server\win32\mencoder.exe" -ss 0 "F:\Castle\Saison 5\Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv" -quiet -quiet -really-quiet -msglevel statusline=2 -ovc copy -nosound -mc 0 -noskip -of rawvideo -o \\.\pipe\1385385963030ffmpegvideo
TRACE 2013-11-25 14:26:03.134 [mencoder.exe-35] Successfully initialized buffer to 1 048 576 bytes.
DEBUG 2013-11-25 14:26:03.134 [mencoder.exe-35] Attaching thread: mencoder.exe-35
DEBUG 2013-11-25 14:26:03.172 [Thread-164] Result of \\.\pipe\1385385963030ffmpegvideo : true
DEBUG 2013-11-25 14:26:03.233 [mencoder.exe-36] Starting "D:\Image & Video (x86)\Universal Media Server\win32\mencoder.exe" -ss 0 "F:\Castle\Saison 5\Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv" -quiet -quiet -really-quiet -msglevel statusline=2 -channels 2 -ovc copy -of rawaudio -mc 0 -noskip -oac copy -fafmttag 0x2000 -lavcopts acodec=ac3:abitrate=448 -af lavcresample=48000 -srate 48000 -quiet -quiet -o \\.\pipe\1385385963031ffmpegaudio01
TRACE 2013-11-25 14:26:03.235 [mencoder.exe-36] Successfully initialized buffer to 1 048 576 bytes.
DEBUG 2013-11-25 14:26:03.235 [mencoder.exe-36] Attaching thread: mencoder.exe-36
DEBUG 2013-11-25 14:26:03.278 [Thread-168] Result of \\.\pipe\1385385963031ffmpegaudio01 : true
DEBUG 2013-11-25 14:26:03.333 [New I/O worker #33] External input stream instance is null... sounds not good, waiting 500ms
DEBUG 2013-11-25 14:26:03.333 [tsMuxeR-new.exe-37] Starting "D:\Image & Video (x86)\Universal Media Server\win32\tsMuxeR-new.exe" C:\Users\D-Kalck\AppData\Local\Temp\universalmediaserver\pms-tsmuxer.meta \\.\pipe\1385385963032tsmuxerout.ts
DEBUG 2013-11-25 14:26:03.334 [tsMuxeR-new.exe-37] Reading pipe: \\.\pipe\1385385963032tsmuxerout.ts
TRACE 2013-11-25 14:26:03.337 [tsMuxeR-new.exe-37] Successfully initialized buffer to 52 428 800 bytes.
DEBUG 2013-11-25 14:26:03.337 [tsMuxeR-new.exe-37] Attaching thread: tsMuxeR-new.exe-37
TRACE 2013-11-25 14:26:03.337 [Timer-37] buffered: 0 bytes / inputs: 0
DEBUG 2013-11-25 14:26:03.374 [Thread-182] Network Optix tsMuxeR.  Version 2.2.3(b). www.networkoptix.com
DEBUG 2013-11-25 14:26:03.575 [Thread-169] Forced reconnection of \\.\pipe\1385385963031audioout with result : true
DEBUG 2013-11-25 14:26:03.575 [Thread-165] Forced reconnection of \\.\pipe\1385385963030videoout with result : true
DEBUG 2013-11-25 14:26:03.575 [Thread-169] Result of \\.\pipe\1385385963031audioout : true
DEBUG 2013-11-25 14:26:03.575 [Thread-165] Result of \\.\pipe\1385385963030videoout : true
DEBUG 2013-11-25 14:26:03.576 [Thread-182] Decoding H264 stream (track 1): Profile: [email protected]  Resolution: 1280:720p  Frame rate: not found
DEBUG 2013-11-25 14:26:03.576 [Thread-182] Change H264 level from 3.1 to 4.1
DEBUG 2013-11-25 14:26:03.576 [Thread-182] H.264 stream doesn't contain fps field. Muxing fps=25
DEBUG 2013-11-25 14:26:03.579 [Thread-171] Result of \\.\pipe\1385385963032tsmuxerout.ts : true
DEBUG 2013-11-25 14:26:03.580 [Thread-182] 100.0% complete
DEBUG 2013-11-25 14:26:03.580 [Thread-182] H264 bitstream changed: insert nal unit delimiters
DEBUG 2013-11-25 14:26:03.580 [Thread-182] Decoding AC3 stream (track 2): Bitrate: 192Kbps Sample Rate: 48KHz Channels: 2
TRACE 2013-11-25 14:26:03.833 [New I/O worker #33] Matched support line f:mpegps     v:mpeg1|mpeg2|mp4|h264   a:ac3|lpcm           m:video/mpeg
TRACE 2013-11-25 14:26:03.833 [New I/O worker #33] Ready to Stop: true
INFO  2013-11-25 14:26:03.833 [StartPlaying Event] Started playing Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv on your PlayStation 3
DEBUG 2013-11-25 14:26:03.833 [StartPlaying Event] The full filename of which is: F:\Castle\Saison 5\Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv and the address of the renderer is: 192.168.10.30
TRACE 2013-11-25 14:26:03.833 [New I/O worker #33] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-25 14:26:03.834 [New I/O worker #33] Sent to socket: Connection: keep-alive
TRACE 2013-11-25 14:26:03.834 [New I/O worker #33] Sent to socket: Content-Type: video/mpeg
TRACE 2013-11-25 14:26:03.834 [New I/O worker #33] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.2
TRACE 2013-11-25 14:26:03.837 [New I/O server boss #34] Creating new pipeline
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Opened request handler on socket /192.168.10.30:50533
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Request: HTTP/1.0 : GET : get/0$4$5$5$33/Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Received on socket: Accept-Encoding: identity
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Received on socket: Cache-Control: no-cache
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Received on socket: Connection: close
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Recognized media renderer: PlayStation 3
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] HTTP: get/0$4$5$5$33/Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv / 0-0
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] discover 0 refresh forced: false
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] discover 0$4 refresh forced: false
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] discover 0$4$5 refresh forced: false
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] discover 0$4$5$5 refresh forced: false
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Asked stream chunk : TimeRange [start=null, end=null] of Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv and player MEncoder
TRACE 2013-11-25 14:26:03.838 [New I/O worker #18] Ready to Stop: false
TRACE 2013-11-25 14:26:03.839 [New I/O worker #18] Matched support line f:mpegps     v:mpeg1|mpeg2|mp4|h264   a:ac3|lpcm           m:video/mpeg
TRACE 2013-11-25 14:26:03.839 [New I/O worker #18] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-25 14:26:03.839 [New I/O worker #18] Sent to socket: Connection: keep-alive
TRACE 2013-11-25 14:26:03.839 [New I/O worker #18] Sent to socket: Content-Type: video/mpeg
TRACE 2013-11-25 14:26:03.839 [New I/O worker #18] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.2
TRACE 2013-11-25 14:26:03.843 [New I/O worker #18] Ready to Stop: true
TRACE 2013-11-25 14:26:03.845 [New I/O server boss #34] Creating new pipeline
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Opened request handler on socket /192.168.10.30:50532
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Request: HTTP/1.1 : GET : get/0$4$5$5$33/Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Received on socket: Accept-Encoding: identity
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Received on socket: Connection: Keep-Alive
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Received on socket: TimeSeekRange.dlna.org: npt=0.000-
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Received on socket: transferMode.dlna.org: Streaming
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment"; mn="PLAYSTATION 3"; mv="1.0";
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] Recognized media renderer: PlayStation 3
TRACE 2013-11-25 14:26:03.845 [New I/O worker #19] HTTP: get/0$4$5$5$33/Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv / 0-0
TRACE 2013-11-25 14:26:03.846 [New I/O worker #19] discover 0 refresh forced: false
TRACE 2013-11-25 14:26:03.846 [New I/O worker #19] discover 0$4 refresh forced: false
TRACE 2013-11-25 14:26:03.846 [New I/O worker #19] discover 0$4$5 refresh forced: false
TRACE 2013-11-25 14:26:03.846 [New I/O worker #19] discover 0$4$5$5 refresh forced: false
TRACE 2013-11-25 14:26:03.846 [New I/O worker #19] Asked stream chunk : TimeRange [start=0.0, end=null] of Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv and player MEncoder
TRACE 2013-11-25 14:26:03.846 [New I/O worker #19] Ready to Stop: false
TRACE 2013-11-25 14:26:03.846 [New I/O worker #19] Matched support line f:mpegps     v:mpeg1|mpeg2|mp4|h264   a:ac3|lpcm           m:video/mpeg
TRACE 2013-11-25 14:26:03.847 [New I/O worker #19] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-25 14:26:03.847 [New I/O worker #19] Sent to socket: Connection: keep-alive
TRACE 2013-11-25 14:26:03.847 [New I/O worker #19] Sent to socket: Content-Type: video/mpeg
TRACE 2013-11-25 14:26:03.847 [New I/O worker #19] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.2
TRACE 2013-11-25 14:26:03.847 [New I/O worker #19] Sent to socket: TimeSeekRange.dlna.org: npt=00:00:00.00-00:41:21,00/00:41:21,00
TRACE 2013-11-25 14:26:03.847 [New I/O worker #19] Sent to socket: TransferMode.DLNA.ORG: Streaming
TRACE 2013-11-25 14:26:03.847 [New I/O worker #19] Sent to socket: X-Seek-Range: npt=00:00:00.00-00:41:21,00/00:41:21,00
DEBUG 2013-11-25 14:26:03.858 [Thread-182] 100.0% complete
TRACE 2013-11-25 14:26:04.101 [Thread-180] Successfully grown buffer from 52 428 800 bytes to 104 857 600 bytes.
DEBUG 2013-11-25 14:26:04.109 [Thread-182] 100.0% complete
DEBUG 2013-11-25 14:26:04.360 [Thread-182] 100.0% complete
TRACE 2013-11-25 14:26:05.337 [Timer-37] buffered: 85 254 815 bytes / inputs: 1
DEBUG 2013-11-25 14:26:05.526 [Buffered IO End Checker] Stopping process: ffmpeg.exe-34
TRACE 2013-11-25 14:26:05.526 [Buffered IO End Checker] Destroying buffer
TRACE 2013-11-25 14:26:05.741 [New I/O worker #19] Ready to Stop: true
TRACE 2013-11-25 14:26:05.742 [New I/O server boss #34] Creating new pipeline
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Opened request handler on socket /192.168.10.30:50531
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Request: HTTP/1.1 : GET : get/0$4$5$5$33/Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Matched media renderer "PlayStation 3" based on address /192.168.10.30
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Received on socket: Accept-Encoding: identity
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Received on socket: Connection: Keep-Alive
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Received on socket: Host: 192.168.10.10:5001
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Received on socket: TimeSeekRange.dlna.org: npt=0.000-
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Received on socket: transferMode.dlna.org: Streaming
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Received on socket: User-Agent: PLAYSTATION 3
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment"; mn="PLAYSTATION 3"; mv="1.0";
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] Recognized media renderer: PlayStation 3
TRACE 2013-11-25 14:26:05.742 [New I/O worker #20] HTTP: get/0$4$5$5$33/Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv / 0-0
TRACE 2013-11-25 14:26:05.743 [New I/O worker #20] discover 0 refresh forced: false
TRACE 2013-11-25 14:26:05.743 [New I/O worker #20] discover 0$4 refresh forced: false
TRACE 2013-11-25 14:26:05.743 [New I/O worker #20] discover 0$4$5 refresh forced: false
TRACE 2013-11-25 14:26:05.743 [New I/O worker #20] discover 0$4$5$5 refresh forced: false
TRACE 2013-11-25 14:26:05.743 [New I/O worker #20] Asked stream chunk : TimeRange [start=0.0, end=null] of Castle.S05E12.FRENCH.720p.WEB-DL.H264-MiND.mkv and player MEncoder
TRACE 2013-11-25 14:26:05.743 [New I/O worker #20] Ready to Stop: false
TRACE 2013-11-25 14:26:05.743 [New I/O worker #20] Matched support line f:mpegps     v:mpeg1|mpeg2|mp4|h264   a:ac3|lpcm           m:video/mpeg
TRACE 2013-11-25 14:26:05.744 [New I/O worker #20] Sent to socket: Accept-Ranges: bytes
TRACE 2013-11-25 14:26:05.744 [New I/O worker #20] Sent to socket: Connection: keep-alive
TRACE 2013-11-25 14:26:05.744 [New I/O worker #20] Sent to socket: Content-Type: video/mpeg
TRACE 2013-11-25 14:26:05.744 [New I/O worker #20] Sent to socket: Server: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.2
TRACE 2013-11-25 14:26:05.744 [New I/O worker #20] Sent to socket: TimeSeekRange.dlna.org: npt=00:00:00.00-00:41:21,00/00:41:21,00
TRACE 2013-11-25 14:26:05.744 [New I/O worker #20] Sent to socket: TransferMode.DLNA.ORG: Streaming
TRACE 2013-11-25 14:26:05.744 [New I/O worker #20] Sent to socket: X-Seek-Range: npt=00:00:00.00-00:41:21,00/00:41:21,00
DEBUG 2013-11-25 14:26:05.867 [Thread-182] 100.0% complete
DEBUG 2013-11-25 14:26:06.375 [Thread-182] 100.0% complete
TRACE 2013-11-25 14:26:07.337 [Timer-37] buffered: 84 545 855 bytes / inputs: 1
DEBUG 2013-11-25 14:26:07.885 [Thread-182] 100.0% complete
TRACE 2013-11-25 14:26:09.337 [Timer-37] buffered: 84 866 495 bytes / inputs: 1
DEBUG 2013-11-25 14:26:09.889 [Thread-182] 100.0% complete
TRACE 2013-11-25 14:26:11.337 [Timer-37] buffered: 84 581 215 bytes / inputs: 1
TRACE 2013-11-25 14:26:13.337 [Timer-37] buffered: 84 619 839 bytes / inputs: 1
DEBUG 2013-11-25 14:26:13.389 [Thread-182] 100.0% complete
TRACE 2013-11-25 14:26:13.503 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-25 14:26:13.546 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-25 14:26:13.546 [UPNPHelper] Sending this reply [192.168.10.10:52100]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Mon, 25 Nov 2013 13:26:13 GMT<CRLF>LOCATION: http://192.168.10.10:5001/description/fetch<CRLF>SERVER: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.2<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-25 14:26:13.587 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-25 14:26:13.627 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-25 14:26:13.667 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-25 14:26:13.667 [UPNPHelper] Sending this reply [192.168.10.10:52100]: HTTP/1.1 200 OK<CRLF>CACHE-CONTROL: max-age=1200<CRLF>DATE: Mon, 25 Nov 2013 13:26:13 GMT<CRLF>LOCATION: http://192.168.10.10:5001/description/fetch<CRLF>SERVER: Windows_7-amd64-6.1, UPnP/1.0, UMS/3.1.2<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-25 14:26:13.707 [UPNPHelper] Receiving a M-SEARCH from [192.168.10.10:52100]
TRACE 2013-11-25 14:26:15.338 [Timer-37] buffered: 84 809 119 bytes / inputs: 1
DEBUG 2013-11-25 14:26:16.390 [Thread-182] 100.0% complete
TRACE 2013-11-25 14:26:17.338 [Timer-37] buffered: 84 839 263 bytes / inputs: 1
TRACE 2013-11-25 14:26:19.338 [Timer-37] buffered: 85 156 127 bytes / inputs: 1
DEBUG 2013-11-25 14:26:20.890 [Thread-182] 100.0% complete
TRACE 2013-11-25 14:26:21.338 [Timer-37] buffered: 84 755 007 bytes / inputs: 1
TRACE 2013-11-25 14:26:23.338 [Timer-37] buffered: 84 629 791 bytes / inputs: 1
Wolfgan
Posts: 370
Joined: Fri Feb 08, 2013 12:33 am

Re: Version 3.1.2 is released

Post by Wolfgan »

Thanks for the new release. Will test and report back. Wolf
-- UMS serving PS3, WDTV, Samsung H6400 + J5500 and Kalemsoft renderers! (no video transcoding but remuxing accepted :D )
ExSport
Posts: 595
Joined: Wed Oct 10, 2012 1:57 am

Re: Version 3.1.2 is released

Post by ExSport »

D-Kalck wrote:The duration is still not fixed on PS3.
Root cause is:
Sent to socket: X-Seek-Range: npt=00:00:00.00-00:41:21,00/00:41:21,00
There should be no ","
I supposed v3.1.2 fixed it but supposedly not 8-)
User avatar
SubJunk
Lead Developer
Posts: 3754
Joined: Sun May 27, 2012 4:12 pm

Re: Version 3.1.2 is released

Post by SubJunk »

Does this one fix it?
D-Kalck
Posts: 15
Joined: Wed May 22, 2013 11:02 am

Re: Version 3.1.2 is released

Post by D-Kalck »

Nope, and here is the log :

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)
The line ExSport talked about is in the same format.

EDIT : it's fixed in 3.1.2.1 ! Thanks a lot !
User avatar
SubJunk
Lead Developer
Posts: 3754
Joined: Sun May 27, 2012 4:12 pm

Re: Version 3.1.2 is released

Post by SubJunk »

Thanks for confirming the fix :)
Samonosuke
Posts: 6
Joined: Wed Nov 27, 2013 10:02 am

Re: Version 3.1.2 is released

Post by Samonosuke »

The "vfolders" configuration does not seem to be working.
It worked pretty fine in 2.x versions but since 3.x it does not work anymore. I already looked at the code and can't find any change in the definition of a "vfolders.conf" - except of tumbnails.
Here is my virtual folder definition in json format - can somebody tell me what is wrong?

Code: Select all

[
	{
	"name":"HD-Movies",
	"thumbnailIcon":"",
	"files":["G:\\HD-Movies_2","I:\\HD_Movies","K:\\HD_Movies","L:\\Filme","M:\\"]
	},
	{
	"name":"Movies",
	"thumbnailIcon":"",
	"files":["I:\\MOVIES","K:\\Movies","G:\\Movies_2"]
	}
]
Post Reply