Subtitles stopped working on 1.04

[koichirose]

I recently upgraded to 1.04 (even though 1.03 did not have any problems for me...)
Now subtitles arent working anymore.

I see the movie title and then [mencoder subtitles found], but it wont show anything. I tried with some avis + srt or mkv+srt (just 1 srt per file). On my pc (with videolan or kmplayer) everything's ok.

The only thing maybe relevant on the debug is this:
[main] TRACE 19:40:10.479 !!! It seems VSFilter.dll is not installed into the AviSynth plugins dir ! It could be troublesome for playing subtitled videos with AviSynth !!!

But I don't use avisynth (mencoder worked just fine with 1.03).

here are my settings:

Code: Select all

navigation options -> only thumbnails generation is checked
common transcoding settings:
buffer: 550mb (is that correct? I have a pretty fast computer (core2duo E8400, winxp sp3) and I'm on ethernet.)
cores: 2
audio: stereo, 384kb
no limit for video bandwidth
quality: keyint=1:vqscale=1:vqmin=2

definitely disable subtitles -> obviously unchecked, next two fields are empty


mencoder settings:

Code: Select all

multicore support and A/V sync checked, the rest is unchecked (i'm talking about the upper side)
subtitles priority-> en (even if I delete it, it will appear again. I don't think it's relevant anyway, since my files are like movie.mkv -> movie.srt)
audio priority -> empty
codepage -> 1252 (I have 1252 on my ps3)
truetype -> empty
ass/ssa -> checked (even if i dont know what it means, subtitles wont show even if I uncheck it)
fontconfig -> checked (same as previous)
ASS default style -> unchecked
ASS font settings: scale: 1.0 outline: 1 shadow: 1 margin: 15
autoload srt/sub -> obviously checked


And this is it I think... A full guide on how to enable subtitles would be really appreciated!

Thanks and congratulations for this great server

[Subject2Change]

Are you entering the #Transcode# folder? or are you doing it from the root. Despite showing subtitles found I often had to enter the Transcode folder for it to playback with subs. Give that a shot, if you have a 1.03 installer maybe revert back to that as well.

[koichirose]

Yes, I have mencoder by default and if I select the one from the transcode folder the result is the same.
Avisynth/mencoder won't show subtitles either, tsmuxer works only without encoding (and to have subtitles I have to encode, is that right?
avisynt/ffmpeg won't even play.

[shagrath]

try to play a file and then, post the debug.log file here

viewtopic.php?f=6&t=496

[koichirose]

Here it is

Code: Select all

[main] TRACE 20:12:03.870 Starting Java PS3 Media Server v1.04
[main] TRACE 20:12:03.885 by shagrath / 2008
[main] TRACE 20:12:03.885 http://ps3mediaserver.blogspot.com
[main] TRACE 20:12:03.885
[main] TRACE 20:12:03.885 Java 1.6.0_10-Sun Microsystems Inc.
[main] TRACE 20:12:03.917 OS Windows XP x86 5.1
[main] TRACE 20:12:03.917 Encoding: Cp1252
[main] TRACE 20:12:03.917 Temp folder: C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\javaps3media
[main] TRACE 20:12:04.229 Found AviSynth plugins dir: C:\Program Files\AviSynth 2.5\plugins
[main] TRACE 20:12:04.245 !!! It seems VSFilter.dll is not installed into the AviSynth plugins dir ! It could be troublesome for playing subtitled videos with AviSynth !!!
[main] TRACE 20:12:04.245 Found VideoLAN version 0.9.6 at: C:\Program Files\VideoLAN\VLC\vlc.exe
[main] TRACE 20:12:04.245 Registering transcoding engine AviSynth/FFmpeg
[main] TRACE 20:12:04.245 Registering transcoding engine FFmpeg Audio
[main] TRACE 20:12:04.245 Registering transcoding engine MEncoder
[main] TRACE 20:12:04.245 Registering transcoding engine Avisynth/MEncoder
[main] TRACE 20:12:04.245 Registering transcoding engine MPlayer Audio
[main] TRACE 20:12:04.245 Registering transcoding engine MEncoder Web
[main] TRACE 20:12:04.245 Registering transcoding engine MPlayer Video Dump
[main] TRACE 20:12:04.245 Registering transcoding engine MPlayer Web
[main] TRACE 20:12:04.260 Registering transcoding engine TsMuxer
[main] TRACE 20:12:04.260 Registering transcoding engine Audio High Fidelity
[main] TRACE 20:12:04.260 Registering transcoding engine VideoLan Audio Streaming
[main] TRACE 20:12:04.260 Registering transcoding engine VideoLan Video Streaming
[main] TRACE 20:12:04.260 Registering transcoding engine FFmpeg DVR-MS Remux
[main] TRACE 20:12:04.260 Registering transcoding engine Raws Thumbnailer
[main] TRACE 20:12:04.323 Scanning network interface lo / MS TCP Loopback interface
[main] TRACE 20:12:04.323 Scanning network interface eth0 / Realtek RTL8168C(P)/8111C(P) PCI-E Gigabit Ethernet NIC - Packet Scheduler Miniport
[main] TRACE 20:12:04.323 Using address /10.0.0.100 found on network interface: name:eth0 (Realtek RTL8168C(P)/8111C(P) PCI-E Gigabit Ethernet NIC - Packet Scheduler Miniport) index: 2 addresses: /10.0.0.100;
[main] TRACE 20:12:04.323 Created socket: /10.0.0.100:5001
[Thread-16] TRACE 20:12:04.338 Starting DLNA Server on host 10.0.0.100 and port 5001...
[main] TRACE 20:12:04.338 Using following UUID: 7094e193-e213-3f58-9e5d-881852c96d0b
[main] TRACE 20:12:04.885 It's ready! You should see the server appears on XMB
[Thread-20] TRACE 20:12:23.854 Receiving search request from 10.0.0.107! Sending DISCOVER message...
[Thread-16] TRACE 20:12:25.463 Receiving a request from: 10.0.0.107
[Thread-158] TRACE 20:14:48.776 Starting transcode/remux of movie.avi
[Thread-220] TRACE 20:17:25.229 Starting transcode/remux of movie.avi
[Thread-244] TRACE 20:17:48.760 Starting transcode/remux of movie.avi
[win32/mencoder.exe] TRACE 20:17:50.838 Process C:\Program Files\PS3 Media Server\win32\mencoder.exe has a return code of 1! Maybe an error occured... check the log file
[Thread-253] TRACE 20:17:58.776 Starting transcode/remux of movie.avi

I posted the trace. Debug.log is like 4500 lines...
I have movie.avi and movie.srt.
Tried with mencoder (default) and mencoder (transcoded folder, which I believe is exactly the same, right?).
Also, both avisynth/ffmpeg and avisynth/mencoder say "data corrupted" (maybe that's the error with return code 1 in the log..)

[shagrath]

a debug.log file excerpt, or you can zip the whole file. this file can be found in the application directory, or if you have UAC and Vista activated, likely found in the Windows temp directory

[ExSport]

It is not debug log but only trace log;-) Debug.log is saved on your disk

[koichirose]

Ok, i'm posting what I think are the most relevant parts of debug.log (which is 4500+ lines)

Code: Select all

[Thread-40] DEBUG 20:13:42.338 Search extension for E:\Divx Moviesss\movie.avi
[Thread-40] DEBUG 20:13:42.338 Found 1! net.pms.formats.MPG
[Thread-40] INFO  20:13:42.338 Adding movie.avi / class: net.pms.dlna.RealFile
[Thread-40] INFO  20:13:42.338 Switching movie.avi to player: MEncoder
[Thread-40] INFO  20:13:42.338 Duplicate movie.avi with player: MEncoder
[Thread-40] INFO  20:13:42.338 Adding movie.avi / class: net.pms.dlna.FileTranscodeVirtualFolder
[Thread-40] DEBUG 20:13:42.338 Search extension for E:\Divx Moviesss\movie.srt
[Thread-158] DEBUG 20:14:48.760 Opened handler on socket Socket[addr=/10.0.0.107,port=52149,localport=5001]
[Thread-158] DEBUG 20:14:48.760 Received on socket: GET /get/0$0$15/movie.avi HTTP/1.1
[Thread-158] DEBUG 20:14:48.760 Received on socket: Host: 10.0.0.100:5001
[Thread-158] DEBUG 20:14:48.760 Received on socket: User-Agent: PLAYSTATION 3
[Thread-158] DEBUG 20:14:48.776 Received on socket: Connection: Keep-Alive
[Thread-158] DEBUG 20:14:48.776 Received on socket: Accept-Encoding: identity
[Thread-158] DEBUG 20:14:48.776 Received on socket: transferMode.dlna.org: Streaming
[Thread-158] DEBUG 20:14:48.776 Received on socket: TimeSeekRange.dlna.org: npt=0.000-
[Thread-158] INFO  20:14:48.776 HTTP: get/0$0$15/movie.avi / 0-0
[Thread-158] DEBUG 20:14:48.776 Wrote on socket: HTTP/1.1 200 OK
[Thread-158] DEBUG 20:14:48.776 Searching for objectId: 0$0$15 with children option: false
[Thread-158] DEBUG 20:14:48.776 Asked stream chunk [0-0] timeseek: 0.0 of movie.avi and player MEncoder
[Thread-158] TRACE 20:14:48.776 Starting transcode/remux of movie.avi
[Thread-158] INFO  20:14:48.901 Creating pipe \\.\pipe\mencoder1234466088901
[Timer-17] DEBUG 20:14:49.104 Buffered Space: 0 bytes
[Thread-159] INFO  20:14:49.104 Waiting for pipe connection \\.\pipe\mencoder1234466088901
[win32/mencoder.exe] INFO  20:14:49.151 Starting C:\Program Files\PS3 Media Server\win32\mencoder.exe -ss 0 -quiet E:\Divx Moviesss\movie.avi -quiet -quiet -oac lavc -of mpeg -mpegopts format=mpeg2:muxrate=500000:vbuf_size=1194:abuf_size=64 -ovc lavc -channels 2 -lavdopts debug=0:threads=2 -lavcopts autoaspect=1:vcodec=mpeg2video:acodec=ac3:abitrate=384:threads=2:keyint=1:vqscale=1:vqmin=2 -ass -fontconfig -subcp cp1252 -subfont C:\Program Files\Left 4 Dead\platform\vgui\fonts\arial.ttf -ass-color ffffff00 -ass-border-color 00000000 -ass-font-scale 1.0 -ass-force-style FontName=Arial,Outline=1,Shadow=1,MarginV=15 -quiet -quiet -sid 100 -quiet -quiet -ofps 24000/1001 -sub E:\Divx Moviesss\movie.srt -mc 0.1 -af lavcresample=48000 -srate 48000 -o \\.\pipe\mencoder1234466088901
[win32/mencoder.exe] INFO  20:14:49.151 Reading pipe: \\.\pipe\mencoder1234466088901
[win32/mencoder.exe] INFO  20:14:49.151 Attaching thread: win32/mencoder.exe
[Thread-163] DEBUG 20:14:49.182 MEncoder Sherpya-SVN-r28311-4.2.5 (C) 2000-2009 MPlayer Team
[Thread-163] DEBUG 20:14:49.182 CPU: Intel(R) Core(TM)2 Duo CPU     E8400  @ 3.00GHz (Family: 6, Model: 23, Stepping: 10)
[Thread-163] DEBUG 20:14:49.182 CPUflags: Type: 6 MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 1 SSE2: 1
[Thread-163] DEBUG 20:14:49.182 Compiled with runtime CPU detection.
[Thread-163] DEBUG 20:14:49.370 success: format: 0  data: 0x0 - 0x15f38800
[Thread-164] DEBUG 20:14:49.479 Opened handler on socket Socket[addr=/10.0.0.107,port=52148,localport=5001]
[Thread-164] DEBUG 20:14:49.479 Received on socket: POST /upnp/control/content_directory HTTP/1.1
[Thread-164] DEBUG 20:14:49.479 Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment Inc."; mn="PLAYSTATION 3"; mv="1.0";
[Thread-164] DEBUG 20:14:49.495 Received on socket: Host: 10.0.0.100:5001
[Thread-164] DEBUG 20:14:49.495 Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
[Thread-164] DEBUG 20:14:49.495 Received on socket: Content-Length: 276
[Thread-164] DEBUG 20:14:49.495 Received on socket: Content-Type: text/xml; charset="utf-8"
[Thread-164] DEBUG 20:14:49.495 Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#GetSystemUpdateID"
[Thread-163] DEBUG 20:14:49.588 AVI file format detected.
[Thread-163] DEBUG 20:14:49.604 [aviheader] Video stream found, -vid 0
[Thread-163] DEBUG 20:14:49.604 [aviheader] Audio stream found, -aid 1
[Thread-163] DEBUG 20:14:49.620 VIDEO:  [XVID]  560x304  12bpp  23.976 fps  850.9 kbps (103.9 kbyte/s)
[Thread-163] DEBUG 20:14:49.620 [V] filefmt:3  fourcc:0x44495658  size:560x304  fps:23.976  ftime:=0.0417
[Thread-163] DEBUG 20:14:49.620 ==========================================================================
[Thread-163] DEBUG 20:14:49.667 Opening audio decoder: [mp3lib] MPEG layer-2, layer-3
[Thread-164] INFO  20:14:49.682 HTTP: upnp/control/content_directory / 0-0
[Thread-164] DEBUG 20:14:49.682 Wrote on socket: HTTP/1.1 200 OK
[Thread-164] DEBUG 20:14:49.682 Wrote on socket: CONTENT-TYPE: text/xml; charset="utf-8"
[Thread-164] DEBUG 20:14:49.682 Wrote on socket: Server: Windows XP-x86-5.1 UPnP/1.0, PMS
[Thread-164] DEBUG 20:14:49.682 Wrote on socket: Content-Length: 332
[Thread-164] DEBUG 20:14:49.682 Wrote on socket:
[Thread-164] DEBUG 20:14:49.682 Close Connection
[Thread-165] DEBUG 20:14:49.682 Opened handler on socket Socket[addr=/10.0.0.107,port=52147,localport=5001]
[Thread-165] DEBUG 20:14:49.682 Received on socket: POST /upnp/control/content_directory HTTP/1.1
[Thread-165] DEBUG 20:14:49.682 Received on socket: X-AV-Client-Info: av=5.0; cn="Sony Computer Entertainment Inc."; mn="PLAYSTATION 3"; mv="1.0";
[Thread-165] DEBUG 20:14:49.682 Received on socket: Host: 10.0.0.100:5001
[Thread-165] DEBUG 20:14:49.682 Received on socket: User-Agent: UPnP/1.0 DLNADOC/1.50
[Thread-165] DEBUG 20:14:49.682 Received on socket: Content-Length: 903
[Thread-165] DEBUG 20:14:49.682 Received on socket: Content-Type: text/xml; charset="utf-8"
[Thread-165] DEBUG 20:14:49.682 Received on socket: SOAPACTION: "urn:schemas-upnp-org:service:ContentDirectory:1#Browse"
[Thread-163] DEBUG 20:14:49.745 AUDIO: 48000 Hz, 2 ch, s16le, 80.0 kbit/5.21% (ratio: 10000->192000)
[Thread-163] DEBUG 20:14:49.745 Selected audio codec: [mp3] afm: mp3lib (mp3lib MPEG layer-2, layer-3)
[Thread-163] DEBUG 20:14:49.745 ==========================================================================
[Thread-159] INFO  20:14:49.745 Result of \\.\pipe\mencoder1234466088901 : true
[Thread-163] DEBUG 20:14:49.776 PACKET SIZE: 2048 bytes, deltascr: 884
[Thread-162] DEBUG 20:14:49.838 mpg123: Can't rewind stream by 199 bits!
[Thread-162] DEBUG 20:14:49.838 Warning: -ass implies -keep-pts, which may cause "badly interleaved" files.
[Thread-163] DEBUG 20:14:49.838 [ass] auto-open
[Thread-163] DEBUG 20:14:49.838 [ass] Init
[Thread-165] INFO  20:14:49.885 HTTP: upnp/control/content_directory / 0-0
[Thread-165] DEBUG 20:14:49.885 Wrote on socket: HTTP/1.1 200 OK
[Thread-165] DEBUG 20:14:49.885 Wrote on socket: CONTENT-TYPE: text/xml; charset="utf-8"


Don't ask me why it does this: subfont C:\Program Files\Left 4 Dead\platform\vgui\fonts\arial.ttf
Do you need a particular line from the log?

[shagrath]

Don't ask me why it does this: subfont C:\Program Files\Left 4 Dead\platform\vgui\fonts\arial.ttf
Do you need a particular line from the log?

yeah that's weird... try forcing a font in mencoder config panel (specific truetype font), go select the arial.ttf file in the windows/fonts folder

[koichirose]

It worked
Why did it choose that file?
That font even existed in that directory...I still don't have a clue!
Thanks