search

add-ons / plugin.video.vrt.nu

Kodi add-on to watch content from VRT MAX
https://www.facebook.com/groups/kodivlaanderen
GNU General Public License v3.0
110 stars 20 forks source link

v2.3.0 issue tracker (ResumePoints, UpNext, overlay, cosmetics) #616

Closed dagwieers closed 4 years ago

dagwieers commented 4 years ago

Describe the bug

Let's try and make this the last issue to stabilize the current implementation. And not introduce any more changes unless they fix a known issue, and retest past issues.

Tests to perform on every change:

Known past issues:

mediaminister commented 4 years ago

After playing videos (from "Most recent") it returns an episode listing with thumbs as icons

I can confirm this also happens in other menus and is not fixed yet.

mediaminister commented 4 years ago

While testing I found out that ActivateWindow works more reliable to update the container after Kodi Player closes. I couldn't reproduce any of the past known issues anymore!

mediaminister commented 4 years ago

After merging https://github.com/pietje666/plugin.video.vrt.nu/pull/640 we still need to find a solution to mark "Up Next" episodes as watched.

dagwieers commented 4 years ago

After merging #640 we still need to find a solution to mark "Up Next" episodes as watched.

I was hoping that jumping to the end (or near-end) briefly would work. If we can make this happen invisibly (maybe using batched JSONRPC?). I tried this and couldn't make this work, but may require more (isolated) testing.

mediaminister commented 4 years ago

Internal Kodi watch status is based on the ListItem path, so this would never work with the plugin://plugin.video.vrt.nu/play/upnext/ path that is not initiated from a ListItem.

mediaminister commented 4 years ago

@dagwieers It seems the unit test test_update_watchlater is removing existing watchlater episodes and fails when watchlater and continue menus are empty.

Not sure if this is a bad unit test or if there is a bug in resumepoints.py

    @unittest.skipUnless(addon.settings.get('username'), 'Skipping as VRT username is missing.')
    @unittest.skipUnless(addon.settings.get('password'), 'Skipping as VRT password is missing.')
    def test_update_watchlater(self):
        """Test updating the watch later list"""
        self._resumepoints.refresh(ttl=0)
        asset_id, first_entry = next(iter(list(self._resumepoints._data.items())))  # pylint: disable=protected-access
        print('%s = %s' % (asset_id, first_entry))
        url = first_entry.get('value').get('url')
        self._resumepoints.watchlater(asset_id=asset_id, title='Foo bar', url=url)
        self._resumepoints.unwatchlater(asset_id=asset_id, title='Foo bar', url=url)
        self._resumepoints.refresh(ttl=0)
        asset_id, first_entry = next(iter(list(self._resumepoints._data.items())))  # pylint: disable=protected-access
        print('%s = %s' % (asset_id, first_entry))

This problem is fixed in https://github.com/pietje666/plugin.video.vrt.nu/pull/650

dagwieers commented 4 years ago

Everything seems to working well. There is still one issue I see from time to time. Forwarding to the end of a video skips back to where you started from. This is not always the cases, but worsens over time it seems. It may not be a VRT NU issue but a combination with other Monitor/Player services, or a Kodi Player bug.

When this happens again I will have to investigate this more closely. (e.g. disable other services) but our own logging doesn't show anything wrong and I don't see a mechanism in our code that would cause this.

Not sure if this warrants delaying a release.

mediaminister commented 4 years ago

I hope my PR fixes these issues: https://github.com/pietje666/plugin.video.vrt.nu/pull/652

dagwieers commented 4 years ago

I once again experienced an empty virtual directory after watching the 3rd episode of The Clinton Affair docu from the Watch later menu. Since I am currently running a LibreELEC 9.2.0 development branch (for GLES game fixes) the log is a full debug log. Here it starts from the moment the player stopped.

2019-12-24 15:51:22.433 T:1156576128   DEBUG: CMMALPool::Configure pool:0x53807320 960x540 (960x544) pix:-1 size:128 fmt:OPQV
2019-12-24 15:51:22.454 T:1137574784   DEBUG: Previous line repeats 1 times.
2019-12-24 15:51:22.454 T:1137574784   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_EOF
2019-12-24 15:51:22.468 T:1156576128   DEBUG: CMMALPool::Configure pool:0x53807320 960x540 (960x544) pix:-1 size:128 fmt:OPQV
2019-12-24 15:51:22.623 T:1137574784   DEBUG: Previous line repeats 2 times.
2019-12-24 15:51:22.623 T:1137574784  NOTICE: CVideoPlayerAudio::Process - stream stalled
2019-12-24 15:51:22.637 T:1156576128   DEBUG: CMMALPool::Configure pool:0x53807320 960x540 (960x544) pix:-1 size:128 fmt:OPQV
2019-12-24 15:51:22.649 T:1209590656   DEBUG: Previous line repeats 2 times.
2019-12-24 15:51:22.650 T:1209590656    INFO: Process - eof reading from demuxer
2019-12-24 15:51:22.650 T:1209590656  NOTICE: CVideoPlayer::OnExit()
2019-12-24 15:51:22.650 T:1209590656  NOTICE: VideoPlayer: eof, waiting for queues to empty
2019-12-24 15:51:22.650 T:1209590656  NOTICE: Closing stream player 1
2019-12-24 15:51:22.650 T:1209590656  NOTICE: CDVDMessageQueue(audio)::WaitUntilEmpty
2019-12-24 15:51:22.650 T:1209590656  NOTICE: Waiting for audio thread to exit
2019-12-24 15:51:22.651 T:1137574784   DEBUG: CVideoPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
2019-12-24 15:51:22.651 T:1137574784  NOTICE: thread end: CVideoPlayerAudio::OnExit()
2019-12-24 15:51:22.651 T:1209590656  NOTICE: Closing audio device
2019-12-24 15:51:22.651 T:1137574784   DEBUG: Thread VideoPlayerAudio 1137574784 terminating
2019-12-24 15:51:22.654 T:1914717056   DEBUG: CActiveAE::DiscardStream - audio stream deleted
2019-12-24 15:51:22.654 T:1914717056   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2019-12-24 15:51:22.655 T:1209590656   DEBUG: Previous line repeats 1 times.
2019-12-24 15:51:22.655 T:1209590656  NOTICE: Deleting audio codec
2019-12-24 15:51:22.655 T:1209590656  NOTICE: Closing stream player 2
2019-12-24 15:51:22.655 T:1209590656  NOTICE: CDVDMessageQueue(video)::WaitUntilEmpty
2019-12-24 15:51:22.746 T:1179538304   DEBUG: CVideoPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
2019-12-24 15:51:22.747 T:1209590656  NOTICE: waiting for video thread to exit
2019-12-24 15:51:22.747 T:1179538304   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2019-12-24 15:51:22.747 T:1179538304  NOTICE: thread end: video_thread
2019-12-24 15:51:22.747 T:1179538304   DEBUG: Thread VideoPlayerVideo 1179538304 terminating
2019-12-24 15:51:22.747 T:1209590656  NOTICE: deleting video codec
2019-12-24 15:51:22.753 T:1179538304   DEBUG: Thread JobWorker start, auto delete: true
2019-12-24 15:51:22.757 T:1209590656   DEBUG: Thread VideoPlayer 1209590656 terminating
2019-12-24 15:51:22.769 T:1209590656   DEBUG: Thread JobWorker start, auto delete: true
2019-12-24 15:51:22.770 T:1179538304   DEBUG: DoWork - Saving file state for video item plugin://plugin.video.vrt.nu/play/id/vid-7153a2c6-b4ec-4e31-8ae3-34d5bec8a456/pbs-pub-a5f9d9dc-0700-4f56-bbb6-adad8b63f4dc
2019-12-24 15:51:22.791 T:1179538304   DEBUG: DoWork - Marking video item plugin://plugin.video.vrt.nu/play/id/vid-7153a2c6-b4ec-4e31-8ae3-34d5bec8a456/pbs-pub-a5f9d9dc-0700-4f56-bbb6-adad8b63f4dc as watched
2019-12-24 15:51:22.796 T:1179538304   DEBUG: Mysql execute: insert into files (idFile, idPath, strFileName) values(NULL, 9102, 'plugin://plugin.video.vrt.nu/play/id/vid-7153a2c6-b4ec-4e31-8ae3-34d5bec8a456/pbs-pub-a5f9d9dc-0700-4f56-bbb6-adad8b63f4dc')
2019-12-24 15:51:22.823 T:1914717056   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2019-12-24 15:51:22.851 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.852 T:1090245504  NOTICE: [plugin.video.vrt.nu] [PlayerInfo 2462] Event onThreadExit
2019-12-24 15:51:22.852 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.854 T:1090245504  NOTICE: [plugin.video.vrt.nu] [Resumepoints] Update resumepoint 'contentdamvrt20191002theclintonaffairr001a0003depotwp00157709' 5168.74902344/5169.04003906
2019-12-24 15:51:22.855 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.855 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.856 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.857 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.857 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.858 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.869 T:1179538304   DEBUG: Mysql execute: update files set playCount=1,lastPlayed='2019-12-24 15:51:22' where idFile=49516
2019-12-24 15:51:22.870 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.871 T:1090245504  NOTICE: [plugin.video.vrt.nu] Got cached token '/storage/.kodi/userdata/addon_data/plugin.video.vrt.nu/tokens/user_XVRTToken.tkn'
2019-12-24 15:51:22.872 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:22.873 T:1090245504  NOTICE: [plugin.video.vrt.nu] URL get: https://video-user-data.vrt.be/resume_points
2019-12-24 15:51:22.876 T:1179538304   DEBUG: Mysql execute: delete from bookmark where idFile=49516 and type=1
2019-12-24 15:51:22.898 T:1209590656   DEBUG: OnPlayBackEnded: CApplication::OnPlayBackEnded
2019-12-24 15:51:22.923 T:1905259392    INFO: CActiveAESink::OpenSink - initialize sink
2019-12-24 15:51:22.925 T:1937117200  NOTICE: CVideoPlayer::CloseFile()
2019-12-24 15:51:22.925 T:1937117200   DEBUG: DeleteRenderer - deleting renderer
2019-12-24 15:51:22.925 T:1937117200   DEBUG: CMMALRenderer::~CMMALRenderer
2019-12-24 15:51:22.926 T:1937117200   DEBUG: CMMALRenderer::UnInitMMAL
2019-12-24 15:51:22.930 T:1112396672   DEBUG: CMMALRenderer::Process - stopping
2019-12-24 15:51:22.930 T:1112396672   DEBUG: Thread MMALRenderer 1112396672 terminating
2019-12-24 15:51:22.949 T:1937117200   DEBUG: CMMALPool::~CMMALPool Destroying pool 0x53807320 for port vc.ril.video_decode:out:0(OPQV)
2019-12-24 15:51:22.955 T:1129182080   DEBUG: CMMALRenderer::Run - stopping
2019-12-24 15:51:22.955 T:1129182080   DEBUG: Thread MMALProcess 1129182080 terminating
2019-12-24 15:51:22.955 T:1937117200  NOTICE: VideoPlayer: waiting for threads to exit
2019-12-24 15:51:22.955 T:1937117200  NOTICE: VideoPlayer: finished waiting
2019-12-24 15:51:22.955 T:1937117200  NOTICE: CVideoPlayer::CloseFile()
2019-12-24 15:51:22.956 T:1937117200  NOTICE: VideoPlayer: waiting for threads to exit
2019-12-24 15:51:22.956 T:1937117200  NOTICE: VideoPlayer: finished waiting
2019-12-24 15:51:22.960 T:1937117200   DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
2019-12-24 15:51:22.960 T:1937117200   DEBUG: CMMALPool::~CMMALPool Destroying pool 0x53583200 for port vc.ril.video_decode:out:0(I420)
2019-12-24 15:51:22.961 T:1937117200   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
2019-12-24 15:51:22.962 T:1937117200   DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
2019-12-24 15:51:22.994 T:1937117200   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
2019-12-24 15:51:22.994 T:1937117200   DEBUG: ------ Window Init (MyVideoNav.xml) ------
2019-12-24 15:51:23.002 T:1937117200   ERROR: Control 55 in window 10025 has been asked to focus, but it can't
2019-12-24 15:51:23.003 T:1209590656    INFO: WEATHER: Downloading weather
2019-12-24 15:51:23.003 T:1104003968   DEBUG: onExecutionDone(46, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py)
2019-12-24 15:51:23.006 T:1937117200   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.vrt.nu/resumepoints/watchlater)
2019-12-24 15:51:23.006 T:1937117200   DEBUG:   ParentPath = [plugin://plugin.video.vrt.nu/resumepoints/watchlater]
2019-12-24 15:51:23.008 T:1065067392   DEBUG: Thread waiting start, auto delete: false
2019-12-24 15:51:23.011 T:1129182080   DEBUG: Thread LanguageInvoker start, auto delete: false
2019-12-24 15:51:23.011 T:1129182080    INFO: initializing python engine.
2019-12-24 15:51:23.011 T:1129182080   DEBUG: CPythonInvoker(47, /storage/.kodi/addons/weather.yahoo/default.py): start processing
2019-12-24 15:51:23.022 T:1905259392   DEBUG: CAESinkPi:Drain delay:99ms now:0ms
2019-12-24 15:51:23.023 T:1905259392   DEBUG: CAESinkPi:Deinitialize
2019-12-24 15:51:23.023 T:1905259392   DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000
2019-12-24 15:51:23.027 T:1905259392   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x57e3e320
2019-12-24 15:51:23.028 T:1905259392   DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI
2019-12-24 15:51:23.028 T:1905259392   DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI
2019-12-24 15:51:23.028 T:1905259392   DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008
2019-12-24 15:51:23.029 T:1905259392   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0x70f54b00
2019-12-24 15:51:23.031 T:1905259392   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16)
2019-12-24 15:51:23.032 T:1905259392   DEBUG: CActiveAESink::OpenSink - SinkPi Initialized:
2019-12-24 15:51:23.032 T:1905259392   DEBUG:   Output Device : HDMI
2019-12-24 15:51:23.032 T:1905259392   DEBUG:   Sample Rate   : 44100
2019-12-24 15:51:23.032 T:1905259392   DEBUG:   Sample Format : AE_FMT_FLOAT
2019-12-24 15:51:23.032 T:1905259392   DEBUG:   Channel Count : 2
2019-12-24 15:51:23.032 T:1905259392   DEBUG:   Channel Layout: FL, FR
2019-12-24 15:51:23.032 T:1905259392   DEBUG:   Frames        : 2205
2019-12-24 15:51:23.032 T:1905259392   DEBUG:   Frame Size    : 8
2019-12-24 15:51:23.034 T:1914717056   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2019-12-24 15:51:23.034 T:1179538304   DEBUG: Previous line repeats 1 times.
2019-12-24 15:51:23.034 T:1179538304   DEBUG: CAddonDatabase::SetLastUsed[plugin.video.vrt.nu] took 28 ms
2019-12-24 15:51:23.035 T:1065067392   DEBUG: StartScript - calling plugin VRT NU('plugin://plugin.video.vrt.nu/resumepoints/watchlater','20','','resume:false')
2019-12-24 15:51:23.035 T:1156576128   DEBUG: Thread JobWorker start, auto delete: true
2019-12-24 15:51:23.035 T:1380701056   DEBUG: Thread LanguageInvoker start, auto delete: false
2019-12-24 15:51:23.035 T:1380701056    INFO: initializing python engine.
2019-12-24 15:51:23.036 T:1380701056   DEBUG: CPythonInvoker(48, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): start processing
2019-12-24 15:51:23.108 T:1937117200   DEBUG: ------ Window Init (DialogBusy.xml) ------
2019-12-24 15:51:23.165 T:1104003968    INFO: Python interpreter stopped
2019-12-24 15:51:23.165 T:1104003968    INFO: Previous line repeats 37 times.
2019-12-24 15:51:23.165 T:1104003968   DEBUG: Thread LanguageInvoker 1104003968 terminating
2019-12-24 15:51:23.186 T:1656652672   DEBUG: CAddonSettings[service.upnext]: loading setting definitions
2019-12-24 15:51:23.187 T:1656652672   DEBUG: CAddonSettings[service.upnext]: trying to load setting definitions from old format...
2019-12-24 15:51:23.188 T:1656652672   DEBUG: CAddonSettings[service.upnext]: loading setting values
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CSettingsManager: requested setting (developerMode) was not found.
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting developerMode. Creating a setting on-the-fly...
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CSettingsManager: requested setting (shortPlayLength) was not found.
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayLength. Creating a setting on-the-fly...
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CSettingsManager: requested setting (shortPlayMode) was not found.
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayMode. Creating a setting on-the-fly...
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CSettingsManager: requested setting (shortPlayNotification) was not found.
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayNotification. Creating a setting on-the-fly...
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CSettingsManager: requested setting (windowMode) was not found.
2019-12-24 15:51:23.189 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting windowMode. Creating a setting on-the-fly...
2019-12-24 15:51:23.191 T:1656652672   DEBUG: CAddonSettings[service.upnext]: loading setting definitions
2019-12-24 15:51:23.192 T:1656652672   DEBUG: CAddonSettings[service.upnext]: trying to load setting definitions from old format...
2019-12-24 15:51:23.193 T:1656652672   DEBUG: CAddonSettings[service.upnext]: loading setting values
2019-12-24 15:51:23.193 T:1656652672   DEBUG: CSettingsManager: requested setting (developerMode) was not found.
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting developerMode. Creating a setting on-the-fly...
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CSettingsManager: requested setting (shortPlayLength) was not found.
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayLength. Creating a setting on-the-fly...
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CSettingsManager: requested setting (shortPlayMode) was not found.
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayMode. Creating a setting on-the-fly...
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CSettingsManager: requested setting (shortPlayNotification) was not found.
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting shortPlayNotification. Creating a setting on-the-fly...
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CSettingsManager: requested setting (windowMode) was not found.
2019-12-24 15:51:23.194 T:1656652672   DEBUG: CAddonSettings[service.upnext]: failed to find definition for setting windowMode. Creating a setting on-the-fly...
2019-12-24 15:51:23.208 T:1690223488   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:23.231 T:1937117200   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x5155b68
2019-12-24 15:51:23.232 T:1937117200   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(314128), nBufferAlignment(16)
2019-12-24 15:51:23.236 T:1937117200   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x370f298
2019-12-24 15:51:23.237 T:1937117200   DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
2019-12-24 15:51:23.244 T:1690223488  NOTICE: [plugin.video.vrt.nu] [PlayerInfo 2462] Event onPlayBackEnded
2019-12-24 15:51:23.244 T:1129182080   DEBUG: -->Python Interpreter Initialized<--
2019-12-24 15:51:23.245 T:1129182080   DEBUG: CPythonInvoker(47, /storage/.kodi/addons/weather.yahoo/default.py): the source file to load is "/storage/.kodi/addons/weather.yahoo/default.py"
2019-12-24 15:51:23.246 T:1380701056   DEBUG: -->Python Interpreter Initialized<--
2019-12-24 15:51:23.246 T:1380701056   DEBUG: CPythonInvoker(48, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): the source file to load is "/storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py"
2019-12-24 15:51:23.247 T:1380701056   DEBUG: CPythonInvoker(48, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): setting the Python path to /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib:/storage/.kodi/addons/script.module.beautifulsoup4/lib:/storage/.kodi/addons/script.module.dateutil/lib:/storage/.kodi/addons/script.module.inputstreamhelper/lib:/storage/.kodi/addons/script.module.routing/lib:/storage/.kodi/addons/script.module.six/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0
2019-12-24 15:51:23.247 T:1380701056   DEBUG: CPythonInvoker(48, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): entering source directory /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib
2019-12-24 15:51:23.248 T:1129182080   DEBUG: CPythonInvoker(47, /storage/.kodi/addons/weather.yahoo/default.py): setting the Python path to /storage/.kodi/addons/weather.yahoo:/storage/.kodi/addons/script.module.certifi/lib:/storage/.kodi/addons/script.module.chardet/lib:/storage/.kodi/addons/script.module.idna/lib:/storage/.kodi/addons/script.module.requests/lib:/storage/.kodi/addons/script.module.urllib3/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0
2019-12-24 15:51:23.248 T:1129182080   DEBUG: CPythonInvoker(47, /storage/.kodi/addons/weather.yahoo/default.py): entering source directory /storage/.kodi/addons/weather.yahoo
2019-12-24 15:51:23.248 T:1380701056   DEBUG: CPythonInvoker(48, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): instantiating addon using automatically obtained id of "plugin.video.vrt.nu" dependent on version 2.25.0 of the xbmc.python api
2019-12-24 15:51:23.286 T:1937117200   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x5155b68
2019-12-24 15:51:23.287 T:1937117200   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x370f298
2019-12-24 15:51:23.288 T:1937117200   DEBUG: DecodeJpegToTexture: decoded special://skin/extras/backgrounds/primary.jpg 1920x1080
2019-12-24 15:51:23.295 T:1937117200   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x304c268
2019-12-24 15:51:23.295 T:1129182080   DEBUG: CPythonInvoker(47, /storage/.kodi/addons/weather.yahoo/default.py): instantiating addon using automatically obtained id of "weather.yahoo" dependent on version 2.25.0 of the xbmc.python api
2019-12-24 15:51:23.295 T:1937117200   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(93824), nBufferAlignment(16)
2019-12-24 15:51:23.298 T:1937117200   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x370f298
2019-12-24 15:51:23.299 T:1937117200   DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
2019-12-24 15:51:23.313 T:1937117200   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x304c268
2019-12-24 15:51:23.314 T:1937117200   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x370f298
2019-12-24 15:51:23.314 T:1937117200   DEBUG: DecodeJpegToTexture: decoded special://skin/extras/backgrounds/pattern1.jpg 900x506
2019-12-24 15:51:23.355 T:1937117200   DEBUG: CRPIUtils::EnableDispmanxWindow(1)
2019-12-24 15:51:23.508 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:23.525 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: loading setting definitions
2019-12-24 15:51:23.525 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: trying to load setting definitions from old format...
2019-12-24 15:51:23.529 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: loading setting values
2019-12-24 15:51:23.530 T:1380701056   DEBUG: CSettingsManager: requested setting (adaptive_settings) was not found.
2019-12-24 15:51:23.530 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting adaptive_settings. Creating a setting on-the-fly...
2019-12-24 15:51:23.530 T:1380701056   DEBUG: CSettingsManager: requested setting (check_credentials) was not found.
2019-12-24 15:51:23.530 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting check_credentials. Creating a setting on-the-fly...
2019-12-24 15:51:23.530 T:1380701056   DEBUG: CSettingsManager: requested setting (clear_tokens) was not found.
2019-12-24 15:51:23.530 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting clear_tokens. Creating a setting on-the-fly...
2019-12-24 15:51:23.530 T:1380701056   DEBUG: CSettingsManager: requested setting (colour_availability) was not found.
2019-12-24 15:51:23.530 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_availability. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (colour_geoblocked) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_geoblocked. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (colour_greyedout) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_greyedout. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (colour_highlight) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_highlight. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (colour_highlighted) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_highlighted. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (colour_set) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_set. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (colour_theme) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_theme. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (delete_tokens) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting delete_tokens. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (invalidate_caches) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting invalidate_caches. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (ishelper_about) was not found.
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting ishelper_about. Creating a setting on-the-fly...
2019-12-24 15:51:23.531 T:1380701056   DEBUG: CSettingsManager: requested setting (ishelper_info) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting ishelper_info. Creating a setting on-the-fly...
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CSettingsManager: requested setting (ishelper_install) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting ishelper_install. Creating a setting on-the-fly...
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CSettingsManager: requested setting (ishelper_settings) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting ishelper_settings. Creating a setting on-the-fly...
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CSettingsManager: requested setting (label0) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting label0. Creating a setting on-the-fly...
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CSettingsManager: requested setting (log_level) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting log_level. Creating a setting on-the-fly...
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CSettingsManager: requested setting (manage_favorites) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting manage_favorites. Creating a setting on-the-fly...
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CSettingsManager: requested setting (pysocks_install) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting pysocks_install. Creating a setting on-the-fly...
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CSettingsManager: requested setting (refresh_favorites) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting refresh_favorites. Creating a setting on-the-fly...
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CSettingsManager: requested setting (refresh_resumepoints) was not found.
2019-12-24 15:51:23.532 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting refresh_resumepoints. Creating a setting on-the-fly...
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CSettingsManager: requested setting (twitter_install) was not found.
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting twitter_install. Creating a setting on-the-fly...
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CSettingsManager: requested setting (twitter_settings) was not found.
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting twitter_settings. Creating a setting on-the-fly...
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CSettingsManager: requested setting (upnext_install) was not found.
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting upnext_install. Creating a setting on-the-fly...
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CSettingsManager: requested setting (upnext_settings) was not found.
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting upnext_settings. Creating a setting on-the-fly...
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CSettingsManager: requested setting (usecredentials) was not found.
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting usecredentials. Creating a setting on-the-fly...
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CSettingsManager: requested setting (vrtradio) was not found.
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting vrtradio. Creating a setting on-the-fly...
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CSettingsManager: requested setting (widevine_install) was not found.
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting widevine_install. Creating a setting on-the-fly...
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CSettingsManager: requested setting (youtube_install) was not found.
2019-12-24 15:51:23.533 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting youtube_install. Creating a setting on-the-fly...
2019-12-24 15:51:23.534 T:1380701056   DEBUG: CSettingsManager: requested setting (youtube_settings) was not found.
2019-12-24 15:51:23.534 T:1380701056   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting youtube_settings. Creating a setting on-the-fly...
2019-12-24 15:51:24.154 T:1380701056  NOTICE: [plugin.video.vrt.nu] Access: plugin://plugin.video.vrt.nu/resumepoints/watchlater
2019-12-24 15:51:24.155 T:1380701056   DEBUG: [plugin.video.vrt.nu][routing] Dispatching to 'resumepoints_watchlater', args: {}
2019-12-24 15:51:24.215 T:1129182080   DEBUG: CAddonSettings[weather.yahoo]: loading setting definitions
2019-12-24 15:51:24.215 T:1129182080   DEBUG: CAddonSettings[weather.yahoo]: trying to load setting definitions from old format...
2019-12-24 15:51:24.216 T:1129182080   DEBUG: CAddonSettings[weather.yahoo]: loading setting values
2019-12-24 15:51:24.217 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.218 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.231 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.233 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.234 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.234 T:1090245504  NOTICE: [plugin.video.vrt.nu] Write cache 'resume_points.json'.
2019-12-24 15:51:24.235 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.238 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.239 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.241 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.242 T:1090245504  NOTICE: [plugin.video.vrt.nu] [Resumepoints] Remove 'contentdamvrt20191002theclintonaffairr001a0003depotwp00157709' from resumepoints
2019-12-24 15:51:24.248 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.249 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.249 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.250 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.250 T:1090245504  NOTICE: [plugin.video.vrt.nu] Write cache 'resume_points.json'.
2019-12-24 15:51:24.251 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.251 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.253 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.254 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.254 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.255 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.255 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.255 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.258 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.328 T:1090245504  NOTICE: [plugin.video.vrt.nu] Execute: Container.Refresh
2019-12-24 15:51:24.329 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.330 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.330 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.330 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.331 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.331 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.334 T:1090245504   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.335 T:1090245504  NOTICE: [plugin.video.vrt.nu] Execute: Container.Refresh
2019-12-24 15:51:24.402 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.403 T:1380701056  NOTICE: [plugin.video.vrt.nu] Got cached token '/storage/.kodi/userdata/addon_data/plugin.video.vrt.nu/tokens/user_XVRTToken.tkn'
2019-12-24 15:51:24.405 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.406 T:1380701056  NOTICE: [plugin.video.vrt.nu] URL get: https://video-user-data.vrt.be/favorites
2019-12-24 15:51:24.407 T:1937117200   DEBUG: CGUIMediaWindow::GetDirectory (plugin://plugin.video.vrt.nu/resumepoints/watchlater)
2019-12-24 15:51:24.407 T:1937117200   DEBUG:   ParentPath = [plugin://plugin.video.vrt.nu/resumepoints/watchlater]
2019-12-24 15:51:24.410 T:1937117200 WARNING: CGUIMediaWindow::OnMessage - updating in progress
2019-12-24 15:51:24.471 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.472 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.478 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.479 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.480 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.480 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.494 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.495 T:1104003968  NOTICE: [plugin.video.vrt.nu] Got cached token '/storage/.kodi/userdata/addon_data/plugin.video.vrt.nu/tokens/user_XVRTToken.tkn'
2019-12-24 15:51:24.694 T:1179538304   DEBUG: CAddonDatabase::SetLastUsed[plugin.video.vrt.nu] took 288 ms
2019-12-24 15:51:24.695 T:1090245504   DEBUG: Thread JobWorker start, auto delete: true
2019-12-24 15:51:24.696 T:1156576128   DEBUG: StartScript - calling plugin VRT NU('plugin://plugin.video.vrt.nu/resumepoints/watchlater','21','','resume:false')
2019-12-24 15:51:24.697 T:1137574784   DEBUG: Thread LanguageInvoker start, auto delete: false
2019-12-24 15:51:24.697 T:1137574784    INFO: initializing python engine.
2019-12-24 15:51:24.697 T:1137574784   DEBUG: CPythonInvoker(49, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): start processing
2019-12-24 15:51:24.752 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:24.761 T:1137574784   DEBUG: -->Python Interpreter Initialized<--
2019-12-24 15:51:24.761 T:1137574784   DEBUG: CPythonInvoker(49, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): the source file to load is "/storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py"
2019-12-24 15:51:24.763 T:1137574784   DEBUG: CPythonInvoker(49, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): setting the Python path to /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib:/storage/.kodi/addons/script.module.beautifulsoup4/lib:/storage/.kodi/addons/script.module.dateutil/lib:/storage/.kodi/addons/script.module.inputstreamhelper/lib:/storage/.kodi/addons/script.module.routing/lib:/storage/.kodi/addons/script.module.six/lib:/usr/lib/python27.zip:/usr/lib/python2.7:/usr/lib/python2.7/plat-linux2:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/lib/python2.7/site-packages:/usr/lib/python2.7/site-packages/gtk-2.0
2019-12-24 15:51:24.763 T:1137574784   DEBUG: CPythonInvoker(49, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): entering source directory /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib
2019-12-24 15:51:24.763 T:1104003968  NOTICE: [plugin.video.vrt.nu] [Resumepoints] 'contentdamvrt20191002theclintonaffairr001a0003depotwp00157709' online deleted: 200
2019-12-24 15:51:24.772 T:1137574784   DEBUG: CPythonInvoker(49, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): instantiating addon using automatically obtained id of "plugin.video.vrt.nu" dependent on version 2.25.0 of the xbmc.python api
2019-12-24 15:51:24.840 T:1104003968   DEBUG: CWebServer[80]: request received for /jsonrpc?Application.GetProperties
2019-12-24 15:51:24.840 T:1104003968   DEBUG: JSONRPC: Incoming request: [{"jsonrpc":"2.0","method":"Application.GetProperties","params":[["volume","muted","version"]],"id":7}]
2019-12-24 15:51:24.848 T:1104003968   DEBUG: CWebServer[80]: request received for /jsonrpc?Player.GetActivePlayers
2019-12-24 15:51:24.848 T:1104003968   DEBUG: JSONRPC: Incoming request: [{"jsonrpc":"2.0","method":"Player.GetActivePlayers","params":{},"id":8}]
2019-12-24 15:51:25.163 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.173 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: loading setting definitions
2019-12-24 15:51:25.173 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: trying to load setting definitions from old format...
2019-12-24 15:51:25.177 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: loading setting values
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (adaptive_settings) was not found.
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting adaptive_settings. Creating a setting on-the-fly...
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (check_credentials) was not found.
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting check_credentials. Creating a setting on-the-fly...
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (clear_tokens) was not found.
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting clear_tokens. Creating a setting on-the-fly...
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (colour_availability) was not found.
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_availability. Creating a setting on-the-fly...
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (colour_geoblocked) was not found.
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_geoblocked. Creating a setting on-the-fly...
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (colour_greyedout) was not found.
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_greyedout. Creating a setting on-the-fly...
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (colour_highlight) was not found.
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_highlight. Creating a setting on-the-fly...
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (colour_highlighted) was not found.
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_highlighted. Creating a setting on-the-fly...
2019-12-24 15:51:25.178 T:1137574784   DEBUG: CSettingsManager: requested setting (colour_set) was not found.
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_set. Creating a setting on-the-fly...
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CSettingsManager: requested setting (colour_theme) was not found.
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting colour_theme. Creating a setting on-the-fly...
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CSettingsManager: requested setting (delete_tokens) was not found.
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting delete_tokens. Creating a setting on-the-fly...
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CSettingsManager: requested setting (invalidate_caches) was not found.
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting invalidate_caches. Creating a setting on-the-fly...
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CSettingsManager: requested setting (ishelper_about) was not found.
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting ishelper_about. Creating a setting on-the-fly...
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CSettingsManager: requested setting (ishelper_info) was not found.
2019-12-24 15:51:25.179 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting ishelper_info. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (ishelper_install) was not found.
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting ishelper_install. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (ishelper_settings) was not found.
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting ishelper_settings. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (label0) was not found.
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting label0. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (log_level) was not found.
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting log_level. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (manage_favorites) was not found.
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting manage_favorites. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (pysocks_install) was not found.
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting pysocks_install. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (refresh_favorites) was not found.
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting refresh_favorites. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (refresh_resumepoints) was not found.
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting refresh_resumepoints. Creating a setting on-the-fly...
2019-12-24 15:51:25.180 T:1137574784   DEBUG: CSettingsManager: requested setting (twitter_install) was not found.
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting twitter_install. Creating a setting on-the-fly...
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CSettingsManager: requested setting (twitter_settings) was not found.
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting twitter_settings. Creating a setting on-the-fly...
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CSettingsManager: requested setting (upnext_install) was not found.
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting upnext_install. Creating a setting on-the-fly...
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CSettingsManager: requested setting (upnext_settings) was not found.
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting upnext_settings. Creating a setting on-the-fly...
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CSettingsManager: requested setting (usecredentials) was not found.
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting usecredentials. Creating a setting on-the-fly...
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CSettingsManager: requested setting (vrtradio) was not found.
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting vrtradio. Creating a setting on-the-fly...
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CSettingsManager: requested setting (widevine_install) was not found.
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting widevine_install. Creating a setting on-the-fly...
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CSettingsManager: requested setting (youtube_install) was not found.
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting youtube_install. Creating a setting on-the-fly...
2019-12-24 15:51:25.181 T:1137574784   DEBUG: CSettingsManager: requested setting (youtube_settings) was not found.
2019-12-24 15:51:25.182 T:1137574784   DEBUG: CAddonSettings[plugin.video.vrt.nu]: failed to find definition for setting youtube_settings. Creating a setting on-the-fly...
2019-12-24 15:51:25.184 T:1137574784  NOTICE: [plugin.video.vrt.nu] Access: plugin://plugin.video.vrt.nu/resumepoints/watchlater
2019-12-24 15:51:25.185 T:1137574784   DEBUG: [plugin.video.vrt.nu][routing] Dispatching to 'resumepoints_watchlater', args: {}
2019-12-24 15:51:25.228 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.229 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.229 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.230 T:1380701056  NOTICE: [plugin.video.vrt.nu] Cache 'favorites.json' has not changed, updating mtime only.
2019-12-24 15:51:25.231 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.232 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.232 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.241 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.244 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.244 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.253 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.258 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.260 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.260 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.261 T:1380701056  NOTICE: [plugin.video.vrt.nu] URL get: https://vrtnu-api.vrt.be/search?i=video&facets[url]=[//www.vrt.be/vrtnu/a-z/cold-case-hammarskjoeld/2019/cold-case-hammarskjoeld/,//www.vrt.be/vrtnu/a-z/de-columbus/2/de-columbus-s2a6/,//www.vrt.be/vrtnu/a-z/sergio-leone--portrait-of-an-outlaw/2018/sergio-leone--portrait-of-an-outlaw/,//www.vrt.be/vrtnu/a-z/pavarotti--pop-tenor/2017/pavarotti--pop-tenor-s2017/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a4/,//www.vrt.be/vrtnu/a-z/komt-een-vrouw-bij-de-dokter/2009/komt-een-vrouw-bij-de-dokter-s2009/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a6-valerie-trouet/,//www.vrt.be/vrtnu/a-z/avicii--true-stories/2017/avicii--true-stories/,//www.vrt.be/vrtnu/a-z/world-war-i--the-final-hours/2018/world-war-i--the-final-hours/,//www.vrt.be/vrtnu/a-z/kleine-houdini/2014/kleine-houdini/,//www.vrt.be/vrtnu/a-z/tiananmen--the-people-versus-the-party/2019/tiananmen--the-people-versus-the-party/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a6/,//www.vrt.be/vrtnu/a-z/terug-naar-de-kust/2009/terug-naar-de-kust/,//www.vrt.be/vrtnu/a-z/wolvenvacht/2015/wolvenvacht/,//www.vrt.be/vrtnu/a-z/allied/2016/allied/,//www.vrt.be/vrtnu/a-z/terzake/2019/terzake-d20191115/,//www.vrt.be/vrtnu/a-z/frantz/2016/frantz/,//www.vrt.be/vrtnu/a-z/on-the-inside-of-a-military-dictatorship/2019/on-the-inside-of-a-military-dictatorship/,//www.vrt.be/vrtnu/a-z/king-bibi/2019/king-bibi-s2019/,//www.vrt.be/vrtnu/a-z/barbra-streisand--becoming-an-icon/2015/barbra-streisand--becoming-an-icon-s2015/,//www.vrt.be/vrtnu/a-z/the-planets/1/the-planets-s1a3/?linkId=vrtnu|vrtnu|/vrtnu/|curatie,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a4-ann-dooms/,//www.vrt.be/vrtnu/a-z/terzake/2019/terzake-d20191115/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a2/,//www.vrt.be/vrtnu/a-z/goulag--une-histoire-sovietique/1/goulag--une-histoire-sovietique-s1a1/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a3-wim-distelmans/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a5-margot-vanderstraeten/,//www.vrt.be/vrtnu/a-z/the-ghost-writer/2010/the-ghost-writer/,//www.vrt.be/vrtnu/a-z/het-huis/1/het-huis-s1a5/,//www.vrt.be/vrtnu/a-z/dark-days/2000/dark-days/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a1/,//www.vrt.be/vrtnu/a-z/lion/2016/lion/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a7-michael-van-peel/,//www.vrt.be/vrtnu/a-z/minding-the-gap/2018/minding-the-gap/,//www.vrt.be/vrtnu/a-z/de-inzichten-van/1/de-inzichten-van-s1a3-de-inzichten-van-wim-distelmans/,//www.vrt.be/vrtnu/a-z/dit-was-yasmine/2019/dit-was-yasmine-s2019/,//www.vrt.be/vrtnu/a-z/het-universum-van-panamarenko/2014/het-universum-van-panamarenko/,//www.vrt.be/vrtnu/a-z/geub/1/geub-s1a0/,//www.vrt.be/vrtnu/a-z/woodstock/2019/woodstock/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a2-chams-eddine-zaougui/,//www.vrt.be/vrtnu/a-z/the-clinton-affair/1/the-clinton-affair-s1a4/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a1-daan-stuyven/,//www.vrt.be/vrtnu/a-z/amerikaanse-overdosis/2019/amerikaanse-overdosis-amerikaanse-overdosis/,//www.vrt.be/vrtnu/a-z/all-the-money-in-the-world/2017/all-the-money-in-the-world/,//www.vrt.be/vrtnu/a-z/poulidor-premier/2015/poulidor-premier-s2015/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a8-maaike-cafmeyer/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a3/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a5/,//www.vrt.be/vrtnu/a-z/circle-of-life/1/circle-of-life-s1a0/,//www.vrt.be/vrtnu/a-z/belga-sport/9/belga-sport-s9a0/,//www.vrt.be/vrtnu/a-z/lords-of-water/2019/lords-of-water/,//www.vrt.be/vrtnu/a-z/midnight-family/2019/midnight-family/,//www.vrt.be/vrtnu/a-z/het-beste-voor-kees/2014/het-beste-voor-kees/,//www.vrt.be/vrtnu/a-z/american-dynasties--the-kennedys/1/american-dynasties--the-kennedys-s1a3/,//www.vrt.be/vrtnu/a-z/het-huis/2/het-huis-s2a4/,//www.vrt.be/vrtnu/a-z/notre-dame--in-flames/2019/notre-dame--in-flames-s2019/,//www.vrt.be/vrtnu/a-z/vandaag/1/vandaag-trailer/,//www.vrt.be/vrtnu/a-z/versailles-rediscovered/2019/versailles-rediscovered/]&from=1&size=80
2019-12-24 15:51:25.262 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.312 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.usehttpproxy"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.313 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxytype"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.314 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyserver"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.314 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyport"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.315 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxyusername"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.315 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "network.httpproxypassword"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.368 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.369 T:1137574784  NOTICE: [plugin.video.vrt.nu] URL get: https://vrtnu-api.vrt.be/search?i=video&facets[url]=[//www.vrt.be/vrtnu/a-z/cold-case-hammarskjoeld/2019/cold-case-hammarskjoeld/,//www.vrt.be/vrtnu/a-z/de-columbus/2/de-columbus-s2a6/,//www.vrt.be/vrtnu/a-z/sergio-leone--portrait-of-an-outlaw/2018/sergio-leone--portrait-of-an-outlaw/,//www.vrt.be/vrtnu/a-z/pavarotti--pop-tenor/2017/pavarotti--pop-tenor-s2017/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a4/,//www.vrt.be/vrtnu/a-z/komt-een-vrouw-bij-de-dokter/2009/komt-een-vrouw-bij-de-dokter-s2009/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a6-valerie-trouet/,//www.vrt.be/vrtnu/a-z/avicii--true-stories/2017/avicii--true-stories/,//www.vrt.be/vrtnu/a-z/world-war-i--the-final-hours/2018/world-war-i--the-final-hours/,//www.vrt.be/vrtnu/a-z/kleine-houdini/2014/kleine-houdini/,//www.vrt.be/vrtnu/a-z/tiananmen--the-people-versus-the-party/2019/tiananmen--the-people-versus-the-party/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a6/,//www.vrt.be/vrtnu/a-z/terug-naar-de-kust/2009/terug-naar-de-kust/,//www.vrt.be/vrtnu/a-z/wolvenvacht/2015/wolvenvacht/,//www.vrt.be/vrtnu/a-z/allied/2016/allied/,//www.vrt.be/vrtnu/a-z/terzake/2019/terzake-d20191115/,//www.vrt.be/vrtnu/a-z/frantz/2016/frantz/,//www.vrt.be/vrtnu/a-z/on-the-inside-of-a-military-dictatorship/2019/on-the-inside-of-a-military-dictatorship/,//www.vrt.be/vrtnu/a-z/king-bibi/2019/king-bibi-s2019/,//www.vrt.be/vrtnu/a-z/barbra-streisand--becoming-an-icon/2015/barbra-streisand--becoming-an-icon-s2015/,//www.vrt.be/vrtnu/a-z/the-planets/1/the-planets-s1a3/?linkId=vrtnu|vrtnu|/vrtnu/|curatie,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a4-ann-dooms/,//www.vrt.be/vrtnu/a-z/terzake/2019/terzake-d20191115/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a2/,//www.vrt.be/vrtnu/a-z/goulag--une-histoire-sovietique/1/goulag--une-histoire-sovietique-s1a1/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a3-wim-distelmans/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a5-margot-vanderstraeten/,//www.vrt.be/vrtnu/a-z/the-ghost-writer/2010/the-ghost-writer/,//www.vrt.be/vrtnu/a-z/het-huis/1/het-huis-s1a5/,//www.vrt.be/vrtnu/a-z/dark-days/2000/dark-days/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a1/,//www.vrt.be/vrtnu/a-z/lion/2016/lion/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a7-michael-van-peel/,//www.vrt.be/vrtnu/a-z/minding-the-gap/2018/minding-the-gap/,//www.vrt.be/vrtnu/a-z/de-inzichten-van/1/de-inzichten-van-s1a3-de-inzichten-van-wim-distelmans/,//www.vrt.be/vrtnu/a-z/dit-was-yasmine/2019/dit-was-yasmine-s2019/,//www.vrt.be/vrtnu/a-z/het-universum-van-panamarenko/2014/het-universum-van-panamarenko/,//www.vrt.be/vrtnu/a-z/geub/1/geub-s1a0/,//www.vrt.be/vrtnu/a-z/woodstock/2019/woodstock/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a2-chams-eddine-zaougui/,//www.vrt.be/vrtnu/a-z/the-clinton-affair/1/the-clinton-affair-s1a4/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a1-daan-stuyven/,//www.vrt.be/vrtnu/a-z/amerikaanse-overdosis/2019/amerikaanse-overdosis-amerikaanse-overdosis/,//www.vrt.be/vrtnu/a-z/all-the-money-in-the-world/2017/all-the-money-in-the-world/,//www.vrt.be/vrtnu/a-z/poulidor-premier/2015/poulidor-premier-s2015/,//www.vrt.be/vrtnu/a-z/alleen-elvis-blijft-bestaan/7/alleen-elvis-blijft-bestaan-s7a8-maaike-cafmeyer/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a3/,//www.vrt.be/vrtnu/a-z/brian-johnson-s-a-life-on-the-road/1/brian-johnson-s-a-life-on-the-road-s1a5/,//www.vrt.be/vrtnu/a-z/circle-of-life/1/circle-of-life-s1a0/,//www.vrt.be/vrtnu/a-z/belga-sport/9/belga-sport-s9a0/,//www.vrt.be/vrtnu/a-z/lords-of-water/2019/lords-of-water/,//www.vrt.be/vrtnu/a-z/midnight-family/2019/midnight-family/,//www.vrt.be/vrtnu/a-z/het-beste-voor-kees/2014/het-beste-voor-kees/,//www.vrt.be/vrtnu/a-z/american-dynasties--the-kennedys/1/american-dynasties--the-kennedys-s1a3/,//www.vrt.be/vrtnu/a-z/het-huis/2/het-huis-s2a4/,//www.vrt.be/vrtnu/a-z/notre-dame--in-flames/2019/notre-dame--in-flames-s2019/,//www.vrt.be/vrtnu/a-z/vandaag/1/vandaag-trailer/,//www.vrt.be/vrtnu/a-z/versailles-rediscovered/2019/versailles-rediscovered/]&from=1&size=80
2019-12-24 15:51:25.663 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.708 T:1380701056  NOTICE: [plugin.video.vrt.nu] Write cache 'watchlater-1.json'.
2019-12-24 15:51:25.710 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:25.711 T:1137574784  NOTICE: [plugin.video.vrt.nu] Write cache 'watchlater-1.json'.
2019-12-24 15:51:25.841 T:1104003968   DEBUG: CWebServer[80]: request received for /jsonrpc?Episode
2019-12-24 15:51:25.842 T:1104003968   DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"VideoLibrary.GetEpisodeDetails","id":"1577199085832","params":{"episodeid":-1,"properties":["title","thumbnail","playcount","lastplayed","dateadded","episode","season","rating","file","cast","showtitle","tvshowid","uniqueid","resume","firstaired","fanart","plot","director","writer","runtime","streamdetails"]}}
2019-12-24 15:51:25.842 T:1104003968   DEBUG: JSONRPC: Value does not lay between minimum and maximum in type episodeid
2019-12-24 15:51:26.510 T:1137574784   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:26.546 T:1137574784  NOTICE: [plugin.video.vrt.nu] [Metadata] manual resumetime set to 716
2019-12-24 15:51:26.750 T:1380701056   DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "params": {"setting": "debug.showloginfo"}, "method": "Settings.GetSettingValue", "id": 0}
2019-12-24 15:51:26.750 T:1380701056  NOTICE: [plugin.video.vrt.nu] [Metadata] manual resumetime set to 716
2019-12-24 15:51:26.918 T:1129182080    INFO: CPythonInvoker(47, /storage/.kodi/addons/weather.yahoo/default.py): script successfully run
2019-12-24 15:51:26.918 T:1129182080   DEBUG: onExecutionDone(47, /storage/.kodi/addons/weather.yahoo/default.py)
2019-12-24 15:51:27.202 T:1129182080    INFO: Python interpreter stopped
2019-12-24 15:51:27.202 T:1129182080   DEBUG: Thread LanguageInvoker 1129182080 terminating
2019-12-24 15:51:27.235 T:1209590656   DEBUG: POParser: loaded 130 weather tokens
2019-12-24 15:51:27.250 T:1209590656   DEBUG: GetImageHash - unable to stat url /storage/.kodi/addons/plugin.video.regiotv/DefaultAddonPVRClient.png
2019-12-24 15:51:27.302 T:1137574784    INFO: CPythonInvoker(49, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): script successfully run
2019-12-24 15:51:27.304 T:1065067392   DEBUG: Thread waiting 1065067392 terminating
2019-12-24 15:51:27.308 T:1380701056 WARNING: Attempt to use invalid handle 20
2019-12-24 15:51:27.314 T:1380701056 WARNING: Previous line repeats 1 times.
2019-12-24 15:51:27.314 T:1380701056    INFO: CPythonInvoker(48, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py): script successfully run
2019-12-24 15:51:27.314 T:1380701056   DEBUG: onExecutionDone(48, /storage/.kodi/addons/plugin.video.vrt.nu/resources/lib/addon_entry.py)
2019-12-24 15:51:27.414 T:1179538304   DEBUG: Using proxy dagit.synology.me, type 0
2019-12-24 15:51:27.414 T:1179538304    INFO: easy_acquire - Created session to https://archive.org
2019-12-24 15:51:27.438 T:1065067392   DEBUG: Thread BackgroundLoader start, auto delete: false
2019-12-24 15:51:27.475 T:1090245504   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x371b9d8
2019-12-24 15:51:27.476 T:1090245504   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(171504), nBufferAlignment(16)
2019-12-24 15:51:27.502 T:1156576128   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x594095e8
2019-12-24 15:51:27.503 T:1156576128   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(284432), nBufferAlignment(16)
2019-12-24 15:51:27.504 T:1090245504   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x34236c8
2019-12-24 15:51:27.505 T:1090245504   DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
2019-12-24 15:51:27.527 T:1156576128   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x4caa36b0
2019-12-24 15:51:27.528 T:1090245504   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x371b9d8
2019-12-24 15:51:27.528 T:1156576128   DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignment(16)
2019-12-24 15:51:27.529 T:1090245504   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x34236c8
2019-12-24 15:51:27.530 T:1090245504   DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/1/1af784fe.jpg 1200x675
2019-12-24 15:51:27.572 T:1156576128   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x594095e8
2019-12-24 15:51:27.573 T:1156576128   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x4caa36b0
2019-12-24 15:51:27.574 T:1156576128   DEBUG: DecodeJpegToTexture: decoded special://masterprofile/Thumbnails/c/c78e4f1b.jpg 1920x1080
2019-12-24 15:51:27.574 T:1156576128   DEBUG: DoWork - took 111 ms to load special://masterprofile/Thumbnails/c/c78e4f1b.jpg
2019-12-24 15:51:27.587 T:1937117200   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
2019-12-24 15:51:27.613 T:1380701056    INFO: Python interpreter stopped
2019-12-24 15:51:27.613 T:1380701056   DEBUG: Thread LanguageInvoker 1380701056 terminating
2019-12-24 15:51:27.631 T:1065067392   DEBUG: Thread BackgroundLoader 1065067392 terminating
2019-12-24 15:51:27.636 T:1065067392   DEBUG: Thread BackgroundLoader start, auto delete: false
2019-12-24 15:51:27.647 T:1065067392   DEBUG: Thread BackgroundLoader 1065067392 terminating
dagwieers commented 4 years ago

From the log I noticed I was having a proxy configured. I disabled it now to see if this happens to me again.

mediaminister commented 4 years ago

Merry Christmas. :christmas_tree:

I once again experienced an empty virtual directory after watching the 3rd episode of The Clinton Affair docu from the Watch later menu.

Definitely using an old version of the add-on ( I changed onThreadExit to onPlayerExit in https://github.com/pietje666/plugin.video.vrt.nu/commit/ede4623d0a0dda39019d1f337d4c1fa5ec8ef783):

2019-12-24 15:51:22.852 T:1090245504  NOTICE: [plugin.video.vrt.nu] [PlayerInfo 2462] Event onThreadExit
dagwieers commented 4 years ago

Are we ready to have a v2.3.0 New Year's release ?

mediaminister commented 4 years ago

Yes, the current master branch is ready for a release.

dagwieers commented 4 years ago

So I'd rather not risk breaking something on New Year's eve (turning into disaster because Ketnet is no longer working :wink: ). So I would rather release on 31/12, but push to repo on 1/1.

dagwieers commented 4 years ago

I moved the tests to the Test plan wiki page. An issue or PR can be opened if we would still encounter any issues.