[Sugar-devel] Sugar web activity wait 1 minute at startup

[Lionel Laské]

Hi all,

I'm currently porting my FoodChain activity [1] as a full Sugar Web
activity. It's mainly a packaging work because FoodChain is already written
in HTML5/JavaScript but with a Python package around it.

The new Sugar Web activity works well but when I'm testing it on Sugar
0.100 on the XO, the loading process is very long (more than 1 minute) -
tested on the XO 1.75 and XO 4 on the old and the recent package.

I've isolated the lost time using the sugar-launch command (see log below).
It takes more than 1 minute to load "favicon.ico" (see log between 15:04:23
and 15:05:34). I don't see any reason for that because it's a very tiny
file.
More, when I delete this file, the lost time is still here and when I see
the trace for another Sugar Web activity, I don't have this loading time,
including when "favicon.ico" is not here.
Launching the activity on sugar-build don't show this issue neither.
I suspect that the problem is not on "favicon.ico" but on something like a
"timeout" somewhere (on a socket ?).
Source code of the activity is here [2], the packaged activity with this
issue could be downloaded here [3].

Any suggestion is welcome to help me to debug this issue.

Best regards from France.

            Lionel.


[1] http://activities.sugarlabs.org/en/sugar/addon/4612
[2] https://github.com/llaske/FoodChain
[3] http://laske.fr/tmp/foodchain-4.xo




[olpc at schoolserver FoodChain.activity]$ sugar-launch
org.olpcfrance.FoodChain
org.olpcfrance.FoodChain
1390662262.276506 ERROR root: Using port 50662
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:22] "GET /index.html
HTTP/1.1" 200 -

(sugar-activity:4018): Gdk-CRITICAL **: gdk_device_get_source: assertion
`GDK_IS_DEVICE (device)' failed
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/sugar-web/graphics/css/sugar.css HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /enyo/enyo.css
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /enyo/enyo.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /lib/require.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /depends.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /styles.css
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/canvas/package.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/canvas/Canvas.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/canvas/CanvasControl.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/canvas/Shape.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/canvas/Circle.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/canvas/Rectangle.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /lib/canvas/Text.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/canvas/Image.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /l10n.js HTTP/1.1"
200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /util.js HTTP/1.1"
200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /audio.js HTTP/1.1"
200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /timer.js HTTP/1.1"
200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /card.js HTTP/1.1"
200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /chain.js HTTP/1.1"
200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /shadowbutton.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /sprite.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /learngame.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /buildgame.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /playgame.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /credits.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /app.js HTTP/1.1"
200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/activity/activity-icon.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /images/en.svg
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET /images/fr.svg
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:04:23] "GET
/lib/sugar-web/graphics/icons/actions/activity-stop.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:34] "GET /favicon.ico
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:34] "GET /js/loader.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET /js/activity.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET /lib/webL10n.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/activity/activity.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/graphics/radiobuttonsgroup.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/datastore.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/activity/shortcut.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/bus.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/env.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/graphics/icon.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/graphics/activitypalette.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/graphics/palette.js HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET /lib/domReady.js
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/graphics/icons/emblems/arrow-down.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/activity/activity-icon.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/lib/sugar-web/graphics/icons/emblems/arrow-up.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET /locale.ini
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/images/FoodChain.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET /images/one.svg
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/images/one_shadow.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET /images/two.svg
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/images/two_shadow.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET /images/three.svg
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/images/three_shadow.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:35] "GET
/images/information.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:36] "GET
/images/information_shadow.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:36] "GET
/activity/activity-icon.svg HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:36] "GET /audio/popcorn.ogg
HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:37] "GET
/images/cards/grass.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:37] "GET
/images/sound_icon.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:38] "GET
/images/cards/tick.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:38] "GET
/images/sound_icon.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:39] "GET
/images/cards/fox.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:39] "GET
/images/sound_icon.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:40] "GET
/images/cards/ox.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:40] "GET
/images/sound_icon.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:41] "GET
/images/cards/mole.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:41] "GET
/images/sound_icon.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:43] "GET
/images/cards/swan.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:43] "GET
/images/sound_icon.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:44] "GET
/images/cards/pig.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:44] "GET
/images/sound_icon.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:45] "GET
/images/cards/corn.png HTTP/1.1" 200 -
xo-d2-7d-0b.localdomain - - [25/Jan/2014 15:05:45] "GET
/images/sound_icon.png HTTP/1.1" 200 -
/usr/bin/sugar-activity:164: Warning: gsignal.c:2576: instance `0x3c58a0'
has no handler with id `132'
  main()
/usr/bin/sugar-activity:164: Warning: gsignal.c:2576: instance `0x2089e8'
has no handler with id `133'
  main()
[olpc at schoolserver FoodChain.activity]$
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sugarlabs.org/archive/sugar-devel/attachments/20140126/eeaa5a7d/attachment.html>