[Gnash-dev] OLPC bug 5987: can't invoke "firefox" on OLPC

[John Gilmore]

When going to pbskids.org, there's a flash animation.  It apparently
tries to do some web browser navigation, which doesn't work with gnash.

There are a couple of Unimplemented's, probably some codec problems
with the sound files (unclear), but the interesting bit is at the very
end, where Gnash tries to invoke "firefox".  Here's the logfile
(from /home/olpc/.sugar/default/logs/org.laptop.WebActivity-1.log).

(That navigation works in real Firefox on Ubuntu, with a close-to-CVS
version of gnash.  There must be something screwy in the browser
plugin that we use on the OLPC.)

        John

xEmbed supported in this browser
GTK2 supported in this browser
NewStream: The full URL is http://www-tc.pbskids.org/swfs/homepage.swf
Closed 34files.
Forked successfully, child process PID is 2153
Starting process: /usr/bin/gtk-gnash -v -x 16780002 -j 1185 -k 579 -u 
http://www-tc.pbskids.org/swfs/homepage.swf -U http://pbskids.org/ -P 
base=http://pbskids.org/_/ -P bgcolor=#ccffff -P 
flashvars=allowScriptAccess=sameDomain -P height=405 -P id=homepage -P 
name=homepage -P quality=high -P src=/swfs/enablecdn/homepage.swf -P style= -P 
type=application/x-shockwave-flash -P width=100% -P wmode=transparent - 
ERROR: can't open debug log file gnash-dbg.log for writing.
Verbose output turned on
2153] 19:41:43: Setting width to 1185
2153] 19:41:43: Setting height to 579
2153] 19:41:43: Setting root URL to http://www-tc.pbskids.org/swfs/homepage.swf
2153] 19:41:43: Setting base URL to http://pbskids.org/
2153] 19:41:43: No rendering flags specified, using rcfile

(<unknown>:2154): libgnomevfs-WARNING **: Unable to create ~/.gnome2 directory: 
Permission denied

(<unknown>:2154): libgnomevfs-WARNING **: gnome_vfs_init(): .gnome does not 
exist

(<unknown>:2153): libgnomevfs-WARNING **: Unable to create ~/.gnome2 directory: 
Permission denied

(<unknown>:2153): libgnomevfs-WARNING **: gnome_vfs_init(): .gnome does not 
exist
2153] 19:41:47: Checking support for MIT-SHM...
2153] 19:41:47: NOTICE: MIT-SHM available (version 1.1)!
2153] 19:41:47: Your X server expects RGB24 pixmap data for standard mode.
2153] 19:41:47: Created XEmbedded window
2153] 19:41:47: DEBUG: bool gnash::Network::connectSocket(const char*) enter
2153] 19:41:47: ERROR: The connect() socket for fd 7 never was available for 
writing
2153] 19:41:47: LIRC daemon not running
2153] 19:41:47: X server pixel format is (R11:5, G5:6, B0:5, 16 bpp)
2153] 19:41:47: X server is using RGB565 pixel format
2153] 19:41:47: framebuffer pixel format is RGB565 (little-endian host)
2153] 19:41:47: DEBUG: Base url set to: http://pbskids.org/_/
2153] 19:41:48: GTK-AGG: Using shared memory image
2153] 19:41:48: initialized AGG buffer <0xb5d17000>, 1373388 bytes, 1185x579, 
rowsize is 2372 bytes
2153] 19:41:52: UNIMPLEMENTED: Stage.showMenu setter
2153] 19:41:52: DEBUG: bool gnash::XML::load(const gnash::URL&) enter
2153] 19:41:52: SECURITY: Load from host pbskids.org granted (default).
2153] 19:41:52: Loading XML file from url: 'http://pbskids.org/_/xml/theme.xml'
2153] 19:41:52: DEBUG: void gnash::XML::queueLoad(std::auto_ptr<tu_file>) enter
2153] 19:41:52: set_member 'loaded' ([bool:false]) became boolean 0
2153] 19:41:53: HTTP response 200 from url http://pbskids.org/_/xml/theme.xml
2153] 19:41:53: DEBUG: Pushed thread 0x83911b0 to _loadThreads, number of XML 
load threads now: 1
2153] 19:41:53: DEBUG: Registered XML loads interval -1
2153] 19:41:53: DEBUG: bool gnash::XML::load(const gnash::URL&) enter
2153] 19:41:53: SECURITY: Load from host pbskids.org granted (default).
2153] 19:41:53: Loading XML file from url: 
'http://pbskids.org/_/xml/bubbles.xml'
2153] 19:41:53: DEBUG: void gnash::XML::queueLoad(std::auto_ptr<tu_file>) enter
2153] 19:41:53: set_member 'loaded' ([bool:false]) became boolean 0
2153] 19:41:55: HTTP response 200 from url http://pbskids.org/_/xml/bubbles.xml
2153] 19:41:55: DEBUG: Pushed thread 0x83a15d0 to _loadThreads, number of XML 
load threads now: 1
2153] 19:41:55: DEBUG: Registered XML loads interval -2
2153] 19:41:55: DEBUG: bool gnash::XML::load(const gnash::URL&) enter
2153] 19:41:55: SECURITY: Load from host pbskids.org granted (default).
2153] 19:41:55: Loading XML file from url: 'http://pbskids.org/_/xml/stunts.xml'
2153] 19:41:55: DEBUG: void gnash::XML::queueLoad(std::auto_ptr<tu_file>) enter
2153] 19:41:55: set_member 'loaded' ([bool:false]) became boolean 0
2153] 19:41:56: HTTP response 200 from url http://pbskids.org/_/xml/stunts.xml
2153] 19:41:56: DEBUG: Pushed thread 0x83b15d8 to _loadThreads, number of XML 
load threads now: 1
2153] 19:41:56: DEBUG: Registered XML loads interval -3
2153] 19:41:56: SECURITY: Load from host pbskids.org granted (default).
2153] 19:41:57: Movie http://pbskids.org/_/swfs/navwheel.swf (SWF7) added to 
library
2153] 19:41:57: HTTP response 200 from url 
http://pbskids.org/_/swfs/navwheel.swf
2153] 19:41:58: DEBUG: bool gnash::XML::load(const gnash::URL&) enter
2153] 19:41:58: SECURITY: Load from host pbskids.org granted (default).
2153] 19:41:58: Loading XML file from url: 
'http://pbskids.org/xml/wheelcolors.xml'
2153] 19:41:58: DEBUG: void gnash::XML::queueLoad(std::auto_ptr<tu_file>) enter
2153] 19:41:58: set_member 'loaded' ([bool:false]) became boolean 0
2153] 19:41:58: HTTP response 200 from url 
http://pbskids.org/xml/wheelcolors.xml
2153] 19:41:58: DEBUG: Pushed thread 0x84ed958 to _loadThreads, number of XML 
load threads now: 1
2153] 19:41:58: DEBUG: Registered XML loads interval -5
2153] 19:41:58: DEBUG: checkLoads_wrapper called
2153] 19:41:58: DEBUG: XML 0x84df3b8 checkLoads call 1, _loadThreads: 1
2153] 19:41:58: DEBUG: XML loads thread 0x84ed958 got 248/248 bytes
2153] 19:41:58: DEBUG: gnash::as_value gnash::xml_ondata(const gnash::fn_call&) 
enter
2153] 19:41:58: set_member 'loaded' ([bool:true]) became boolean 1
2153] 19:41:58: DEBUG: Completed load, _loadThreads have now 0 elements
2153] 19:41:58: DEBUG: Clearing XML load checker interval timer
2153] 19:41:58: DEBUG: checkLoads_wrapper called
2153] 19:41:58: DEBUG: XML 0x83a2458 checkLoads call 1, _loadThreads: 2
2153] 19:41:58: DEBUG: XML loads thread 0x83b15d8 got 78/78 bytes
2153] 19:41:58: DEBUG: gnash::as_value gnash::xml_ondata(const gnash::fn_call&) 
enter
2153] 19:41:58: set_member 'loaded' ([bool:true]) became boolean 1
2153] 19:41:58: SECURITY: Load from host pbskids.org granted (default).
2153] 19:41:59: HTTP response 200 from url http://pbskids.org/_/swfs/stunts/
2153] 19:41:59: ERROR: unknown file type (unknown)
2153] 19:41:59: ERROR: Couldn't load library movie 
'http://pbskids.org/_/swfs/stunts/'
2153] 19:41:59: ERROR: can't create movie_definition for 
http://pbskids.org/_/swfs/stunts/
2153] 19:41:59: DEBUG: Completed load, _loadThreads have now 0 elements
2153] 19:41:59: DEBUG: Clearing XML load checker interval timer
2153] 19:41:59: DEBUG: checkLoads_wrapper called
2153] 19:41:59: DEBUG: XML 0x83924c0 checkLoads call 1, _loadThreads: 3
2153] 19:41:59: DEBUG: XML loads thread 0x83a15d0 got 296/296 bytes
2153] 19:41:59: DEBUG: gnash::as_value gnash::xml_ondata(const gnash::fn_call&) 
enter
2153] 19:41:59: set_member 'loaded' ([bool:true]) became boolean 1
2153] 19:41:59: SECURITY: Load from host pbskids.org granted (default).
2153] 19:42:00: HTTP response 200 from url 
http://pbskids.org/_/bubbles/jakers_lb_01group.swf
2153] 19:42:00: Movie http://pbskids.org/_/bubbles/jakers_lb_01group.swf (SWF7) 
added to library
2153] 19:42:00: SECURITY: Load from host pbskids.org granted (default).
2153] 19:42:02: HTTP response 200 from url 
http://pbskids.org/_/bubbles/rogers_mb_01withking.swf
2153] 19:42:02: Movie http://pbskids.org/_/bubbles/rogers_mb_01withking.swf 
(SWF7) added to library
2153] 19:42:02: SECURITY: Load from host pbskids.org granted (default).
2153] 19:42:06: HTTP response 200 from url 
http://pbskids.org/_/bubbles/arthur_lb_03withpal.swf
2153] 19:42:06: Movie http://pbskids.org/_/bubbles/arthur_lb_03withpal.swf 
(SWF7) added to library
2153] 19:42:06: DEBUG: Completed load, _loadThreads have now 0 elements
2153] 19:42:06: DEBUG: Clearing XML load checker interval timer
2153] 19:42:06: DEBUG: checkLoads_wrapper called
2153] 19:42:06: DEBUG: XML 0x8379428 checkLoads call 1, _loadThreads: 4
2153] 19:42:06: DEBUG: XML loads thread 0x83911b0 got 96/96 bytes
2153] 19:42:06: DEBUG: gnash::as_value gnash::xml_ondata(const gnash::fn_call&) 
enter
2153] 19:42:06: set_member 'loaded' ([bool:true]) became boolean 1
2153] 19:42:06: SECURITY: Load from host pbskids.org granted (default).
2153] 19:42:07: HTTP response 200 from url 
http://pbskids.org/_/swfs/themes/arctic.swf
2153] 19:42:07: Movie http://pbskids.org/_/swfs/themes/arctic.swf (SWF7) added 
to library
2153] 19:42:08: DEBUG: Completed load, _loadThreads have now 0 elements
2153] 19:42:08: DEBUG: Clearing XML load checker interval timer
2153] 19:42:08: DEBUG: Global instance list grew to 330 entries
2153] 19:42:52: UNIMPLEMENTED: updateAfterEvent()
2153] 19:42:52: get url: target=_level0.audioHolder, 
url=http://pbskids.org/_/swfs/audio/go.swf (swfs/audio/go.swf), method=40 
(sendVars:0, loadTarget:1, loadVariable:0)
2153] 19:42:52: SECURITY: Load from host pbskids.org granted (default).
2153] 19:42:52: getURL2 target load
2153] 19:42:52: SECURITY: Load from host pbskids.org granted (default).
2153] 19:42:54: HTTP response 200 from url 
http://pbskids.org/_/swfs/audio/go.swf
2153] 19:42:54: Movie http://pbskids.org/_/swfs/audio/go.swf (SWF7) added to 
library
2153] 19:42:54: TRACE: swfs/audio/go.swf
2153] 19:42:54: frame load advancement (from -1 to 1)
2153] 19:42:54: ERROR: At end of stream, still no 'audio_program' symbol found 
in m_exports (5 entries in it, follow)
2153] 19:42:56: DEBUG: Global instance list grew to 331 entries
2153] 19:43:57: get url: target=_level0.audioHolder, 
url=http://pbskids.org/_/swfs/audio/allshows.swf (swfs/audio/allshows.swf), 
method=40 (sendVars:0, loadTarget:1, loadVariable:0)
2153] 19:43:57: SECURITY: Load from host pbskids.org granted (default).
2153] 19:43:57: getURL2 target load
2153] 19:43:57: SECURITY: Load from host pbskids.org granted (default).
2153] 19:43:58: HTTP response 200 from url 
http://pbskids.org/_/swfs/audio/allshows.swf
2153] 19:43:58: Movie http://pbskids.org/_/swfs/audio/allshows.swf (SWF7) added 
to library
2153] 19:43:58: TRACE: swfs/audio/allshows.swf
2153] 19:43:58: frame load advancement (from -1 to 1)
2153] 19:43:58: ERROR: At end of stream, still no 'audio_clip' symbol found in 
m_exports (5 entries in it, follow)
2153] 19:44:00: get url: target=_level0.audioHolder, 
url=http://pbskids.org/_/swfs/audio/agg_games.swf (swfs/audio/agg_games.swf), 
method=40 (sendVars:0, loadTarget:1, loadVariable:0)
2153] 19:44:00: SECURITY: Load from host pbskids.org granted (default).
2153] 19:44:00: getURL2 target load
2153] 19:44:00: SECURITY: Load from host pbskids.org granted (default).
2153] 19:44:01: HTTP response 200 from url 
http://pbskids.org/_/swfs/audio/agg_games.swf
2153] 19:44:01: Movie http://pbskids.org/_/swfs/audio/agg_games.swf (SWF7) 
added to library
2153] 19:44:01: TRACE: swfs/audio/agg_games.swf
2153] 19:44:01: frame load advancement (from -1 to 1)
2153] 19:44:01: ERROR: At end of stream, still no 'audio_program' symbol found 
in m_exports (5 entries in it, follow)
2153] 19:44:03: get url: target=_level0.audioHolder, 
url=http://pbskids.org/_/swfs/audio/agg_music.swf (swfs/audio/agg_music.swf), 
method=40 (sendVars:0, loadTarget:1, loadVariable:0)
2153] 19:44:03: SECURITY: Load from host pbskids.org granted (default).
2153] 19:44:03: getURL2 target load
2153] 19:44:03: SECURITY: Load from host pbskids.org granted (default).
2153] 19:44:04: HTTP response 200 from url 
http://pbskids.org/_/swfs/audio/agg_music.swf
2153] 19:44:04: Movie http://pbskids.org/_/swfs/audio/agg_music.swf (SWF7) 
added to library
2153] 19:44:04: TRACE: swfs/audio/agg_music.swf
2153] 19:44:04: frame load advancement (from -1 to 1)
2153] 19:44:04: ERROR: At end of stream, still no 'audio_program' symbol found 
in m_exports (5 entries in it, follow)
2153] 19:44:11: get url: target=, 
url=http://pbskids.org/fromkids/music/index.html?campaign=fkag_mu 
(/fromkids/music/index.html?campaign=fkag_mu), method=0 (sendVars:0, 
loadTarget:0, loadVariable:0)
2153] 19:44:11: SECURITY: Load from host pbskids.org granted (default).
2153] 19:44:11: Launching URL... firefox -remote 
'openurl(http://pbskids.org/fromkids/music/index.html?campaign=fkag_mu)'
sh: firefox: command not found