mp3 Video Rendering problem?

[Fuzzy]

I am attempting to play .mp3 files in sage, and getting an error that it can't render the video portion of the playback. I'm pretty sure it's because there isn't any, but what is the cuase of this error?

following is the excerpt from the log file:

Tue 9/27 13:44:17.892 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]] ifn=null])
Tue 9/27 13:44:17.894 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]] ifn=null] nPlayin=false
Tue 9/27 13:44:17.918 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]] ifn=null]
Tue 9/27 13:44:17.920 VideoFrame creating new media player for file:MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]]
Tue 9/27 13:44:17.937 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]] ifn=null] nPlayin=false
Tue 9/27 13:44:17.943 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]] ifn=null]
Tue 9/27 13:44:17.946 VF file=MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]] targetTime = Mon 9/26 16:12:06.933
Tue 9/27 13:44:17.946 VideoFrame.timeSelected(Mon 9/26 16:12:06.933, true) currFile=MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]]
Tue 9/27 13:44:17.958 Using the default source filter
Tue 9/27 13:44:17.960 NATIVE Loading the source file
Tue 9/27 13:44:17.965 NATIVE RenderSource called
Tue 9/27 13:44:18.178 VideoFrame.timeSelected2(Mon 9/26 16:12:06.933, true) currFile=MediaFile[id=833581 A[833583,833582,"40 Oz. to Freedom [Australia]",0@0926.16:12,2] host=fuzzytv encodedBy= \\office\shareddocs\my music\Sublime\40 Oz. to Freedom [Australia]\40 Oz. to Freedom [Australia]-01-Waiting for My Ruca.mp3, Seg0[Mon 9/26 16:12:06.933-Mon 9/26 16:14:27.359]] realDur=0
Tue 9/27 13:44:18.178 About to perform media time selection for 0
Tue 9/27 13:44:18.178 Did Media Time Select Millis 0
Tue 9/27 13:44:18.178 Exception from line: 115
sage.PlaybackException: ERROR (-10,0x80004001): There was an error seeking the media player.
Tue 9/27 13:44:18.179 	at sage.DShowMediaPlayer.seek0(Native Method)
Tue 9/27 13:44:18.179 	at sage.DShowMediaPlayer.seek(Unknown Source)
Tue 9/27 13:44:18.179 	at sage.VideoFrame.if(Unknown Source)
Tue 9/27 13:44:18.179 	at sage.VideoFrame.a(Unknown Source)
Tue 9/27 13:44:18.179 	at sage.VideoFrame.access$100(Unknown Source)
Tue 9/27 13:44:18.180 	at sage.VideoFrame$4.run(Unknown Source)
Tue 9/27 13:44:18.180 	at java.awt.event.InvocationEvent.dispatch(Unknown Source)
Tue 9/27 13:44:18.180 	at java.awt.EventQueue.dispatchEvent(Unknown Source)
Tue 9/27 13:44:18.180 	at java.awt.EventDispatchThread.pumpOneEventForHierarchy(Unknown Source)
Tue 9/27 13:44:18.180 	at java.awt.EventDispatchThread.pumpEventsForHierarchy(Unknown Source)
Tue 9/27 13:44:18.180 	at java.awt.EventDispatchThread.pumpEvents(Unknown Source)
Tue 9/27 13:44:18.180 	at java.awt.EventDispatchThread.pumpEvents(Unknown Source)
Tue 9/27 13:44:18.181 	at java.awt.EventDispatchThread.run(Unknown Source)
Tue 9/27 13:44:18.181 VideFrame had an error loading the file. It must abort the file load.
Tue 9/27 13:44:18.181 Tearing down playback graph
Tue 9/27 13:44:18.182 processOptionsMenu optionsMenu=Widget[ErrorMessage id=1161433 OptionsMenu Layout=Vertical AnchorX=0.5 AnchorY=0.5 down=[1163288,1163289,1163290,1160232,] up=[1163291,1163292,] context=Context[parent=Context[parent=Context[parent=null map={CurrAlbum=40 Oz. to Freedom [Australia] by Sublime, AllAlbums=[Lsage.ao;@1818f80, AlbumIndex=0, DefaultTrack=null}] map=null] map={ErrorCategory=Playback, ErrorDetails=sage.PlaybackException: ERROR (-4,0x0): There was a problem rendering the video portion of the content for playback., _SAGE_HOOKSOURCEWIDGET=java.lang.Object@cd43ce, ReturnValue=null}]
Tue 9/27 13:44:18.194 NATIVE Deallocating the CPlayerData object
Tue 9/27 13:44:18.195 NATIVE Finished destroying the playback graph
Tue 9/27 13:44:18.195 Graph ref Count=1
Tue 9/27 13:44:18.196 VF processing job null nPlayin=false
Tue 9/27 13:44:18.196 VF thread is now waiting for 0:00:00.000
Tue 9/27 13:44:19.873 PM currState=3 wakeupTime=Wed 12/31/1969 16:00:00.000
Tue 9/27 13:44:22.172 UIManager.gotoSleep(true) isTaskbar=false
Tue 9/27 13:44:22.172 Sage is going to SLEEP

[linkster99]

I've had the same problem ever since I started using Sage with the original 2.0 release. It doesn't happn on all my music files - only a few. Of course all files play fine with any other player I choose, so it seems like it must be a sage issue.

[hammondr]

I'm having the same problem now, only with the latest sage 6.0.19 (and 6.0.17 RC). I'm 99% sure this is a problem I caused myself while using a filter manager to tweak my audio & video decoder settings. I get the same errors whether using the standard STV or SageMC, so I don't think this is a problem with the sage core or any STV. The problem is that Sage will no longer play mp3s. It can play archived videos, recorded shows with no problem. I've somehow broken my tuner card configuration (which I think is related), but I'm trying to concentrate on mp3 playing for right now.

I'm running a single sage server / client setup, with the sage server running as a service. All media files are local, and I've tried running the service as a named windows (admin) user, and as localsystem. Same affect either way. I must have done some crazy things to make Sage try to play mp3s with the wrong codec or something. Other software on the same machine (windows media player, real player, graphedit) can play all these mp3s just fine. I have used this sage setup to play all of these mp3s for 6 months with no problem.

This is definitely not an issue of currupted or unavailable media files -- they are there and can be played with other software. The problem was almost definitely caused by me tinkering with filters and installing & uninstalling various video codecs.

Would someone take a look at this log snapshot and let me know what you see? If someone could post a similar log snapshot of a working mp3 setup, that would be great. (All of my old sageclient log files have aged off, so I can't find any copies from before I hosed myself.)

Mon 1/1 10:24:16.862 VF processing on UI Thread VFJob[StartPlaylist r=0.0 t=0 file=null ifn=null]
Mon 1/1 10:24:16.863 VF.playbackNextRandomPlaylist playlistChain=[Playlist[Now Playing A[610137,610133,"Mosaic",0@1109.20:33,3], A[610140,610139,"Mosaic",0@1109.20:34,1], A[610143,610142,"Mosaic",0@1109.20:35,1], A[610146,610145,"Mosaic",0@1109.20:35,1], A[610149,610148,"Mosaic",0@1109.20:34,1], A[610152,610151,"Mosaic",0@1109.20:34,2], A[610155,610154,"Mosaic",0@1109.20:31,5], A[610158,610157,"Mosaic",0@1109.20:34,2], A[610161,610160,"Mosaic",0@1109.20:33,3], A[610164,610163,"Mosaic",0@1109.20:31,5], A[610167,610166,"Mosaic",0@1109.20:32,4], A[610170,610169,"Mosaic",0@1109.20:32,4], A[610173,610172,"Mosaic",0@1109.20:33,3], A[610176,610175,"Mosaic",0@1109.20:30,6], A[610179,610178,"Mosaic",0@1109.20:34,2], A[610182,610181,"Mosaic",0@1109.20:36,1], A[610185,610184,"Mosaic",0@1109.20:34,2], A[610188,610187,"Mosaic",0@1109.20:35,2], A[610191,610190,"Mosaic",0@1109.20:35,2], A[610194,610193,"Mosaic",0@1109.20:34,2], A[610197,610196,"Mosaic",0@1109.20:33,3], ]] playindices=[-1] currFile=null
Mon 1/1 10:24:16.864 VideoFrame.watch(A[610152,610151,"Mosaic",0@1109.20:34,2])
Mon 1/1 10:24:16.864 watchThisFile=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]]
Mon 1/1 10:24:16.866 Watch airing is a file & over, do that instead dvd=false
Mon 1/1 10:24:16.870 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]] ifn=null])
Mon 1/1 10:24:16.876 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= [b]format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0][b]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]] ifn=null] nPlayin=false
Mon 1/1 10:24:16.878 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]] ifn=null]
Mon 1/1 10:24:16.879 VideoFrame creating new media player for file:MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]]
Mon 1/1 10:24:16.881 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]] ifn=null] nPlayin=false
Mon 1/1 10:24:16.885 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]] ifn=null]
Mon 1/1 10:24:16.886 VF file=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]] targetTime = Thu 11/9 20:34:33.145
Mon 1/1 10:24:16.886 VideoFrame.timeSelected(Thu 11/9 20:34:33.145, true) currFile=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]]
Mon 1/1 10:24:16.891 Using the default source filter
Mon 1/1 10:24:16.891 NATIVE Loading the source file
Mon 1/1 10:24:16.895 NATIVE RenderSource called
Mon 1/1 10:24:16.915 DShowGraphFilters=Default DirectSound Device,MPEG Layer-3 Decoder,MPEG-1 Stream Splitter,E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3,
Mon 1/1 10:24:16.918 VideoFrame.timeSelected2(Thu 11/9 20:34:33.145, true) currFile=MediaFile[id=610150 A[610152,610151,"Mosaic",0@1109.20:34,2] host=htpc encodedBy= format=MP3 0:02:09 191 kbps [#0 Audio[MP3 44100 Hz 2 channels 192 kbps idx=0]{Album=Mosaic, Year=2002, Duration=129623, ThumbnailOffset=48, Genre=Instrumental, Artist=Alexandria Guitar Trio, Title=Suite of Six Trios 6/6, ThumbnailSize=60374, Track=6}] E:\Music\Alexandria Guitar Trio\Mosaic\06-Alexandria Guitar Trio-Mosaic-Suite of Six Trios 6-6.mp3, Seg0[Thu 11/9 20:34:33.145-Thu 11/9 20:36:42.768]] realDur=0
Mon 1/1 10:24:16.918 About to perform media time selection for 0
Mon 1/1 10:24:16.919 Exception from line: 117
Mon 1/1 10:24:16.919 sage.PlaybackException: ERROR (-10,0x80004001): There was an error seeking the media player.
Mon 1/1 10:24:16.920 	at sage.DShowMediaPlayer.seek0(Native Method)
Mon 1/1 10:24:16.920 	at sage.DShowMediaPlayer.seek(Unknown Source)
Mon 1/1 10:24:16.920 	at sage.VideoFrame.a(Unknown Source)
Mon 1/1 10:24:16.921 	at sage.VideoFrame.a(Unknown Source)
Mon 1/1 10:24:16.921 	at sage.VideoFrame.access$100(Unknown Source)
Mon 1/1 10:24:16.921 	at sage.VideoFrame$4.run(Unknown Source)
Mon 1/1 10:24:16.921 	at java.awt.event.InvocationEvent.dispatch(Unknown Source)
Mon 1/1 10:24:16.921 	at java.awt.EventQueue.dispatchEvent(Unknown Source)
Mon 1/1 10:24:16.921 	at java.awt.EventDispatchThread.pumpOneEventForFilters(Unknown Source)
Mon 1/1 10:24:16.921 	at java.awt.EventDispatchThread.pumpEventsForFilter(Unknown Source)
Mon 1/1 10:24:16.922 	at java.awt.EventDispatchThread.pumpEventsForHierarchy(Unknown Source)
Mon 1/1 10:24:16.922 	at java.awt.EventDispatchThread.pumpEvents(Unknown Source)
Mon 1/1 10:24:16.922 	at java.awt.EventDispatchThread.pumpEvents(Unknown Source)
Mon 1/1 10:24:16.922 	at java.awt.EventDispatchThread.run(Unknown Source)
Mon 1/1 10:24:16.923 VideFrame had an error loading the file. It must abort the file load.
Mon 1/1 10:24:16.923 Tearing down playback graph
Mon 1/1 10:24:16.925 processOptionsMenu optionsMenu=default:null|OptionsMenu:ErrorMessage
Mon 1/1 10:24:16.944 NATIVE Deallocating the CPlayerData object
Mon 1/1 10:24:16.945 NATIVE Finished destroying the playback graph
Mon 1/1 10:24:16.953 VF processing job null nPlayin=false
Mon 1/1 10:24:16.953 VF thread is now waiting for 0:00:00.000

I can see that sage correctly detects that this is an mp3 file with 44100 Hz 2 channel audio encoded at 192 kbps. That's promising. I think the problem line is the one with "DShowGraphFilters=Default DirectSound Device,MPEG Layer-3 Decoder,MPEG-1 Stream Splitter". Having spent many, many hours trying to get different *video* decoders to work, I'm used to seeing similar log entries when Sage is trying to pick a decoder. I just can't remember what it's supposed to look like for mp3s when it works.

The line about "an error seeking the media player." is also bugging me, but I don't know if it is just a downstream error caused by the first one.

I've reinstalled sage twice in hopes that would fix it, but no. I've tried to use the DirectShow Filter Manager (v0.5) to make sure the merit of the audio compressors and directshow filters is set so that the "right" ones get picked, but I'm kind of shooting in the dark here.

Any thoughts? I'm going insane with this.

Thanks,
Ryan