-
FEATURED COMPONENTS
First time here? Check out the FAQ!
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
Disappoint, there hasn't solution for this issue.
It has been tormented me some day.
2011/3/23 下午 05:28:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 98398 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 05:38:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 98998 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 05:48:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 99598 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 05:58:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 100198 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 06:08:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 100798 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 06:18:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 101398 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 06:28:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 101998 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 06:38:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 102598 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 06:48:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 103198 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 06:58:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 103798 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 07:08:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 104398 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 07:18:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 104998 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 07:28:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 105598 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 07:38:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 106198 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
2011/3/23 下午 07:48:14 org.zkoss.zk.ui.impl.EventProcessingThreadImpl processEvent:355
警告: The event processing takes more than 106798 seconds: [comp: <Datebox datebox>, event: [InputEvent onChange <Datebox datebox>]]
It is a dilemma. As described in the document (refer to the link I posted above), you have to disable the use of event thread, since threads cannot be migrated from one machine to another. Unfortunately, once disabled, you cannot suspend the execution.
Without re-implementing some logic (nor disabling the event thread), you might consider to disable the migration of a session from one machine to another. I don't know about ACE, but, in general, the session is migrated if 1) the machine is dead, 2) the machine is overloaded. For the first case, we got no solution without disabling the use of event thread. For the second case, there might be a setting in ACE that allows you to disable it.
In short, check ACE manual, and see if there is any way to disable the migration of HTTP sessions.
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!
@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);
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!
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.
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!
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.
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!
@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.
Asked: 2010-03-09 10:20:50 +0800
Seen: 1,564 times
Last updated: Mar 23 '11