PMC Crashing When Opening Channel

Dev Community Dev/API Corner
1

Hoping someone here can help me out.

I'm working on fixing the Picasa plugin, but since it appears that PlexWeb doesn't implement everything from the API (or there are bugs) PMC is my first choice for debugging. However, when I try to open the plugin from the PMC (Windows 7, latest server) it crashes. It works with other channels, and it works on Android and PlexWeb. Server version is 0.9.7.22-4b5280f

This is the log from the PMC at the time of the crash:
 

11:42:04 T:2120 M:42%  NOTICE: PlexDirectory::GetDirectory(http://127.0.0.1:32400/photos/PicasaWeb)
11:42:04 T:2120 M:42%   ERROR: CThread::staticThread - Unhandled exception caught in thread process, attemping cleanup in OnExit
11:42:09 T:876 M:42%  NOTICE: PlexDirectory::GetDirectory(http://127.0.0.1:32400)
 
This is the log from the PMS Plex Media Server.txt log:

Apr 08, 2013 11:42:02:866 [3624] DEBUG -  * filter => photo
Apr 08, 2013 11:42:02:867 [3624] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:02:867 [3624] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:02:867 [3624] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:02:867 [3624] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:02:867 [3624] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.
Apr 08, 2013 11:42:04:727 [3616] DEBUG - Request: GET /photos/PicasaWeb [::ffff:127.0.0.1:52365] (4 live)
Apr 08, 2013 11:42:04:727 [3616] DEBUG - Plug-in com.plexapp.plugins.picasaweb has been used 619 times.
Apr 08, 2013 11:42:04:727 [3616] DEBUG - [com.plexapp.plugins.picasaweb] Sending command over HTTP (GET): /photos/PicasaWeb
Apr 08, 2013 11:42:04:727 [3616] DEBUG - (Capabilties) Passing down capabilities of 'protocols=http-video,shoutcast;videoDecoders=h264{profile:high&resolution:1080&level:51};audioDecoders=mp3,aac' to plug-in.
Apr 08, 2013 11:42:04:727 [3616] DEBUG - HTTP requesting to: http://127.0.0.1:52321/photos/PicasaWeb
Apr 08, 2013 11:42:04:744 [3616] DEBUG - [com.plexapp.plugins.picasaweb] HTTP reply status 200, with 3041 bytes of content.
Apr 08, 2013 11:42:09:753 [5124] DEBUG - Request: GET / [::ffff:127.0.0.1:52372] (4 live)
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Apr 08, 2013 11:42:09:753 [5124] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:09:754 [5124] DEBUG - It took 0.001000 sec to serialize a list with 10 elements.
Apr 08, 2013 11:42:19:765 [3260] DEBUG - Request: GET / [::ffff:127.0.0.1:52385] (2 live)
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Apr 08, 2013 11:42:19:765 [3260] DEBUG - (Capabilities) Adding WebKit.
Apr 08, 2013 11:42:19:766 [3260] DEBUG - It took 0.000000 sec to serialize a list with 10 elements.

And here is the log from plugin log:

2013-04-08 11:41:52,211 (d68) :  INFO (core:336) - Starting framework core - Version: 2.3.3, Build: 42865ab (Wed Feb 27 19:41:33 UTC 2013)
2013-04-08 11:41:52,211 (d68) :  DEBUG (core:348) - Using the elevated policy
2013-04-08 11:41:52,211 (d68) :  DEBUG (core:437) - Starting runtime component.
2013-04-08 11:41:52,214 (d68) :  DEBUG (core:437) - Starting caching component.
2013-04-08 11:41:52,214 (d68) :  DEBUG (core:437) - Starting data component.
2013-04-08 11:41:52,214 (d68) :  DEBUG (core:437) - Starting networking component.
2013-04-08 11:41:52,216 (d68) :  DEBUG (networking:318) - Loaded HTTP cookies
2013-04-08 11:41:52,216 (d68) :  DEBUG (networking:504) - Setting the default network timeout to 20.0
2013-04-08 11:41:52,217 (d68) :  DEBUG (core:437) - Starting localization component.
2013-04-08 11:41:52,217 (d68) :  INFO (localization:408) - Setting the default locale to en-us
2013-04-08 11:41:52,217 (d68) :  DEBUG (localization:426) - Loaded en strings
2013-04-08 11:41:52,217 (d68) :  DEBUG (core:437) - Starting messaging component.
2013-04-08 11:41:52,219 (d68) :  DEBUG (core:437) - Starting debugging component.
2013-04-08 11:41:52,219 (d68) :  DEBUG (core:437) - Starting services component.
2013-04-08 11:41:52,219 (d68) :  DEBUG (core:437) - Starting myplex component.
2013-04-08 11:41:52,219 (1e2c) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.plugins.picasaweb'
2013-04-08 11:41:52,219 (d68) :  DEBUG (core:437) - Starting notifications component.
2013-04-08 11:41:52,311 (d68) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.plugins.picasaweb in namespace 'metadata'
2013-04-08 11:41:52,315 (d68) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2013-04-08 11:41:52,336 (1718) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2013-04-08 11:41:52,336 (d68) :  DEBUG (runtime:1089) - Created a thread named 'load_all_services'
2013-04-08 11:41:52,338 (d68) :  DEBUG (runtime:1089) - Created a thread named 'get_server_info'
2013-04-08 11:41:52,338 (d68) :  DEBUG (core:149) - Finished starting framework core
2013-04-08 11:41:52,339 (1f50) :  DEBUG (networking:172) - Requesting 'http://127.0.0.1:32400'
2013-04-08 11:41:52,339 (d68) :  DEBUG (core:547) - Loading plug-in code
2013-04-08 11:41:52,355 (1f50) :  DEBUG (core:525) - Machine identifier is ------------------------------------------------
2013-04-08 11:41:52,355 (1f50) :  DEBUG (core:526) - Server version is 0.9.7.22-4b5280f
2013-04-08 11:41:52,604 (1718) :  DEBUG (services:362) - Loaded services
2013-04-08 11:41:52,611 (b0c) :  DEBUG (services:438) - No shared code to load
2013-04-08 11:41:52,644 (d68) :  DEBUG (core:553) - Finished loading plug-in code
2013-04-08 11:41:52,869 (d68) :  DEBUG (runtime:623) - Adding a prefix handler for 'Picasa Web Albums' ('/photos/PicasaWeb')
2013-04-08 11:41:52,871 (d68) :  INFO (logkit:16) - Initializing gdata PhotoService
2013-04-08 11:41:52,871 (d68) :  DEBUG (preferences:256) - Loaded preferences from DefaultPrefs.json
2013-04-08 11:41:52,874 (d68) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.plugins.picasaweb
2013-04-08 11:41:52,874 (d68) :  INFO (logkit:16) - Client authenticating against google as 'donny.itp'
2013-04-08 11:41:54,009 (d68) :  INFO (logkit:16) - Client login was successful
2013-04-08 11:41:54,010 (d68) :  INFO (core:598) - Started plug-in
2013-04-08 11:41:54,010 (d68) :  DEBUG (socketinterface:144) - Starting socket server
2013-04-08 11:41:54,012 (d68) :  DEBUG (runtime:1089) - Created a thread named 'start'
2013-04-08 11:41:54,013 (d68) :  INFO (socketinterface:168) - Socket server started on port 52321
2013-04-08 11:41:54,013 (d68) :  INFO (pipeinterface:25) - Entering run loop
2013-04-08 11:41:54,013 (d68) :  DEBUG (runtime:700) - Handling request GET /:/prefixes
2013-04-08 11:41:54,013 (d68) :  DEBUG (runtime:796) - Found route matching /:/prefixes
2013-04-08 11:41:54,015 (d68) :  DEBUG (runtime:896) - Response: [200] MediaContainer, 463 bytes
2013-04-08 11:41:54,045 (1ea8) :  DEBUG (runtime:700) - Handling request GET /photos/PicasaWeb/:/function/GetAlbumList?function_args=---------------------------
2013-04-08 11:41:54,046 (1ea8) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-04-08 11:41:54,055 (1ea8) :  DEBUG (runtime:796) - Found route matching /photos/PicasaWeb/:/function/GetAlbumList
2013-04-08 11:41:54,056 (1ea8) :  DEBUG (runtime:143) - Calling function 'GetAlbumList'
2013-04-08 11:41:54,552 (1ea8) :  DEBUG (runtime:106) - Sending packed state data (110 bytes)
2013-04-08 11:41:54,552 (1ea8) :  DEBUG (runtime:896) - Response: [200] MediaContainer, 79788 bytes
2013-04-08 11:42:04,737 (ed8) :  DEBUG (runtime:700) - Handling request GET /photos/PicasaWeb
2013-04-08 11:42:04,739 (ed8) :  DEBUG (runtime:796) - Found route matching /photos/PicasaWeb
2013-04-08 11:42:04,743 (ed8) :  DEBUG (runtime:896) - Response: [200] MediaContainer, 3041 bytes
2013-04-08 11:42:48,059 (c90) :  DEBUG (runtime:700) - Handling request GET /photos/PicasaWeb
2013-04-08 11:42:48,061 (c90) :  DEBUG (runtime:796) - Found route matching /photos/PicasaWeb
2013-04-08 11:42:48,063 (1c98) :  DEBUG (runtime:700) - Handling request GET /photos/PicasaWeb/:/function/GetAlbumList?function_args=----------------------------------
2013-04-08 11:42:48,065 (1c98) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2013-04-08 11:42:48,068 (1c98) :  DEBUG (runtime:796) - Found route matching /photos/PicasaWeb/:/function/GetAlbumList
2013-04-08 11:42:48,068 (1c98) :  DEBUG (runtime:143) - Calling function 'GetAlbumList'
2013-04-08 11:42:48,071 (c90) :  DEBUG (runtime:106) - Sending packed state data (110 bytes)
2013-04-08 11:42:48,072 (c90) :  DEBUG (runtime:896) - Response: [200] MediaContainer, 3041 bytes
2013-04-08 11:42:48,506 (1c98) :  DEBUG (runtime:106) - Sending packed state data (110 bytes)
2013-04-08 11:42:48,506 (1c98) :  DEBUG (runtime:896) - Response: [200] MediaContainer, 79788 bytes
2013-04-08 11:42:57,155 (2b8) :  DEBUG (runtime:700) - Handling request GET /photos/PicasaWeb
2013-04-08 11:42:57,157 (2b8) :  DEBUG (runtime:796) - Found route matching /photos/PicasaWeb
2013-04-08 11:42:57,161 (2b8) :  DEBUG (runtime:896) - Response: [200] MediaContainer, 3041 bytes
2013-04-08 11:46:21,707 (14b8) :  DEBUG (runtime:700) - Handling request GET /photos/PicasaWeb
2013-04-08 11:46:21,707 (14b8) :  DEBUG (runtime:796) - Found route matching /photos/PicasaWeb
2013-04-08 11:46:21,711 (14b8) :  DEBUG (runtime:896) - Response: [200] MediaContainer, 3041 bytes

I have tried including this:
PlexClientPlatforms
*
in my Info.plist and I've tried without it. Didn't make a difference.
2

Ooo the dreaded webkit based crash.  I've seen this type of thing happen before occasionally and it can typically be incredibly hard to track down.  If  you have your code online somewhere it might be helpful if we can see that, but it's unlikely that anything you've done in your code should be able to actually crash PMC.  You might try asking about this in the appropriate PMC forum as well.

3

Ooo the dreaded webkit based crash.  I've seen this type of thing happen before occasionally and it can typically be incredibly hard to track down.  If  you have your code online somewhere it might be helpful if we can see that, but it's unlikely that anything you've done in your code should be able to actually crash PMC.  You might try asking about this in the appropriate PMC forum as well.

Wow, I don't know why I didn't see this reply until now. Anyways, here's a link to the code (https://github.com/eygraber/picasa-plex-plugin) although I've moved on to other projects. 

4

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.