myGBPVR: use XMBC as GBPVR FrontEnd

[Ton]

Okay,

I have added some debug-statements in the code that connects to GBPVR....

Please extract the included GBPVR_Connect.py (in the zip) to your ..../myGBPVR/resources/src directory.

Then try again and post the log-file... It should now show the authentication-object I'm sending over the wire (and which seems to not play nice with EWA) and the timestamp used to create the authentication object....

I'll be waiting for your results....

[carpeVideo]

Here is the new XBMC log and the EWA log

Code:

16:12:45 T:340 M:2092519424 DEBUG: LoadLibraryA('MSVCR71.dll') 16:12:45 T:340 M:2092519424 DEBUG: LoadLibrary('MSVCR71.dll') returning: 0181E158 16:12:45 T:340 M:2092519424 DEBUG: LoadLibrary('MSVCP71.dll') returning: 0181D500 16:12:47 T:5360 M:2093441024 NOTICE: --> AreYouThere() socket on port 7647 using ip=192.168.0.10 16:12:47 T:5360 M:2093441024 NOTICE: mac:00:23:54:33:f3:f3broadcast: 255.255.255.0 16:12:47 T:5360 M:2093441024 NOTICE: This got: True / 00:23:54:33:f3:f3 16:12:47 T:5360 M:2093432832 NOTICE: --> AreYouThere() returning: True 16:12:48 T:5360 M:2091634688 NOTICE: TimeString used: 30-04-2010 16:12:00 16:12:48 T:5360 M:2091520000 NOTICE: (webServiceAuthentication){ R = "5JF7uo8ysp+CyxphQhrPB91QlIBR1wJ9FF+3Ph8F6TRbjvWeOboi7Qq4HLDftq0n+r9gxhNTFjYqBWnwXa27oQ==+r9gxhNTFjYqBWnwXa27oQ==000021dd8d24a440ac16b7dae03db0833479" RL = "WFPKW9S3+0cij/WKTBNuOw==" RTL = "he7bT4pWoJLB2mLH2mx7Gw==" UserName = None Password = None } 16:12:48 T:5360 M:2091487232 NOTICE: EXCEPTION: : Traceback (most recent call last): File "D:\programs\XBMC\scripts\myGBPVR\resources\src\gbpvr\home.py", line 152, in refreshOnInit self.statusData = self.gbpvr.GetGBPVRInfo(self.settings.GBPVR_USER, self.settings.GBPVR_PW) File "D:\programs\XBMC\scripts\myGBPVR\resources\src\GBPVR_Connect.py", line 134, in GetGBPVRInfo self.channels = self.getChannelList(userid, password) File "D:\programs\XBMC\scripts\myGBPVR\resources\src\GBPVR_Connect.py", line 157, in getChannelList ret_soap = client.service.getChannelListObject(soapheaders=authObj) WebFault: Server raised fault: 'Server was unable to process request. ---> String was not recognized as a valid DateTime.' 16:12:48 T:5360 M:2091487232 INFO: Loading skin file: DialogOK.xml 16:12:48 T:5360 M:2091610112 DEBUG: Load DialogOK.xml: 16.35ms 16:12:48 T:340 M:2091610112 DEBUG: ------ Window Init (DialogOK.xml) ------ 16:12:48 T:340 M:2091610112 DEBUG: Alloc resources: 0.41ms (0.00 ms skin load) 16:12:50 T:340 M:2091249664 DEBUG: ------ Window Deinit (DialogOK.xml) ------ 16:12:50 T:340 M:2091307008 DEBUG: Activating window ID: 10020 16:12:50 T:340 M:2091307008 DEBUG: Checking if window ID 10020 is locked. 16:12:50 T:340 M:2091307008 DEBUG: ------ Window Deinit (D:\programs\XBMC\scripts\myGBPVR\resources\skins\Default\720p\gbpvr_home.xml) ------ 16:12:51 T:340 M:2091167744 DEBUG: ------ Window Init (MyScripts.xml) ------ 16:12:51 T:340 M:2091167744 INFO: Loading skin file: MyScripts.xml 16:12:51 T:340 M:2091134976 DEBUG: Load MyScripts.xml: 21.46ms 16:12:51 T:340 M:2090881024 DEBUG: Alloc resources: 25.64ms (21.54 ms skin load) 16:12:51 T:340 M:2090881024 DEBUG: CGUIMediaWindow::GetDirectory (special://home/scripts) 16:12:51 T:340 M:2090881024 DEBUG: ParentPath = [special://home/scripts] 16:12:51 T:340 M:2085974016 INFO: msg: Corrupt JPEG data: 78 extraneous bytes before marker 0xd1 16:12:51 T:340 M:2085949440 INFO: msg: Corrupt JPEG data: 78 extraneous bytes before marker 0xd1 16:12:51 T:5360 M:2087280640 INFO: Scriptresult: Success 16:12:51 T:5360 M:2086555648 INFO: Python script stopped 16:12:51 T:5360 M:2086555648 DEBUG: Thread 5360 terminating 16:12:51 T:340 M:2086617088 DEBUG: python thread 1 destructed 16:12:51 T:340 M:2086617088 INFO: Python, unloading python24.dll because no scripts are running anymore 16:12:51 T:340 M:2086617088 ERROR: CLocalizeStrings::ClearBlock: Trying to clear non existent block D:\programs\XBMC\scripts\myGBPVR 16:12:51 T:340 M:2087358464 DEBUG: XBPython::UnloadExtensionLibs, clearing python extension libraries 16:12:51 T:340 M:2087358464 DEBUG: Win32DllLoader::Unload DLLs\zlib.pyd 16:12:51 T:340 M:2087358464 DEBUG: FreeLibrary(python24.dll) -> 027AFBF0 16:12:51 T:340 M:2087358464 DEBUG: FreeLibrary(MSVCR71.dll) -> 0181E158 16:12:51 T:340 M:2087419904 DEBUG: Win32DllLoader::Unload DLLs\_socket.pyd 16:12:51 T:340 M:2087419904 DEBUG: FreeLibrary(python24.dll) -> 027AFBF0 16:12:51 T:340 M:2087419904 DEBUG: FreeLibrary(MSVCR71.dll) -> 0181E158 16:12:51 T:340 M:2087464960 DEBUG: Win32DllLoader::Unload DLLs\_ssl.pyd 16:12:51 T:340 M:2087464960 DEBUG: FreeLibrary(python24.dll) -> 027AFBF0 16:12:51 T:340 M:2087464960 DEBUG: FreeLibrary(MSVCR71.dll) -> 0181E158 16:12:51 T:340 M:2087890944 DEBUG: Win32DllLoader::Unload DLLs\pyexpat.pyd 16:12:51 T:340 M:2087890944 DEBUG: FreeLibrary(python24.dll) -> 027AFBF0 16:12:51 T:340 M:2087890944 DEBUG: FreeLibrary(MSVCR71.dll) -> 0181E158 16:12:51 T:340 M:2087993344 DEBUG: Win32DllLoader::Unload D:\programs\XBMC\scripts\myGBPVR\resources\lib\Crypto\Cipher\AES.pyd 16:12:51 T:340 M:2087993344 DEBUG: FreeLibrary(python24.dll) -> 027AFBF0 16:12:51 T:340 M:2087993344 DEBUG: FreeLibrary(MSVCR71.dll) -> 0181E158 16:12:51 T:340 M:2088034304 DEBUG: Win32DllLoader::Unload python24.dll 16:12:51 T:340 M:2088034304 DEBUG: FreeLibrary(MSVCR71.dll) -> 0181E158 16:12:51 T:340 M:2089029632 DEBUG: CGUIMediaWindow::GetDirectory (special://home/scripts) 16:12:51 T:340 M:2089029632 DEBUG: ParentPath = [special://home/scripts]

Code:

2010-04-30 15:54:13.829 INFO [4] ********************* 2010-04-30 15:54:13.829 INFO [4] * Validating Incomming Web Service 2010-04-30 15:54:13.829 INFO [4] * 2010-04-30 15:54:13.829 INFO [4] User Name = gbpvr 2010-04-30 15:54:13.829 INFO [4] User Password = gbpvr 2010-04-30 15:54:13.829 INFO [4] * 2010-04-30 15:54:13.829 INFO [4] * 2010-04-30 15:54:13.829 INFO [4] Checking credential timestamp.... 2010-04-30 15:54:13.829 INFO [4] nowTime = 4/30/2010 8:54:13 PM 2010-04-30 15:54:13.829 INFO [4] Credential time string = 30-04-2010 15:54:00 2010-04-30 15:54:27.199 INFO [4] In writing of file 2010-04-30 15:54:27.199 INFO [4] readcount = 10258

[Ton]

Anything more in the EWA log-file??
This:

Code:

WebFault: Server raised fault: 'Server was unable to process request. ---> String was not recognized as a valid DateTime.'

tells me the server raises a fault..., I would expect something in the EWA log about that....

Also, what's the geographical date-time setting on your GBPVR server? Is it mm-dd-yy (seems to, from the log-file)?

[carpeVideo]

No but changing the python code to this fixed it:

timeString = datetime.now().strftime("%m/%d/%Y %H:%M:00")

[Ton]

Hi,

No but changing the python code to this fixed it:

timeString = datetime.now().strftime("%m/%d/%Y %H:%M:00")

Yeah, we were on the same track! Good work! So it is related to .NET parsing the timestamp based on it's own regional settings....
I have it now so it uses ISO format (Y-M-D) and that seems to work (on my end). Could you try that too?
It is:

Code:

timeString = datetime.now().strftime("%Y-%m-%d %H:%M:00")

Or even better (It's what I'm using in the epg):

Code:

timeString = datetime.now().strftime("%Y-%m-%dT%H:%M:00")

That way we can make it independant on regional settings on xbmc and GBPVR...

Have you been able to try out the "play" function as well?

[dljones8053]

capeVideo

Thanks for the fix, I was going crazy trying to figure out why mind stop working, I also posted before and after the change so others know what to look for.

Log before the fix
2010-04-30 19:29:06.844 INFO [4] Checking credential timestamp....
2010-04-30 19:29:06.844 INFO [4] nowTime = 4/30/2010 11:29:06 PM
2010-04-30 19:29:06.844 INFO [4] Credential time string = 30-04-2010 19:29:00

Log after the fix
2010-04-30 19:34:32.938 INFO [4] Checking credential timestamp....
2010-04-30 19:34:32.938 INFO [4] nowTime = 4/30/2010 11:34:32 PM
2010-04-30 19:34:32.938 INFO [4] Credential time string = 04/30/2010 19:34:00
2010-04-30 19:34:32.938 INFO [4] Credential time converted = 4/30/2010 7:34:00 PM

DJ

[carpeVideo]

Hi,

Yeah, we were on the same track! Good work! So it is related to .NET parsing the timestamp based on it's own regional settings....
I have it now so it uses ISO format (Y-M-D) and that seems to work (on my end). Could you try that too?
It is:

Code:

timeString = datetime.now().strftime("%Y-%m-%d %H:%M:00")

Or even better (It's what I'm using in the epg):

Code:

timeString = datetime.now().strftime("%Y-%m-%dT%H:%M:00")

That way we can make it independant on regional settings on xbmc and GBPVR...

Have you been able to try out the "play" function as well?

I will give it a try tomorrow - as to the play function - I still can't get that far, for some reason that and a few other screens seem to freeze or time out, I am not sure why the webservices would be so slow. I'll post those logs as well.

Thanks for the help

[Ton]

I will give it a try tomorrow - as to the play function - I still can't get that far, for some reason that and a few other screens seem to freeze or time out, I am not sure why the webservices would be so slow. I'll post those logs as well.

Thanks for the help

Thanks, that will allow me to put ths in my next release..
As far as the freezes and timeouts are concerned, not sure if it's related, but sometimes XBMC seems to get confused when you make changes to scripts. Sometimes that makes it necessary (for me) to restart XBMC....

[carpeVideo]

The second (datetime.now().strftime("%Y-%m-%dT%H:%M:00") ) returns this:

Code:

msvcrt.dll incomplete function _fdopen(...) called 08:28:47 T:3124 M:2052808704 NOTICE: TimeString 2 used: 2010-05-01T08:28:00 08:28:47 T:3124 M:2052997120 DEBUG: LoadLibraryExA called with flags: - LOAD_WITH_ALTERED_SEARCH_PATH 08:28:47 T:3124 M:2052997120 DEBUG: LoadLibraryA('AES.pyd') 08:28:47 T:3124 M:2052997120 DEBUG: Win32DllLoader::Load(D:\programs\XBMC\scripts\myGBPVR\resources\lib\Crypto\Cipher\AES.pyd) 08:28:47 T:3124 M:2053132288 DEBUG: LoadLibraryA('python24.dll') 08:28:47 T:3124 M:2053132288 DEBUG: LoadLibrary('python24.dll') returning: 026A1D70 08:28:47 T:3124 M:2053132288 DEBUG: LoadLibraryA('MSVCR71.dll') 08:28:47 T:3124 M:2053132288 DEBUG: LoadLibrary('MSVCR71.dll') returning: 0181E458 08:28:47 T:3124 M:2053132288 DEBUG: LoadLibrary('AES.pyd') returning: 02578068 08:28:47 T:3124 M:2053046272 NOTICE: (webServiceAuthentication){ R = "Snk3CwPvENLKJ/RjNrKHdiSRMFP3rILHc8qhpvEGymuCDfeeLgleL+LwRuxDO2w87QzPY1hIRmh1Mv9hgko2nw==7QzPY1hIRmh1Mv9hgko2nw==0000799ebd6fd6bcb17dd4ed28fe7d617f9d" RL = "vxvDRlhjr8AzzAVinsqmYQ==" RTL = "QyyeO0O3vk0yaP4VwYRXBw==" UserName = None Password = None } 08:28:47 T:3124 M:2053013504 NOTICE: No handlers could be found for logger "suds.client" 08:28:47 T:3124 M:2053013504 NOTICE: EXCEPTION: : Traceback (most recent call last): File "D:\programs\XBMC\scripts\myGBPVR\resources\src\gbpvr\home.py", line 152, in refreshOnInit self.statusData = self.gbpvr.GetGBPVRInfo(self.settings.GBPVR_USER, self.settings.GBPVR_PW) File "D:\programs\XBMC\scripts\myGBPVR\resources\src\GBPVR_Connect.py", line 134, in GetGBPVRInfo self.channels = self.getChannelList(userid, password) File "D:\programs\XBMC\scripts\myGBPVR\resources\src\GBPVR_Connect.py", line 157, in getChannelList ret_soap = client.service.getChannelListObject(soapheaders=authObj) WebFault: Server raised fault: 'Unauthorized - Timeout' 08:28:47 T:3124 M:2053009408 INFO: Loading skin file: DialogOK.xml 08:28:47 T:312

However removing the T : datetime.now().strftime("%Y-%m-%d %H:%M:00") seems to make it work.

The freeze part seems to be due to a file error:

Code:

08:41:33 T:5260 M:1962680320 INFO: Skin version is: 2.1 08:41:33 T:5260 M:1962672128 INFO: Loading skin includes from D:\programs\XBMC\scripts\myGBPVR\resources\skins\Default\720p\includes.xml 08:41:33 T:5260 M:1962668032 INFO: Error loading includes.xml file (D:\programs\XBMC\scripts\myGBPVR\resources\skins\Default\720p\includes.xml): Failed to open file (row=0, col=0) 08:41:33 T:4540 M:1962643456 DEBUG: ------ Window Init (D:\programs\XBMC\scripts\myGBPVR\resources\skins\Default\720p\gbpvr_details.xml) ------ 08:41:33 T:4540 M:1962643456 INFO: Loading skin file: D:\programs\XBMC\scripts\myGBPVR\resources\skins\Default\720p\gbpvr_details.xml 08:41:33 T:4540 M:1962467328 DEBUG: Load D:\programs\XBMC\scripts\myGBPVR\resources\skins\Default\720p\gbpvr_details.xml: 8.12ms 08:41:33 T:4540 M:1962500096 DEBUG: Alloc resources: 10.85ms (8.25 ms skin load) 08:41:33 T:4540 M:1962496000 DEBUG: CGUIMediaWindow::GetDirectory (?)

includes.xml - was that in the update for the "play" button?

[dljones8053]

capeVideo

I have a question while setting up the XBMC Library for some reason it will not pick up the recordings the the format GBPVR writes them out, just wondering if you have seen this as well.

thanks
DJ