Error while playing any video stream for a 2nd time | Page 4

Discussion in 'Watch / Listen Media' started by xppx, April 20, 2014.

  1. xppx

    xppx Portal Pro

    Joined:
    September 2, 2007
    Messages:
    71
    Likes Received:
    15
    Ratings:
    +29 / 0
    Home Country:
    Belgium Belgium
    Show System Specs
    Got it... will install & test now...
    Extracted, skin renamed, copied, configuration set, testing now...



    -- done the test via watchdog --
    Added the log

    MP 1.6 pre
    Refresh rate changer disabled
    Result: CHANGE! - error after (re)start of 3th video (before it was always after the 4th)
     
    Last edited: June 9, 2014
  2. Google AdSense Guest Advertisement



    to hide all adverts.
  3. johanj
    • Premium Supporter

    johanj Well-Known Member

    Joined:
    January 31, 2009
    Messages:
    781
    Likes Received:
    270
    Gender:
    Male
    Ratings:
    +398 / 1
    Home Country:
    Sweden Sweden
    I tried the play command again and could reproduce the issue. It was actually after cleaning up AudioCodec, see below.

    Code (Text):
    1. [2014-06-09 21:37:52,287] [Log    ] [47       ] [DEBUG] - [WIFI_REMOTE]  User iPad [MPiV] successfully authentificated by username and password
    2. [2014-06-09 21:37:52,287] [Log    ] [47       ] [DEBUG] - [WIFI_REMOTE]  Client identified: iPad [MPiV]
    3. [2014-06-09 21:38:05,087] [Log    ] [57       ] [DEBUG] - [WIFI_REMOTE]  play mediaitem: ItemId: fs1_MzAwXDMwMC5ta3Y=, itemType: 4, providerId: 10
    4. [2014-06-09 21:38:05,090] [Log    ] [57       ] [INFO ] - MAP: using default mappings for WifiRemote
    5. [2014-06-09 21:38:05,091] [Log    ] [57       ] [DEBUG] - [WIFI_REMOTE]  Play video file: \\SERVER\Videos\300\300.mkv, pos: 0
    6. [2014-06-09 21:38:05,092] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Opening file : \\SERVER\Videos\300\300.mkv
    7. [2014-06-09 21:38:05,226] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: DLL Version      : MediaInfoLib - v0.7.69
    8. [2014-06-09 21:38:05,226] [Log    ] [57       ] [INFO ] - MediaInfoWrapper.MediaInfoWrapper: Inspecting media : \\SERVER\Videos\300\300.mkv
    9. [2014-06-09 21:38:05,227] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Parse speed      : 0.3
    10. [2014-06-09 21:38:05,227] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: FrameRate        : 23,976
    11. [2014-06-09 21:38:05,227] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Width            : 1280
    12. [2014-06-09 21:38:05,227] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Height           : 528
    13. [2014-06-09 21:38:05,228] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AspectRatio      : widescreen
    14. [2014-06-09 21:38:05,228] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoCodec       : AVC [ "avc.png" ]
    15. [2014-06-09 21:38:05,228] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Scan type        : progressive
    16. [2014-06-09 21:38:05,229] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: IsInterlaced     : False
    17. [2014-06-09 21:38:05,229] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoResolution  : 720P
    18. [2014-06-09 21:38:05,229] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoDuration    : 6992576
    19. [2014-06-09 21:38:05,229] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioRate        : 48000
    20. [2014-06-09 21:38:05,230] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioChannels    : 6 [ "5.1.png" ]
    21. [2014-06-09 21:38:05,230] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: AudioCodec       : AC-3 [ "ac-3.png" ]
    22. [2014-06-09 21:38:05,230] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasAudio         : True
    23. [2014-06-09 21:38:05,230] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasVideo         : True
    24. [2014-06-09 21:38:05,231] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: HasSubtitles     : False
    25. [2014-06-09 21:38:05,231] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: NumSubtitles     : 0
    26. [2014-06-09 21:38:05,231] [Log    ] [57       ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Closing file : \\SERVER\Videos\300\300.mkv
    27. [2014-06-09 21:38:05,232] [Log    ] [57       ] [INFO ] - RefreshRateChanger.AdaptRefreshRate: framerate on file \\SERVER\Videos\300\300.mkv is 23,976
    28. [2014-06-09 21:38:05,243] [Log    ] [57       ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: current refreshrate is 50hz - changing it to 23hz
    29. [2014-06-09 21:38:05,244] [Log    ] [57       ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: using internal win32 method for changing refreshrate. current is 50hz, desired is 23
    30. [2014-06-09 21:38:05,263] [Log    ] [57       ] [INFO ] - RefreshRateChanger AdapterOrdinal value is 0
    31. [2014-06-09 21:38:05,264] [Log    ] [57       ] [DEBUG] - CycleRefreshRate: Current MonitorIndex : 0 and current deviceName : \\.\DISPLAY1
    32. [2014-06-09 21:38:06,039] [Log    ] [57       ] [INFO ] - CycleRefreshRate: result DISP_CHANGE_SUCCESSFUL for refresh rate change 23Hz
    33. [2014-06-09 21:38:06,039] [Log    ] [57       ] [DEBUG] - CycleRefresh: DWM Detected, performing shenanigans
    34. [2014-06-09 21:38:06,040] [Log    ] [57       ] [INFO ] - g_Player.Play(\\SERVER\Videos\300\300.mkv Video)
    35. [2014-06-09 21:38:06,040] [Log    ] [57       ] [DEBUG] - PlayerFactory: Successfully created player instance for file - \\SERVER\Videos\300\300.mkv
    36. [2014-06-09 21:38:06,041] [Log    ] [57       ] [INFO ] - VideoPlayer:play \\SERVER\Videos\300\300.mkv
    37. [2014-06-09 21:38:06,047] [Log    ] [57       ] [INFO ] - VideoPlayer9: Enabling DX9 exclusive mode
    38. [2014-06-09 21:38:06,047] [Log    ] [57       ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete.
    39. [2014-06-09 21:38:06,303] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DISPLAYCHANGE
    40. [2014-06-09 21:38:06,310] [Log    ] [MPMain   ] [DEBUG] - Main: WM_GETMINMAXINFO Start (MaxSize: 1920x1080 - MaxPostion: 0,0 - MinTrackSize: 2x2 - MaxTrackSize: 1932x1092)
    41. [2014-06-09 21:38:06,343] [Log    ] [MPMain   ] [DEBUG] - Main: WM_GETMINMAXINFO End (MaxSize: 1920x1080 - MaxPostion: 0,0 - MinTrackSize: 1920x1080 - MaxTrackSize: 1920x1080)
    42. [2014-06-09 21:38:06,345] [Log    ] [MPMain   ] [DEBUG] - D3D: OnPaint()
    43. [2014-06-09 21:38:06,676] [Log    ] [MPMain   ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
    44. [2014-06-09 21:38:06,676] [Log    ] [MPMain   ] [INFO ] - Main: Deactivation request received
    45. [2014-06-09 21:38:06,676] [Log    ] [MPMain   ] [INFO ] - Main: Refresh rate changer running. Ignoring deactivation request
    46. [2014-06-09 21:38:06,677] [Log    ] [MPMain   ] [DEBUG] - D3D: OnLostFocus()
    47. [2014-06-09 21:38:06,682] [Log    ] [MPMain   ] [DEBUG] - Main: WM_ACTIVATE (WA_ACTIVE)
    48. [2014-06-09 21:38:06,682] [Log    ] [MPMain   ] [INFO ] - Main: Activation request received
    49. [2014-06-09 21:38:06,682] [Log    ] [MPMain   ] [DEBUG] - D3D: OnGotFocus()
    50. [2014-06-09 21:38:06,690] [Log    ] [57       ] [INFO ] - VMR9: added EVR Renderer to graph
    51. [2014-06-09 21:38:06,690] [Log    ] [57       ] [DEBUG] - VMR9: Now active
    52. [2014-06-09 21:38:06,691] [Log    ] [57       ] [DEBUG] - VMR9: Renderer successfully added
    53. [2014-06-09 21:38:06,691] [Log    ] [57       ] [DEBUG] - VideoPlayer9: Add LAVSplitter Source to graph
    54. [2014-06-09 21:38:06,692] [Log    ] [MPMain   ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 0
    55. [2014-06-09 21:38:06,720] [Log    ] [57       ] [INFO ] - VideoPlayer9: found H264 video out pin
    56. [2014-06-09 21:38:06,722] [Log    ] [57       ] [INFO ] - Added filter: LAV Video Decoder to graph
    57. [2014-06-09 21:38:06,722] [Log    ] [57       ] [INFO ] - DirectShowUtils: First try to insert new audio renderer Default DirectSound Device
    58. [2014-06-09 21:38:06,723] [Log    ] [57       ] [INFO ] - DirectShowUtils: Found audio renderer
    59. [2014-06-09 21:38:06,724] [Log    ] [57       ] [DEBUG] - DirectShowUtils: added filter:Default DirectSound Device to graph
    60. [2014-06-09 21:38:06,728] [Log    ] [57       ] [INFO ] - Added filter: LAV Audio Decoder to graph
    61. [2014-06-09 21:38:06,728] [Log    ] [57       ] [DEBUG] - Filter: LAV Splitter Source - try to connect: Video
    62. [2014-06-09 21:38:06,728] [Log    ] [57       ] [DEBUG] - DirectShowUtil: build the graph for PIN : Video
    63. [2014-06-09 21:38:06,806] [Log    ] [57       ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces
    64. [2014-06-09 21:38:06,807] [Log    ] [57       ] [DEBUG] - Filter: LAV Splitter Source - try to connect: Audio
    65. [2014-06-09 21:38:06,807] [Log    ] [57       ] [DEBUG] - DirectShowUtil: volume handler value 61439
    66. [2014-06-09 21:38:06,808] [Log    ] [57       ] [DEBUG] - DirectShowUtil: build the graph for PIN : Audio
    67. [2014-06-09 21:38:06,827] [Log    ] [57       ] [DEBUG] - Check graph connections for: Default DirectSound Device
    68. [2014-06-09 21:38:06,827] [Log    ] [57       ] [DEBUG] - Check graph connections for: Enhanced Video Renderer
    69. [2014-06-09 21:38:06,827] [Log    ] [57       ] [DEBUG] - Check graph connections for: LAV Audio Decoder
    70. [2014-06-09 21:38:06,827] [Log    ] [57       ] [DEBUG] - Check graph connections for: LAV Video Decoder
    71. [2014-06-09 21:38:06,827] [Log    ] [57       ] [DEBUG] - Check graph connections for: LAV Splitter Source
    72. [2014-06-09 21:38:06,828] [Log    ] [57       ] [DEBUG] - MpcEngine: using texture size of 1280x720
    73. [2014-06-09 21:38:06,828] [Log    ] [57       ] [INFO ] - MpcEngine: Subtitle VideoPlayer CultureInfo sv
    74. [2014-06-09 21:38:06,844] [Log    ] [57       ] [DEBUG] - VideoPlayer: FoundStreams: Type=Video; Name=V: English [eng] (h264 main L5.1, yuv420p, 1280x528) [default], Filter=LAV Splitter Source, Id=0, PDWGroup=0, LCID=1033
    75. [2014-06-09 21:38:06,844] [Log    ] [57       ] [DEBUG] - VideoPlayer: FoundStreams: Type=Audio; Name=A: ac3, 48000 Hz, 5.1, 640 kb/s [default], Filter=LAV Splitter Source, Id=1, PDWGroup=1, LCID=0
    76. [2014-06-09 21:38:06,844] [Log    ] [57       ] [INFO ] - VideoPlayerVMR7: AudioLanguage CultureInfo sv
    77. [2014-06-09 21:38:06,852] [Log    ] [MPMain   ] [DEBUG] - VMR9: Repainting -> Playing, Frames: 50
    78. [2014-06-09 21:38:06,859] [Log    ] [57       ] [INFO ] - VideoPlayer:Duration:6992,579708
    79. [2014-06-09 21:38:06,859] [Log    ] [57       ] [INFO ] - g_Player.OnStarted() \\SERVER\Videos\300\300.mkv media:Video
    80. [2014-06-09 21:38:06,864] [Log    ] [57       ] [DEBUG] - VideodatabaseSqllite AddFile:\300.mkv
    81. [2014-06-09 21:38:06,866] [Log    ] [57       ] [INFO ] - VideoDatabase media info scanning file: \\SERVER\Videos\300\300.mkv
    82. [2014-06-09 21:38:06,870] [Log    ] [57       ] [DEBUG] - VideodatabaseSqllite Finished AddFile:\300.mkv
    83. [2014-06-09 21:38:06,872] [Log    ] [57       ] [DEBUG] - [WIFI_REMOTE]  Playback started!
    84. [2014-06-09 21:38:06,876] [Log    ] [57       ] [INFO ] - [WIFI_REMOTE]  {"Type":"nowplaying","Duration":6992,"File":"\\\\SERVER\\Videos\\300\\300.mkv","Position":0,"IsTv":false,"IsFullscreen":false,"MediaInfo":{"MediaType":"movie","MpExtId":"12","MpExtMediaType":0,"MpExtProviderId":3,"ItemId":12,"Summary":"Based on Frank Miller's graphic novel, \"300\" is very loosely based the 480 B.C. Battle of Thermopylae, where the King of Sparta led his army against the advancing Persians; the battle is said to have inspired all of Greece to band together against the Persians, and helped usher in the world's first democracy.","Title":"300","AlternateTitles":"|300: The IMAX Experience|300: Bitva u Thermopyl|Three Hundred|300|300|300壯士:斯巴達的逆襲|300 1|300 I|300 (2007)|300 (2006)|","Tagline":"Spartans, tonight, we dine in hell!","Directors":"|Zack Snyder|","Writers":"|Frank Miller|Zack Snyder|Kurt Johnstad|Michael B. Gordon|Lynn Varley|","Actors":"|Gerard Butler|Lena Headey|Dominic West|David Wenham|Vincent Regan|Michael Fassbender|Tom Wisdom|Andrew Tiernan|Andrew Pleavin|Rodrigo Santoro|Giovani Cimmino|Tyler Neitzel|Greg Kramer|Stephen McHattie|Alex Ivanovici|Kelly Craig|Eli Snyder|Tim Connolly|Marie-Julie Rivest|Sebastian St. Germain|Peter Mensah|Arthur Holden|Michael Sinelnikoff|John Dunn-Hill|Dennis St John|Neil Napier|Dylan Smith|Maurizio Terrazzano|Robert Paradis|Kwasi Songui|Alexandra Beaton|Frédéric Smith|","Rating":"7,7","Year":2006,"Genres":"|Action|Fantasy|History|War|","Certification":"R","DetailsUrl":"http://www.imdb.com/title/tt0416449","ImageName":"C:\\ProgramData\\Team MediaPortal\\MediaPortal\\Thumbs\\MovingPictures\\Covers\\FullSize\\{300} [1096401819].jpg"}}
    85. [2014-06-09 21:38:06,877] [Log    ] [49       ] [DEBUG] - [WIFI_REMOTE]  Start now-playing update thread
    86. [2014-06-09 21:38:06,987] [Log    ] [52       ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces
    87. [2014-06-09 21:38:07,060] [Log    ] [36       ] [DEBUG] - PlaneScene: crop T, B  : 0, 0
    88. [2014-06-09 21:38:07,060] [Log    ] [36       ] [DEBUG] - PlaneScene: crop L, R  : 0, 0
    89. [2014-06-09 21:38:07,060] [Log    ] [36       ] [INFO ] - PlaneScene: video WxH  : 1280x528
    90. [2014-06-09 21:38:07,061] [Log    ] [36       ] [DEBUG] - PlaneScene: video AR   : 80:33
    91. [2014-06-09 21:38:07,061] [Log    ] [36       ] [INFO ] - PlaneScene: screen WxH : 240x135
    92. [2014-06-09 21:38:07,061] [Log    ] [36       ] [DEBUG] - PlaneScene: AR type    : Normal
    93. [2014-06-09 21:38:07,062] [Log    ] [36       ] [DEBUG] - PlaneScene: PixelRatio : 1
    94. [2014-06-09 21:38:07,062] [Log    ] [36       ] [DEBUG] - PlaneScene: src        : (0,0)-(1280,528)
    95. [2014-06-09 21:38:07,062] [Log    ] [36       ] [DEBUG] - PlaneScene: dst        : (683,61)-(923,160)
    96. [2014-06-09 21:38:14,488] [Log    ] [MPMain   ] [DEBUG] - g_Player: ShowFullScreenWindow
    97. [2014-06-09 21:38:14,489] [Log    ] [MPMain   ] [INFO ] - g_Player: ShowFullScreenWindow switching to fullscreen video
    98. [2014-06-09 21:38:14,490] [Log    ] [MPMain   ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoPlayList deinit
    99. [2014-06-09 21:38:14,511] [Log    ] [MPMain   ] [DEBUG] - TextureManager: CleanupThumbs()
    100. [2014-06-09 21:38:14,526] [Log    ] [MPMain   ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFullscreen init
    101. [2014-06-09 21:38:14,527] [Log    ] [MPMain   ] [DEBUG] - Loading AR modes from "movies" section...
    102. [2014-06-09 21:38:14,565] [Log    ] [36       ] [DEBUG] - PlaneScene: crop T, B  : 0, 0
    103. [2014-06-09 21:38:14,565] [Log    ] [36       ] [DEBUG] - PlaneScene: crop L, R  : 0, 0
    104. [2014-06-09 21:38:14,566] [Log    ] [36       ] [INFO ] - PlaneScene: video WxH  : 1280x528
    105. [2014-06-09 21:38:14,566] [Log    ] [36       ] [DEBUG] - PlaneScene: video AR   : 80:33
    106. [2014-06-09 21:38:14,567] [Log    ] [36       ] [INFO ] - PlaneScene: screen WxH : 1920x1080
    107. [2014-06-09 21:38:14,567] [Log    ] [36       ] [DEBUG] - PlaneScene: AR type    : Normal
    108. [2014-06-09 21:38:14,567] [Log    ] [36       ] [DEBUG] - PlaneScene: PixelRatio : 1
    109. [2014-06-09 21:38:14,568] [Log    ] [36       ] [DEBUG] - PlaneScene: src        : (0,0)-(1280,528)
    110. [2014-06-09 21:38:14,568] [Log    ] [36       ] [DEBUG] - PlaneScene: dst        : (0,144)-(1920,936)
    111. [2014-06-09 21:38:16,943] [Log    ] [MPMain   ] [INFO ] - Main: Stopping media
    112. [2014-06-09 21:38:16,943] [Log    ] [MPMain   ] [DEBUG] - g_Player.doStop() keepTimeShifting = False keepExclusiveModeOn = False
    113. [2014-06-09 21:38:16,944] [Log    ] [MPMain   ] [INFO ] - g_Player.OnStopped()
    114. [2014-06-09 21:38:16,944] [Log    ] [MPMain   ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: current refreshrate is 23hz - changing it to 50hz
    115. [2014-06-09 21:38:16,945] [Log    ] [MPMain   ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: using internal win32 method for changing refreshrate. current is 23hz, desired is 50
    116. [2014-06-09 21:38:16,945] [Log    ] [MPMain   ] [INFO ] - RefreshRateChanger AdapterOrdinal value is 0
    117. [2014-06-09 21:38:16,946] [Log    ] [MPMain   ] [DEBUG] - CycleRefreshRate: Current MonitorIndex : 0 and current deviceName : \\.\DISPLAY1
    118. [2014-06-09 21:38:17,510] [Log    ] [MPMain   ] [DEBUG] - Main: WM_GETMINMAXINFO Start (MaxSize: 1920x1080 - MaxPostion: 0,0 - MinTrackSize: 2x2 - MaxTrackSize: 1932x1092)
    119. [2014-06-09 21:38:17,511] [Log    ] [MPMain   ] [DEBUG] - Main: WM_GETMINMAXINFO End (MaxSize: 1920x1080 - MaxPostion: 0,0 - MinTrackSize: 1920x1080 - MaxTrackSize: 1920x1080)
    120. [2014-06-09 21:38:17,513] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DISPLAYCHANGE
    121. [2014-06-09 21:38:17,928] [Log    ] [MPMain   ] [INFO ] - CycleRefreshRate: result DISP_CHANGE_SUCCESSFUL for refresh rate change 50Hz
    122. [2014-06-09 21:38:17,928] [Log    ] [MPMain   ] [DEBUG] - CycleRefresh: DWM Detected, performing shenanigans
    123. [2014-06-09 21:38:17,929] [Log    ] [MPMain   ] [INFO ] - RefreshRateChanger.SetRefreshRateBasedOnFPS: dynamic refresh rate change - notify video renderer
    124. [2014-06-09 21:38:18,109] [Log    ] [MPMain   ] [INFO ] - GUIVideoFiles: OnPlayBackStopped idFile=27 timeMovieStopped=9 resumeData= bdtitle=1000
    125. [2014-06-09 21:38:18,110] [Log    ] [MPMain   ] [DEBUG] - GUIVideoFiles: OnPlayBackStopped store resume time
    126. [2014-06-09 21:38:18,111] [Log    ] [MPMain   ] [INFO ] - RadioRecorded:OnPlayRecordingBackStopped Video \\SERVER\Videos\300\300.mkv
    127. [2014-06-09 21:38:18,112] [Log    ] [MPMain   ] [INFO ] - TvRecorded:OnPlayRecordingBackStopped Video \\SERVER\Videos\300\300.mkv
    128. [2014-06-09 21:38:18,112] [Log    ] [MPMain   ] [DEBUG] - [WIFI_REMOTE]  Playback stopped!
    129. [2014-06-09 21:38:18,112] [Log    ] [MPMain   ] [DEBUG] - g_Player.doStop() - stop
    130. [2014-06-09 21:38:18,112] [Log    ] [MPMain   ] [INFO ] - VideoPlayer:ended \\SERVER\Videos\300\300.mkv
    131. [2014-06-09 21:38:18,112] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup DShow graph
    132. [2014-06-09 21:38:18,129] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup Sub/PostProcess
    133. [2014-06-09 21:38:18,129] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup VideoCodec
    134. [2014-06-09 21:38:18,129] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup AudioCodec
    135. [2014-06-09 21:38:18,130] [Log    ] [MPMain   ] [DEBUG] - D3D: OnPaint()
    136. [2014-06-09 21:38:18,134] [Error  ] [MPMain   ] [ERROR] - VideoPlayer9: Exception while cleanuping DShow graph - Unable to cast COM object of type 'System.__ComObject' to interface type 'DirectShowLib.IBaseFilter'. This operation failed because the QueryInterface call on the COM component for the interface with IID '{56A86895-0AD4-11CE-B03A-0020AF0BA770}' failed due to the following error: No such interface supported (Exception from HRESULT: 0x80004002 (E_NOINTERFACE)).    at System.StubHelpers.StubHelpers.GetCOMIPFromRCW(Object objSrc, IntPtr pCPCMD, IntPtr& ppTarget, Boolean& pfNeedsRelease)
    137.    at DirectShowLib.IBaseFilter.EnumPins(IEnumPins& ppEnum)
    138.    at DShowNET.Helper.DirectShowUtil.DisconnectAllPins(IGraphBuilder graphBuilder, IBaseFilter filter)
    139.    at MediaPortal.Player.VideoPlayerVMR9.Cleanup()
    140. [2014-06-09 21:38:18,135] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Disabling DX9 exclusive mode
    141. [2014-06-09 21:38:18,135] [Log    ] [MPMain   ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete.
    142. [2014-06-09 21:38:18,136] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup DShow graph
    143. [2014-06-09 21:38:18,136] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup Sub/PostProcess
    144. [2014-06-09 21:38:18,136] [Log    ] [MPMain   ] [DEBUG] - D3D: OnPaint()
    145. [2014-06-09 21:38:18,137] [Error  ] [MPMain   ] [ERROR] - VideoPlayer9: Exception while cleanuping DShow graph - Unable to cast COM object of type 'System.__ComObject' to interface type 'DirectShowLib.IBaseFilter'. This operation failed because the QueryInterface call on the COM component for the interface with IID '{56A86895-0AD4-11CE-B03A-0020AF0BA770}' failed due to the following error: No such interface supported (Exception from HRESULT: 0x80004002 (E_NOINTERFACE)).    at System.StubHelpers.StubHelpers.GetCOMIPFromRCW(Object objSrc, IntPtr pCPCMD, IntPtr& ppTarget, Boolean& pfNeedsRelease)
    146.    at DirectShowLib.IBaseFilter.EnumPins(IEnumPins& ppEnum)
    147.    at DShowNET.Helper.DirectShowUtil.DisconnectAllPins(IGraphBuilder graphBuilder, IBaseFilter filter)
    148.    at MediaPortal.Player.VideoPlayerVMR9.Cleanup()
    149. [2014-06-09 21:38:18,137] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Disabling DX9 exclusive mode
    150. [2014-06-09 21:38:18,137] [Log    ] [MPMain   ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete.
    151. [2014-06-09 21:38:18,138] [Log    ] [MPMain   ] [INFO ] - GUIVideoFullscreen:stop
    152. [2014-06-09 21:38:18,138] [Log    ] [MPMain   ] [DEBUG] - Windowmanager: Goto previous window
    153. [2014-06-09 21:38:18,138] [Log    ] [MPMain   ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFullscreen deinit
    154. [2014-06-09 21:38:18,139] [Log    ] [MPMain   ] [DEBUG] - TextureManager: CleanupThumbs()
    155. [2014-06-09 21:38:18,157] [Log    ] [MPMain   ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\myvideoplaylist.xml' is missing control id 6 (window property: btnPlayDVD)
    156. [2014-06-09 21:38:18,157] [Log    ] [MPMain   ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\myvideoplaylist.xml' is missing control id 7 (window property: btnScanNew)
    157. [2014-06-09 21:38:18,157] [Log    ] [MPMain   ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\myvideoplaylist.xml' is missing control id 8 (window property: btnTrailers)
    158. [2014-06-09 21:38:18,158] [Log    ] [MPMain   ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\myvideoplaylist.xml' is missing control id 3 (window property: btnSortBy)
    159. [2014-06-09 21:38:18,158] [Log    ] [MPMain   ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\Titan\myvideoplaylist.xml' is missing control id 5 (window property: btnViews)
    160. [2014-06-09 21:38:18,195] [Log    ] [MPMain   ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoPlayList init
    161. [2014-06-09 21:38:18,195] [Log    ] [MPMain   ] [DEBUG] - TextureManager: CleanupThumbs()
    162. [2014-06-09 21:38:18,443] [Log    ] [MPMain   ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
    163. [2014-06-09 21:38:18,443] [Log    ] [MPMain   ] [INFO ] - Main: Deactivation request received
    164. [2014-06-09 21:38:18,444] [Log    ] [MPMain   ] [INFO ] - Main: Refresh rate changer running. Ignoring deactivation request
    165. [2014-06-09 21:38:18,444] [Log    ] [MPMain   ] [DEBUG] - D3D: OnLostFocus()
    166. [2014-06-09 21:38:18,446] [Log    ] [MPMain   ] [DEBUG] - Main: WM_ACTIVATE (WA_ACTIVE)
    167. [2014-06-09 21:38:18,446] [Log    ] [MPMain   ] [INFO ] - Main: Activation request received
    168. [2014-06-09 21:38:18,447] [Log    ] [MPMain   ] [DEBUG] - D3D: OnGotFocus()
    169. [2014-06-09 21:38:18,878] [Log    ] [49       ] [DEBUG] - [WIFI_REMOTE]  Stop now-playing update thread
    170. [2014-06-09 21:38:19,971] [Log    ] [MPMain   ] [DEBUG] - VMR9Helper: Playing -> Repainting, Frames 0
    171. [2014-06-09 21:38:29,060] [Log    ] [MPMain   ] [DEBUG] - D3D: OnFormClosing()
    172. [2014-06-09 21:38:29,061] [Log    ] [MPMain   ] [DEBUG] - D3D CleanupEnvironment()
     
    • Thank You! Thank You! x 2
  4. xppx

    xppx Portal Pro

    Joined:
    September 2, 2007
    Messages:
    71
    Likes Received:
    15
    Ratings:
    +29 / 0
    Home Country:
    Belgium Belgium
    Show System Specs
    Sniplet from the last generated log file - focused on when the error occurs -

    I get the error a bit later - after the "VideoPlayer9: Disabling DX9 exclusive mode"

    Code (Text):
    1. [2014-06-09 21:44:10,196] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup DShow graph
    2. [2014-06-09 21:44:10,343] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup Sub/PostProcess
    3. [2014-06-09 21:44:10,344] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup VideoCodec
    4. [2014-06-09 21:44:10,344] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup AudioCodec
    5. [2014-06-09 21:44:10,344] [Log    ] [MPMain   ] [INFO ] - Disconnecting all pins from filter DirectSound: Onkyo TX-SR605 [Receiver - Optical] (Realtek High Definition Audio)
    6. [2014-06-09 21:44:10,345] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup AudioRenderer
    7. [2014-06-09 21:44:10,345] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup InterfaceSourceFilter
    8. [2014-06-09 21:44:10,345] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup PostProcess
    9. [2014-06-09 21:44:10,345] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup PostProcess MediaPortal AudioSwitcher
    10. [2014-06-09 21:44:10,346] [Log    ] [MPMain   ] [INFO ] - Disconnecting all pins from filter Enhanced Video Renderer
    11. [2014-06-09 21:44:10,346] [Log    ] [MPMain   ] [DEBUG] - Remove filter from graph: Enhanced Video Renderer 2
    12. [2014-06-09 21:44:10,346] [Log    ] [MPMain   ] [INFO ] - Disconnecting all pins from filter LAV Audio Decoder
    13. [2014-06-09 21:44:10,346] [Log    ] [MPMain   ] [INFO ] - Disconnecting pin Output
    14. [2014-06-09 21:44:10,347] [Log    ] [MPMain   ] [INFO ] -   Not connected
    15. [2014-06-09 21:44:10,347] [Log    ] [MPMain   ] [DEBUG] - Remove filter from graph: LAV Audio Decoder 1
    16. [2014-06-09 21:44:10,347] [Log    ] [MPMain   ] [INFO ] - Disconnecting all pins from filter CoreAVC Video Decoder
    17. [2014-06-09 21:44:10,347] [Log    ] [MPMain   ] [INFO ] - Disconnecting pin Output
    18. [2014-06-09 21:44:10,347] [Log    ] [MPMain   ] [INFO ] -   Not connected
    19. [2014-06-09 21:44:10,367] [Log    ] [MPMain   ] [DEBUG] - Remove filter from graph: CoreAVC Video Decoder 1
    20. [2014-06-09 21:44:10,368] [Log    ] [MPMain   ] [INFO ] - Disconnecting all pins from filter Haali Media Splitter
    21. [2014-06-09 21:44:10,368] [Log    ] [MPMain   ] [INFO ] - Disconnecting pin Video
    22. [2014-06-09 21:44:10,368] [Log    ] [MPMain   ] [INFO ] -   Not connected
    23. [2014-06-09 21:44:10,368] [Log    ] [MPMain   ] [INFO ] - Disconnecting pin Audio
    24. [2014-06-09 21:44:10,368] [Log    ] [MPMain   ] [INFO ] -   Not connected
    25. [2014-06-09 21:44:10,368] [Log    ] [MPMain   ] [DEBUG] - Remove filter from graph: Haali Media Splitter 3
    26. [2014-06-09 21:44:10,368] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Cleanup Graphbuilder
    27. [2014-06-09 21:44:10,369] [Log    ] [MPMain   ] [DEBUG] - VMR9: Dispose
    28. [2014-06-09 21:44:10,369] [Log    ] [MPMain   ] [DEBUG] - SubtitleRenderer: starting cleanup
    29. [2014-06-09 21:44:10,369] [Log    ] [MPMain   ] [DEBUG] - SubtitleRenderer: cleanup done
    30. [2014-06-09 21:44:10,369] [Log    ] [MPMain   ] [DEBUG] - VMR9: Inactive
    31. [2014-06-09 21:44:10,378] [Log    ] [MPMain   ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces
    32. [2014-06-09 21:44:10,401] [Log    ] [MPMain   ] [INFO ] - VideoPlayer9: Disabling DX9 exclusive mode
    33. [2014-06-09 21:44:10,401] [Log    ] [MPMain   ] [INFO ] - Main: GUI_MSG_SWITCH_FULL_WINDOWED message is obsolete.
    34. [2014-06-09 21:44:10,401] [Log    ] [MPMain   ] [INFO ] - GUIVideoFullscreen:stop
    35. [2014-06-09 21:44:10,401] [Log    ] [MPMain   ] [DEBUG] - Windowmanager: Goto previous window
    36. [2014-06-09 21:44:10,402] [Log    ] [MPMain   ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFullscreen deinit
    37. [2014-06-09 21:44:10,403] [Log    ] [MPMain   ] [DEBUG] - TextureManager: CleanupThumbs()
    38. [2014-06-09 21:44:10,404] [Log    ] [MPMain   ] [DEBUG] - Window: MediaPortal.GUI.Video.GUIVideoFiles init
    39. [2014-06-09 21:44:10,450] [Log    ] [MPMain   ] [DEBUG] - D3D: OnPaint()
    40. [2014-06-09 21:44:11,451] [Log    ] [MPMain   ] [DEBUG] - VirtualDirectory: this file is not remote
    41. [2014-06-09 21:44:11,462] [Log    ] [MPMain   ] [DEBUG] - WindowManager: route MediaPortal.GUI.Video.GUIVideoFiles:6->MediaPortal.Dialogs.GUIDialogMenu:2012
    42. [2014-06-09 21:44:11,463] [Log    ] [MPMain   ] [DEBUG] - DialogWindow: MediaPortal.Dialogs.GUIDialogMenu init
    43. [2014-06-09 21:44:12,704] [Log    ] [MPMain   ] [DEBUG] - WindowManager: unroute to MediaPortal.Dialogs.GUIDialogMenu:2012->MediaPortal.GUI.Video.GUIVideoFiles:6
    44. ...
    45. [2014-06-09 21:44:12,794] [Log    ] [MPMain   ] [INFO ] - RefreshRateChanger.AdaptRefreshRate: 'auto refreshrate changer' disabled
    46. [2014-06-09 21:44:12,794] [Log    ] [MPMain   ] [INFO ] - g_Player.Play(M:\Clips\The Walking Dead Cold Storage\Webisode 03 The Chosen Ones.mp4 Video)
    47. [2014-06-09 21:44:12,795] [Log    ] [MPMain   ] [DEBUG] - PlayerFactory: Successfully created player instance for file - M:\Clips\The Walking Dead Cold Storage\Webisode 03 The Chosen Ones.mp4
    48. [2014-06-09 21:44:12,795] [Log    ] [MPMain   ] [INFO ] - VideoPlayer:play M:\Clips\The Walking Dead Cold Storage\Webisode 03 The Chosen Ones.mp4
    49. [2014-06-09 21:44:12,799] [Error  ] [MPMain   ] [ERROR] - VideoPlayer9: Exception while creating DShow graph Creating an instance of the COM component with CLSID {E436EBB3-524F-11CE-9F53-0020AF0BA770} from the IClassFactory failed due to the following error: 8000ffff Catastrophic failure (Exception from HRESULT: 0x8000FFFF (E_UNEXPECTED)).    at MediaPortal.Player.VideoPlayerVMR9.GetInterfaces()
    50. [2014-06-09 21:44:12,819] [Log    ] [MPMain   ] [INFO ] - g_Player: ended
    51. [2014-06-09 21:44:12,820] [Error  ] [MPMain   ] [ERROR] - PlaylistPlayer: *** unable to play - M:\Clips\The Walking Dead Cold Storage\Webisode 03 The Chosen Ones.mp4 - skipping track!
     
  5. Sebastiii
    • Team MediaPortal

    Sebastiii Development Group

    Joined:
    November 12, 2007
    Messages:
    16,238
    Likes Received:
    6,201
    Gender:
    Male
    Location:
    France
    Ratings:
    +10,199 / 9
    Home Country:
    France France
    Show System Specs
    Sorry :)
    We can try tomorrow, we need to find the broken commit to be able to analyse it for sure lol

    Maybe you can setup on your PC (not the htpc) dev env build to be able to compil source and then it will be faster than me to upload you release build.
    In between i will try to create a new 1.6.0 but at a random commit to see if we can start from there or after :)
     
    • Agree Agree x 1
  6. xppx

    xppx Portal Pro

    Joined:
    September 2, 2007
    Messages:
    71
    Likes Received:
    15
    Ratings:
    +29 / 0
    Home Country:
    Belgium Belgium
    Show System Specs
    No problem. Take your time.

    During the week I do not have anyway a lot of time in the evening (I spend long days at work).
    If you give me the directions on how to setup a local build (incl. all artefacts: project structure, libraries, compiler, (ant?) build scripts) then I will do that.

    Thanks again for your effort! :cool:
     
    • Like Like x 1
  7. Sebastiii
    • Team MediaPortal

    Sebastiii Development Group

    Joined:
    November 12, 2007
    Messages:
    16,238
    Likes Received:
    6,201
    Gender:
    Male
    Location:
    France
    Ratings:
    +10,199 / 9
    Home Country:
    France France
    Show System Specs
    • Agree Agree x 1
  8. Sebastiii
    • Team MediaPortal

    Sebastiii Development Group

    Joined:
    November 12, 2007
    Messages:
    16,238
    Likes Received:
    6,201
    Gender:
    Male
    Location:
    France
    Ratings:
    +10,199 / 9
    Home Country:
    France France
    Show System Specs
    New bin to test : https://dl.dropboxusercontent.com/u/10536084/mp/MP1.5/MP.1.6.0Pre-release-MP1-4116-Actors.zip
    I have reset the 1.6.0Pre-release to that commit :
    Code (Text):
    1. SHA-1: 5f3b5d1c7e8f1c5bc9d453043270b726d19059ec
    2. * MP1-4116-Actors update (fetch fix)
    3. - prevent saving movieinfo to the database on actors fetch while scraping movie before all movieinfo metadata are set
    In 1.6.0, we have major update (NET4 and co) some change into EVR related to refresh rate etc.
    So will see how it does with this build :)
     
    • Thank You! Thank You! x 1
  9. xppx

    xppx Portal Pro

    Joined:
    September 2, 2007
    Messages:
    71
    Likes Received:
    15
    Ratings:
    +29 / 0
    Home Country:
    Belgium Belgium
    Show System Specs
    Just back from work.

    I'll test the new bin tomorrow if that's ok with you.

    And I'll try to get hold of VS 2013... for the dev setup.
    Do I need specific acces rights to GIT? Or is setting up a GitHub account enough?
     
    • Agree Agree x 1
  10. Sebastiii
    • Team MediaPortal

    Sebastiii Development Group

    Joined:
    November 12, 2007
    Messages:
    16,238
    Likes Received:
    6,201
    Gender:
    Male
    Location:
    France
    Ratings:
    +10,199 / 9
    Home Country:
    France France
    Show System Specs
    No need to have a GIT account but you can create one if you want but that's not needed, you can grab source as public and start to build when you have all ready for building :)

    No need to have ant for that lol but maybe you can help (for later) the BD-J part where ant and co are needed for building all needed Jar :) (but that not related to MP1 repo :p (for now) )
     
  11. xppx

    xppx Portal Pro

    Joined:
    September 2, 2007
    Messages:
    71
    Likes Received:
    15
    Ratings:
    +29 / 0
    Home Country:
    Belgium Belgium
    Show System Specs
    Worked again late today...

    Did test the 1.6.0-pre build. See result.

    Just a thought - most probably a stupid question - but how do you manage synchronization in MP? As developer I would look for methods that are running in parallel and do not respect an order when they actually should: the cleanup might take too long (which might cover another issue to be resolved) and the initiation of the next video thread perhaps doesn't wait until the cleanup is finished... (if both methods were synchronized against the default lock manager, they would be slower but never run at the same time and thus avoid a racing condition) - typical side effect is that errors that might appear or not depending on the hardware (number of CPU's, speed) and the system (number of available threads) and the software (each compile might change the behavior a bit). And we see the error appear on the 3rd video for 1.6 in debug, 4th video for 1.7 and 1.8pre in debug, and 2nd video in normal mode...
    What do you think?

    Result: still error
    Code (Text):
    1. MP 1.6 pre - Commit: SHA-1: 5f3b5d1c7e8f1c5bc9d453043270b726d19059ec
    2. Refresh rate changer disabled
    3. Result: Error after start of 3rd video (in 1.7 and 1.8 this happens on the 4th in debug mode and on the 2nd in normal mode)
     
    Last edited: June 11, 2014
    • Thank You! Thank You! x 1
Loading...

Users Viewing Thread (Users: 0, Guests: 0)

  1. This site uses cookies to help personalise content, tailor your experience and to keep you logged in if you register.
    By continuing to use this site, you are consenting to our use of cookies.
    Dismiss Notice
  • About The Project

    The vision of the MediaPortal project is to create a free open source media centre application, which supports all advanced media centre functions, and is accessible to all Windows users.

    In reaching this goal we are working every day to make sure our software is one of the best.

             

  • Support MediaPortal!

    The team works very hard to make sure the community is running the best HTPC-software. We give away MediaPortal for free but hosting and software is not for us.

    Care to support our work with a few bucks? We'd really appreciate it!