MPlayer и .ts

    попробовал VO="mpegpes:card=`grep PrimaryDVB /etc/vdr/setup.conf | cut -d" " -f3`"
    ничего не изменилось
    кстати
    arvdr:~# grep PrimaryDVB /etc/vdr/setup.conf | cut -d" " -f3
    1
    arvdr:~#


    у меня стоит одна dvb карточка, поэтому данной проблемы быть не может.


    и уж ели мы заговорили про этот нюанс с несколькими dvb карточками , то в версии конфига 0.8.6 (включена в arvdr)
    про это ни слова



    # Video out device
    # most users will want the default of 'mpegpes', but users with a dxr3
    # need to use 'dxr3'
    #VO="dxr3" .tar.gz
    VO="mpegpes"




    а вот что говорит нам свежий конфиг версии 0.8.7


    # Video out device
    # most users will want the default of 'mpegpes', but users with a dxr3
    # need to use 'dxr3', or users using softdevice might need 'dfbmga'
    # If the DVB card with the MPEG decoder is not the first device as seen
    # by the drivers/VDR, then you have to specify which card it actually is.
    # Do so with 'mpegpes:card=X', where X is the card number (see also AO)
    #VO="dxr3"
    #VO="dfbmga" .tar.gz
    #VO="mpegpes:card=1"
    VO="mpegpes"
    #VO="mpegpes:card=`grep PrimaryDVB /etc/vdr/setup.conf | cut -d" " -f3`"


    но это так, к слову.
    к моей проблеме это не имеет никакого отношения.


    вы заметили из предыдущих логов, что с опцией mplayer -vo null все работает нормально ?

    Сообщение было отредактировано 1 раз, последнее редактирование пользователем Goga777 ().

    так я же и передал, и в последнем логе показал результат этого.
    но понятное дело, это только в качестве эксперимента было.
    чтобы хоть за что-то зацепиться, чтобы найти трабл.

    ну, что еще добавить ?


    вот фрагмент лога неудачной попытки


    PLAYBACK STARTED
    mplayer: slave cmd: mute
    mplayer: volume=0 mpVolume=100 mpMute=1
    mplayer: slave cmd: get_time_length
    mplayer: slave cmd: get_percent_pos
    VDec: vo config request - 720 x 576 (preferred colorspace: Mpeg PES)
    VDec: using Mpeg PES as output csp (no 0)
    Movie-Aspect is 1.33:1 - prescaling to correct movie aspect.
    VO: [mpegpes] 720x576 => 768x576 Mpeg PES
    A:79053.7 V: 0.0 A-V:79053.641 ct: 0.000 1/ 1 ??% ??% ??,?% 0 0 93% ^[
    A:79053.7 V: 0.1 A-V:79053.602 ct: 0.004 2/ 2 ??% ??% ??,?% 0 0 93% ^[
    A:79053.7 V: 0.1 A-V:79053.562 ct: 0.004 3/ 3 ??% ??% ??,?% 0 0 93% ^[
    A:79053.7 V: 0.1 A-V:79053.594 ct: 0.008 3/ 3 ??% ??% ??,?% 0 0 93%
    A:79053.7 V:79054.0 A-V: -0.210 ct: 0.004 4/ 4 ??% ??% ??,?% 0 0 93%
    A:79053.8 V:79054.0 A-V: -0.240 ct: 0.004 5/ 5 ??% ??% ??,?% 0 0 93% ^[
    A:79053.8 V:79054.0 A-V: -0.198 ct: 0.000 5/ 5 ??% ??% ??,?% 0 0 93% ^[
    A:79053.8 V:79054.0 A-V: -0.197 ct: -0.004 6/ 6 ??% ??% ??,?% 0 0 93% ^[
    mplayer: slave cmd: get_time_length
    mplayer: slave cmd: get_percent_pos
    mplayer: slave cmd: get_percent_pos
    mplayer: slave cmd: get_percent_pos
    mplayer: slave cmd: get_percent_pos
    mplayer: slave cmd: get_percent_pos
    mplayer: slave cmd: get_percent_pos



    что характерно - высокая загрузка проца - 93%, сообщения
    mplayer: slave cmd: get_time_length
    mplayer: slave cmd: get_percent_pos


    появляются после 6 кадра, при этом знаки вопроса указывают, что неизвестна загрузка проца для аудио и видео кодека. При этом пропущенных кадров нет.
    Меня настораживает факт, что сообщения
    mplayer: slave cmd: get_time_length
    mplayer: slave cmd: get_percent_pos


    в неудачном логе появляются слишком рано - после воспроизведения 6 кадра. В удачном логе - по другому, они появляются после 18 кадра. При этом на 14 кадре появляется инфа (вместо знаков вопроса) о загрузке процессора.



    mplayer: volume=0 mpVolume=100 mpMute=1
    mplayer: slave cmd: get_time_length
    mplayer: slave cmd: get_percent_pos
    VDec: vo config request - 720 x 576 (preferred colorspace: Mpeg PES)
    VDec: using Mpeg PES as output csp (no 0)
    Movie-Aspect is 1.33:1 - prescaling to correct movie aspect.
    VO: [mpegpes] 720x576 => 768x576 Mpeg PES
    A:79053.7 V: 0.0 A-V:79053.641 ct: 0.000 1/ 1 ??% ??% ??,?% 0 0 93%^J
    A:79053.7 V: 0.1 A-V:79053.602 ct: 0.004 2/ 2 ??% ??% ??,?% 0 0 93%
    A:79053.7 V: 0.1 A-V:79053.562 ct: 0.004 3/ 3 ??% ??% ??,?% 0 0 93%
    A:79053.7 V: 0.1 A-V:79053.594 ct: 0.008 3/ 3 ??% ??% ??,?% 0 0 93%
    A:79053.7 V:79054.0 A-V: -0.210 ct: 0.004 4/ 4 ??% ??% ??,?% 0 0 93%
    A:79053.8 V:79054.0 A-V: -0.240 ct: 0.004 5/ 5 ??% ??% ??,?% 0 0 93%
    A:79053.8 V:79054.0 A-V: -0.198 ct: 0.000 5/ 5 ??% ??% ??,?% 0 0 93%
    A:79053.8 V:79054.0 A-V: -0.197 ct: -0.004 6/ 6 ??% ??% ??,?% 0 0 93%
    A:79053.8 V:79054.1 A-V: -0.237 ct: -0.004 7/ 7 ??% ??% ??,?% 0 0 93%
    A:79053.9 V:79054.1 A-V: -0.198 ct: -0.008 7/ 7 ??% ??% ??,?% 0 0 91%
    A:79053.9 V:79054.1 A-V: -0.196 ct: -0.012 8/ 8 ??% ??% ??,?% 0 0 91%
    A:79053.9 V:79054.2 A-V: -0.225 ct: -0.012 9/ 9 ??% ??% ??,?% 0 0 91%
    A:79054.0 V:79054.2 A-V: -0.184 ct: -0.016 9/ 9 ??% ??% ??,?% 0 0 91%
    A:79054.0 V:79054.2 A-V: -0.182 ct: -0.020 10/ 10 ??% ??% ??,?% 0 0 91%
    79054.0 V:79054.2 A-V: -0.222 ct: -0.020 11/ 11 ??% ??% ??,?% 0 0 91%
    A:79054.1 V:79054.2 A-V: -0.180 ct: -0.024 11/ 11 ??% ??% ??,?% 0 0 91%
    A:79054.1 V:79054.3 A-V: -0.179 ct: -0.028 12/ 12 ??% ??% ??,?% 0 0 86%
    A:79054.1 V:79054.3 A-V: -0.208 ct: -0.028 13/ 13 ??% ??% ??,?% 0 0 86%
    A:79054.1 V:79054.3 A-V: -0.180 ct: -0.032 13/ 13 ??% ??% ??,?% 0 0 86%
    A:79054.2 V:79054.4 A-V: -0.168 ct: -0.036 14/ 14 0% 10% 0.4% 0 0 86%
    A:79054.2 V:79054.4 A-V: -0.208 ct: -0.036 15/ 15 0% 9% 0.4% 0 0 86%
    A:79054.2 V:79054.4 A-V: -0.166 ct: -0.040 15/ 15 0% 9% 0.4% 0 0 86%
    A:79054.3 V:79054.4 A-V: -0.169 ct: -0.044 16/ 16 0% 10% 0.4% 0 0 86%
    A:79054.3 V:79054.5 A-V: -0.209 ct: -0.044 17/ 17 0% 10% 0.4% 0 0 86%
    A:79054.3 V:79054.5 A-V: -0.167 ct: -0.048 17/ 17 0% 10% 0.4% 0 0 86%
    A:79054.4 V:79054.5 A-V: -0.157 ct: -0.052 18/ 18 0% 11% 0.4% 0 0 84%
    mplayer: slave cmd: get_time_length
    mplayer: slave cmd: get_percent_pos
    A:79054.4 V:79054.6 A-V: -0.197 ct: -0.052 19/ 19 0% 10% 0.4% 0 0 84%
    A:79054.4 V:79054.6 A-V: -0.156 ct: -0.056 19/ 19 0% 10% 0.4% 0 0 84%
    A:79054.4 V:79054.6 A-V: -0.154 ct: -0.060 20/ 20 0% 11% 0.4% 0 0 84%
    A:79054.4 V:79054.6 A-V: -0.194 ct: -0.060 21/ 21 0% 10% 0.4% 0 0 84%
    A:79054.5 V:79054.6 A-V: -0.152 ct: -0.064 21/ 21 0% 10% 0.4% 0 0 84%
    A:79054.5 V:79054.7 A-V: -0.151 ct: -0.068 22/ 22 0% 11% 0.4% 0 0 84%
    A:79054.5 V:79054.7 A-V: -0.180 ct: -0.068 23/ 23 0% 12% 0.4% 0 0 84%
    A:79054.6 V:79054.7 A-V: -0.139 ct: -0.072 23/ 23 0% 12% 0.4% 0 0 84%
    A:79054.6 V:79054.8 A-V: -0.147 ct: -0.076 24/ 24 0% 12% 0.5% 0 0 80%
    A :79054.6 V:79054.8 A-V: -0.187 ct: -0.076 25/ 25 0% 12% 0.5% 0 0 80%




    вот как бы настроить так, чтобы мплеер не торопился выдавать запросы типа
    mplayer: slave cmd: get_time_length
    mplayer: slave cmd: get_percent_pos

    в загашниках у free-x оказалась еще одна версия mplayer с другими опциями для компиляции, с которой все пошло воспроизводиться без проблем - vob, mpeg4, ts и mpeg4
    вопрос закрыт

    Цитата

    Со слов пользователя Goga777
    в загашниках у free-x оказалась еще одна версия mplayer с другими опциями для компиляции, с которой все пошло воспроизводиться без проблем - vob, mpeg4, ts и mpeg4
    вопрос закрыт


    Вопрос возник теперь у меня. Стоит VEDRO уже не помню какой версии, ставилось все еще года полтора-два назад. Проблема в том, что последнее время все больше и больше файлов, кодированных чем-то что не воспроизводится (например dx50). apt-get update; apt-get upgrade; apt-get dist-upgrade не помогли. Решил пересобрать mplayer из сорцов.
    Нашел инструкцию Пожалуйста зарегистрируйся для просмотра данной ссылки на страницу. , кстати неполную, и понеслось. В конце концов собрал. И такое чуство что он даже работает, но вот только темный экран и звук идет от канала, который показывал до этого. При этом процесс mplayer добросовестно работает. По команде "прекратить воспроизведение" возвращаемся к просмотру канала.

    Старый бинарник сохранен и если указать на него в mplayer.sh.conf (новый лежит в /usr/local/bin ) то работает как и раньше. Т.е. проблема в собранном mplayer.


    Есть идеи? Опции компиляции какие-то специфические?

    ну, так ты хоть логи покажи - на что там мплеер ругается. Про логи вики почитай.
    а опции для компиляции - обычные
    ./configure --enable-runtime-cpudetection --enable-largefiles --enable-menu --enable-dvb


    у меня правда тоже не получилось свежий мплеер запустить на вдр - при попытке проигрывания любого файла, он затыкается на 4-м кадре, висит, так и не показав ничего.
    вот тут я описал подробности свой проблемы
    Пожалуйста зарегистрируйся для просмотра данной ссылки на страницу.

    записал я на дриме с 40е из мпег4 пакета фрагмент МУЗТВ. Закинул на вдр - с помощью командной строки воспроизвел фрагмент нормально



    arvdr:~# /usr/bin/mplayer -vo mpegpes -ao mpegpes -nolirc -msglevel all=5 /data/mplayer/1muz.ts
    MPlayer 1.0pre8-3.3.5 (C) 2000-2006 MPlayer Team
    CPU: AMD Sempron(tm) Processor 2800+ (Family: 15, Model: 44, Stepping: 2)
    CPUflags: MMX: 1 MMX2: 1 3DNow: 1 3DNow2: 1 SSE: 1 SSE2: 1
    Compiled with runtime CPU detection.



    Playing /data/mplayer/1muz.ts.
    TS file format detected.
    DEMUX OPEN, AUDIO_ID: -1, VIDEO_ID: -1, SUBTITLE_ID: -2,
    PROBING UP TO 2000000, PROG: 0
    VIDEO H264(pid=538)...AUDIO MPA(pid=725) NO SUBS (yet)! PROGRAM N. 18
    Opened TS demuxer, audio: 50(pid 725), video: 10000005(pid 538)...POS=752, PROBE=2000000
    FPS seems to be: 25.000000
    ==========================================================================
    Opening audio decoder: [mp3lib] MPEG layer-2, layer-3
    AUDIO: 48000 Hz, 2 ch, s16le, 128.0 kbit/8.33% (ratio: 16000->192000)
    Selected audio codec: [mp3] afm: mp3lib (mp3lib MPEG layer-2, layer-3)
    ==========================================================================
    Opening /dev/dvb/adapter0/video0+audio0
    ==========================================================================
    Opening video decoder: [ffmpeg] FFmpeg's libavcodec codec family
    Selected video codec: [ffh264] vfm: ffmpeg (FFmpeg H.264)
    ==========================================================================
    AO: [mpegpes] 48000Hz 2ch s16le (2 bytes per sample)
    Starting playback...
    VDec: vo config request - 720 x 576 (preferred colorspace: Planar YV12)
    Could not find matching colorspace - retrying with -vf scale...
    Opening video filter: [scale]
    Opening video filter: [lavc]
    VDec: using Planar YV12 as output csp (no 0)
    Movie-Aspect is 1.36:1 - prescaling to correct movie aspect.
    [mpeg1video @ 0x86b8c00]removing common factors from framerate
    VO: [mpegpes] 720x576 => 786x576 Mpeg PES
    A:92662.0 V:92663.2 A-V: -1.214 ct: -0.356 90/ 90 33% 33% 0.4% 0 0



    а вот через OSD с помощью плагина mplayer что-то не завелось



    Jul 11 00:02:14 arvdr logger: *** Starting mplayer.sh Version 0.8.7
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable CFGFIL has value "/usr/bin/mplayer.sh.conf"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable USEAC3 has value "false"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable AC3AOUT has value "-ac hwac3"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable TV_ASPECT has value "4/3"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable PAL has value "true"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable NTSC has value "true"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable USE_SPEED has value "false"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable DETC_FILTER has value "detc=dr=2:am=1"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable MPLAYER has value "/usr/bin/mplayer"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable VOP has value "lavc=5000"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable VO has value "mpegpes:card=1"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable AO has value "mpegpes:card=1"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable CACHE has value "15777"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable CACHESTR has value "-cache 15777"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable FRAMEDROP has value "false"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable FDSTR has value ""
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable LIRCRC has value "/video/.lircrc"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable LIRCSTR has value "-lircconf /video/.lircrc"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable SUBTITLE has value " -subpos 80 -sub-bg-color 0 -sub-bg-alpha 30"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable REMOTE has value "-slave -nolirc"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable USERDEF has value ""
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable XResPAL has value "352 480 528 544 704 720"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable XResNTSC has value "352 480 512 544 640 704 720"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable SLOW_CPU has value "false"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: *** Option DVDFiles not set correctly! You will not be able to play VCD/DVD ""
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable DVDFiles has value ""
    Jul 11 00:02:14 arvdr logger: *** DEBUG: *** Option DVD not set correctly! You will not be able to play VCD/DVD ""
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable DVD has value ""
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable DVDLANG has value "en"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable DVDOPTIONS has value ""
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable VCDOPTIONS has value ""
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable MPEG_DIRECT has value "false"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable SUFFIX has value "z.ts"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Variable MPLAYER_V1 has value "true"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: Calling getvidxy function to analyze source video stream ...
    Jul 11 00:02:14 arvdr logger: *** DEBUG: MPLAYER_RETURN: "0"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: parsed output for ORIG_X: "0"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: parsed output for ORIG_Y: "0"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: parsed output for ORIG_FPS: "25.000"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: parsed output for ORIG_ASPECT: "0.0000"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: parsed output for VIDEO_FORMAT: "0x10000005"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: parsed output for AUDIO_CODEC: "mp3"
    Jul 11 00:02:14 arvdr logger: *** INFO: Source Video has Resolution of 0 x 0 ...
    Jul 11 00:02:14 arvdr logger: *** FATAL: something went wrong analyzing the video; check your mplayer version ...




    думаю, что проблема в строчках



    Jul 11 00:02:14 arvdr logger: *** DEBUG: parsed output for ORIG_X: "0"
    Jul 11 00:02:14 arvdr logger: *** DEBUG: parsed output for ORIG_Y: "0"



    должно быть , как с удачной попыткой




    ul 10 23:53:37 arvdr logger: *** DEBUG: parsed output for ORIG_Y: "576"
    Jul 10 23:53:37 arvdr logger: *** DEBUG: parsed output for ORIG_FPS: "25.000"
    Jul 10 23:53:37 arvdr logger: *** DEBUG: parsed output for ORIG_ASPECT: "0.0000"
    Jul 10 23:53:37 arvdr logger: *** DEBUG: parsed output for VIDEO_FORMAT: "0x10000002"
    Jul 10 23:53:37 arvdr logger: *** DEBUG: parsed output for AUDIO_CODEC: "mp3"
    Jul 10 23:53:37 arvdr logger: *** INFO: Source Video has Resolution of 704 x 576 ...
    Jul 10 23:53:37 arvdr logger: *** DEBUG: PAL or unknown "25.000"
    Jul 10 23:53:37 arvdr logger: *** DEBUG: Variable MAX_X has value "768"
    Jul 10 23:53:37 arvdr logger: *** DEBUG: Variable NEW_Y has value "628"


    но вот почему плагин неправльно определил разрешение - тут я так и не смог понять...

    отчет
    записан фрагменты с 40Е 5 канал и hd-test
    первое и самое главное - PAFF не используется!!!!


    второе - нагрузка при распаковке приличная
    файл 07-07-10\ -\ 5\ Kanal\ -\ .ts
    1) вывод на экран монитора в иксах

    Исходный код
    # mplayer -msglevel all=5 07-07-10\ -\ 5\ Kanal\ -\ .ts


    нагрузка 123% 32% 1.2%


    2) вывод туда же только даем команду mplayer особо не стараться -vfm ffmpeg -lavdopts lowres=1:fast:skiploopfilter=all

    Исходный код
    # mplayer -vfm ffmpeg -lavdopts lowres=1:fast:skiploopfilter=all  -msglevel all=5 07-07-10\ -\ 5\ Kanal\ -\ .ts


    нагрузка 48% 22% 0.7% - упала в 2 раза


    3) выводим видео через СС1 на телик -vo mpegpes

    Исходный код
    # mplayer -vo mpegpes -vfm ffmpeg -lavdopts lowres=1:fast:skiploopfilter=all  -msglevel all=5 07-07-10\ -\ 5\ Kanal\ -\ .ts


    нагрузка 61 -29-0.9 - при выводе через СС1 нагрузка вырастает в 1.5 раза по
    сравнению с выводом через видяху


    4) вывод и видео и звука через СС1 -vo mpegpes -ao mpegpes

    Исходный код
    # mplayer -vo mpegpes -ao mpegpes -vfm ffmpeg -lavdopts lowres=1:fast:skiploopfilter=all  -msglevel all=5 07-07-10\ -\ 5\ Kanal\ -\ .ts


    нагрузка 76-33-1.0 - вывод звука через СС1 добавил свои 10%


    5) а это выводим напрямую на СС1 без упрощения

    Исходный код
    # mplayer -vo mpegpes -ao mpegpes  -msglevel all=5 07-07-10\ -\ 5\ Kanal\ -\ .ts


    нагрузка 120-32-0.7

    а если скармливать вдр-у этот файл через osd меню с помощью плагина мплеер ? идет картинка ?
    и какая версия мплеер у тебя ? есть мнение, что svn версия - самая лучшая для h264 контента - ресурсов меньше потребляет, т.к. авторы ffmpeg постоянно оптимизируют свой продукт.
    (я на компе как раз запускал самую свежую версию мплеер)

    Цитата

    Со слов пользователя Goga777
    ну, так ты хоть логи покажи - на что там мплеер ругается. Про логи вики почитай.


    Самое забавное, что ни на что не ругается. Все ОК, только не работает ;-)


    Jul 5 23:25:03 arvdr logger: *** Starting mplayer.sh Version 0.8.6
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable CFGFIL has value "/usr/bin/mplayer.sh.conf"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable USEAC3 has value "false"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable AC3AOUT has value "-ao alsa9 -ac hwac3"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable TV_ASPECT has value "4/3"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable PAL has value "true"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable NTSC has value "true"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable USE_SPEED has value "true"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable DETC_FILTER has value "detc=dr=2:am=1"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable MPLAYER has value "/usr/local/bin/mplayer"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable VOP has value "lavc=5000"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable VO has value "mpegpes"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable AO has value "mpegpes"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable CACHE has value "4096"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable CACHESTR has value "-cache 4096"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable FRAMEDROP has value "true"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable FDSTR has value "-framedrop"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable LIRCRC has value "/video/.lircrc"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable LIRCSTR has value "-lircconf /video/.lircrc"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable SUBTITLE has value " -subpos 80 -sub-bg-color 0 -sub-bg-alpha 30"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable REMOTE has value "-slave -nolirc"
    Jul 5 23:25:03 arvdr logger: *** Use Option USERDEF at your own risk!
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable USERDEF has value "-quiet"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable XResPAL has value "352 480 528 544 688 704 720"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable XResNTSC has value "352 480 512 640 704 720"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable SLOW_CPU has value "false"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: *** Option DVDFiles not set correctly! You will not be able to play VCD/DVD ""
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable DVDFiles has value ""
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable DVD has value "/dev/hdc"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable DVDLANG has value "de"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable DVDOPTIONS has value "-aop list=volume:volume=170"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable VCDOPTIONS has value ""
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable MPEG_DIRECT has value "true"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable SUFFIX has value ".avi"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Variable MPLAYER_V1 has value "true"
    Jul 5 23:25:03 arvdr logger: *** DEBUG: Calling getvidxy function to analyze source video stream ...
    Jul 5 23:25:04 arvdr logger: *** DEBUG: OutputFromMPLAYER: "ID_VIDEO_ID=0 ID_AUDIO_ID=1 ID_CLIP_INFO_NAME0=Software ID_CLIP_INFO_VALUE0=Nandub v1.0rc2 ID_CLIP_INFO_N=1 ID_FILENAME=/pub/mplayer/antikiller.avi ID_DEMUXER=avi ID_VIDEO_FORMAT=DIV3 ID_VIDEO_BITRATE=710528 ID_VIDEO_WIDTH=512 ID_VIDEO_HEIGHT=288 ID_VIDEO_FPS=25.000 ID_VIDEO_ASPECT=0.0000 ID_AUDIO_FORMAT=85 ID_AUDIO_BITRATE=123016 ID_AUDIO_RATE=0 ID_AUDIO_NCH=0 ID_LENGTH=6791.44 ID_VIDEO_CODEC=ffdivx ID_AUDIO_BITRATE=32000 ID_AUDIO_RATE=48000 ID_AUDIO_NCH=2 ID_AUDIO_CODEC=mp3"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: MPLAYER_RETURN: "0"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: parsed output for ORIG_X: "512"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: parsed output for ORIG_Y: "288"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: parsed output for ORIG_FPS: "25.000"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: parsed output for ORIG_ASPECT: "0.0000"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: parsed output for VIDEO_FORMAT: "DIV3"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: parsed output for AUDIO_CODEC: "mp3"
    Jul 5 23:25:04 arvdr logger: *** INFO: Source Video has Resolution of 512 x 288 ...
    Jul 5 23:25:04 arvdr logger: *** DEBUG: PAL or unknown "25.000"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: Variable MAX_X has value "768"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: Variable NEW_Y has value "432"
    Jul 5 23:25:04 arvdr logger: *** INFO: For Sqare Pixels we would scale to 768 x 432 ...
    Jul 5 23:25:04 arvdr logger: *** DEBUG: Variable XResTEMP has value "352 480 528 544 688 704 720"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: Variable AnzahlVonXResTEMP has value "7"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: Variable NEW_X has value "528"
    Jul 5 23:25:04 arvdr logger: *** DEBUG: setting REAL_Y = FULL_Y ""
    Jul 5 23:25:04 arvdr logger: *** DEBUG: Variable CMDLINE has value "/usr/local/bin/mplayer -vo mpegpes -ao mpegpes -vop lavc=5000:25,expand=528:576:-1:-1:1,scale=528:432 -framedrop -cache 4096 -slave -nolirc -subpos 80 -sub-bg-color 0 -sub-bg-alpha 30 -quiet "