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

[Daniel Narvaez]

I guess the webkit1 stuff is to blame here? It uses a different way to
serve files, so it seems consistent with what you are seeing.

If that's the issue, Gonzalo might be able to help.

On Sunday, 26 January 2014, Lionel Laské <lionel at olpc-france.org> wrote:

>
> 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]$
>


-- 
Daniel Narvaez
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sugarlabs.org/archive/sugar-devel/attachments/20140126/224b2bc3/attachment-0001.html>