0

Event processing warning in log / catalina.out

asked 2010-03-09 10:20:50 +0800

dastultz gravatar image dastultz
797 9

Hello,

I've just discovered lots of entries like this:

Mar 7, 2010 9:46:02 PM org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
WARNING: The event processing takes more than 15000 seconds: [comp: <Button z_8s_jh>, event: [MouseEvent onClick <Button z_8s_jh>]]

The entries span a time frame of 41 hours (so far). The button ID is the same and the number of seconds keeps going up so I'm assuming the warning pertains to one thing and is being repeated. Anybody know what this is about? Have I got a defunct thread lying around? How do I further investigate this?

Thanks.

/Daryl

delete flag offensive retag edit

12 Replies

Sort by ยป oldest newest

answered 2010-03-09 22:17:48 +0800

PeterKuo gravatar image PeterKuo
481 2

After looking into the code,
org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355

It looks like a bug, an event that never get executed.

You may report to bug, and let us track it.
It's better to have a scenario to duplicate the issue.

link publish delete flag offensive edit

answered 2010-09-08 20:17:34 +0800

umanga gravatar image umanga
180 3

Greetings,

Has this bug been fixed ?

I also got the same repeating error for over 3 days.
I get this error on version Zk 3.6.3 .

thanks in advance.

link publish delete flag offensive edit

answered 2010-09-08 20:31:41 +0800

PeterKuo gravatar image PeterKuo
481 2

@umanga
I don't know if dastultz has reported this bug.
Can you update to latest zk version, and try again? At least zk 3.6.4 is newer than zk3.6.3

If the bug still happen,
please post to bug, and give us reproducible sample.

link publish delete flag offensive edit

answered 2010-09-15 07:58:09 +0800

Nametest gravatar image Nametest
18

Hi, Peter!

We've the same problem! But, I cannot find the solution/bug report for this issue. (Websphere 6.1, ZKoss 3.6.3, Spring, SpringSecurity)

The Sysout log is full with this: org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 600 seconds: [comp: <Window PreRecJUSTWindow>, event: [CreateEvent onCreate <Window PreRecJUSTWindow>]]

and: [9/13/10 12:42:38:262 CEST] 0000004f zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@677e677e

------------------------------------------
In DUMP we've found:

at java/lang/Object.wait(Native Method)
at java/lang/Object.wait(Object.java:231(Compiled Code))
at org/zkoss/zk/ui/impl/UiEngineImpl.doActivate(UiEngineImpl.java:1533(Compiled Code))
at org/zkoss/zk/ui/impl/UiEngineImpl.activate(UiEngineImpl.java:1495)
at org/zkoss/zk/ui/impl/UiEngineImpl.desktopDestroyed(UiEngineImpl.java:180)
at org/zkoss/zk/ui/impl/SimpleDesktopCache.desktopDestroyed(SimpleDesktopCache.java:118)
at org/zkoss/zk/ui/impl/SimpleDesktopCache.stop(SimpleDesktopCache.java:166)
at org/zkoss/zk/ui/impl/SessionDesktopCacheProvider.sessionDestroyed(SessionDesktopCacheProvider.java:59)
at org/zkoss/zk/ui/impl/AbstractWebApp.sessionDestroyed(AbstractWebApp.java:286)
at org/zkoss/zk/ui/http/WebManager.sessionDestroyed(WebManager.java:356)
at org/zkoss/zk/ui/http/HttpSessionListener.sessionDestroyed(HttpSessionListener.java:41)

(At SessioDestroy one thread is blocking the session handling of WebShpere)

------------------------------------
DUMP:

at java/lang/Object.wait(Native Method)
at java/lang/Object.wait(Object.java:199(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.doSuspend0(EventProcessingThreadImpl.java:221)
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.doSuspend(EventProcessingThreadImpl.java:187)
at org/zkoss/zk/ui/impl/UiEngineImpl.wait(UiEngineImpl.java:1296)
at org/zkoss/zk/ui/Executions.wait(Executions.java:667)
at org/zkoss/zul/Window.enterModal(Window.java:618)
at org/zkoss/zul/Window.doModal(Window.java:554)
at com/generali/dur/web/util/DurViewUtil.displayPaymentsData(DurViewUtil.java:2168)
at com/generali/dur/web/view/PreRecDetailsCommonWindow.onClick$btnPaymentsData(PreRecDetailsCommonWindow.java:1118)
at sun/reflect/NativeMethodAccessorImpl.invoke0(Native Method)
at sun/reflect/NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79(Compiled Code))
at sun/reflect/DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43(Compiled Code))
at java/lang/reflect/Method.invoke(Method.java:603(Compiled Code))
at org/zkoss/zk/ui/event/GenericEventListener.onEvent(GenericEventListener.java:83(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessor.process0(EventProcessor.java:197(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessor.process(EventProcessor.java:141(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.process0(EventProcessingThreadImpl.java:519(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.sendEvent(EventProcessingThreadImpl.java:123(Compiled Code))
at org/zkoss/zk/ui/event/Events.sendEvent(Events.java:293(Compiled Code))
at org/zkoss/zk/ui/event/Events.sendEvent(Events.java:303(Compiled Code))
at org/zkoss/zk/ui/AbstractComponent$ForwardListener.onEvent(AbstractComponent.java:2245(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessor.process0(EventProcessor.java:197(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessor.process(EventProcessor.java:141(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.process0(EventProcessingThreadImpl.java:519(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.run(EventProcessingThreadImpl.java:446(Compiled Code))

(A couple of threads are staying in doSuspend0)


at java/lang/Object.wait(Native Method)
at java/lang/Object.wait(Object.java:199(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.doSuspend0(EventProcessingThreadImpl.java:221)
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.doSuspend(EventProcessingThreadImpl.java:187)
at org/zkoss/zk/ui/impl/UiEngineImpl.wait(UiEngineImpl.java:1296)
at org/zkoss/zk/ui/Executions.wait(Executions.java:667)
at org/zkoss/zul/Window.enterModal(Window.java:618)
at org/zkoss/zul/Window.doModal(Window.java:554)
at com/generali/dur/web/view/base/AbstractSearchPageComposer.onView(AbstractSearchPageComposer.java:409)
at com/generali/dur/web/view/RemittanceSearchWindow$1.onEvent(RemittanceSearchWindow.java:379)
at org/zkoss/zk/ui/impl/EventProcessor.process0(EventProcessor.java:197(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessor.process(EventProcessor.java:141(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.process0(EventProcessingThreadImpl.java:519(Compiled Code))
at org/zkoss/zk/ui/impl/EventProcessingThreadImpl.run(EventProcessingThreadImpl.java:446(Compiled Code))


I check the release notes of V 3.6.4: http://www.zkoss.org/release/rn-3.6.4.dsp, but I didn't find the bugfix for this problem.

Could you check if this problem has already been fixed?
If it hasn't, what is the workaround? We could upgrade to 3.6.4, but 5.0 or higher is impossible for this application:(

than you in advance!

link publish delete flag offensive edit

answered 2010-09-17 03:41:12 +0800

tomyeh gravatar image tomyeh
610 1 3
http://blog.zkoss.org ZK Team

updated 2010-09-17 03:42:03 +0800

Not a bug. It indicates that an event queued for processing is waiting too long. The only cause we know is that it is blocked by the previous event. In other words, if you have an event that takes long to execute, then this warning will be shown up when the user clicks, say, other button. There are generally two solutions for it.

1. Disable the warning by specifying a negative number to event-time-warning (refer here.

2. Prevent the user from triggering other actions. It can be done by invoke Clients.showBusy and Events.echo. Refer here.

link publish delete flag offensive edit

answered 2010-09-17 04:44:31 +0800

Nametest gravatar image Nametest
18

Dear Tomyeh!

I don't really understand your replay:(
Our problem isn't unexpected warning popup to user!

So, our application has been deployed to cluster-environment.
And we observed: node1 is unreachable(from browser, with direct link), than reachable, than node2 is unreachable, than reachable.

And we checked the SysOut.log, and we've found the above messages, and we dumped the threads on node, you can see the dumped stacktraces above.

So, our conclusion: ZK cannot destroy the sessions(threads), and these sessions are block the node, and our application is unreachable from clients(IExplorer):(

Any suggestions?

Thank you in advance!

link publish delete flag offensive edit

answered 2010-09-17 06:06:59 +0800

tomyeh gravatar image tomyeh
610 1 3
http://blog.zkoss.org ZK Team

updated 2010-09-17 06:11:09 +0800

When using in a clustering environment, it is better to turn off the event processing thread. I don't know if WebSphere is capable, but no server I know is able to migrate a thread from one node to another. It might explain the case you ran into.

For more information, please take a look at here.

link publish delete flag offensive edit

answered 2010-09-17 06:44:37 +0800

Nametest gravatar image Nametest
18

Ok, we will try it, but looking into logs makes me cry. It is terrible, and I don't understand, what is the reason of these entries appear in our logs.

Look that(Sysout log, Node1)

[9/17/10 9:37:12:307 CEST] 00000045 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@490f490f
[9/17/10 9:38:07:183 CEST] 0000004e zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@58035803
[9/17/10 9:38:37:309 CEST] 0000004e zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@323f323f
[9/17/10 9:38:53:700 CEST] 0000004e zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@66f466f4
[9/17/10 9:40:06:374 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@233d233d
[9/17/10 9:44:49:475 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@714c714c
[9/17/10 9:46:26:274 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@6bfe6bfe
[9/17/10 9:49:54:670 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@78fc78fc
[9/17/10 9:51:10:641 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@513d513d
[9/17/10 9:51:27:516 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@ae60ae6
[9/17/10 9:54:46:443 CEST] 0000004b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@3f003f00
[9/17/10 9:58:26:168 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@763e763e
[9/17/10 9:59:34:607 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@1ac81ac8
[9/17/10 9:59:47:295 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@7a027a02
[9/17/10 10:00:37:952 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@3c0e3c0e
[9/17/10 10:01:28:594 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@66e366e3
[9/17/10 10:02:38:330 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@70327032
[9/17/10 10:03:51:223 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@10811081
[9/17/10 10:07:19:963 CEST] 0000004e zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2b0e2b0e
[9/17/10 10:11:04:390 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@3a683a68
[9/17/10 10:12:25:095 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@54535453
[9/17/10 10:13:37:191 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2f7d2f7d
[9/17/10 10:13:59:207 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2b242b24
[9/17/10 10:14:02:207 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@21c821c8
[9/17/10 10:14:11:504 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@39883988
[9/17/10 10:17:16:900 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@4da74da7
[9/17/10 10:19:28:028 CEST] 00000049 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2f4c2f4c
[9/17/10 10:22:07:298 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@7d207d2
[9/17/10 10:22:21:330 CEST] 0000004e zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@5b535b53
[9/17/10 10:23:09:440 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@5cd35cd3
[9/17/10 10:24:00:426 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@f2c0f2c
[9/17/10 10:30:29:358 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@60c060c
[9/17/10 10:30:54:686 CEST] 00000049 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@3ade3ade
[9/17/10 10:33:36:847 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@4da44da4
[9/17/10 10:34:00:613 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@b1b0b1b
[9/17/10 10:34:56:333 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@dca0dca
[9/17/10 10:34:57:865 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2fc12fc1
[9/17/10 10:36:07:866 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@21d221d2
[9/17/10 10:36:29:992 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@23102310
[9/17/10 10:36:46:180 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@3b353b35
[9/17/10 10:37:47:463 CEST] 000000a6 WSChannelFram A CHFW0019I: The Transport Channel Service has started chain HttpOutboundChain:localhost:80.
[9/17/10 10:39:01:199 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@28372837
[9/17/10 10:39:31:653 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@21d221d2
[9/17/10 10:39:47:200 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@292b292b
[9/17/10 10:42:43:689 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@67a367a3
[9/17/10 10:44:39:473 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@449f449f
[9/17/10 10:44:54:505 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@623d623d
[9/17/10 10:45:38:615 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@334f334f
[9/17/10 10:45:49:225 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@39f739f7
[9/17/10 10:51:58:000 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@23d723d7
[9/17/10 10:53:02:877 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@67926792
[9/17/10 10:53:26:065 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@5c215c21
[9/17/10 10:56:09:991 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@58d158d1
[9/17/10 10:57:26:774 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@f9a0f9a
[9/17/10 10:59:00:417 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2de52de5
[9/17/10 10:59:18:449 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@ccd0ccd
[9/17/10 11:01:20:561 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@423f423f
[9/17/10 11:02:18:875 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@3d713d71
[9/17/10 11:02:34:391 CEST] 00000096 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@1ddf1ddf
[9/17/10 11:05:00:442 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@1a981a98
[9/17/10 11:05:22:242 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@46784678
[9/17/10 11:07:10:447 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@46024602
[9/17/10 11:07:26:981 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@4590459
[9/17/10 11:14:29:027 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@6feb6feb
[9/17/10 11:14:53:638 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@b830b83
[9/17/10 11:15:37:359 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2c9d2c9d
[9/17/10 11:15:56:438 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@a230a23
[9/17/10 11:18:43:976 CEST] 0000004b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@4c044c04
[9/17/10 11:19:57:275 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@ea20ea2
[9/17/10 11:22:42:015 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@c990c99
[9/17/10 11:24:49:393 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2e902e9
[9/17/10 11:25:05:706 CEST] 0000008b zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@523a523a
[9/17/10 11:27:53:382 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@73357335
[9/17/10 11:29:44:744 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@7b047b04
[9/17/10 11:29:58:542 CEST] 0000008d zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@36713671
[9/17/10 11:34:07:048 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@50155015
[9/17/10 11:36:38:771 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@5e415e41
[9/17/10 11:37:01:381 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@76d876d8
[9/17/10 11:37:24:100 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@1bc01bc
[9/17/10 11:38:10:836 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@592e592e
[9/17/10 11:39:40:853 CEST] 00000049 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@7a667a66
[9/17/10 11:41:16:309 CEST] 00000049 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@49414941
[9/17/10 11:42:28:623 CEST] 00000049 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@4f3a4f3a
[9/17/10 11:46:31:036 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@33233323
[9/17/10 11:47:15:990 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@62ca62ca
[9/17/10 11:47:20:068 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@53755375
[9/17/10 11:47:36:069 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@a4f0a4f
[9/17/10 11:49:10:665 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@10bc10bc
[9/17/10 11:49:10:759 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@10bc10bc
[9/17/10 11:50:23:745 CEST] 00000049 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@43384338
[9/17/10 11:54:50:236 CEST] 000000b4 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@9290929
[9/17/10 11:59:34:072 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@429c429c
[9/17/10 11:59:56:041 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@400f400f
[9/17/10 12:00:44:980 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@59255925
[9/17/10 12:02:12:138 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@5c405c4
[9/17/10 12:03:51:156 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@15cc15cc
[9/17/10 12:04:59:064 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2a3f2a3f
[9/17/10 12:07:46:287 CEST] 0000004a zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@6b806b8
[9/17/10 12:09:01:539 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@71b471b4
[9/17/10 12:09:51:540 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@57c557c5
[9/17/10 12:10:06:478 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@43ca43ca
[9/17/10 12:11:43:246 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@37093709
[9/17/10 12:12:26:404 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@64d264d2
[9/17/10 12:12:54:139 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2b022b02
[9/17/10 12:17:20:411 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2e8b2e8b
[9/17/10 12:17:28:302 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@78a778a7
[9/17/10 12:18:54:461 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@34b034b0
[9/17/10 12:22:27:404 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@52f252f2
[9/17/10 12:23:19:311 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@77cb77cb
[9/17/10 12:24:43:845 CEST] 00000047 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@30e030e0
[9/17/10 12:26:23:378 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@12671267
[9/17/10 12:27:29:536 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@8940894
[9/17/10 12:30:16:775 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@32f232f2
[9/17/10 12:32:22:247 CEST] 000000b3 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@6ac06ac
[9/17/10 12:34:17:062 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@40e140e1
[9/17/10 12:34:57:876 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@4e844e84
[9/17/10 12:35:24:439 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@1e381e38
[9/17/10 12:38:39:007 CEST] 000000b4 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@68756875
[9/17/10 12:39:51:477 CEST] 0000004e zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@30613061
[9/17/10 12:41:25:011 CEST] 0000004e zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@4f024f02
[9/17/10 12:46:56:301 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@2d322d32
[9/17/10 12:47:16:129 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@322a322a
[9/17/10 12:48:55:929 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@778c778c
[9/17/10 12:52:48:060 CEST] 00000049 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@dd30dd3
[9/17/10 12:56:47:519 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@54285428
[9/17/10 12:57:29:035 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@62c162c1
[9/17/10 12:57:54:708 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@7deb7deb
[9/17/10 13:01:09:494 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@36a436a4
[9/17/10 13:03:45:654 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@7a957a95
[9/17/10 13:03:56:405 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@c950c95
[9/17/10 13:08:24:740 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@11051105
[9/17/10 13:08:27:677 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@10be10be
[9/17/10 13:09:49:570 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@14061406
[9/17/10 13:11:29:354 CEST] 00000087 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@d120d12
[9/17/10 13:15:18:532 CEST] 000000b1 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@26c526c5
[9/17/10 13:19:18:335 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@1f251f25
[9/17/10 13:20:10:273 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@417a417a
[9/17/10 13:20:25:055 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@522d522d
[9/17/10 13:23:33:076 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@ac20ac2
[9/17/10 13:23:39:935 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@26a626a6
[9/17/10 13:26:16:064 CEST] 000000b2 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@69e169e1
[9/17/10 13:32:22:808 CEST] 00000048 zkoss I org.zkoss.zk.ui.http.SimpleSession recover:357 Recover an invalidated session, org.zkoss.zk.ui.http.SimpleSession@7a1e7a1e


Sysout.log . another node:

[9/17/10 11:53:25:854 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 11:54:14:434 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 11:54:14:996 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 4200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 11:57:59:988 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 6600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 11:58:12:395 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:03:25:873 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:04:14:453 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:04:15:016 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 4800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:08:00:007 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:08:12:429 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:13:25:893 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 8400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:14:14:488 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 8400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:14:15:035 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 5400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:18:00:026 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:18:12:449 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 8400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:23:25:927 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:24:14:507 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:24:15:054 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 6000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:28:00:046 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 8400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:28:12:468 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:33:25:947 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:34:14:526 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:34:15:089 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 6600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:38:00:080 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:38:12:487 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:43:25:966 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:44:14:545 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:44:15:108 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:48:00:100 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:48:12:522 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:53:25:985 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:54:14:565 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:54:15:127 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 7800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:58:00:119 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 12:58:12:541 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:03:26:020 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 11400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:04:14:599 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 11400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:04:15:146 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 8400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:08:00:138 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10800 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:08:12:560 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 11400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:09:01:499 CEST] 00000356 JobStoreTX I org.quartz.impl.jdbcjobstore.JobStoreSupport logWarnIfNonZero ClusterManager: detected 1 failed or restarted instances.
[9/17/10 13:13:26:039 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 12000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:14:14:619 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 12000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:14:15:166 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:18:00:157 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 11400 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:18:12:579 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 12000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:19:41:567 CEST] 00000356 JobStoreTX I org.quartz.impl.jdbcjobstore.JobStoreSupport logWarnIfNonZero ClusterManager: detected 1 failed or restarted instances.
[9/17/10 13:19:41:567 CEST] 00000356 JobStoreTX I org.quartz.impl.jdbcjobstore.JobStoreSupport clusterRecover ClusterManager: Scanning for instance "HU00000HWW13X1284722243018"'s failed in-progress jobs.
[9/17/10 13:23:26:085 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 12600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:24:14:681 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 12600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:24:15:228 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 9600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:28:00:282 CEST] 00000084 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 12000 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:28:12:706 CEST] 0000005e zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 12600 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:33:26:215 CEST] 00000041 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 13200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:34:14:797 CEST] 00000047 zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 13200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]
[9/17/10 13:34:15:360 CEST] 000000fa zkoss W org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355 The event processing takes more than 10200 seconds: [comp: <DurButton btnSearch>, event: [Event onClick <DurButton btnSearch>]]


Durbutton is extended from zul Button, with Clients.showBusy!

link publish delete flag offensive edit

answered 2010-09-20 02:03:38 +0800

PeterKuo gravatar image PeterKuo
481 2

@Nametest
Dig into the source code of ZK, you'll find.
SimpleSession.java

	public void recover(Object nativeSession) {
		if (_invalidated)
			log.info("Recover an invalidated session, "+this);

EventProcessingThreadImpl.java

public boolean processEvent(Desktop desktop, Component comp, Event event) {
.....

						log.warning("The event processing takes more than "+
							((System.currentTimeMillis()-begt)/1000)+" seconds: "+proc);

link publish delete flag offensive edit

answered 2010-09-21 02:36:07 +0800

Nametest gravatar image Nametest
18

Hi! I know, what is the problem, but I don't know the solution:(

The problem is: we're using popups, and popups blocks the threads.
This setting is solved the "blocking problem":
<system-config>
<disable-event-thread>true</disable-event-thread>
</system-config>

But, now, our popups disappear immediately after appearing on the screen, popup doesn't wait for user interaction( eg: clicking on button on popup).

It is terrible for us.

We need a fast solution, because we can't redesign the code, the dedline is coming:)

We use ZK since 2008. Version 3.6.3 since 2009. The problem(threads blocked) since 1 mounth. There are no any changes on our architecture.
WAS 6.1 has not been patched by administrators. The app runs in cluster, on two nodes, under ACE load balancer.
Environment: WAS 6.1, DB2, Spring, Hibernate, ZK 3.6.3

Do you know a fast workaround?

Many, many thanks in advance!

link publish delete flag offensive edit
Your reply
Please start posting your answer anonymously - your answer will be saved within the current session and published after you log in or create a new account. Please try to give a substantial answer, for discussions, please use comments and please do remember to vote (after you log in)!

[hide preview]

Question tools

Follow

RSS

Stats

Asked: 2010-03-09 10:20:50 +0800

Seen: 1,564 times

Last updated: Mar 23 '11

Support Options
  • Email Support
  • Training
  • Consulting
  • Outsourcing
Learn More