/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/mnmimage.jpg' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'mnmicon.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/mnmicon.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'stubrufanart.jpg' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/stubrufanart.jpg' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'stubruimage.jpg' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/stubruimage.jpg' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'stubruicon.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/stubruicon.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'eenfanart.jpg' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/eenfanart.jpg' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'eenimage.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/eenimage.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'eenlarge.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/eenlarge.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'canvasfanart.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/canvasfanart.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'canvasimage.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/canvasimage.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'canvaslarge.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/canvaslarge.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'ketnetfanart.jpg' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/ketnetfanart.jpg' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'ketnetimage.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/ketnetimage.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'ketnetlarge.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/ketnetlarge.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'sporzafanart.jpg' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/sporzafanart.jpg' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'sporzaimage.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/sporzaimage.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'sporzalarge.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/sporzalarge.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'ketnetfanart.jpg' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/ketnetfanart.jpg' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'ketnetimage.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/ketnetimage.png' 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'ketnetlarge.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/ketnetlarge.png' 20170320 12:01:51 - [DEBUG ] - chn_class.py - 144 - Initializing channel (InitChannel): VRT NU (vrtnu) [net.rieter.xot.channel.be.vrtnu-4.1.3.6, be, National, F530A9EC-3C0D-49B6-96C2-480273417460] (Order: 11) 20170320 12:01:51 - [DEBUG ] - cached.py - 90 - Resolved cached texture for 'vrtnuimage.png' to 'special://profile/addon_data/net.rieter.xot/textures/net.rieter.xot.channel.be.vrtnu/vrtnuimage.png' 20170320 12:01:51 - [INFO ] - plugin.py - 191 - Loaded: VRT NU 20170320 12:01:51 - [DEBUG ] - locker.py - 37 - Locking interface and showing BusyDialog 20170320 12:01:51 - [DEBUG ] - plugin.py - 551 - Playing videoitem using PlayListMethod 20170320 12:01:51 - [DEBUG ] - chn_class.py - 1218 - Found 4 DataParsers for 'https://www.vrt.be/vrtnu/a-z/reizen-waes/3/reizen-waes-s3a8-venezuela/' 20170320 12:01:51 - [INFO ] - chn_class.py - 428 - One or more dataparsers require logging in. 20170320 12:01:51 - [DEBUG ] - chn_class.py - 431 - Processing Updater from DataParser (Json=False, Generic=False, MatchType=MatchStart, Logon=True): 20170320 12:01:51 - [DEBUG ] - chn_class.py - 431 - | Match: * 20170320 12:01:51 - [DEBUG ] - chn_class.py - 431 - | Pre: None 20170320 12:01:51 - [DEBUG ] - chn_class.py - 431 - | Parser: None 20170320 12:01:51 - [DEBUG ] - chn_class.py - 431 - | Creator: None 20170320 12:01:51 - [DEBUG ] - chn_class.py - 431 - + Updater: > 20170320 12:01:51 - [DEBUG ] - chn_vrtnu.py - 353 - Starting UpdateVideoItem for Venezuela (VRT NU) 20170320 12:01:51 - [INFO ] - urihandler.py - 346 - Retreiving Header info for https://www.vrt.be/vrtnu/a-z/reizen-waes/3/reizen-waes-s3a8-venezuela/ 20170320 12:01:51 - [DEBUG ] - urihandler.py - 901 - Adding header 'Accept-Encoding: gzip, deflate' 20170320 12:01:51 - [DEBUG ] - urihandler.py - 887 - Setting request type to HEAD. 20170320 12:01:51 - [DEBUG ] - urihandler.py - 920 - Decompressing 'gzip' response 20170320 12:01:51 - [DEBUG ] - urihandler.py - 365 - Header info retreived: text/html; charset=UTF-8 for realUrl https://www.vrt.be/vrtnu/a-z/reizen-waes/3/reizen-waes-s3a8-venezuela/ 20170320 12:01:51 - [INFO ] - urihandler.py - 561 - Opening requested uri: https://www.vrt.be/vrtnu/a-z/reizen-waes/3/reizen-waes-s3a8-venezuela.securevideo.json (callback=False, timeout=30) 20170320 12:01:51 - [DEBUG ] - urihandler.py - 901 - Adding header 'Accept-Encoding: gzip, deflate' 20170320 12:01:52 - [DEBUG ] - urihandler.py - 920 - Decompressing 'gzip' response 20170320 12:01:52 - [DEBUG ] - urihandler.py - 578 - Determining number of bytes to fetch 20170320 12:01:52 - [DEBUG ] - urihandler.py - 582 - ByteSize is known (fileSize=153) 20170320 12:01:52 - [INFO ] - urihandler.py - 655 - Url https://www.vrt.be/vrtnu/a-z/reizen-waes/3/reizen-waes-s3a8-venezuela.securevideo.json was opened successfully 20170320 12:01:52 - [INFO ] - urihandler.py - 561 - Opening requested uri: https://mediazone.vrt.be/api/v1/vrtvideo/assets/md-ast-42b1f592-0114-483a-8b19-6151dd595a21_1489569681186 (callback=False, timeout=30) 20170320 12:01:52 - [DEBUG ] - urihandler.py - 901 - Adding header 'Accept-Encoding: gzip, deflate' 20170320 12:01:52 - [DEBUG ] - urihandler.py - 955 - No Content-Encoding header found 20170320 12:01:52 - [DEBUG ] - urihandler.py - 578 - Determining number of bytes to fetch 20170320 12:01:52 - [DEBUG ] - urihandler.py - 582 - ByteSize is known (fileSize=1412) 20170320 12:01:52 - [INFO ] - urihandler.py - 655 - Url https://mediazone.vrt.be/api/v1/vrtvideo/assets/md-ast-42b1f592-0114-483a-8b19-6151dd595a21_1489569681186 was opened successfully 20170320 12:01:52 - [DEBUG ] - urihandler.py - 326 - Decoding data using charset HTML Header: UTF-8 20170320 12:01:52 - [INFO ] - urihandler.py - 561 - Opening requested uri: https://vod.stream.vrt.be/mediazone_vrt/_definst_/smil:vid/2017/03/18/vid-dis-1e929247-57f7-4742-9171-d108d8dcf080-2/video.smil/playlist.m3u8 (callback=False, timeout=30) 20170320 12:01:52 - [DEBUG ] - urihandler.py - 901 - Adding header 'Accept-Encoding: gzip, deflate' 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - Error Opening url https://vod.stream.vrt.be/mediazone_vrt/_definst_/smil:vid/2017/03/18/vid-dis-1e929247-57f7-4742-9171-d108d8dcf080-2/video.smil/playlist.m3u8 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - | Traceback (most recent call last): 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - | File "/storage/.kodi/addons/net.rieter.xot/resources/libs/urihandler.py", line 573, in __RetreiveData 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - | srcHandle = opener.open(uri) 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - | File "/usr/lib/python2.7/urllib2.py", line 429, in open 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - | File "/usr/lib/python2.7/urllib2.py", line 447, in _open 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - | File "/usr/lib/python2.7/urllib2.py", line 407, in _call_chain 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - | File "/usr/lib/python2.7/urllib2.py", line 1241, in https_open 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - | File "/usr/lib/python2.7/urllib2.py", line 1198, in do_open 20170320 12:01:52 - [CRITICAL] - urihandler.py - 639 - + URLError: 20170320 12:01:52 - [DEBUG ] - m3u8.py - 49 - Processing M3U8 Streams: https://vod.stream.vrt.be/mediazone_vrt/_definst_/smil:vid/2017/03/18/vid-dis-1e929247-57f7-4742-9171-d108d8dcf080-2/video.smil/playlist.m3u8 20170320 12:01:52 - [DEBUG ] - m3u8.py - 76 - Found 0 substreams in M3U8 20170320 12:01:52 - [WARNING ] - plugin.py - 574 - UpdateVideoItem returned an item that had item.complete = False: 20170320 12:01:52 - [WARNING ] - plugin.py - 574 - | MediaItem: Venezuela [Type=video, Complete=False, IsLive=False, Date=2017-03-19, Downloadable=False, Geo/DRM=False/False] 20170320 12:01:52 - [WARNING ] - plugin.py - 574 - + MediaPart: Venezuela [CanStream=True, HttpHeaders={}] 20170320 12:01:52 - [DEBUG ] - statistics.py - 179 - Sending statistics: el=Play%3A%20Venezuela&ea=VRT%20NU&ec=Errors&an=Retrospect&av=4.1.3.6&ev=1&dr=https%3A//www.vrt.be/vrtnu/a-z/reizen-waes/3/reizen-waes-s3a8-venezuela/&cid=f999ca90-0a32-11e7-aae7-c42f11192d99&t=event&v=1&tid=UA-3902785-11&aid=net.rieter.xot.channel.be.vrtnu 20170320 12:01:52 - [INFO ] - addonsettings.py - 307 - User agent set to: Kodi/17.1-RC2 (Linux 3.14.29; armv7l; http://kodi.tv) Version/17.1-RC2-Git:5814568 20170320 12:01:52 - [INFO ] - addonsettings.py - 331 - User agent updated due to Kodi version change from 20170320 12:01:52 - [INFO ] - addonsettings.py - 331 - | Kodi/17.1-RC2 (Linux 3.14.29; armv7l; http://kodi.tv) Version/17.1-RC2-Git:5814568 to 20170320 12:01:52 - [INFO ] - addonsettings.py - 331 - + Kodi/17.1-RC2 (Linux 3.14.29; armv7l; http://kodi.tv) Version/17.1-RC2-Git:5814568 20170320 12:01:52 - [DEBUG ] - addonsettings.py - 336 - User agent retrieved from cache: Kodi/17.1-RC2 (Linux 3.14.29; armv7l; http://kodi.tv) Version/17.1-RC2-Git:5814568 20170320 12:01:52 - [INFO ] - urihandler.py - 561 - Opening requested uri: http://www.google-analytics.com/collect (callback=False, timeout=30) 20170320 12:01:52 - [INFO ] - urihandler.py - 787 - Using UserAgent from AdditionalHeaders: Kodi/17.1-RC2 (Linux 3.14.29; armv7l; http://kodi.tv) Version/17.1-RC2-Git:5814568 20170320 12:01:52 - [DEBUG ] - urihandler.py - 901 - Adding header 'Accept-Encoding: gzip, deflate' 20170320 12:01:52 - [DEBUG ] - urihandler.py - 955 - No Content-Encoding header found 20170320 12:01:52 - [DEBUG ] - urihandler.py - 578 - Determining number of bytes to fetch 20170320 12:01:52 - [DEBUG ] - urihandler.py - 582 - ByteSize is known (fileSize=35) 20170320 12:01:52 - [INFO ] - urihandler.py - 655 - Url http://www.google-analytics.com/collect was opened successfully 20170320 12:01:52 - [DEBUG ] - statistics.py - 188 - Statistics were successfully sent. Content Length: 35 20170320 12:01:52 - [WARNING ] - plugin.py - 582 - Could not start playback due to missing streams. Item: 20170320 12:01:52 - [WARNING ] - plugin.py - 582 - | MediaItem: Venezuela [Type=video, Complete=False, IsLive=False, Date=2017-03-19, Downloadable=False, Geo/DRM=False/False] 20170320 12:01:52 - [WARNING ] - plugin.py - 582 - + MediaPart: Venezuela [CanStream=True, HttpHeaders={}] 20170320 12:01:52 - [DEBUG ] - locker.py - 56 - Un-locking interface and hiding BusyDialog 20170320 12:01:52 - [INFO ] - default.py - 70 - Retrospect :: Flushing and closing logfile.