Objects not stored in database after flush and/or commit

qxmk · **Joined:** Thu Apr 03, 2008 11:14 am **Posts:** 10

Hibernate version:
Hibernate 3.2.5
Hibernate Annotations 3.3.0.GA
Bytecode provider name: cglib

Mapping documents:
I use Annotations, but the hibernate.cfg.xml content is

Code:

<?xml version='1.0' encoding='utf-8'?>
<!DOCTYPE hibernate-configuration PUBLIC
"-//Hibernate/Hibernate Configuration DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd">
<hibernate-configuration>
    <session-factory>

        <property name="connection.driver_class">com.mysql.jdbc.Driver</property>
        <property name="connection.url">jdbc:mysql://server/database</property>
        <property name="connection.username">***</property>
        <property name="connection.password">***</property>
        <property name="connection.pool_size">1</property>
        <property name="connection.autocommit">true</property>

        <property name="dialect">org.hibernate.dialect.MySQLDialect</property>
        <property name="current_session_context_class">thread</property>
        <property name="cache.provider_class">org.hibernate.cache.NoCacheProvider</property>
        <property name="show_sql">true</property>
        <property name="format_sql">false</property>
        <property name="use_sql_comments">false</property>
        <property name="hbm2ddl.auto">update</property>
        
        <property name="hibernate.validator.autoregister_listeners">false</property>

        <mapping class="path.to.package.ClassName" /><!-- for each @Entity-annoted class -->

    </session-factory>
</hibernate-configuration>

Code between sessionFactory.openSession() and session.close():
the source is a bit complicated... i have a HibernateUtils class like HibernateUtil in the documentation... and a dedicated method

Code:

frame()

that shall be responsible for session management. To allow recursive calls it uses a

Code:

static private int frameLevel

as a counter. Only the first-level call will use openSession() and afterwards close(). So here is a simplified version of frame():

Code:

    private static int frameLevel = 0; // the counter
    public static boolean frame(HibernateFramedMethod p) {
        // HibernateFramedMethod is an interface with one method
        //   public boolean process(Session s);
        frameLevel++;
        try {
            Session s;
            if (frameLevel <= 1) {
                s = getSessionFactory().openSession();
                s.createSQLQuery("SET SESSION sql_mode='NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'").executeUpdate(); // might be omitted (?)
                s.createSQLQuery("SET NAMES utf8").executeUpdate(); // might be omitted (?)
                s.createSQLQuery("SELECT @@SQL_MODE").uniqueResult(); // might be omitted (?)
            } else {
                s = getSessionFactory().getCurrentSession();
            }
            Transaction tx;
            boolean ok;
            try {
                if (frameLevel <= 1)
                    tx = s.beginTransaction();
                else
                    tx = s.getTransaction();

                if (!tx.isActive()) // might be omitted (?), but sometimes tx.isActive()==false ...why?
                    try { tx.begin(); }
                    catch(HibernateException e) {}

                try { ok = p.process(s); } // calls a callback method
                catch(Exception e) { ok = false; }

                if (frameLevel <= 1) {
                    if (ok) {
                        tx.commit();
                    }
                    else {
                        tx.rollback();
                    }
                }
                
            } finally {
                tx = null;
                if ((frameLevel <= 1) && (s.isOpen())) {
                    s.close();
                }
            }
            return Log.leave(ok);

        } catch(Throwable t) {
            t.printStackTrace();
            return Log.leave(false);
        }
    }

Full stack trace of any exception that occurs:
no exception thrown, but Hibernate behaves in a "strange" way

Name and version of the database you are using:
MySQL 5 (Server version: 5.0.41-community-nt-log MySQL Community Edition (GPL))
for administration I use MySQL Administrator and the MySQL Query Browser (QB)

excerpted from the console:

Code:

JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.0.7 ( $Date: 2007-03-09 22:13:57 +0100 (Fri, 09 Mar 2007) $, $Revision: 6341 $ )

an mysqlqueries.log (MySQL-Variable log) excerpt:

Code:

15:41:39
Connect      ***@server on database
SHOW VARIABLES
SHOW WARNINGS
SHOW COLLATION
SET character_set_results = NULL
SET autocommit=1
SET SESSION sql_mode='STRICT_TRANS_TABLES,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
SET autocommit=0
15:41:41
Connect     ***@server on database
SHOW VARIABLES
SHOW WARNINGS
SHOW COLLATION
SET character_set_results = NULL
SET autocommit=1
SET autocommit=0
select count(*) as col_0_0_ from tableX x0_, tableY y_ where ... and y_.property=0
select x0_.id as id1_6_, x0_.s as s6_, ... from tableX x0_, tableY y_ where ... limit 3
commit
SET autocommit=1
SHOW FULL TABLES FROM `database` LIKE 'tableX'
SHOW FULL TABLES FROM `database` LIKE 'tableX'
SHOW FULL COLUMNS FROM `tableX` FROM `database` LIKE '%'
SHOW CREATE TABLE `database`.`tableX`
SHOW INDEX FROM `tableX` FROM `database`
...//for each entity
SET SESSION sql_mode='NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
SET NAMES utf8
SELECT @@SQL_MODE
SHOW CHARACTER SET
SET autocommit=0
insert into tableX (s, ...) values ('test', ...)
//4 more insert's; one for each newly created object instance
commit
SET autocommit=1
SET SESSION sql_mode='NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
SET NAMES utf8
SELECT @@SQL_MODE
SET autocommit=0
select count(*) as col_0_0_ from tableX x0_, tableY y_ where ... and y_.property=0
select x0_.id as id1_6_, x0_.s as s6_, ... from tableX x0_, tableY y_ where ... limit 3
commit
SET autocommit=1
SET SESSION sql_mode='NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
SET NAMES utf8
SELECT @@SQL_MODE
SET autocommit=0
select x0_.id as id1_6_, x0_.s as s6_, ... from tableX x0_, tableY y_ where ... limit 3
commit
SET autocommit=1

The generated SQL (show_sql=true):
see next section

Debug level Hibernate log excerpt:

Code:

41:37,696  INFO [Version] Hibernate Annotations 3.3.0.GA
41:37,729  INFO [Environment] Hibernate 3.2.5
41:37,733  INFO [Environment] hibernate.properties not found
41:37,738  INFO [Environment] Bytecode provider name : cglib
41:37,745  INFO [Environment] using JDK 1.4 java.sql.Timestamp handling
41:37,879  INFO [Configuration] configuring from resource: /hibernate.cfg.xml
41:37,879  INFO [Configuration] Configuration resource: /hibernate.cfg.xml
41:38,055  INFO [Configuration] Configured SessionFactory: null
41:38,253  INFO [AnnotationBinder] Binding entity from annotated class: classX
41:38,323  INFO [EntityBinder] Bind entity classX on table tableX
...
41:38,841  INFO [CollectionBinder] Mapping collection: classY.members -> tableY
41:38,843  INFO [AnnotationConfiguration] Hibernate Validator not found: ignoring
41:38,868  INFO [DriverManagerConnectionProvider] Using Hibernate built-in connection pool (not for production use!)
41:38,868  INFO [DriverManagerConnectionProvider] Hibernate connection pool size: 1
41:38,868  INFO [DriverManagerConnectionProvider] autocommit mode: true
41:38,881  INFO [DriverManagerConnectionProvider] using driver: com.mysql.jdbc.Driver at URL: jdbc:mysql://server/database
41:38,881  INFO [DriverManagerConnectionProvider] connection properties: {user=***, password=***, autocommit=true}
41:39,360  INFO [SettingsFactory] RDBMS: MySQL, version: 5.0.41-community-nt-log
41:39,360  INFO [SettingsFactory] JDBC driver: MySQL-AB JDBC Driver, version: mysql-connector-java-5.0.7 ( $Date: 2007-03-09 22:13:57 +0100 (Fri, 09 Mar 2007) $, $Revision: 6341 $ )
41:39,388  INFO [Dialect] Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
41:39,396  INFO [TransactionFactoryFactory] Using default transaction strategy (direct JDBC transactions)
41:39,401  INFO [TransactionManagerLookupFactory] No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
41:39,401  INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
41:39,401  INFO [SettingsFactory] Automatic session close at end of transaction: disabled
41:39,401  INFO [SettingsFactory] JDBC batch size: 10
41:39,401  INFO [SettingsFactory] JDBC batch updates for versioned data: disabled
41:39,403  INFO [SettingsFactory] Scrollable result sets: enabled
41:39,403  INFO [SettingsFactory] JDBC3 getGeneratedKeys(): enabled
41:39,403  INFO [SettingsFactory] Connection release mode: auto
41:39,403  INFO [SettingsFactory] Maximum outer join fetch depth: 2
41:39,404  INFO [SettingsFactory] Default batch fetch size: 1
41:39,404  INFO [SettingsFactory] Generate SQL with comments: disabled
41:39,404  INFO [SettingsFactory] Order SQL updates by primary key: disabled
41:39,404  INFO [SettingsFactory] Order SQL inserts for batching: disabled
41:39,404  INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
41:39,406  INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
41:39,406  INFO [SettingsFactory] Query language substitutions: {}
41:39,407  INFO [SettingsFactory] JPA-QL strict compliance: disabled
41:39,407  INFO [SettingsFactory] Second-level cache: enabled
41:39,407  INFO [SettingsFactory] Query cache: disabled
41:39,407  INFO [SettingsFactory] Cache provider: org.hibernate.cache.NoCacheProvider
41:39,407  INFO [SettingsFactory] Optimize cache for minimal puts: disabled
41:39,408  INFO [SettingsFactory] Structured second-level cache entries: disabled
41:39,412  INFO [SettingsFactory] Echoing all SQL to stdout
41:39,412  INFO [SettingsFactory] Statistics: disabled
41:39,412  INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
41:39,413  INFO [SettingsFactory] Default entity-mode: pojo
41:39,413  INFO [SettingsFactory] Named query checking : enabled
41:39,466  INFO [SessionFactoryImpl] building session factory
41:40,634  INFO [SessionFactoryObjectFactory] Not binding factory to JNDI, no JNDI name configured
41:40,639  INFO [MAIN] SessionFactory built: org.hibernate.impl.SessionFactoryImpl@9444d1
Hibernate: SET SESSION sql_mode='STRICT_TRANS_TABLES,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
Hibernate: select count(*) as col_0_0_ from users user0_, attributes attribute1_ where attribute1_.objID+0=user0_.id_user and attribute1_.objType='users' and attribute1_.published=1 and attribute1_.removed=0
Hibernate: select user0_.id_user as id1_6_, user0_.name as name6_, user0_.password as password6_, user0_.personRecord_id_person as personRe5_6_, user0_.recordManager_id_user as recordMa6_6_, user0_.rights as rights6_ from users user0_, attributes attribute1_ where attribute1_.objID+0=user0_.id_user and attribute1_.objType='users' and attribute1_.published=1 and attribute1_.removed=0 limit ?
Hibernate: SET SESSION sql_mode='NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
Hibernate: SET NAMES utf8
Hibernate: SELECT @@SQL_MODE
Hibernate: insert into users (name, password, personRecord_id_person, recordManager_id_user, rights) values (?, ?, ?, ?, ?)
Hibernate: insert into users (name, password, personRecord_id_person, recordManager_id_user, rights) values (?, ?, ?, ?, ?)
Hibernate: insert into users (name, password, personRecord_id_person, recordManager_id_user, rights) values (?, ?, ?, ?, ?)
Hibernate: insert into users (name, password, personRecord_id_person, recordManager_id_user, rights) values (?, ?, ?, ?, ?)
Hibernate: insert into users (name, password, personRecord_id_person, recordManager_id_user, rights) values (?, ?, ?, ?, ?)
Hibernate: SET SESSION sql_mode='NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
Hibernate: SET NAMES utf8
Hibernate: SELECT @@SQL_MODE
Hibernate: select count(*) as col_0_0_ from users user0_, attributes attribute1_ where attribute1_.objID+0=user0_.id_user and attribute1_.objType='users' and (attribute1_.published=1 or attribute1_.creator_id_user='1') and attribute1_.removed=0
Hibernate: select user0_.id_user as id1_6_, user0_.name as name6_, user0_.password as password6_, user0_.personRecord_id_person as personRe5_6_, user0_.recordManager_id_user as recordMa6_6_, user0_.rights as rights6_ from users user0_, attributes attribute1_ where attribute1_.objID+0=user0_.id_user and attribute1_.objType='users' and (attribute1_.published=1 or attribute1_.creator_id_user='1') and attribute1_.removed=0 limit ?
Hibernate: SET SESSION sql_mode='NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
Hibernate: SET NAMES utf8
Hibernate: SELECT @@SQL_MODE
Hibernate: select user0_.id_user as id1_6_, user0_.name as name6_, user0_.password as password6_, user0_.personRecord_id_person as personRe5_6_, user0_.recordManager_id_user as recordMa6_6_, user0_.rights as rights6_ from users user0_
41:41,676  INFO [MAIN] Ausgeführt? (64 ms) > Ja (seit 15:41:40.640)

it's a bit shortened (my own debug messages have been removed); generated with following log4j.properties file:

Code:

### direct log messages to stdout ###
log4j.appender.stdout=org.apache.log4j.ConsoleAppender
log4j.appender.stdout.Target=System.out
log4j.appender.stdout.layout=org.apache.log4j.PatternLayout
log4j.appender.stdout.layout.ConversionPattern=%d{ABSOLUTE} %5p [%c{1}] %m%n

### direct messages to file hibernate.log ###
#log4j.appender.file=org.apache.log4j.FileAppender
#log4j.appender.file.File=hibernate.log
#log4j.appender.file.layout=org.apache.log4j.PatternLayout
#log4j.appender.file.layout.ConversionPattern=%d{ABSOLUTE} %5p %c{1}:%L - %m%n

### set log levels - for more verbose logging change 'info' to 'debug' ###

log4j.rootLogger=warn, stdout

log4j.logger.org.hibernate=info
#log4j.logger.org.hibernate=debug

### log JDBC bind parameters ###
log4j.logger.org.hibernate.type=info

### log schema export/update ###
log4j.logger.org.hibernate.tool.hbm2ddl=warn

Failure description
I call frame() with an callback method that simply creates 50 objects... inside the dedicated createNew() methods frame() is called again with the method calling the constructor of the class, then saving it by saveOrUpdate(). When all 50 objects have been created I try to output a list of all stored objects via createQuery("from X").list() but I only retrieve the "old" that existed before the program started!
When the program finishes their is no data stored in the DB... but if the program waits a few seconds before System.exit(0) then everything becomes confusing! The MySQL QB cannot fetch the new objects (rows) but the Administrator monitors some new items in the Tables view (the number of rows is increased)... and if the sleep time is over the Administrator monitors no new items, too. I tried calling flush() and clear() after each saveOrUpdate(), or disabling auto-commit, but nothing matters and no error occures...

Earlier this month I used eager collection fetching but now I use lazy fetching for every association... due to some efficiency problems.

A last point: when I copy the INSERT INTO statements from the mysqlqueries.log and paste it into the QueryBrowser everything works and the data will be stored in the table... this is an excerpt from the log when using the QueryBrowser:

Code:

19:25:03
Connect     ***@server on database
SELECT @@sql_mode
SET SESSION sql_mode='STRICT_TRANS_TABLES,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION'
SET NAMES utf8
Init DB     database
SELECT @@SQL_MODE
insert into users (name, password, personRecord_id_person, recordManager_id_user, rights) values ('user-0', 'password', null, 1, null)

Final notes
I will post more details soon... elsewise just ask me for method bodies, call hierarchies and so on...

I thank you for help!

qxmk · **Joined:** Thu Apr 03, 2008 11:14 am **Posts:** 10

I'm sorry, the Hibernate log was incomplete... now the log4j is re-configured and the log is:
[code]
19:34:07,790 INFO [Version] Hibernate Annotations 3.3.0.GA
19:34:07,810 INFO [Environment] Hibernate 3.2.5
19:34:07,814 INFO [Environment] hibernate.properties not found
19:34:07,818 INFO [Environment] Bytecode provider name : cglib
19:34:07,824 INFO [Environment] using JDK 1.4 java.sql.Timestamp handling
19:34:07,941 INFO [Configuration] configuring from resource: /hibernate.cfg.xml
19:34:07,941 INFO [Configuration] Configuration resource: /hibernate.cfg.xml
19:34:08,044 DEBUG [DTDEntityResolver] trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd]
19:34:08,044 DEBUG [DTDEntityResolver] recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
19:34:08,045 DEBUG [DTDEntityResolver] located [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd] in classpath
19:34:08,108 DEBUG [Configuration] connection.driver_class=com.mysql.jdbc.Driver
19:34:08,108 DEBUG [Configuration] connection.url=jdbc:mysql://localhost/crm
19:34:08,108 DEBUG [Configuration] connection.username=root
19:34:08,108 DEBUG [Configuration] connection.password=uniadmin
19:34:08,112 DEBUG [Configuration] connection.pool_size=1
19:34:08,113 DEBUG [Configuration] connection.autocommit=true
19:34:08,113 DEBUG [Configuration] jdbc.batch_size=10
19:34:08,113 DEBUG [Configuration] dialect=org.hibernate.dialect.MySQL5InnoDBDialect
19:34:08,113 DEBUG [Configuration] current_session_context_class=thread
19:34:08,113 DEBUG [Configuration] cache.provider_class=org.hibernate.cache.NoCacheProvider
19:34:08,113 DEBUG [Configuration] show_sql=true
19:34:08,113 DEBUG [Configuration] format_sql=false
19:34:08,115 DEBUG [Configuration] use_sql_comments=false
19:34:08,115 DEBUG [Configuration] hbm2ddl.auto=update
19:34:08,115 DEBUG [Configuration] hibernate.validator.autoregister_listeners=false
19:34:08,115 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@fd54d6 [Attribute: name class value "tuc_d_k.crm.database.entities.Address"]
19:34:08,132 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@15a3d6b [Attribute: name class value "tuc_d_k.crm.database.entities.Attribute"]
19:34:08,134 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@1ef9f1d [Attribute: name class value "tuc_d_k.crm.database.entities.Authority"]
19:34:08,137 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@1b16e52 [Attribute: name class value "tuc_d_k.crm.database.entities.Change"]
19:34:08,138 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@4413ee [Attribute: name class value "tuc_d_k.crm.database.entities.Company"]
19:34:08,138 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@197a37c [Attribute: name class value "tuc_d_k.crm.database.entities.Employee"]
19:34:08,142 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@17fa65e [Attribute: name class value "tuc_d_k.crm.database.entities.ForwardingAgency"]
19:34:08,143 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@2808b3 [Attribute: name class value "tuc_d_k.crm.database.entities.Person"]
19:34:08,143 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@922804 [Attribute: name class value "tuc_d_k.crm.database.entities.PrivateClient"]
19:34:08,144 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@b1c260 [Attribute: name class value "tuc_d_k.crm.database.entities.Task"]
19:34:08,146 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@100ab23 [Attribute: name class value "tuc_d_k.crm.database.entities.TaskInvitation"]
19:34:08,148 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@1dd46f7 [Attribute: name class value "tuc_d_k.crm.database.entities.User"]
19:34:08,148 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@df503 [Attribute: name class value "tuc_d_k.crm.database.entities.UserGroup"]
19:34:08,150 DEBUG [AnnotationConfiguration] null<-org.dom4j.tree.DefaultAttribute@d70d7a [Attribute: name class value "tuc_d_k.crm.database.entities.Vendor"]
19:34:08,151 INFO [Configuration] Configured SessionFactory: null
19:34:08,154 DEBUG [Configuration] properties: {show_sql=true, java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, hibernate.connection.url=jdbc:mysql://localhost/crm, sun.management.compiler=HotSpot Client Compiler, use_sql_comments=false, hbm2ddl.auto=update, os.name=Windows Vista, sun.boot.class.path=C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\resources.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\rt.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\sunrsasign.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\jsse.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\jce.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\charsets.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\classes, hibernate.current_session_context_class=thread, sun.desktop=windows, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_04-b12, hibernate.connection.autocommit=true, hibernate.cache.provider_class=org.hibernate.cache.NoCacheProvider, user.name=Matthias, connection.driver_class=com.mysql.jdbc.Driver, current_session_context_class=thread, idea.launcher.bin.path=C:\Program Files (x86)\JetBrains\IntelliJ IDEA 7.0.2\bin, user.language=de, sun.boot.library.path=C:\Program Files (x86)\Java\jdk1.6.0_04\jre\bin, dialect=org.hibernate.dialect.MySQL5InnoDBDialect, java.version=1.6.0_04, user.timezone=Europe/Berlin, jdbc.batch_size=10, sun.arch.data.model=32, java.endorsed.dirs=C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\endorsed, sun.cpu.isalist=, sun.jnu.encoding=Cp1252, file.encoding.pkg=sun.io, file.separator=\, java.specification.name=Java Platform API Specification, hibernate.format_sql=false, java.class.version=50.0, user.country=DE, connection.url=jdbc:mysql://localhost/crm, java.home=C:\Program Files (x86)\Java\jdk1.6.0_04\jre, java.vm.info=mixed mode, sharing, os.version=6.0, path.separator=;, connection.password=uniadmin, java.vm.version=10.0-b19, hibernate.connection.password=uniadmin, user.variant=, hibernate.jdbc.batch_size=10, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=root, hibernate.validator.autoregister_listeners=false, user.home=E:\Matthias Dateien, java.specification.vendor=Sun Microsystems Inc., hibernate.hbm2ddl.auto=update, java.library.path=C:\Program Files (x86)\Java\jdk1.6.0_04\bin;.;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Program Files (x86)\CodeGear\RAD Studio\5.0\bin;C:\Users\Public\Documents\RAD Studio\5.0\Bpl;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Program Files (x86)\Bitvise Tunnelier;C:\Program Files (x86)\MySQL\MySQL Server 5.0\bin;C:\Program Files (x86)\Smart Projects\IsoBuster, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=com.mysql.jdbc.Driver, connection.username=root, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=org.hibernate.dialect.MySQL5InnoDBDialect, java.runtime.name=Java(TM) SE Runtime Environment, java.class.path=C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\charsets.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\deploy.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\javaws.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\jce.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\jsse.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\management-agent.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\plugin.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\resources.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\rt.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\ext\dnsns.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\ext\localedata.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\ext\sunjce_provider.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\ext\sunmscapi.jar;C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\ext\sunpkcs11.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\out\production\crm_database;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\hibernate3.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\dom4j.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\cglib.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\servlet-2_5-api.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\commons-lang-2.3.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\commons-collections.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\asm.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\log4j-1.2.11.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\commons-logging.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\antlr.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database\lib\jta.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_test\lib\mysql-connector-java-5.0.7-bin.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_test\lib\hibernate-annotations.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_test\lib\ejb3-persistence.jar;E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_test\lib\hibernate-commons-annotations.jar;E:\Matthias Dateien\Desktop\Java Bibliotheken\JDom\jdom-1.1\jdom-1.1\build\jdom.jar;C:\Program Files (x86)\JetBrains\IntelliJ IDEA 7.0.2\lib\idea_rt.jar, hibernate.bytecode.use_reflection_optimizer=false, idea.launcher.port=7537, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, sun.cpu.endian=little, sun.os.patch.level=Service Pack 1, connection.autocommit=true, connection.pool_size=1, java.io.tmpdir=C:\Users\Matthias\AppData\Local\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\Program Files (x86)\Java\jdk1.6.0_04\jre\lib\ext;C:\Windows\Sun\Java\lib\ext, hibernate.use_sql_comments=false, user.dir=E:\Matthias Dateien\Desktop\IDEA Projekte\SIGMA\crm_database, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, cache.provider_class=org.hibernate.cache.NoCacheProvider, file.encoding=windows-1252, format_sql=false, java.specification.version=1.6, hibernate.show_sql=true, hibernate.connection.pool_size=1}
19:34:08,158 DEBUG [AnnotationConfiguration] Validator not present in classpath, ignoring event listener registration
19:34:08,158 DEBUG [AnnotationConfiguration] Search not present in classpath, ignoring event listener registration
19:34:08,159 DEBUG [Configuration] Preparing to build session factory with filters : {}
19:34:08,159 DEBUG [AnnotationConfiguration] Execute first pass mapping processing
19:34:08,349 DEBUG [AnnotationConfiguration] Process hbm files
19:34:08,350 DEBUG [AnnotationConfiguration] Process annotated classes
19:34:08,359 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.Address
19:34:08,378 DEBUG [Ejb3Column] Binding column DTYPE unique false
19:34:08,399 DEBUG [EntityBinder] Import with entity name=Address
19:34:08,406 INFO [EntityBinder] Bind entity tuc_d_k.crm.database.entities.Address on table addresses
19:34:08,418 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.Address property annotation
19:34:08,765 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.id_address
19:34:08,769 DEBUG [Ejb3Column] Binding column id_address unique false
19:34:08,769 DEBUG [AnnotationBinder] id_address is an id
19:34:08,772 DEBUG [SimpleValueBinder] building SimpleValue for id_address
19:34:08,813 DEBUG [PropertyBinder] Building property id_address
19:34:08,817 DEBUG [PropertyBinder] Cascading id_address with null
19:34:08,817 DEBUG [AnnotationBinder] Bind @Id on id_address
19:34:08,818 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.belongsTo
19:34:08,822 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,822 DEBUG [Ejb3Column] Binding column belongsTo unique false
19:34:08,828 DEBUG [PropertyBinder] Building property belongsTo
19:34:08,828 DEBUG [PropertyBinder] Cascading belongsTo with none
19:34:08,828 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.country
19:34:08,828 DEBUG [Ejb3Column] Binding column country unique false
19:34:08,829 DEBUG [PropertyBinder] binding property country with lazy=false
19:34:08,832 DEBUG [SimpleValueBinder] building SimpleValue for country
19:34:08,833 DEBUG [PropertyBinder] Building property country
19:34:08,833 DEBUG [PropertyBinder] Cascading country with null
19:34:08,833 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.district
19:34:08,833 DEBUG [Ejb3Column] Binding column district unique false
19:34:08,833 DEBUG [PropertyBinder] binding property district with lazy=false
19:34:08,833 DEBUG [SimpleValueBinder] building SimpleValue for district
19:34:08,834 DEBUG [PropertyBinder] Building property district
19:34:08,834 DEBUG [PropertyBinder] Cascading district with null
19:34:08,834 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.number
19:34:08,834 DEBUG [Ejb3Column] Binding column number unique false
19:34:08,834 DEBUG [PropertyBinder] binding property number with lazy=false
19:34:08,834 DEBUG [SimpleValueBinder] building SimpleValue for number
19:34:08,834 DEBUG [PropertyBinder] Building property number
19:34:08,836 DEBUG [PropertyBinder] Cascading number with null
19:34:08,836 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.poBox
19:34:08,836 DEBUG [Ejb3Column] Binding column poBox unique false
19:34:08,836 DEBUG [PropertyBinder] binding property poBox with lazy=false
19:34:08,836 DEBUG [SimpleValueBinder] building SimpleValue for poBox
19:34:08,836 DEBUG [PropertyBinder] Building property poBox
19:34:08,836 DEBUG [PropertyBinder] Cascading poBox with null
19:34:08,837 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.recordManager
19:34:08,838 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,838 DEBUG [Ejb3Column] Binding column recordManager unique false
19:34:08,838 DEBUG [PropertyBinder] Building property recordManager
19:34:08,838 DEBUG [PropertyBinder] Cascading recordManager with none
19:34:08,838 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.state
19:34:08,838 DEBUG [Ejb3Column] Binding column state unique false
19:34:08,839 DEBUG [PropertyBinder] binding property state with lazy=false
19:34:08,840 DEBUG [SimpleValueBinder] building SimpleValue for state
19:34:08,840 DEBUG [PropertyBinder] Building property state
19:34:08,840 DEBUG [PropertyBinder] Cascading state with null
19:34:08,840 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.street
19:34:08,840 DEBUG [Ejb3Column] Binding column street unique false
19:34:08,840 DEBUG [PropertyBinder] binding property street with lazy=false
19:34:08,841 DEBUG [SimpleValueBinder] building SimpleValue for street
19:34:08,842 DEBUG [PropertyBinder] Building property street
19:34:08,842 DEBUG [PropertyBinder] Cascading street with null
19:34:08,842 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.town
19:34:08,842 DEBUG [Ejb3Column] Binding column town unique false
19:34:08,842 DEBUG [PropertyBinder] binding property town with lazy=false
19:34:08,842 DEBUG [SimpleValueBinder] building SimpleValue for town
19:34:08,842 DEBUG [PropertyBinder] Building property town
19:34:08,842 DEBUG [PropertyBinder] Cascading town with null
19:34:08,844 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.type
19:34:08,844 DEBUG [Ejb3Column] Binding column type unique false
19:34:08,844 DEBUG [PropertyBinder] binding property type with lazy=false
19:34:08,844 DEBUG [SimpleValueBinder] building SimpleValue for type
19:34:08,844 DEBUG [PropertyBinder] Building property type
19:34:08,844 DEBUG [PropertyBinder] Cascading type with null
19:34:08,844 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Address.zipCode
19:34:08,846 DEBUG [Ejb3Column] Binding column zipCode unique false
19:34:08,846 DEBUG [PropertyBinder] binding property zipCode with lazy=false
19:34:08,846 DEBUG [SimpleValueBinder] building SimpleValue for zipCode
19:34:08,846 DEBUG [PropertyBinder] Building property zipCode
19:34:08,846 DEBUG [PropertyBinder] Cascading zipCode with null
19:34:08,848 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.Attribute
19:34:08,849 DEBUG [Ejb3Column] Binding column DTYPE unique false
19:34:08,849 DEBUG [EntityBinder] Import with entity name=Attribute
19:34:08,849 INFO [EntityBinder] Bind entity tuc_d_k.crm.database.entities.Attribute on table attributes
19:34:08,849 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.Attribute property annotation
19:34:08,875 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.id_attribute
19:34:08,875 DEBUG [Ejb3Column] Binding column id_attribute unique false
19:34:08,875 DEBUG [AnnotationBinder] id_attribute is an id
19:34:08,875 DEBUG [SimpleValueBinder] building SimpleValue for id_attribute
19:34:08,875 DEBUG [PropertyBinder] Building property id_attribute
19:34:08,875 DEBUG [PropertyBinder] Cascading id_attribute with null
19:34:08,875 DEBUG [AnnotationBinder] Bind @Id on id_attribute
19:34:08,876 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.CTime
19:34:08,876 DEBUG [Ejb3Column] Binding column ctime unique false
19:34:08,876 DEBUG [PropertyBinder] binding property CTime with lazy=false
19:34:08,876 DEBUG [SimpleValueBinder] building SimpleValue for CTime
19:34:08,876 DEBUG [PropertyBinder] Building property CTime
19:34:08,876 DEBUG [PropertyBinder] Cascading CTime with null
19:34:08,876 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.creator
19:34:08,878 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,878 DEBUG [Ejb3Column] Binding column creator unique false
19:34:08,878 DEBUG [PropertyBinder] Building property creator
19:34:08,878 DEBUG [PropertyBinder] Cascading creator with none
19:34:08,878 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.description
19:34:08,878 DEBUG [Ejb3Column] Binding column description unique false
19:34:08,878 DEBUG [PropertyBinder] binding property description with lazy=false
19:34:08,881 DEBUG [SimpleValueBinder] building SimpleValue for description
19:34:08,881 DEBUG [PropertyBinder] Building property description
19:34:08,881 DEBUG [PropertyBinder] Cascading description with null
19:34:08,881 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.locked
19:34:08,881 DEBUG [Ejb3Column] Binding column locked unique false
19:34:08,881 DEBUG [PropertyBinder] binding property locked with lazy=false
19:34:08,881 DEBUG [SimpleValueBinder] building SimpleValue for locked
19:34:08,882 DEBUG [PropertyBinder] Building property locked
19:34:08,882 DEBUG [PropertyBinder] Cascading locked with null
19:34:08,882 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.objectID
19:34:08,882 DEBUG [Ejb3Column] Binding column objID unique false
19:34:08,882 DEBUG [PropertyBinder] binding property objectID with lazy=false
19:34:08,882 DEBUG [SimpleValueBinder] building SimpleValue for objectID
19:34:08,882 DEBUG [PropertyBinder] Building property objectID
19:34:08,884 DEBUG [PropertyBinder] Cascading objectID with null
19:34:08,884 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.objectType
19:34:08,885 DEBUG [Ejb3Column] Binding column objType unique false
19:34:08,885 DEBUG [PropertyBinder] binding property objectType with lazy=false
19:34:08,885 DEBUG [SimpleValueBinder] building SimpleValue for objectType
19:34:08,885 DEBUG [PropertyBinder] Building property objectType
19:34:08,885 DEBUG [PropertyBinder] Cascading objectType with null
19:34:08,886 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.published
19:34:08,886 DEBUG [Ejb3Column] Binding column published unique false
19:34:08,887 DEBUG [PropertyBinder] binding property published with lazy=false
19:34:08,887 DEBUG [SimpleValueBinder] building SimpleValue for published
19:34:08,887 DEBUG [PropertyBinder] Building property published
19:34:08,887 DEBUG [PropertyBinder] Cascading published with null
19:34:08,887 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Attribute.removed
19:34:08,888 DEBUG [Ejb3Column] Binding column removed unique false
19:34:08,888 DEBUG [PropertyBinder] binding property removed with lazy=false
19:34:08,888 DEBUG [SimpleValueBinder] building SimpleValue for removed
19:34:08,888 DEBUG [PropertyBinder] Building property removed
19:34:08,888 DEBUG [PropertyBinder] Cascading removed with null
19:34:08,888 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.Person
19:34:08,888 DEBUG [Ejb3Column] Binding column personType unique false
19:34:08,890 DEBUG [EntityBinder] Import with entity name=Person
19:34:08,890 INFO [EntityBinder] Bind entity tuc_d_k.crm.database.entities.Person on table persons
19:34:08,890 DEBUG [AnnotationBinder] Setting discriminator for entity tuc_d_k.crm.database.entities.Person
19:34:08,890 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.Person property annotation
19:34:08,908 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.id_person
19:34:08,909 DEBUG [Ejb3Column] Binding column id_person unique false
19:34:08,909 DEBUG [AnnotationBinder] id_person is an id
19:34:08,909 DEBUG [SimpleValueBinder] building SimpleValue for id_person
19:34:08,909 DEBUG [PropertyBinder] Building property id_person
19:34:08,909 DEBUG [PropertyBinder] Cascading id_person with null
19:34:08,909 DEBUG [AnnotationBinder] Bind @Id on id_person
19:34:08,909 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.cellPhoneNumber
19:34:08,911 DEBUG [Ejb3Column] Binding column cellPhoneNumber unique false
19:34:08,911 DEBUG [PropertyBinder] binding property cellPhoneNumber with lazy=false
19:34:08,911 DEBUG [SimpleValueBinder] building SimpleValue for cellPhoneNumber
19:34:08,911 DEBUG [PropertyBinder] Building property cellPhoneNumber
19:34:08,911 DEBUG [PropertyBinder] Cascading cellPhoneNumber with null
19:34:08,911 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.dateOfBirth
19:34:08,912 DEBUG [Ejb3Column] Binding column dateOfBirth unique false
19:34:08,913 DEBUG [PropertyBinder] binding property dateOfBirth with lazy=false
19:34:08,913 DEBUG [SimpleValueBinder] building SimpleValue for dateOfBirth
19:34:08,913 DEBUG [PropertyBinder] Building property dateOfBirth
19:34:08,913 DEBUG [PropertyBinder] Cascading dateOfBirth with null
19:34:08,913 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.dateOfEstimation
19:34:08,913 DEBUG [Ejb3Column] Binding column dateOfEstimation unique false
19:34:08,913 DEBUG [PropertyBinder] binding property dateOfEstimation with lazy=false
19:34:08,915 DEBUG [SimpleValueBinder] building SimpleValue for dateOfEstimation
19:34:08,915 DEBUG [PropertyBinder] Building property dateOfEstimation
19:34:08,915 DEBUG [PropertyBinder] Cascading dateOfEstimation with null
19:34:08,915 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.defaultAddress
19:34:08,915 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,915 DEBUG [Ejb3Column] Binding column defaultAddress unique false
19:34:08,915 DEBUG [PropertyBinder] Building property defaultAddress
19:34:08,917 DEBUG [PropertyBinder] Cascading defaultAddress with none
19:34:08,917 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.emailAddress
19:34:08,917 DEBUG [Ejb3Column] Binding column emailAddress unique false
19:34:08,917 DEBUG [PropertyBinder] binding property emailAddress with lazy=false
19:34:08,917 DEBUG [SimpleValueBinder] building SimpleValue for emailAddress
19:34:08,917 DEBUG [PropertyBinder] Building property emailAddress
19:34:08,917 DEBUG [PropertyBinder] Cascading emailAddress with null
19:34:08,919 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.faxNumber
19:34:08,919 DEBUG [Ejb3Column] Binding column faxNumber unique false
19:34:08,919 DEBUG [PropertyBinder] binding property faxNumber with lazy=false
19:34:08,919 DEBUG [SimpleValueBinder] building SimpleValue for faxNumber
19:34:08,920 DEBUG [PropertyBinder] Building property faxNumber
19:34:08,920 DEBUG [PropertyBinder] Cascading faxNumber with null
19:34:08,920 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.lastResults
19:34:08,921 DEBUG [Ejb3Column] Binding column lastResult unique false
19:34:08,922 DEBUG [PropertyBinder] binding property lastResults with lazy=false
19:34:08,922 DEBUG [SimpleValueBinder] building SimpleValue for lastResults
19:34:08,922 DEBUG [PropertyBinder] Building property lastResults
19:34:08,922 DEBUG [PropertyBinder] Cascading lastResults with null
19:34:08,922 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.leadStatus
19:34:08,922 DEBUG [Ejb3Column] Binding column leadStatus unique false
19:34:08,923 DEBUG [PropertyBinder] binding property leadStatus with lazy=false
19:34:08,923 DEBUG [SimpleValueBinder] building SimpleValue for leadStatus
19:34:08,923 DEBUG [PropertyBinder] Building property leadStatus
19:34:08,923 DEBUG [PropertyBinder] Cascading leadStatus with null
19:34:08,923 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.mailTitle
19:34:08,923 DEBUG [Ejb3Column] Binding column mailTitle unique false
19:34:08,923 DEBUG [PropertyBinder] binding property mailTitle with lazy=false
19:34:08,925 DEBUG [SimpleValueBinder] building SimpleValue for mailTitle
19:34:08,925 DEBUG [PropertyBinder] Building property mailTitle
19:34:08,925 DEBUG [PropertyBinder] Cascading mailTitle with null
19:34:08,925 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.memberOf
19:34:08,925 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,925 DEBUG [Ejb3Column] Binding column memberOf unique false
19:34:08,926 DEBUG [PropertyBinder] Building property memberOf
19:34:08,927 DEBUG [PropertyBinder] Cascading memberOf with none
19:34:08,927 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.members
19:34:08,927 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,927 DEBUG [Ejb3Column] Binding column members unique false
19:34:08,929 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,948 DEBUG [Ejb3Column] Binding column element unique false
19:34:08,949 DEBUG [Ejb3Column] Binding column mapkey unique false
19:34:08,950 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,950 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,950 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,952 DEBUG [CollectionBinder] Collection role: tuc_d_k.crm.database.entities.Person.members
19:34:08,955 DEBUG [PropertyBinder] Building property members
19:34:08,955 DEBUG [PropertyBinder] Cascading members with none
19:34:08,955 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.phoneNumber
19:34:08,955 DEBUG [Ejb3Column] Binding column phoneNumber unique false
19:34:08,955 DEBUG [PropertyBinder] binding property phoneNumber with lazy=false
19:34:08,955 DEBUG [SimpleValueBinder] building SimpleValue for phoneNumber
19:34:08,955 DEBUG [PropertyBinder] Building property phoneNumber
19:34:08,956 DEBUG [PropertyBinder] Cascading phoneNumber with null
19:34:08,957 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.recordManager
19:34:08,957 DEBUG [Ejb3Column] Binding column null unique false
19:34:08,957 DEBUG [Ejb3Column] Binding column recordManager unique false
19:34:08,957 DEBUG [PropertyBinder] Building property recordManager
19:34:08,957 DEBUG [PropertyBinder] Cascading recordManager with none
19:34:08,957 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.sortName
19:34:08,958 DEBUG [Ejb3Column] Binding column sortName unique false
19:34:08,958 DEBUG [PropertyBinder] binding property sortName with lazy=false
19:34:08,958 DEBUG [SimpleValueBinder] building SimpleValue for sortName
19:34:08,958 DEBUG [PropertyBinder] Building property sortName
19:34:08,958 DEBUG [PropertyBinder] Cascading sortName with null
19:34:08,958 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.surName
19:34:08,958 DEBUG [Ejb3Column] Binding column surName unique false
19:34:08,960 DEBUG [PropertyBinder] binding property surName with lazy=false
19:34:08,960 DEBUG [SimpleValueBinder] building SimpleValue for surName
19:34:08,960 DEBUG [PropertyBinder] Building property surName
19:34:08,960 DEBUG [PropertyBinder] Cascading surName with null
19:34:08,960 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Person.type
19:34:08,960 DEBUG [Ejb3Column] Binding column personType unique false
19:34:08,960 DEBUG [PropertyBinder] binding property type with lazy=false
19:34:08,965 DEBUG [SimpleValueBinder] building SimpleValue for type
19:34:08,965 DEBUG [PropertyBinder] Building property type
19:34:08,965 DEBUG [PropertyBinder] Cascading type with null
19:34:08,966 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.LegalPerson
19:34:08,967 DEBUG [EntityBinder] Import with entity name=LegalPerson
19:34:08,982 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.LegalPerson property annotation
19:34:08,990 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.abcPotential
19:34:08,990 DEBUG [Ejb3Column] Binding column abcPotential unique false
19:34:08,990 DEBUG [PropertyBinder] binding property abcPotential with lazy=false
19:34:08,990 DEBUG [SimpleValueBinder] building SimpleValue for abcPotential
19:34:08,990 DEBUG [PropertyBinder] Building property abcPotential
19:34:08,990 DEBUG [PropertyBinder] Cascading abcPotential with null
19:34:08,990 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.accumulatedCapital
19:34:08,991 DEBUG [Ejb3Column] Binding column accumulatedCapital unique false
19:34:08,991 DEBUG [PropertyBinder] binding property accumulatedCapital with lazy=false
19:34:08,991 DEBUG [SimpleValueBinder] building SimpleValue for accumulatedCapital
19:34:08,991 DEBUG [PropertyBinder] Building property accumulatedCapital
19:34:08,991 DEBUG [PropertyBinder] Cascading accumulatedCapital with null
19:34:08,991 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.annualRevenue
19:34:08,993 DEBUG [Ejb3Column] Binding column annualRevenue unique false
19:34:08,993 DEBUG [PropertyBinder] binding property annualRevenue with lazy=false
19:34:08,993 DEBUG [SimpleValueBinder] building SimpleValue for annualRevenue
19:34:08,993 DEBUG [PropertyBinder] Building property annualRevenue
19:34:08,993 DEBUG [PropertyBinder] Cascading annualRevenue with null
19:34:08,993 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.annualTurnover
19:34:08,993 DEBUG [Ejb3Column] Binding column annualTurnover unique false
19:34:08,995 DEBUG [PropertyBinder] binding property annualTurnover with lazy=false
19:34:08,995 DEBUG [SimpleValueBinder] building SimpleValue for annualTurnover
19:34:08,995 DEBUG [PropertyBinder] Building property annualTurnover
19:34:08,995 DEBUG [PropertyBinder] Cascading annualTurnover with null
19:34:08,995 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.businessFields
19:34:08,995 DEBUG [Ejb3Column] Binding column businessFields unique false
19:34:08,996 DEBUG [PropertyBinder] binding property businessFields with lazy=false
19:34:08,997 DEBUG [SimpleValueBinder] building SimpleValue for businessFields
19:34:08,997 DEBUG [PropertyBinder] Building property businessFields
19:34:08,997 DEBUG [PropertyBinder] Cascading businessFields with null
19:34:08,997 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.countryOfHQ
19:34:08,997 DEBUG [Ejb3Column] Binding column countryOfHQ unique false
19:34:08,997 DEBUG [PropertyBinder] binding property countryOfHQ with lazy=false
19:34:08,997 DEBUG [SimpleValueBinder] building SimpleValue for countryOfHQ
19:34:08,999 DEBUG [PropertyBinder] Building property countryOfHQ
19:34:08,999 DEBUG [PropertyBinder] Cascading countryOfHQ with null
19:34:08,999 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.dateOf1stBusiness
19:34:08,999 DEBUG [Ejb3Column] Binding column dateOf1stBusiness unique false
19:34:08,999 DEBUG [PropertyBinder] binding property dateOf1stBusiness with lazy=false
19:34:08,999 DEBUG [SimpleValueBinder] building SimpleValue for dateOf1stBusiness
19:34:08,999 DEBUG [PropertyBinder] Building property dateOf1stBusiness
19:34:09,001 DEBUG [PropertyBinder] Cascading dateOf1stBusiness with null
19:34:09,001 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.economicSectors
19:34:09,001 DEBUG [Ejb3Column] Binding column economicSectors unique false
19:34:09,001 DEBUG [PropertyBinder] binding property economicSectors with lazy=false
19:34:09,001 DEBUG [SimpleValueBinder] building SimpleValue for economicSectors
19:34:09,001 DEBUG [PropertyBinder] Building property economicSectors
19:34:09,003 DEBUG [PropertyBinder] Cascading economicSectors with null
19:34:09,003 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.newsletter
19:34:09,003 DEBUG [Ejb3Column] Binding column newsletter unique false
19:34:09,003 DEBUG [PropertyBinder] binding property newsletter with lazy=false
19:34:09,003 DEBUG [SimpleValueBinder] building SimpleValue for newsletter
19:34:09,003 DEBUG [PropertyBinder] Building property newsletter
19:34:09,003 DEBUG [PropertyBinder] Cascading newsletter with null
19:34:09,005 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.ourTerms
19:34:09,005 DEBUG [Ejb3Column] Binding column ourTerms unique false
19:34:09,005 DEBUG [PropertyBinder] binding property ourTerms with lazy=false
19:34:09,005 DEBUG [SimpleValueBinder] building SimpleValue for ourTerms
19:34:09,005 DEBUG [PropertyBinder] Building property ourTerms
19:34:09,005 DEBUG [PropertyBinder] Cascading ourTerms with null
19:34:09,005 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.sumOfBalances
19:34:09,007 DEBUG [Ejb3Column] Binding column sumOfBalances unique false
19:34:09,007 DEBUG [PropertyBinder] binding property sumOfBalances with lazy=false
19:34:09,007 DEBUG [SimpleValueBinder] building SimpleValue for sumOfBalances
19:34:09,007 DEBUG [PropertyBinder] Building property sumOfBalances
19:34:09,007 DEBUG [PropertyBinder] Cascading sumOfBalances with null
19:34:09,007 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.thereTerms
19:34:09,007 DEBUG [Ejb3Column] Binding column thereTerms unique false
19:34:09,009 DEBUG [PropertyBinder] binding property thereTerms with lazy=false
19:34:09,009 DEBUG [SimpleValueBinder] building SimpleValue for thereTerms
19:34:09,009 DEBUG [PropertyBinder] Building property thereTerms
19:34:09,009 DEBUG [PropertyBinder] Cascading thereTerms with null
19:34:09,009 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.LegalPerson.websiteURL
19:34:09,009 DEBUG [Ejb3Column] Binding column websiteURL unique false
19:34:09,009 DEBUG [PropertyBinder] binding property websiteURL with lazy=false
19:34:09,011 DEBUG [SimpleValueBinder] building SimpleValue for websiteURL
19:34:09,011 DEBUG [PropertyBinder] Building property websiteURL
19:34:09,012 DEBUG [PropertyBinder] Cascading websiteURL with null
19:34:09,012 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.Company
19:34:09,012 DEBUG [EntityBinder] Import with entity name=Company
19:34:09,012 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.Company property annotation
19:34:09,021 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Company.VATNumber
19:34:09,021 DEBUG [Ejb3Column] Binding column VATNumber unique false
19:34:09,021 DEBUG [PropertyBinder] binding property VATNumber with lazy=false
19:34:09,021 DEBUG [SimpleValueBinder] building SimpleValue for VATNumber
19:34:09,021 DEBUG [PropertyBinder] Building property VATNumber
19:34:09,021 DEBUG [PropertyBinder] Cascading VATNumber with null
19:34:09,021 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Company.businessConditions
19:34:09,023 DEBUG [Ejb3Column] Binding column businessConditions unique false
19:34:09,023 DEBUG [PropertyBinder] binding property businessConditions with lazy=false
19:34:09,023 DEBUG [SimpleValueBinder] building SimpleValue for businessConditions
19:34:09,023 DEBUG [PropertyBinder] Building property businessConditions
19:34:09,023 DEBUG [PropertyBinder] Cascading businessConditions with null
19:34:09,023 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Company.courtOfRegistry
19:34:09,024 DEBUG [Ejb3Column] Binding column courtOfRegistry unique false
19:34:09,024 DEBUG [PropertyBinder] binding property courtOfRegistry with lazy=false
19:34:09,024 DEBUG [SimpleValueBinder] building SimpleValue for courtOfRegistry
19:34:09,024 DEBUG [PropertyBinder] Building property courtOfRegistry
19:34:09,024 DEBUG [PropertyBinder] Cascading courtOfRegistry with null
19:34:09,024 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Company.hoppenstedtID
19:34:09,024 DEBUG [Ejb3Column] Binding column hoppenstedtID unique false
19:34:09,027 DEBUG [PropertyBinder] binding property hoppenstedtID with lazy=false
19:34:09,027 DEBUG [SimpleValueBinder] building SimpleValue for hoppenstedtID
19:34:09,027 DEBUG [PropertyBinder] Building property hoppenstedtID
19:34:09,027 DEBUG [PropertyBinder] Cascading hoppenstedtID with null
19:34:09,027 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Company.numEmployees
19:34:09,027 DEBUG [Ejb3Column] Binding column numEmployees unique false
19:34:09,027 DEBUG [PropertyBinder] binding property numEmployees with lazy=false
19:34:09,028 DEBUG [SimpleValueBinder] building SimpleValue for numEmployees
19:34:09,028 DEBUG [PropertyBinder] Building property numEmployees
19:34:09,028 DEBUG [PropertyBinder] Cascading numEmployees with null
19:34:09,028 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Company.taxNumber
19:34:09,028 DEBUG [Ejb3Column] Binding column taxNumber unique false
19:34:09,028 DEBUG [PropertyBinder] binding property taxNumber with lazy=false
19:34:09,028 DEBUG [SimpleValueBinder] building SimpleValue for taxNumber
19:34:09,030 DEBUG [PropertyBinder] Building property taxNumber
19:34:09,030 DEBUG [PropertyBinder] Cascading taxNumber with null
19:34:09,030 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Company.tradeRegisterID
19:34:09,030 DEBUG [Ejb3Column] Binding column tradeRegisterID unique false
19:34:09,030 DEBUG [PropertyBinder] binding property tradeRegisterID with lazy=false
19:34:09,030 DEBUG [SimpleValueBinder] building SimpleValue for tradeRegisterID
19:34:09,030 DEBUG [PropertyBinder] Building property tradeRegisterID
19:34:09,032 DEBUG [PropertyBinder] Cascading tradeRegisterID with null
19:34:09,032 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.Authority
19:34:09,032 DEBUG [EntityBinder] Import with entity name=Authority
19:34:09,033 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.Authority property annotation
19:34:09,035 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Authority.level
19:34:09,035 DEBUG [Ejb3Column] Binding column level unique false
19:34:09,035 DEBUG [PropertyBinder] binding property level with lazy=false
19:34:09,036 DEBUG [SimpleValueBinder] building SimpleValue for level
19:34:09,036 DEBUG [PropertyBinder] Building property level
19:34:09,036 DEBUG [PropertyBinder] Cascading level with null
19:34:09,036 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.Change
19:34:09,036 DEBUG [Ejb3Column] Binding column DTYPE unique false
19:34:09,036 DEBUG [EntityBinder] Import with entity name=Change
19:34:09,037 INFO [EntityBinder] Bind entity tuc_d_k.crm.database.entities.Change on table changes
19:34:09,038 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.Change property annotation
19:34:09,047 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Change.id_change
19:34:09,048 DEBUG [Ejb3Column] Binding column id_change unique false
19:34:09,048 DEBUG [AnnotationBinder] id_change is an id
19:34:09,048 DEBUG [SimpleValueBinder] building SimpleValue for id_change
19:34:09,048 DEBUG [PropertyBinder] Building property id_change
19:34:09,048 DEBUG [PropertyBinder] Cascading id_change with null
19:34:09,048 DEBUG [AnnotationBinder] Bind @Id on id_change
19:34:09,048 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Change.newValue
19:34:09,048 DEBUG [Ejb3Column] Binding column newValue unique false
19:34:09,050 DEBUG [PropertyBinder] binding property newValue with lazy=false
19:34:09,050 DEBUG [SimpleValueBinder] building SimpleValue for newValue
19:34:09,050 DEBUG [PropertyBinder] Building property newValue
19:34:09,050 DEBUG [PropertyBinder] Cascading newValue with null
19:34:09,050 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Change.objectAttribute
19:34:09,050 DEBUG [Ejb3Column] Binding column objectAttribute unique false
19:34:09,050 DEBUG [PropertyBinder] binding property objectAttribute with lazy=false
19:34:09,052 DEBUG [SimpleValueBinder] building SimpleValue for objectAttribute
19:34:09,052 DEBUG [PropertyBinder] Building property objectAttribute
19:34:09,052 DEBUG [PropertyBinder] Cascading objectAttribute with null
19:34:09,052 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Change.objectID
19:34:09,052 DEBUG [Ejb3Column] Binding column objectID unique false
19:34:09,052 DEBUG [PropertyBinder] binding property objectID with lazy=false
19:34:09,053 DEBUG [SimpleValueBinder] building SimpleValue for objectID
19:34:09,054 DEBUG [PropertyBinder] Building property objectID
19:34:09,054 DEBUG [PropertyBinder] Cascading objectID with null
19:34:09,054 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Change.objectType
19:34:09,054 DEBUG [Ejb3Column] Binding column objectType unique false
19:34:09,054 DEBUG [PropertyBinder] binding property objectType with lazy=false
19:34:09,054 DEBUG [SimpleValueBinder] building SimpleValue for objectType
19:34:09,054 DEBUG [PropertyBinder] Building property objectType
19:34:09,056 DEBUG [PropertyBinder] Cascading objectType with null
19:34:09,056 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Change.oldValue
19:34:09,056 DEBUG [Ejb3Column] Binding column oldValue unique false
19:34:09,056 DEBUG [PropertyBinder] binding property oldValue with lazy=false
19:34:09,056 DEBUG [SimpleValueBinder] building SimpleValue for oldValue
19:34:09,056 DEBUG [PropertyBinder] Building property oldValue
19:34:09,056 DEBUG [PropertyBinder] Cascading oldValue with null
19:34:09,058 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Change.when
19:34:09,058 DEBUG [Ejb3Column] Binding column wtime unique false
19:34:09,058 DEBUG [PropertyBinder] binding property when with lazy=false
19:34:09,058 DEBUG [SimpleValueBinder] building SimpleValue for when
19:34:09,058 DEBUG [PropertyBinder] Building property when
19:34:09,058 DEBUG [PropertyBinder] Cascading when with null
19:34:09,058 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Change.who
19:34:09,060 DEBUG [Ejb3Column] Binding column null unique false
19:34:09,060 DEBUG [Ejb3Column] Binding column who unique false
19:34:09,061 DEBUG [PropertyBinder] Building property who
19:34:09,061 DEBUG [PropertyBinder] Cascading who with none
19:34:09,061 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.NaturalPerson
19:34:09,061 DEBUG [EntityBinder] Import with entity name=NaturalPerson
19:34:09,061 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.NaturalPerson property annotation
19:34:09,064 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.NaturalPerson.academicTitle
19:34:09,064 DEBUG [Ejb3Column] Binding column academicTitle unique false
19:34:09,064 DEBUG [PropertyBinder] binding property academicTitle with lazy=false
19:34:09,064 DEBUG [SimpleValueBinder] building SimpleValue for academicTitle
19:34:09,064 DEBUG [PropertyBinder] Building property academicTitle
19:34:09,064 DEBUG [PropertyBinder] Cascading academicTitle with null
19:34:09,064 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.NaturalPerson.additionalNames
19:34:09,065 DEBUG [Ejb3Column] Binding column additionalNames unique false
19:34:09,065 DEBUG [PropertyBinder] binding property additionalNames with lazy=false
19:34:09,065 DEBUG [SimpleValueBinder] building SimpleValue for additionalNames
19:34:09,065 DEBUG [PropertyBinder] Building property additionalNames
19:34:09,065 DEBUG [PropertyBinder] Cascading additionalNames with null
19:34:09,065 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.NaturalPerson.firstName
19:34:09,065 DEBUG [Ejb3Column] Binding column firstName unique false
19:34:09,067 DEBUG [PropertyBinder] binding property firstName with lazy=false
19:34:09,067 DEBUG [SimpleValueBinder] building SimpleValue for firstName
19:34:09,067 DEBUG [PropertyBinder] Building property firstName
19:34:09,067 DEBUG [PropertyBinder] Cascading firstName with null
19:34:09,067 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.NaturalPerson.gender
19:34:09,067 DEBUG [Ejb3Column] Binding column gender unique false
19:34:09,067 DEBUG [PropertyBinder] binding property gender with lazy=false
19:34:09,079 DEBUG [SimpleValueBinder] building SimpleValue for gender
19:34:09,079 DEBUG [PropertyBinder] Building property gender
19:34:09,080 DEBUG [PropertyBinder] Cascading gender with null
19:34:09,080 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.Employee
19:34:09,080 DEBUG [EntityBinder] Import with entity name=Employee
19:34:09,081 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.Employee property annotation
19:34:09,083 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Employee.department
19:34:09,083 DEBUG [Ejb3Column] Binding column department unique false
19:34:09,084 DEBUG [PropertyBinder] binding property department with lazy=false
19:34:09,084 DEBUG [SimpleValueBinder] building SimpleValue for department
19:34:09,084 DEBUG [PropertyBinder] Building property department
19:34:09,084 DEBUG [PropertyBinder] Cascading department with null
19:34:09,084 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Employee.position
19:34:09,085 DEBUG [Ejb3Column] Binding column position unique false
19:34:09,085 DEBUG [PropertyBinder] binding property position with lazy=false
19:34:09,085 DEBUG [SimpleValueBinder] building SimpleValue for position
19:34:09,085 DEBUG [PropertyBinder] Building property position
19:34:09,085 DEBUG [PropertyBinder] Cascading position with null
19:34:09,085 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Employee.sharesInProfits
19:34:09,086 DEBUG [Ejb3Column] Binding column sharesInProfits unique false
19:34:09,088 DEBUG [PropertyBinder] binding property sharesInProfits with lazy=false
19:34:09,088 DEBUG [SimpleValueBinder] building SimpleValue for sharesInProfits
19:34:09,088 DEBUG [PropertyBinder] Building property sharesInProfits
19:34:09,088 DEBUG [PropertyBinder] Cascading sharesInProfits with null
19:34:09,088 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.ForwardingAgency
19:34:09,088 DEBUG [EntityBinder] Import with entity name=ForwardingAgency
19:34:09,089 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.ForwardingAgency property annotation
19:34:09,091 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.ForwardingAgency.deliveringSupported
19:34:09,091 DEBUG [Ejb3Column] Binding column deliveringSupported unique false
19:34:09,091 DEBUG [PropertyBinder] binding property deliveringSupported with lazy=false
19:34:09,091 DEBUG [SimpleValueBinder] building SimpleValue for deliveringSupported
19:34:09,091 DEBUG [PropertyBinder] Building property deliveringSupported
19:34:09,091 DEBUG [PropertyBinder] Cascading deliveringSupported with null
19:34:09,093 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.ForwardingAgency.deliveringTerms
19:34:09,093 DEBUG [Ejb3Column] Binding column deliveringTerms unique false
19:34:09,093 DEBUG [PropertyBinder] binding property deliveringTerms with lazy=false
19:34:09,093 DEBUG [SimpleValueBinder] building SimpleValue for deliveringTerms
19:34:09,093 DEBUG [PropertyBinder] Building property deliveringTerms
19:34:09,093 DEBUG [PropertyBinder] Cascading deliveringTerms with null
19:34:09,095 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.ForwardingAgency.details
19:34:09,095 DEBUG [Ejb3Column] Binding column details unique false
19:34:09,095 DEBUG [PropertyBinder] binding property details with lazy=false
19:34:09,095 DEBUG [SimpleValueBinder] building SimpleValue for details
19:34:09,095 DEBUG [PropertyBinder] Building property details
19:34:09,095 DEBUG [PropertyBinder] Cascading details with null
19:34:09,095 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.ForwardingAgency.shippingSupported
19:34:09,097 DEBUG [Ejb3Column] Binding column shippingSupported unique false
19:34:09,097 DEBUG [PropertyBinder] binding property shippingSupported with lazy=false
19:34:09,097 DEBUG [SimpleValueBinder] building SimpleValue for shippingSupported
19:34:09,097 DEBUG [PropertyBinder] Building property shippingSupported
19:34:09,097 DEBUG [PropertyBinder] Cascading shippingSupported with null
19:34:09,097 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.PrivateClient
19:34:09,099 DEBUG [EntityBinder] Import with entity name=PrivateClient
19:34:09,099 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.PrivateClient property annotation
19:34:09,100 INFO [AnnotationBinder] Binding entity from annotated class: tuc_d_k.crm.database.entities.Task
19:34:09,100 DEBUG [Ejb3Column] Binding column DTYPE unique false
19:34:09,100 DEBUG [EntityBinder] Import with entity name=Task
19:34:09,100 INFO [EntityBinder] Bind entity tuc_d_k.crm.database.entities.Task on table tasks
19:34:09,101 DEBUG [AnnotationBinder] Processing tuc_d_k.crm.database.entities.Task property annotation
19:34:09,122 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Task.id_task
19:34:09,122 DEBUG [Ejb3Column] Binding column id_task unique false
19:34:09,122 DEBUG [AnnotationBinder] id_task is an id
19:34:09,122 DEBUG [SimpleValueBinder] building SimpleValue for id_task
19:34:09,122 DEBUG [PropertyBinder] Building property id_task
19:34:09,122 DEBUG [PropertyBinder] Cascading id_task with null
19:34:09,122 DEBUG [AnnotationBinder] Bind @Id on id_task
19:34:09,122 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Task.duration
19:34:09,124 DEBUG [Ejb3Column] Binding column duration unique false
19:34:09,124 DEBUG [PropertyBinder] binding property duration with lazy=false
19:34:09,124 DEBUG [SimpleValueBinder] building SimpleValue for duration
19:34:09,124 DEBUG [PropertyBinder] Building property duration
19:34:09,124 DEBUG [PropertyBinder] Cascading duration with null
19:34:09,124 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Task.invitations
19:34:09,124 DEBUG [Ejb3Column] Binding column null unique false
19:34:09,126 DEBUG [Ejb3Column] Binding column invitations unique false
19:34:09,126 DEBUG [Ejb3Column] Binding column null unique false
19:34:09,127 DEBUG [Ejb3Column] Binding column element unique false
19:34:09,127 DEBUG [Ejb3Column] Binding column mapkey unique false
19:34:09,127 DEBUG [Ejb3Column] Binding column null unique false
19:34:09,127 DEBUG [Ejb3Column] Binding column null unique false
19:34:09,127 DEBUG [Ejb3Column] Binding column null unique false
19:34:09,127 DEBUG [CollectionBinder] Collection role: tuc_d_k.crm.database.entities.Task.invitations
19:34:09,128 DEBUG [PropertyBinder] Building property invitations
19:34:09,128 DEBUG [PropertyBinder] Cascading invitations with none
19:34:09,128 DEBUG [AnnotationBinder] Processing annotations of tuc_d_k.crm.database.entities.Task.name
19:34:09,128 DEBUG [Ejb3Column] Binding column name