SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
09:12:54,542 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,543 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,544 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,544 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,544 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,552 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,559 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,561 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,580 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,586 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,589 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,596 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,601 WARN [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,609 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,610 WARN [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,614 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,622 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,634 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,635 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,637 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,639 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,640 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,644 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,652 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,654 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,659 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,671 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,684 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,685 WARN [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,686 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,695 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,702 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,703 WARN [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,709 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,710 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,716 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,728 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,729 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,729 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,730 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,731 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,753 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,753 WARN [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,759 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,764 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,767 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,769 WARN [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,772 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,778 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,780 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,787 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,789 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,793 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,800 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,803 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,805 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,824 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,827 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,828 WARN [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,833 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,834 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,845 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,856 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,857 WARN [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,857 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,862 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,868 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,869 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,875 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,889 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,892 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,896 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,897 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,908 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,909 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,920 WARN [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,920 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,921 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,924 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,925 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,944 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,945 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,949 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,950 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,951 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,954 WARN [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,957 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,964 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,972 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,976 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,976 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:54,980 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,000 WARN [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,006 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,009 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,014 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,018 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,034 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,036 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,041 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,043 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,044 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,046 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,049 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,049 WARN [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,052 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,064 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,069 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,073 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,080 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,089 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,092 WARN [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,092 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,101 WARN [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,102 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,110 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,113 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,116 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,120 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,120 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,121 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,125 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,144 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,145 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,160 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,162 WARN [qtp525020488-565][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,169 WARN [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,176 WARN [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,180 WARN [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,182 WARN [qtp525020488-409][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,193 WARN [qtp525020488-414][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,194 WARN [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,195 WARN [qtp525020488-434][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,197 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,199 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,199 WARN [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,211 WARN [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,215 WARN [qtp525020488-446][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:12:55,224 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is 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:13:02,355 INFO [qtp525020488-564][Cumulus4jStoreManager] ====================== Cumulus4j ======================
09:13:02,355 INFO [qtp525020488-564][Cumulus4jStoreManager] Bundle: org.cumulus4j.store - Version: 1.2.1.SNAPSHOT
09:13:02,355 INFO [qtp525020488-564][Cumulus4jStoreManager] =======================================================
09:13:02,356 ERROR [qtp525020488-564][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.GeneratedMethodAccessor421.invoke(Unknown Source)
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)
... 69 more
09:13:02,360 DEBUG [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Movie
09:13:02,361 INFO [qtp525020488-564][AbstractCryptoManager] getCryptoSessionExpiryTimerPeriod: Property 'cumulus4j.cryptoSessionExpiryTimer.period' is not set. Using default value 60000.
09:13:02,361 TRACE [qtp525020488-564][AbstractCryptoSession] setCryptoSessionID: cryptoSessionID=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z*1*0s3shs
09:13:02,361 INFO [qtp525020488-564][AbstractCryptoManager] getCryptoSessionExpiryAgeMSec: Property 'cumulus4j.cryptoSessionExpiryAge' is not set. Using default value 1800000.
09:13:02,535 INFO [qtp525020488-564][MinimumCumulus4jVersion] version=10100001
09:13:02,966 INFO [qtp525020488-564][MinimumCumulus4jVersion] version=10100001
09:13:02,966 DEBUG [qtp525020488-564][RecreateIndex] deleteIndex: Entered.
09:13:03,218 DEBUG [qtp525020488-564][RecreateIndex] deleteIndex: There are no IndexEntry instances in the database => quit.
09:13:03,218 DEBUG [qtp525020488-564][RecreateIndex] deleteIndex: Leaving.
09:13:03,349 DEBUG [qtp525020488-564][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Movie
09:13:03,385 DEBUG [qtp525020488-564][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@2aeefbf3[null,org.cumulus4j.store.test.movie.Movie]
09:13:03,407 DEBUG [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,425 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@1b77469f[null,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,441 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,441 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,454 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,455 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,455 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,456 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@457ce8d9[null,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:13:03,457 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@457ce8d9[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:13:03,458 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,458 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,459 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@5ab755c6[null,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,462 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,462 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,462 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,462 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,462 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,464 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@2ee38630[null,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:13:03,465 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@2ee38630[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:13:03,466 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,466 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,466 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@44fef89f[null,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,467 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,467 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@44fef89f[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,468 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44fef89f[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,468 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,468 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@41ea15ef[null,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,469 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,469 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@41ea15ef[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,469 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41ea15ef[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,469 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,470 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@766e8616[null,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,470 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,470 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,471 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,471 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,471 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,475 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@2817c876[null,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:13:03,477 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@2817c876[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:13:03,478 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,478 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,478 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@7a44a243[null,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,480 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,481 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@7a44a243[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,481 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7a44a243[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,481 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,481 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@69d885b8[null,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,484 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,484 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,484 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,484 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,484 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,485 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@190a7a83[null,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:13:03,486 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@190a7a83[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:13:03,486 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,486 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,487 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@45c6fcaa[null,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,488 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,488 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@45c6fcaa[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,488 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@45c6fcaa[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,488 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,488 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@7c478491[null,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,489 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,489 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,490 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,490 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,490 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,490 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@58945d89[null,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:13:03,491 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@58945d89[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:13:03,492 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,492 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,492 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,492 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,492 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44fef89f[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,492 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41ea15ef[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,492 DEBUG [qtp525020488-564][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@41ea15ef[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,609 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,609 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7a44a243[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,609 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,609 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@45c6fcaa[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,609 DEBUG [qtp525020488-564][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@45c6fcaa[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,611 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,611 DEBUG [qtp525020488-564][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,623 DEBUG [qtp525020488-564][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,623 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@2aeefbf3[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,623 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,624 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@457ce8d9[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@457ce8d9[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:13:03,624 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@457ce8d9[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@457ce8d9[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@1b77469f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,624 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@2ee38630[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@2ee38630[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:13:03,624 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2ee38630[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@2ee38630[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@5ab755c6[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,624 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@45c6fcaa[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@45c6fcaa[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,625 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@45c6fcaa[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@45c6fcaa[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,625 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@58945d89[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@58945d89[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:13:03,625 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@58945d89[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@58945d89[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@7c478491[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@41ea15ef[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@41ea15ef[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,625 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41ea15ef[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@41ea15ef[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@7a44a243[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,625 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@7a44a243[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,626 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7a44a243[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,626 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@7a44a243[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,626 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,626 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,626 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,626 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@190a7a83[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:13:03,626 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@190a7a83[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:13:03,626 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@190a7a83[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:13:03,626 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@190a7a83[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:13:03,626 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@69d885b8[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,637 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@44fef89f[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,637 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@44fef89f[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,637 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44fef89f[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,637 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@44fef89f[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,637 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,638 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,638 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,638 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@2817c876[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:13:03,638 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@2817c876[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:13:03,638 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2817c876[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:13:03,638 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@2817c876[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:13:03,638 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@766e8616[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,648 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@b9f267f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@501567de[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@22288cd2[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7b75189c[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@92b7553[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@72fbc83f[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@145384ae[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@47b1cf40[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1562ab08[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@39f423ab[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@310aa6cf[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7808b461[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@78326a83[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,648 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6043f755[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
09:13:03,648 DEBUG [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 1288 ms). class=org.cumulus4j.store.test.movie.Movie
09:13:03,681 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:13:03,681 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=8 value=[]
09:13:03,681 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,681 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@72fbc83f[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:03,681 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,681 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[]
09:13:03,682 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,682 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@39f423ab[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:03,682 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=null
09:13:03,682 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,682 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=4 value=[]
09:13:03,682 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,682 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@78326a83[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:03,682 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,682 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,682 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=1 value=[]
09:13:03,682 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,682 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@22288cd2[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:03,682 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=0 value=[]
09:13:03,682 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:03,682 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@b9f267f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:03,682 TRACE [qtp525020488-564][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
09:13:03,682 INFO [qtp525020488-564][AbstractCryptoManager] getEncryptionAlgorithm: Property 'cumulus4j.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
09:13:03,682 INFO [qtp525020488-564][AbstractCryptoManager] getMACAlgorithm: Property 'cumulus4j.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
09:13:04,251 DEBUG [CryptoCache][CryptoCache$CleanupTask] run: entered
09:13:04,251 INFO [CryptoCache][CryptoCache] getCryptoCacheEntryExpiryAgeMSec: Property 'cumulus4j.CryptoCache.entryExpiryAge' cannot be read, because NucleusContext was garbage-collected. Using fallback value 300000.
09:13:04,252 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyEncryptionKeyEntry (1 left).
09:13:04,252 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyDecrypterEntry (1 left).
09:13:04,252 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyEntry (1 left).
09:13:04,252 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheCipherEntry (2 left).
09:13:04,252 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of empty List<CryptoCacheCipherEntry> (2 non-empty lists left).
09:13:04,701 DEBUG [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: Returning request: null
09:13:04,702 DEBUG [Thread-508][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
09:13:04,705 DEBUG [qtp525020488-447][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
09:13:04,706 DEBUG [qtp525020488-447][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotofio0-htir1km09rox_68mdft29]: Entered.
09:13:06,201 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
09:13:06,482 DEBUG [SessionManager][SessionManager$ExpireSessionTimerTask] run: 1 sessions left.
09:13:07,232 DEBUG [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: Returning request: null
09:13:07,236 DEBUG [Thread-502][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
09:13:07,244 DEBUG [qtp525020488-412][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
09:13:07,245 DEBUG [qtp525020488-412][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: Entered.
09:13:07,310 DEBUG [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: Returning request: null
09:13:07,312 DEBUG [Thread-504][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
09:13:07,314 DEBUG [qtp525020488-421][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
09:13:07,316 DEBUG [qtp525020488-421][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: Entered.
09:13:07,341 DEBUG [qtp525020488-432][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: Returning request: null
09:13:07,343 DEBUG [Thread-503][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
09:13:07,345 DEBUG [qtp525020488-431][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
09:13:07,346 DEBUG [qtp525020488-431][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: Entered.
09:13:07,376 DEBUG [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: Returning request: null
09:13:07,377 DEBUG [Thread-505][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
09:13:07,379 DEBUG [qtp525020488-444][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
09:13:07,380 DEBUG [qtp525020488-444][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotof2q7-1dgk1c5eci6n3_cnss123k]: Entered.
09:13:10,573 DEBUG [AbstractCryptoManager][AbstractCryptoManager$CloseExpiredSessionsTask] run: entered
09:13:10,635 DEBUG [CryptoCache][CryptoCache$CleanupTask] run: entered
09:13:10,635 INFO [CryptoCache][CryptoCache] getCryptoCacheEntryExpiryAgeMSec: Property 'cumulus4j.CryptoCache.entryExpiryAge' is not set. Using default value 1800000.
09:13:10,636 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyEncryptionKeyEntry (1 left).
09:13:10,636 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyDecrypterEntry (1 left).
09:13:10,636 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheKeyEntry (1 left).
09:13:10,636 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of CryptoCacheCipherEntry (2 left).
09:13:10,636 DEBUG [CryptoCache][CryptoCache] removeExpiredEntries: Removed 0 instances of empty List<CryptoCacheCipherEntry> (2 non-empty lists left).
09:13:12,667 INFO [qtp525020488-564][CryptoCache] Persistence property 'cumulus4j.CryptoCache.cleanupTimer.period' is not set. Using default value 60000.
09:13:12,667 DEBUG [qtp525020488-564][MessageBrokerPMF] [hqie9a] _query[requestID=0wsdmgxfu4jqbp4gj2fz81zxs]: Entered with request: org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest@77d64f99[0wsdmgxfu4jqbp4gj2fz81zxs,test-iotoh64v-f3ignf5kcx1y_hrhp6w4z*1*0s3shs]
09:13:12,832 DEBUG [qtp525020488-564][MessageBrokerPMF] [hqie9a] _query[requestID=0wsdmgxfu4jqbp4gj2fz81zxs]: Request persisted.
09:13:12,910 DEBUG [qtp525020488-443][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: Returning request: org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest@77d64f99[0wsdmgxfu4jqbp4gj2fz81zxs,test-iotoh64v-f3ignf5kcx1y_hrhp6w4z*1*0s3shs]
09:13:12,910 ERROR [qtp525020488-413][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@5e54adc0" (id="0wsdmgxfu4jqbp4gj2fz81zxs") 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:13:12,910 DEBUG [qtp525020488-443][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest matching=true
09:13:12,911 WARN [qtp525020488-413][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@5e54adc0" (id="0wsdmgxfu4jqbp4gj2fz81zxs") 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@77d64f99
09:13:12,912 ERROR [qtp525020488-433][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@411a5afa" (id="0wsdmgxfu4jqbp4gj2fz81zxs") 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:13:12,912 WARN [qtp525020488-433][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@411a5afa" (id="0wsdmgxfu4jqbp4gj2fz81zxs") 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@77d64f99
09:13:12,913 ERROR [qtp525020488-441][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@426232e4" (id="0wsdmgxfu4jqbp4gj2fz81zxs") 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:13:12,913 DEBUG [Thread-518][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Request matching=true
09:13:12,913 WARN [qtp525020488-441][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@426232e4" (id="0wsdmgxfu4jqbp4gj2fz81zxs") 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@77d64f99
09:13:12,913 ERROR [qtp525020488-553][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@164afceb" (id="0wsdmgxfu4jqbp4gj2fz81zxs") 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:13:12,914 WARN [qtp525020488-553][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@164afceb" (id="0wsdmgxfu4jqbp4gj2fz81zxs") 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@77d64f99
09:13:12,924 DEBUG [Thread-518][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyResponse matching=true
09:13:12,930 DEBUG [qtp525020488-445][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
09:13:12,933 DEBUG [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pushResponse[requestID=0wsdmgxfu4jqbp4gj2fz81zxs]: Entered.
09:13:12,965 DEBUG [qtp525020488-445][MessageBrokerPMF] [hqie9a] _pollRequest[cryptoSessionIDPrefix=test-iotoh64v-f3ignf5kcx1y_hrhp6w4z]: Entered.
09:13:13,192 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Created new Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with key and IV.
09:13:13,193 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,199 TRACE [qtp525020488-564][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
09:13:13,200 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,200 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,200 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@b9f267f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:13,209 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,209 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,214 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@22288cd2[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:13,216 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,216 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,224 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,225 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,242 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,242 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,248 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@78326a83[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:13,250 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,251 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,252 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@39f423ab[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:13,254 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,254 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,256 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@72fbc83f[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:13,257 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,257 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,259 DEBUG [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Rating
09:13:13,260 DEBUG [qtp525020488-564][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Rating
09:13:13,261 DEBUG [qtp525020488-564][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@6d77f875[null,org.cumulus4j.store.test.movie.Rating]
09:13:13,261 DEBUG [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@6d77f875[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,262 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@14a91fb9[null,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,263 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@6d77f875[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,263 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@14a91fb9[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,264 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@14a91fb9[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,264 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@6d77f875[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,264 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@8f076a2[null,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,265 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@6d77f875[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,265 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@8f076a2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,266 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@8f076a2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,266 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@6d77f875[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,267 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@14a91fb9[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,267 DEBUG [qtp525020488-564][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@14a91fb9[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,268 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@8f076a2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,268 DEBUG [qtp525020488-564][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@6d77f875[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,268 DEBUG [qtp525020488-564][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@6d77f875[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,268 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@6d77f875[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,268 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@8f076a2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,268 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@8f076a2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,268 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@8f076a2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,268 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@8f076a2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,269 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@14a91fb9[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,269 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@14a91fb9[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,269 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@14a91fb9[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,269 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@14a91fb9[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,269 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@179b6e44[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,269 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1ad8ad2[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
09:13:13,269 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@73cf5b5d[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
09:13:13,269 DEBUG [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 10 ms). class=org.cumulus4j.store.test.movie.Rating
09:13:13,269 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@179b6e44[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,269 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@179b6e44[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,269 TRACE [qtp525020488-564][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
09:13:13,269 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,270 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,271 TRACE [qtp525020488-564][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
09:13:13,271 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@179b6e44[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,271 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@179b6e44[2,org.cumulus4j.store.test.movie.Rating]
09:13:13,272 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,272 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,274 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,274 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,276 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:13:13,276 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=8 value=[]
09:13:13,276 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@72fbc83f[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:13,277 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[]
09:13:13,277 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@39f423ab[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:13,277 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=null
09:13:13,277 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=4 value=[]
09:13:13,277 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@78326a83[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:13,277 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=1 value=[]
09:13:13,277 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@22288cd2[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:13,277 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=0 value=[]
09:13:13,277 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,277 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@b9f267f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:13,277 TRACE [qtp525020488-564][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 2
09:13:13,278 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,278 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,278 TRACE [qtp525020488-564][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 2
09:13:13,279 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@b9f267f[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
09:13:13,280 TRACE [qtp525020488-564][CryptoCache] getKeyData: Found cached key with keyID=1.
09:13:13,281 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Created new Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with key and IV.
09:13:13,281 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,281 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,281 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,281 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@22288cd2[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
09:13:13,290 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,290 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,290 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,290 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,292 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,293 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,295 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,295 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,296 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@78326a83[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
09:13:13,298 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,298 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,298 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,298 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,298 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@39f423ab[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:13,300 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,300 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,300 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,300 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,301 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@72fbc83f[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
09:13:13,303 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,303 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,304 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,304 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,305 DEBUG [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Person
09:13:13,305 DEBUG [qtp525020488-564][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Person
09:13:13,308 DEBUG [qtp525020488-564][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@56b8a766[null,org.cumulus4j.store.test.movie.Person]
09:13:13,309 DEBUG [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@56b8a766[3,org.cumulus4j.store.test.movie.Person]
09:13:13,309 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@45a954a8[null,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,310 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@56b8a766[3,org.cumulus4j.store.test.movie.Person]
09:13:13,310 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@45a954a8[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,311 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@45a954a8[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,311 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@56b8a766[3,org.cumulus4j.store.test.movie.Person]
09:13:13,311 DEBUG [qtp525020488-564][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@efd2027[null,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,312 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@56b8a766[3,org.cumulus4j.store.test.movie.Person]
09:13:13,312 DEBUG [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@efd2027[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,313 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@efd2027[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,313 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@56b8a766[3,org.cumulus4j.store.test.movie.Person]
09:13:13,313 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@45a954a8[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,313 DEBUG [qtp525020488-564][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@45a954a8[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,315 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@efd2027[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,316 DEBUG [qtp525020488-564][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@56b8a766[3,org.cumulus4j.store.test.movie.Person]
09:13:13,316 DEBUG [qtp525020488-564][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@56b8a766[3,org.cumulus4j.store.test.movie.Person]
09:13:13,316 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@56b8a766[3,org.cumulus4j.store.test.movie.Person]
09:13:13,316 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@45a954a8[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,316 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@45a954a8[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,316 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@45a954a8[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,316 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@45a954a8[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,317 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@efd2027[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,317 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@efd2027[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,317 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@efd2027[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,317 DEBUG [qtp525020488-564][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@efd2027[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,317 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@208b71e2[3,org.cumulus4j.store.test.movie.Person]
09:13:13,317 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@489bfccb[17,org.cumulus4j.store.test.movie.Person#name[primary]]
09:13:13,317 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@73946be6[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
09:13:13,317 DEBUG [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 12 ms). class=org.cumulus4j.store.test.movie.Person
09:13:13,317 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@208b71e2[3,org.cumulus4j.store.test.movie.Person]
09:13:13,317 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@208b71e2[3,org.cumulus4j.store.test.movie.Person]
09:13:13,317 TRACE [qtp525020488-564][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
09:13:13,317 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,318 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,319 TRACE [qtp525020488-564][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
09:13:13,319 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@208b71e2[3,org.cumulus4j.store.test.movie.Person]
09:13:13,319 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@208b71e2[3,org.cumulus4j.store.test.movie.Person]
09:13:13,321 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,321 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,324 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,324 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,325 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:13:13,336 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,336 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,336 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[org.cumulus4j.store.test.movie.Person@1]
09:13:13,336 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,336 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@39f423ab[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:13,336 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Person
09:13:13,346 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,346 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,346 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,346 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@39f423ab[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:13,355 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,355 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,356 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,356 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,356 TRACE [qtp525020488-564][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@39f423ab[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
09:13:13,358 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,358 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,360 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,360 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,361 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:13:13,363 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,363 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,363 TRACE [qtp525020488-564][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=org.cumulus4j.store.test.movie.Rating@1
09:13:13,364 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,364 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Rating
09:13:13,365 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,365 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,365 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,376 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,377 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1108231832 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,389 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:13:13,390 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:13:13,401 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClassID: found cache entry. classID=1
09:13:13,402 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:13:13,409 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,409 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,409 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,409 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,410 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,410 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClassID: found cache entry. classID=1
09:13:13,410 TRACE [qtp525020488-564][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
09:13:13,412 TRACE [qtp525020488-564][CryptoCache] acquireCipherEntry: Found cached Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
09:13:13,412 TRACE [qtp525020488-564][CryptoCache] releaseCipherEntry: Releasing Cipher@1164086904 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
09:13:13,412 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,412 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
09:13:13,412 TRACE [qtp525020488-564][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5ee0fc8f[1,org.cumulus4j.store.test.movie.Movie]
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 130.627 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.withkeyserver ---
[INFO] No sources in project. Archive not created.
[INFO]
[INFO] --- maven-install-plugin:2.4:install (default-install) @ org.cumulus4j.integrationtest.withkeyserver ---
[INFO] Installing /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.integrationtest.withkeyserver/target/org.cumulus4j.integrationtest.withkeyserver-1.2.1-SNAPSHOT.jar to /home/tomcat/.m2/repository/org/cumulus4j/org.cumulus4j.integrationtest.withkeyserver/1.2.1-SNAPSHOT/org.cumulus4j.integrationtest.withkeyserver-1.2.1-SNAPSHOT.jar
[INFO] Installing /home/tomcat/.jenkins/workspace/org.cumulus4j/org.cumulus4j/org.cumulus4j.integrationtest.withkeyserver/pom.xml to /home/tomcat/.m2/repository/org/cumulus4j/org.cumulus4j.integrationtest.withkeyserver/1.2.1-SNAPSHOT/org.cumulus4j.integrationtest.withkeyserver-1.2.1-SNAPSHOT.pom
Console Output
Help us localize this page