Error on puplishing Form

classic Classic list List threaded Threaded
4 messages Options
Reply | Threaded
Open this post in threaded view
|

Error on puplishing Form

Askerion
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.log

2015-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"}



Reply | Threaded
Open this post in threaded view
|

Re: Error on puplishing Form

Alessandro  Vernet
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
Reply | Threaded
Open this post in threaded view
|

Re: Error on puplishing Form

Askerion
Many thanks,
it works
Reply | Threaded
Open this post in threaded view
|

Re: Error on puplishing Form

Alessandro  Vernet
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