WDTV Live transcoding hangs after few minutes (SOLVED)

[ditlew]

Hi,

*UPDATE* This is solved in 1.11.372+

This is my setup:
P4 3Ghz, 2 GB, XP, PMS 1.11.366 (Tested on 1.11.350 and 1.10.51 also), direct connection 100mbit ethernet through WGR614 v6 Netgear Router

The device I am streaming to is a WD TV Live: 1.01.11

The problem:
When I try to play a title on a dvd iso, memcoder will stop encoding after 3-5 minutes (it cpu usage will go to 0%) and as a result the video will hang a little later. The time that passes before this happens is related to how large I allow the 'Transcode buffer' maximum size to be. The larger the size the longer it will be before memcoder goes zombie.

I've found one bypass that works. If I pauses the video before it hangs and waits till the original memcoder process terminates and then unpauses the video a new memcoder process will start and this one will play the rest of the title with no problems!

I'm not sure if it is related but if I stop the movie after the point where memcoder has stopped encoding and says yes to restart the movie at the point I stopped it, memcoder will start briefly and then die leaving the player waitning for a movie stream.

I've attached the debug.log as zipped.

I'm using the following render .conf for WDTV Live, where I've tried different settings as you can see, with no luck:

Code: Select all

# ps3mediaserver renderer profile for wdtv live
# Refer to PS3.conf for help

RendererName=WD TV Live
RendererIcon=unknown.png
UserAgentSearch=INTEL_NMPR
Video=true
Audio=true
Image=true
SeekByTime=false
#SeekByTime=true
TranscodeVideo=MPEGAC3
#TranscodeVideo=MPEGTSAC3
TranscodeAudio=PCM
DefaultVBVBufSize=false
#DefaultVBVBufSize=true
MuxH264ToMpegTS=false
MuxDTSToMpeg=true
WrapDTSIntoPCM=false
MuxLPCMToMpeg=false
MaxVideoBitrateMbps=0
MaxVideoWidth=0
MaxVideoHeight=0
TranscodedVideoFileSize=0
#TranscodedVideoFileSize=-1
#TranscodedVideoFileSize=100000000000
#TranscodeFastStart=true
#TranscodeFastStart=false
TranscodeExtensions=flv,flac,fla
StreamExtensions=avi,mpg,mkv,hdmov,hdm,dts,ogg,asf,asx,m2v


[ditlew]

Additional info:

Plays fine (no transcoding)

Code: Select all

[New I/O server worker #2-1] DEBUG 09:56:27.694 Opened handler on socket /10.0.0.19:43192 // WD TV Live
[New I/O server worker #2-1] DEBUG 09:56:27.694 Handler infos: HTTP/1.0 : HEAD : get/0$1$23/TEST.720p.MPEG2.mpg
[New I/O server worker #2-1] DEBUG 09:56:27.694 Received on socket: getcontentFeatures.dlna.org: 1
[New I/O server worker #2-1] DEBUG 09:56:27.694 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #2-1] DEBUG 09:56:27.694 Received on socket: transferMode.dlna.org: Streaming
[New I/O server worker #2-1] DEBUG 09:56:27.694 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #2-1] INFO  09:56:27.694 HTTP: get/0$1$23/TEST.720p.MPEG2.mpg / 0-0
[New I/O server worker #2-1] DEBUG 09:56:27.694 Searching for objectId: 0$1$23 with children option: false
[New I/O server worker #2-1] DEBUG 09:56:27.694 Asked stream chunk [0-0] timeseek: 0.0 of TEST.720p.MPEG2.mpg and player null
[New I/O server worker #2-1] DEBUG 09:56:27.694 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #2-1] DEBUG 09:56:27.694 Sent to socket: Connection: keep-alive
[New I/O server worker #2-1] DEBUG 09:56:27.694 Sent to socket: Content-Length: 10469583700
[New I/O server worker #2-1] DEBUG 09:56:27.694 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #2-1] DEBUG 09:56:27.694 Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=MPEG_TS_SD_EU;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
[New I/O server worker #2-1] DEBUG 09:56:27.694 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.20
[New I/O server worker #2-1] DEBUG 09:56:27.694 Sent to socket: TransferMode.DLNA.ORG: Streaming
[New I/O server worker #2-2] DEBUG 09:56:27.710 Opened handler on socket /10.0.0.19:43193 // WD TV Live
[New I/O server worker #2-2] DEBUG 09:56:27.710 Handler infos: HTTP/1.0 : GET : get/0$1$23/TEST.720p.MPEG2.mpg
[New I/O server worker #2-2] DEBUG 09:56:27.710 Received on socket: Content-Length: 0
[New I/O server worker #2-2] DEBUG 09:56:27.710 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #2-2] DEBUG 09:56:27.710 Received on socket: transferMode.dlna.org: Streaming
[New I/O server worker #2-2] DEBUG 09:56:27.710 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #2-2] INFO  09:56:27.710 HTTP: get/0$1$23/TEST.720p.MPEG2.mpg / 0-0
[New I/O server worker #2-2] DEBUG 09:56:27.710 Searching for objectId: 0$1$23 with children option: false
[New I/O server worker #2-2] DEBUG 09:56:27.710 Asked stream chunk [0-0] timeseek: 0.0 of TEST.720p.MPEG2.mpg and player null
[New I/O server worker #2-2] DEBUG 09:56:27.741 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #2-2] DEBUG 09:56:27.741 Sent to socket: Connection: keep-alive
[New I/O server worker #2-2] DEBUG 09:56:27.741 Sent to socket: Content-Length: 10469583700
[New I/O server worker #2-2] DEBUG 09:56:27.741 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #2-2] DEBUG 09:56:27.741 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.20
[New I/O server worker #2-2] DEBUG 09:56:27.741 Sent to socket: TransferMode.DLNA.ORG: Streaming


Stops after filled buffer (transcoded dvd title)

Code: Select all

[New I/O server worker #1-2] DEBUG 23:39:40.663 Opened handler on socket /10.0.0.19:50240 // WD TV Live
[New I/O server worker #1-2] DEBUG 23:39:40.663 Handler infos: HTTP/1.0 : HEAD : get/0$0$0$0/Title+1
[New I/O server worker #1-2] DEBUG 23:39:40.663 Received on socket: getcontentFeatures.dlna.org: 1
[New I/O server worker #1-2] DEBUG 23:39:40.663 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-2] DEBUG 23:39:40.663 Received on socket: transferMode.dlna.org: Streaming
[New I/O server worker #1-2] DEBUG 23:39:40.663 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-2] INFO  23:39:40.663 HTTP: get/0$0$0$0/Title+1 / 0-0
[New I/O server worker #1-2] DEBUG 23:39:40.663 Searching for objectId: 0$0$0$0 with children option: false
[New I/O server worker #1-2] DEBUG 23:39:40.663 Asked stream chunk [0-0] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-2] DEBUG 23:39:47.362 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #1-2] DEBUG 23:39:47.362 Sent to socket: Connection: keep-alive
[New I/O server worker #1-2] DEBUG 23:39:47.362 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #1-2] DEBUG 23:39:47.362 Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
[New I/O server worker #1-2] DEBUG 23:39:47.362 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.20
[New I/O server worker #1-2] DEBUG 23:39:47.362 Sent to socket: TransferMode.DLNA.ORG: Streaming
[New I/O server worker #1-1] DEBUG 23:39:47.378 Opened handler on socket /10.0.0.19:50241 // WD TV Live
[New I/O server worker #1-1] DEBUG 23:39:47.378 Handler infos: HTTP/1.0 : GET : get/0$0$0$0/Title+1
[New I/O server worker #1-1] DEBUG 23:39:47.378 Received on socket: getcontentFeatures.dlna.org: 1
[New I/O server worker #1-1] DEBUG 23:39:47.378 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-1] DEBUG 23:39:47.378 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-1] INFO  23:39:47.378 HTTP: get/0$0$0$0/Title+1 / 0-0
[New I/O server worker #1-1] DEBUG 23:39:47.378 Searching for objectId: 0$0$0$0 with children option: false
[New I/O server worker #1-1] DEBUG 23:39:47.378 Asked stream chunk [0-0] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-2] DEBUG 23:39:47.393 Opened handler on socket /10.0.0.19:50242 // WD TV Live
[New I/O server worker #1-2] DEBUG 23:39:47.393 Handler infos: HTTP/1.0 : GET : get/0$0$0$0/Title+1
[New I/O server worker #1-2] DEBUG 23:39:47.393 Received on socket: Content-Length: 0
[New I/O server worker #1-2] DEBUG 23:39:47.393 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-2] DEBUG 23:39:47.393 Received on socket: transferMode.dlna.org: Streaming


[ditlew]

Have check both the upnp syntax and the transcoded stream now. They are both valid. The problem is that buffer just files up and there for goes into suspend mode. For some reason it never decreases.

Transcoded via PS3 - OK

Code: Select all

[Timer-0] DEBUG 22:20:44.696 Buffered Space: 26718207 bytes / inputs: 1
[Timer-0] DEBUG 22:20:46.710 Buffered Space: 31430655 bytes / inputs: 1
[Thread-31] DEBUG 22:20:47.679 freeMemory: 2837968
[Thread-31] DEBUG 22:20:47.679 totalMemory: 58613760
[Thread-31] DEBUG 22:20:47.679 maxMemory: 799145984
[Thread-31] DEBUG 22:20:47.679 Extending buffer to 104857600
[Thread-31] DEBUG 22:20:48.225 Done extending
[Timer-0] DEBUG 22:20:48.725 Buffered Space: 35964927 bytes / inputs: 1
[Timer-0] DEBUG 22:20:50.739 Buffered Space: 40513535 bytes / inputs: 1
[Timer-0] DEBUG 22:20:52.753 Buffered Space: 44333055 bytes / inputs: 1
[Timer-0] DEBUG 22:20:54.768 Buffered Space: 50104319 bytes / inputs: 1
[Timer-0] DEBUG 22:20:56.782 Buffered Space: 55166975 bytes / inputs: 1
[Timer-0] DEBUG 22:20:58.796 Buffered Space: 60651519 bytes / inputs: 1
[Timer-0] DEBUG 22:21:00.811 Buffered Space: 65273855 bytes / inputs: 1
[Timer-0] DEBUG 22:21:02.825 Buffered Space: 71475199 bytes / inputs: 1
[Timer-0] DEBUG 22:21:04.839 Buffered Space: 75612159 bytes / inputs: 1
[New I/O server worker #1-2] INFO  22:21:05.948 Setting margin to 2Mb
[Timer-0] DEBUG 22:21:06.854 Buffered Space: 78082047 bytes / inputs: 1
[Timer-0] DEBUG 22:21:08.868 Buffered Space: 82565119 bytes / inputs: 1
[Timer-0] DEBUG 22:21:10.882 Buffered Space: 87433215 bytes / inputs: 1
[Timer-0] DEBUG 22:21:12.897 Buffered Space: 92508159 bytes / inputs: 1
[Timer-0] DEBUG 22:21:14.911 Buffered Space: 97224703 bytes / inputs: 1
[Timer-0] DEBUG 22:21:16.925 Buffered Space: 102864895 bytes / inputs: 1
[Timer-0] DEBUG 22:21:18.940 Buffered Space: 102871039 bytes / inputs: 1
[Timer-0] DEBUG 22:21:20.954 Buffered Space: 102795263 bytes / inputs: 1
[Timer-0] DEBUG 22:21:22.968 Buffered Space: 101810175 bytes / inputs: 1
[Timer-0] DEBUG 22:21:24.983 Buffered Space: 102860799 bytes / inputs: 1
[Timer-0] DEBUG 22:21:26.997 Buffered Space: 102049791 bytes / inputs: 1
[Timer-0] DEBUG 22:21:29.011 Buffered Space: 101818367 bytes / inputs: 1
[Timer-0] DEBUG 22:21:31.026 Buffered Space: 102017023 bytes / inputs: 1
[Timer-0] DEBUG 22:21:33.040 Buffered Space: 102492159 bytes / inputs: 1
[Timer-0] DEBUG 22:21:35.054 Buffered Space: 101836799 bytes / inputs: 1
[Timer-0] DEBUG 22:21:37.069 Buffered Space: 102473727 bytes / inputs: 1
[Timer-0] DEBUG 22:21:39.083 Buffered Space: 102391807 bytes / inputs: 1
[Timer-0] DEBUG 22:21:41.097 Buffered Space: 102868991 bytes / inputs: 1
[Timer-0] DEBUG 22:21:43.112 Buffered Space: 101799935 bytes / inputs: 1
[Timer-0] DEBUG 22:21:45.126 Buffered Space: 102860799 bytes / inputs: 1
[Timer-0] DEBUG 22:21:47.140 Buffered Space: 102862847 bytes / inputs: 1
[Timer-0] DEBUG 22:21:49.155 Buffered Space: 102805503 bytes / inputs: 1
[Timer-0] DEBUG 22:21:51.169 Buffered Space: 102860799 bytes / inputs: 1
[Timer-0] DEBUG 22:21:53.183 Buffered Space: 102860799 bytes / inputs: 1
[Timer-0] DEBUG 22:21:55.198 Buffered Space: 102858751 bytes / inputs: 1
[Timer-0] DEBUG 22:21:57.212 Buffered Space: 102004735 bytes / inputs: 1
[Timer-0] DEBUG 22:21:59.226 Buffered Space: 102336511 bytes / inputs: 1
[Timer-0] DEBUG 22:22:01.241 Buffered Space: 102877183 bytes / inputs: 1
[Timer-0] DEBUG 22:22:03.255 Buffered Space: 101842943 bytes / inputs: 1
[Timer-0] DEBUG 22:22:05.269 Buffered Space: 101941247 bytes / inputs: 1
[Timer-0] DEBUG 22:22:07.284 Buffered Space: 102866943 bytes / inputs: 1
[Timer-0] DEBUG 22:22:09.298 Buffered Space: 102490111 bytes / inputs: 1

Transcoded via WDTV Live - Buffer gets suspend

Code: Select all

[Timer-0] DEBUG 22:37:27.714 Buffered Space: 28643327 bytes / inputs: 1
[Timer-0] DEBUG 22:37:29.728 Buffered Space: 31297535 bytes / inputs: 1
[Timer-0] DEBUG 22:37:31.742 Buffered Space: 38866943 bytes / inputs: 2
[Thread-31] DEBUG 22:37:31.820 freeMemory: 3405080
[Thread-31] DEBUG 22:37:31.820 totalMemory: 58425344
[Thread-31] DEBUG 22:37:31.820 maxMemory: 799145984
[Thread-31] DEBUG 22:37:31.820 Extending buffer to 104857600
[Thread-31] DEBUG 22:37:32.461 Done extending
[Timer-0] DEBUG 22:37:33.757 Buffered Space: 45674495 bytes / inputs: 2
[Timer-0] DEBUG 22:37:35.771 Buffered Space: 53596159 bytes / inputs: 2
[Timer-0] DEBUG 22:37:37.785 Buffered Space: 61876223 bytes / inputs: 2
[Timer-0] DEBUG 22:37:39.799 Buffered Space: 70574079 bytes / inputs: 2
[Timer-0] DEBUG 22:37:41.813 Buffered Space: 79767551 bytes / inputs: 2
[Timer-0] DEBUG 22:37:43.827 Buffered Space: 84963327 bytes / inputs: 2
[Timer-0] DEBUG 22:37:45.842 Buffered Space: 84963327 bytes / inputs: 2
[Timer-0] DEBUG 22:37:47.856 Buffered Space: 84963327 bytes / inputs: 2
[Timer-0] DEBUG 22:37:49.870 Buffered Space: 84963327 bytes / inputs: 2
[Timer-0] DEBUG 22:37:51.884 Buffered Space: 84963327 bytes / inputs: 2
[Timer-0] DEBUG 22:37:53.898 Buffered Space: 84963327 bytes / inputs: 2
[New I/O server worker #1-1] INFO  22:37:55.210 Setting margin to 2Mb
[Timer-0] DEBUG 22:37:55.912 Buffered Space: 87859199 bytes / inputs: 2
[Timer-0] DEBUG 22:37:57.927 Buffered Space: 96622591 bytes / inputs: 2
[Timer-0] DEBUG 22:37:59.941 Buffered Space: 102858751 bytes / inputs: 2
[Timer-0] DEBUG 22:38:01.955 Buffered Space: 102858751 bytes / inputs: 2
[Timer-0] DEBUG 22:38:03.969 Buffered Space: 102858751 bytes / inputs: 2
[Timer-0] DEBUG 22:38:05.983 Buffered Space: 102858751 bytes / inputs: 2
[Timer-0] DEBUG 22:38:07.997 Buffered Space: 102858751 bytes / inputs: 2
[Timer-0] DEBUG 22:38:10.012 Buffered Space: 102858751 bytes / inputs: 2
[Timer-0] DEBUG 22:38:12.026 Buffered Space: 102858751 bytes / inputs: 2
[Timer-0] DEBUG 22:38:14.040 Buffered Space: 102858751 bytes / inputs: 2
[Timer-0] DEBUG 22:38:16.054 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:18.068 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:20.082 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:22.097 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:24.111 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:26.125 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:28.139 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:30.153 Buffered Space: 103325983 bytes / inputs: 2
[New I/O server worker #1-1] DEBUG 22:38:30.809 Suspend Read: readCount=113147904 / writeCount=114147616
[Timer-0] DEBUG 22:38:32.167 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:34.182 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:36.196 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:38.210 Buffered Space: 103325983 bytes / inputs: 2
[New I/O server worker #1-1] DEBUG 22:38:38.304 Resume Read: readCount=113147904 / writeCount=114147616
[New I/O server worker #1-1] DEBUG 22:38:38.304 Suspend Read: readCount=113147905 / writeCount=114147616
[Timer-0] DEBUG 22:38:40.224 Buffered Space: 103325983 bytes / inputs: 2
[Timer-0] DEBUG 22:38:42.238 Buffered Space: 103325983 bytes / inputs: 2

[shagrath]

Interesting case

"inputs: 2" is not good at all, that means there's currently 2 client connections hooked into the server's buffer and locking it up. According to the log, a first request is made by the receiver, then a second one, definitive (10 seconds later), but unlike the ps3, the first connection is *not* closed, leading to a confused situation on the server

You can try to add this hidden property in PMS.conf:

transcode_block_multiple_connections=true

Hope you will report back on this one :p

[ditlew]

Hi shagrath,

Yep

Did a quick morning test, the result was no video at all at the client side. Here you have the debug output relating to the request:

Code: Select all

[New I/O server worker #1-1] DEBUG 07:02:36.741 Opened handler on socket /10.0.0.19:41875 // WD TV Live
[New I/O server worker #1-1] DEBUG 07:02:36.741 Handler infos: HTTP/1.0 : HEAD : get/0$0$0$0/Title+1
[New I/O server worker #1-1] DEBUG 07:02:36.741 Received on socket: getcontentFeatures.dlna.org: 1
[New I/O server worker #1-1] DEBUG 07:02:36.741 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-1] DEBUG 07:02:36.741 Received on socket: transferMode.dlna.org: Streaming
[New I/O server worker #1-1] DEBUG 07:02:36.741 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-1] INFO  07:02:36.741 HTTP: get/0$0$0$0/Title+1 / 0-0
[New I/O server worker #1-1] DEBUG 07:02:36.741 Searching for objectI
d: 0$0$0$0 with children option: false
[New I/O server worker #1-1] DEBUG 07:02:36.741 Asked stream chunk [0-0] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-1] TRACE 07:02:36.741 Starting transcode/remux of Title 1
[New I/O server worker #1-1] DEBUG 07:02:36.741 Searching an audio track with lang: dan
[New I/O server worker #1-1] DEBUG 07:02:36.741 Searching an audio track with lang: eng
[New I/O server worker #1-1] DEBUG 07:02:36.741 Searching an audio track with lang: fre
[New I/O server worker #1-1] DEBUG 07:02:36.741 Searching an audio track with lang: jpn
[New I/O server worker #1-1] DEBUG 07:02:36.741 Searching an audio track with lang: ger
[New I/O server worker #1-1] DEBUG 07:02:36.741 Choosed a default audio track: Audio: AC3 / lang: unknown / ID: 128
[New I/O server worker #1-1] DEBUG 07:02:36.741 Found subtitles track : Sub: Embedded / lang: fi / ID: 0 / FILE: -
[New I/O server worker #1-1] DEBUG 07:02:36.741 Found subtitles track : Sub: Embedded / lang: sv / ID: 1 / FILE: -
[New I/O server worker #1-1] DEBUG 07:02:36.741 Found subtitles track : Sub: Embedded / lang: no / ID: 2 / FILE: -
[New I/O server worker #1-1] DEBUG 07:02:36.741 Found subtitles track : Sub: Embedded / lang: da / ID: 3 / FILE: -
[New I/O server worker #1-1] DEBUG 07:02:36.741 Searching a subtitle track with lang: dan
[New I/O server worker #1-1] DEBUG 07:02:36.741 Matched sub track : Sub: Embedded / lang: da / ID: 3 / FILE: -
[New I/O server worker #1-1] INFO  07:02:36.757 Creating pipe \\.\pipe\mencoder1259820156757

[Timer-2] DEBUG 07:02:37.022 Buffered Space: 0 bytes / inputs: 0
[Thread-45] INFO  07:02:37.022 Waiting for pipe connection \\.\pipe\mencoder1259820156757
[win32/mencoder.exe] DEBUG 07:02:37.085 Starting C:\Program Files\PS3 Media Server\win32\mencoder.exe -ss 0 -dvd-device D:\shared\downloads\series\test\test.iso dvd://1 -quiet -oac lavc -of mpeg -quiet -quiet -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64:vaspect=4/3 -ovc lavc -channels 6 -lavdopts debug=0:threads=1:skiploopfilter=all -lavcopts autoaspect=1:vcodec=mpeg2video:acodec=ac3:abitrate=256:threads=2:keyint=1:vqscale=1:vqmin=2 -subcp cp1252 -subfont C:\WINDOWS\Fonts\Arial.ttf -quiet -quiet -quiet -quiet -quiet -quiet -quiet -quiet -quiet -quiet -sid 3 -quiet -quiet -ofps 25 -quiet -quiet -quiet -af lavcresample=48000 -srate 48000 -o \\.\pipe\mencoder1259820156757
[win32/mencoder.exe] INFO  07:02:37.085 Reading pipe: \\.\pipe\mencoder1259820156757
[win32/mencoder.exe] INFO  07:02:37.085 Attaching thread: win32/mencoder.exe

[Thread-48] DEBUG 07:02:37.132 MEncoder Sherpya-SVN-r29238-4.5.0 (C) 2000-2009 MPlayer Team
[Thread-48] DEBUG 07:02:37.147 There are 4 titles on this DVD.
[Thread-48] DEBUG 07:02:37.147 There are 1 angles in this DVD title.
[Thread-48] DEBUG 07:02:37.147 audio stream: 0 format: ac3 (stereo) language: unknown aid: 128.
[Thread-48] DEBUG 07:02:37.147 number of audio channels on disk: 1.
[Thread-48] DEBUG 07:02:37.147 subtitle ( sid ): 0 language: fi
[Thread-48] DEBUG 07:02:37.147 subtitle ( sid ): 1 language: sv
[Thread-48] DEBUG 07:02:37.147 subtitle ( sid ): 2 language: no
[Thread-48] DEBUG 07:02:37.147 subtitle ( sid ): 3 language: da
[Thread-48] DEBUG 07:02:37.147 number of subtitles on disk: 4
[Thread-48] DEBUG 07:02:37.147 success: format: 2  data: 0x0 - 0x4ae50000
[Thread-48] DEBUG 07:02:37.147 MPEG-PS file format detected.
[Thread-48] DEBUG 07:02:37.147 VIDEO:  MPEG2  720x576  (aspect 2)  25.000 fps  9000.0 kbps (1125.0 kbyte/s)
[Thread-48] DEBUG 07:02:37.147 [V] filefmt:2  fourcc:0x10000002  size:720x576  fps:25.000  ftime:=0.0400
[Thread-48] DEBUG 07:02:37.147 ==========================================================================
[Thread-48] DEBUG 07:02:37.147 Opening audio decoder: [liba52] AC3 decoding with liba52
[Thread-48] DEBUG 07:02:37.163 AUDIO: 48000 Hz, 2 ch, s16le, 192.0 kbit/12.50% (ratio: 24000->192000)
[Thread-48] DEBUG 07:02:37.163 Selected audio codec: [a52] afm: liba52 (AC3-liba52)
[Thread-48] DEBUG 07:02:37.163 ==========================================================================
[Thread-45] INFO  07:02:37.163 Result of \\.\pipe\mencoder1259820156757 : true
[Thread-48] DEBUG 07:02:37.163 PACKET SIZE: 2048 bytes, deltascr: 884
[Thread-48] DEBUG 07:02:37.163 MPEG MUXER, patching aspect ratio to 4/3
[Thread-48] DEBUG 07:02:37.163 Opening video filter: [expand osd=1]
[Thread-48] DEBUG 07:02:37.163 Expand: -1 x -1, -1 ; -1, osd: 1, aspect: 0.000000, round: 1
[Thread-48] DEBUG 07:02:37.163 ==========================================================================
[Thread-48] DEBUG 07:02:37.163 Opening video decoder: [mpegpes] MPEG 1/2 Video passthrough
[Thread-48] DEBUG 07:02:37.163 VDec: vo config request - 720 x 576 (preferred colorspace: Mpeg PES)
[Thread-48] DEBUG 07:02:37.163 Could not find matching colorspace - retrying with -vf scale...
[Thread-48] DEBUG 07:02:37.163 Opening video filter: [scale]
[Thread-48] DEBUG 07:02:37.163 VDecoder init failed :(
[Thread-48] DEBUG 07:02:37.163 Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family
[Thread-48] DEBUG 07:02:37.163 Selected video codec: [ffmpeg2] vfm: ffmpeg (FFmpeg MPEG-2)
[Thread-48] DEBUG 07:02:37.163 ==========================================================================
[Thread-47] DEBUG 07:02:37.179 libdvdread: Using libdvdcss version 1.2.10 for DVD access
[Thread-47] DEBUG 07:02:37.179 libdvdread: Attempting to retrieve all CSS keys
[Thread-47] DEBUG 07:02:37.179 libdvdread: This can take a _long_ time, please be patient
[Thread-47] DEBUG 07:02:37.179 libdvdread: Found 1 VTS's
[Thread-47] DEBUG 07:02:37.179 libdvdread: Elapsed time 0
[Thread-47] DEBUG 07:02:37.179 Using SSE optimized IMDCT transform
[Thread-47] DEBUG 07:02:37.179 Unimplemented resampler for mode 0x2 -> 6 channels conversion - Contact MPlayer developers!
[Thread-47] DEBUG 07:02:37.179 Unimplemented resampler for mode 0x2 -> 5 channels conversion - Contact MPlayer developers!
[Thread-47] DEBUG 07:02:37.179 Unimplemented resampler for mode 0x2 -> 4 channels conversion - Contact MPlayer developers!
[Thread-47] DEBUG 07:02:37.179 Unimplemented resampler for mode 0x2 -> 3 channels conversion - Contact MPlayer developers!
[Thread-47] DEBUG 07:02:37.179 Using MMX optimized resampler
[Thread-47] DEBUG 07:02:37.179 The selected video_out device is incompatible with this codec.
[Thread-47] DEBUG 07:02:37.179 Try appending the scale filter to your filter list,
[Thread-47] DEBUG 07:02:37.179 e.g. -vf spp,scale instead of -vf spp.
[Thread-47] DEBUG 07:02:37.179 Unsupported PixelFormat -1
[Thread-47] DEBUG 07:02:37.179 [ac3 @ 0x1e7d780]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[Thread-47] DEBUG 07:02:37.179 Limiting audio preload to 0.4s.
[Thread-47] DEBUG 07:02:37.179 Increasing audio density to 4.
[Thread-48] DEBUG 07:02:37.179 VDec: vo config request - 720 x 576 (preferred colorspace: Planar YV12)
[Thread-48] DEBUG 07:02:37.179 VDec: using Planar YV12 as output csp (no 0)
[Thread-48] DEBUG 07:02:37.179 Movie-Aspect is 1.33:1 - prescaling to correct movie aspect.
[Thread-48] DEBUG 07:02:37.179 videocodec: libavcodec (720x576 fourcc=3267706d [mpg2])
[Thread-48] DEBUG 07:02:37.179 [VE_LAVC] Using constant qscale = 1.000000 (VBR).
[Thread-48] DEBUG 07:02:37.210 Writing header...

[Timer-2] DEBUG 07:02:39.022 Buffered Space: 6666240 bytes / inputs: 0
[Timer-2] DEBUG 07:02:41.022 Buffered Space: 16912384 bytes / inputs: 0
[Timer-2] DEBUG 07:02:43.022 Buffered Space: 27672576 bytes / inputs: 0

[New I/O server worker #1-1] DEBUG 07:02:43.194 Ready to Stop: true
[New I/O server worker #1-1] DEBUG 07:02:43.226 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #1-1] DEBUG 07:02:43.226 Sent to socket: Connection: keep-alive
[New I/O server worker #1-1] DEBUG 07:02:43.226 Sent to socket: Content-Length: 3383123968
[New I/O server worker #1-1] DEBUG 07:02:43.226 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #1-1] DEBUG 07:02:43.226 Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
[New I/O server worker #1-1] DEBUG 07:02:43.226 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.20
[New I/O server worker #1-1] DEBUG 07:02:43.226 Sent to socket: TransferMode.DLNA.ORG: Streaming


[New I/O server worker #1-2] DEBUG 07:02:43.226 Opened handler on socket /10.0.0.19:41876 // WD TV Live
[New I/O server worker #1-2] DEBUG 07:02:43.226 Handler infos: HTTP/1.0 : GET : get/0$0$0$0/Title+1
[New I/O server worker #1-2] DEBUG 07:02:43.226 Received on socket: Content-Length: 0
[New I/O server worker #1-2] DEBUG 07:02:43.226 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-2] DEBUG 07:02:43.226 Received on socket: transferMode.dlna.org: Streaming
[New I/O server worker #1-2] DEBUG 07:02:43.226 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-2] INFO  07:02:43.226 HTTP: get/0$0$0$0/Title+1 / 0-0
[New I/O server worker #1-2] DEBUG 07:02:43.226 Searching for objectId: 0$0$0$0 with children option: false
[New I/O server worker #1-2] DEBUG 07:02:43.226 Asked stream chunk [0-0] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-2] DEBUG 07:02:43.226 Ready to Stop: false
[New I/O server worker #1-2] DEBUG 07:02:43.226 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #1-2] DEBUG 07:02:43.226 Sent to socket: Connection: keep-alive
[New I/O server worker #1-2] DEBUG 07:02:43.226 Sent to socket: Content-Length: 3383123968
[New I/O server worker #1-2] DEBUG 07:02:43.226 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #1-2] DEBUG 07:02:43.226 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.20
[New I/O server worker #1-2] DEBUG 07:02:43.226 Sent to socket: TransferMode.DLNA.ORG: Streaming


[Timer-2] DEBUG 07:02:45.022 Buffered Space: 32692223 bytes / inputs: 1
[Timer-2] DEBUG 07:02:47.022 Buffered Space: 36986879 bytes / inputs: 1
[Thread-45] DEBUG 07:02:47.897 freeMemory: 9764976
[Thread-45] DEBUG 07:02:47.897 totalMemory: 64999424
[Thread-45] DEBUG 07:02:47.897 maxMemory: 799145984
[Thread-45] DEBUG 07:02:47.897 Extending buffer to 104857600


[New I/O server worker #1-1] DEBUG 07:02:48.351 Opened handler on socket /10.0.0.19:41877 // WD TV Live
[New I/O server worker #1-1] DEBUG 07:02:48.351 Handler infos: HTTP/1.0 : GET : get/0$0$0$0/Title+1
[New I/O server worker #1-1] DEBUG 07:02:48.351 Received on socket: Content-Length: 0
[New I/O server worker #1-1] DEBUG 07:02:48.351 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-1] DEBUG 07:02:48.351 Received on socket: Range: bytes=0-
[New I/O server worker #1-1] DEBUG 07:02:48.351 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-1] INFO  07:02:48.351 HTTP: get/0$0$0$0/Title+1 / 0-100000000000
[New I/O server worker #1-1] DEBUG 07:02:48.351 Searching for objectId: 0$0$0$0 with children option: false
[New I/O server worker #1-1] DEBUG 07:02:48.351 Asked stream chunk [0-100000000000] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-1] INFO  07:02:48.351 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:48.351 External inputstream instance is null... sounds not good, waiting 500ms
[Thread-45] DEBUG 07:02:48.507 Done extending
[New I/O server worker #1-1] INFO  07:02:48.851 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:48.851 External inputstream instance is null... sounds not good, waiting 500ms
[Timer-2] DEBUG 07:02:49.022 Buffered Space: 42123263 bytes / inputs: 1
[New I/O server worker #1-1] INFO  07:02:49.351 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:49.351 External inputstream instance is null... sounds not good, waiting 500ms
[New I/O server worker #1-1] INFO  07:02:49.851 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:49.851 External inputstream instance is null... sounds not good, waiting 500ms
[New I/O server worker #1-1] INFO  07:02:50.351 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:50.351 External inputstream instance is null... sounds not good, waiting 500ms
[New I/O server worker #1-1] INFO  07:02:50.851 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:50.851 External inputstream instance is null... sounds not good, waiting 500ms
[Timer-2] DEBUG 07:02:51.022 Buffered Space: 51585023 bytes / inputs: 1
[New I/O server worker #1-1] INFO  07:02:51.351 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:51.351 External inputstream instance is null... sounds not good, waiting 500ms
[New I/O server worker #1-1] INFO  07:02:51.851 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:51.851 External inputstream instance is null... sounds not good, waiting 500ms
[New I/O server worker #1-1] INFO  07:02:52.351 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:52.351 External inputstream instance is null... sounds not good, waiting 500ms
[New I/O server worker #1-1] INFO  07:02:52.851 BufferedOutputFile is already attached to an InputStream: net.pms.io.BufferedOutputFile$WaitBufferedInputStream@80669d
[New I/O server worker #1-1] DEBUG 07:02:52.851 External inputstream instance is null... sounds not good, waiting 500ms
[Timer-2] DEBUG 07:02:53.022 Buffered Space: 60561407 bytes / inputs: 1
[New I/O server worker #1-1] DEBUG 07:02:53.351 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #1-1] DEBUG 07:02:53.351 Sent to socket: Connection: keep-alive
[New I/O server worker #1-1] DEBUG 07:02:53.351 Sent to socket: Content-Length: 0
[New I/O server worker #1-1] DEBUG 07:02:53.351 Sent to socket: Content-Range: bytes 0-99999999999/100000000000
[New I/O server worker #1-1] DEBUG 07:02:53.351 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #1-1] DEBUG 07:02:53.351 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.20
[Thread-10] INFO  07:02:54.944 Sending ALIVE...
[Thread-10] DEBUG 07:02:54.944 Setting multicast network interface: name:eth0 (Intel(R) PRO/1000 MT Network Connection - Packet Scheduler Miniport) index: 2 addresses:
/10.0.0.12;

[Thread-10] DEBUG 07:02:54.976 Sending message from multicast socket on network interface: name:eth0 (Intel(R) PRO/1000 MT Network Connection - Packet Scheduler Miniport) index: 2 addresses:
/10.0.0.12;

[Thread-10] DEBUG 07:02:54.976 Multicast socket is on interface: /10.0.0.12
[Thread-10] DEBUG 07:02:54.976 Socket Timeout: 0
[Thread-10] DEBUG 07:02:54.976 Socket TTL: 32
[Timer-2] DEBUG 07:02:55.022 Buffered Space: 69873663 bytes / inputs: 1
[Timer-2] DEBUG 07:02:57.022 Buffered Space: 79493119 bytes / inputs: 1
[Timer-2] DEBUG 07:02:59.022 Buffered Space: 84860927 bytes / inputs: 1
[Timer-2] DEBUG 07:03:01.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:03.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:05.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:07.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:09.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:11.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:13.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:15.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:17.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:19.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:21.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:23.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:25.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:27.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:29.038 Buffered Space: 85326111 bytes / inputs: 1
[Timer-2] DEBUG 07:03:31.038 Buffered Space: 85326111 bytes / inputs: 1


[shagrath]

yep, I know what happens, I will make very soon a new version, r369 (370 maybe ?), with a new property to add:

transcode_keep_first_connection=false

[ditlew]

Sounds great, tnx!

[ExSport]

yep, I know what happens, I will make very soon a new version, r369 (370 maybe ?), with a new property to add:

transcode_keep_first_connection=false

It means that same file will not be possible to be streamed to 2 different PS3 at a time because first connection will be dropped?

[shagrath]

r369 is out, you can try the trick

Exsport: not at all, it's just some options disabled by default, but maybe useful with this particular renderer

[ditlew]

Sadly it still says inputs: 2 and stops the same spot.

I placed the 'transcode_keep_first_connection=false' in PMS.conf

Code: Select all

[New I/O server worker #1-2] DEBUG 00:55:55.465 Opened handler on socket /10.0.0.19:36488 // WD TV Live
[New I/O server worker #1-2] DEBUG 00:55:55.465 Handler infos: HTTP/1.0 : HEAD : get/0$0$0$0/Title+1
[New I/O server worker #1-2] DEBUG 00:55:55.465 Received on socket: getcontentFeatures.dlna.org: 1
[New I/O server worker #1-2] DEBUG 00:55:55.465 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-2] DEBUG 00:55:55.465 Received on socket: transferMode.dlna.org: Streaming
[New I/O server worker #1-2] DEBUG 00:55:55.465 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-2] INFO  00:55:55.465 HTTP: get/0$0$0$0/Title+1 / 0-0
[New I/O server worker #1-2] DEBUG 00:55:55.465 Searching for objectId: 0$0$0$0 with children option: false
[New I/O server worker #1-2] DEBUG 00:55:55.465 Asked stream chunk [0-0] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-2] TRACE 00:55:55.480 Starting transcode/remux of Title 1
[New I/O server worker #1-2] DEBUG 00:55:55.480 Searching an audio track with lang: dan
[New I/O server worker #1-2] DEBUG 00:55:55.480 Searching an audio track with lang: eng
[New I/O server worker #1-2] DEBUG 00:55:55.480 Searching an audio track with lang: fre
[New I/O server worker #1-2] DEBUG 00:55:55.480 Searching an audio track with lang: jpn
[New I/O server worker #1-2] DEBUG 00:55:55.480 Searching an audio track with lang: ger
[New I/O server worker #1-2] DEBUG 00:55:55.496 Choosed a default audio track: Audio: AC3 / lang: unknown / ID: 128
[New I/O server worker #1-2] DEBUG 00:55:55.496 Found subtitles track : Sub: Embedded / lang: fi / ID: 0 / FILE: -
[New I/O server worker #1-2] DEBUG 00:55:55.496 Found subtitles track : Sub: Embedded / lang: sv / ID: 1 / FILE: -
[New I/O server worker #1-2] DEBUG 00:55:55.496 Found subtitles track : Sub: Embedded / lang: no / ID: 2 / FILE: -
[New I/O server worker #1-2] DEBUG 00:55:55.496 Found subtitles track : Sub: Embedded / lang: da / ID: 3 / FILE: -
[New I/O server worker #1-2] DEBUG 00:55:55.496 Searching a subtitle track with lang: dan
[New I/O server worker #1-2] DEBUG 00:55:55.496 Matched sub track : Sub: Embedded / lang: da / ID: 3 / FILE: -
[New I/O server worker #1-2] INFO  00:55:55.683 Creating pipe \\.\pipe\mencoder1259884555683
[Timer-1] DEBUG 00:55:55.964 Buffered Space: 0 bytes / inputs: 0
[Thread-32] INFO  00:55:55.964 Waiting for pipe connection \\.\pipe\mencoder1259884555683
[Thread-7] DEBUG 00:55:56.011 Receiving a NOTIFY from [10.0.0.12:3225]
[win32/mencoder.exe] INFO  00:55:56.027 Starting C:\Program Files\PS3 Media Server\win32\mencoder.exe -ss 0 -dvd-device D:\shared\downloads\series\test\test.iso dvd://1 -quiet -oac lavc -of mpeg -quiet -quiet -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64:vaspect=4/3 -ovc lavc -channels 6 -lavdopts debug=0:threads=1:skiploopfilter=all -lavcopts autoaspect=1:vcodec=mpeg2video:acodec=ac3:abitrate=256:threads=2:keyint=1:vqscale=1:vqmin=2 -subcp cp1252 -subfont C:\WINDOWS\Fonts\Arial.ttf -quiet -quiet -quiet -quiet -quiet -quiet -quiet -quiet -quiet -quiet -sid 3 -quiet -quiet -ofps 25 -quiet -quiet -quiet -af lavcresample=48000 -srate 48000 -o \\.\pipe\mencoder1259884555683
[win32/mencoder.exe] INFO  00:55:56.027 Reading pipe: \\.\pipe\mencoder1259884555683
[win32/mencoder.exe] INFO  00:55:56.027 Attaching thread: win32/mencoder.exe
[Thread-35] DEBUG 00:55:56.074 MEncoder Sherpya-SVN-r29851-4.2.5 (C) 2000-2009 MPlayer Team
[Thread-11] INFO  00:55:56.089 Stopping process: win32/mplayer.exe
[Thread-35] DEBUG 00:55:56.089 There are 4 titles on this DVD.
[Thread-35] DEBUG 00:55:56.089 There are 1 angles in this DVD title.
[Thread-35] DEBUG 00:55:56.089 audio stream: 0 format: ac3 (stereo) language: unknown aid: 128.
[Thread-35] DEBUG 00:55:56.089 number of audio channels on disk: 1.
[Thread-35] DEBUG 00:55:56.089 subtitle ( sid ): 0 language: fi
[Thread-35] DEBUG 00:55:56.089 subtitle ( sid ): 1 language: sv
[Thread-35] DEBUG 00:55:56.089 subtitle ( sid ): 2 language: no
[Thread-35] DEBUG 00:55:56.089 subtitle ( sid ): 3 language: da
[Thread-35] DEBUG 00:55:56.089 number of subtitles on disk: 4
[Thread-35] DEBUG 00:55:56.089 success: format: 2  data: 0x0 - 0x4ae50000
[Thread-35] DEBUG 00:55:56.089 MPEG-PS file format detected.
[Thread-35] DEBUG 00:55:56.089 VIDEO:  MPEG2  720x576  (aspect 2)  25.000 fps  9000.0 kbps (1125.0 kbyte/s)
[Thread-35] DEBUG 00:55:56.089 [V] filefmt:2  fourcc:0x10000002  size:720x576  fps:25.000  ftime:=0.0400
[Thread-35] DEBUG 00:55:56.089 ==========================================================================
[Thread-35] DEBUG 00:55:56.089 Opening audio decoder: [liba52] AC3 decoding with liba52
[Thread-35] DEBUG 00:55:56.089 AUDIO: 48000 Hz, 2 ch, s16le, 192.0 kbit/12.50% (ratio: 24000->192000)
[Thread-35] DEBUG 00:55:56.089 Selected audio codec: [a52] afm: liba52 (AC3-liba52)
[Thread-35] DEBUG 00:55:56.089 ==========================================================================
[Thread-32] INFO  00:55:56.089 Result of \\.\pipe\mencoder1259884555683 : true
[Thread-35] DEBUG 00:55:56.105 PACKET SIZE: 2048 bytes, deltascr: 884
[Thread-35] DEBUG 00:55:56.105 MPEG MUXER, patching aspect ratio to 4/3
[Thread-12] INFO  00:55:56.105 Stopping process: win32/ffmpeg.exe
[Thread-12] INFO  00:55:56.105 Destroying buffer
[Thread-7] DEBUG 00:55:56.245 Receiving a NOTIFY from [10.0.0.12:3225]
[Thread-34] DEBUG 00:55:56.245 libdvdread: Using libdvdcss version 1.2.10 for DVD access
[Thread-34] DEBUG 00:55:56.245 libdvdread: Attempting to retrieve all CSS keys
[Thread-7] DEBUG 00:55:56.245 Receiving a NOTIFY from [10.0.0.12:3225]
[Thread-34] DEBUG 00:55:56.245 libdvdread: This can take a _long_ time, please be patient
[Thread-34] DEBUG 00:55:56.245 libdvdread: Found 1 VTS's
[Thread-34] DEBUG 00:55:56.245 libdvdread: Elapsed time 0
[Thread-34] DEBUG 00:55:56.245 Using SSE optimized IMDCT transform
[Thread-34] DEBUG 00:55:56.245 Unimplemented resampler for mode 0x2 -> 6 channels conversion - Contact MPlayer developers!
[Thread-34] DEBUG 00:55:56.245 Unimplemented resampler for mode 0x2 -> 5 channels conversion - Contact MPlayer developers!
[Thread-34] DEBUG 00:55:56.245 Unimplemented resampler for mode 0x2 -> 4 channels conversion - Contact MPlayer developers!
[Thread-34] DEBUG 00:55:56.245 Unimplemented resampler for mode 0x2 -> 3 channels conversion - Contact MPlayer developers!
[Thread-34] DEBUG 00:55:56.245 Using MMX optimized resampler
[Thread-34] DEBUG 00:55:56.245 The selected video_out device is incompatible with this codec.
[Thread-34] DEBUG 00:55:56.245 Try appending the scale filter to your filter list,
[Thread-34] DEBUG 00:55:56.245 e.g. -vf spp,scale instead of -vf spp.
[Thread-34] DEBUG 00:55:56.245 Unsupported PixelFormat -1
[Thread-35] DEBUG 00:55:56.370 Opening video filter: [expand osd=1]
[Thread-7] DEBUG 00:55:56.370 Receiving a NOTIFY from [10.0.0.12:3226]
[Thread-35] DEBUG 00:55:56.370 Expand: -1 x -1, -1 ; -1, osd: 1, aspect: 0.000000, round: 0
[Thread-35] DEBUG 00:55:56.370 ==========================================================================
[Thread-35] DEBUG 00:55:56.370 Opening video decoder: [mpegpes] MPEG 1/2 Video passthrough
[Thread-35] DEBUG 00:55:56.370 Could not find matching colorspace - retrying with -vf scale...
[Thread-35] DEBUG 00:55:56.370 Opening video filter: [scale]
[Thread-35] DEBUG 00:55:56.370 VDecoder init failed :(
[Thread-35] DEBUG 00:55:56.370 Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family
[Thread-35] DEBUG 00:55:56.370 Selected video codec: [ffmpeg2] vfm: ffmpeg (FFmpeg MPEG-2)
[Thread-35] DEBUG 00:55:56.370 ==========================================================================
[Thread-7] DEBUG 00:55:56.589 Receiving a NOTIFY from [10.0.0.12:3227]
[Thread-7] DEBUG 00:55:56.589 Receiving a NOTIFY from [10.0.0.12:3228]
[Thread-35] DEBUG 00:55:56.589 Movie-Aspect is 1.33:1 - prescaling to correct movie aspect.
[Thread-35] DEBUG 00:55:56.605 videocodec: libavcodec (720x576 fourcc=3267706d [mpg2])
[Thread-35] DEBUG 00:55:56.605 [VE_LAVC] Using constant qscale = 1.000000 (VBR).
[Thread-35] DEBUG 00:55:56.605 Writing header...
[Thread-34] DEBUG 00:55:56.589 [ac3 @ 0x1ebd8a0]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
[Thread-34] DEBUG 00:55:56.605 Limiting audio preload to 0.4s.
[Thread-34] DEBUG 00:55:56.605 Increasing audio density to 4.
[Thread-19] INFO  00:55:57.854 Stopping process: win32/mplayer.exe
[Timer-1] DEBUG 00:55:57.979 Buffered Space: 5031936 bytes / inputs: 0
[Thread-22] INFO  00:55:58.166 Stopping process: win32/mplayer.exe
[Thread-25] INFO  00:55:58.385 Stopping process: win32/mplayer.exe
[Thread-28] INFO  00:55:58.572 Stopping process: win32/mplayer.exe
[Timer-1] DEBUG 00:55:59.993 Buffered Space: 15702016 bytes / inputs: 0
[Timer-1] DEBUG 00:56:02.008 Buffered Space: 26634240 bytes / inputs: 0
[Thread-7] DEBUG 00:56:02.070 Receiving a NOTIFY from [10.0.0.19:1900]
[Thread-7] DEBUG 00:56:02.070 Receiving a NOTIFY from [10.0.0.19:1900]
[New I/O server worker #1-2] DEBUG 00:56:02.242 Ready to Stop: true
[New I/O server worker #1-2] DEBUG 00:56:02.242 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #1-2] DEBUG 00:56:02.242 Sent to socket: Connection: keep-alive
[New I/O server worker #1-2] DEBUG 00:56:02.242 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #1-2] DEBUG 00:56:02.242 Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
[New I/O server worker #1-2] DEBUG 00:56:02.242 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.11.369
[New I/O server worker #1-2] DEBUG 00:56:02.242 Sent to socket: TransferMode.DLNA.ORG: Streaming
[New I/O server worker #1-1] DEBUG 00:56:02.273 Opened handler on socket /10.0.0.19:36489 // WD TV Live
[New I/O server worker #1-1] DEBUG 00:56:02.273 Handler infos: HTTP/1.0 : GET : get/0$0$0$0/Title+1
[New I/O server worker #1-1] DEBUG 00:56:02.273 Received on socket: getcontentFeatures.dlna.org: 1
[New I/O server worker #1-1] DEBUG 00:56:02.273 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-1] DEBUG 00:56:02.273 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-1] INFO  00:56:02.273 HTTP: get/0$0$0$0/Title+1 / 0-0
[New I/O server worker #1-1] DEBUG 00:56:02.273 Searching for objectId: 0$0$0$0 with children option: false
[New I/O server worker #1-1] DEBUG 00:56:02.273 Asked stream chunk [0-0] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-1] DEBUG 00:56:02.273 Ready to Stop: false
[New I/O server worker #1-1] DEBUG 00:56:02.273 Ready to Stop: true
[New I/O server worker #1-2] DEBUG 00:56:02.273 Opened handler on socket /10.0.0.19:36490 // WD TV Live
[New I/O server worker #1-2] DEBUG 00:56:02.273 Handler infos: HTTP/1.0 : GET : get/0$0$0$0/Title+1
[New I/O server worker #1-2] DEBUG 00:56:02.273 Received on socket: Content-Length: 0
[New I/O server worker #1-2] DEBUG 00:56:02.273 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-2] DEBUG 00:56:02.273 Received on socket: transferMode.dlna.org: Streaming
[New I/O server worker #1-2] DEBUG 00:56:02.273 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-2] INFO  00:56:02.273 HTTP: get/0$0$0$0/Title+1 / 0-0
[New I/O server worker #1-2] DEBUG 00:56:02.273 Searching for objectId: 0$0$0$0 with children option: false
[New I/O server worker #1-2] DEBUG 00:56:02.289 Asked stream chunk [0-0] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-2] DEBUG 00:56:02.289 Ready to Stop: false
[New I/O server worker #1-1] DEBUG 00:56:02.305 Ready to Stop: true
[New I/O server worker #1-2] DEBUG 00:56:02.305 Ready to Stop: false
[New I/O server worker #1-1] DEBUG 00:56:02.305 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #1-1] DEBUG 00:56:02.305 Sent to socket: Connection: keep-alive
[New I/O server worker #1-1] DEBUG 00:56:02.305 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #1-1] DEBUG 00:56:02.305 Sent to socket: ContentFeatures.DLNA.ORG: DLNA.ORG_PN=MPEG_PS_PAL;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000
[New I/O server worker #1-1] DEBUG 00:56:02.305 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.11.369
[New I/O server worker #1-2] DEBUG 00:56:02.320 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #1-2] DEBUG 00:56:02.320 Sent to socket: Connection: keep-alive
[New I/O server worker #1-2] DEBUG 00:56:02.320 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #1-2] DEBUG 00:56:02.320 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.11.369
[New I/O server worker #1-2] DEBUG 00:56:02.320 Sent to socket: TransferMode.DLNA.ORG: Streaming
[Timer-1] DEBUG 00:56:04.022 Buffered Space: 31580159 bytes / inputs: 1
[Timer-1] DEBUG 00:56:06.037 Buffered Space: 35170303 bytes / inputs: 1
[New I/O server worker #1-1] DEBUG 00:56:06.740 Opened handler on socket /10.0.0.19:36491 // WD TV Live
[New I/O server worker #1-1] DEBUG 00:56:06.740 Handler infos: HTTP/1.0 : GET : get/0$0$0$0/Title+1
[New I/O server worker #1-1] DEBUG 00:56:06.740 Received on socket: Content-Length: 0
[New I/O server worker #1-1] DEBUG 00:56:06.755 Received on socket: Host: 10.0.0.12:5001
[New I/O server worker #1-1] DEBUG 00:56:06.755 Received on socket: Range: bytes=0-
[New I/O server worker #1-1] DEBUG 00:56:06.755 Received on socket: User-Agent: INTEL_NMPR/2.1 DLNADOC/1.50 dma/3.0 alphanetworks
[New I/O server worker #1-1] INFO  00:56:06.755 HTTP: get/0$0$0$0/Title+1 / 0-100000000000
[New I/O server worker #1-1] DEBUG 00:56:06.755 Searching for objectId: 0$0$0$0 with children option: false
[New I/O server worker #1-1] DEBUG 00:56:06.755 Asked stream chunk [0-100000000000] timeseek: 0.0 of Title 1 and player MEncoder
[New I/O server worker #1-1] DEBUG 00:56:06.771 Sent to socket: Accept-Ranges: bytes
[New I/O server worker #1-1] DEBUG 00:56:06.771 Sent to socket: Connection: keep-alive
[New I/O server worker #1-1] DEBUG 00:56:06.771 Sent to socket: Content-Range: bytes 0-99999999999/100000000000
[New I/O server worker #1-1] DEBUG 00:56:06.771 Sent to socket: Content-Type: video/mpeg
[New I/O server worker #1-1] DEBUG 00:56:06.771 Sent to socket: Server: Windows_XP-x86-5.1, UPnP/1.0, PMS/1.11.369
[Thread-32] DEBUG 00:56:07.302 freeMemory: 41411456
[Thread-32] DEBUG 00:56:07.302 totalMemory: 95752192
[Thread-32] DEBUG 00:56:07.302 maxMemory: 799145984
[Thread-32] DEBUG 00:56:07.302 Extending buffer to 104857600
[Thread-32] DEBUG 00:56:07.942 Done extending
[Timer-1] DEBUG 00:56:08.051 Buffered Space: 40089599 bytes / inputs: 2
[Timer-1] DEBUG 00:56:10.066 Buffered Space: 49676287 bytes / inputs: 2
[Timer-1] DEBUG 00:56:12.080 Buffered Space: 57425919 bytes / inputs: 2