Grettings
I have a simple query (below) that fetches objects (Produto) with one many-to-many association witch is declared as lazy. When I execute the query the lazy collection is fetched (as can be seen in the SQL) resulting in poor performance of my application!
The second time I exec. the same query only on line of SQL is produced as expected! Is this some cache/configuration issue? I've browsed the forums and docs and found nothing (at least nothing obvious)! What an I doing wrong?
best regards
beowulf
Hibernate version:Hibernate 2.1.6
Mapping documents:
Produto.hbm.xml
<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD 2.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd">
<hibernate-mapping>
<class name="pt.trigenius.collipo.core.Produto" table="PRODUTO">
<id name="id" type="int" column="ID" unsaved-value="-1">
<generator class="sequence">
<param name="sequence">PRODUTO_ID_GEN</param>
</generator>
</id>
<property name="nome" column="nome" type="string"/>
<property name="referencia" column="ref" type="string"/>
<property name="facturadoValue" column="facturado" type="string"/>
<property name="peso" column="peso" type="double"/>
<property name="custo" column="custo" type="double"/>
<property name="proveito" column="proveito" type="double"/>
<property name="desconto" column="desconto" type="double"/>
<property name="ganhoRelativo" column="ganho_relativo" type="double"/>
<property name="pvp1" column="pvp1" type="double"/>
<property name="pvp2" column="pvp2" type="double"/>
<property name="pvp3" column="pvp3" type="double"/>
<many-to-one name="iva" column="id_iva" class="pt.trigenius.collipo.core.Iva"/>
<many-to-one name="familia" column="id_familia" class="pt.trigenius.collipo.core.Familia"/>
<many-to-one name="centroCusto" column="id_centro_custo" class="pt.trigenius.collipo.core.CentroCusto"/>
<bag name="itens" table="item_composto" lazy="true" inverse="true" outer-join="false">
<key column="id_produto"/>
<many-to-many column="id_sub_item" class="pt.trigenius.collipo.core.Item" outer-join="false"/>
</bag>
</class>
</hibernate-mapping>
ItemImpl.hbm.xml
<hibernate-mapping>
<!-- pt.trigenius.collipo.core.DataEntity root -->
<class name="pt.trigenius.collipo.core.ItemImpl" table="ITEM">
<id name="id" type="int" column="ID" unsaved-value="-1">
<generator class="sequence">
<param name="sequence">ITEM_ID_GEN</param>
</generator>
</id>
<discriminator column="tipo" type="string"/>
<property name="nome" column="nome" type="string"/>
<property name="referencia" column="ref" type="string"/>
<property name="facturadoValue" column="facturado" type="string"/>
<property name="peso" column="peso" type="double"/>
<property name="custo" column="custo" type="double"/>
<property name="proveito" column="proveito" type="double"/>
<property name="desconto" column="desconto" type="double"/>
<property name="ganhoRelativo" column="ganho_relativo" type="double"/>
<property name="pvp1" column="pvp1" type="double"/>
<property name="pvp2" column="pvp2" type="double"/>
<property name="pvp3" column="pvp3" type="double"/>
<many-to-one name="iva" column="id_iva" class="pt.trigenius.collipo.core.Iva"/>
<bag name="itens" table="item_composto" lazy="true">
<key column="id_item"/>
<many-to-many column="id_sub_item" class="pt.trigenius.collipo.core.ItemImpl"/>
</bag>
<subclass name="pt.trigenius.collipo.core.Item" discriminator-value="ITEM" lazy="true">
</subclass>
<subclass name="pt.trigenius.collipo.core.RecursoHumano" discriminator-value="RECURSO_HUMANO">
<many-to-one name="funcionario" column="id_operacional" class="pt.trigenius.collipo.core.Funcionario"/>
</subclass>
</class>
</hibernate-mapping>
Code between sessionFactory.openSession() and session.close():
public List getProdutosByName(String name) {
try {
Query q = HibernateSession.getSession().createQuery("from Produto p where p.nome like :n");
q.setString("n", "%" + name + "%");
return q.list();
} catch (HibernateException e) {
throw new MapperRuntimeException("Erro ao extrair query!", e);
}
}
Name and version of the database you are using:
Interbase 6.0.1.6
The generated SQL (show_sql=true):
First time the above query is executed:
Hibernate: select produto0_.ID as ID, produto0_.nome as nome, produto0_.ref as ref, produto0_.facturado as facturado, produto0_.peso as peso, produto0_.custo as custo, produto0_.proveito as proveito, produto0_.desconto as desconto, produto0_.ganho_relativo as ganho_re9_, produto0_.pvp1 as pvp1, produto0_.pvp2 as pvp2, produto0_.pvp3 as pvp3, produto0_.id_iva as id_iva, produto0_.id_familia as id_familia, produto0_.id_centro_custo as id_cent15_ from PRODUTO produto0_ where (produto0_.nome like ? )
[...]
Hibernate: select item0_.ID as ID1_, item0_.nome as nome1_, item0_.ref as ref1_, item0_.facturado as facturado1_, item0_.peso as peso1_, item0_.custo as custo1_, item0_.proveito as proveito1_, item0_.desconto as desconto1_, item0_.ganho_relativo as ganho_r10_1_, item0_.pvp1 as pvp11_, item0_.pvp2 as pvp21_, item0_.pvp3 as pvp31_, item0_.id_iva as id_iva1_, iva1_.ID as ID0_, iva1_.taxa as taxa0_, iva1_.designacao as designacao0_ from ITEM item0_ left outer join IVA iva1_ on item0_.id_iva=iva1_.ID where item0_.ID=?
Hibernate: select itens0_.id_item as id_item__, itens0_.id_sub_item as id_sub_i2___, itemimpl1_.ID as ID0_, itemimpl1_.tipo as tipo0_, itemimpl1_.nome as nome0_, itemimpl1_.ref as ref0_, itemimpl1_.facturado as facturado0_, itemimpl1_.peso as peso0_, itemimpl1_.custo as custo0_, itemimpl1_.proveito as proveito0_, itemimpl1_.desconto as desconto0_, itemimpl1_.ganho_relativo as ganho_r10_0_, itemimpl1_.pvp1 as pvp10_, itemimpl1_.pvp2 as pvp20_, itemimpl1_.pvp3 as pvp30_, itemimpl1_.id_iva as id_iva0_, itemimpl1_.id_operacional as id_oper15_0_ from item_composto itens0_ inner join ITEM itemimpl1_ on itens0_.id_sub_item=itemimpl1_.ID where itens0_.id_item=?
Hibernate: select item0_.ID as ID1_, item0_.nome as nome1_, item0_.ref as ref1_, item0_.facturado as facturado1_, item0_.peso as peso1_, item0_.custo as custo1_, item0_.proveito as proveito1_, item0_.desconto as desconto1_, item0_.ganho_relativo as ganho_r10_1_, item0_.pvp1 as pvp11_, item0_.pvp2 as pvp21_, item0_.pvp3 as pvp31_, item0_.id_iva as id_iva1_, iva1_.ID as ID0_, iva1_.taxa as taxa0_, iva1_.designacao as designacao0_ from ITEM item0_ left outer join IVA iva1_ on item0_.id_iva=iva1_.ID where item0_.ID=?
Hibernate: select itens0_.id_item as id_item__, itens0_.id_sub_item as id_sub_i2___, itemimpl1_.ID as ID0_, itemimpl1_.tipo as tipo0_, itemimpl1_.nome as nome0_, itemimpl1_.ref as ref0_, itemimpl1_.facturado as facturado0_, itemimpl1_.peso as peso0_, itemimpl1_.custo as custo0_, itemimpl1_.proveito as proveito0_, itemimpl1_.desconto as desconto0_, itemimpl1_.ganho_relativo as ganho_r10_0_, itemimpl1_.pvp1 as pvp10_, itemimpl1_.pvp2 as pvp20_, itemimpl1_.pvp3 as pvp30_, itemimpl1_.id_iva as id_iva0_, itemimpl1_.id_operacional as id_oper15_0_ from item_composto itens0_ inner join ITEM itemimpl1_ on itens0_.id_sub_item=itemimpl1_.ID where itens0_.id_item=?
Hibernate: select iva0_.ID as ID0_, iva0_.taxa as taxa0_, iva0_.designacao as designacao0_ from IVA iva0_ where iva0_.ID=?
Hibernate: select familia0_.ID as ID0_, familia0_.nome as nome0_, familia0_.abreviatura as abreviat3_0_ from familia familia0_ where familia0_.ID=?
Hibernate: select centrocust0_.ID as ID0_, centrocust0_.nome as nome0_, centrocust0_.abreviatura as abreviat3_0_, centrocust0_.cor as cor0_ from CENTRO_CUSTO centrocust0_ where centrocust0_.ID=?
Hibernate: select itens0_.id_produto as id_produto__, itens0_.id_sub_item as id_sub_i2___ from item_composto itens0_ where itens0_.id_produto=?
Hibernate: select item0_.ID as ID1_, item0_.nome as nome1_, item0_.ref as ref1_, item0_.facturado as facturado1_, item0_.peso as peso1_, item0_.custo as custo1_, item0_.proveito as proveito1_, item0_.desconto as desconto1_, item0_.ganho_relativo as ganho_r10_1_, item0_.pvp1 as pvp11_, item0_.pvp2 as pvp21_, item0_.pvp3 as pvp31_, item0_.id_iva as id_iva1_, iva1_.ID as ID0_, iva1_.taxa as taxa0_, iva1_.designacao as designacao0_ from ITEM item0_ left outer join IVA iva1_ on item0_.id_iva=iva1_.ID where item0_.ID=?
Hibernate: select itens0_.id_item as id_item__, itens0_.id_sub_item as id_sub_i2___, itemimpl1_.ID as ID0_, itemimpl1_.tipo as tipo0_, itemimpl1_.nome as nome0_, itemimpl1_.ref as ref0_, itemimpl1_.facturado as facturado0_, itemimpl1_.peso as peso0_, itemimpl1_.custo as custo0_, itemimpl1_.proveito as proveito0_, itemimpl1_.desconto as desconto0_, itemimpl1_.ganho_relativo as ganho_r10_0_, itemimpl1_.pvp1 as pvp10_, itemimpl1_.pvp2 as pvp20_, itemimpl1_.pvp3 as pvp30_, itemimpl1_.id_iva as id_iva0_, itemimpl1_.id_operacional as id_oper15_0_ from item_composto itens0_ inner join ITEM itemimpl1_ on itens0_.id_sub_item=itemimpl1_.ID where itens0_.id_item=?
Hibernate: select funcionari0_.ID as ID1_, funcionari0_.telefone as telefone1_, funcionari0_.nome as nome1_, funcionari0_.observacoes as observac4_1_, funcionari0_.morada as morada1_, funcionari0_.localidade as localidade1_, funcionari0_.localidade_postal as localida7_1_, funcionari0_.codigo_postal as codigo_p8_1_, funcionari0_.telemovel as telemovel1_, funcionari0_.fax as fax1_, funcionari0_.email as email1_, funcionari0_.carta_conducao as carta_c12_1_, funcionari0_.banco as banco1_, funcionari0_.numero_conta as numero_14_1_, funcionari0_.tamanho_calcado as tamanho15_1_, funcionari0_.tamanho_calca as tamanho16_1_, funcionari0_.tamanho_t_shirt as tamanho17_1_, funcionari0_.foto as foto1_, funcionari0_.avaliacao as avaliacao1_, funcionari0_.seleccao as seleccao1_, funcionari0_.ID_FUNCAO as ID_FUNCAO1_, funcao1_.ID as ID0_, funcao1_.designacao as designacao0_, funcao1_.tipo as tipo0_ from FUNCIONARIO funcionari0_ left outer join FUNCAO funcao1_ on funcionari0_.ID_FUNCAO=funcao1_.ID where funcionari0_.ID=?
Hibernate: select itens0_.id_item as id_item__, itens0_.id_sub_item as id_sub_i2___, itemimpl1_.ID as ID0_, itemimpl1_.tipo as tipo0_, itemimpl1_.nome as nome0_, itemimpl1_.ref as ref0_, itemimpl1_.facturado as facturado0_, itemimpl1_.peso as peso0_, itemimpl1_.custo as custo0_, itemimpl1_.proveito as proveito0_, itemimpl1_.desconto as desconto0_, itemimpl1_.ganho_relativo as ganho_r10_0_, itemimpl1_.pvp1 as pvp10_, itemimpl1_.pvp2 as pvp20_, itemimpl1_.pvp3 as pvp30_, itemimpl1_.id_iva as id_iva0_, itemimpl1_.id_operacional as id_oper15_0_ from item_composto itens0_ inner join ITEM itemimpl1_ on itens0_.id_sub_item=itemimpl1_.ID where itens0_.id_item=?
Hibernate: select iva0_.ID as ID0_, iva0_.taxa as taxa0_, iva0_.designacao as designacao0_ from IVA iva0_ where iva0_.ID=?
Hibernate: select familia0_.ID as ID0_, familia0_.nome as nome0_, familia0_.abreviatura as abreviat3_0_ from familia familia0_ where familia0_.ID=?
Hibernate: select centrocust0_.ID as ID0_, centrocust0_.nome as nome0_, centrocust0_.abreviatura as abreviat3_0_, centrocust0_.cor as cor0_ from CENTRO_CUSTO centrocust0_ where centrocust0_.ID=?
Hibernate: select itens0_.id_produto as id_produto__, itens0_.id_sub_item as id_sub_i2___ from item_composto itens0_ where itens0_.id_produto=?
Hibernate: select item0_.ID as ID1_, item0_.nome as nome1_, item0_.ref as ref1_, item0_.facturado as facturado1_, item0_.peso as peso1_, item0_.custo as custo1_, item0_.proveito as proveito1_, item0_.desconto as desconto1_, item0_.ganho_relativo as ganho_r10_1_, item0_.pvp1 as pvp11_, item0_.pvp2 as pvp21_, item0_.pvp3 as pvp31_, item0_.id_iva as id_iva1_, iva1_.ID as ID0_, iva1_.taxa as taxa0_, iva1_.designacao as designacao0_ from ITEM item0_ left outer join IVA iva1_ on item0_.id_iva=iva1_.ID where item0_.ID=?
Hibernate: select itens0_.id_item as id_item__, itens0_.id_sub_item as id_sub_i2___, itemimpl1_.ID as ID0_, itemimpl1_.tipo as tipo0_, itemimpl1_.nome as nome0_, itemimpl1_.ref as ref0_, itemimpl1_.facturado as facturado0_, itemimpl1_.peso as peso0_, itemimpl1_.custo as custo0_, itemimpl1_.proveito as proveito0_, itemimpl1_.desconto as desconto0_, itemimpl1_.ganho_relativo as ganho_r10_0_, itemimpl1_.pvp1 as pvp10_, itemimpl1_.pvp2 as pvp20_, itemimpl1_.pvp3 as pvp30_, itemimpl1_.id_iva as id_iva0_, itemimpl1_.id_operacional as id_oper15_0_ from item_composto itens0_ inner join ITEM itemimpl1_ on itens0_.id_sub_item=itemimpl1_.ID where itens0_.id_item=?
[...]
second time the query is executed:
Hibernate: select produto0_.ID as ID, produto0_.nome as nome, produto0_.ref as ref, produto0_.facturado as facturado, produto0_.peso as peso, produto0_.custo as custo, produto0_.proveito as proveito, produto0_.desconto as desconto, produto0_.ganho_relativo as ganho_re9_, produto0_.pvp1 as pvp1, produto0_.pvp2 as pvp2, produto0_.pvp3 as pvp3, produto0_.id_iva as id_iva, produto0_.id_familia as id_familia, produto0_.id_centro_custo as id_cent15_ from PRODUTO produto0_ where (produto0_.nome like ? )
Debug level Hibernate log excerpt:
DEBUG SessionImpl:3260 - initializing collection [pt.trigenius.collipo.core.Produto.itens#4]
18:10:32,841 DEBUG SessionImpl:3261 - checking second-level cache
18:10:32,841 DEBUG SessionImpl:3267 - collection not cached
18:10:32,841 DEBUG BatcherImpl:200 - about to open: 0 open PreparedStatements, 0 open ResultSets
18:10:32,841 DEBUG SQL:226 - select itens0_.id_produto as id_produto__, itens0_.id_sub_item as id_sub_i2___ from item_composto itens0_ where itens0_.id_produto=?
Hibernate: select itens0_.id_produto as id_produto__, itens0_.id_sub_item as id_sub_i2___ from item_composto itens0_ where itens0_.id_produto=?
18:10:32,841 DEBUG BatcherImpl:249 - preparing statement
18:10:32,851 DEBUG Loader:402 - result set contains (possibly empty) collection: [pt.trigenius.collipo.core.Produto.itens#4]
18:10:32,851 DEBUG SessionImpl:3018 - uninitialized collection: initializing
18:10:32,861 DEBUG Loader:277 - processing result set
18:10:32,861 DEBUG Loader:294 - done processing result set (0 rows)
18:10:32,861 DEBUG BatcherImpl:207 - done closing: 0 open PreparedStatements, 0 open ResultSets
18:10:32,861 DEBUG BatcherImpl:269 - closing statement
18:10:32,861 DEBUG SessionImpl:3077 - 1 collections were found in result set
18:10:32,861 DEBUG SessionImpl:3095 - collection fully initialized: [pt.trigenius.collipo.core.Produto.itens#4]
18:10:32,861 DEBUG SessionImpl:3098 - 1 collections initialized
18:10:32,861 DEBUG SessionImpl:3269 - collection initialized
18:10:32,861 DEBUG SessionImpl:2226 - done materializing entity [pt.trigenius.collipo.core.Produto#4]
18:10:32,861 DEBUG SessionImpl:2202 - resolving associations for [pt.trigenius.collipo.core.Produto#5]
18:10:32,871 DEBUG SessionImpl:1986 - loading [pt.trigenius.collipo.core.Familia#10000000]
18:10:32,871 DEBUG SessionImpl:2083 - attempting to resolve [pt.trigenius.collipo.core.Familia#10000000]
18:10:32,871 DEBUG SessionImpl:2099 - resolved object in session cache [pt.trigenius.collipo.core.Familia#10000000]
18:10:32,871 DEBUG SessionImpl:1986 - loading [pt.trigenius.collipo.core.CentroCusto#10000000]
18:10:32,871 DEBUG SessionImpl:2083 - attempting to resolve [pt.trigenius.collipo.core.CentroCusto#10000000]
18:10:32,871 DEBUG SessionImpl:2099 - resolved object in session cache [pt.trigenius.collipo.core.CentroCusto#10000000]
18:10:32,871 DEBUG SessionImpl:3933 - creating collection wrapper:[pt.trigenius.collipo.core.Produto.itens#5]
18:10:32,871 DEBUG SessionImpl:3260 - initializing collection [pt.trigenius.collipo.core.Produto.itens#5]
18:10:32,871 DEBUG SessionImpl:3261 - checking second-level cache
18:10:32,871 DEBUG SessionImpl:3267 - collection not cached
18:10:32,871 DEBUG BatcherImpl:200 - about to open: 0 open PreparedStatements, 0 open ResultSets
18:10:32,871 DEBUG SQL:226 - select itens0_.id_produto as id_produto__, itens0_.id_sub_item as id_sub_i2___ from item_composto itens0_ where itens0_.id_produto=?
Hibernate: select itens0_.id_produto as id_produto__, itens0_.id_sub_item as id_sub_i2___ from item_composto itens0_ where itens0_.id_produto=?
18:10:32,871 DEBUG BatcherImpl:249 - preparing statement
18:10:32,891 DEBUG Loader:402 - result set contains (possibly empty) collection: [pt.trigenius.collipo.core.Produto.itens#5]
18:10:32,891 DEBUG SessionImpl:3018 - uninitialized collection: initializing
18:10:32,891 DEBUG Loader:277 - processing result set
18:10:32,891 DEBUG Loader:480 - result row:
18:10:32,891 DEBUG Loader:367 - found row of collection: [pt.trigenius.collipo.core.Produto.itens#5]
18:10:32,891 DEBUG SessionImpl:3041 - reading row
18:10:32,891 DEBUG SessionImpl:1986 - loading [pt.trigenius.collipo.core.Item#10000001]
18:10:32,891 DEBUG Loader:480 - result row:
18:10:32,891 DEBUG Loader:367 - found row of collection: [pt.trigenius.collipo.core.Produto.itens#5]
18:10:32,891 DEBUG SessionImpl:3041 - reading row
18:10:32,901 DEBUG SessionImpl:1986 - loading [pt.trigenius.collipo.core.Item#10000002]
18:10:32,901 DEBUG Loader:294 - done processing result set (2 rows)
18:10:32,901 DEBUG BatcherImpl:207 - done closing: 0 open PreparedStatements, 0 open ResultSets
18:10:32,901 DEBUG BatcherImpl:269 - closing statement
18:10:32,901 DEBUG SessionImpl:3077 - 1 collections were found in result set
18:10:32,901 DEBUG SessionImpl:3095 - collection fully initialized: [pt.trigenius.collipo.core.Produto.itens#5]
18:10:32,901 DEBUG SessionImpl:3098 - 1 collections initialized
18:10:32,901 DEBUG SessionImpl:3269 - collection initialized
18:10:32,901 DEBUG SessionImpl:2083 - attempting to resolve [pt.trigenius.collipo.core.Item#10000001]
18:10:32,901 DEBUG SessionImpl:2116 - object not resolved in any cache [pt.trigenius.collipo.core.Item#10000001]
18:10:32,911 DEBUG EntityPersister:416 - Materializing entity: [pt.trigenius.collipo.core.Item#10000001]
18:10:32,911 DEBUG BatcherImpl:200 - about to open: 0 open PreparedStatements, 0 open ResultSets
18:10:32,911 DEBUG SQL:226 - select item0_.ID as ID1_, item0_.nome as nome1_, item0_.ref as ref1_, item0_.facturado as facturado1_, item0_.peso as peso1_, item0_.custo as custo1_, item0_.proveito as proveito1_, item0_.desconto as desconto1_, item0_.ganho_relativo as ganho_r10_1_, item0_.pvp1 as pvp11_, item0_.pvp2 as pvp21_, item0_.pvp3 as pvp31_, item0_.id_iva as id_iva1_, iva1_.ID as ID0_, iva1_.taxa as taxa0_, iva1_.designacao as designacao0_ from ITEM item0_ left outer join IVA iva1_ on item0_.id_iva=iva1_.ID where item0_.ID=?
Hibernate: select item0_.ID as ID1_, item0_.nome as nome1_, item0_.ref as ref1_, item0_.facturado as facturado1_, item0_.peso as peso1_, item0_.custo as custo1_, item0_.proveito as proveito1_, item0_.desconto as desconto1_, item0_.ganho_relativo as ganho_r10_1_, item0_.pvp1 as pvp11_, item0_.pvp2 as pvp21_, item0_.pvp3 as pvp31_, item0_.id_iva as id_iva1_, iva1_.ID as ID0_, iva1_.taxa as taxa0_, iva1_.designacao as designacao0_ from ITEM item0_ left outer join IVA iva1_ on item0_.id_iva=iva1_.ID where item0_.ID=?
18:10:32,911 DEBUG BatcherImpl:249 - preparing statement
18:10:32,922 DEBUG Loader:277 - processing result set
18:10:32,922 DEBUG Loader:480 - result row: 10000000, 10000001
[...]
Second query execution
[...]
18:12:07,908 DEBUG SessionImpl:1527 - find: from Produto p where p.nome like :n
18:12:07,908 DEBUG QueryParameters:112 - named parameters: {n=%%}
18:12:07,908 DEBUG QueryTranslator:147 - compiling query
18:12:07,908 DEBUG SessionImpl:2246 - flushing session
18:12:07,918 DEBUG SessionImpl:2439 - Flushing entities and processing referenced collections
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#10000000], was: [pt.trigenius.collipo.core.Produto.itens#10000000]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#10000001], was: [pt.trigenius.collipo.core.Produto.itens#10000001]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#8], was: [pt.trigenius.collipo.core.Produto.itens#8]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#9], was: [pt.trigenius.collipo.core.Produto.itens#9]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#10], was: [pt.trigenius.collipo.core.Produto.itens#10]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#1], was: [pt.trigenius.collipo.core.Produto.itens#1]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#11], was: [pt.trigenius.collipo.core.Produto.itens#11]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#12], was: [pt.trigenius.collipo.core.Produto.itens#12]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#13], was: [pt.trigenius.collipo.core.Produto.itens#13]
18:12:07,928 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#14], was: [pt.trigenius.collipo.core.Produto.itens#14]
18:12:07,938 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#15], was: [pt.trigenius.collipo.core.Produto.itens#15]
18:12:07,938 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#16], was: [pt.trigenius.collipo.core.Produto.itens#16]
18:12:07,938 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#17], was: [pt.trigenius.collipo.core.Produto.itens#17]
18:12:07,938 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#18], was: [pt.trigenius.collipo.core.Produto.itens#18]
18:12:07,938 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#19], was: [pt.trigenius.collipo.core.Produto.itens#19]
18:12:07,948 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#20], was: [pt.trigenius.collipo.core.Produto.itens#20]
18:12:07,948 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#21], was: [pt.trigenius.collipo.core.Produto.itens#21]
18:12:07,948 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#22], was: [pt.trigenius.collipo.core.Produto.itens#22]
18:12:07,948 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#23], was: [pt.trigenius.collipo.core.Produto.itens#23]
18:12:07,948 DEBUG SessionImpl:2884 - Collection found: [pt.trigenius.collipo.core.Produto.itens#24], was: [pt.trigenius.collipo.core.Produto.itens#24]
[...]
|