17:10:35 T:139919832577792  NOTICE: -->Python Interpreter Initialized<--
17:10:35 T:139919832577792   DEBUG: CPythonInvoker(29, /home/greg/.kodi/addons/plugin.video.ustvnow/default.py): the source file to load is "/home/greg/.kodi/addons/plugin.video.ustvnow/default.py"
17:10:35 T:139919832577792   DEBUG: CPythonInvoker(29, /home/greg/.kodi/addons/plugin.video.ustvnow/default.py): setting the Python path to /home/greg/.kodi/addons/plugin.video.ustvnow:/usr/lib/python2.7:/usr/lib/python2.7/plat-x86_64-linux-gnu:/usr/lib/python2.7/lib-tk:/usr/lib/python2.7/lib-old:/usr/lib/python2.7/lib-dynload:/usr/local/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages:/usr/lib/python2.7/dist-packages/PILcompat:/usr/lib/python2.7/dist-packages/gst-0.10:/usr/lib/python2.7/dist-packages/gtk-2.0:/usr/lib/python2.7/dist-packages/ubuntu-sso-client
17:10:35 T:139919832577792   DEBUG: CPythonInvoker(29, /home/greg/.kodi/addons/plugin.video.ustvnow/default.py): entering source directory /home/greg/.kodi/addons/plugin.video.ustvnow
17:10:35 T:139919832577792   DEBUG: CPythonInvoker(29, /home/greg/.kodi/addons/plugin.video.ustvnow/default.py): instantiating addon using automatically obtained id of "plugin.video.ustvnow" dependent on version 2.1.0 of the xbmc.python api
17:10:35 T:139919832577792   DEBUG: USTVnow: plugin url: plugin://plugin.video.ustvnow/
17:10:35 T:139919832577792   DEBUG: USTVnow: plugin queries: {'play': '', 'name': 'CBS', 'mode': 'play'}
17:10:35 T:139919832577792   DEBUG: USTVnow: plugin handle: 16
17:10:35 T:139919832577792   DEBUG: USTVnow: logging in
17:10:36 T:139920244221888   DEBUG: DialogProgress::StartModal called
17:10:36 T:139920244221888   DEBUG: ------ Window Init (DialogProgress.xml) ------
17:10:38 T:139919832577792  NOTICE: setResolvedUrl
17:10:38 T:139920244221888   DEBUG: WaitOnScriptResult- plugin returned successfully
17:10:38 T:139919832577792    INFO: CPythonInvoker(29, /home/greg/.kodi/addons/plugin.video.ustvnow/default.py): script successfully run
17:10:38 T:139920244221888   DEBUG: Loading settings for rtmp://lv7.ustvnow.com:1935/dvrtest?key=CAPT-APA54610F41BDA9AE79E5A58678EDCDA001439/mp4:EAE07WHP0USTVNOW1
17:10:38 T:139920244221888   DEBUG: CPlayerCoreFactory::GetPlayers(rtmp://lv7.ustvnow.com:1935/dvrtest?key=CAPT-APA54610F41BDA9AE79E5A58678EDCDA001439/mp4:EAE07WHP0USTVNOW1)
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/mms/udp
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: rtmp
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: adding player: videodefaultplayer (1) for rule: rtmp
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
17:10:38 T:139920244221888   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
17:10:38 T:139920244221888   DEBUG: CPlayerCoreFactory::GetPlayers: matched 1 rules with players
17:10:38 T:139920244221888   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
17:10:38 T:139920244221888   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
17:10:38 T:139920244221888   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
17:10:38 T:139920244221888   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
17:10:38 T:139920244221888   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
17:10:38 T:139920244221888  NOTICE: DVDPlayer: Opening: rtmp://lv7.ustvnow.com:1935/dvrtest?key=CAPT-APA54610F41BDA9AE79E5A58678EDCDA001439/mp4:EAE07WHP0USTVNOW1
17:10:38 T:139920244221888 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
17:10:38 T:139920244221888   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
17:10:38 T:139920244221888   DEBUG: LinuxRendererGL: Cleaning up GL resources
17:10:38 T:139920244221888   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
17:10:38 T:139919388251904  NOTICE: Thread DVDPlayer start, auto delete: false
17:10:38 T:139919388251904  NOTICE: Creating InputStream
17:10:38 T:139919388251904   DEBUG: SECTION:LoadDLL(librtmp.so.1)
17:10:38 T:139919388251904   DEBUG: Loading: librtmp.so.1
17:10:38 T:139919388251904   ERROR: Unable to load librtmp.so.1, reason: librtmp.so.1: cannot open shared object file: No such file or directory
17:10:38 T:139919388251904   DEBUG: Dll librtmp.so.1 was not found in path
17:10:38 T:139919388251904   ERROR: CDVDPlayer::OpenInputStream - error opening [rtmp://lv7.ustvnow.com:1935/dvrtest?key=CAPT-APA54610F41BDA9AE79E5A58678EDCDA001439/mp4:EAE07WHP0USTVNOW1]
17:10:38 T:139919388251904  NOTICE: CDVDPlayer::OnExit()
17:10:38 T:139919388251904   DEBUG: OnPlayBackStopped: play state was 1, starting 1
17:10:38 T:139920244221888   DEBUG: OnPlayBackStopped: play state was 3, starting 0
17:10:38 T:139920244221888   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
17:10:38 T:139920244221888   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
17:10:38 T:139920244221888   ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.video.ustvnow/?name=CBS&mode=play]
17:10:38 T:139920244221888   DEBUG: Playlist Player: one or more items failed to play... aborting playback
17:10:38 T:139920244221888   DEBUG: ------ Window Init (DialogOK.xml) ------
17:10:38 T:139920244221888  NOTICE: CDVDPlayer::CloseFile()
17:10:38 T:139920244221888  NOTICE: DVDPlayer: waiting for threads to exit
17:10:38 T:139920244221888  NOTICE: DVDPlayer: finished waiting
17:10:38 T:139920244221888   DEBUG: LinuxRendererGL: Cleaning up GL resources
17:10:38 T:139920244221888  NOTICE: CDVDPlayer::CloseFile()
17:10:38 T:139920244221888  NOTICE: DVDPlayer: waiting for threads to exit
17:10:38 T:139920244221888  NOTICE: DVDPlayer: finished waiting
17:10:38 T:139920244221888   DEBUG: LinuxRendererGL: Cleaning up GL resources
17:10:38 T:139919388251904   DEBUG: Thread DVDPlayer 139919388251904 terminating
17:10:38 T:139919832577792    INFO: Python script stopped
17:10:38 T:139919154472704  NOTICE: Stream All The Sources: Service: Playback Stopped
17:10:38 T:139919832577792   DEBUG: Thread LanguageInvoker 139919832577792 terminating
17:10:38 T:139919396644608  NOTICE: 1Channel: Service: Playback Stopped
17:10:38 T:139919396644608  NOTICE: 1Channel: Service: Resetting...
17:10:38 T:139920244221888   DEBUG: ------ Window Deinit (DialogProgress.xml) ------
17:10:38 T:139920244221888   DEBUG: UPower: Received an unknown signal DeviceChanged
17:10:39 T:139920244221888   DEBUG: Previous line repeats 1 times.
17:10:39 T:139920244221888   DEBUG: ------ Window Init (Pointer.xml) ------
17:10:40 T:139920244221888   DEBUG: ProcessMouse: trying mouse action leftclick
17:10:40 T:139920244221888   DEBUG: ------ Window Deinit (Pointer.xml) ------
17:10:41 T:139920244221888   DEBUG: ------ Window Deinit (DialogOK.xml) ------
17:10:41 T:139920244221888   DEBUG: CGUIMediaWindow::GetDirectory (playlistvideo://)
17:10:41 T:139920244221888   DEBUG:   ParentPath = [playlistvideo://]
17:10:41 T:139919832577792  NOTICE: Thread BackgroundLoader start, auto delete: false
17:10:41 T:139920244221888   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
17:10:41 T:139920244221888   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
17:10:41 T:139919456024320   DEBUG: DoWork - trying to extract filestream details from video file plugin://plugin.video.ustvnow/?name=CBS&mode=play
17:10:41 T:139919832577792   DEBUG: Thread BackgroundLoader 139919832577792 terminating
17:10:41 T:139920244221888   DEBUG: ------ Window Init (Pointer.xml) ------
17:10:43 T:139920244221888   DEBUG: ProcessMouse: trying mouse action leftclick
17:10:43 T:139920244221888   DEBUG: Activating window ID: 10000
17:10:44 T:139920244221888   DEBUG: ------ Window Deinit (MyVideoPlaylist.xml) ------
17:10:44 T:139920244221888   DEBUG: ------ Window Init (Home.xml) ------
17:10:44 T:139920244221888   DEBUG: ------ Window Deinit (Pointer.xml) ------
17:10:44 T:139920244221888   DEBUG: ------ Window Init (Pointer.xml) ------
17:10:45 T:139920244221888   DEBUG: ProcessMouse: trying mouse action leftclick
17:10:45 T:139920244221888   DEBUG: Activating window ID: 10004
17:10:46 T:139920244221888   DEBUG: ------ Window Deinit (Home.xml) ------
17:10:46 T:139920244221888   DEBUG: ------ Window Init (Settings.xml) ------
17:10:46 T:139920244221888   DEBUG: ------ Window Deinit (Pointer.xml) ------
17:10:46 T:139920244221888   DEBUG: ------ Window Init (Pointer.xml) ------
17:10:48 T:139920244221888   DEBUG: ProcessMouse: trying mouse action leftclick
17:10:48 T:139920244221888   DEBUG: Activating window ID: 10018
17:10:48 T:139920244221888   DEBUG: ------ Window Deinit (Settings.xml) ------
17:10:48 T:139920244221888   DEBUG: ------ Window Init (SettingsCategory.xml) ------
17:10:48 T:139920244221888   DEBUG: ------ Window Deinit (Pointer.xml) ------
17:10:49 T:139920244221888   DEBUG: ------ Window Init (Pointer.xml) ------
17:10:52 T:139920244221888   DEBUG: ProcessMouse: trying mouse action leftclick
17:10:52 T:139920244221888   DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
17:10:52 T:139920244221888   DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
17:10:52 T:139920244221888   DEBUG: CGUIWindowManager::PreviousWindow: Activate new
17:10:52 T:139920244221888   DEBUG: ------ Window Init (Settings.xml) ------
17:10:55 T:139920244221888   DEBUG: ProcessMouse: trying mouse action leftclick
17:10:55 T:139920244221888   DEBUG: Activating window ID: 10016
17:10:55 T:139920244221888   DEBUG: ------ Window Deinit (Settings.xml) ------
17:10:55 T:139920244221888   DEBUG: ------ Window Init (SettingsCategory.xml) ------
17:10:56 T:139920244221888   DEBUG: ProcessMouse: trying mouse action leftclick
17:10:56 T:139920244221888  NOTICE: Disabled debug logging due to GUI setting. Level 0.
17:10:56 T:139920244221888  NOTICE: Log level changed to "LOG_LEVEL_NORMAL"
17:11:23 T:139919447631616  NOTICE: Thread LanguageInvoker start, auto delete: false
17:11:23 T:139919447631616  NOTICE: -->Python Interpreter Initialized<--
17:11:24 T:139919447631616  NOTICE: metahandler: Initializing MetaHandlers version: 2.7.0
17:11:24 T:139919447631616  NOTICE: metahandler: Loading sqlite3 as DB engine version: 3.8.2
17:11:24 T:139919447631616  NOTICE: metahandler: Looking up TMDB config cache values
17:11:24 T:139919447631616  NOTICE: metahandler: Cache still valid, setting values
17:11:24 T:139919447631616  NOTICE: metahandler: No SQLite rows requiring update
17:11:24 T:139919447631616  NOTICE: Mode: None
17:11:24 T:139919447631616  NOTICE: URL: None
17:11:24 T:139919447631616  NOTICE: Name: None
17:11:24 T:139919447631616  NOTICE: IconImage: None
17:11:24 T:139919447631616  NOTICE: 3
17:11:24 T:139919447631616  NOTICE: 4
17:11:24 T:139919447631616  NOTICE: 8
17:11:24 T:139919447631616  NOTICE: ['8']
17:11:24 T:139919447631616  NOTICE: 8
17:11:24 T:139919464417024  NOTICE: Thread BackgroundLoader start, auto delete: false
17:11:24 T:139919490017024  NOTICE: Thread JobWorker start, auto delete: true
17:11:32 T:139919447631616  NOTICE: Thread LanguageInvoker start, auto delete: false
17:11:32 T:139919447631616  NOTICE: -->Python Interpreter Initialized<--
17:11:32 T:139919447631616  NOTICE: metahandler: Initializing MetaHandlers version: 2.7.0
17:11:32 T:139919447631616  NOTICE: metahandler: Loading sqlite3 as DB engine version: 3.8.2
17:11:32 T:139919447631616  NOTICE: metahandler: Looking up TMDB config cache values
17:11:32 T:139919447631616  NOTICE: metahandler: Cache still valid, setting values
17:11:32 T:139919447631616  NOTICE: metahandler: No SQLite rows requiring update
17:11:32 T:139919447631616  NOTICE: Mode: 7
17:11:32 T:139919447631616  NOTICE: URL: http://tribeca.tvaddons.ag/tools/maintenance
17:11:32 T:139919447631616  NOTICE: Name: General Maintenance
17:11:32 T:139919447631616  NOTICE: IconImage: http://tribeca.tvaddons.ag/tools/maintenance/thumbs/generalmaintenance.jpg
17:11:32 T:139919464417024  NOTICE: Thread BackgroundLoader start, auto delete: false
17:11:37 T:139919447631616  NOTICE: Thread LanguageInvoker start, auto delete: false
17:11:37 T:139919447631616  NOTICE: -->Python Interpreter Initialized<--
17:11:37 T:139919447631616  NOTICE: metahandler: Initializing MetaHandlers version: 2.7.0
17:11:37 T:139919447631616  NOTICE: metahandler: Loading sqlite3 as DB engine version: 3.8.2
17:11:37 T:139919447631616  NOTICE: metahandler: Looking up TMDB config cache values
17:11:37 T:139919447631616  NOTICE: metahandler: Cache still valid, setting values
17:11:37 T:139919447631616  NOTICE: metahandler: No SQLite rows requiring update
17:11:37 T:139919447631616  NOTICE: Mode: None
17:11:37 T:139919447631616  NOTICE: URL: None
17:11:37 T:139919447631616  NOTICE: Name: None
17:11:37 T:139919447631616  NOTICE: IconImage: None
17:11:37 T:139919447631616  NOTICE: 4
17:11:37 T:139919447631616  NOTICE: 5
17:11:37 T:139919447631616  NOTICE: 8
17:11:37 T:139919447631616  NOTICE: ['8']
17:11:37 T:139919447631616  NOTICE: 8
17:11:37 T:139919464417024  NOTICE: Thread BackgroundLoader start, auto delete: false
17:11:37 T:139919480055552  NOTICE: Thread JobWorker start, auto delete: true
17:11:39 T:139919447631616  NOTICE: Thread LanguageInvoker start, auto delete: false
17:11:40 T:139919447631616  NOTICE: -->Python Interpreter Initialized<--
17:11:40 T:139919447631616  NOTICE: metahandler: Initializing MetaHandlers version: 2.7.0
17:11:40 T:139919447631616  NOTICE: metahandler: Loading sqlite3 as DB engine version: 3.8.2
17:11:40 T:139919447631616  NOTICE: metahandler: Looking up TMDB config cache values
17:11:40 T:139919447631616  NOTICE: metahandler: Cache still valid, setting values
17:11:40 T:139919447631616  NOTICE: metahandler: No SQLite rows requiring update
17:11:40 T:139919447631616  NOTICE: Mode: 10
17:11:40 T:139919447631616  NOTICE: URL: http://tribeca.tvaddons.ag/tools/maintenance
17:11:40 T:139919447631616  NOTICE: Name: System Tweaks
17:11:40 T:139919447631616  NOTICE: IconImage: http://tribeca.tvaddons.ag/tools/maintenance/thumbs/systemtweaks.jpg
17:11:40 T:139919464417024  NOTICE: Thread BackgroundLoader start, auto delete: false
17:11:49 T:139919413430016  NOTICE: Thread LanguageInvoker start, auto delete: false
17:11:50 T:139919413430016  NOTICE: -->Python Interpreter Initialized<--
17:11:50 T:139919413430016  NOTICE: metahandler: Initializing MetaHandlers version: 2.7.0
17:11:50 T:139919413430016  NOTICE: metahandler: Loading sqlite3 as DB engine version: 3.8.2
17:11:50 T:139919413430016  NOTICE: metahandler: Looking up TMDB config cache values
17:11:50 T:139919413430016  NOTICE: metahandler: Cache still valid, setting values
17:11:50 T:139919413430016  NOTICE: metahandler: No SQLite rows requiring update
17:11:50 T:139919413430016  NOTICE: Mode: None
17:11:50 T:139919413430016  NOTICE: URL: None
17:11:50 T:139919413430016  NOTICE: Name: None
17:11:50 T:139919413430016  NOTICE: IconImage: None
17:11:50 T:139919413430016  NOTICE: 5
17:11:50 T:139919413430016  NOTICE: 6
17:11:50 T:139919413430016  NOTICE: 8
17:11:50 T:139919413430016  NOTICE: ['8']
17:11:50 T:139919413430016  NOTICE: 8
17:11:50 T:139919464417024  NOTICE: Thread BackgroundLoader start, auto delete: false
17:11:54 T:139919490017024   ERROR: Unable to find plugin
17:11:54 T:139920244221888   ERROR: GetDirectory - Error getting plugin://
17:11:54 T:139920244221888   ERROR: CGUIMediaWindow::GetDirectory(plugin://) failed
17:11:54 T:139919413430016  NOTICE: Thread BackgroundLoader start, auto delete: false
17:12:03 T:139919413430016  NOTICE: Previous line repeats 1 times.
17:12:03 T:139919413430016  NOTICE: Thread LanguageInvoker start, auto delete: false
17:12:03 T:139919413430016  NOTICE: -->Python Interpreter Initialized<--
17:12:54 T:139919413430016  NOTICE: Kodi Log Uploader: started
17:12:54 T:139919413430016  NOTICE: Kodi Log Uploader: settings: len(email)=20
17:12:54 T:139919413430016  NOTICE: Kodi Log Uploader: settings: skip_oldlog=True
17:12:57 T:139919413430016  NOTICE: Kodi Log Uploader: reading log...