SuccessConsole Output

Skipping 855 KB.. Full Log
.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
09:10:39,006 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,010 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,012 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,013 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,013 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,024 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,026 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,027 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,027 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,035 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,044 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,045 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,048 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,055 WARN  [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,062 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,076 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,087 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,091 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,095 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,097 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,098 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,099 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,103 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,108 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,110 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,116 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,119 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,133 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,148 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,151 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,154 WARN  [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,156 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,160 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,164 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,167 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,172 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,174 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,176 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,181 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,188 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,190 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,201 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,206 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,217 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,226 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,227 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,230 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,231 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,233 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,233 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,237 WARN  [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,245 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,259 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,260 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,262 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,271 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,275 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,288 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,291 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,292 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,293 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,294 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,298 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,303 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,308 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,312 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,332 WARN  [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,332 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,348 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,355 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,358 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,359 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,360 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,366 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,366 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,367 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,372 DEBUG [CryptoCache][CryptoCache$CleanupTask] run: entered
09:10:39,372 INFO  [CryptoCache][CryptoCache$CleanupTask] run: CryptoCache was garbage-collected. Cancelling this TimerTask.
09:10:39,373 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,381 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,384 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,389 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,406 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,407 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,414 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,426 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,428 WARN  [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,430 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,433 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,435 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,436 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,438 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,438 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,441 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,457 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,464 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,471 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,474 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,483 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,484 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,485 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,493 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,495 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,505 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,512 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,514 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,517 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,521 WARN  [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,526 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,532 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,533 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,536 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,544 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,548 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,560 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,561 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,565 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,569 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,571 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,574 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,576 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,584 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,594 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,597 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,601 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,608 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,620 WARN  [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,623 WARN  [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,625 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,630 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,630 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,637 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,637 WARN  [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,646 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,650 WARN  [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,651 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,656 WARN  [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,657 DEBUG [qtp525020488-435][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: Returning request: null
09:10:39,657 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,662 WARN  [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,662 DEBUG [Thread-11][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
09:10:39,665 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,667 DEBUG [qtp525020488-447][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
09:10:39,669 DEBUG [qtp525020488-447][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: Entered.
09:10:39,670 WARN  [qtp525020488-447][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,682 WARN  [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,694 WARN  [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoctdq--42ggaks071cd_dkjhdxar]: 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
09:10:39,697 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,700 WARN  [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,702 WARN  [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:39,710 WARN  [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,712 WARN  [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoc8jy-1f73bz94mw0zc_5nnprddg]: 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
09:10:39,713 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: 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
09:10:44,668 INFO  [qtp525020488-434][Cumulus4jStoreManager] ====================== Cumulus4j ======================
09:10:44,668 INFO  [qtp525020488-434][Cumulus4jStoreManager] Bundle: org.cumulus4j.store - Version: 1.2.1.SNAPSHOT
09:10:44,668 INFO  [qtp525020488-434][Cumulus4jStoreManager] =======================================================
09:10:44,671 ERROR [qtp525020488-434][Persistence] java.lang.reflect.InvocationTargetException
	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
	at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
	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:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	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:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	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

09:10:44,672 DEBUG [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Movie
09:10:44,674 INFO  [qtp525020488-434][AbstractCryptoManager] getCryptoSessionExpiryTimerPeriod: Property 'cumulus4j.cryptoSessionExpiryTimer.period' is not set. Using default value 60000.
09:10:44,674 TRACE [qtp525020488-434][AbstractCryptoSession] setCryptoSessionID: cryptoSessionID=test-8640869029098107368_8csj8d4q*1*0nk4g1
09:10:44,674 INFO  [qtp525020488-434][AbstractCryptoManager] getCryptoSessionExpiryAgeMSec: Property 'cumulus4j.cryptoSessionExpiryAge' is not set. Using default value 1800000.
09:10:44,806 INFO  [qtp525020488-434][MinimumCumulus4jVersion] version=10100001
09:10:45,176 INFO  [qtp525020488-434][MinimumCumulus4jVersion] version=10100001
09:10:45,176 DEBUG [qtp525020488-434][RecreateIndex] deleteIndex: Entered.
09:10:45,340 DEBUG [qtp525020488-434][RecreateIndex] deleteIndex: There are no IndexEntry instances in the database => quit.
09:10:45,340 DEBUG [qtp525020488-434][RecreateIndex] deleteIndex: Leaving.
09:10:45,376 DEBUG [qtp525020488-434][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Movie
09:10:45,389 DEBUG [qtp525020488-434][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@4bc1c9a0[null,org.cumulus4j.store.test.movie.Movie]
09:10:45,397 DEBUG [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,409 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@724dd9a2[null,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,416 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,416 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,426 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,426 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,426 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,426 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@68817366[null,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:10:45,428 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@68817366[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:10:45,428 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,428 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,429 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@2e0cdd8e[null,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,429 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,430 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,430 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,430 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,430 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,430 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@4e93cffa[null,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:10:45,432 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@4e93cffa[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:10:45,432 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,432 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,432 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@7c42520d[null,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,433 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,433 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@7c42520d[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,434 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c42520d[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,434 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,434 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@418a06dd[null,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,435 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,435 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@418a06dd[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,435 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@418a06dd[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,435 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,436 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@44ff3e89[null,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,437 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,437 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,437 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,437 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,437 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,438 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@12ba4ced[null,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:10:45,438 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@12ba4ced[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:10:45,439 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,439 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,439 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@6f4292c0[null,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,440 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,440 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@6f4292c0[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,441 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6f4292c0[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,441 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,441 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@25d00c88[null,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,443 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,443 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,444 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,444 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,444 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,444 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@3a647966[null,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:10:45,445 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@3a647966[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:10:45,445 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,445 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,446 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@54301cf4[null,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,447 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,447 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@54301cf4[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,447 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@54301cf4[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,447 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,448 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@340c6936[null,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,449 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,449 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,449 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,450 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,450 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,450 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@20fc5531[null,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:10:45,451 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@20fc5531[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:10:45,451 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,451 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,451 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,452 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,452 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c42520d[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,452 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@418a06dd[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,452 DEBUG [qtp525020488-434][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@418a06dd[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,464 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,464 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6f4292c0[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,464 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,464 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@54301cf4[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,464 DEBUG [qtp525020488-434][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@54301cf4[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,466 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,466 DEBUG [qtp525020488-434][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,466 DEBUG [qtp525020488-434][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,466 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@4bc1c9a0[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,466 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,466 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,467 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,467 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@68817366[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:10:45,467 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@68817366[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:10:45,467 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@68817366[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:10:45,467 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@68817366[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:10:45,467 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@724dd9a2[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,468 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@4e93cffa[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@4e93cffa[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:10:45,468 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4e93cffa[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@4e93cffa[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@2e0cdd8e[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@54301cf4[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@54301cf4[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,468 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@54301cf4[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@54301cf4[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,468 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,469 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,469 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@20fc5531[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:10:45,469 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@20fc5531[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:10:45,469 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@20fc5531[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:10:45,469 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@20fc5531[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:10:45,469 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@340c6936[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,469 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@418a06dd[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,469 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@418a06dd[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,469 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@418a06dd[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,469 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@418a06dd[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,469 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@6f4292c0[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@6f4292c0[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,470 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6f4292c0[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@6f4292c0[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,470 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@3a647966[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@3a647966[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:10:45,470 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3a647966[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@3a647966[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@25d00c88[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,470 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@7c42520d[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,471 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@7c42520d[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,471 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c42520d[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,471 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@7c42520d[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,471 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,471 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,471 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,471 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@12ba4ced[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:10:45,471 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@12ba4ced[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:10:45,471 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@12ba4ced[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:10:45,471 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@12ba4ced[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:10:45,472 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@44ff3e89[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,474 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@712021f5[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@29c4e2af[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3ea55a3d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@34ae96fd[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44f12614[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4aae75bf[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5eceba65[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1e17d63f[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@539b826f[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6aa1162e[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4197848d[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@611114ae[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6e967a19[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,474 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@c641183[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:10:45,475 DEBUG [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 803 ms). class=org.cumulus4j.store.test.movie.Movie
09:10:45,490 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:10:45,490 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=8 value=[]
09:10:45,490 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,490 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4aae75bf[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:45,490 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,490 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[]
09:10:45,490 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,490 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6aa1162e[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:45,490 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=null
09:10:45,490 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,490 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=4 value=[]
09:10:45,490 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,491 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6e967a19[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:45,491 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,491 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,491 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=1 value=[]
09:10:45,491 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,491 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3ea55a3d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:45,491 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=0 value=[]
09:10:45,491 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:45,492 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@712021f5[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:45,492 TRACE [qtp525020488-434][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
09:10:45,492 INFO  [qtp525020488-434][AbstractCryptoManager] getEncryptionAlgorithm: Property 'cumulus4j.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:10:45,492 INFO  [qtp525020488-434][AbstractCryptoManager] getMACAlgorithm: Property 'cumulus4j.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:10:50,927 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
09:10:51,463 DEBUG [SessionManager][SessionManager$ExpireSessionTimerTask] run: 1 sessions left.
09:10:53,674 INFO  [qtp525020488-434][CryptoCache] Persistence property 'cumulus4j.CryptoCache.cleanupTimer.period' is not set. Using default value 60000.
09:10:53,674 DEBUG [qtp525020488-434][MessageBrokerPMF] [hqie9a] _query[requestID=3nptt1gqutacrd5dsbpqu8d68]: Entered with request: org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest@d345f40b[3nptt1gqutacrd5dsbpqu8d68,test-8640869029098107368_8csj8d4q*1*0nk4g1]
09:10:53,828 DEBUG [qtp525020488-434][MessageBrokerPMF] [hqie9a] _query[requestID=3nptt1gqutacrd5dsbpqu8d68]: Request persisted.
09:10:53,917 ERROR [qtp525020488-445][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@24520878" (id="3nptt1gqutacrd5dsbpqu8d68") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
09:10:53,918 DEBUG [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: Returning request: org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest@d345f40b[3nptt1gqutacrd5dsbpqu8d68,test-8640869029098107368_8csj8d4q*1*0nk4g1]
09:10:53,918 ERROR [qtp525020488-411][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@5a47e4bd" (id="3nptt1gqutacrd5dsbpqu8d68") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
09:10:53,918 DEBUG [qtp525020488-444][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest matching=true
09:10:53,918 WARN  [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@24520878" (id="3nptt1gqutacrd5dsbpqu8d68") 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@d345f40b
09:10:53,919 WARN  [qtp525020488-411][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@5a47e4bd" (id="3nptt1gqutacrd5dsbpqu8d68") 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@d345f40b
09:10:53,919 ERROR [qtp525020488-443][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@3aecc728" (id="3nptt1gqutacrd5dsbpqu8d68") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
09:10:53,920 WARN  [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@3aecc728" (id="3nptt1gqutacrd5dsbpqu8d68") 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@d345f40b
09:10:53,920 ERROR [qtp525020488-446][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@5b35159a" (id="3nptt1gqutacrd5dsbpqu8d68") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
09:10:53,921 WARN  [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@5b35159a" (id="3nptt1gqutacrd5dsbpqu8d68") 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@d345f40b
09:10:53,924 DEBUG [Thread-16][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Request matching=true
09:10:53,938 DEBUG [Thread-16][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyResponse matching=true
09:10:53,948 DEBUG [qtp525020488-453][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
09:10:53,951 DEBUG [qtp525020488-453][MessageBrokerPMF] [hqie9a] _pushResponse[requestID=3nptt1gqutacrd5dsbpqu8d68]: Entered.
09:10:53,993 DEBUG [qtp525020488-453][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-8640869029098107368_8csj8d4q]: Entered.
09:10:54,182 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Created new Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with key and IV.
09:10:54,182 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,188 TRACE [qtp525020488-434][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
09:10:54,188 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,188 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,188 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@712021f5[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:54,197 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,197 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,202 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3ea55a3d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:54,204 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,204 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,214 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,214 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,230 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,230 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,236 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6e967a19[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:54,238 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,238 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,239 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6aa1162e[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:54,241 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,241 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,242 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4aae75bf[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:54,244 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,244 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,247 DEBUG [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Rating
09:10:54,247 DEBUG [qtp525020488-434][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Rating
09:10:54,249 DEBUG [qtp525020488-434][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@55cede3[null,org.cumulus4j.store.test.movie.Rating]
09:10:54,250 DEBUG [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@55cede3[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,250 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@2f71d931[null,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,251 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@55cede3[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,251 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@2f71d931[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,251 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2f71d931[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,251 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@55cede3[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,252 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@2df5c311[null,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,253 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@55cede3[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,253 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@2df5c311[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,254 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2df5c311[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,254 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@55cede3[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,254 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2f71d931[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,254 DEBUG [qtp525020488-434][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@2f71d931[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,255 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2df5c311[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,256 DEBUG [qtp525020488-434][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@55cede3[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,256 DEBUG [qtp525020488-434][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@55cede3[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,256 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@55cede3[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,256 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@2df5c311[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,257 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@2df5c311[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,257 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2df5c311[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,257 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@2df5c311[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,257 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@2f71d931[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,257 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@2f71d931[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,257 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2f71d931[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,257 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@2f71d931[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,257 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1da5c742[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,257 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5ae3cde3[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:10:54,257 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6430b782[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:10:54,257 DEBUG [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 10 ms). class=org.cumulus4j.store.test.movie.Rating
09:10:54,257 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1da5c742[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,257 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1da5c742[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,257 TRACE [qtp525020488-434][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
09:10:54,258 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,258 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,259 TRACE [qtp525020488-434][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
09:10:54,259 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1da5c742[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,259 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1da5c742[2,org.cumulus4j.store.test.movie.Rating]
09:10:54,261 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,261 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,263 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,264 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,265 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:10:54,265 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=8 value=[]
09:10:54,265 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,265 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4aae75bf[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:54,265 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,265 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[]
09:10:54,265 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,265 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6aa1162e[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:54,265 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=null
09:10:54,265 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,265 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=4 value=[]
09:10:54,265 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,266 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6e967a19[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:54,266 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,266 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,266 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=1 value=[]
09:10:54,266 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,266 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3ea55a3d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:54,266 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=0 value=[]
09:10:54,266 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,266 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@712021f5[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:54,266 TRACE [qtp525020488-434][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 2
09:10:54,266 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,266 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,267 TRACE [qtp525020488-434][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 2
09:10:54,267 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@712021f5[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:10:54,269 TRACE [qtp525020488-434][CryptoCache] getKeyData: Found cached key with keyID=1.
09:10:54,270 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Created new Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with key and IV.
09:10:54,270 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,270 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,271 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,271 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3ea55a3d[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:10:54,280 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,280 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,280 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,280 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,283 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,283 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,286 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,286 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,287 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6e967a19[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:10:54,289 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,289 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,289 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,289 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,289 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6aa1162e[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:54,291 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,291 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,292 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,292 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,292 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4aae75bf[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:10:54,294 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,294 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,294 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,295 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,296 DEBUG [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Person
09:10:54,296 DEBUG [qtp525020488-434][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Person
09:10:54,298 DEBUG [qtp525020488-434][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@3216bf1d[null,org.cumulus4j.store.test.movie.Person]
09:10:54,299 DEBUG [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@3216bf1d[3,org.cumulus4j.store.test.movie.Person]
09:10:54,300 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@5bad8fb2[null,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,300 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@3216bf1d[3,org.cumulus4j.store.test.movie.Person]
09:10:54,301 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@5bad8fb2[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,301 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5bad8fb2[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,301 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@3216bf1d[3,org.cumulus4j.store.test.movie.Person]
09:10:54,301 DEBUG [qtp525020488-434][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@3b819cf5[null,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,302 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@3216bf1d[3,org.cumulus4j.store.test.movie.Person]
09:10:54,302 DEBUG [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@3b819cf5[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,302 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3b819cf5[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,302 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@3216bf1d[3,org.cumulus4j.store.test.movie.Person]
09:10:54,302 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5bad8fb2[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,303 DEBUG [qtp525020488-434][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@5bad8fb2[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,304 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3b819cf5[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,304 DEBUG [qtp525020488-434][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@3216bf1d[3,org.cumulus4j.store.test.movie.Person]
09:10:54,304 DEBUG [qtp525020488-434][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@3216bf1d[3,org.cumulus4j.store.test.movie.Person]
09:10:54,304 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@3216bf1d[3,org.cumulus4j.store.test.movie.Person]
09:10:54,305 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@5bad8fb2[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,305 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@5bad8fb2[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,305 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5bad8fb2[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,305 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@5bad8fb2[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,305 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@3b819cf5[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,305 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@3b819cf5[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,305 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3b819cf5[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,305 DEBUG [qtp525020488-434][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@3b819cf5[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,305 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7301f877[3,org.cumulus4j.store.test.movie.Person]
09:10:54,305 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2f148514[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:10:54,305 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7f29df86[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:10:54,305 DEBUG [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 9 ms). class=org.cumulus4j.store.test.movie.Person
09:10:54,305 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7301f877[3,org.cumulus4j.store.test.movie.Person]
09:10:54,305 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7301f877[3,org.cumulus4j.store.test.movie.Person]
09:10:54,306 TRACE [qtp525020488-434][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
09:10:54,306 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,306 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,307 TRACE [qtp525020488-434][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
09:10:54,307 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7301f877[3,org.cumulus4j.store.test.movie.Person]
09:10:54,307 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7301f877[3,org.cumulus4j.store.test.movie.Person]
09:10:54,309 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,309 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,311 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,311 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,313 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:10:54,323 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,324 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,324 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=org.cumulus4j.store.test.movie.Rating@1
09:10:54,324 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,324 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Rating
09:10:54,333 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,334 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,334 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,350 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,350 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,351 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:10:54,353 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,353 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,354 TRACE [qtp525020488-434][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[org.cumulus4j.store.test.movie.Person@1]
09:10:54,354 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,354 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6aa1162e[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:54,354 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Person
09:10:54,355 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,355 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,356 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,356 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6aa1162e[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:54,358 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,358 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,358 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,358 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,358 TRACE [qtp525020488-434][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6aa1162e[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:10:54,360 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,360 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,362 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,363 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1404410839 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,380 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:10:54,381 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:10:54,389 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClassID: found cache entry. classID=1
09:10:54,389 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:10:54,403 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,403 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,404 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,404 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,404 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,404 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClassID: found cache entry. classID=1
09:10:54,404 TRACE [qtp525020488-434][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:10:54,406 TRACE [qtp525020488-434][CryptoCache] acquireCipherEntry: Found cached Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:10:54,407 TRACE [qtp525020488-434][CryptoCache] releaseCipherEntry: Releasing Cipher@1059496179 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:10:54,407 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,407 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
09:10:54,407 TRACE [qtp525020488-434][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@1753ba5e[1,org.cumulus4j.store.test.movie.Movie]
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 124.041 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 ---
[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
[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