I have a world with a lot of models, which still appear in-world
within a user-acceptable window of time.
There's also a scripted bot.
Now I added 3 html posters (for navigation), each containing a few
links to placemarks and to a web site : the result is that they take a
long time to appear in-world (about 20 seconds for each of them) and
during that time the avatar cannot move.
Anybody saw this behavior and found a way to make it a better user
experience ?
I have a world with 20+ posters. It does take a little while to load, but certainly not 20 seconds for each poster. I wonder if there's something else going on...
> I have a world with a lot of models, which still appear in-world
> within a user-acceptable window of time.
> There's also a scripted bot.
> Now I added 3 html posters (for navigation), each containing a few
> links to placemarks and to a web site : the result is that they take a
> long time to appear in-world (about 20 seconds for each of them) and
> during that time the avatar cannot move.
> Anybody saw this behavior and found a way to make it a better user
> experience ?
I looked to the system, and the SOFFICE headless process was taking a full CPU for it, in loop (see attached).
After stopping that SO server, things are a little bit better.
> I have a world with 20+ posters. It does take a little while to load, > but certainly not 20 seconds for each poster. I wonder if there's > something else going on...
> Nicole.
> On 5/3/12 4:11 PM, micheldenis wrote:
>> Hello,
>> I have a world with a lot of models, which still appear in-world
>> within a user-acceptable window of time.
>> There's also a scripted bot.
>> Now I added 3 html posters (for navigation), each containing a few
>> links to placemarks and to a web site : the result is that they take a
>> long time to appear in-world (about 20 seconds for each of them) and
>> during that time the avatar cannot move.
>> Anybody saw this behavior and found a way to make it a better user
>> experience ?
the SO process (on the server) shouldn't really make any difference to
the amount of time it takes for each client to render the HTML
posters.
However, what's in your HTML? E.g. are there references to images via
URLs? Or is it just straight HTML? When I've seen this behaviour
before, it's been when I've been waiting for an image URL to resolve.
regards
Bernard
On May 4, 8:44 am, Michel DENIS <michel.m.de...@gmail.com> wrote:
> I looked to the system, and the SOFFICE headless process was taking a
> full CPU for it, in loop (see attached).
> After stopping that SO server, things are a little bit better.
> -michel
> Nicole Yankelovich a ļæ½crit :
> > Michel,
> > I have a world with 20+ posters. It does take a little while to load,
> > but certainly not 20 seconds for each poster. I wonder if there's
> > something else going on...
> >> I have a world with a lot of models, which still appear in-world
> >> within a user-acceptable window of time.
> >> There's also a scripted bot.
> >> Now I added 3 html posters (for navigation), each containing a few
> >> links to placemarks and to a web site : the result is that they take a
> >> long time to appear in-world (about 20 seconds for each of them) and
> >> during that time the avatar cannot move.
> >> Anybody saw this behavior and found a way to make it a better user
> >> experience ?
<p><center><font size="7">vous souhaitent la bienvenue ! </font></center>
<br><br><br>
<p><font size="6"><b><i>Cliquez sur le lien en bleu de votre destination
:</i></b></font>
<br><br><br>
> the SO process (on the server) shouldn't really make any difference to
> the amount of time it takes for each client to render the HTML
> posters.
> However, what's in your HTML? E.g. are there references to images via
> URLs? Or is it just straight HTML? When I've seen this behaviour
> before, it's been when I've been waiting for an image URL to resolve.
> regards
> Bernard
> On May 4, 8:44 am, Michel DENIS <michel.m.de...@gmail.com> wrote:
> > Nicole,
> > Thanks.
> > I looked to the system, and the SOFFICE headless process was taking a
> > full CPU for it, in loop (see attached).
> > After stopping that SO server, things are a little bit better.
this HTML doesn't appear to be well-formed (there are some opening
<p>'s without their closing counterparts), and I'm not sure what the
table is for, as there appears to be only one row? If it's just to
inset the text, then you might be better introducing a div element,
such as
(I use something like this for most of my posters)
is there anything in the client java console to indicate that the HTML
is taking a time to render or resolve the image URL? For example, are
you getting a timeout? Also, is there any difference between clients
in time taken (based on the network latency) to retrieve the image?
regards
Bernard
On May 4, 2:45 pm, michel denis <michel.m.de...@gmail.com> wrote:
> <p><center><font size="7">vous souhaitent la bienvenue ! </font></center>
> <br><br><br>
> <p><font size="6"><b><i>Cliquez sur le lien en bleu de votre destination
> :</i></b></font>
> <br><br><br>
> > the SO process (on the server) shouldn't really make any difference to
> > the amount of time it takes for each client to render the HTML
> > posters.
> > However, what's in your HTML? E.g. are there references to images via
> > URLs? Or is it just straight HTML? When I've seen this behaviour
> > before, it's been when I've been waiting for an image URL to resolve.
> > regards
> > Bernard
> > On May 4, 8:44 am, Michel DENIS <michel.m.de...@gmail.com> wrote:
> > > Nicole,
> > > Thanks.
> > > I looked to the system, and the SOFFICE headless process was taking a
> > > full CPU for it, in loop (see attached).
> > > After stopping that SO server, things are a little bit better.
When I do a test in standalone mode, ie no network, it also takes time to render every poster, so I don't think it is the network latency at all.
Please see below the console log (which also shows that scriptingComponent is running and a bot).
Thanks for your help,
-michel
---------------
8 mai 2012 09:38:06 org.jdesktop.wonderland.client.jme.Webstart webstartSetup
ATTENTION: Running from Java Web Start. Performing setup.
8 mai 2012 09:38:30 org.jdesktop.wonderland.common.utils.ScannedClassLoader createDB
ATTENTION: Scanned classes in 10416 ms.
8 mai 2012 09:38:42 org.jdesktop.wonderland.modules.avatarbase.client.imi.ImiAvatarConfigManage r addServerAndSync
ATTENTION: Adding server http://i3spaces-demo:8080/ ScriptingComponentClientState : In getInfo - info = Default
ScriptingComponentClientState : In getEventNames
ScriptingComponentClientState : In getScriptType
ScriptingComponentClientState : In getEventResource
ScriptingComponentClientState : In getCellOwner - michel
ScriptingComponentClientState : In getUseGlobalScripts - true
USING CLASSLOADER org.jdesktop.wonderland.common.utils.ScannedClassLoader@17c4d28
TIMELINE ENGINE INIT true
READING jar:wlj://animationbase@i3spaces-demo:8080/client/trident.jar!/META-INF/tri dent-plugin.properties
your console points to three potential problems, but they don't seem
definitely related to the posters. So, I'm not wholly convinced that
the posters are the root cause. So, could you try the following:
1) Remove all the posters and check that the delay no longer occurs.
2) Assuming (1), could monitor the java console when the posters are
loading and let me know where the console stalls? (It should stall at
the same point that you are unable to move your avatar)
2) A problem encountered by mtgame, which _may_ be due to the frame
for a poster
ATTENTION: Error updating object Local-to-cell node for frame
component
FrameRect for FrameSide LEFT
3) A problem encountered by mtgame updating elements of npcCell:
java.lang.ClassCastException:
org.jdesktop.wonderland.modules.npc.client.cell.NpcCell cannot be
cast
to org.jdesktop.wonderland.client.cell.view.AvatarCell
Of these (1) and (3) may be causing the stall, but I'm not sure.
regards
Bernard
On May 8, 8:51 am, Michel DENIS <michel.m.de...@gmail.com> wrote:
I removed the 4 html posters and instead of 7 minutes to be ready, there is (only) 2 minutes to be ready. Five minutes saved.
This means that each poster takes more than 1 minute each to load ... see log.
The main stalls (look at time stamps) are each time with the console showing the following message (and it occurs multiple times in a run): "Trying to draw to texture whose ID hasn't been allocated". This is about the times when posters try to set up themselves, I see on the client.
These messages, and the 5 minutes load extras, don't exist in the same world without the posters (see second log below).
Thanks for your support .. and hopefully a solution .. maybe different contents in posters' html (though contents are similar to the ones from the html posters' documentation).
Thanks,
-michel
---------------------------------
LOG WITH HTML POSTERS AS PER MY POST -- 7 minutes
---------------------------------
10 mai 2012 14:43:44 org.jdesktop.wonderland.client.jme.Webstart webstartSetup
ATTENTION: Running from Java Web Start. Performing setup.
10 mai 2012 14:43:58 org.jdesktop.wonderland.common.utils.ScannedClassLoader createDB
ATTENTION: Scanned classes in 8109 ms.
10 mai 2012 14:44:08 org.jdesktop.wonderland.modules.avatarbase.client.imi.ImiAvatarConfigManage r addServerAndSync
ATTENTION: Adding server http://i3spaces-demo:8080/ USING CLASSLOADER org.jdesktop.wonderland.common.utils.ScannedClassLoader@17d1256
TIMELINE ENGINE INIT true
READING jar:wlj://animationbase@i3spaces-demo:8080/client/trident.jar!/META-INF/tri dent-plugin.properties
TRIDENT #UIToolkitHandler org.pushingpixels.trident.swt.SWTToolkitHandler
TRIDENT #PropertyInterpolatorSource
org.pushingpixels.trident.swt.SWTPropertyInterpolators
TRIDENT PropertyInterpolatorSource
org.pushingpixels.trident.interpolator.CorePropertyInterpolators
Resolving groups... Notifying listeners for group resolution
groups received and added!
ScriptingComponentClientState : In getInfo - info = Default
ScriptingComponentClientState : In getEventNames
ScriptingComponentClientState : In getScriptType
ScriptingComponentClientState : In getEventResource
ScriptingComponentClientState : In getCellOwner - michel
ScriptingComponentClientState : In getUseGlobalScripts - true
10 mai 2012 14:44:12 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
ATTENTION: doSetInvisible false
10 mai 2012 14:44:12 org.jdesktop.wonderland.modules.npc.client.cell.NpcAvatarConfigComponent setClientState
ATTENTION: SETTING CLIENT STATE wlcontent://users/michel/avatars/multimesh-evolver/Eliane2_2.xml
ScriptingComponentClientState : In getInfo - info = Default
ScriptingComponentClientState : In getEventNames
ScriptingComponentClientState : In getScriptType
ScriptingComponentClientState : In getEventResource
ScriptingComponentClientState : In getCellOwner - michel
ScriptingComponentClientState : In getUseGlobalScripts - true
10 mai 2012 14:44:14 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
10 mai 2012 14:44:14 com.jmex.model.collada.ExtraPluginManager processExtra
ATTENTION: Collada loader could not process extra of type: FCOLLADA
10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.userlist.client.WonderlandUserList initialize
ATTENTION: inside initialize!
10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.audiomanager.client.AudioManagerClient connectSoftphone
ATTENTION: Sending message to server to get voice bridge... 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.defaultenvironment.client.DefaultEnvironmen tRenderer setStatus
ATTENTION: CELL LIGHT MAP IS EMPTY!!
10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.audiomanager.client.AudioManagerClient startSoftphone
ATTENTION: Got voice bridge null::192.168.1.30:6666:5060:i3spaces-demo:6666:5060
10 mai 2012 14:44:16 org.jdesktop.wonderland.common.cell.ProximityListenerRecord viewCellMoved
ATTENTION: View cell 40 lastContainerIndex: -1 currentContainerIndex: 0 on org.jdesktop.wonderland.common.cell.ProximityListenerRecord@1743518
10 mai 2012 14:44:16 org.jdesktop.wonderland.client.softphone.SoftphoneControlImpl startSoftphone
ATTENTION: Launching communicator: C:\Program Files (x86)\Java\jre6\bin\java -Dsun.java2d.noddraw=true -jar C:\Users\michel\.wonderland\0.5-dev\softphone\softphone.jar -mc -u michel -r i3spaces-demo;sip-stun:5060 -stun i3spaces-demo:5060 -t 10 -l 192.168.1.30
10 mai 2012 14:44:19 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
10 mai 2012 14:44:26 org.jdesktop.wonderland.client.assetmgr.content.WlContentAssetRepository openAssetStream
ATTENTION: Unable to open URL for asset http://i3spaces-demo:8080/webdav/content/users/assets/models/collada/... response 404
10 mai 2012 14:45:26 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
ATTENTION: Trying to draw to texture whose ID hasn't been allocated
This is the default script that executes if there is no startup script found otherwise
10 mai 2012 14:47:10 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
ATTENTION: Trying to draw to texture whose ID hasn't been allocated
10 mai 2012 14:47:20 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
ATTENTION: Trying to draw to texture whose ID hasn't been allocated
10 mai 2012 14:48:55 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
ATTENTION: Trying to draw to texture whose ID hasn't been allocated
10 mai 2012 14:50:17 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
ATTENTION: Trying to draw to texture whose ID hasn't been allocated
10 mai 2012 14:50:28 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
10 mai 2012 14:50:29 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
ATTENTION: Trying to draw to texture whose ID hasn't been allocated
startup.js hotesse starting
10 mai 2012 14:50:40 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
ATTENTION: doSetInvisible false
10 mai 2012 14:50:40 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
ATTENTION: Updating effects
10 mai 2012 14:50:44 org.jdesktop.wonderland.common.cell.ProximityListenerRecord viewCellMoved
ATTENTION: View cell 2 lastContainerIndex: -1 currentContainerIndex: 0 on org.jdesktop.wonderland.common.cell.ProximityListenerRecord@1a3f1db
10 mai 2012 14:50:44 org.jdesktop.mtgame.Renderer processUpdates
---------------------------------
LOG WITHOUT THESE 4 HTML POSTERS -- 2 minutes
---------------------------------
10 mai 2012 10:01:09 org.jdesktop.wonderland.common.utils.ScannedClassLoader createDB
ATTENTION: Scanned classes in 8445 ms.
10 mai 2012 10:01:20 org.jdesktop.wonderland.modules.avatarbase.client.imi.ImiAvatarConfigManage r addServerAndSync
ATTENTION: Adding server http://i3spaces-demo:8080/ USING CLASSLOADER org.jdesktop.wonderland.common.utils.ScannedClassLoader@6bcf5d
TIMELINE ENGINE INIT true
READING jar:wlj://animationbase@i3spaces-demo:8080/client/trident.jar!/META-INF/tri dent-plugin.properties
TRIDENT #UIToolkitHandler org.pushingpixels.trident.swt.SWTToolkitHandler
TRIDENT #PropertyInterpolatorSource
org.pushingpixels.trident.swt.SWTPropertyInterpolators
TRIDENT PropertyInterpolatorSource
org.pushingpixels.trident.interpolator.CorePropertyInterpolators
ScriptingComponentClientState : In getInfo - info = Default
ScriptingComponentClientState : In getEventNames
ScriptingComponentClientState : In getScriptType
ScriptingComponentClientState : In getEventResource
ScriptingComponentClientState : In getCellOwner - michel
ScriptingComponentClientState : In getUseGlobalScripts - true
10 mai 2012 10:01:23 org.jdesktop.wonderland.modules.npc.client.cell.NpcAvatarConfigComponent setClientState
ATTENTION: SETTING CLIENT STATE wlcontent://users/michel/avatars/multimesh-evolver/Eliane2_2.xml
ScriptingComponentClientState : In getInfo - info = Default
ScriptingComponentClientState : In getEventNames
ScriptingComponentClientState : In getScriptType
ScriptingComponentClientState : In getEventResource
ScriptingComponentClientState : In getCellOwner - michel
ScriptingComponentClientState : In getUseGlobalScripts - true
10 mai 2012 10:01:23
...
yes, this is most odd. The warning is coming from the bowels of the code that renders the HTML into the world [1], and according to the comments in the code, this warning indicates a timing issue that resolves itself. However, given that these warnings disappear when you remove only some of the posters, and that there are less warnings than posters, I suspect that it is just one or two of the posters that are causing the problem.
> I removed the 4 html posters and instead of 7 minutes to be ready, there is (only) 2 minutes to be ready. Five minutes saved.
> This means that each poster takes more than 1 minute each to load ... see log.
> The main stalls (look at time stamps) are each time with the console showing the following message (and it occurs multiple times in a run): "Trying to draw to texture whose ID hasn't been allocated". This is about the times when posters try to set up themselves, I see on the client.
> These messages, and the 5 minutes load extras, don't exist in the same world without the posters (see second log below).
> Thanks for your support .. and hopefully a solution .. maybe different contents in posters' html (though contents are similar to the ones from the html posters' documentation).
> Thanks,
> -michel
> ---------------------------------
> LOG WITH HTML POSTERS AS PER MY POST -- 7 minutes
> ---------------------------------
> 10 mai 2012 14:43:44 org.jdesktop.wonderland.client.jme.Webstart webstartSetup
> ATTENTION: Running from Java Web Start. Performing setup.
> 10 mai 2012 14:43:58 org.jdesktop.wonderland.common.utils.ScannedClassLoader createDB
> ATTENTION: Scanned classes in 8109 ms.
> 10 mai 2012 14:44:08 org.jdesktop.wonderland.modules.avatarbase.client.imi.ImiAvatarConfigManage r addServerAndSync
> ATTENTION: Adding server http://i3spaces-demo:8080/ > USING CLASSLOADER org.jdesktop.wonderland.common.utils.ScannedClassLoader@17d1256
> TIMELINE ENGINE INIT true
> READING jar:wlj://animationbase@i3spaces-demo:8080/client/trident.jar!/META-INF/tri dent-plugin.properties > TRIDENT UIToolkitHandler org.pushingpixels.trident.swing.SwingToolkitHandler
> TRIDENT PropertyInterpolatorSource org.pushingpixels.trident.swing.AWTPropertyInterpolators
> TRIDENT UIToolkitHandler org.jdesktop.wonderland.modules.animationbase.client.toolkit.JMEToolkitHand ler > TRIDENT PropertyInterpolatorSource org.jdesktop.wonderland.modules.animationbase.client.toolkit.JMEPropertyInt erpolators > TRIDENT #UIToolkitHandler org.pushingpixels.trident.swt.SWTToolkitHandler
> TRIDENT #PropertyInterpolatorSource org.pushingpixels.trident.swt.SWTPropertyInterpolators
> TRIDENT PropertyInterpolatorSource org.pushingpixels.trident.interpolator.CorePropertyInterpolators
> Resolving groups... Notifying listeners for group resolution
> groups received and added!
> ScriptingComponentClientState : In getInfo - info = Default
> ScriptingComponentClientState : In getEventNames
> ScriptingComponentClientState : In getScriptType
> ScriptingComponentClientState : In getEventResource
> ScriptingComponentClientState : In getCellOwner - michel
> ScriptingComponentClientState : In getUseGlobalScripts - true
> 10 mai 2012 14:44:12 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
> ATTENTION: doSetInvisible false
> 10 mai 2012 14:44:12 org.jdesktop.wonderland.modules.npc.client.cell.NpcAvatarConfigComponent setClientState
> ATTENTION: SETTING CLIENT STATE wlcontent://users/michel/avatars/multimesh-evolver/Eliane2_2.xml
> ScriptingComponentClientState : In getInfo - info = Default
> ScriptingComponentClientState : In getEventNames
> ScriptingComponentClientState : In getScriptType
> ScriptingComponentClientState : In getEventResource
> ScriptingComponentClientState : In getCellOwner - michel
> ScriptingComponentClientState : In getUseGlobalScripts - true
> 10 mai 2012 14:44:14 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
> ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
> 10 mai 2012 14:44:14 com.jmex.model.collada.ExtraPluginManager processExtra
> ATTENTION: Collada loader could not process extra of type: FCOLLADA
> 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.userlist.client.WonderlandUserList initialize
> ATTENTION: inside initialize!
> 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.audiomanager.client.AudioManagerClient connectSoftphone
> ATTENTION: Sending message to server to get voice bridge... 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.defaultenvironment.client.DefaultEnvironmen tRenderer setStatus
> ATTENTION: CELL LIGHT MAP IS EMPTY!!
> 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.audiomanager.client.AudioManagerClient startSoftphone
> ATTENTION: Got voice bridge null::192.168.1.30:6666:5060:i3spaces-demo:6666:5060
> 10 mai 2012 14:44:16 org.jdesktop.wonderland.common.cell.ProximityListenerRecord viewCellMoved
> ATTENTION: View cell 40 lastContainerIndex: -1 currentContainerIndex: 0 on org.jdesktop.wonderland.common.cell.ProximityListenerRecord@1743518
> 10 mai 2012 14:44:16 org.jdesktop.wonderland.client.softphone.SoftphoneControlImpl startSoftphone
> ATTENTION: Launching communicator: C:\Program Files (x86)\Java\jre6\bin\java -Dsun.java2d.noddraw=true -jar C:\Users\michel\.wonderland\0.5-dev\softphone\softphone.jar -mc -u michel -r i3spaces-demo;sip-stun:5060 -stun i3spaces-demo:5060 -t 10 -l 192.168.1.30
> 10 mai 2012 14:44:19 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
> ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
> 10 mai 2012 14:44:26 org.jdesktop.wonderland.client.assetmgr.content.WlContentAssetRepository openAssetStream
> ATTENTION: Unable to open URL for asset http://i3spaces-demo:8080/webdav/content/users/assets/models/collada/... response 404
> 10 mai 2012 14:45:26 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> This is the default script that executes if there is no startup script found otherwise
> 10 mai 2012 14:47:10 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> 10 mai 2012 14:47:20 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> 10 mai 2012 14:48:55 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> 10 mai 2012 14:50:17 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> 10 mai 2012 14:50:28 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
> ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
> 10 mai 2012 14:50:29 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> startup.js hotesse starting
> 10 mai 2012 14:50:40 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
> ATTENTION: doSetInvisible false
> 10 mai 2012 14:50:40 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
> ATTENTION: Updating effects
> 10 mai 2012 14:50:44 org.jdesktop.wonderland.common.cell.ProximityListenerRecord viewCellMoved
> ATTENTION: View cell 2 lastContainerIndex: -1 currentContainerIndex: 0 on org.jdesktop.wonderland.common.cell.ProximityListenerRecord@1a3f1db
> 10 mai 2012 14:50:44 org.jdesktop.mtgame.Renderer processUpdates
> ---------------------------------
> LOG WITHOUT THESE 4 HTML POSTERS -- 2 minutes
> ---------------------------------
> 10 mai 2012 10:01:09 org.jdesktop.wonderland.common.utils.ScannedClassLoader createDB
> ATTENTION: Scanned classes in 8445 ms.
> 10 mai 2012 10:01:20 org.jdesktop.wonderland.modules.avatarbase.client.imi.ImiAvatarConfigManage r addServerAndSync
> ATTENTION: Adding server http://i3spaces-demo:8080/ > USING CLASSLOADER org.jdesktop.wonderland.common.utils.ScannedClassLoader@6bcf5d
> TIMELINE ENGINE INIT true
> READING jar:wlj://animationbase@i3spaces-demo:8080/client/trident.jar!/META-INF/tri dent-plugin.properties > TRIDENT UIToolkitHandler org.pushingpixels.trident.swing.SwingToolkitHandler
> TRIDENT PropertyInterpolatorSource org.pushingpixels.trident.swing.AWTPropertyInterpolators
> TRIDENT UIToolkitHandler org.jdesktop.wonderland.modules.animationbase.client.toolkit.JMEToolkitHand ler > TRIDENT PropertyInterpolatorSource org.jdesktop.wonderland.modules.animationbase.client.toolkit.JMEPropertyInt erpolators > TRIDENT #UIToolkitHandler org.pushingpixels.trident.swt.SWTToolkitHandler
> TRIDENT #PropertyInterpolatorSource org.pushingpixels.trident.swt.SWTPropertyInterpolators
> TRIDENT PropertyInterpolatorSource org.pushingpixels.trident.interpolator.CorePropertyInterpolators
> ScriptingComponentClientState : In getInfo - info = Default
> ScriptingComponentClientState : In getEventNames
> ScriptingComponentClientState : In
I just took a look at your world, and yes, your posters definitely
take a long time to appear. Removing them reduces the time to jump to
a place mark significantly. The cell performance view indicates that
most of the time is spent in ACTIVE.
I also just ran the netbeans profiler on a OWL client connected to a
local world on my network and most of the time seems to be spend in
setStatus (confirming the cell performance viewer results). Much of
that seems to be in retrieving the image to display for my system, so
perhaps yours is due to a server that is some distance away?
Perhaps you could raise it as an issue in the tracker? I wonder if
there's something we could do to cache the images for the posters....
regards
Bernard
P.S> You'll need to restore your snapshot!
On May 11, 1:32 pm, "Horan, Bernard" <bern...@essex.ac.uk> wrote:
> yes, this is most odd. The warning is coming from the bowels of the code that renders the HTML into the world [1], and according to the comments in the code, this warning indicates a timing issue that resolves itself. However, given that these warnings disappear when you remove only some of the posters, and that there are less warnings than posters, I suspect that it is just one or two of the posters that are causing the problem.
> > I removed the 4 html posters and instead of 7 minutes to be ready, there is (only) 2 minutes to be ready. Five minutes saved.
> > This means that each poster takes more than 1 minute each to load ... see log.
> > The main stalls (look at time stamps) are each time with the console showing the following message (and it occurs multiple times in a run): "Trying to draw to texture whose ID hasn't been allocated". This is about the times when posters try to set up themselves, I see on the client.
> > These messages, and the 5 minutes load extras, don't exist in the same world without the posters (see second log below).
> > Thanks for your support .. and hopefully a solution .. maybe different contents in posters' html (though contents are similar to the ones from the html posters' documentation).
> > Thanks,
> > -michel
> > ---------------------------------
> > LOG WITH HTML POSTERS AS PER MY POST -- 7 minutes
> > ---------------------------------
> > 10 mai 2012 14:43:44 org.jdesktop.wonderland.client.jme.Webstart webstartSetup
> > ATTENTION: Running from Java Web Start. Performing setup.
> > 10 mai 2012 14:43:58 org.jdesktop.wonderland.common.utils.ScannedClassLoader createDB
> > ATTENTION: Scanned classes in 8109 ms.
> > 10 mai 2012 14:44:08 org.jdesktop.wonderland.modules.avatarbase.client.imi.ImiAvatarConfigManage r addServerAndSync
> > ATTENTION: Adding serverhttp://i3spaces-demo:8080/ > > USING CLASSLOADER org.jdesktop.wonderland.common.utils.ScannedClassLoader@17d1256
> > TIMELINE ENGINE INIT true
> > READING jar:wlj://animationbase@i3spaces-demo:8080/client/trident.jar!/META-INF/tri dent-plugin.properties
> > TRIDENT UIToolkitHandler org.pushingpixels.trident.swing.SwingToolkitHandler
> > TRIDENT PropertyInterpolatorSource org.pushingpixels.trident.swing.AWTPropertyInterpolators
> > TRIDENT UIToolkitHandler org.jdesktop.wonderland.modules.animationbase.client.toolkit.JMEToolkitHand ler
> > TRIDENT PropertyInterpolatorSource org.jdesktop.wonderland.modules.animationbase.client.toolkit.JMEPropertyInt erpolators
> > TRIDENT #UIToolkitHandler org.pushingpixels.trident.swt.SWTToolkitHandler
> > TRIDENT #PropertyInterpolatorSource org.pushingpixels.trident.swt.SWTPropertyInterpolators
> > TRIDENT PropertyInterpolatorSource org.pushingpixels.trident.interpolator.CorePropertyInterpolators
> > Resolving groups... Notifying listeners for group resolution
> > groups received and added!
> > ScriptingComponentClientState : In getInfo - info = Default
> > ScriptingComponentClientState : In getEventNames
> > ScriptingComponentClientState : In getScriptType
> > ScriptingComponentClientState : In getEventResource
> > ScriptingComponentClientState : In getCellOwner - michel
> > ScriptingComponentClientState : In getUseGlobalScripts - true
> > 10 mai 2012 14:44:12 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
> > ATTENTION: doSetInvisible false
> > 10 mai 2012 14:44:12 org.jdesktop.wonderland.modules.npc.client.cell.NpcAvatarConfigComponent setClientState
> > ATTENTION: SETTING CLIENT STATE wlcontent://users/michel/avatars/multimesh-evolver/Eliane2_2.xml
> > ScriptingComponentClientState : In getInfo - info = Default
> > ScriptingComponentClientState : In getEventNames
> > ScriptingComponentClientState : In getScriptType
> > ScriptingComponentClientState : In getEventResource
> > ScriptingComponentClientState : In getCellOwner - michel
> > ScriptingComponentClientState : In getUseGlobalScripts - true
> > 10 mai 2012 14:44:14 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
> > ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
> > 10 mai 2012 14:44:14 com.jmex.model.collada.ExtraPluginManager processExtra
> > ATTENTION: Collada loader could not process extra of type: FCOLLADA
> > 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.userlist.client.WonderlandUserList initialize
> > ATTENTION: inside initialize!
> > 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.audiomanager.client.AudioManagerClient connectSoftphone
> > ATTENTION: Sending message to server to get voice bridge... 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.defaultenvironment.client.DefaultEnvironmen tRenderer setStatus
> > ATTENTION: CELL LIGHT MAP IS EMPTY!!
> > 10 mai 2012 14:44:16 org.jdesktop.wonderland.modules.audiomanager.client.AudioManagerClient startSoftphone
> > ATTENTION: Got voice bridge null::192.168.1.30:6666:5060:i3spaces-demo:6666:5060
> > 10 mai 2012 14:44:16 org.jdesktop.wonderland.common.cell.ProximityListenerRecord viewCellMoved
> > ATTENTION: View cell 40 lastContainerIndex: -1 currentContainerIndex: 0 on org.jdesktop.wonderland.common.cell.ProximityListenerRecord@1743518
> > 10 mai 2012 14:44:16 org.jdesktop.wonderland.client.softphone.SoftphoneControlImpl startSoftphone
> > ATTENTION: Launching communicator: C:\Program Files (x86)\Java\jre6\bin\java -Dsun.java2d.noddraw=true -jar C:\Users\michel\.wonderland\0.5-dev\softphone\softphone.jar -mc -u michel -r i3spaces-demo;sip-stun:5060 -stun i3spaces-demo:5060 -t 10 -l 192.168.1.30
> > 10 mai 2012 14:44:19 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
> > ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
> > 10 mai 2012 14:44:26 org.jdesktop.wonderland.client.assetmgr.content.WlContentAssetRepository openAssetStream
> > ATTENTION: Unable to open URL for assethttp://i3spaces-demo:8080/webdav/content/users/assets/models/collada/... 404
> > 10 mai 2012 14:45:26 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> > ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> > This is the default script that executes if there is no startup script found otherwise
> > 10 mai 2012 14:47:10 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> > ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> > 10 mai 2012 14:47:20 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> > ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> > 10 mai 2012 14:48:55 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> > ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> > 10 mai 2012 14:50:17 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> > ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> > 10 mai 2012 14:50:28 org.jdesktop.wonderland.modules.avatarbase.client.jme.cellrenderer.AvatarIm iJME supportsHighQualityAvatars
> > ATTENTION: Checking avatar detail level. OpenGL20: true ShaderCheck: true UniformsCheck: true
> > 10 mai 2012 14:50:29 org.jdesktop.wonderland.modules.appbase.client.DrawingSurfaceImageGraphics$ UpdateProcessor commit
> > ATTENTION: Trying to draw to texture whose ID hasn't been allocated
> > startup.js hotesse starting
> > 10 mai 2012 14:50:40 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
> > ATTENTION: doSetInvisible false
> > 10 mai 2012 14:50:40 org.jdesktop.wonderland.modules.admintools.client.AdminToolsComponent doSetInvisible
> > ATTENTION: Updating effects
> > 10 mai 2012 14:50:44 org.jdesktop.wonderland.common.cell.ProximityListenerRecord viewCellMoved
> > ATTENTION: View cell 2 lastContainerIndex: -1 currentContainerIndex: 0 on org.jdesktop.wonderland.common.cell.ProximityListenerRecord@1a3f1db
> > 10 mai 2012 14:50:44 org.jdesktop.mtgame.Renderer processUpdates
> > ---------------------------------
> > LOG WITHOUT THESE 4 HTML POSTERS -- 2 minutes
> > ---------------------------------
> > 10 mai 2012 10:01:09 org.jdesktop.wonderland.common.utils.ScannedClassLoader createDB
> > ATTENTION: Scanned classes in 8445 ms.
> > 10 mai 2012 10:01:20 org.jdesktop.wonderland.modules.avatarbase.client.imi.ImiAvatarConfigManage r addServerAndSync
> > ATTENTION: Adding serverhttp://i3spaces-demo:8080/ > > USING CLASSLOADER org.jdesktop.wonderland.common.utils.ScannedClassLoader@6bcf5d
