Hi guys,
we're looking for some help. We changed the database-connection of the orbeon tool 4.10 to a my-sql-server in our network. Since that action we were only able to save the new forms but failed to publish them. When saving the new forms a new row in the database is created. When we hit the publish-button we get the following error-dialoge: (There was an error during form publishing.) - We've tested it with tomcat7 and tomcat8 but failed with the same error message. - We removed the xml-noted refering to the exist-database We attached the configuration-files and the orbeon.log. We are really looking forward to your assistance. Could you please help us - we're desperate! context.xml<?xml version="1.0" encoding="UTF-8"?> <Context path="/orbeon" docBase="D:\orbeon\web\" reloadable="false" override="true" allowLinking="true" crossContext="true" debug="5"> <WatchedResource>WEB-INF/web.xml</WatchedResource> <Resource name="jdbc/mysql" auth="Container" type="javax.sql.DataSource" initialSize="3" maxActive="10" maxIdle="20" maxWait="30000" driverClassName="com.mysql.jdbc.Driver" poolPreparedStatements="true" validationQuery="select 1 from dual" testOnBorrow="true" username="orbeon" password="******" url="jdbc:mysql://10.10.0.6:3306/orbeon"/> </Context> web.xml<?xml version="1.0" encoding="UTF-8"?> <web-app version="2.4" xsi:schemaLocation="http://java.sun.com/xml/ns/j2ee http://java.sun.com/xml/ns/j2ee/web-app_2_4.xsd" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns="http://java.sun.com/xml/ns/j2ee"> <display-name>Orbeon Forms</display-name> <description> Orbeon Forms is an open source, standard-based web forms solution, which includes Form Builder, a WYSIWYG browser-based authoring tool, and Form Runner, a runtime environment which facilitates the deployment and integration of a large number of complex forms. Orbeon Forms implements different technologies, such as XForms and Ajax, with no need for client-side software or plug-ins. </description> <!--Initialize main resource manager--> <context-param> <param-name>oxf.resources.factory</param-name> <param-value>org.orbeon.oxf.resources.PriorityResourceManagerFactory</param-value> </context-param> <!-- Uncomment this for the filesystem resource manager (development mode) --> <!-- <context-param> <param-name>oxf.resources.priority.0</param-name> <param-value>org.orbeon.oxf.resources.FilesystemResourceManagerFactory</param-value> </context-param> <context-param> <param-name>oxf.resources.priority.0.oxf.resources.filesystem.sandbox-directory</param-name> <param-value>/home/teamcity/TeamCity/buildAgent/work/9118ccd79b542921/src/resources-local</param-value> </context-param> <context-param> <param-name>oxf.resources.priority.1</param-name> <param-value>org.orbeon.oxf.resources.FilesystemResourceManagerFactory</param-value> </context-param> <context-param> <param-name>oxf.resources.priority.1.oxf.resources.filesystem.sandbox-directory</param-name> <param-value>/home/teamcity/TeamCity/buildAgent/work/9118ccd79b542921/src/resources</param-value> </context-param> <context-param> <param-name>oxf.resources.priority.3</param-name> <param-value>org.orbeon.oxf.resources.FilesystemResourceManagerFactory</param-value> </context-param> <context-param> <param-name>oxf.resources.priority.3.oxf.resources.filesystem.sandbox-directory</param-name> <param-value>/home/teamcity/TeamCity/buildAgent/work/9118ccd79b542921/src/resources-packaged</param-value> </context-param> <context-param> <param-name>oxf.resources.priority.5</param-name> <param-value>org.orbeon.oxf.resources.FilesystemResourceManagerFactory</param-value> </context-param> <context-param> <param-name>oxf.resources.priority.5.oxf.resources.filesystem.sandbox-directory</param-name> <param-value>/home/teamcity/TeamCity/buildAgent/work/9118ccd79b542921/src/test/resources</param-value> </context-param>--> <!-- End filesystem resource manager (development mode) --> <!--Web application resource manager for resources--> <context-param> <param-name>oxf.resources.priority.2</param-name> <param-value>org.orbeon.oxf.resources.WebAppResourceManagerFactory</param-value> </context-param> <context-param> <param-name>oxf.resources.priority.2.oxf.resources.webapp.rootdir</param-name> <param-value>/WEB-INF/resources</param-value> </context-param> <!--Classloader resource manager--> <context-param> <param-name>oxf.resources.priority.4</param-name> <param-value>org.orbeon.oxf.resources.ClassLoaderResourceManagerFactory</param-value> </context-param> <!--Set run mode ("dev" or "prod")--> <context-param> <param-name>oxf.run-mode</param-name> <param-value>prod</param-value> </context-param> <!--Set location of properties.xml--> <context-param> <param-name>oxf.properties</param-name> <param-value>oxf:/config/properties-${oxf.run-mode}.xml</param-value> </context-param> <!--Determine whether logging initialization must take place--> <context-param> <param-name>oxf.initialize-logging</param-name> <param-value>true</param-value> </context-param> <!--Set context listener processors--> <!-- Uncomment this for the context listener processors --> <!-- <context-param> <param-name>oxf.context-initialized-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}pipeline</param-value> </context-param> <context-param> <param-name>oxf.context-initialized-processor.input.config</param-name> <param-value>oxf:/apps/context/context-initialized.xpl</param-value> </context-param> <context-param> <param-name>oxf.context-destroyed-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}pipeline</param-value> </context-param> <context-param> <param-name>oxf.context-destroyed-processor.input.config</param-name> <param-value>oxf:/apps/context/context-destroyed.xpl</param-value> </context-param>--> <!-- End context listener processors --> <!--Set session listener processors--> <!-- Uncomment this for the session listener processors --> <!-- <context-param> <param-name>oxf.session-created-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}pipeline</param-value> </context-param> <context-param> <param-name>oxf.session-created-processor.input.config</param-name> <param-value>oxf:/apps/context/session-created.xpl</param-value> </context-param> <context-param> <param-name>oxf.session-destroyed-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}pipeline</param-value> </context-param> <context-param> <param-name>oxf.session-destroyed-processor.input.config</param-name> <param-value>oxf:/apps/context/session-destroyed.xpl</param-value> </context-param>--> <!-- End session listener processors --> <!--Security filter for eXist--> <!-- <filter> <filter-name>orbeon-exist-filter</filter-name> <filter-class>org.orbeon.oxf.servlet.TokenSecurityFilter</filter-class> </filter> <filter-mapping> <filter-name>orbeon-exist-filter</filter-name> <url-pattern>/exist/*</url-pattern> <dispatcher>REQUEST</dispatcher> <dispatcher>FORWARD</dispatcher> </filter-mapping> --> <!--Limit concurrent access to Form Runner--> <filter> <filter-name>orbeon-limiter-filter</filter-name> <filter-class>org.orbeon.oxf.servlet.LimiterFilter</filter-class> <!--Include Form Runner pages and XForms Ajax requests--> <init-param> <param-name>include</param-name> <param-value>(/fr/.*)|(/xforms-server)</param-value> </init-param> <!--Exclude resources not produced by services--> <init-param> <param-name>exclude</param-name> <param-value>(?!/([^/]+)/service/).+\.(gif|css|pdf|json|js|coffee|map|png|jpg|xsd|htc|ico|swf|html|htm|txt)</param-value> </init-param> <!--Minimum, requested, and maximum number of concurrent threads allowed--> <!--The `x` prefix specifies a multiple of the number of CPU cores reported by the JVM--> <init-param> <param-name>min-threads</param-name> <param-value>1</param-value> </init-param> <init-param> <param-name>num-threads</param-name> <param-value>x1</param-value> </init-param> <init-param> <param-name>max-threads</param-name> <param-value>x1</param-value> </init-param> </filter> <filter-mapping> <filter-name>orbeon-limiter-filter</filter-name> <url-pattern>/*</url-pattern> <dispatcher>REQUEST</dispatcher> </filter-mapping> <!--Add internal Orbeon-* headers for auth--> <filter> <filter-name>orbeon-form-runner-auth-servlet-filter</filter-name> <filter-class>org.orbeon.oxf.servlet.FormRunnerAuthFilter</filter-class> </filter> <filter-mapping> <filter-name>orbeon-form-runner-auth-servlet-filter</filter-name> <url-pattern>/*</url-pattern> <dispatcher>REQUEST</dispatcher> <dispatcher>FORWARD</dispatcher> </filter-mapping> <!--All JSP files under /xforms-jsp go through the XForms filter--> <filter> <filter-name>orbeon-xforms-filter</filter-name> <filter-class>org.orbeon.oxf.servlet.OrbeonXFormsFilter</filter-class> <!-- Uncomment this for the separate WAR deployment --> <!-- <init-param> <param-name>oxf.xforms.renderer.context</param-name> <param-value>/orbeon</param-value> </init-param> <init-param> <param-name>oxf.xforms.renderer.default-encoding</param-name> <param-value>UTF-8</param-value> </init-param>--> <!-- End separate WAR deployment --> </filter> <filter-mapping> <filter-name>orbeon-xforms-filter</filter-name> <url-pattern>/xforms-jsp/*</url-pattern> <!--Servlet 2.4 configuration allowing the filter to run upon forward in addition to request--> <dispatcher>REQUEST</dispatcher> <dispatcher>FORWARD</dispatcher> </filter-mapping> <!--Orbeon context listener--> <listener> <listener-class>org.orbeon.oxf.webapp.OrbeonServletContextListener</listener-class> </listener> <!--Orbeon session listener--> <listener> <listener-class>org.orbeon.oxf.webapp.OrbeonSessionListener</listener-class> </listener> <!--Ehcache shutdown listener--> <listener> <listener-class>net.sf.ehcache.constructs.web.ShutdownListener</listener-class> </listener> <!--This is the main Orbeon Forms servlet--> <servlet> <servlet-name>orbeon-main-servlet</servlet-name> <servlet-class>org.orbeon.oxf.servlet.OrbeonServlet</servlet-class> <!--Set main processor--> <init-param> <param-name>oxf.main-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}pipeline</param-value> </init-param> <init-param> <param-name>oxf.main-processor.input.config</param-name> <param-value>oxf:/config/prologue-servlet.xpl</param-value> </init-param> <!--Set error processor--> <init-param> <param-name>oxf.error-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}page-flow</param-value> </init-param> <init-param> <param-name>oxf.error-processor.input.controller</param-name> <param-value>oxf:/config/error-page-flow.xml</param-value> </init-param> <!--Set supported methods--> <init-param> <param-name>oxf.http.accept-methods</param-name> <param-value>get,post,head,put,delete</param-value> </init-param> <!--Set servlet initialization and destruction listeners--> <!-- Uncomment this for the servlet listener processors --> <!-- <init-param> <param-name>oxf.servlet-initialized-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}pipeline</param-value> </init-param> <init-param> <param-name>oxf.servlet-initialized-processor.input.config</param-name> <param-value>oxf:/apps/context/servlet-initialized.xpl</param-value> </init-param> <init-param> <param-name>oxf.servlet-destroyed-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}pipeline</param-value> </init-param> <init-param> <param-name>oxf.servlet-destroyed-processor.input.config</param-name> <param-value>oxf:/apps/context/servlet-destroyed.xpl</param-value> </init-param>--> <!-- End servlet listener processors --> </servlet> <!--This is the XForms Renderer servlet, used to deploy Orbeon Forms as a separate WAR--> <servlet> <servlet-name>orbeon-renderer-servlet</servlet-name> <servlet-class>org.orbeon.oxf.servlet.OrbeonServlet</servlet-class> <!--Set main processor--> <init-param> <param-name>oxf.main-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}page-flow</param-value> </init-param> <init-param> <param-name>oxf.main-processor.input.controller</param-name> <param-value>oxf:/ops/xforms/xforms-renderer-page-flow.xml</param-value> </init-param> <!--Set error processor--> <init-param> <param-name>oxf.error-processor.name</param-name> <param-value>{http://www.orbeon.com/oxf/processors}pipeline</param-value> </init-param> <init-param> <param-name>oxf.error-processor.input.config</param-name> <param-value>oxf:/config/error.xpl</param-value> </init-param> </servlet> <!-- Uncomment this for the Experimental MongoDB Form Runner persistence implementation --> <!-- <servlet> <servlet-name>form-runner-mongodb-servlet</servlet-name> <servlet-class>org.orbeon.oxf.fr.mongodb.MongoDBPersistence</servlet-class> </servlet>--> <!-- End Experimental MongoDB Form Runner persistence implementation --> <servlet> <servlet-name>display-chart-servlet</servlet-name> <servlet-class>org.jfree.chart.servlet.DisplayChart</servlet-class> </servlet> <!-- Uncomment this for the eXist XMLRPC support --> <!-- <servlet> <servlet-name>exist-xmlrpc-servlet</servlet-name> <servlet-class>org.exist.xmlrpc.RpcServlet</servlet-class> </servlet>--> <!-- End eXist XMLRPC support --> <!-- <servlet> <servlet-name>exist-rest-servlet</servlet-name> <servlet-class>org.exist.http.servlets.EXistServlet</servlet-class> <init-param> <param-name>basedir</param-name> <param-value>WEB-INF/</param-value> </init-param> <init-param> <param-name>configuration</param-name> <param-value>exist-conf.xml</param-value> </init-param> <init-param> <param-name>start</param-name> <param-value>true</param-value> </init-param> </servlet> --> <!-- Uncomment this for the eXist WebDAV support --> <!-- <servlet> <servlet-name>exist-webdav-servlet</servlet-name> <servlet-class>org.exist.http.servlets.WebDAVServlet</servlet-class> <init-param> <param-name>authentication</param-name> <param-value>basic</param-value> </init-param> </servlet> --> <!-- End eXist WebDAV support --> <servlet-mapping> <servlet-name>orbeon-main-servlet</servlet-name> <url-pattern>/</url-pattern> </servlet-mapping> <servlet-mapping> <servlet-name>orbeon-renderer-servlet</servlet-name> <url-pattern>/xforms-renderer</url-pattern> </servlet-mapping> <!-- <servlet-mapping> <servlet-name>exist-rest-servlet</servlet-name> <url-pattern>/exist/rest/*</url-pattern> </servlet-mapping> --> <!-- Uncomment this for the Experimental MongoDB Form Runner persistence implementation --> <!-- <servlet-mapping> <servlet-name>form-runner-mongodb-servlet</servlet-name> <url-pattern>/fr/service/mongo/*</url-pattern> </servlet-mapping>--> <!-- End Experimental MongoDB Form Runner persistence implementation --> <!-- Uncomment this for the eXist XMLRPC support --> <!-- <servlet-mapping> <servlet-name>exist-xmlrpc-servlet</servlet-name> <url-pattern>/exist/xmlrpc</url-pattern> </servlet-mapping>--> <!-- End eXist XMLRPC support --> <!-- Uncomment this for the eXist WebDAV support --> <!-- <servlet-mapping> <servlet-name>exist-webdav-servlet</servlet-name> <url-pattern>/exist/webdav/*</url-pattern> </servlet-mapping>--> <!-- End eXist WebDAV support --> <servlet-mapping> <servlet-name>display-chart-servlet</servlet-name> <url-pattern>/chartDisplay</url-pattern> </servlet-mapping> <!-- Uncomment this for the relational persistence, and change oracle if necessary --> <resource-ref> <description>DataSource</description> <res-ref-name>jdbc/mysql</res-ref-name> <res-type>javax.sql.DataSource</res-type> <res-auth>Container</res-auth> </resource-ref> <!-- End relational persistence, and change oracle if necessary --> <!-- Uncomment this for the Form Runner authentication --> <!-- <security-constraint> <web-resource-collection> <web-resource-name>Form Runner pages</web-resource-name> <url-pattern>/fr/*</url-pattern> </web-resource-collection> <auth-constraint> <role-name>orbeon-user</role-name> </auth-constraint> </security-constraint> <security-constraint> <web-resource-collection> <web-resource-name>Form Runner services and public pages and resources</web-resource-name> <url-pattern>/fr/service/*</url-pattern> <url-pattern>/fr/style/*</url-pattern> <url-pattern>/fr/not-found</url-pattern> <url-pattern>/fr/unauthorized</url-pattern> <url-pattern>/fr/error</url-pattern> <url-pattern>/fr/login</url-pattern> <url-pattern>/fr/login-error</url-pattern> </web-resource-collection> </security-constraint> <login-config> <auth-method>FORM</auth-method> <form-login-config> <form-login-page>/fr/login</form-login-page> <form-error-page>/fr/login-error</form-error-page> </form-login-config> </login-config> <security-role> <role-name>orbeon-user</role-name> </security-role>--> <!-- End Form Runner authentication --> <session-config> <session-timeout>60</session-timeout> </session-config> </web-app> properties-local-prod.xml<!-- This file is an empty template for your own properties-local-prod.xml file. Please follow the instructions here: http://wiki.orbeon.com/forms/doc/developer-guide/configuration-properties --> <properties xmlns:xs="http://www.w3.org/2001/XMLSchema" xmlns:oxf="http://www.orbeon.com/oxf/processors" xmlns:xxf="http://orbeon.org/oxf/xml/xforms"> <property as="xs:string" name="oxf.fr.persistence.provider.*.*.*" value="mysql"/> <property as="xs:string" name="oxf.fr.persistence.mysql.datasource" value="mysql"/> <property as="xs:boolean" name="oxf.fr.persistence.mysql.autosave" value="true"/> <property as="xs:boolean" name="oxf.fr.persistence.mysql.permissions" value="true"/> <property as="xs:boolean" name="oxf.fr.persistence.mysql.versioning" value="true"/> </properties> orbeon.log2015-11-18 15:53:24,358 INFO ProcessorService - Context listener - Context initialized. 2015-11-18 15:53:24,367 INFO limiter - initializing 2015-11-18 15:53:24,372 INFO limiter - configuring: FilterSettings(java.util.concurrent.Semaphore@4f43ede6[Permits = 8],(/fr/.*)|(/xforms-server),(?!/([^/]+)/service/).+\.(gif|css|pdf|json|js|coffee|map|png|jpg|xsd|htc|ico|swf|html|htm|txt)) 2015-11-18 15:53:24,611 INFO lifecycle - event: {"request": "1", "source": "limiter", "message": "start: nofilter", "path": "/index.jsp", "method": "GET"} 2015-11-18 15:53:25,024 INFO ProcessorService - Session listener - Session created. 2015-11-18 15:53:25,050 INFO lifecycle - event: {"request": "1", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: nofilter", "time": "440 ms"} 2015-11-18 15:53:30,439 INFO ProcessorService - Servlet initialized. 2015-11-18 15:53:30,440 INFO lifecycle - event: {"request": "2", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/fr/orbeon/builder/new", "method": "GET"} 2015-11-18 15:53:30,441 INFO lifecycle - event: {"request": "2", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/fr/orbeon/builder/new", "method": "GET", "wait": "0"} 2015-11-18 15:53:30,456 INFO lifecycle - event: {"request": "2", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:30,457 INFO ProcessorService - /fr/orbeon/builder/new - Received request 2015-11-18 15:53:31,329 INFO lifecycle - event: {"request": "3", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/crud/orbeon/builder/form/form.xhtml", "method": "GET"} 2015-11-18 15:53:31,329 INFO ProcessorService - /fr/service/persistence/crud/orbeon/builder/form/form.xhtml - Received request 2015-11-18 15:53:31,411 INFO lifecycle - event: {"request": "4", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/resource/crud/orbeon/builder/form/form.xhtml", "method": "GET"} 2015-11-18 15:53:31,411 INFO ProcessorService - /fr/service/resource/crud/orbeon/builder/form/form.xhtml - Received request 2015-11-18 15:53:31,490 INFO ProcessorService - /fr/service/resource/crud/orbeon/builder/form/form.xhtml - Timing: 78 2015-11-18 15:53:31,490 INFO lifecycle - event: {"request": "4", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "79 ms"} 2015-11-18 15:53:31,505 INFO ProcessorService - /fr/service/persistence/crud/orbeon/builder/form/form.xhtml - Timing: 176 2015-11-18 15:53:31,505 INFO lifecycle - event: {"request": "3", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "178 ms"} 2015-11-18 15:53:33,643 WARN XFormsServer - unknown id {id: "fr-number"} 2015-11-18 15:53:33,644 WARN XFormsServer - observer attribute present but does not refer to at least one valid id, ignoring event handler {element: "<xxf:script ev:target="#observer" ev:observer="fr-number" ev:event="DOMFocusOut" id="xf-2546">[...]</xxf:script>"} 2015-11-18 15:53:33,644 WARN XFormsServer - target attribute present but does not refer to at least one valid id, ignoring event handler {element: "<xxf:script ev:target="#observer" ev:observer="fr-number" ev:event="DOMFocusOut" id="xf-2546">[...]</xxf:script>"} 2015-11-18 15:53:33,644 WARN XFormsServer - unknown id {id: "fr-number"} 2015-11-18 15:53:33,644 WARN XFormsServer - observer attribute present but does not refer to at least one valid id, ignoring event handler {element: "<xxf:script ev:target="#observer" ev:observer="fr-number" ev:event="DOMFocusOut" id="xf-2584">[...]</xxf:script>"} 2015-11-18 15:53:33,644 WARN XFormsServer - target attribute present but does not refer to at least one valid id, ignoring event handler {element: "<xxf:script ev:target="#observer" ev:observer="fr-number" ev:event="DOMFocusOut" id="xf-2584">[...]</xxf:script>"} 2015-11-18 15:53:33,780 INFO lifecycle - event: {"request": "2", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "new form session", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:33,929 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:34,272 INFO lifecycle - event: {"request": "5", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/i18n/fr-resources/orbeon/builder", "method": "GET"} 2015-11-18 15:53:34,272 INFO ProcessorService - /fr/service/i18n/fr-resources/orbeon/builder - Received request 2015-11-18 15:53:34,393 INFO ProcessorService - /fr/service/i18n/fr-resources/orbeon/builder - Timing: 121 2015-11-18 15:53:34,393 INFO lifecycle - event: {"request": "5", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "121 ms"} 2015-11-18 15:53:34,678 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:35,028 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:35,080 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:35,127 WARN XFormsServer - Unsupported event context information for event('singleton'). 2015-11-18 15:53:35,903 INFO lifecycle - event: {"request": "2", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"} 2015-11-18 15:53:36,076 INFO ProcessorService - /fr/orbeon/builder/new - Timing: 5619 2015-11-18 15:53:36,076 INFO lifecycle - event: {"request": "2", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "5.620 ms"} 2015-11-18 15:53:36,100 INFO lifecycle - event: {"request": "2", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "5.659 ms"} 2015-11-18 15:53:36,101 INFO lifecycle - event: {"request": "2", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "5.660 ms"} 2015-11-18 15:53:38,603 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:38,604 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:38,608 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:38,608 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:38,669 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:38,671 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:38,671 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:38,844 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"} 2015-11-18 15:53:38,844 INFO ProcessorService - /xforms-server - Timing: 236 2015-11-18 15:53:38,845 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "237 ms"} 2015-11-18 15:53:38,862 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "258 ms"} 2015-11-18 15:53:38,863 INFO lifecycle - event: {"request": "6", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "259 ms"} 2015-11-18 15:53:39,425 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:39,425 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:39,429 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:39,429 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:39,437 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:39,437 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:39,437 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:39,537 INFO lifecycle - event: {"request": "8", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/custom/orbeon/builder/toolbox", "method": "GET"} 2015-11-18 15:53:39,537 INFO ProcessorService - /fr/service/custom/orbeon/builder/toolbox - Received request 2015-11-18 15:53:39,653 INFO lifecycle - event: {"request": "9", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/crud/orbeon/library/form/form.xhtml", "method": "GET"} 2015-11-18 15:53:39,654 INFO ProcessorService - /fr/service/persistence/crud/orbeon/library/form/form.xhtml - Received request 2015-11-18 15:53:39,660 INFO lifecycle - event: {"request": "10", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/mysql/crud/orbeon/library/form/form.xhtml", "method": "GET"} 2015-11-18 15:53:39,660 INFO ProcessorService - /fr/service/mysql/crud/orbeon/library/form/form.xhtml - Received request 2015-11-18 15:53:39,797 INFO ProcessorService - /fr/service/mysql/crud/orbeon/library/form/form.xhtml - Timing: 137 2015-11-18 15:53:39,798 INFO lifecycle - event: {"request": "10", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "137 ms"} 2015-11-18 15:53:39,798 INFO ProcessorService - /fr/service/persistence/crud/orbeon/library/form/form.xhtml - Timing: 144 2015-11-18 15:53:39,798 INFO lifecycle - event: {"request": "9", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "145 ms"} 2015-11-18 15:53:39,808 INFO lifecycle - event: {"request": "11", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/crud/test/library/form/form.xhtml", "method": "GET"} 2015-11-18 15:53:39,808 INFO ProcessorService - /fr/service/persistence/crud/test/library/form/form.xhtml - Received request 2015-11-18 15:53:39,813 INFO lifecycle - event: {"request": "12", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/mysql/crud/test/library/form/form.xhtml", "method": "GET"} 2015-11-18 15:53:39,814 INFO ProcessorService - /fr/service/mysql/crud/test/library/form/form.xhtml - Received request 2015-11-18 15:53:39,920 INFO ProcessorService - /fr/service/mysql/crud/test/library/form/form.xhtml - Timing: 106 2015-11-18 15:53:39,920 INFO lifecycle - event: {"request": "12", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "107 ms"} 2015-11-18 15:53:39,920 INFO ProcessorService - /fr/service/persistence/crud/test/library/form/form.xhtml - Timing: 112 2015-11-18 15:53:39,920 INFO lifecycle - event: {"request": "11", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "112 ms"} 2015-11-18 15:53:39,925 INFO ProcessorService - /fr/service/custom/orbeon/builder/toolbox - Timing: 388 2015-11-18 15:53:39,925 INFO lifecycle - event: {"request": "8", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "388 ms"} 2015-11-18 15:53:40,002 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:40,199 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"} 2015-11-18 15:53:40,199 INFO ProcessorService - /xforms-server - Timing: 770 2015-11-18 15:53:40,200 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "771 ms"} 2015-11-18 15:53:40,217 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "792 ms"} 2015-11-18 15:53:40,217 INFO lifecycle - event: {"request": "7", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "792 ms"} 2015-11-18 15:53:40,373 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:40,373 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:40,377 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:40,377 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:40,384 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:40,385 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:40,385 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:40,388 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"} 2015-11-18 15:53:40,388 INFO ProcessorService - /xforms-server - Timing: 11 2015-11-18 15:53:40,389 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "12 ms"} 2015-11-18 15:53:40,404 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "30 ms"} 2015-11-18 15:53:40,404 INFO lifecycle - event: {"request": "13", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "31 ms"} 2015-11-18 15:53:41,243 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:41,243 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:41,249 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:41,249 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:41,255 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:41,256 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:41,256 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:41,261 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:41,303 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "fr", "message": "start: process", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "scope": "oxf.fr.detail.process", "name": "save"} 2015-11-18 15:53:41,348 INFO lifecycle - event: {"request": "15", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/crud/orbeon/builder/data/c56be3b0d5fe84316e934a1ec5c917a6c381f25f/data.xml", "method": "PUT"} 2015-11-18 15:53:41,348 INFO ProcessorService - /fr/service/persistence/crud/orbeon/builder/data/c56be3b0d5fe84316e934a1ec5c917a6c381f25f/data.xml - Received request 2015-11-18 15:53:41,367 INFO lifecycle - event: {"request": "16", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/mysql/crud/orbeon/builder/data/c56be3b0d5fe84316e934a1ec5c917a6c381f25f/data.xml", "method": "PUT"} 2015-11-18 15:53:41,367 INFO ProcessorService - /fr/service/mysql/crud/orbeon/builder/data/c56be3b0d5fe84316e934a1ec5c917a6c381f25f/data.xml - Received request 2015-11-18 15:53:41,382 INFO lifecycle - event: {"request": "17", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/form/orbeon/builder", "method": "GET"} 2015-11-18 15:53:41,382 INFO ProcessorService - /fr/service/persistence/form/orbeon/builder - Received request 2015-11-18 15:53:41,388 INFO lifecycle - event: {"request": "18", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/resource/form/orbeon/builder", "method": "GET"} 2015-11-18 15:53:41,388 INFO ProcessorService - /fr/service/resource/form/orbeon/builder - Received request 2015-11-18 15:53:41,402 INFO ProcessorService - /fr/service/resource/form/orbeon/builder - Timing: 14 2015-11-18 15:53:41,402 INFO lifecycle - event: {"request": "18", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "14 ms"} 2015-11-18 15:53:41,406 INFO ProcessorService - /fr/service/persistence/form/orbeon/builder - Timing: 24 2015-11-18 15:53:41,407 INFO lifecycle - event: {"request": "17", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "25 ms"} 2015-11-18 15:53:41,616 INFO ProcessorService - /fr/service/mysql/crud/orbeon/builder/data/c56be3b0d5fe84316e934a1ec5c917a6c381f25f/data.xml - Timing: 249 2015-11-18 15:53:41,618 INFO lifecycle - event: {"request": "16", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "250 ms"} 2015-11-18 15:53:41,618 INFO ProcessorService - /fr/service/persistence/crud/orbeon/builder/data/c56be3b0d5fe84316e934a1ec5c917a6c381f25f/data.xml - Timing: 270 2015-11-18 15:53:41,619 INFO lifecycle - event: {"request": "15", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "271 ms"} 2015-11-18 15:53:41,619 WARN XFormsServer - instance or text replacement did not take place upon successful response because no body was provided. {submission id: "fr-create-update-submission"} 2015-11-18 15:53:41,624 WARN XFormsServer - Unsupported event context information for event('holder'). 2015-11-18 15:53:41,633 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:41,673 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:41,705 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "fr", "message": "end: process", "time": "402 ms"} 2015-11-18 15:53:41,759 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"} 2015-11-18 15:53:41,760 INFO ProcessorService - /xforms-server - Timing: 511 2015-11-18 15:53:41,760 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "511 ms"} 2015-11-18 15:53:41,775 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "531 ms"} 2015-11-18 15:53:41,775 INFO lifecycle - event: {"request": "14", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "532 ms"} 2015-11-18 15:53:42,927 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:42,928 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:42,931 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:42,931 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:42,937 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:42,937 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:42,937 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:42,963 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:42,990 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "fr", "message": "start: process", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "scope": "oxf.fr.detail.process", "name": "test"} 2015-11-18 15:53:42,993 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "fr", "message": "end: process", "time": "3 ms"} 2015-11-18 15:53:43,014 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"} 2015-11-18 15:53:43,014 INFO ProcessorService - /xforms-server - Timing: 83 2015-11-18 15:53:43,014 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "83 ms"} 2015-11-18 15:53:43,028 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "100 ms"} 2015-11-18 15:53:43,029 INFO lifecycle - event: {"request": "19", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "101 ms"} 2015-11-18 15:53:43,132 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:43,132 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:43,135 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:43,135 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:43,141 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:43,141 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:43,141 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:43,409 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"} 2015-11-18 15:53:43,409 INFO ProcessorService - /xforms-server - Timing: 274 2015-11-18 15:53:43,409 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "274 ms"} 2015-11-18 15:53:43,426 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "294 ms"} 2015-11-18 15:53:43,426 INFO lifecycle - event: {"request": "20", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "294 ms"} 2015-11-18 15:53:43,455 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/fr/orbeon/builder/edit/c56be3b0d5fe84316e934a1ec5c917a6c381f25f", "method": "POST"} 2015-11-18 15:53:43,456 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/fr/orbeon/builder/edit/c56be3b0d5fe84316e934a1ec5c917a6c381f25f", "method": "POST", "wait": "0"} 2015-11-18 15:53:43,459 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:43,459 INFO ProcessorService - /fr/orbeon/builder/edit/c56be3b0d5fe84316e934a1ec5c917a6c381f25f - Received request 2015-11-18 15:53:43,504 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:43,504 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/fr/orbeon/builder/edit/c56be3b0d5fe84316e934a1ec5c917a6c381f25f", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:43,529 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "1", "document cache max size": "50"} 2015-11-18 15:53:43,529 INFO lifecycle - event: {"request": "22", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/test/test/test", "method": "POST"} 2015-11-18 15:53:43,530 INFO ProcessorService - /fr/test/test/test - Received request 2015-11-18 15:53:43,708 WARN XFormsServer - unknown id {id: "fb-form-instance"} 2015-11-18 15:53:43,709 WARN XFormsServer - unknown id {id: "fr-captcha-group"} 2015-11-18 15:53:43,709 WARN XFormsServer - unknown id {id: "fr-captcha-group"} 2015-11-18 15:53:43,709 WARN XFormsServer - unknown id {id: "fr-captcha-group"} 2015-11-18 15:53:43,882 INFO lifecycle - event: {"request": "22", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "new form session", "uuid": "940f74baeb7cbd840649d0a735bc6f6d11f767c4"} 2015-11-18 15:53:43,908 INFO lifecycle - event: {"request": "23", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/i18n/fr-resources/test/test", "method": "GET"} 2015-11-18 15:53:43,908 INFO ProcessorService - /fr/service/i18n/fr-resources/test/test - Received request 2015-11-18 15:53:43,937 INFO ProcessorService - /fr/service/i18n/fr-resources/test/test - Timing: 29 2015-11-18 15:53:43,937 INFO lifecycle - event: {"request": "23", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "30 ms"} 2015-11-18 15:53:44,043 INFO lifecycle - event: {"request": "22", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"} 2015-11-18 15:53:44,069 INFO ProcessorService - /fr/test/test/test - Timing: 540 2015-11-18 15:53:44,070 INFO lifecycle - event: {"request": "22", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "541 ms"} 2015-11-18 15:53:44,074 INFO ProcessorService - /fr/orbeon/builder/edit/c56be3b0d5fe84316e934a1ec5c917a6c381f25f - Timing: 615 2015-11-18 15:53:44,076 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "617 ms"} 2015-11-18 15:53:44,093 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "637 ms"} 2015-11-18 15:53:44,093 INFO lifecycle - event: {"request": "21", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "638 ms"} 2015-11-18 15:53:44,317 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:44,317 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:44,320 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:44,320 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:44,326 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:44,326 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:44,326 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:44,368 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"} 2015-11-18 15:53:44,368 INFO ProcessorService - /xforms-server - Timing: 48 2015-11-18 15:53:44,369 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "49 ms"} 2015-11-18 15:53:44,383 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "66 ms"} 2015-11-18 15:53:44,383 INFO lifecycle - event: {"request": "24", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "66 ms"} 2015-11-18 15:53:46,077 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:46,077 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:46,080 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:46,080 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:46,086 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:46,086 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:46,086 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:46,128 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"} 2015-11-18 15:53:46,129 INFO ProcessorService - /xforms-server - Timing: 49 2015-11-18 15:53:46,129 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "49 ms"} 2015-11-18 15:53:46,147 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "69 ms"} 2015-11-18 15:53:46,147 INFO lifecycle - event: {"request": "25", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "70 ms"} 2015-11-18 15:53:46,286 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:46,287 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:46,292 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:46,292 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:46,298 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:46,298 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:46,298 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:46,300 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"} 2015-11-18 15:53:46,300 INFO ProcessorService - /xforms-server - Timing: 8 2015-11-18 15:53:46,300 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "8 ms"} 2015-11-18 15:53:46,336 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "48 ms"} 2015-11-18 15:53:46,336 INFO lifecycle - event: {"request": "26", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "50 ms"} 2015-11-18 15:53:46,828 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:46,828 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:46,830 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:46,830 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:46,836 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:46,836 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:46,836 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:46,838 WARN XFormsServer - xxf:instance() - instance not found {instance id: "fr-form-attachments"} 2015-11-18 15:53:46,864 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "fr", "message": "start: process", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "scope": "oxf.fr.detail.process", "name": "publish"} 2015-11-18 15:53:46,871 WARN XFormsServer - instance() - instance not found {instance id: "fr-create-update-submission-response"} 2015-11-18 15:53:46,877 INFO lifecycle - event: {"request": "28", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/crud/test/test/form/form.xhtml", "method": "GET"} 2015-11-18 15:53:46,877 INFO ProcessorService - /fr/service/persistence/crud/test/test/form/form.xhtml - Received request 2015-11-18 15:53:46,884 INFO lifecycle - event: {"request": "29", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/mysql/crud/test/test/form/form.xhtml", "method": "GET"} 2015-11-18 15:53:46,884 INFO ProcessorService - /fr/service/mysql/crud/test/test/form/form.xhtml - Received request 2015-11-18 15:53:46,990 INFO ProcessorService - /fr/service/mysql/crud/test/test/form/form.xhtml - Timing: 106 2015-11-18 15:53:46,990 INFO lifecycle - event: {"request": "29", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "106 ms"} 2015-11-18 15:53:46,991 INFO ProcessorService - /fr/service/persistence/crud/test/test/form/form.xhtml - Timing: 114 2015-11-18 15:53:46,991 INFO lifecycle - event: {"request": "28", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "114 ms"} 2015-11-18 15:53:47,019 ERROR XFormsServer - xforms-submit-error - setting throwable {throwable: " +----------------------------------------------------------------------------------------------------------------------+ |An Error has Occurred | |----------------------------------------------------------------------------------------------------------------------| |xf:submission for submission id: fb-read-form-definition, error code received when submitting instance: 404 | |----------------------------------------------------------------------------------------------------------------------| |Application Call Stack | |----------------------------------------------------------------------------------------------------------------------| |----------------------------------------------------------------------------------------------------------------------| |Exception: org.orbeon.oxf.xforms.submission.XFormsSubmissionException | |----------------------------------------------------------------------------------------------------------------------| |.orbeon.oxf.xforms.submission.XFormsModelSubmission|getReplacer |XFormsModelSubmission.java | 729| |rg.orbeon.oxf.xforms.submission.RegularSubmission$1|call |RegularSubmission.java | 106| |rg.orbeon.oxf.xforms.submission.RegularSubmission$1|call |RegularSubmission.java | 76| |org.orbeon.oxf.xforms.submission.BaseSubmission |submitCallable |BaseSubmission.scala | 89| |org.orbeon.oxf.xforms.submission.RegularSubmission |connect |RegularSubmission.java | 129| |.orbeon.oxf.xforms.submission.XFormsModelSubmission|doSubmit |XFormsModelSubmission.java | 460| |.orbeon.oxf.xforms.submission.XFormsModelSubmission|performDefaultAction |XFormsModelSubmission.java | 292| |.oxf.xforms.event.Dispatch$$anonfun$dispatchEvent$1|apply$mcV$sp |Dispatch.scala | 138| |.oxf.xforms.event.Dispatch$$anonfun$dispatchEvent$1|apply |Dispatch.scala | 73| |.oxf.xforms.event.Dispatch$$anonfun$dispatchEvent$1|apply |Dispatch.scala | 73| |org.orbeon.oxf.xforms.event.Dispatch$ |withEvent$1 |Dispatch.scala | 38| |org.orbeon.oxf.xforms.event.Dispatch$ |dispatchEvent |Dispatch.scala | 72| |g.orbeon.oxf.xforms.action.actions.XFormsSendAction|execute |XFormsSendAction.scala | 51| |org.orbeon.oxf.xforms.action.XFormsAction |execute |XFormsAction.scala | 36| |rg.orbeon.oxf.xforms.action.XFormsActionInterpreter|runSingleIteration |XFormsActionInterpreter.java | 221| |rg.orbeon.oxf.xforms.action.XFormsActionInterpreter|runAction |XFormsActionInterpreter.java | 157| |ction.actions.XFormsActionAction$$anonfun$execute$1|apply |XFormsActionAction.scala | 52| |ction.actions.XFormsActionAction$$anonfun$execute$1|apply |XFormsActionAction.scala | 40| |scala.collection.Iterator$class |foreach |Iterator.scala | 750| |scala.collection.AbstractIterator |foreach |Iterator.scala |1202| |scala.collection.IterableLike$class |foreach |IterableLike.scala | 72| |scala.collection.AbstractIterable |foreach |Iterable.scala | 54| |orbeon.oxf.xforms.action.actions.XFormsActionAction|execute |XFormsActionAction.scala | 40| |rg.orbeon.oxf.xforms.action.XFormsActionInterpreter|runSingleIteration |XFormsActionInterpreter.java | 221| |rg.orbeon.oxf.xforms.action.XFormsActionInterpreter|runAction |XFormsActionInterpreter.java | 157| |forms.event.EventHandlerImpl$$anonfun$handleEvent$1|apply$mcV$sp |EventHandlerImpl.scala | 256| |forms.event.EventHandlerImpl$$anonfun$handleEvent$1|apply |EventHandlerImpl.scala | 256| |forms.event.EventHandlerImpl$$anonfun$handleEvent$1|apply |EventHandlerImpl.scala | 256| |org.orbeon.oxf.util.DynamicVariable |withValue |DynamicVariable.scala | 42| |org.orbeon.oxf.xforms.action.XFormsAPI$ |withScalaAction |XFormsAPI.scala | 47| |org.orbeon.oxf.xforms.event.EventHandlerImpl |handleEvent |EventHandlerImpl.scala | 255| |$$anonfun$apply$2$$anonfun$apply$4$$anonfun$apply$1|apply$mcV$sp |Dispatch.scala | 93| |$$anonfun$apply$2$$anonfun$apply$4$$anonfun$apply$1|apply |Dispatch.scala | 92| |$$anonfun$apply$2$$anonfun$apply$4$$anonfun$apply$1|apply |Dispatch.scala | 92| |org.orbeon.oxf.util.Logging$class |withDebug |Logging.scala | 50| |org.orbeon.oxf.xforms.event.Dispatch$ |withDebug |Dispatch.scala | 23| |nfun$$doPhase$1$1$$anonfun$apply$2$$anonfun$apply$4|apply |Dispatch.scala | 92| |nfun$$doPhase$1$1$$anonfun$apply$2$$anonfun$apply$4|apply |Dispatch.scala | 86| |ollection.TraversableLike$WithFilter$$anonfun$map$2|apply |TraversableLike.scala | 728| |scala.collection.immutable.List |foreach |List.scala | 381| |---8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<---| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.apache.tomcat.websocket.server.WsFilter |doFilter |WsFilter.java | 52| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |g.netbeans.modules.web.monitor.server.MonitorFilter|doFilter |MonitorFilter.java | 393| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.orbeon.oxf.servlet.FormRunnerAuthFilter |doFilter |FormRunnerAuthFilter.scala | 26| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply$mcV$sp |LimiterFilter.scala | 85| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply |LimiterFilter.scala | 85| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply |LimiterFilter.scala | 85| |org.orbeon.oxf.logging.LifecycleLogger$ |withEvent |LifecycleLogger.scala | 124| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply$mcV$sp |LimiterFilter.scala | 84| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply |LimiterFilter.scala | 78| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply |LimiterFilter.scala | 78| |org.orbeon.oxf.logging.LifecycleLogger$ |withEvent |LifecycleLogger.scala | 124| |rbeon.oxf.servlet.LimiterFilter$$anonfun$doFilter$1|apply |LimiterFilter.scala | 78| |rbeon.oxf.servlet.LimiterFilter$$anonfun$doFilter$1|apply |LimiterFilter.scala | 72| |scala.Option |foreach |Option.scala | 257| |org.orbeon.oxf.servlet.LimiterFilter |doFilter |LimiterFilter.scala | 72| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.apache.catalina.core.StandardWrapperValve |invoke |StandardWrapperValve.java | 217| |org.apache.catalina.core.StandardContextValve |invoke |StandardContextValve.java | 106| |org.apache.catalina.authenticator.AuthenticatorBase|invoke |AuthenticatorBase.java | 502| |org.apache.catalina.core.StandardHostValve |invoke |StandardHostValve.java | 142| |org.apache.catalina.valves.ErrorReportValve |invoke |ErrorReportValve.java | 79| |org.apache.catalina.valves.AbstractAccessLogValve |invoke |AbstractAccessLogValve.java | 616| |org.apache.catalina.core.StandardEngineValve |invoke |StandardEngineValve.java | 88| |org.apache.catalina.connector.CoyoteAdapter |service |CoyoteAdapter.java | 518| |org.apache.coyote.http11.AbstractHttp11Processor |process |AbstractHttp11Processor.java |1091| |e.coyote.AbstractProtocol$AbstractConnectionHandler|process |AbstractProtocol.java | 673| |.apache.tomcat.util.net.NioEndpoint$SocketProcessor|doRun |NioEndpoint.java |1500| |.apache.tomcat.util.net.NioEndpoint$SocketProcessor|run |NioEndpoint.java |1456| |java.util.concurrent.ThreadPoolExecutor |runWorker |ThreadPoolExecutor.java |1142| |java.util.concurrent.ThreadPoolExecutor$Worker |run |ThreadPoolExecutor.java | 617| |che.tomcat.util.threads.TaskThread$WrappingRunnable|run |TaskThread.java | 61| |java.lang.Thread |run |Thread.java | 745| +----------------------------------------------------------------------------------------------------------------------+"} 2015-11-18 15:53:47,037 WARN XFormsServer - instance() - instance not found {instance id: "fr-create-update-submission-response"} 2015-11-18 15:53:47,041 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "fr", "message": "end: process", "time": "177 ms"} 2015-11-18 15:53:47,060 WARN XFormsServer - instance() - instance not found {instance id: "fr-create-update-submission-response"} 2015-11-18 15:53:47,083 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"} 2015-11-18 15:53:47,083 INFO ProcessorService - /xforms-server - Timing: 253 2015-11-18 15:53:47,084 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "254 ms"} 2015-11-18 15:53:47,105 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "276 ms"} 2015-11-18 15:53:47,105 INFO lifecycle - event: {"request": "27", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "278 ms"} 2015-11-18 15:53:48,994 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: filter", "path": "/xforms-server", "method": "POST"} 2015-11-18 15:53:48,995 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "start: chain", "path": "/xforms-server", "method": "POST", "wait": "0"} 2015-11-18 15:53:48,997 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle"} 2015-11-18 15:53:48,997 INFO ProcessorService - /xforms-server - Received request 2015-11-18 15:53:49,006 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "ajax with update events", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:49,006 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "before document lock", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f"} 2015-11-18 15:53:49,006 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "got document lock", "path": "/xforms-server", "method": "POST", "uuid": "2ac54815085aa50371c8637474ce9dce8a1e8a1f", "wait": "0"} 2015-11-18 15:53:49,045 INFO lifecycle - event: {"request": "31", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/persistence/crud/test/test/form/form.xhtml", "method": "PUT"} 2015-11-18 15:53:49,045 INFO ProcessorService - /fr/service/persistence/crud/test/test/form/form.xhtml - Received request 2015-11-18 15:53:49,059 INFO lifecycle - event: {"request": "32", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "start: handle", "path": "/fr/service/mysql/crud/test/test/form/form.xhtml", "method": "PUT"} 2015-11-18 15:53:49,059 INFO ProcessorService - /fr/service/mysql/crud/test/test/form/form.xhtml - Received request 2015-11-18 15:53:49,409 ERROR PageFlowControllerProcessor - error caught {controller: "oxf:/apps/fr/page-flow.xml", method: "PUT", path: "/fr/service/mysql/crud/test/test/form/form.xhtml"} 2015-11-18 15:53:49,431 ERROR PageFlowControllerProcessor - +----------------------------------------------------------------------------------------------------------------------+ |An Error has Occurred | |----------------------------------------------------------------------------------------------------------------------| |Unknown column 'form_metadata' in 'field list' | |----------------------------------------------------------------------------------------------------------------------| |Application Call Stack | |----------------------------------------------------------------------------------------------------------------------| |oxf:/apps/fr/page-flow.xml |reading page model data output| 42| |······················································································································| |element=<service path="/fr/service/(oracle|mysql|postgresql|db2|sqlserver)/crud/([^/]+/[^/]+/(form/[^/]+|(data|draft)/| |model =persistence/relational/crud.xpl | |----------------------------------------------------------------------------------------------------------------------| |oxf:/apps/fr/persistence/relational/crud.xpl |executing processor | 17| |······················································································································| |element=<p:processor name="fr:relational-crud"/> | |name ={http://orbeon.org/oxf/xml/form-runner}relational-crud | |----------------------------------------------------------------------------------------------------------------------| |----------------------------------------------------------------------------------------------------------------------| |Exception: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException | |----------------------------------------------------------------------------------------------------------------------| |sun.reflect.NativeConstructorAccessorImpl |newInstance0 |NativeConstructorAccessorImpl.| | |sun.reflect.NativeConstructorAccessorImpl |newInstance |NativeConstructorAccessorImpl.| 62| |sun.reflect.DelegatingConstructorAccessorImpl |newInstance |DelegatingConstructorAccessorI| 45| |java.lang.reflect.Constructor |newInstance |Constructor.java | 422| |com.mysql.jdbc.Util |handleNewInstance |Util.java | 411| |com.mysql.jdbc.Util |getInstance |Util.java | 386| |com.mysql.jdbc.SQLError |createSQLException |SQLError.java |1053| |com.mysql.jdbc.MysqlIO |checkErrorPacket |MysqlIO.java |4096| |com.mysql.jdbc.MysqlIO |checkErrorPacket |MysqlIO.java |4028| |com.mysql.jdbc.MysqlIO |sendCommand |MysqlIO.java |2490| |com.mysql.jdbc.MysqlIO |sqlQueryDirect |MysqlIO.java |2651| |com.mysql.jdbc.ConnectionImpl |execSQL |ConnectionImpl.java |2734| |com.mysql.jdbc.PreparedStatement |executeInternal |PreparedStatement.java |2155| |com.mysql.jdbc.PreparedStatement |executeUpdate |PreparedStatement.java |2458| |com.mysql.jdbc.PreparedStatement |executeUpdate |PreparedStatement.java |2375| |com.mysql.jdbc.PreparedStatement |executeUpdate |PreparedStatement.java |2359| |pache.tomcat.dbcp.dbcp2.DelegatingPreparedStatement|executeUpdate |DelegatingPreparedStatement.ja| 97| |pache.tomcat.dbcp.dbcp2.DelegatingPreparedStatement|executeUpdate |DelegatingPreparedStatement.ja| 97| |pache.tomcat.dbcp.dbcp2.DelegatingPreparedStatement|executeUpdate |DelegatingPreparedStatement.ja| 97| |eon.oxf.fr.relational.crud.CreateUpdateDelete$class|org$orbeon$oxf$fr$relational$c|CreateUpdateDelete.scala | 231| |elational.crud.CreateUpdateDelete$$anonfun$change$1|apply |CreateUpdateDelete.scala | 409| |elational.crud.CreateUpdateDelete$$anonfun$change$1|apply |CreateUpdateDelete.scala | 327| |org.orbeon.oxf.util.ScalaUtils$ |useAndClose |ScalaUtils.scala | 68| |org.orbeon.oxf.fr.relational.RelationalUtils$ |withConnection |RelationalUtils.scala | 33| |eon.oxf.fr.relational.crud.CreateUpdateDelete$class|change |CreateUpdateDelete.scala | 327| |org.orbeon.oxf.fr.relational.crud.CRUD |change |CRUD.scala | 22| |org.orbeon.oxf.fr.relational.crud.CRUD |start |CRUD.scala | 39| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$4|run |PipelineProcessor.java | 618| |----------------------------------------------------------------------------------------------------------------------| |Exception: org.orbeon.oxf.common.ValidationException | |----------------------------------------------------------------------------------------------------------------------| |org.orbeon.oxf.common.OrbeonLocationException$ |wrapException |OrbeonLocationException.scala | 60| |org.orbeon.oxf.common.OrbeonLocationException |wrapException |OrbeonLocationException.scala | | |g.orbeon.oxf.processor.pipeline.PipelineProcessor$4|run |PipelineProcessor.java | 620| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeChildren |PipelineProcessor.java | 680| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|start |PipelineProcessor.java | 615| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$4|run |PipelineProcessor.java | 618| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeChildren |PipelineProcessor.java | 680| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|start |PipelineProcessor.java | 615| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$1|getInput |PipelineProcessor.java | 141| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$1|readImpl |PipelineProcessor.java | 84| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |processor.pipeline.choose.ConcreteChooseProcessor$1|readImpl |ConcreteChooseProcessor.java | 126| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 266| |org.orbeon.oxf.processor.IdentityProcessor$1 |readImpl |IdentityProcessor.java | 33| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |orbeon.oxf.processor.pipeline.PipelineProcessor$1$1|run |PipelineProcessor.java | 93| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeChildren |PipelineProcessor.java | 680| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|access$000 |PipelineProcessor.java | 60| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$1|readImpl |PipelineProcessor.java | 91| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 266| |org.orbeon.oxf.processor.IdentityProcessor$1 |readImpl |IdentityProcessor.java | 33| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 266| |org.orbeon.oxf.processor.IdentityProcessor$1 |readImpl |IdentityProcessor.java | 33| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |orbeon.oxf.processor.pipeline.PipelineProcessor$1$1|run |PipelineProcessor.java | 93| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeChildren |PipelineProcessor.java | 680| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|access$000 |PipelineProcessor.java | 60| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$1|readImpl |PipelineProcessor.java | 91| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |processor.pipeline.choose.ConcreteChooseProcessor$1|readImpl |ConcreteChooseProcessor.java | 126| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |ssor.pipeline.PipelineProcessor$InternalTopOutput$1|run |PipelineProcessor.java | 536| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeParents |PipelineProcessor.java | 697| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|access$400 |PipelineProcessor.java | 60| |cessor.pipeline.PipelineProcessor$InternalTopOutput|readImpl |PipelineProcessor.java | 532| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 266| |org.orbeon.oxf.processor.NullSerializer |start |NullSerializer.java | 28| |----------------------------------------------------------------------------------------------------------------------| |Exception: org.orbeon.oxf.common.OXFException | |----------------------------------------------------------------------------------------------------------------------| |org.orbeon.oxf.processor.NullSerializer |start |NullSerializer.java | 30| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$4|run |PipelineProcessor.java | 618| |----------------------------------------------------------------------------------------------------------------------| |Exception: org.orbeon.oxf.common.ValidationException | |----------------------------------------------------------------------------------------------------------------------| |org.orbeon.oxf.common.OrbeonLocationException$ |wrapException |OrbeonLocationException.scala | 60| |org.orbeon.oxf.common.OrbeonLocationException |wrapException |OrbeonLocationException.scala | | |g.orbeon.oxf.processor.pipeline.PipelineProcessor$4|run |PipelineProcessor.java | 620| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeChildren |PipelineProcessor.java | 680| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|start |PipelineProcessor.java | 615| |f.processor.pipeline.choose.ConcreteChooseProcessor|start |ConcreteChooseProcessor.java | 231| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$4|run |PipelineProcessor.java | 618| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeChildren |PipelineProcessor.java | 680| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|start |PipelineProcessor.java | 615| |ller.PageFlowControllerProcessor$PageOrServiceRoute|process |PageFlowControllerProcessor.sc| 572| |g.orbeon.oxf.controller.PageFlowControllerProcessor|start |PageFlowControllerProcessor.sc| 172| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$4|run |PipelineProcessor.java | 618| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeChildren |PipelineProcessor.java | 680| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|start |PipelineProcessor.java | 615| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$1|getInput |PipelineProcessor.java | 141| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$1|readImpl |PipelineProcessor.java | 84| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |processor.pipeline.choose.ConcreteChooseProcessor$1|readImpl |ConcreteChooseProcessor.java | 126| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 266| |org.orbeon.oxf.processor.IdentityProcessor$1 |readImpl |IdentityProcessor.java | 33| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |orbeon.oxf.processor.pipeline.PipelineProcessor$1$1|run |PipelineProcessor.java | 93| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|executeChildren |PipelineProcessor.java | 680| |org.orbeon.oxf.processor.pipeline.PipelineProcessor|access$000 |PipelineProcessor.java | 60| |g.orbeon.oxf.processor.pipeline.PipelineProcessor$1|readImpl |PipelineProcessor.java | 91| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 266| |org.orbeon.oxf.processor.IdentityProcessor$1 |readImpl |IdentityProcessor.java | 33| |essor.impl.ProcessorOutputImpl$TopLevelOutputFilter|read |ProcessorOutputImpl.java | 257| |org.orbeon.oxf.processor.impl.ProcessorOutputImpl |read |ProcessorOutputImpl.java | 394| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 262| |org.orbeon.oxf.processor.ProcessorImpl |readInputAsSAX |ProcessorImpl.java | 266| |---8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<---| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.apache.tomcat.websocket.server.WsFilter |doFilter |WsFilter.java | 52| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |g.netbeans.modules.web.monitor.server.MonitorFilter|doFilter |MonitorFilter.java | 393| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.orbeon.oxf.servlet.FormRunnerAuthFilter |doFilter |FormRunnerAuthFilter.scala | 26| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply$mcV$sp |LimiterFilter.scala | 85| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply |LimiterFilter.scala | 85| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply |LimiterFilter.scala | 85| |org.orbeon.oxf.logging.LifecycleLogger$ |withEvent |LifecycleLogger.scala | 124| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply$mcV$sp |LimiterFilter.scala | 84| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply |LimiterFilter.scala | 78| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply |LimiterFilter.scala | 78| |org.orbeon.oxf.logging.LifecycleLogger$ |withEvent |LifecycleLogger.scala | 124| |rbeon.oxf.servlet.LimiterFilter$$anonfun$doFilter$1|apply |LimiterFilter.scala | 78| |rbeon.oxf.servlet.LimiterFilter$$anonfun$doFilter$1|apply |LimiterFilter.scala | 72| |scala.Option |foreach |Option.scala | 257| |org.orbeon.oxf.servlet.LimiterFilter |doFilter |LimiterFilter.scala | 72| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.apache.catalina.core.StandardWrapperValve |invoke |StandardWrapperValve.java | 217| |org.apache.catalina.core.StandardContextValve |invoke |StandardContextValve.java | 106| |org.apache.catalina.authenticator.AuthenticatorBase|invoke |AuthenticatorBase.java | 502| |org.apache.catalina.core.StandardHostValve |invoke |StandardHostValve.java | 142| |org.apache.catalina.valves.ErrorReportValve |invoke |ErrorReportValve.java | 79| |org.apache.catalina.valves.AbstractAccessLogValve |invoke |AbstractAccessLogValve.java | 616| |org.apache.catalina.core.StandardEngineValve |invoke |StandardEngineValve.java | 88| |org.apache.catalina.connector.CoyoteAdapter |service |CoyoteAdapter.java | 518| |org.apache.coyote.http11.AbstractHttp11Processor |process |AbstractHttp11Processor.java |1091| |e.coyote.AbstractProtocol$AbstractConnectionHandler|process |AbstractProtocol.java | 673| |.apache.tomcat.util.net.NioEndpoint$SocketProcessor|doRun |NioEndpoint.java |1500| |.apache.tomcat.util.net.NioEndpoint$SocketProcessor|run |NioEndpoint.java |1456| |java.util.concurrent.ThreadPoolExecutor |runWorker |ThreadPoolExecutor.java |1142| |java.util.concurrent.ThreadPoolExecutor$Worker |run |ThreadPoolExecutor.java | 617| |che.tomcat.util.threads.TaskThread$WrappingRunnable|run |TaskThread.java | 61| |java.lang.Thread |run |Thread.java | 745| +----------------------------------------------------------------------------------------------------------------------+ 2015-11-18 15:53:49,431 INFO ProcessorService - /fr/service/mysql/crud/test/test/form/form.xhtml - Timing: 372 2015-11-18 15:53:49,433 INFO lifecycle - event: {"request": "32", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "373 ms"} 2015-11-18 15:53:49,433 INFO ProcessorService - /fr/service/persistence/crud/test/test/form/form.xhtml - Timing: 388 2015-11-18 15:53:49,434 INFO lifecycle - event: {"request": "31", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "389 ms"} 2015-11-18 15:53:49,437 ERROR XFormsServer - xforms-submit-error - setting throwable {throwable: " +----------------------------------------------------------------------------------------------------------------------+ |An Error has Occurred | |----------------------------------------------------------------------------------------------------------------------| |xf:submission for submission id: fr-create-update-submission, error code received when submitting instance: 500 | |----------------------------------------------------------------------------------------------------------------------| |Application Call Stack | |----------------------------------------------------------------------------------------------------------------------| |----------------------------------------------------------------------------------------------------------------------| |Exception: org.orbeon.oxf.xforms.submission.XFormsSubmissionException | |----------------------------------------------------------------------------------------------------------------------| |.orbeon.oxf.xforms.submission.XFormsModelSubmission|getReplacer |XFormsModelSubmission.java | 729| |rg.orbeon.oxf.xforms.submission.RegularSubmission$1|call |RegularSubmission.java | 106| |rg.orbeon.oxf.xforms.submission.RegularSubmission$1|call |RegularSubmission.java | 76| |org.orbeon.oxf.xforms.submission.BaseSubmission |submitCallable |BaseSubmission.scala | 89| |org.orbeon.oxf.xforms.submission.RegularSubmission |connect |RegularSubmission.java | 129| |.orbeon.oxf.xforms.submission.XFormsModelSubmission|doSubmit |XFormsModelSubmission.java | 460| |.orbeon.oxf.xforms.submission.XFormsModelSubmission|performDefaultAction |XFormsModelSubmission.java | 292| |.oxf.xforms.event.Dispatch$$anonfun$dispatchEvent$1|apply$mcV$sp |Dispatch.scala | 138| |.oxf.xforms.event.Dispatch$$anonfun$dispatchEvent$1|apply |Dispatch.scala | 73| |.oxf.xforms.event.Dispatch$$anonfun$dispatchEvent$1|apply |Dispatch.scala | 73| |org.orbeon.oxf.xforms.event.Dispatch$ |withEvent$1 |Dispatch.scala | 38| |org.orbeon.oxf.xforms.event.Dispatch$ |dispatchEvent |Dispatch.scala | 72| |.orbeon.oxf.xforms.action.XFormsAPI$$anonfun$send$1|apply |XFormsAPI.scala | 279| |.orbeon.oxf.xforms.action.XFormsAPI$$anonfun$send$1|apply |XFormsAPI.scala | 266| |scala.Option |flatMap |Option.scala | 171| |org.orbeon.oxf.xforms.action.XFormsAPI$ |send |XFormsAPI.scala | 266| |org.orbeon.oxf.xforms.action.XFormsAPI$ |sendThrowOnError |XFormsAPI.scala | 297| |org.orbeon.oxf.fr.FormRunnerPersistence$class |saveData$1 |FormRunnerPersistence.scala | 273| |org.orbeon.oxf.fr.FormRunnerPersistence$class |putWithAttachments |FormRunnerPersistence.scala | 288| |org.orbeon.oxf.fr.FormRunner$ |putWithAttachments |FormRunner.scala | 18| |org.orbeon.oxf.fb.PublishOps$class |publish |PublishOps.scala | 32| |org.orbeon.oxf.fb.FormBuilder$ |publish |FormBuilder.scala | 21| |org.orbeon.oxf.fb.FormBuilder |publish |FormBuilder.scala | | |sun.reflect.NativeMethodAccessorImpl |invoke0 |NativeMethodAccessorImpl.java | | |sun.reflect.NativeMethodAccessorImpl |invoke |NativeMethodAccessorImpl.java | 62| |sun.reflect.DelegatingMethodAccessorImpl |invoke |DelegatingMethodAccessorImpl.j| 43| |java.lang.reflect.Method |invoke |Method.java | 497| |org.orbeon.saxon.functions.ExtensionFunctionCall |invokeMethod |ExtensionFunctionCall.java | 690| |org.orbeon.saxon.functions.ExtensionFunctionCall |call |ExtensionFunctionCall.java | 343| |org.orbeon.saxon.functions.ExtensionFunctionCall |iterate |ExtensionFunctionCall.java | 224| |org.orbeon.saxon.expr.ForExpression$MappingAction |map |ForExpression.java | 775| |org.orbeon.saxon.expr.MappingIterator |next |MappingIterator.java | 54| |org.orbeon.scaxon.XML$$anon$2 |<init> |XML.scala | 654| |org.orbeon.scaxon.XML$ |asScalaIterator |XML.scala | 652| |.PooledXPathExpression$$anonfun$evaluateKeepItems$1|apply |PooledXPathExpression.scala | 93| |.PooledXPathExpression$$anonfun$evaluateKeepItems$1|apply |PooledXPathExpression.scala | 93| |org.orbeon.oxf.util.DynamicVariable |withValue |DynamicVariable.scala | 42| |org.orbeon.oxf.util.XPath$ |withFunctionContext |XPath.scala | 61| |org.orbeon.oxf.util.PooledXPathExpression |evaluateKeepItems |PooledXPathExpression.scala | 92| |on.oxf.util.XPathCache$$anonfun$evaluateKeepItems$1|apply |XPathCache.scala | 153| |---8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<--------8<---| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.apache.tomcat.websocket.server.WsFilter |doFilter |WsFilter.java | 52| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |g.netbeans.modules.web.monitor.server.MonitorFilter|doFilter |MonitorFilter.java | 393| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.orbeon.oxf.servlet.FormRunnerAuthFilter |doFilter |FormRunnerAuthFilter.scala | 26| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply$mcV$sp |LimiterFilter.scala | 85| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply |LimiterFilter.scala | 85| |doFilter$1$$anonfun$apply$1$$anonfun$apply$mcV$sp$1|apply |LimiterFilter.scala | 85| |org.orbeon.oxf.logging.LifecycleLogger$ |withEvent |LifecycleLogger.scala | 124| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply$mcV$sp |LimiterFilter.scala | 84| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply |LimiterFilter.scala | 78| |.LimiterFilter$$anonfun$doFilter$1$$anonfun$apply$1|apply |LimiterFilter.scala | 78| |org.orbeon.oxf.logging.LifecycleLogger$ |withEvent |LifecycleLogger.scala | 124| |rbeon.oxf.servlet.LimiterFilter$$anonfun$doFilter$1|apply |LimiterFilter.scala | 78| |rbeon.oxf.servlet.LimiterFilter$$anonfun$doFilter$1|apply |LimiterFilter.scala | 72| |scala.Option |foreach |Option.scala | 257| |org.orbeon.oxf.servlet.LimiterFilter |doFilter |LimiterFilter.scala | 72| |org.apache.catalina.core.ApplicationFilterChain |internalDoFilter |ApplicationFilterChain.java | 239| |org.apache.catalina.core.ApplicationFilterChain |doFilter |ApplicationFilterChain.java | 206| |org.apache.catalina.core.StandardWrapperValve |invoke |StandardWrapperValve.java | 217| |org.apache.catalina.core.StandardContextValve |invoke |StandardContextValve.java | 106| |org.apache.catalina.authenticator.AuthenticatorBase|invoke |AuthenticatorBase.java | 502| |org.apache.catalina.core.StandardHostValve |invoke |StandardHostValve.java | 142| |org.apache.catalina.valves.ErrorReportValve |invoke |ErrorReportValve.java | 79| |org.apache.catalina.valves.AbstractAccessLogValve |invoke |AbstractAccessLogValve.java | 616| |org.apache.catalina.core.StandardEngineValve |invoke |StandardEngineValve.java | 88| |org.apache.catalina.connector.CoyoteAdapter |service |CoyoteAdapter.java | 518| |org.apache.coyote.http11.AbstractHttp11Processor |process |AbstractHttp11Processor.java |1091| |e.coyote.AbstractProtocol$AbstractConnectionHandler|process |AbstractProtocol.java | 673| |.apache.tomcat.util.net.NioEndpoint$SocketProcessor|doRun |NioEndpoint.java |1500| |.apache.tomcat.util.net.NioEndpoint$SocketProcessor|run |NioEndpoint.java |1456| |java.util.concurrent.ThreadPoolExecutor |runWorker |ThreadPoolExecutor.java |1142| |java.util.concurrent.ThreadPoolExecutor$Worker |run |ThreadPoolExecutor.java | 617| |che.tomcat.util.threads.TaskThread$WrappingRunnable|run |TaskThread.java | 61| |java.lang.Thread |run |Thread.java | 745| +----------------------------------------------------------------------------------------------------------------------+"} 2015-11-18 15:53:49,456 WARN XFormsServer - instance() - instance not found {instance id: "fr-create-update-submission-response"} 2015-11-18 15:53:49,474 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "xforms", "message": "after cacheOrStore", "document cache current size": "2", "document cache max size": "50"} 2015-11-18 15:53:49,475 INFO ProcessorService - /xforms-server - Timing: 477 2015-11-18 15:53:49,475 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "service", "message": "end: handle", "time": "478 ms"} 2015-11-18 15:53:49,488 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: chain", "time": "493 ms"} 2015-11-18 15:53:49,488 INFO lifecycle - event: {"request": "30", "session": "AF52BB39F561F8171619E1ACF74DB646", "source": "limiter", "message": "end: filter", "time": "494 ms"} |
Administrator
|
Hi Askerion,
Let's figure this out! If saving works, you're already in a good place, as saving and publishing, under the hood, are very similar. The significant error message is: "Unknown column 'form_metadata' in 'field list'". I suspect that you have outdated tables in your database that are missing columns. On the section linked below, see the "DDL to create the tables from scratch" for the version you're using, and notice it has a form_metadata column in orbeon_form_definition. If your tables are for a previous version, you can "upgrade" them by running the upgrade DDL linked from the same section. http://doc.orbeon.com/form-runner/persistence/relational-db.html#mysql You'll let us know how this works out, Alex
--
Follow Orbeon on Twitter: @orbeon Follow me on Twitter: @avernet |
Many thanks,
it works |
Administrator
|
Askerion, excellent, I'm glad this was it, and thanks for the update.
Alex
--
Follow Orbeon on Twitter: @orbeon Follow me on Twitter: @avernet |
Free forum by Nabble | Edit this page |