SuccessConsole Output

Skipping 1,216 KB.. Full Log
  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,588 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,589 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,590 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,591 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,596 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,602 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,603 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,620 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,629 WARN  [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,632 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,635 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,644 WARN  [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,646 WARN  [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,649 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,649 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,654 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,657 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,663 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,668 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,668 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,683 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,688 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,693 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,707 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,724 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,728 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,729 WARN  [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,731 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,732 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,733 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,734 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,734 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,738 WARN  [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,740 WARN  [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,746 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,755 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,761 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,762 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,774 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,780 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,789 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,796 WARN  [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,800 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,812 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,814 WARN  [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,815 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,823 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,830 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,833 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,834 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,838 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,839 WARN  [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,840 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,842 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,845 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,867 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,870 WARN  [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,878 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,885 WARN  [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,893 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,896 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,898 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,904 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,906 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,912 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,927 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,931 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,933 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,935 WARN  [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,939 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,941 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,943 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,944 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,953 WARN  [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,970 WARN  [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,974 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,980 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,984 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,985 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,987 WARN  [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:38,990 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,003 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,004 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,008 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,011 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,023 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,036 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,038 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,040 WARN  [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,041 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,042 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,060 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,061 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,068 WARN  [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,069 WARN  [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,070 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,071 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,072 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,074 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,096 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,102 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,105 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,110 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,126 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,127 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,131 WARN  [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,137 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,140 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,141 WARN  [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,147 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,150 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,155 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,156 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,163 WARN  [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,169 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,182 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,196 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,200 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,201 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,202 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,203 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,208 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,209 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,219 WARN  [qtp1100829986-412][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,230 WARN  [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,231 WARN  [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,232 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,232 WARN  [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,233 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,238 WARN  [qtp1100829986-448][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,267 WARN  [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,269 WARN  [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,272 WARN  [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,292 WARN  [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,292 WARN  [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,293 WARN  [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,294 WARN  [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,295 WARN  [qtp1100829986-436][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,296 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDODataStoreException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
NestedThrowables:
java.sql.SQLException: org.apache.derby.jdbc.EmbeddedDriver is not registered with the JDBC driver manager
20:51:39,761 DEBUG [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Returning request: null
20:51:39,765 DEBUG [Thread-502][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:39,772 DEBUG [qtp1100829986-580][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:39,780 DEBUG [qtp1100829986-580][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Entered.
20:51:40,070 DEBUG [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Returning request: null
20:51:40,075 DEBUG [Thread-504][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:40,079 DEBUG [qtp1100829986-409][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:40,083 DEBUG [qtp1100829986-409][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Entered.
20:51:40,840 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
20:51:40,924 DEBUG [SessionManager][SessionManager$ExpireSessionTimerTask] run: 1 sessions left.
20:51:41,581 DEBUG [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: Returning request: null
20:51:41,584 DEBUG [Thread-510][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:41,588 DEBUG [qtp1100829986-435][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:41,592 DEBUG [qtp1100829986-435][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: Entered.
20:51:42,761 DEBUG [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: Returning request: null
20:51:42,765 DEBUG [Thread-513][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:42,770 DEBUG [qtp1100829986-434][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:42,774 DEBUG [qtp1100829986-434][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: Entered.
20:51:42,831 DEBUG [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: Returning request: null
20:51:42,833 DEBUG [Thread-511][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:42,838 DEBUG [qtp1100829986-433][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:42,842 DEBUG [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: Returning request: null
20:51:42,844 DEBUG [Thread-512][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:42,848 DEBUG [qtp1100829986-433][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: Entered.
20:51:42,849 DEBUG [qtp1100829986-411][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:42,854 DEBUG [qtp1100829986-411][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gnjho-chpp3ab5xa2n_g9a09iwo]: Entered.
20:51:45,416 DEBUG [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Returning request: null
20:51:45,418 DEBUG [Thread-506][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:45,425 DEBUG [qtp1100829986-453][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:45,429 DEBUG [qtp1100829986-453][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Entered.
20:51:48,313 DEBUG [KeyStore][KeyStore$ExipreCacheEntryTimerTask] run: 1 users left in cache.
20:51:49,711 DEBUG [SessionManager][SessionManager$ExpireSessionTimerTask] run: 1 sessions left.
20:51:51,681 DEBUG [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Returning request: null
20:51:51,684 DEBUG [Thread-505][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:51,689 DEBUG [qtp1100829986-442][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:51,692 DEBUG [qtp1100829986-442][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Entered.
20:51:51,768 DEBUG [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Returning request: null
20:51:51,774 DEBUG [Thread-503][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.NullResponse matching=true
20:51:51,779 DEBUG [qtp1100829986-445][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:51:51,784 DEBUG [qtp1100829986-445][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12gl1sc--oqqfj5rokkxn_escyyj4l]: Entered.
20:51:55,208 DEBUG [AbstractCryptoManager][AbstractCryptoManager$CloseExpiredSessionsTask] run: entered
20:51:55,208 INFO  [AbstractCryptoManager][AbstractCryptoManager$CloseExpiredSessionsTask] run: AbstractCryptoManager was garbage-collected. Cancelling this TimerTask.
20:52:02,376 INFO  [qtp1100829986-410][Cumulus4jStoreManager] ====================== Cumulus4j ======================
20:52:02,376 INFO  [qtp1100829986-410][Cumulus4jStoreManager] Bundle: org.cumulus4j.store - Version: 1.2.1.SNAPSHOT
20:52:02,376 INFO  [qtp1100829986-410][Cumulus4jStoreManager] =======================================================
20:52:02,378 ERROR [qtp1100829986-410][Persistence] java.lang.reflect.InvocationTargetException
	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
	at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
	at org.datanucleus.plugin.NonManagedPluginRegistry.createExecutableExtension(NonManagedPluginRegistry.java:631)
	at org.datanucleus.plugin.PluginManager.createExecutableExtension(PluginManager.java:301)
	at org.datanucleus.NucleusContext.initialiseAutoStart(NucleusContext.java:432)
	at org.datanucleus.NucleusContext.initialise(NucleusContext.java:384)
	at org.datanucleus.api.jdo.JDOPersistenceManagerFactory.freezeConfiguration(JDOPersistenceManagerFactory.java:768)
	at org.datanucleus.api.jdo.JDOPersistenceManagerFactory.createPersistenceManagerFactory(JDOPersistenceManagerFactory.java:326)
	at org.datanucleus.api.jdo.JDOPersistenceManagerFactory.getPersistenceManagerFactory(JDOPersistenceManagerFactory.java:195)
	at sun.reflect.GeneratedMethodAccessor466.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at javax.jdo.JDOHelper$16.run(JDOHelper.java:1970)
	at java.security.AccessController.doPrivileged(Native Method)
	at javax.jdo.JDOHelper.invoke(JDOHelper.java:1965)
	at javax.jdo.JDOHelper.invokeGetPersistenceManagerFactoryOnImplementation(JDOHelper.java:1171)
	at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:813)
	at javax.jdo.JDOHelper.getPersistenceManagerFactory(JDOHelper.java:702)
	at org.cumulus4j.integrationtest.webapp.TestService.getPersistenceManagerFactory(TestService.java:59)
	at org.cumulus4j.integrationtest.webapp.TestService.getPersistenceManager(TestService.java:72)
	at org.cumulus4j.integrationtest.webapp.TestService.testPost(TestService.java:93)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.sun.jersey.spi.container.JavaMethodInvokerFactory$1.invoke(JavaMethodInvokerFactory.java:60)
	at com.sun.jersey.server.impl.model.method.dispatch.AbstractResourceMethodDispatchProvider$TypeOutInvoker._dispatch(AbstractResourceMethodDispatchProvider.java:185)
	at com.sun.jersey.server.impl.model.method.dispatch.ResourceJavaMethodDispatcher.dispatch(ResourceJavaMethodDispatcher.java:75)
	at com.sun.jersey.server.impl.uri.rules.HttpMethodRule.accept(HttpMethodRule.java:288)
	at com.sun.jersey.server.impl.uri.rules.ResourceClassRule.accept(ResourceClassRule.java:108)
	at com.sun.jersey.server.impl.uri.rules.RightHandPathRule.accept(RightHandPathRule.java:147)
	at com.sun.jersey.server.impl.uri.rules.RootResourceClassesRule.accept(RootResourceClassesRule.java:84)
	at com.sun.jersey.server.impl.application.WebApplicationImpl._handleRequest(WebApplicationImpl.java:1469)
	at com.sun.jersey.server.impl.application.WebApplicationImpl._handleRequest(WebApplicationImpl.java:1400)
	at com.sun.jersey.server.impl.application.WebApplicationImpl.handleRequest(WebApplicationImpl.java:1349)
	at com.sun.jersey.server.impl.application.WebApplicationImpl.handleRequest(WebApplicationImpl.java:1339)
	at com.sun.jersey.spi.container.servlet.WebComponent.service(WebComponent.java:416)
	at com.sun.jersey.spi.container.servlet.ServletContainer.service(ServletContainer.java:537)
	at com.sun.jersey.spi.container.servlet.ServletContainer.service(ServletContainer.java:699)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847)
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:546)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1283)
	at org.cumulus4j.keymanager.back.shared.AjaxHeadersFilter.doFilter(AjaxHeadersFilter.java:33)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1254)
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:481)
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:119)
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:516)
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:230)
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:956)
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:411)
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:188)
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:891)
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:117)
	at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:247)
	at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:151)
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:114)
	at org.eclipse.jetty.server.Server.handle(Server.java:353)
	at org.eclipse.jetty.server.HttpConnection.handleRequest(HttpConnection.java:598)
	at org.eclipse.jetty.server.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:1059)
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:590)
	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:212)
	at org.eclipse.jetty.server.HttpConnection.handle(HttpConnection.java:427)
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:510)
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.access$000(SelectChannelEndPoint.java:34)
	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:40)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:450)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.ClassCastException: org.cumulus4j.store.Cumulus4jStoreManager cannot be cast to org.datanucleus.store.rdbms.RDBMSStoreManager
	at org.datanucleus.store.rdbms.autostart.SchemaAutoStarter.<init>(SchemaAutoStarter.java:68)
	... 69 more

20:52:02,379 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Movie
20:52:02,381 INFO  [qtp1100829986-410][AbstractCryptoManager] getCryptoSessionExpiryTimerPeriod: Property 'cumulus4j.cryptoSessionExpiryTimer.period' is not set. Using default value 60000.
20:52:02,381 TRACE [qtp1100829986-410][AbstractCryptoSession] setCryptoSessionID: cryptoSessionID=test-i12golvv--wgbjx13976sy_jl3yalwq*1*0tfb0u
20:52:02,381 INFO  [qtp1100829986-410][AbstractCryptoManager] getCryptoSessionExpiryAgeMSec: Property 'cumulus4j.cryptoSessionExpiryAge' is not set. Using default value 1800000.
20:52:02,930 INFO  [qtp1100829986-410][MinimumCumulus4jVersion] version=10100001
20:52:03,621 INFO  [qtp1100829986-410][MinimumCumulus4jVersion] version=10100001
20:52:03,621 DEBUG [qtp1100829986-410][RecreateIndex] deleteIndex: Entered.
20:52:03,797 DEBUG [qtp1100829986-410][RecreateIndex] deleteIndex: There are no IndexEntry instances in the database => quit.
20:52:03,797 DEBUG [qtp1100829986-410][RecreateIndex] deleteIndex: Leaving.
20:52:03,840 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Movie
20:52:03,856 DEBUG [qtp1100829986-410][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@7cb3eb59[null,org.cumulus4j.store.test.movie.Movie]
20:52:03,864 DEBUG [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,878 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@7cbe6b6d[null,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,886 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,886 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,899 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,899 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,899 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,899 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@38014683[null,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:52:03,901 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@38014683[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:52:03,902 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,902 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,903 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@41c88d13[null,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,905 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,905 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,905 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,905 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,905 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,906 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@1da9c98b[null,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:52:03,908 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@1da9c98b[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:52:03,908 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,908 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,909 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@36068d57[null,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,911 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,911 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@36068d57[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,913 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@36068d57[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,913 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,913 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@67e0d737[null,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,915 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,915 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@67e0d737[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,916 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@67e0d737[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,916 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,916 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@44f5b823[null,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,918 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,918 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,919 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,919 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,919 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,919 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@52b11b02[null,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:52:03,921 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@52b11b02[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:52:03,922 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,922 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,922 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@1531b41f[null,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,924 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,924 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@1531b41f[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,925 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1531b41f[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,925 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,925 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@6cb8414c[null,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,928 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,928 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,929 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,929 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,929 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,930 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@3f8c8d5a[null,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:52:03,932 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@3f8c8d5a[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:52:03,932 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,932 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,933 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@754e3b9a[null,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,935 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,935 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@754e3b9a[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,935 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@754e3b9a[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,936 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,936 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@1b2b1985[null,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,938 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,938 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,939 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,939 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,939 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,939 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@33edc23f[null,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:52:03,941 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@33edc23f[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:52:03,942 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,942 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,942 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,942 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,942 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@36068d57[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,942 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@67e0d737[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,943 DEBUG [qtp1100829986-410][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@67e0d737[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,960 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,960 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1531b41f[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,961 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,961 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@754e3b9a[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,961 DEBUG [qtp1100829986-410][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@754e3b9a[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,963 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,964 DEBUG [qtp1100829986-410][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,964 DEBUG [qtp1100829986-410][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,964 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@7cb3eb59[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,964 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@36068d57[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,965 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@36068d57[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,965 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@36068d57[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,965 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@36068d57[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,965 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@754e3b9a[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,965 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@754e3b9a[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,965 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@754e3b9a[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,965 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@754e3b9a[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,965 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,966 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,966 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,966 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@1da9c98b[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:52:03,966 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@1da9c98b[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:52:03,966 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1da9c98b[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:52:03,966 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@1da9c98b[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:52:03,966 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@41c88d13[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,966 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,966 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,966 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,967 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@52b11b02[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:52:03,967 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@52b11b02[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:52:03,967 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@52b11b02[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:52:03,967 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@52b11b02[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:52:03,967 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@44f5b823[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,967 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@67e0d737[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,967 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@67e0d737[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,967 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@67e0d737[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,967 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@67e0d737[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,968 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,968 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,968 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,968 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@38014683[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:52:03,968 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@38014683[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:52:03,968 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@38014683[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:52:03,968 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@38014683[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:52:03,968 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@7cbe6b6d[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,968 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@1531b41f[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,969 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@1531b41f[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,969 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1531b41f[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,969 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@1531b41f[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,969 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,969 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,969 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,969 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@3f8c8d5a[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:52:03,969 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@3f8c8d5a[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:52:03,969 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3f8c8d5a[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:52:03,969 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@3f8c8d5a[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:52:03,969 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@6cb8414c[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,970 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,970 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,970 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,970 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@33edc23f[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:52:03,971 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@33edc23f[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:52:03,971 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@33edc23f[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:52:03,971 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@33edc23f[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:52:03,971 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@1b2b1985[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,974 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@4d0c4e3b[5,org.cumulus4j.store.test.movie.Movie#movieID[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@62e42b01[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@71c3815f[4,org.cumulus4j.store.test.movie.Movie#languages[collectionElement]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5a62be52[12,org.cumulus4j.store.test.movie.Movie#tagLine[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@8bd27da[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3a835db1[8,org.cumulus4j.store.test.movie.Movie#producedBy[collectionElement]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@67c2d7a[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@428da7e9[2,org.cumulus4j.store.test.movie.Movie#directedBy[collectionElement]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@620ad421[6,org.cumulus4j.store.test.movie.Movie#name[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@34ecf4d0[9,org.cumulus4j.store.test.movie.Movie#rating[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@30bc9ed5[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2f5fcaf2[11,org.cumulus4j.store.test.movie.Movie#starring[collectionElement]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3a04ee2a[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,974 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@19b02528[14,org.cumulus4j.store.test.movie.Movie#writtenBy[collectionElement]]
20:52:03,974 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 1594 ms). class=org.cumulus4j.store.test.movie.Movie
20:52:03,989 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:52:03,989 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=8 value=[]
20:52:03,989 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,990 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3a04ee2a[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:03,990 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,990 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[]
20:52:03,990 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,990 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@30bc9ed5[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:03,990 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=null
20:52:03,990 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,990 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=4 value=[]
20:52:03,990 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,990 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@8bd27da[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:03,990 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,990 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,990 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=1 value=[]
20:52:03,990 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,990 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@62e42b01[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:03,990 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=0 value=[]
20:52:03,991 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:03,991 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@67c2d7a[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:03,991 TRACE [qtp1100829986-410][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
20:52:03,991 INFO  [qtp1100829986-410][AbstractCryptoManager] getEncryptionAlgorithm: Property 'cumulus4j.encryptionAlgorithm' is not set. Using default algorithm 'Twofish/GCM/NoPadding'.
20:52:03,991 INFO  [qtp1100829986-410][AbstractCryptoManager] getMACAlgorithm: Property 'cumulus4j.macAlgorithm' is not set. Using default MAC algorithm 'NONE'.
20:52:11,381 DEBUG [AbstractCryptoManager][AbstractCryptoManager$CloseExpiredSessionsTask] run: entered
20:52:12,235 INFO  [qtp1100829986-410][CryptoCache] Persistence property 'cumulus4j.CryptoCache.cleanupTimer.period' is not set. Using default value 60000.
20:52:12,236 DEBUG [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _query[requestID=7l0nt1zcaylhxbpati1rhsryg]: Entered with request: org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest@7fe14177[7l0nt1zcaylhxbpati1rhsryg,test-i12golvv--wgbjx13976sy_jl3yalwq*1*0tfb0u]
20:52:12,291 DEBUG [qtp1100829986-410][MessageBrokerPMF] [trfdz3] _query[requestID=7l0nt1zcaylhxbpati1rhsryg]: Request persisted.
20:52:12,346 DEBUG [qtp1100829986-413][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: Returning request: org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest@7fe14177[7l0nt1zcaylhxbpati1rhsryg,test-i12golvv--wgbjx13976sy_jl3yalwq*1*0tfb0u]
20:52:12,346 ERROR [qtp1100829986-444][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@a2e1c29" (id="7l0nt1zcaylhxbpati1rhsryg") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
20:52:12,347 DEBUG [qtp1100829986-413][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyRequest matching=true
20:52:12,347 WARN  [qtp1100829986-444][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@a2e1c29" (id="7l0nt1zcaylhxbpati1rhsryg") 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@7fe14177
20:52:12,348 ERROR [qtp1100829986-579][Persistence] Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@5f318bc0" (id="7l0nt1zcaylhxbpati1rhsryg") has been changed in the datastore since your last read. Your transaction is using version "1" but this doesnt exist in the datastore now
20:52:12,349 DEBUG [Thread-518][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Request matching=true
20:52:12,353 WARN  [qtp1100829986-579][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: javax.jdo.JDOOptimisticVerificationException: Transaction failed to commit
NestedThrowables:
javax.jdo.JDOOptimisticVerificationException: Object "org.cumulus4j.store.crypto.keymanager.messagebroker.pmf.PendingRequest@5f318bc0" (id="7l0nt1zcaylhxbpati1rhsryg") 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@7fe14177
20:52:12,362 DEBUG [Thread-518][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.GetActiveEncryptionKeyResponse matching=true
20:52:12,365 DEBUG [qtp1100829986-430][JAXBContextResolver] getContext: objectType=org.cumulus4j.keymanager.back.shared.Response matching=true
20:52:12,368 DEBUG [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pushResponse[requestID=7l0nt1zcaylhxbpati1rhsryg]: Entered.
20:52:12,408 DEBUG [qtp1100829986-430][MessageBrokerPMF] [trfdz3] _pollRequest[cryptoSessionIDPrefix=test-i12golvv--wgbjx13976sy_jl3yalwq]: Entered.
20:52:12,565 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Created new Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with key and IV.
20:52:12,566 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,575 TRACE [qtp1100829986-410][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
20:52:12,575 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,575 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,575 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@67c2d7a[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:12,590 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,590 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,599 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@62e42b01[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:12,602 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,603 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,620 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,620 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,644 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,645 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,653 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@8bd27da[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:12,657 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,657 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,659 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@30bc9ed5[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:12,662 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,663 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,665 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3a04ee2a[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:12,668 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,669 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,673 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Rating
20:52:12,674 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Rating
20:52:12,676 DEBUG [qtp1100829986-410][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@5e262253[null,org.cumulus4j.store.test.movie.Rating]
20:52:12,678 DEBUG [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@5e262253[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,680 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@6ca1f89e[null,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,682 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5e262253[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,682 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@6ca1f89e[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,682 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6ca1f89e[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,682 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5e262253[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,683 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@7fbe0717[null,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,685 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5e262253[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,685 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@7fbe0717[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,687 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7fbe0717[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,687 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5e262253[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,687 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6ca1f89e[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,687 DEBUG [qtp1100829986-410][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@6ca1f89e[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,689 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7fbe0717[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,690 DEBUG [qtp1100829986-410][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@5e262253[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,690 DEBUG [qtp1100829986-410][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@5e262253[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,690 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@5e262253[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,691 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@7fbe0717[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,691 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@7fbe0717[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,691 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7fbe0717[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,691 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@7fbe0717[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,691 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@6ca1f89e[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,691 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@6ca1f89e[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,691 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@6ca1f89e[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,692 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@6ca1f89e[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,692 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@49abc2fb[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,692 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@2b8e1286[16,org.cumulus4j.store.test.movie.Rating#ratingID[primary]]
20:52:12,692 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@7c6231c0[15,org.cumulus4j.store.test.movie.Rating#name[primary]]
20:52:12,692 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 18 ms). class=org.cumulus4j.store.test.movie.Rating
20:52:12,692 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@49abc2fb[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,692 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@49abc2fb[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,692 TRACE [qtp1100829986-410][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
20:52:12,693 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,693 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,695 TRACE [qtp1100829986-410][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
20:52:12,696 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@49abc2fb[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,696 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@49abc2fb[2,org.cumulus4j.store.test.movie.Rating]
20:52:12,699 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,699 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,705 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,705 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,708 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:52:12,708 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=8 value=[]
20:52:12,708 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,708 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3a04ee2a[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:12,708 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,708 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[]
20:52:12,708 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,708 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@30bc9ed5[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:12,708 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=null
20:52:12,708 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,709 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=4 value=[]
20:52:12,709 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,709 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@8bd27da[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:12,709 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,709 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,709 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=1 value=[]
20:52:12,709 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,709 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@62e42b01[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:12,709 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=0 value=[]
20:52:12,709 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,709 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@67c2d7a[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:12,709 TRACE [qtp1100829986-410][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 2
20:52:12,709 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,710 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,712 TRACE [qtp1100829986-410][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 2
20:52:12,712 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@67c2d7a[1,org.cumulus4j.store.test.movie.Movie#directedBy[primary]]
20:52:12,716 TRACE [qtp1100829986-410][CryptoCache] getKeyData: Found cached key with keyID=1.
20:52:12,717 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Created new Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with key and IV.
20:52:12,718 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,718 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,718 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,719 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@62e42b01[3,org.cumulus4j.store.test.movie.Movie#languages[primary]]
20:52:12,732 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,733 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,733 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,733 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,737 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,738 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,743 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,743 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,745 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@8bd27da[7,org.cumulus4j.store.test.movie.Movie#producedBy[primary]]
20:52:12,749 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,750 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,750 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,750 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,751 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@30bc9ed5[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:12,756 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,756 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,756 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,756 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,757 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@3a04ee2a[13,org.cumulus4j.store.test.movie.Movie#writtenBy[primary]]
20:52:12,762 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,762 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,762 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,763 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,765 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: begin loading. class=org.cumulus4j.store.test.movie.Person
20:52:12,765 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] registerClass: clazz=org.cumulus4j.store.test.movie.Person
20:52:12,769 DEBUG [qtp1100829986-410][ClassMeta] jdoPreStore: org.cumulus4j.store.model.ClassMeta@58cd0dc5[null,org.cumulus4j.store.test.movie.Person]
20:52:12,771 DEBUG [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta == null => populating. this=org.cumulus4j.store.model.ClassMeta@58cd0dc5[3,org.cumulus4j.store.test.movie.Person]
20:52:12,772 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@5776c2fd[null,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,774 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@58cd0dc5[3,org.cumulus4j.store.test.movie.Person]
20:52:12,774 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@5776c2fd[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,775 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5776c2fd[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,775 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@58cd0dc5[3,org.cumulus4j.store.test.movie.Person]
20:52:12,776 DEBUG [qtp1100829986-410][FieldMeta] jdoPreStore: org.cumulus4j.store.model.FieldMeta@26c0c60f[null,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,778 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@58cd0dc5[3,org.cumulus4j.store.test.movie.Person]
20:52:12,778 DEBUG [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta == null => populating. this=org.cumulus4j.store.model.FieldMeta@26c0c60f[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,778 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@26c0c60f[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,778 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@58cd0dc5[3,org.cumulus4j.store.test.movie.Person]
20:52:12,779 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5776c2fd[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,779 DEBUG [qtp1100829986-410][FieldMeta] getEmbeddedClassMeta: this.embeddedClassMetaLoaded == false => loading. this=org.cumulus4j.store.model.FieldMeta@5776c2fd[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,781 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@26c0c60f[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,782 DEBUG [qtp1100829986-410][ClassMeta] jdoPostDetach: attached=org.cumulus4j.store.model.ClassMeta@58cd0dc5[3,org.cumulus4j.store.test.movie.Person]
20:52:12,782 DEBUG [qtp1100829986-410][ClassMeta] jdoPostDetach: Detaching this.fieldName2FieldMeta: attached=org.cumulus4j.store.model.ClassMeta@58cd0dc5[3,org.cumulus4j.store.test.movie.Person]
20:52:12,782 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@58cd0dc5[3,org.cumulus4j.store.test.movie.Person]
20:52:12,782 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@5776c2fd[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,783 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@5776c2fd[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,783 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@5776c2fd[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,783 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@5776c2fd[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,783 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: attached=org.cumulus4j.store.model.FieldMeta@26c0c60f[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,783 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.role2SubFieldMeta: attached=org.cumulus4j.store.model.FieldMeta@26c0c60f[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,783 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@26c0c60f[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,783 DEBUG [qtp1100829986-410][FieldMeta] jdoPostDetach: Detaching this.embeddedClassMeta: attached=org.cumulus4j.store.model.FieldMeta@26c0c60f[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,783 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@578fb540[3,org.cumulus4j.store.test.movie.Person]
20:52:12,783 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@1bc4a9a9[17,org.cumulus4j.store.test.movie.Person#name[primary]]
20:52:12,783 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@c5ea47d[18,org.cumulus4j.store.test.movie.Person#personID[primary]]
20:52:12,783 DEBUG [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: end loading (took 18 ms). class=org.cumulus4j.store.test.movie.Person
20:52:12,784 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@578fb540[3,org.cumulus4j.store.test.movie.Person]
20:52:12,784 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@578fb540[3,org.cumulus4j.store.test.movie.Person]
20:52:12,784 TRACE [qtp1100829986-410][Cumulus4jPersistenceHandler] insertObject: Created new DataEntry for: 1
20:52:12,784 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,784 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,787 TRACE [qtp1100829986-410][Cumulus4jPersistenceHandler] insertObject: Persisted new non-embedded DataEntry for: 1
20:52:12,787 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@578fb540[3,org.cumulus4j.store.test.movie.Person]
20:52:12,787 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@578fb540[3,org.cumulus4j.store.test.movie.Person]
20:52:12,790 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,790 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,796 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,796 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,799 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:52:12,815 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,815 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,816 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=5 value=org.cumulus4j.store.test.movie.Rating@1
20:52:12,816 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,816 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Rating
20:52:12,830 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,830 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,831 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,858 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,859 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,861 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:52:12,865 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,865 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,866 TRACE [qtp1100829986-410][StoreFieldManager] storeObjectField: classMeta.className=org.cumulus4j.store.test.movie.Movie fieldNumber=6 value=[org.cumulus4j.store.test.movie.Person@1]
20:52:12,866 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,866 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@30bc9ed5[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:12,866 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Person
20:52:12,869 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,869 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,869 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,869 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@30bc9ed5[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:12,874 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,874 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,875 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,875 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,875 TRACE [qtp1100829986-410][FieldMeta] getRole2SubFieldMeta: this.role2SubFieldMeta != null (already populated). this=org.cumulus4j.store.model.FieldMeta@30bc9ed5[10,org.cumulus4j.store.test.movie.Movie#starring[primary]]
20:52:12,879 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,880 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,885 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,885 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@1989887732 for opmode=ENCRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,914 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:52:12,914 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:52:12,926 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClassID: found cache entry. classID=1
20:52:12,926 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:52:12,942 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,943 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,944 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,944 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,944 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,944 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClassID: found cache entry. classID=1
20:52:12,944 TRACE [qtp1100829986-410][Cumulus4jStoreManager] getClassMetaByClass: found cache entry. class=org.cumulus4j.store.test.movie.Movie
20:52:12,947 TRACE [qtp1100829986-410][CryptoCache] acquireCipherEntry: Found cached Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING and keyID=1. Initialising it with new IV (without key).
20:52:12,947 TRACE [qtp1100829986-410][CryptoCache] releaseCipherEntry: Releasing Cipher@594150535 for opmode=DECRYPT, encryptionAlgorithm=TWOFISH/GCM/NOPADDING keyID=1.
20:52:12,948 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,948 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
20:52:12,948 TRACE [qtp1100829986-410][ClassMeta] getFieldName2FieldMeta: this.fieldName2FieldMeta != null (already populated). this=org.cumulus4j.store.model.ClassMeta@110a9252[1,org.cumulus4j.store.test.movie.Movie]
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 208.922 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