[mythtv-users] DVD iso Playback Fails After Upgrade to 0.28

Jay Foster jayf0ster at roadrunner.com
Sat Sep 30 22:43:12 UTC 2017 

On 09/27/2017 12:26 PM, Richard Hulme wrote:
> On 22/09/17 00:18, Jay Foster wrote:
>>>
>> Here is the mythfrontend log.  Nothing pops out to me.
>
> <snip>
>
>> Sep 21 15:13:54 mythbox mythfrontend.real: mythfrontend[24974]: D 
>> CoreContext tv_play.cpp:2245 (HandleStateChange) 
>> TV::HandleStateChange(): (0) -- begin
>> Sep 21 15:13:54 mythbox mythfrontend.real: mythfrontend[24974]: I 
>> CoreContext tv_play.cpp:2275 (HandleStateChange) 
>> TV::HandleStateChange(): Attempting to change from None to WatchingVideo
>
> This last line pops out to me.  It means it's trying to play the image 
> as a video, not as a DVD.  It should be "Attempting to change from 
> None to WatchingDVD".
>
> If you start mythfrontend with "-v media" (or just add it to the 
> existing parameters - "-v playback,media --loglevel=debug") you should 
> one of:
>
> "Found Bluray at ..."
> "Found DVD at ..."
> or
> "inspectImage - unknown"
>
> Basically, if you try to play an ISO the code tries to inspect the 
> contents of the image.  If it finds a sub-directory under root called 
> 'BDMV', it treats it as a Blu-ray image.  If it finds a directory 
> called "VIDEO_TS", it treats it as a DVD.  Otherwise it gives up.
>
> Richard
> _______________________________________________
> mythtv-users mailing list
> mythtv-users at mythtv.org
> http://lists.mythtv.org/mailman/listinfo/mythtv-users
> http://wiki.mythtv.org/Mailing_List_etiquette
> MythTV Forums: https://forum.mythtv.org
>
Here is the frontend log with the failing ISO followed by a working 
ISO.  You are correct that the failing one states, "Attempting to change 
from None to WatchingVideo", and the working one states, "Attempting to 
change from None to WatchingDVD".

Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:378 (StartTV) TV::StartTV(): tv->Playback() -- begin
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:2245 (HandleStateChange) 
TV::HandleStateChange(): (0) -- begin
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:2275 (HandleStateChange) 
TV::HandleStateChange(): Attempting to change from None to WatchingVideo
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext fileringbuffer.cpp:185 (OpenFile) 
FileRingBuf(/home2/mythtv/videos/dvd/matrix/THE MATRIX.iso): 
OpenFile(/home2/mythtv/videos/dvd/matrix/THE MATRIX.iso, 2000 ms)
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:5825 (StartPlayer) TV::StartPlayer(): (0, 
WatchingVideo, main) -- begin
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:5829 (StartPlayer) TV::StartPlayer(): Elapsed 
time since TV constructor was called: 41 ms
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[adjust] = 0x12a3530
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[bobdeint] = 0x1007100
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[crop] = 0x4f4b4d0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[denoise3d] = 0x5322ef0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[fieldorderdoubleprocessdeint] = 0x4e5ede0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[forceyv12] = 0x5324080
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[forceyuv422p] = 0x53250c0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[forcergb24] = 0x5325c10
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[forceargb32] = 0x5326550
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[greedyhdeint] = 0x5325ce0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[greedyhdoubleprocessdeint] = 0x5328090
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[invert] = 0x5328210
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[ivtc] = 0x5328c10
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[kerneldeint] = 0x532c070
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[kerneldoubleprocessdeint] = 0x532c280
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[linearblend] = 0x532c3d0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[onefield] = 0x532e0d0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[postprocess] = 0x532dda0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[quickdnr] = 0x5332170
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[vflip] = 0x53332b0
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[yadifdeint] = 0x5334150
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext filtermanager.cpp:179 (LoadFilterLib) FilterManager: 
filters[yadifdoubleprocessdeint] = 0x5335430
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: N 
CoreContext audioplayer.cpp:167 (ReinitAudio) AudioPlayer: Enabling Audio
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: E 
CoreContext mythplayer.cpp:980 (OpenFile) Player(0): Couldn't find an 
A/V decoder for: '/home2/mythtv/videos/dvd/matrix/THE MATRIX.iso'
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: E 
CoreContext mythplayer.cpp:2917 (StartPlaying) Player(0): Unable to open 
video file.
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: E 
CoreContext playercontext.cpp:460 (StartPlaying) playCtx: StartPlaying() 
Failed to start player
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: C 
CoreContext tv_play.cpp:5866 (StartPlayer) TV::StartPlayer(): Failed to 
create player.
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:5871 (StartPlayer) TV::StartPlayer(): (0, 
WatchingVideo, main) -- end error
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:8748 (DrawUnusedRects) TV::DrawUnusedRects(): -- 
begin
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:8761 (DrawUnusedRects) TV::DrawUnusedRects(): -- end
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:2638 (HandleStateChange) 
TV::HandleStateChange(): Main UI disabled.
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:2643 (HandleStateChange) 
TV::HandleStateChange(): (0) -- end
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext lcddevice.cpp:607 (switchToChannel) LCDdevice: switchToChannel
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:389 (StartTV) TV::StartTV(): tv->Playback() -- end
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:424 (StartTV) TV::StartTV(): Entering main 
playback loop.
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext screensaver-x11.cpp:119 (ResetTimer) ScreenSaverX11Private: 
ResetTimer -- begin
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext screensaver-x11.cpp:105 (StopTimer) ScreenSaverX11Private: 
StopTimer
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext screensaver-x11.cpp:112 (StartTimer) ScreenSaverX11Private: 
StartTimer
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext screensaver-x11.cpp:132 (ResetTimer) ScreenSaverX11Private: 
ResetTimer -- end
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:426 (StartTV) TV::StartTV(): Exiting main 
playback loop.
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:456 (StartTV) TV::StartTV(): -- process events 2 
begin
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:460 (StartTV) TV::StartTV(): -- process events 2 end
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:1367 (~TV) TV::~TV(): -- begin
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:1384 (~TV) TV::~TV(): -- lock
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext lcddevice.cpp:584 (switchToTime) LCDdevice: switchToTime
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext mythplayer.cpp:2965 (StopPlaying) Player(0): StopPlaying - begin
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext mythplayer.cpp:3337 (DecoderEnd) Player(0): Exited decoder loop.
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext mythplayer.cpp:2975 (StopPlaying) Player(0): StopPlaying - end
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:1449 (~TV) TV::~TV(): -- end
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: N 
CoreContext mythmainwindow.cpp:2956 (PauseIdleTimer) Resuming idle timer
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:515 (StartTV) TV::StartTV(): -- end
Sep 30 15:33:00 mythbox mythfrontend.real: mythfrontend[9849]: N 
CoreContext mythmainwindow.cpp:2956 (PauseIdleTimer) Resuming idle timer
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: N 
CoreContext mythmainwindow.cpp:2951 (PauseIdleTimer) Suspending idle timer
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext mythcdrom.cpp:213 (inspectImage) Found DVD at 
/home2/mythtv/videos/dvd/MissionImpossible/Mission_Impossible.iso
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext DVD/dvdringbuffer.cpp:50 (DVDInfo) DVDInfo: Trying 
dvd:/home2/mythtv/videos/dvd/MissionImpossible/Mission_Impossible.iso
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext DVD/dvdringbuffer.cpp:76 (~DVDInfo) DVDInfo: Finishing.
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:1092 (TV) TV::TV(): Creating TV object
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:1111 (TV) TV::TV(): Finished creating TV object
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:332 (StartTV) TV::StartTV(): -- begin
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: N 
CoreContext mythmainwindow.cpp:2951 (PauseIdleTimer) Suspending idle timer
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:1240 (Init) TV::Init(): -- begin
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:1314 (Init) TV::Init(): Created TvPlayWindow.
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:8748 (DrawUnusedRects) TV::DrawUnusedRects(): -- 
begin
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:8761 (DrawUnusedRects) TV::DrawUnusedRects(): -- end
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:8748 (DrawUnusedRects) TV::DrawUnusedRects(): -- 
begin
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:8761 (DrawUnusedRects) TV::DrawUnusedRects(): -- end
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:8748 (DrawUnusedRects) TV::DrawUnusedRects(): -- 
begin
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:8761 (DrawUnusedRects) TV::DrawUnusedRects(): -- end
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:1361 (Init) TV::Init(): -- end
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:378 (StartTV) TV::StartTV(): tv->Playback() -- begin
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:2245 (HandleStateChange) 
TV::HandleStateChange(): (0) -- begin
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:2275 (HandleStateChange) 
TV::HandleStateChange(): Attempting to change from None to WatchingDVD
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext ringbuffer.cpp:183 (Create) Trying DVD at 
/home2/mythtv/videos/dvd/MissionImpossible/Mission_Impossible.iso
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext DVD/dvdringbuffer.cpp:506 (OpenFile) DVDRB: Opened DVD 
device at /home2/mythtv/videos/dvd/MissionImpossible/Mission_Impossible.iso
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: E 
CoreContext mythcdrom-linux.cpp:785 (setDeviceSpeed) MythCDROMLinux: 
Changing CD/DVD speed needs write access
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext DVD/dvdringbuffer.cpp:539 (OpenFile) DVDRB: DVD Serial 
Number 253d0a79
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: D 
CoreContext tv_play.cpp:5825 (StartPlayer) TV::StartPlayer(): (0, 
WatchingDVD, main) -- begin
Sep 30 15:33:09 mythbox mythfrontend.real: mythfrontend[9849]: I 
CoreContext tv_play.cpp:5829 (StartPlayer) TV::StartPlayer(): Elapsed 
time since TV constructor was called: 35 ms

Is MythTV confused by the space character in the file name?  I know this 
worked on 0.21, and assume on 0.27 (do not know if I ever checked).

Jay

More information about the mythtv-users mailing list