Hi everybody,
Last week we had a big problem with the webclient version of our solution but only on few servers. No exception or system error but most of the link/buttons used gave this message in the top right corner instead of “Loading…”: “Error calling Server”.
After a long investigation we found that it is a problem with the latest version of Servoy 4.1.4. The issue is only apparent if v4.1.4 was a clean install, that is it was not upgraded from the previous version. The other servers that did not show the problem were all upgraded from v4.1.3.
code wise a 4.1.4 clean or a 4.1.4 upgraded from 4.1.3 should be equal.
What is different in the install dirs?
If it just does say “error calling server” and you dont see any other error in the server logs
then the calls are not coming to the server at all. So there is something wrong in between.
You could look at the access log of your server to see if the calls are really not coming through.
Here is the only error we get. It is input in the .service_log.txt each time we click and get the message “Error Calling Server”
INFO | jvm 2 | 2009/09/26 14:21:29 | 26-Sep-2009 14:21:29 org.apache.catalina.core.StandardWrapperValve invoke
INFO | jvm 2 | 2009/09/26 14:21:29 | SEVERE: Servlet.service() for servlet servoy_webclient threw exception
INFO | jvm 2 | 2009/09/26 14:21:29 | javax.servlet.ServletException: Servlet execution threw an exception
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:313)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:230)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:104)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:563)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:261)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:581)
INFO | jvm 2 | 2009/09/26 14:21:29 | at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
INFO | jvm 2 | 2009/09/26 14:21:29 | at java.lang.Thread.run(Unknown Source)
It is 100% sure that making a clean install of the 4.1.4 generate this issue because we did a clean install of the 4.1.3 on the servers having this issue and using the same settings, then did the upgrade to 4.1.4 and it worked fine after.
that doesnt say much, isnt there anything below or above it?
Or in the servoy log itself?
Here is what we get in the .service_log.txt:
STATUS | wrapper | 2009/09/26 14:20:18 | Launching a JVM…
INFO | jvm 2 | 2009/09/26 14:20:19 | Wrapper (Version 3.1.2) http://wrapper.tanukisoftware.org
INFO | jvm 2 | 2009/09/26 14:20:19 |
INFO | jvm 2 | 2009/09/26 14:20:23 | 26-Sep-2009 14:20:23 org.apache.coyote.http11.Http11Protocol init
INFO | jvm 2 | 2009/09/26 14:20:23 | INFO: Initializing Coyote HTTP/1.1 on http-8080
INFO | jvm 2 | 2009/09/26 14:20:23 | 26-Sep-2009 14:20:23 org.apache.catalina.startup.Catalina load
INFO | jvm 2 | 2009/09/26 14:20:23 | INFO: Initialization processed in 245 ms
INFO | jvm 2 | 2009/09/26 14:20:23 | 26-Sep-2009 14:20:23 org.apache.catalina.core.StandardService start
INFO | jvm 2 | 2009/09/26 14:20:23 | INFO: Starting service Catalina
INFO | jvm 2 | 2009/09/26 14:20:23 | 26-Sep-2009 14:20:23 org.apache.catalina.core.StandardEngine start
INFO | jvm 2 | 2009/09/26 14:20:23 | INFO: Starting Servlet Engine: Apache Tomcat/6.0-snapshot
INFO | jvm 2 | 2009/09/26 14:20:23 | 26-Sep-2009 14:20:23 org.apache.catalina.startup.DigesterFactory register
INFO | jvm 2 | 2009/09/26 14:20:23 | WARNING: Could not get url for /javax/servlet/resources/web-app_2_5.xsd
INFO | jvm 2 | 2009/09/26 14:20:24 | 26-Sep-2009 14:20:24 org.apache.coyote.http11.Http11Protocol start
INFO | jvm 2 | 2009/09/26 14:20:24 | INFO: Starting Coyote HTTP/1.1 on http-8080
INFO | jvm 2 | 2009/09/26 14:20:24 | 26-Sep-2009 14:20:24 org.apache.catalina.startup.Catalina start
INFO | jvm 2 | 2009/09/26 14:20:24 | INFO: Server startup in 874 ms
INFO | jvm 2 | 2009/09/26 14:20:53 | Registering Free IT2BE Component runtime licenses for Elite Control Systems Limited
INFO | jvm 2 | 2009/09/26 14:20:54 | Registering Commercial IT2BE Component runtime licenses for Elite Control Systems Limited
INFO | jvm 2 | 2009/09/26 14:21:15 | 26-Sep-2009 14:21:15 org.apache.catalina.core.StandardWrapperValve invoke
INFO | jvm 2 | 2009/09/26 14:21:15 | SEVERE: Servlet.service() for servlet servoy_webclient threw exception
INFO | jvm 2 | 2009/09/26 14:21:15 | javax.servlet.ServletException: Servlet execution threw an exception
INFO | jvm 2 | 2009/09/26 14:21:15 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:313)
…
//same errors as shown in previous post
In servoy_log.txt we get an error that already had in the past and which didn’t influence the program:
2009-09-26 14:21:06,764 ERROR [http-8080-2] com.servoy.j2db.util.Debug - Throwable
java.lang.NumberFormatException: For input string: “8pt”
at java.lang.NumberFormatException.forInputString(Unknown Source)
at java.lang.Integer.parseInt(Unknown Source)
at java.lang.Integer.parseInt(Unknown Source)
at com.servoy.j2db.server.headlessclient.dataui.TemplateGenerator.createCellBasedView(TemplateGenerator.java:151)
at com.servoy.j2db.server.headlessclient.dataui.TemplateGenerator.getFormHTMLAndCSS(TemplateGenerator.java:210)
at com.servoy.j2db.server.headlessclient.Zvc.locate(Zvc.java:62)
at org.apache.wicket.util.resource.locator.ResourceStreamLocator.locate(ResourceStreamLocator.java:122)
at com.servoy.j2db.server.headlessclient.ServoyResourceStreamLocator.locate(ServoyResourceStreamLocator.java:10)
at org.apache.wicket.markup.DefaultMarkupResourceStreamProvider.getMarkupResourceStream(DefaultMarkupResourceStreamProvider.java:82)
at org.apache.wicket.markup.MarkupCache.getMarkup(MarkupCache.java:299)
at org.apache.wicket.markup.MarkupCache.getMarkupStream(MarkupCache.java:215)
at org.apache.wicket.MarkupContainer.getAssociatedMarkupStream(MarkupContainer.java:342)
at com.servoy.j2db.server.headlessclient.WebForm.getAssociatedMarkupStream(WebForm.java:94)
at org.apache.wicket.markup.html.ContainerWithAssociatedMarkupHelper.renderHeadFromAssociatedMarkupFile(ContainerWithAssociatedMarkupHelper.java:72)
at org.apache.wicket.markup.html.WebMarkupContainerWithAssociatedMarkup.renderHeadFromAssociatedMarkupFile(WebMarkupContainerWithAssociatedMarkup.java:72)
at org.apache.wicket.markup.html.panel.Panel.renderHead(Panel.java:134)
at org.apache.wicket.markup.html.internal.HtmlHeaderContainer$1.component(HtmlHeaderContainer.java:220)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:830)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:845)
at org.apache.wicket.MarkupContainer.visitChildren(MarkupContainer.java:870)
at org.apache.wicket.markup.html.internal.HtmlHeaderContainer.renderHeaderSections(HtmlHeaderContainer.java:211)
at org.apache.wicket.markup.html.internal.HtmlHeaderContainer.onComponentTagBody(HtmlHeaderContainer.java:136)
at org.apache.wicket.Component.renderComponent(Component.java:2524)
at org.apache.wicket.MarkupContainer.onRender(MarkupContainer.java:1412)
at org.apache.wicket.Component.render(Component.java:2361)
at org.apache.wicket.MarkupContainer.autoAdd(MarkupContainer.java:222)
at org.apache.wicket.markup.resolver.HtmlHeaderResolver.resolve(HtmlHeaderResolver.java:80)
at org.apache.wicket.MarkupContainer.renderNext(MarkupContainer.java:1325)
at org.apache.wicket.MarkupContainer.renderAll(MarkupContainer.java:1428)
at org.apache.wicket.Page.onRender(Page.java:1502)
at org.apache.wicket.Component.render(Component.java:2361)
at org.apache.wicket.Page.renderPage(Page.java:913)
at org.apache.wicket.request.target.component.PageRequestTarget.respond(PageRequestTarget.java:63)
at org.apache.wicket.request.AbstractRequestCycleProcessor.respond(AbstractRequestCycleProcessor.java:104)
at org.apache.wicket.RequestCycle.processEventsAndRespond(RequestCycle.java:1245)
at org.apache.wicket.RequestCycle.step(RequestCycle.java:1316)
at org.apache.wicket.RequestCycle.steps(RequestCycle.java:1418)
at org.apache.wicket.RequestCycle.request(RequestCycle.java:532)
at org.apache.wicket.protocol.http.WicketFilter.doGet(WicketFilter.java:356)
at org.apache.wicket.protocol.http.WicketServlet.doGet(WicketServlet.java:124)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:689)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:230)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:104)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:563)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:261)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:581)
at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
at java.lang.Thread.run(Unknown Source)
By the way, we get more than 10 times this error in few sec.
Here is what we have in Tomcat log file for this period:
192.168.1.15 - - [26/Sep/2009:14:20:08 +0100] “POST /servoy-admin/shutdown HTTP/1.1” 200 6967
192.168.1.15 - - [26/Sep/2009:14:20:25 +0100] “GET /servoy-admin HTTP/1.1” 302 -
192.168.1.15 - - [26/Sep/2009:14:20:25 +0100] “GET /servoy-admin/ HTTP/1.1” 200 24975
192.168.1.15 - - [26/Sep/2009:14:20:28 +0100] “GET /servoy-admin/solutions HTTP/1.1” 200 8437
192.168.1.15 - - [26/Sep/2009:14:20:38 +0100] “POST /servoy-admin/solutions/flush/AssetGuardian HTTP/1.1” 200 6955
192.168.1.15 - - [26/Sep/2009:14:20:40 +0100] “POST /servoy-admin/solutions/flush/AssetGuardian HTTP/1.1” 200 7000
192.168.1.15 - - [26/Sep/2009:14:20:41 +0100] “POST /servoy-admin/solutions/flush/AssetGuardian HTTP/1.1” 200 6905
192.168.1.15 - - [26/Sep/2009:14:20:42 +0100] “POST /servoy-admin/solutions/flush/AssetGuardian HTTP/1.1” 200 6678
192.168.1.15 - - [26/Sep/2009:14:20:44 +0100] “GET /servoy-admin HTTP/1.1” 302 -
192.168.1.15 - - [26/Sep/2009:14:20:44 +0100] “GET /servoy-admin/ HTTP/1.1” 200 24976
192.168.1.15 - - [26/Sep/2009:14:20:51 +0100] “GET /servoy-webclient/solutions/solution/AssetGuardian HTTP/1.1” 200 3374
192.168.1.15 - - [26/Sep/2009:14:20:51 +0100] “POST /servoy-webclient/?x=kdFSTl9H5Vys43DIKsTD7w HTTP/1.1” 302 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/solutions/solution/AssetGuardian HTTP/1.1” 302 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/?x=9qYyaMpWHlSSSwA1cXNw HTTP/1.1" 200 8525
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/resources/wicketevent/wicket-event.js?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/templates/default/servoy_web_client_default.css HTTP/1.1” 200 3164
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/resources/wicketajax/wicket-ajax.js?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/resources/servoyjs/servoy.js?w:lm=1251112478 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/resources/draggable/js/effects.js?w:lm=1251112478 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/resources/draggable/js/prototype.js?w:lm=1251112478 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/resources/draggable/js/dragdrop.js?w:lm=1251112478 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/resources/modalwindow/res/modal.js?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/resources/modalwindow/res/modal.css?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:20:54 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_login_t1253971254222t.css HTTP/1.1” 200 3581
192.168.1.15 - - [26/Sep/2009:14:20:59 +0100] “POST /servoy-webclient/?x=nCKwJJiSnV7t4-URRgiennPnthPV1TofW49xum4T-hPUU46-BS2y9RLw3RRnpBvc&random=0.5379663995632027 HTTP/1.1” 200 2245
192.168.1.15 - - [26/Sep/2009:14:20:59 +0100] “GET /servoy-webclient/?x=CksJKlhmyYIxxZpOw-WxsEjAQGt9ohTZRBCEGym8LlUyNiCWwzNfc052AXhXrB3&ignoremp=true&random=0.3543755409616347 HTTP/1.1" 200 618
192.168.1.15 - - [26/Sep/2009:14:21:06 +0100] “POST /servoy-webclient/?x=YLrZMa6oPvRvpvPzJ6A4tNYA0-cVQ3976sN3PgQtR0-5XNXkZd7EyZIAQgsFgmnK&random=0.9278536722406152 HTTP/1.1” 200 1
192.168.1.15 - - [26/Sep/2009:14:21:06 +0100] "GET /servoy-webclient/?x=CksJKlhmyYKk-OrqS4JaObzetgHvi5gU7VIr4jNv1ntpnqQIa534EDzxd0ZrYx-&ignoremp=true&random=0.11462675998996674 HTTP/1.1” 200 69
192.168.1.15 - - [26/Sep/2009:14:21:06 +0100] “GET /servoy-webclient/resources/wicketevent/wicket-event.js?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:06 +0100] “GET /servoy-webclient/resources/wicketajax/wicket-ajax.js?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:06 +0100] “GET /servoy-webclient/resources/servoyjs/servoy.js?w:lm=1251112478 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:06 +0100] “GET /servoy-webclient/resources/draggable/js/prototype.js?w:lm=1251112478 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:06 +0100] “GET /servoy-webclient/resources/draggable/js/effects.js?w:lm=1251112478 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:07 +0100] “GET /servoy-webclient/resources/draggable/js/dragdrop.js?w:lm=1251112478 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:07 +0100] “GET /servoy-webclient/resources/modalwindow/res/modal.js?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:07 +0100] “GET /servoy-webclient/resources/modalwindow/res/modal.css?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:07 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_main_ag_t1253971266739t.css HTTP/1.1” 200 12892
192.168.1.15 - - [26/Sep/2009:14:21:07 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_main_rhs_ag_t1253971266754t.css HTTP/1.1” 200 585
192.168.1.15 - - [26/Sep/2009:14:21:07 +0100] “GET /servoy-webclient/?x=vL0UcAZzfHWJYZb-Lwvg HTTP/1.1” 200 90768
192.168.1.15 - - [26/Sep/2009:14:21:07 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_diary_t1253971266757t.css HTTP/1.1” 200 5524
192.168.1.15 - - [26/Sep/2009:14:21:07 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_diary_history_list_t1253971266799t.css HTTP/1.1” 200 1756
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_diary_list_t1253971266763t.css HTTP/1.1” 200 4171
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_navigation_tree_ag_t1253971266814t.css HTTP/1.1” 200 1626
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_diary_details_t1253971266788t.css HTTP/1.1” 200 10436
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/resources/org.apache.wicket.extensions.ajax.markup.html.autocomplete.AutoCompleteBehavior/wicket-autocomplete.js?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/resources/org.apache.wicket.markup.html.tree.AbstractTree/res/tree.js?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/resources/com.servoy.extensions.beans.dbtreeview.WicketDBTreeView/res/base-tree.css?w:lm=1248691113 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/?x=kAyoUgBRlUT4SQLE7gcfGizntEZmj8xRZU0sH71PdNujQipGSZS17KwCbs0ttT11mgoHe3ZHdTvbsHcQBSFfbBZUI3U4FNXCKxtx8xApfZIbsaSEZ6PnSm-nK3t9xbcdae88C25SQW1zSJEWjZlHGkiQDNRnyOs5NHDR-cX-m6QLXlsBOM6XE5RHKXLDSx8jlJozB8AOZVokqfKRVRMPOSXrIUHebUzyY2g8LI&x=1253971266838 HTTP/1.1" 200 17491
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] "GET /servoy-webclient/?x=kAyoUgBRlUT4SQLE7gcfGizntEZmj8xRZU0sH71PdNujQipGSZS17KwCbs0ttT11mgoHe3ZHdTvbsHcQBSFfbBZUI3U4FNXCKxtx8xApfZIbsaSEZ6PnSm-nK3t9xbcdae88C24WRMbftE6vgt2MUasnNFyRVYYH90YTGgyZUa8LTdUlClkstlhQXhcXux9TsBJMsDwhYjStuoaZlO4CC9QsKDP3O-qWdLV4HvcOjFemtyWeilaNuqdCAJxgsAoR2DNADjxICpSJGFVxeUD0a8mzDaAfvqwd1cTiVSW4sCCNhU1Lh6Cpb6doHxbOTkfuMcxAHzBAx1WTShtjPUI2KUrPGOmThlKoNmBqJIG7aZtZFNpuNDdwUnpUNB9uN40bNouTMzO3AO7FaIOGdkwUEx7uLUZWnldPVIGmeUUmzUlDXpZ-lx-mSPx1xui1yo0PJgP5Cy3sspjmCvJG6XKXj8ViZiQyfc3HZsHdY-bf2S0sCIfHjPPtI4sOE&x=1253971266846 HTTP/1.1” 200 10981
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/resources/application/images/arrow_off.png?w:lm=1251112481 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:08 +0100] “GET /servoy-webclient/templates/lafs/kunststoff/images/button/secondary-enabled.gif HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:09 +0100] “GET /servoy-webclient/?x=kAyoUgBRlUT4SQLE7gcfGizntEZmj8xRZU0sH71PdNujQipGSZS17KwCbs0ttT11mgoHe3ZHdTvbsHcQBSFfbBZUI3U4FNXCKxtx8xApfZIbsaSEZ6PnSm-nK3t9xbcdae88C25SQW1zSJEWjZlHGkiQDNRnyOs5NHDR-cX-m6QLXlsBOM6XE5RHKXLDSx8jlJozB8AOZVokqfKRVRMPOSXrIUHebUzyY2g8LI&r=0.49257295623555875 HTTP/1.1" 200 17491
192.168.1.15 - - [26/Sep/2009:14:21:09 +0100] “GET /servoy-webclient/resources/org.apache.wicket.markup.html.tree.LabelIconPanel/res/folder-closed.gif?w:lm=1247826301 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:09 +0100] “GET /servoy-webclient/resources/servoy/javax.swing.ImageIcon@6e63d9 HTTP/1.1” 200 349
192.168.1.15 - - [26/Sep/2009:14:21:09 +0100] “GET /servoy-webclient/resources/servoy/javax.swing.ImageIcon@a75818 HTTP/1.1” 200 349
192.168.1.15 - - [26/Sep/2009:14:21:09 +0100] “GET /servoy-webclient/resources/servoy/javax.swing.ImageIcon@72add8 HTTP/1.1” 200 349
192.168.1.15 - - [26/Sep/2009:14:21:09 +0100] “GET /servoy-webclient/resources/servoy/javax.swing.ImageIcon@895684 HTTP/1.1” 200 349
192.168.1.15 - - [26/Sep/2009:14:21:09 +0100] “GET /servoy-webclient/resources/com.servoy.extensions.beans.dbtreeview.WicketDBTreeView/res/base-tree-images.png HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:09 +0100] "GET /servoy-webclient/?x=kAyoUgBRlUT4SQLE7gcfGizntEZmj8xRZU0sH71PdNujQipGSZS17KwCbs0ttT11mgoHe3ZHdTvbsHcQBSFfbBZUI3U4FNXCKxtx8xApfZIbsaSEZ6PnSm-nK3t9xbcdae88C24WRMbftE6vgt2MUasnNFyRVYYH90YTGgyZUa8LTdUlClkstlhQXhcXux9TsBJMsDwhYjStuoaZlO4CC9QsKDP3O-qWdLV4HvcOjFemtyWeilaNuqdCAJxgsAoR2DNADjxICpSJGFVxeUD0a8mzDaAfvqwd1cTiVSW4sCCNhU1Lh6Cpb6doHxbOTkfuMcxAHzBAx1WTShtjPUI2KUrPGOmThlKoNmBqJIG7aZtZFNpuNDdwUnpUNB9uN40bNouTMzO3AO7FaIOGdkwUEx7uLUZWnldPVIGmeUUmzUlDXpZ-lx-mSPx1xui1yo0PJgP5Cy3sspjmCvJG6XKXj8ViZiQyfc3HZsHdY-bf2S0sCIfHjPPtI4sOE&r=0.4956737335181891 HTTP/1.1” 200 10981
192.168.1.15 - - [26/Sep/2009:14:21:15 +0100] "GET /servoy-webclient/?x=Jmv0YMwrxHYzQMwnGPZOqQZyCibKa5DlUNi0vNBfFQLA-cupjwTGvU8CFv8L-ds&ignoremp=true&random=0.7396535431760152 HTTP/1.1" 500 1236
192.168.1.15 - - [26/Sep/2009:14:21:23 +0100] “GET /servoy-webclient/?x=na9ltWxXeDDzbu6g22BkosTtfZ2aPqHGHXftOCmk3uyYfDn64CUKKjYNX50dTDql&random=0.8375491709897234 HTTP/1.1” 500 1236
192.168.1.15 - - [26/Sep/2009:14:21:29 +0100] "GET /servoy-webclient/?x=Y7MqWDVK2W3P0N85eNVtPQdcVC69pFXpqjMrDXdWiHCMW6a6kFSA6iOnwwLq&random=0.7458737098539089 HTTP/1.1" 500 1236
192.168.1.15 - - [26/Sep/2009:14:21:31 +0100] "GET /servoy-webclient/?x=Y7MqWDVK2W3P0N85eNVtPQdcVC69pFXpqjMrDXd*WiHCMW6a6kFSA6iOnwwLq&random=0.7775181268535987 HTTP/1.1" 500 1236
192.168.1.15 - - [26/Sep/2009:14:21:35 +0100] "GET /servoy-webclient/?x=wwm4NYbwtwkiZjD3HtJaJyV4bJS43US1kEgBm35BQStfGLbFjv6TvqJw3aoM9&random=0.14835628599758321 HTTP/1.1" 200 94980
192.168.1.15 - - [26/Sep/2009:14:21:35 +0100] “GET /servoy-webclient/resources/com.servoy.extensions.plugins.popupmenu.wicket.MenuExecutorBehavior/res/fonts-min.css?w:lm=1251112260 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:35 +0100] “GET /servoy-webclient/resources/com.servoy.extensions.plugins.popupmenu.wicket.MenuExecutorBehavior/res/servoy-menu.css?w:lm=1251112260 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:35 +0100] “GET /servoy-webclient/resources/com.servoy.extensions.plugins.popupmenu.wicket.MenuExecutorBehavior/res/menu.css?w:lm=1251112260 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:35 +0100] “GET /servoy-webclient/resources/com.servoy.extensions.plugins.popupmenu.wicket.MenuExecutorBehavior/res/yahoo-dom-event.js?w:lm=1251112260 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:35 +0100] “GET /servoy-webclient/resources/com.servoy.extensions.plugins.popupmenu.wicket.MenuExecutorBehavior/res/container_core-min.js?w:lm=1251112260 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:35 +0100] “GET /servoy-webclient/resources/com.servoy.extensions.plugins.popupmenu.wicket.MenuExecutorBehavior/res/menu-min.js?w:lm=1251112260 HTTP/1.1” 304 -
192.168.1.15 - - [26/Sep/2009:14:21:36 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_documents_t1253971293046t.css HTTP/1.1” 200 5528
192.168.1.15 - - [26/Sep/2009:14:21:36 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_documents_list_t1253971293051t.css HTTP/1.1” 200 4175
192.168.1.15 - - [26/Sep/2009:14:21:36 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_documents_details_t1253971293069t.css HTTP/1.1” 200 10440
192.168.1.15 - - [26/Sep/2009:14:21:36 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_documents_history_list_t1253971293077t.css HTTP/1.1” 200 1760
192.168.1.15 - - [26/Sep/2009:14:21:36 +0100] "GET /servoy-webclient/?x=kAyoUgBRlUT4SQLE7gcfGizntEZmj8xRZU0sH71PdNujQipGSZS17KwCbs0ttT11mgoHe3ZHdTvbsHcQBSFfbBZUI3U4FNXCKxtx8xApfZIbsaSEZ6PnSm-nK3t9xbcdae88C24WRMbftE6vgt2MUasnNFyRVYYH90YTGgyZUa8LTdUlClkstlhQXhcXux9TsBJMsDwhYjStuoaZlO4CC9QsKDP3O-qWdLV4HvcOjFemtyWeilaNuqdCAJxgsAoR2DNADjxICpSJGFVxeUD0a8mzDaAfvqwd1cTiVSW4sCCNhU1Lh6Cpb6doHxbOTkfuMcxAHzBAx1WTShtjPUI2KUrPGOmThlKoNmBqJIG7aZtZFNpuNDdwUnpUNB9uN40bNouTMzO3AO7FaIOGdkwUEx7uLUZWnldPVIGmeUUmzUlDXpZ-lx-mSPx1xui1yo0PJgP5Cy3sspjmCvJG6XKXj8ViZiQyfc3HZsHdY-bf2S0sCIfHjPPtI4sOE&r=0.44592878400751645 HTTP/1.1” 200 10981
192.168.1.15 - - [26/Sep/2009:14:21:36 +0100] “GET /servoy-webclient/?x=kAyoUgBRlUT4SQLE7gcfGizntEZmj8xRZU0sH71PdNujQipGSZS17KwCbs0ttT11mgoHe3ZHdTvbsHcQBSFfbBZUI3U4FNXCKxtx8xApfZIbsaSEZ6PnSm-nK3t9xbcdae88C24WRMbftE6vgt2MUasnNFyRVYYH90YTGgyZUa8LTdUlClkstlhQXhcXux9TsBJMsDwhYjStuoaZlO4CC9QsKDP3O-qWdLV4HvcOjFemtyWeilaNuqdCAJxgsAoR2DNADjxICpSJGFVxeUD0a8mzDaAfvqwd1cTiVSW4sCCNhU1Lh6Cpb6doHxbOTkfuMcxAHzBAx1WTShtjPUI2KUrPGOmThlKoNmBqJIG7aZtZFNpuNDdwUnpUNB9uN40bNouTMzO3AO7FaIOGdkwUEx7uLUZWnldPVIGmeUUmzUlDXpZ-lx-mSPx1xui1yo0PJgP5Cy3sspjmCvJG6XKXj8ViZiQyfc3HZsHdY-bf2S0sCIfHjPPtI4sOE&x=1253971293098 HTTP/1.1" 200 10981
192.168.1.15 - - [26/Sep/2009:14:21:39 +0100] “GET /servoy-webclient/?x=tvc38JmKIt6hbZkJlz09ox8vI0dwh9JpcufzGfFQcnlh4w8RGs6-mK3I1qgAvf19&m=16059567&random=0.03540898666667819 HTTP/1.1” 500 1236
192.168.1.15 - - [26/Sep/2009:14:21:48 +0100] "GET /servoy-webclient/?x=XYqudawbzZpRhLjqsweVhFV0xejDakhlrFJuevuM0ecl1-X7CXUSnZQoTwzTCQU&random=0.760853348734922 HTTP/1.1” 200 95412
192.168.1.15 - - [26/Sep/2009:14:21:48 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_faults_t1253971306914t.css HTTP/1.1” 200 6683
192.168.1.15 - - [26/Sep/2009:14:21:48 +0100] “GET /servoy-webclient/formcss/AssetGuardian/frm_faults_list_t1253971306919t.css HTTP/1.1” 200 5168
Apparently at the moment of the 1st exception (26/Sep/2009:14:21:15) there is an HTTP error 500 thrown for some reason ![:roll:]( "Rolling Eyes")
at the same time you see that 500 you also see:
INFO | jvm 2 | 2009/09/26 14:21:29 | 26-Sep-2009 14:21:29 org.apache.catalina.core.StandardWrapperValve invoke
in the log?
Problem is that that doesnt say much there is an exception wrapped into that that we have to know then.
You can try to play with the logging settings in the servoy.properties file so that you get way more logging, but errors should be reported any way.
What happens if you dont run it as a service but just as a app server in a command prompt
does the servoy_log tell you something then?
I’ve set the wrapper log to TRACE. There seems to be more exceptions displayed. I have attached the log file generated.
Once I’ve started the service, the log stopped writing at 2009/09/29 15:50:26
Then I logged in at 2009/09/29 15:52:34
Then I clicked to trigger an action and got the error “Error calling server” at 2009/09/29 15:53:24
I have also run the server in command line but the error displayed are the same.
service_log.txt (63.4 KB)
If you run on the command line and you change the logging part in the servoy.properties to this:
log4j.appender.configservlet=com.servoy.j2db.util.SlidingWindowAppender
log4j.appender.configservlet.layout=com.servoy.j2db.util.Log4JHTMLTableLayout
log4j.appender.configservlet.layout.dateTimeFormat=yyyy-MM-dd HH:mm
log4j.appender.configservlet.windowSize=1000
log4j.appender.file=org.apache.log4j.RollingFileAppender
log4j.appender.file.File=%%servoy_app_server_dir%%\servoy_log.txt
log4j.appender.file.MaxBackupIndex=1
log4j.appender.file.MaxFileSize=10MB
log4j.appender.file.layout=org.apache.log4j.PatternLayout
log4j.appender.file.layout.ConversionPattern=%d %p [%t] %c - %m%n
log4j.appender.stdout=org.apache.log4j.ConsoleAppender
log4j.appender.stdout.layout=org.apache.log4j.PatternLayout
log4j.appender.stdout.layout.ConversionPattern=%d %p [%t] %c - %m%n
log4j.debug=true
log4j.logger.com.servoy.j2db.util.Debug=TRACE
log4j.logger.org.apache.wicket=WARN
log4j.rootCategory=WARN, file, configservlet
then you should get way more output.
Here are the logs generated with the new settings.
I have tried to find an error or something abnormal when the server says “Error Calling Server” at 2009/09/30 11:35:37, but I didn’t found anything.
Maybe you will have more success?! ![:roll:]( "Rolling Eyes")
servoy_log.zip (248 KB)
.service_log.txt (26.3 KB)
i cant find anything.
a the time of your failure i see a normal request happening and that ends at:
2009-09-30 11:35:37,443 DEBUG [http-8080-exec-1] com.servoy.j2db.util.Debug - Warning: g_row_selectapp_software is not defined in the variables, a dynamic (media type) variable is created
thats the last thing it does.
after that no request are coming to the server anymore.
Is that g_row_selectapp_software something you touch in that call that fails?
I did fix your error you have about that “pt” if you change your css to “px” then it should work fine for you in the current setup.
Indeed this warning was a mistake from us. It should have been g_row_select_app_software instead of g_row_selectapp_software .
We have fixed it but we still get the same problem of “Error Calling Server”.
Now there is a new error in the log but I’m not sure it’s linked to what we try to fix.
I still don’t see anything abnormal in the rest of the logs ![:(]( "Sad")
[attachment=0]servoy_log.zip[/attachment][attachment=1].service_log.txt[/attachment]
But I don’t understand, if it’s a solution problem, why is it working on 4.1.3 clean install and 4.1.4 updated ? ![:shock:]( "Shocked")
Also thanks for the fix of the “pt”. But now, people probably already told you, with “px” the font sizes are different from the Smart Client to the WebClient
servoy_log.zip (249 KB)
.service_log.txt (50 KB)
i dont understand it then, the dirs should be the same, you could compare the 2 dirs?
i dont see any other error in the logs then what you had before.
Can you reproduce this at another install? If so then you could create a case with your solution.
I’ve done more tests this morning and found that, if I install 4.1.3 then update straight to 4.1.4 without running the server for the 1st time, I’m getting the error. But If I run the server on version 4.1.3 and then do the update to 4.1.4, the solution will work fine.
Also I have tried the sample solution servoy_sample_crm on 4.1.4 (clean install) and it works fine.
My question is, what files are created when you start the server? Apparently 4.1.3 creates files which are needed by our solution/components ?! ![:roll:]( "Rolling Eyes")
I’ll try to compare both folders.
thats very weird,
i wouldnt know what the server really writes. as far as i know nothing (besides if you change a setting on the admin page) or the webstart jar cache in the tomcat work dir.
I have tried to compare the 3 installs folders (Servoy folders) generated after running the server (4.1.3, 4.1.4, 4.1.4 updated)
But it looks like the files I need to make it work are modified once and not new ones or missing ones. Here are my results:
Folder 4.1.3 = 922 files and 89 Folders
Folder 4.1.4 = 916 files and 89 Folders
Folder 4.1.4 updated = 917 files and 89 Folders
Files added by us are the same for each folder.
The extra file in 4.1.4u compared to 4.1.4 is just “application_server\solutions\examples\servoy_sample_bug_db.servoy” which doesn’t fix the problem.
What do you advise me to test? The problem seems to be linked to the DbTreeview and Pop Up Menus (when you click on them nothing happens and you get the error). Also I don’t think, I mentioned it before, it is not working as well on Developer: same error and behaviour.
Case created. ID = 247425
so the number of files are pretty much equal
can you dump 2 times the result of a dir/s of the complete servoy dir?
dir/s >dump.txt
then we can also compare file sizes.
Here are the dumps of the 3 install directories described previously:
dump_4.1.4u.txt (70.4 KB)
dump_4.1.4.txt (70.3 KB)
dump_4.1.3.txt (70.6 KB)