anager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,748 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,757 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,772 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,772 WARN [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,773 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,775 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,782 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,785 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,786 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,791 WARN [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,796 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,797 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,805 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,814 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,816 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,825 WARN [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,830 WARN [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,835 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,842 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,847 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,848 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,852 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,854 WARN [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,856 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,873 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,873 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,879 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,891 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,892 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,899 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,903 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,908 WARN [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,909 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,914 WARN [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,916 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,921 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,927 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,929 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,930 WARN [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,935 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,952 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,953 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,969 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,971 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,975 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,979 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,987 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,990 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,994 WARN [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:02,999 WARN [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,009 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,010 WARN [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,012 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,013 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,019 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,021 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,032 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,042 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,044 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,053 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,058 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,059 WARN [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,071 WARN [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,072 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,073 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,077 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,081 WARN [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,086 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,091 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,092 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,093 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,097 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,098 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,100 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,121 WARN [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,129 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,136 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,140 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,142 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,151 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,152 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,157 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,168 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,171 WARN [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,173 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,176 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,178 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,179 WARN [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,180 WARN [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,181 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,186 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,197 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,206 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,211 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,225 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,234 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,234 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,237 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,241 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,247 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,250 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,251 WARN [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,252 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,263 WARN [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,267 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,269 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,275 WARN [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,284 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,290 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,292 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,294 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,295 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,306 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,307 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,330 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,337 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,340 WARN [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,345 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,346 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,347 WARN [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,352 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,357 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,362 WARN [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,366 WARN [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,373 WARN [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,375 WARN [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,380 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,384 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,395 WARN [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,408 WARN [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,410 WARN [qtp1100829986-414][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,411 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,411 WARN [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:03,416 WARN [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:48:05,763 DEBUG [CryptoCache][CryptoCache$CleanupTask] run: entered
20:48:05,763 INFO [CryptoCache][CryptoCache$CleanupTask] run: CryptoCache was garbage-collected. Cancelling this TimerTask.
20:48:11,033 DEBUG [AbstractCryptoManager][AbstractCryptoManager$CloseExpiredSessionsTask] run: entered
20:48:11,033 INFO [AbstractCryptoManager][AbstractCryptoManager$CloseExpiredSessionsTask] run: AbstractCryptoManager was garbage-collected. Cancelling this TimerTask.
20:48:12,908 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
20:48:13,862 DEBUG [SessionManager][SessionManager$ExpireSessionTimerTask] run: 1 sessions left.
20:48:15,896 DEBUG [AbstractCryptoManager][AbstractCryptoManager$CloseExpiredSessionsTask] run: entered
20:48:21,397 DEBUG [CryptoCache][CryptoCache$CleanupTask] run: entered
20:48:21,398 INFO [CryptoCache][CryptoCache$CleanupTask] run: CryptoCache was garbage-collected. Cancelling this TimerTask.
20:48:21,992 DEBUG [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: Returning request: null
20:48:22,015 DEBUG [Thread-2][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:48:22,036 DEBUG [qtp1100829986-442][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:48:22,041 DEBUG [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12ggi42-jpn7g9lums1_q8nvb3hr]: Entered.
20:48:23,600 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
20:48:23,651 DEBUG [SessionManager][SessionManager$ExpireSessionTimerTask] run: 1 sessions left.
20:48:24,724 DEBUG [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: Returning request: null
20:48:24,728 DEBUG [Thread-9][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:48:24,733 DEBUG [qtp1100829986-430][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:48:24,737 DEBUG [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: Entered.
20:48:25,335 DEBUG [CryptoCache][CryptoCache$CleanupTask] run: entered
20:48:25,335 INFO [CryptoCache][CryptoCache] getCryptoCacheEntryExpiryAgeMSec: Property 'cumulus4j.CryptoCache.entryExpiryAge' cannot be read, because NucleusContext was garbage-collected. Using fallback value 300000.
20:48:25,335 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyEncryptionKeyEntry (1 left).
20:48:25,335 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyDecrypterEntry (1 left).
20:48:25,335 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyEntry (1 left).
20:48:25,336 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheCipherEntry (2 left).
20:48:25,336 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of empty List<CryptoCacheCipherEntry> (2 non-empty lists left).
20:48:25,870 DEBUG [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: Returning request: null
20:48:25,873 DEBUG [Thread-12][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:48:25,878 DEBUG [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: Returning request: null
20:48:25,882 DEBUG [Thread-10][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:48:25,889 DEBUG [qtp1100829986-436][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:48:25,890 DEBUG [qtp1100829986-453][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:48:25,894 DEBUG [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: Entered.
20:48:25,905 DEBUG [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gi120--54zz8bsz7ofp_0sbk7wqb]: Entered.
20:48:28,227 INFO [qtp1100829986-445][Cumulus4jStoreManager] ====================== Cumulus4j ======================
20:48:28,227 INFO [qtp1100829986-445][Cumulus4jStoreManager] Bundle: org.cumulus4j.store - Version: 1.2.1.SNAPSHOT
20:48:28,227 INFO [qtp1100829986-445][Cumulus4jStoreManager] =======================================================
20:48:28,228 ERROR [qtp1100829986-445][Persistence] java.lang.reflect.InvocationTargetException
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
at org.datanucleus.plugin.NonManagedPluginRegistry.createExecutableExtension(NonManagedPluginRegistry.java:631)
at org.datanucleus.plugin.PluginManager.createExecutableExtension(PluginManager.java:301)
at org.datanucleus.NucleusContext.initialiseAutoStart(NucleusContext.java:432)
at org.datanucleus.NucleusContext.initialise(NucleusContext.java:384)
at org.datanucleus.api.jdo.JDOPersistenceManagerFactory.freezeConfiguration(JDOPersistenceManagerFactory.java:768)
at org.datanucleus.api.jdo.JDOPersistenceManagerFactory.createPersistenceManagerFactory(JDOPersistenceManagerFactory.java:326)
at org.datanucleus.api.jdo.JDOPersistenceManagerFactory.getPersistenceManagerFactory(JDOPersistenceManagerFactory.java:195)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at javax.jdo.JDOHelper$16.run(JDOHelper.java:1970)
at java.security.AccessController.doPrivileged(Native Method)
at javax.jdo.JDOHelper.invoke(JDOHelper.java:1965)
at javax.jdo.JDOHelper.invokeGetPersistenceManagerFactoryOnImplementation(JDOHelper.java:1171)
at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:813)
at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:702)
at org.cumulus4j.integrationtest.webapp.TestService.getPersistenceManagerFactory(TestService.java:59)
at org.cumulus4j.integrationtest.webapp.TestService.getPersistenceManager(TestService.java:72)
at org.cumulus4j.integrationtest.webapp.TestService.testPost(TestService.java:93)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.sun.jersey.spi.container.JavaMethodInvokerFactory$1.invoke(JavaMethodInvokerFactory.java:60)
at com.sun.jersey.server.impl.model.method.dispatch.AbstractResourceMethodDispatchProvider$TypeOutInvoker._dispatch(AbstractResourceMethodDispatchProvider.java:185)
at com.sun.jersey.server.impl.model.method.dispatch.ResourceJavaMethodDispatcher.dispatch(ResourceJavaMethodDispatcher.java:75)
at com.sun.jersey.server.impl.uri.rules.HttpMethodRule.accept(HttpMethodRule.java:288)
at com.sun.jersey.server.impl.uri.rules.ResourceClassRule.accept(ResourceClassRule.java:108)
at com.sun.jersey.server.impl.uri.rules.RightHandPathRule.accept(RightHandPathRule.java:147)
at com.sun.jersey.server.impl.uri.rules.RootResourceClassesRule.accept(RootResourceClassesRule.java:84)
at com.sun.jersey.server.impl.application.WebApplicationImpl._handleRequest(WebApplicationImpl.java:1469)
at com.sun.jersey.server.impl.application.WebApplicationImpl._handleRequest(WebApplicationImpl.java:1400)
at com.sun.jersey.server.impl.application.WebApplicationImpl.handleRequest(WebApplicationImpl.java:1349)
at com.sun.jersey.server.impl.application.WebApplicationImpl.handleRequest(WebApplicationImpl.java:1339)
at com.sun.jersey.spi.container.servlet.WebComponent.service(WebComponent.java:416)
at com.sun.jersey.spi.container.servlet.ServletContainer.service(ServletContainer.java:537)
at com.sun.jersey.spi.container.servlet.ServletContainer.service(ServletContainer.java:699)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:847)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:546)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1283)
at org.cumulus4j.keymanager.back.shared.AjaxHeadersFilter.doFilter(AjaxHeadersFilter.java:33)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1254)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:481)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:516)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:230)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:956)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:411)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:188)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:891)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:117)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:247)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:151)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:114)
at org.eclipse.jetty.server.Server.handle(Server.java:353)
at org.eclipse.jetty.server.HttpConnection.handleRequest(HttpConnection.java:598)
at org.eclipse.jetty.server.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:1059)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:590)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:212)
at org.eclipse.jetty.server.HttpConnection.handle(HttpConnection.java:427)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:510)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint.access$000(SelectChannelEndPoint.java:34)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:40)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:450)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.ClassCastException: org.cumulus4j.store.Cumulus4jStoreManager cannot be cast to org.datanucleus.store.rdbms.RDBMSStoreManager
at org.datanucleus.store.rdbms.autostart.SchemaAutoStarter.<init>(SchemaAutoStarter.java:68)
... 70 more
20:48:28,229 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Movie
20:48:28,229 INFO [qtp1100829986-445][AbstractCryptoManager] getCryptoSessionExpiryTimerPeriod: Property 'cumulus4j.cryptoSessionExpiryTimer.period' is not set. Using default value 60000.
20:48:28,230 TRACE [qtp1100829986-445][AbstractCryptoSession] setCryptoSessionID: cryptoSessionID=test-8279361546655905561_iauy3qr1*1*0bgvu3
20:48:28,230 INFO [qtp1100829986-445][AbstractCryptoManager] getCryptoSessionExpiryAgeMSec: Property 'cumulus4j.cryptoSessionExpiryAge' is not set. Using default value 1800000.
20:48:28,764 INFO [qtp1100829986-445][MinimumCumulus4jVersion] version=10100001
20:48:29,402 INFO [qtp1100829986-445][MinimumCumulus4jVersion] version=10100001
20:48:29,402 DEBUG [qtp1100829986-445][RecreateIndex] deleteIndex: Entered.
20:48:29,588 DEBUG [qtp1100829986-445][RecreateIndex] deleteIndex: There are no IndexEntry instances in the database => quit.
20:48:29,588 DEBUG [qtp1100829986-445][RecreateIndex] deleteIndex: Leaving.
20:48:29,646 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Movie
20:48:29,667 DEBUG [qtp1100829986-445][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@5ccdaca1[null,org.cumulus4j.store.test.movie.Movie]
20:48:29,676 DEBUG [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,694 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@3c00aee4[null,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,705 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,705 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,721 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,721 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,721 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,722 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@4a86d2ec[null,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:48:29,725 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@4a86d2ec[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:48:29,726 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,726 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,727 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@5239c87d[null,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,729 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,729 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,730 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,730 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,730 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,731 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@62ce1ec3[null,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:48:29,734 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@62ce1ec3[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:48:29,735 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,736 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,736 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@43d41231[null,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,740 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,740 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@43d41231[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,741 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@43d41231[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,741 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,741 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@57c02e03[null,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,743 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,744 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@57c02e03[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,745 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@57c02e03[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,745 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,745 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@309b3d67[null,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,747 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,748 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,749 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,749 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,749 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,750 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@1c05b903[null,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:48:29,753 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@1c05b903[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:48:29,754 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,754 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,754 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@1d440b3[null,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,757 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,757 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@1d440b3[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,758 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1d440b3[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,758 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,758 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@544b4c59[null,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,761 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,762 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,763 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,763 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,763 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,763 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@333b4de5[null,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:48:29,766 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@333b4de5[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:48:29,767 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,767 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,768 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@78ac1040[null,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,772 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,773 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@78ac1040[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,774 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@78ac1040[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,774 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,774 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@34a87a07[null,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,776 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,777 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,778 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,778 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,778 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,778 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@473af810[null,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:48:29,780 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@473af810[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:48:29,781 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,782 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,782 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,782 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,782 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@43d41231[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,782 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@57c02e03[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,782 DEBUG [qtp1100829986-445][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@57c02e03[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,808 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,808 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1d440b3[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,808 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,808 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@78ac1040[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,808 DEBUG [qtp1100829986-445][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@78ac1040[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,811 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,812 DEBUG [qtp1100829986-445][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,812 DEBUG [qtp1100829986-445][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,812 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ccdaca1[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,813 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@43d41231[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,813 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@43d41231[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,813 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@43d41231[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,814 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@43d41231[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,815 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@78ac1040[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,815 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@78ac1040[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,815 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@78ac1040[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,816 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@78ac1040[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,816 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,818 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,818 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,818 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@62ce1ec3[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:48:29,818 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@62ce1ec3[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:48:29,818 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@62ce1ec3[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:48:29,819 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@62ce1ec3[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:48:29,819 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@5239c87d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,819 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,819 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,819 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,820 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@1c05b903[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:48:29,820 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@1c05b903[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:48:29,820 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1c05b903[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:48:29,820 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@1c05b903[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:48:29,820 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@309b3d67[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,820 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@57c02e03[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,821 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@57c02e03[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,821 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@57c02e03[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,821 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@57c02e03[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,821 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,821 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,822 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,822 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@4a86d2ec[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:48:29,822 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@4a86d2ec[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:48:29,822 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4a86d2ec[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:48:29,822 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@4a86d2ec[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:48:29,822 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@3c00aee4[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,823 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@1d440b3[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,823 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@1d440b3[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,823 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1d440b3[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,823 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@1d440b3[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,824 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,824 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,824 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,824 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@333b4de5[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:48:29,824 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@333b4de5[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:48:29,824 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@333b4de5[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:48:29,825 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@333b4de5[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:48:29,825 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@544b4c59[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,825 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,825 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,825 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,826 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@473af810[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:48:29,826 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@473af810[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:48:29,826 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@473af810[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:48:29,826 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@473af810[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:48:29,826 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@34a87a07[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,828 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,829 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6d389e5a[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:48:29,829 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@20245ebb[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,829 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@643fe37d[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:48:29,829 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1e7056f6[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:48:29,829 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@311a964a[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,829 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6a3fa080[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:48:29,829 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@774269fe[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,830 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@153171cc[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:48:29,830 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@612612f2[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:48:29,830 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@aa5ffb2[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:48:29,830 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@18360494[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,830 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2669cf7[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:48:29,830 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1ecb1d5c[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,830 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@28f492cf[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:48:29,830 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 1601 ms). class=org.cumulus4j.store.test.movie.Movie
20:48:29,848 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:48:29,849 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=8 value=[]
20:48:29,849 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,849 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1ecb1d5c[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:29,849 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,849 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[]
20:48:29,850 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,850 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@18360494[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:29,850 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=null
20:48:29,850 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,850 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=4 value=[]
20:48:29,850 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,851 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@311a964a[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:29,851 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,851 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,851 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=1 value=[]
20:48:29,851 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,851 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@20245ebb[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:29,851 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=0 value=[]
20:48:29,851 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:29,852 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@774269fe[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:29,852 TRACE [qtp1100829986-445][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
20:48:29,852 INFO [qtp1100829986-445][AbstractCryptoManager] getEncryptionAlgorithm: Property 'cumulus4j.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
20:48:29,852 INFO [qtp1100829986-445][AbstractCryptoManager] getMACAlgorithm: Property 'cumulus4j.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
20:48:32,372 INFO [qtp1100829986-445][CryptoCache] Persistence property 'cumulus4j.CryptoCache.cleanupTimer.period' is not set. Using default value 60000.
20:48:32,373 DEBUG [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _query[requestID=58fdz673tbcdliltepwnednog]: Entered with request: org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest@44397d58[58fdz673tbcdliltepwnednog,test-8279361546655905561_iauy3qr1*1*0bgvu3]
20:48:32,432 DEBUG [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _query[requestID=58fdz673tbcdliltepwnednog]: Request persisted.
20:48:32,487 ERROR [qtp1100829986-444][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@28563b94" (id="58fdz673tbcdliltepwnednog") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
20:48:32,487 DEBUG [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: Returning request: org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest@44397d58[58fdz673tbcdliltepwnednog,test-8279361546655905561_iauy3qr1*1*0bgvu3]
20:48:32,487 DEBUG [qtp1100829986-434][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest matching=true
20:48:32,488 WARN [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@28563b94" (id="58fdz673tbcdliltepwnednog") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
FailedObject:org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@44397d58
20:48:32,488 ERROR [qtp1100829986-448][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@6d3287a5" (id="58fdz673tbcdliltepwnednog") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
20:48:32,492 WARN [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@6d3287a5" (id="58fdz673tbcdliltepwnednog") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
FailedObject:org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@44397d58
20:48:32,493 ERROR [qtp1100829986-435][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@61b52767" (id="58fdz673tbcdliltepwnednog") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
20:48:32,494 WARN [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@61b52767" (id="58fdz673tbcdliltepwnednog") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
FailedObject:org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@44397d58
20:48:32,495 ERROR [qtp1100829986-447][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@524725db" (id="58fdz673tbcdliltepwnednog") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
20:48:32,495 WARN [qtp1100829986-447][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@524725db" (id="58fdz673tbcdliltepwnednog") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
FailedObject:org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@44397d58
20:48:32,496 DEBUG [Thread-13][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Request matching=true
20:48:32,510 DEBUG [Thread-13][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyResponse matching=true
20:48:32,515 DEBUG [qtp1100829986-434][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:48:32,518 DEBUG [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pushResponse[requestID=58fdz673tbcdliltepwnednog]: Entered.
20:48:32,564 DEBUG [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-8279361546655905561_iauy3qr1]: Entered.
20:48:32,722 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Created new Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with key and IV.
20:48:32,724 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,733 TRACE [qtp1100829986-445][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
20:48:32,733 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,733 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,733 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@774269fe[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:32,750 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,750 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,759 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@20245ebb[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:32,763 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,763 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,782 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,787 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,814 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,815 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,824 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@311a964a[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:32,828 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,828 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,830 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@18360494[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:32,835 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,835 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,837 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1ecb1d5c[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:32,841 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,841 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,846 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Rating
20:48:32,846 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Rating
20:48:32,848 DEBUG [qtp1100829986-445][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@46aa56c9[null,org.cumulus4j.store.test.movie.Rating]
20:48:32,851 DEBUG [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@46aa56c9[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,852 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@1aff09cb[null,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,854 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@46aa56c9[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,854 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@1aff09cb[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,855 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1aff09cb[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,855 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@46aa56c9[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,856 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@65577656[null,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,858 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@46aa56c9[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,859 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@65577656[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,860 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@65577656[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,860 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@46aa56c9[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,860 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1aff09cb[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,860 DEBUG [qtp1100829986-445][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@1aff09cb[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,863 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@65577656[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,864 DEBUG [qtp1100829986-445][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@46aa56c9[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,864 DEBUG [qtp1100829986-445][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@46aa56c9[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,864 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@46aa56c9[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,864 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@65577656[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,864 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@65577656[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,864 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@65577656[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,864 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@65577656[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,865 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@1aff09cb[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,865 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@1aff09cb[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,865 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1aff09cb[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,865 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@1aff09cb[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,865 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@beb78b3[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,865 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@104135e2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:48:32,865 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@293890c4[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:48:32,865 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 19 ms). class=org.cumulus4j.store.test.movie.Rating
20:48:32,865 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@beb78b3[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,865 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@beb78b3[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,865 TRACE [qtp1100829986-445][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
20:48:32,866 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,866 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,869 TRACE [qtp1100829986-445][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
20:48:32,869 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@beb78b3[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,869 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@beb78b3[2,org.cumulus4j.store.test.movie.Rating]
20:48:32,872 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,873 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,878 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,879 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,882 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:48:32,882 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=8 value=[]
20:48:32,882 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,882 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1ecb1d5c[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:32,882 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,882 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[]
20:48:32,882 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,882 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@18360494[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:32,882 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=null
20:48:32,882 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,883 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=4 value=[]
20:48:32,883 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,883 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@311a964a[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:32,883 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,883 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,883 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=1 value=[]
20:48:32,883 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,883 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@20245ebb[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:32,883 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=0 value=[]
20:48:32,883 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,883 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@774269fe[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:32,884 TRACE [qtp1100829986-445][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 2
20:48:32,884 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,884 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,887 TRACE [qtp1100829986-445][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 2
20:48:32,887 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@774269fe[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:48:32,891 TRACE [qtp1100829986-445][CryptoCache] getKeyData: Found cached key with keyID=1.
20:48:32,892 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Created new Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with key and IV.
20:48:32,893 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,894 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,894 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,894 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@20245ebb[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:48:32,909 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,909 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,909 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,910 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,914 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,915 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,920 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,920 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,923 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@311a964a[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:48:32,927 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,927 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,927 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,928 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,928 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@18360494[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:32,933 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,933 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,933 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,934 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,934 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1ecb1d5c[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:48:32,939 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,940 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,940 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,940 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,942 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Person
20:48:32,943 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Person
20:48:32,946 DEBUG [qtp1100829986-445][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@38c39d6[null,org.cumulus4j.store.test.movie.Person]
20:48:32,948 DEBUG [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@38c39d6[3,org.cumulus4j.store.test.movie.Person]
20:48:32,949 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@58c288d1[null,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,952 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@38c39d6[3,org.cumulus4j.store.test.movie.Person]
20:48:32,952 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@58c288d1[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,953 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@58c288d1[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,953 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@38c39d6[3,org.cumulus4j.store.test.movie.Person]
20:48:32,953 DEBUG [qtp1100829986-445][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@3fb027cd[null,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,955 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@38c39d6[3,org.cumulus4j.store.test.movie.Person]
20:48:32,955 DEBUG [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@3fb027cd[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,956 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3fb027cd[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,956 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@38c39d6[3,org.cumulus4j.store.test.movie.Person]
20:48:32,956 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@58c288d1[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,956 DEBUG [qtp1100829986-445][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@58c288d1[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,959 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3fb027cd[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,959 DEBUG [qtp1100829986-445][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@38c39d6[3,org.cumulus4j.store.test.movie.Person]
20:48:32,959 DEBUG [qtp1100829986-445][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@38c39d6[3,org.cumulus4j.store.test.movie.Person]
20:48:32,959 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@38c39d6[3,org.cumulus4j.store.test.movie.Person]
20:48:32,960 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@58c288d1[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,960 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@58c288d1[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,960 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@58c288d1[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,960 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@58c288d1[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,960 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@3fb027cd[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,960 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@3fb027cd[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,960 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3fb027cd[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,960 DEBUG [qtp1100829986-445][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@3fb027cd[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,961 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@53436854[3,org.cumulus4j.store.test.movie.Person]
20:48:32,961 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6e768d80[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:48:32,961 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@29440728[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:48:32,961 DEBUG [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 19 ms). class=org.cumulus4j.store.test.movie.Person
20:48:32,961 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@53436854[3,org.cumulus4j.store.test.movie.Person]
20:48:32,961 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@53436854[3,org.cumulus4j.store.test.movie.Person]
20:48:32,961 TRACE [qtp1100829986-445][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
20:48:32,961 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,962 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,964 TRACE [qtp1100829986-445][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
20:48:32,965 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@53436854[3,org.cumulus4j.store.test.movie.Person]
20:48:32,965 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@53436854[3,org.cumulus4j.store.test.movie.Person]
20:48:32,968 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,968 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,974 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,974 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,977 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:48:32,994 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:32,995 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:32,996 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=org.cumulus4j.store.test.movie.Rating@1
20:48:32,996 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:32,996 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Rating
20:48:33,011 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,012 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,012 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:33,042 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,042 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,045 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:48:33,048 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,049 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,049 TRACE [qtp1100829986-445][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[org.cumulus4j.store.test.movie.Person@1]
20:48:33,050 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:33,050 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@18360494[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:33,050 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Person
20:48:33,053 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,053 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,053 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:33,053 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@18360494[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:33,058 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,059 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,059 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,059 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,060 TRACE [qtp1100829986-445][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@18360494[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:48:33,064 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,065 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,070 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,070 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@971289006 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,099 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:48:33,100 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:48:33,112 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClassID: found cache entry. classID=1
20:48:33,113 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:48:33,129 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,129 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,130 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:33,130 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:33,130 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:33,130 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClassID: found cache entry. classID=1
20:48:33,131 TRACE [qtp1100829986-445][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:48:33,134 TRACE [qtp1100829986-445][CryptoCache] acquireCipherEntry: Found cached Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:48:33,134 TRACE [qtp1100829986-445][CryptoCache] releaseCipherEntry: Releasing Cipher@1445598134 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:48:33,135 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:33,135 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
20:48:33,135 TRACE [qtp1100829986-445][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@473ed1b0[1,org.cumulus4j.store.test.movie.Movie]
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 201.243 sec
Results :
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0
[JENKINS] Recording test results
[INFO]
[INFO] --- maven-source-plugin:2.1.2:jar-no-fork (attach-sources) @ org.cumulus4j.integrationtest.webapp ---
20:48:33,899 DEBUG [AbstractCryptoManager][AbstractCryptoManager$CloseExpiredSessionsTask] run: entered
[INFO] org already added, skipping
[INFO] org/cumulus4j already added, skipping
[INFO] org/cumulus4j/integrationtest already added, skipping
[INFO] org/cumulus4j/integrationtest/webapp already added, skipping
[INFO] LICENCE.txt already added, skipping
[INFO] cumulus4j-test-datanucleus.properties already added, skipping
[INFO] Building jar: /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.integrationtest.webapp/target/org.cumulus4j.integrationtest.webapp-1.2.1-SNAPSHOT-sources.jar
[INFO] org already added, skipping
[INFO] org/cumulus4j already added, skipping
[INFO] org/cumulus4j/integrationtest already added, skipping
[INFO] org/cumulus4j/integrationtest/webapp already added, skipping
[INFO] LICENCE.txt already added, skipping
[INFO] cumulus4j-test-datanucleus.properties already added, skipping
20:48:34,124 DEBUG [CryptoCache][CryptoCache$CleanupTask] run: entered
20:48:34,124 INFO [CryptoCache][CryptoCache] getCryptoCacheEntryExpiryAgeMSec: Property 'cumulus4j.CryptoCache.entryExpiryAge' is not set. Using default value 1800000.
20:48:34,124 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyEncryptionKeyEntry (1 left).
20:48:34,124 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyDecrypterEntry (1 left).
20:48:34,124 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyEntry (1 left).
20:48:34,124 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheCipherEntry (2 left).
20:48:34,124 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of empty List<CryptoCacheCipherEntry> (2 non-empty lists left).
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ org.cumulus4j.integrationtest.webapp ---
[INFO] Installing /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.integrationtest.webapp/target/org.cumulus4j.integrationtest.webapp-1.2.1-SNAPSHOT.war to /home/tomcat/.m2/repository/org/cumulus4j/org.cumulus4j.integrationtest.webapp/1.2.1-SNAPSHOT/org.cumulus4j.integrationtest.webapp-1.2.1-SNAPSHOT.war
[INFO] Installing /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.integrationtest.webapp/pom.xml to /home/tomcat/.m2/repository/org/cumulus4j/org.cumulus4j.integrationtest.webapp/1.2.1-SNAPSHOT/org.cumulus4j.integrationtest.webapp-1.2.1-SNAPSHOT.pom
[INFO] Installing /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.integrationtest.webapp/target/org.cumulus4j.integrationtest.webapp-1.2.1-SNAPSHOT-sources.jar to /home/tomcat/.m2/repository/org/cumulus4j/org.cumulus4j.integrationtest.webapp/1.2.1-SNAPSHOT/org.cumulus4j.integrationtest.webapp-1.2.1-SNAPSHOT-sources.jar
Console Output
Help us localize this page