Commercial Skipping not functioning.

Help
Wob
2007-05-28
2013-04-08
  • Wob

    Wob - 2007-05-28

    Hey Guys,
       I hope this hasn't been covered, I did search the archive with no success.  It's not the time zone issue that was mention previously.  I am able to see the play+skip button when a recording has markings, but when I play nothing happens, it just plays like I hit play.

    I am using the following;
    T3CH release of XBMC (2007-05-20)
    XBMCMythTV Version: 0.20.34
    and MythTV 0.20 (KnoppMyth R5F1 to be exact)

    Here is a snippet of the logs, if you need more let me know.

    Cheers,
    Wob

    xbmcmythtv.log
    2007-05-28 23:23:46.145000 [27] *** Loading Cold Case - Red and the Blue Please Wait...: Cold Case - Red and the Blue - When Jeffries' former partner comes forth with new evidence of the 2000 murder of a country singer, Lilly must travel to Knoxville, Tennessee with the reluctant Scotty in tow.

    Rating: M

    Credits/Ca
    2007-05-28 23:23:47.731000 [27] > mythtvrecordedshowdetails.Window.loadShowDetails()
    2007-05-28 23:23:47.767000 [27] > mythtvrecordedshowdetails.Window.populateShowDetails()
    2007-05-28 23:23:47.814000 [27] show_title=[Cold Case - Red and the Blue]
    2007-05-28 23:23:47.863000 [27] show_air_date=[Wednesday May 16, 08:25PM - 09:40PM]
    2007-05-28 23:23:47.900000 [27] show_channel=[8 NBN]
    2007-05-28 23:23:47.945000 [27] show_orig_air=[2007]
    2007-05-28 23:23:47.990000 [27] show_descr=[When Jeffries' former partner comes forth with new evidence of the 2000 murder of a country singer, Lilly must travel to Knoxville, Tennessee with the reluctant Scotty in tow.

    Rating: M

    Credits/Ca]
    2007-05-28 23:23:48.033000 [27] show_category=[Drama]
    2007-05-28 23:23:48.079000 [27] show_autoexpire=[No]
    2007-05-28 23:23:48.130000 [27] > getCommMarkup (1008, 20070516202500)
    2007-05-28 23:23:48.166000 [27]    SQL: [
                SELECT
                    mark,
                    type
                FROM
                    recordedmarkup
                WHERE
                    chanid = 1008
                    and starttime = 20070516202500
                    and type in (4, 5)  -- 4=comm start, 5=comm stop
                ORDER BY
                    mark
            ]
    2007-05-28 23:23:48.216000 [27] < getCommMarkup [[{'type': '4', 'mark': '29668'}, {'type': '5', 'mark': '36614'}, {'type': '4', 'mark': '42818'}, {'type': '5', 'mark': '48936'}, {'type': '4', 'mark': '55242'}, {'type': '5', 'mark': '61571'}, {'type': '4', 'mark': '77740'}, {'type': '5', 'mark': '79614'}, {'type': '4', 'mark': '92090'}, {'type': '5', 'mark': '98677'}, {'type': '4', 'mark': '102822'}, {'type': '5', 'mark': '104397'}, {'type': '4', 'mark': '117717'}, {'type': '5', 'mark': '121466'}]]
    2007-05-28 23:23:48.255000 [27] show_category=[Drama]
    2007-05-28 23:23:48.299000 [27] < mythtvrecordedshowdetails.Window.populateShowDetails()
    2007-05-28 23:23:48.344000 [27] > mythtv.Settings.getSetting( tag=[mythtv_host] )
    2007-05-28 23:23:48.389000 [27] < mythtv.Settings.getSetting( tag=[mythtv_host] ) => [192.168.0.250]
    2007-05-28 23:23:48.984000 [27] hostIp: 192.168.0.250
    2007-05-28 23:23:49.078000 [27] < mythtvrecordedshowdetails.Window.loadShowDetails()
    2007-05-28 23:23:51.807000 [28] > mythtvrecordedshowdetails.Window.onControlHook()
    2007-05-28 23:23:51.848000 [28] > mythtvrecordedshowdetails.Window.play()
    2007-05-28 23:23:51.893000 [28]  File Size: 3947930.00391
    2007-05-28 23:23:51.937000 [28] > mythtv.Settings.getSetting( tag=[mythtv_recplayer] )
    2007-05-28 23:23:51.982000 [28] < mythtv.Settings.getSetting( tag=[mythtv_recplayer] ) => [0]
    2007-05-28 23:23:52.026000 [28] Using MPlayer for Recorded Show Playback
    2007-05-28 23:23:52.070000 [28] > mythtvstruct.ProgramFromRecordings.remotePath()
    2007-05-28 23:23:52.114000 [28] > mythtvrecordedshowdetails.ProgramDetails.remoteMythPath()
    2007-05-28 23:23:52.159000 [28] < mythtvrecordedshowdetails.ProgramDetails.remoteMythPath() => [myth://192.168.0.250:6543/1007_20070516202500_20070516214000.nuv]
    2007-05-28 23:23:52.203000 [28] > mythtv.Settings.getSetting( tag=[paths_recordedprefix] )
    2007-05-28 23:23:52.248000 [28] < mythtv.Settings.getSetting( tag=[paths_recordedprefix] ) => [smb://mythtv:mythtv@%h/myth/tv]
    2007-05-28 23:23:52.292000 [28] > mythtv.Settings.getSetting( tag=[mythtv_host] )
    2007-05-28 23:23:52.337000 [28] < mythtv.Settings.getSetting( tag=[mythtv_host] ) => [192.168.0.250]
    2007-05-28 23:23:52.455000 [28] Host: mythtv hostIp: 192.168.0.250
    2007-05-28 23:23:52.494000 [28] < mythtvp.ProgramDetails.remotePath() => [smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv]
    2007-05-28 23:24:02.127000 [15]  > onPlayBackStarted - doCommSkip: True Markups: [{'type': '4', 'mark': '29668'}, {'type': '5', 'mark': '36614'}, {'type': '4', 'mark': '42818'}, {'type': '5', 'mark': '48936'}, {'type': '4', 'mark': '55242'}, {'type': '5', 'mark': '61571'}, {'type': '4', 'mark': '77740'}, {'type': '5', 'mark': '79614'}, {'type': '4', 'mark': '92090'}, {'type': '5', 'mark': '98677'}, {'type': '4', 'mark': '102822'}, {'type': '5', 'mark': '104397'}, {'type': '4', 'mark': '117717'}, {'type': '5', 'mark': '121466'}]
    2007-05-28 23:24:02.251000 [15] > findMarkupIndex( pType=4, pPos=0.0 )
    2007-05-28 23:24:02.361000 [15] < findMarkupIndex => [0]
    2007-05-28 23:24:02.458000 [15] getTotalTime=5095.0
    2007-05-28 23:24:03.094000 [14] lastPos=[0.0] curPos=[0.0]
    2007-05-28 23:24:03.227000 [14] curPos=[0.0] nextStart=[989.9]
    2007-05-28 23:24:03.825000 [14] lastPos=[0.0] curPos=[0.0]
    2007-05-28 23:24:03.881000 [16] curPos=[0.0] nextStart=[989.9]
    2007-05-28 23:24:04.455000 [14] lastPos=[0.0] curPos=[0.0]
    2007-05-28 23:24:04.535000 [13] curPos=[0.0] nextStart=[989.9]
    2007-05-28 23:24:05.112000 [10] lastPos=[0.0] curPos=[0.0]
    2007-05-28 23:24:05.156000 [10] curPos=[0.0] nextStart=[989.9]
    2007-05-28 23:24:05.714000 [10] lastPos=[0.0] curPos=[1.0]
    2007-05-28 23:24:05.760000 [10] curPos=[1.0] nextStart=[989.9]
    2007-05-28 23:24:06.304000 [10] lastPos=[1.0] curPos=[1.0]
    2007-05-28 23:24:06.343000 [10] curPos=[1.0] nextStart=[989.9]
    2007-05-28 23:24:06.887000 [10] lastPos=[1.0] curPos=[2.0]
    2007-05-28 23:24:06.931000 [10] curPos=[2.0] nextStart=[989.9]
    2007-05-28 23:24:07.480000 [10] lastPos=[2.0] curPos=[2.0]
    2007-05-28 23:24:07.520000 [10] curPos=[2.0] nextStart=[989.9]
    2007-05-28 23:24:08.065000 [10] lastPos=[2.0] curPos=[3.0]
    2007-05-28 23:24:08.106000 [10] curPos=[3.0] nextStart=[989.9]
    2007-05-28 23:24:08.650000 [10] lastPos=[3.0] curPos=[4.0]
    2007-05-28 23:24:08.694000 [10] curPos=[4.0] nextStart=[989.9]
    2007-05-28 23:24:09.242000 [10] lastPos=[4.0] curPos=[4.0]
    2007-05-28 23:24:09.282000 [10] curPos=[4.0] nextStart=[989.9]
    2007-05-28 23:24:09.837000 [10] lastPos=[4.0] curPos=[5.0]
    2007-05-28 23:24:09.881000 [10] curPos=[5.0] nextStart=[989.9]
    2007-05-28 23:24:10.426000 [10] lastPos=[5.0] curPos=[5.0]
    2007-05-28 23:24:10.468000 [10] curPos=[5.0] nextStart=[989.9]
    2007-05-28 23:24:11.012000 [10] lastPos=[5.0] curPos=[6.0]
    2007-05-28 23:24:11.056000 [10] curPos=[6.0] nextStart=[989.9]

    xbmc.log
    23:23:52 M: 29351936   DEBUG: CPlayerCoreFactor::GetPlayers(smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv)
    23:23:52 M: 28827648    INFO: mplayer play:smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv cachesize:2048
    23:23:52 M: 28827648   DEBUG: CUtil::CacheSubtitles: START
    23:23:52 M: 28827648   DEBUG: CUtil::CacheSubtitles: Checking for common subirs...
    23:23:53 M: 28827648   DEBUG: CUtil::CacheSubtitles: Done (time: 254 ms)
    23:23:53 M: 28827648   DEBUG: CUtil::CacheSubtitles: Searching for subtitles...
    23:23:53 M: 28827648   DEBUG: DIRECTORY::CSMBDirectory::OpenDir - Using authentication url smb://mythtv:mythtv@192.168.0.250/myth/tv
    23:23:53 M: 28717056    INFO: Loading skin file: DialogBusy.xml
    23:23:54 M: 28598272   DEBUG: CUtil::CacheSubtitles: Done (time: 1342 ms)
    23:23:54 M: 28598272   DEBUG: CUtil::CacheSubtitles: END (total time: 1641 ms)
    23:23:55 M: 21524480   DEBUG:   msg:  set audio functions called
    23:23:55 M: 21524480   DEBUG:   msg:  done
    23:23:55 M: 21524480    INFO: Using -subcp enca:__:CP1252 to detect the subtitle charset
    23:23:55 M: 21524480    INFO: Flipping bi-directional subtitles disabled
    23:23:55 M: 21524480   DEBUG:   msg: mplayer_init()
    23:23:55 M: 21524480   DEBUG:   msg: enable mplayer osd
    23:23:55 M: 21524480   DEBUG:   msg: MPlayer XBMC-SVN-r8532 (C) 2000-2004 MPlayer Team
    23:23:55 M: 21524480   DEBUG:   msg: CPU: Intel Celeron 2/Pentium III Coppermine,Geyserville
    23:23:55 M: 21524480   DEBUG:   msg: (Family: 6, Stepping: 10)
    23:23:55 M: 21524480   DEBUG:   msg: Detected cache-line size is 32 bytes
    23:23:55 M: 21524480   DEBUG:   msg: CPUflags:  MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 0 SSE2: 0
    23:23:55 M: 21524480   DEBUG:   msg: Compiled for x86 CPU with extensions:
    23:23:55 M: 21524480   DEBUG:   msg:  MMX
    23:23:55 M: 21524480   DEBUG:   msg:  MMX2
    23:23:55 M: 21524480   DEBUG:   msg:  SSE
    23:23:55 M: 21524480   DEBUG:   msg:
    23:23:55 M: 21512192    INFO: Loading skin file: DialogProgress.xml
    23:23:55 M: 21512192   DEBUG:   msg:
    23:23:56 M: 21331968   DEBUG:   msg: Creating config file: Q:\system\players\mplayer\config
    23:23:56 M: 21319680   DEBUG:   msg:
    23:23:56 M: 21331968   DEBUG:   msg: get_path('codecs.conf') -> 'Q:\system\players\mplayer\codecs.conf'
    23:23:56 M: 21331968   DEBUG:   msg: Reading Q:\system\players\mplayer\codecs.conf:
    23:23:56 M: 20963328   DEBUG:   msg: 107 audio & 226 video codecs
    23:23:56 M: 20963328   DEBUG:   msg: CommandLine:
    23:23:56 M: 20963328   DEBUG:   msg:  '-dr'
    23:23:56 M: 20963328   DEBUG:   msg:  '-v'
    23:23:56 M: 20963328   DEBUG:   msg:  '-subcp'
    23:23:56 M: 20963328   DEBUG:   msg:  'enca:__:CP1252'
    23:23:56 M: 20963328   DEBUG:   msg:  '-channels'
    23:23:56 M: 20963328   DEBUG:   msg:  '6'
    23:23:56 M: 20963328   DEBUG:   msg:  '-a52drc'
    23:23:56 M: 20963328   DEBUG:   msg:  '1'
    23:23:56 M: 20963328   DEBUG:   msg:  '-noflip-hebrew'
    23:23:57 M: 20963328   DEBUG:   msg:  '-noflip-hebrew-commas'
    23:23:57 M: 20963328   DEBUG:   msg:  '-fs'
    23:23:57 M: 20963328   DEBUG:   msg:  '1.avi'
    23:23:57 M: 20963328   DEBUG:   msg:
    23:23:57 M: 20963328   DEBUG:   msg: get_path('font/font.desc') -> 'Q:\system\players\mplayer\font/font.desc'
    23:23:57 M: 20430848   DEBUG:   msg: font: can't open file: Q:\system\players\mplayer\font/font.desc
    23:23:57 M: 20430848   DEBUG:   msg: font: can't open file: Q:\system\players\mplayer/font/font.desc
    23:23:57 M: 20963328   DEBUG:   msg: Using MMX (with tiny bit MMX2) Optimized OnScreenDisplay
    23:23:57 M: 20938752   DEBUG:   msg: Using XBOX High Resolution Timers for timing
    23:23:57 M: 20938752   DEBUG:   msg: get_path('input.conf') -> 'Q:\system\players\mplayer\input.conf'
    23:23:57 M: 20938752   DEBUG:   msg: Parsing input config file Q:\system\players\mplayer\input.conf
    23:23:57 M: 20930560   DEBUG:   msg: Input config file Q:\system\players\mplayer\input.conf parsed: 51 binds
    23:23:57 M: 20930560   DEBUG:   msg: mplayer_open_file(smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv)
    23:23:57 M: 20930560   DEBUG:   msg: get_path('1007_20070516202500_20070516214000.nuv.conf') -> 'Q:\system\players\mplayer\1007_20070516202500_20070516214000.nuv.conf'
    23:23:57 M: 20930560   DEBUG:   msg:
                                 Playing smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv.
    23:23:57 M: 20930560   DEBUG:   msg: find subtitles
    23:23:57 M: 20930560   DEBUG:   msg: auto open Z:\subtitle
    23:23:57 M: 20930560   DEBUG:   msg: Filename for url is now smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv
    23:23:57 M: 20930560   DEBUG:   msg: protocol:smb
    23:23:57 M: 20930560   DEBUG:   msg: use standard file for protocol:smb
    23:23:57 M: 20930560   DEBUG:   msg: Option stream url: This URL doesn't have a username part.
    23:23:57 M: 20930560   DEBUG:   msg: Option stream url: This URL doesn't have a hostname part.
    23:23:58 M: 20910080   DEBUG:   msg: WINSOCK2 init: 0
    23:23:58 M: 20910080   DEBUG:   msg: [file] File size is -252286972 bytes
    23:23:58 M: 20910080   DEBUG:   msg: STREAM: [file] smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv
    23:23:58 M: 20910080   DEBUG:   msg: STREAM: Description: File
    23:23:58 M: 20910080   DEBUG:   msg: STREAM: Author: Albeu
    23:23:58 M: 20910080   DEBUG:   msg: STREAM: Comment: based on the code from ??? (probably Arpi)
    23:23:58 M: 18259968   DEBUG:   msg: CACHE_PRE_INIT: 0 [0] 0  pre:0  eof:0
    23:23:58 M: 18259968   DEBUG:   msg:
    Cache fill:  6.25% (131072 bytes)
    23:23:58 M: 18259968   DEBUG:   msg:
    Cache fill: 15.63% (327680 bytes)
    23:23:58 M: 18259968   DEBUG:   msg: demux_open(00EDE020,0,-1,-1,-1)
    23:23:58 M: 18259968   DEBUG:   msg: Checking for YUV4MPEG2
    23:23:58 M: 18259968   DEBUG:   msg: ASF_check: not ASF guid!
    23:23:58 M: 18259968   DEBUG:   msg: Checking for Nullsoft Streaming Video
    23:23:58 M: 18259968   DEBUG:   msg: Checking for NuppelVideo
    23:23:58 M: 18259968   DEBUG:   msg: Checking for REAL
    23:23:58 M: 18259968   DEBUG:   msg: Checking for SMJPEG
    23:23:58 M: 18259968   DEBUG:   msg: SUB: opened iconv descriptor.
    23:23:58 M: 18259968   DEBUG:   msg: Searching demuxer type for filename smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv ext: .nuv
    23:23:58 M: 18259968   DEBUG:   msg: Checking for MOV
    23:23:58 M: 18259968   DEBUG:   msg: Checking for VIVO
    23:23:59 M: 18259968   DEBUG:   msg: header block 1 size: 0
    23:23:59 M: 18259968   DEBUG:   msg: SUB: opened iconv descriptor.
    23:23:59 M: 18259968   DEBUG:   msg: SUB: closed iconv descriptor.
    23:23:59 M: 18264064   DEBUG:   msg: Checking for PVA
    23:23:59 M: 18264064   DEBUG:   msg: Checking for MPEG-TS...
    23:23:59 M: 18264064   DEBUG:   msg: TRIED UP TO POSITION 68237, FOUND 47, packet_size= 0, SEEMS A TS? 0
    23:23:59 M: 18264064   DEBUG:   msg: Checking for LMLM4 Stream Format
    23:23:59 M: 18264064   DEBUG:   msg: Invalid packet in LMLM4 stream: ch=0 size=1140851704
    23:23:59 M: 18264064   DEBUG:   msg: LMLM4 Stream Format not found
    23:23:59 M: 18264064   DEBUG:   msg: system stream synced at 0xD (13)!
    23:23:59 M: 18264064   DEBUG:   msg: ==> Found video stream: 0
    23:23:59 M: 18264064   DEBUG:   msg: ==> Found audio stream: 0
    23:23:59 M: 18264064   DEBUG:   msg: MPEG-PS file format detected.
    23:23:59 M: 18264064   DEBUG:   msg: Searching for sequence header...
    23:23:59 M: 18264064   DEBUG:   msg: OK!
    23:23:59 M: 17211392   DEBUG:   msg: VIDEO:  MPEG2  720x576  (aspect 3)  25.000 fps  6000.0 kbps (750.0 kbyte/s)
    23:23:59 M: 17211392   DEBUG:   msg: [V] filefmt:2  fourcc:0x10000002  size:720x576  fps:25.00  ftime:=0.0400
    23:23:59 M: 17211392   DEBUG:   msg: get_path('sub/') -> 'Q:\system\players\mplayer\sub/'
    23:23:59 M: 17211392   DEBUG:   msg: Starting lookup for subs
    23:23:59 M: 17211392   DEBUG:   msg: Global Subsize: 0 - VOBSUB: -1, DEMUX: -1, SUBS: -1
    23:24:00 M: 17211392   DEBUG:   msg: Global Subpos : -1
    23:24:00 M: 17211392   DEBUG:   msg: ==========================================================================
    23:24:00 M: 17211392   DEBUG:   msg: Opening audio decoder: [mp3lib] MPEG layer-2, layer-3
    23:24:00 M: 17211392   DEBUG:   msg: dec_audio: Allocating 4608 + 65536 = 70144 bytes for output buffer.
    23:24:00 M: 17137664   DEBUG:   msg: mp3lib: made decode tables with MMX optimization
    23:24:00 M: 17137664   DEBUG:   msg: mp3lib: using MMX optimized decore!
    23:24:00 M: 17137664   DEBUG:   msg: MP3lib: init layer2&3 finished, tables done
    23:24:00 M: 17137664   DEBUG:   msg:
    MPEG 1.0, Layer II, 48000 Hz 256 kbit Stereo, BPF: 768
    23:24:00 M: 17137664   DEBUG:   msg: Channels: 2, copyright: No, original: No, CRC: Yes, emphasis: 0
    23:24:00 M: 17137664   DEBUG:   msg: AUDIO: 48000 Hz, 2 ch, 16 bit (0x10), ratio: 32000->192000 (256.0 kbit)
    23:24:00 M: 17137664   DEBUG:   msg: Selected audio codec: [mp3] afm:mp3lib (mp3lib MPEG layer-2, layer-3)
    23:24:00 M: 17137664   DEBUG:   msg: ==========================================================================
    23:24:00 M: 17137664   DEBUG: CXBoxRenderManager::PreInit - Selected RGB-Renderer
    23:24:00 M: 17137664   DEBUG:   msg: ==========================================================================
    23:24:00 M: 17137664   DEBUG:   msg: Opening video decoder: [mpegpes] MPEG 1/2 Video passthrough
    23:24:00 M: 17137664   DEBUG:   msg: VDec: vo config request - 720 x 576 (preferred colorspace: Mpeg PES)
    23:24:00 M: 17137664   DEBUG:   msg: Trying filter chain:
    23:24:00 M: 17137664   DEBUG:   msg:  vo
    23:24:00 M: 17137664   DEBUG:   msg:
    23:24:00 M: 17137664   DEBUG:   msg: Could not find matching colorspace - retrying with -vf scale...
    23:24:00 M: 17137664   DEBUG:   msg: Opening video filter: [scale]
    23:24:01 M: 17137664   DEBUG:   msg: SwScale params: -1 x -1 (-1=no scaling)
    23:24:01 M: 17137664   DEBUG:   msg: Trying filter chain:
    23:24:01 M: 17137664   DEBUG:   msg:  scale
    23:24:01 M: 17137664   DEBUG:   msg:  vo
    23:24:01 M: 17137664   DEBUG:   msg:
    23:24:01 M: 17137664   DEBUG:   msg: The selected video_out device is incompatible with this codec.
                                 Try adding the scale filter, e.g. -vf spp,scale instead of -vf spp.
    23:24:01 M: 17137664   DEBUG:   msg: VDecoder init failed :(
    23:24:01 M: 17137664   DEBUG:   msg: Opening video decoder: [libmpeg2] MPEG 1/2 Video decoder libmpeg2-v0.4.0b
    23:24:01 M: 15888384   DEBUG:   msg: Selected video codec: [mpeg12] vfm:libmpeg2 (MPEG-1 or 2 (libmpeg2))
    23:24:01 M: 15888384   DEBUG:   msg: ==========================================================================
    23:24:01 M: 15888384   DEBUG:   msg: Checking audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/6ch/16bit...
    23:24:01 M: 15888384   DEBUG:   msg: [libaf] Adding filter dummy
    23:24:01 M: 15888384   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
    23:24:01 M: 15888384   DEBUG:   msg: AF_pre: af format: 2 bps, 2 ch, 48000 hz, little endian signed int
    23:24:01 M: 15888384   DEBUG:   msg: AF_pre: 48000Hz 2ch Signed 16-bit (Little-Endian)
    23:24:01 M: 15839232   DEBUG:   msg: AO: [win32] 48000Hz 2ch Signed 16-bit (Little-Endian) (2 bps)
    23:24:01 M: 15839232   DEBUG:   msg: AO: Description: Windows waveOut audio output
                                 AO: Author: SaschaSommer <saschasommer@freenet.de>
    23:24:01 M: 15839232   DEBUG:   msg: Building audio filter chain for 48000Hz/2ch/16bit -> 48000Hz/2ch/16bit...
    23:24:01 M: 15839232   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
    23:24:01 M: 15839232   DEBUG:   msg: [dummy] Was reinitialized, rate=48000Hz, nch = 2, format = 0x00000001 and bps = 2
    23:24:01 M: 15769600   DEBUG:   msg: Starting playback...
    23:24:02 M: 15769600   DEBUG:   msg: mplayer_open_file(smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv) done 1.0000
    23:24:02 M: 15667200   DEBUG: Playback has started
    23:24:02 M: 16109568   DEBUG: CGUIInfoManager::SetCurrentMovie(smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv)
    23:24:02 M: 15994880   DEBUG:   msg: VDec: vo config request - 720 x 576 (preferred colorspace: Planar YV12)
    23:24:02 M: 15941632   DEBUG:   msg: Trying filter chain:
    23:24:02 M: 15982592   DEBUG:   msg:  vo
    23:24:02 M: 15941632   DEBUG:   msg:
    23:24:02 M: 15941632   DEBUG:   msg: VDec: using Planar YV12 as output csp (no 0)
    23:24:02 M: 15982592   DEBUG: CVideoDatabase::GetMovieInfo(smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv), query = select idMovie from movie where idFile=387
    23:24:02 M: 15851520   DEBUG:   msg: Movie-Aspect is 1.78:1 - prescaling to correct movie aspect.
    23:24:02 M: 15851520    INFO: Loading skin file: DialogBusy.xml
    23:24:02 M: 15892480   DEBUG: CVideoDatabase::GetEpisodeInfo(smb://mythtv:mythtv@192.168.0.250/myth/tv/1007_20070516202500_20070516214000.nuv), query = select idEpisode from episode where idFile=387
    23:24:03 M: 15712256   DEBUG:   msg: VO Config (720x576->1024x576,flags=1,'MPlayer',0x32315659)
    23:24:03 M: 15765504   DEBUG:   msg: VO: [directx] 720x576 => 1024x576 Planar YV12  [fs]
    23:24:03 M: 15855616   DEBUG:   msg: VO: Description: XBOX Direct3D8 YUY2 renderer
    23:24:03 M: 15855616   DEBUG:   msg: VO: Author: Frodo/JCMarshall
    23:24:03 M: 15855616   DEBUG: mplayer::video_config(720, 576, 1024, 576, 1, 842094169)
    23:24:03 M: 15855616  NOTICE: Display resolution AUTO : 720p 16:9 (1)
    23:24:03 M: 15470592   DEBUG: Activating window ID: 12005
    23:24:03 M: 15470592   DEBUG: Checking if window ID 12005 is locked.
    23:24:03 M: 15601664   DEBUG: Setting resolution 1
    23:24:03 M: 17485824   DEBUG: We set resolution 1
    23:24:03 M: 16822272   DEBUG: Created YV12 texture 0
    23:24:04 M: 16822272    INFO: Loading skin file: VideoFullscreen.xml
    23:24:04 M: 16822272    INFO: Loading skin file: VideoOSD.xml
    23:24:04 M: 16228352    INFO: Loading skin file: VideoOSDSettings.xml
    23:24:04 M: 15556608    INFO: Loading skin file: VideoOSDSettings.xml
    23:24:04 M: 15470592   DEBUG: CGUIFontTTF::Load Scaled size of font Q:\Media\Fonts\Arial.ttf (28): width = 76, height = 39
    23:24:04 M: 14802944   DEBUG:   msg: *** [vo] Allocating mp_image_t, 720x576x12bpp YUV planar, 622080 bytes
    23:24:04 M: 14168064   DEBUG:   msg: *** [vo] Allocating mp_image_t, 720x576x12bpp YUV planar, 622080 bytes
    23:24:04 M: 11296768    INFO: Created 444P texture
    23:24:04 M: 11296768   DEBUG:   msg: *** [vo] Allocating mp_image_t, 720x576x12bpp YUV planar, 622080 bytes
    23:24:11 M: 11300864   DEBUG: CApplication::OnKey: 166 pressed, action is 22
    23:24:12 M: 11268096   DEBUG: CApplication::OnKey: 166 pressed, action is 22
    23:24:13 M: 11255808   DEBUG: CApplication::OnKey: 169 pressed, action is 21
    23:24:14 M: 11124736   DEBUG: CApplication::OnKey: 169 pressed, action is 21
    23:24:15 M: 11083776   DEBUG: CApplication::OnKey: 168 pressed, action is 20
    23:24:19 M: 11341824   DEBUG: CApplication::OnKey: 224 pressed, action is 13
    23:24:20 M: 11358208   DEBUG:   msg:  mplayer_close_file()
    23:24:20 M: 11358208   DEBUG:   msg:
    23:24:20 M: 11358208   DEBUG:   msg:  uninit_player
    23:24:20 M: 14000128   DEBUG:   msg: WINSOCK2 uninit
    23:24:20 M: 14012416   DEBUG:   msg: Uninit audio filters...
    23:24:20 M: 14012416   DEBUG:   msg: [libaf] Removing filter dummy
    23:24:20 M: 14012416   DEBUG:   msg: Uninit audio: mp3lib
    23:24:21 M: 14147584   DEBUG:   msg: Uninit video: libmpeg2
    23:24:21 M: 19578880   DEBUG: Deleted 444P video texture
    23:24:21 M: 20766720   DEBUG: Deleted YV12 texture 0
    23:24:21 M: 21078016   DEBUG:   msg:  uninit_player - done
    23:24:21 M: 21078016   DEBUG: Freeing FS segment @ 0xb04650

     
    • frooby

      frooby - 2007-05-29

      looks like it was working as expected to me. Nothing was going to happen till 989.9 seconds into the show where it thinks the first commercial is. Judging by the log you made it about 6 seconds in.

       
      • Wob

        Wob - 2007-05-29

        Hi frooby,
           The first point at 989.9 is where it start (thanks to all the buffer recorded).  It should just straight to that point on opening.  I skipped ahead manually to before the first commercial break and waited for it to skip, but nothing happened.  Is not able to detect an add break from the very start? maybe I should put in a bit at the start (few seconds) and then have the skip.  And if I manually fwd/rwd does this disable the skipping?

        Cheers,
        Beau

         
        • frooby

          frooby - 2007-05-29

          I don't know how the myth commercial detection works, but the script will only ever use the markups that myth has created. If the first markup is a '4' then it's the START of a commercial so it's not going to skip TO that point, it's going to skip from that point when it's reached, to the next point (the following '5').

          So if the show starts recording in the middle of a commercial does myth detect that it's a commercial? If it does and there's a '5' record at the start of the markup when yes it probably should jump straight to that point. And if you then manually skip forward and start playing when you are in a commercial break then it should know you are past the current 'next start' and try and find the next end and skip to that.

          Maybe you can email me the full log, and also everything you did, and I might be able to make more sense of what's happening.

           
    • Wob

      Wob - 2007-05-29

      I forgot to say, there is more to the xbmcmythtv.log, I didn't post it before because I thought that would be enough, here is the end of it (starting from the end of my first post.  If you need the log I can always email directly.

      Thanks
      Beau

      2007-05-28 23:24:11.609000 [10] lastPos=[6.0] curPos=[607.0]
      2007-05-28 23:24:11.656000 [10] > findMarkupIndex( pType=4, pPos=607.0 )
      2007-05-28 23:24:11.699000 [10] < findMarkupIndex => [0]
      2007-05-28 23:24:11.744000 [10] curPos=[607.0] nextStart=[989.9]
      2007-05-28 23:24:12.299000 [10] lastPos=[607.0] curPos=[608.0]
      2007-05-28 23:24:12.342000 [10] curPos=[608.0] nextStart=[989.9]
      2007-05-28 23:24:12.894000 [10] lastPos=[608.0] curPos=[1209.0]
      2007-05-28 23:24:12.941000 [10] > findMarkupIndex( pType=4, pPos=1209.0 )
      2007-05-28 23:24:12.986000 [10] < findMarkupIndex => [2]
      2007-05-28 23:24:13.030000 [10] curPos=[1209.0] nextStart=[1428.7]
      2007-05-28 23:24:13.577000 [10] lastPos=[1209.0] curPos=[1210.0]
      2007-05-28 23:24:13.649000 [10] curPos=[1210.0] nextStart=[1428.7]
      2007-05-28 23:24:14.203000 [10] lastPos=[1210.0] curPos=[1181.0]
      2007-05-28 23:24:14.243000 [10] > findMarkupIndex( pType=4, pPos=1181.0 )
      2007-05-28 23:24:14.285000 [10] < findMarkupIndex => [2]
      2007-05-28 23:24:14.328000 [10] curPos=[1181.0] nextStart=[1428.7]
      2007-05-28 23:24:14.872000 [10] lastPos=[1181.0] curPos=[1182.0]
      2007-05-28 23:24:14.919000 [10] curPos=[1182.0] nextStart=[1428.7]
      2007-05-28 23:24:15.459000 [10] lastPos=[1182.0] curPos=[1153.0]
      2007-05-28 23:24:15.503000 [10] > findMarkupIndex( pType=4, pPos=1153.0 )
      2007-05-28 23:24:15.548000 [10] < findMarkupIndex => [2]
      2007-05-28 23:24:15.592000 [10] curPos=[1153.0] nextStart=[1428.7]
      2007-05-28 23:24:16.136000 [10] lastPos=[1153.0] curPos=[1184.0]
      2007-05-28 23:24:16.180000 [10] > findMarkupIndex( pType=4, pPos=1184.0 )
      2007-05-28 23:24:16.228000 [10] < findMarkupIndex => [2]
      2007-05-28 23:24:16.273000 [10] curPos=[1184.0] nextStart=[1428.7]
      2007-05-28 23:24:16.812000 [10] lastPos=[1184.0] curPos=[1185.0]
      2007-05-28 23:24:16.867000 [10] curPos=[1185.0] nextStart=[1428.7]
      2007-05-28 23:24:17.411000 [10] lastPos=[1185.0] curPos=[1185.0]
      2007-05-28 23:24:17.466000 [10] curPos=[1185.0] nextStart=[1428.7]
      2007-05-28 23:24:18.010000 [10] lastPos=[1185.0] curPos=[1186.0]
      2007-05-28 23:24:18.054000 [10] curPos=[1186.0] nextStart=[1428.7]
      2007-05-28 23:24:18.602000 [10] lastPos=[1186.0] curPos=[1187.0]
      2007-05-28 23:24:18.653000 [10] curPos=[1187.0] nextStart=[1428.7]
      2007-05-28 23:24:19.200000 [10] lastPos=[1187.0] curPos=[1187.0]
      2007-05-28 23:24:19.241000 [10] curPos=[1187.0] nextStart=[1428.7]
      2007-05-28 23:24:19.804000 [10] lastPos=[1187.0] curPos=[1188.0]
      2007-05-28 23:24:19.873000 [10] curPos=[1188.0] nextStart=[1428.7]
      2007-05-28 23:24:20.440000 [10] lastPos=[1188.0] curPos=[1188.0]
      2007-05-28 23:24:20.471000 [10] curPos=[1188.0] nextStart=[1428.7]
      2007-05-28 23:24:21.033000 [13]  < onPlayBackStarted
      2007-05-28 23:24:21.102000 [16] < mythtvrecordedshowdetails.Window.play()
      2007-05-28 23:24:21.178000 [19] < mythtvrecordedshowdetails.Window.onControlHook()

       
    • Wob

      Wob - 2007-07-06

      Been busy of late and forgot to reply on this one.  The problem doesn't appear to be in the code as such, it thinks it's skipping at the right time but the time codes are not matching up with the video.  It skips, just not at the right times.  I also noted that when playing a video from the myth box the total time is constantly fluctuating, it moved up and down the whole time the video is playing.  I am sure this has something to do with the problem but I am not sure how to fix it, maybe an it needs a patch to mplayer to handle the mythtv files?

      I am using 0.20 version of mythtv and hence mpg files now, but it does the same thing on older .nuv recorded shows.

      Cheers,
      Wob

       
    • frooby

      frooby - 2007-07-17

      Wob, That was the exact problem I was seeing, and that was about the stage I gave up. I havn't since tried using the DVD Player core in xbmc for it though, so maybe that's better at handling it? Last time I used that I couldn't even fast forward or rewind.

       
      • Wob

        Wob - 2007-08-02

        Just an update to this issue, it appears that this is a know limitation to mplayer (also effects dvdplayer).  It uses average bitrate to calculate the approximate length of large mpeg4 files when playing them over a network.  Problem has been known for some time and no one seems to be aiming to resolve it, so I don't think we will see any solution to this any time soon.

        Cheers,
        Wob

         
        • jezzaaaa

          jezzaaaa - 2008-07-15

          Wob, do you have a reference/URL/bug# for this known issue in XBMC?

          BTW, great project frooby.  I've been using XBMCmythtv for years now.  The new(ish) plug-in is looking good too, for those who want something more integrated into the XBMC interface.

           
    • daniel

      daniel - 2007-07-18

      Frooby,
      I see you're very active within the Help Forum, I am really struggling with getting XBMCMythtv working with Fiesty Fawn Ubuntu which has SVN .20 version of Mythtv and I am currently using the June 06, 2007 version of XBMC from T3CH and the 20.31 version of xbmcmythtv. The log actually shows me connecting to the backend database and something about Protocol 31. When I test the settings however it gives me the dreaded "failed to connect to database, conn". The only thing that works is the Upcomings Recordings are shown but nothing else. I have posted many threads about what I have done. This is copied and pasted from an email I sent to the person who posted the solution for using the OLD_PASSWORD hashing. Please keep in mind that since I couldn't get the 20.34b to work, I am now trying the 20.31 and I am at least seeing in the log that the protocols match now.

      .................I can't seem to get this to work despite me trying your fix
      that you suggested here:
      http://sourceforge.net/forum/forum.php?thread_id=1738450&forum_id=436331

      When I enter the command: mysql> UPDATE mysql.user SET
      password=OLD_PASSWORD('mwpnycvz') WHERE user='mythtv' AND
      host='mythtv@192.168.0.2';

      It returns:
      Query OK, 0 rows affected (0.00 sec)
      Rows matched: 0  Changed: 0  Warnings: 0

      which leads me to believe that it didn't update anything
      because it's already correct? I am using the THC3 build:
      XBMC SVN 2007-06-25 (25th of June 2007) rev9424

      The xbmcmythtv I am using is the latest one from
      sourceforge, I think it's 20.34b.zip. What's weird is that
      when I read the README within that .zip file, it says this:
      MythTV@XBMC v0.20.31 is a python module for XBMC.
      Which leads me to believe they either didn't update the
      README or something is amiss? Can you please provide any
      comments? Here is what my phpmyadmin privileges looks like:
      http://img366.imageshack.us/img366/7963/privileges1wo8.png

      and then this is what the variables still says as far as
      old_passwords = off????
      http://img366.imageshack.us/img366/4246/variables1fx3.png

      Again, any help would be greatly appreciated. I can be
      emailed here or darfsten@hotmail.com. Thank you soo much!...............

      The other issue I might be having is my smb shares. I have weird characters within my password so it doesn't appear to be working and maybe that's why it's stating that it failed to connect to database or maybe that's just a generic error? I tried to look at the code of default.py and mythtv.py but I can't see where it calls for checking the smb shares. I CAN view the shares just fine thru XBMC but I believe it's because there's a place to set the username and password and workgroup within xbmc settings under network, so I am sure that's why I can navigate to the server and then the shares just fine. So for example, say my username was ralph and my password was #5481@98!99 and the location of the mythtv recordings folder was on server 192.168.0.3, within the path /media/400gb/mythtv. How would I enter that within the settings page? AGain, I really would appreciate your help here. I've even tried guest access but that doesn't work either. I also can't seem to find any error's within the syslog because that's where Debian logs mysql stuff. Can I up the amount of mysql debug info somehow? I did check out my.cnf but there's really nothing that I can change to make it output more info to syslog? Thanks if you can help.

      PS. Sorry so long but I have been at this for more than 40 hours.

       
      • frooby

        frooby - 2007-07-18

        First would suggest you stick with the latest version of xbmcmythtv. All version are backwards compatible, you will just see an initial protocol not matching error, but then it will be ok.  But if you are happy with the older version then go for it. I don't tend to update the readme files so not suprised it's a bit out of date!

        First I don't really know much about the SQL side. But one thing I noticed in your query:

        UPDATE mysql.user SET password=OLD_PASSWORD('mwpnycvz') WHERE user='mythtv' AND host='mythtv@192.168.0.2';

        host should be '192.168.0.2' NOT 'mythtv@192...' The fact it said 0 changes made would be because it didn't find the row to update. When you do a grant priviledges you would do the full 'mythtv'@'192.168.0.2' thing (note the quotes around both user and host) but not and UPDATE/WHERE.

        Now for the samba problem, my only suggestion would be to change the mythtv (or 'media' in your case) share to allow guest users, then you won't need a password. Maybe even set up a dedicated share for mythtv and set that to guest ok, just make sure the contents of the directory are world readable. Make the share read only if you need to, but it makes things ALOT easier when trying to get things working.

         
      • frooby

        frooby - 2007-07-18

        Also check that you can actually connect to your mysql database from any other machine other than the localhost. ie you mysql server is bound to the actual eth0 ip address (192.168.0.2), not the localhost.

         
    • daniel

      daniel - 2007-07-24

      Well, the only reason I tried that fix suggested was because here, (http://sourceforge.net/forum/forum.php?thread_id=1738450&forum_id=436331) it specifically states that he got his Mythtv SVN .20 working with xbmcmythtv 20.34b by doing that.

      I think that it stated zero lines updated within the database priveleges was because I had done it a few times. I DO have it working now, well the live tv wasn't working last night with 20.31 and also, when I went to my recordings, I could see it BUT when I clicked Play, it didn't play, it just sat there. So needless to say, I am going with your suggestion and will now use the latest xbmcmythtv since I think my issue was really the smb shares, HOPEFULLY. and now that I have that straightened out, I am just going to use the settings.xml that was working for 20.31 and put it in the 20.34b version and hope for the best. You don't see a problem with that do you? Meaning, you didn't change any code within the settings.xml file did you?

      Thanks for the support, that's what makes sourceforge.net the best!

       
    • daniel

      daniel - 2007-08-07

      can you please comment on the fact that you're saying that livetv and watching recordings thru xbmcmythtv does NOT work? I for the life of me can't get it, I have lowered the cache settings within xbmc, I have made sure that record livetv is set, I have made sure I can access the samba directory for the recordings and I am using the same directory for livetv. Everything else is great, I can see my listings, I can see all my scheduling info, I can encoder status, I can see previous recordings, even delete them, BUT can't play them, nothing happens and the sound effects stop working until I either play a movie over network using samba and xbmc or I restart xbox. Livetv is the same, it just sits there forever. Oh well, I have tried long enough. I try when I new one comes out again.

       
      • jezzaaaa

        jezzaaaa - 2008-07-15

        Daniel, I think your post is off-topic.

        Regardless, I can confirm that watching recordings through XBMCMythTV does work.  However I've never had Live TV work for me.  At one stage it would play for about 1 second and then exit, but that was several versions ago of both XBMC and XBMCMythTV.  But no problems with recorded programs.

         

Log in to post a comment.

Get latest updates about Open Source Projects, Conferences and News.

Sign up for the SourceForge newsletter:

JavaScript is required for this form.





No, thanks