Announcement

**alius** · 9 Feb 2012, 09:31

Could you post complete server log with debug level for following packages
com.isomorphic.jpa
com.isomorphic.datasource
com.isomorphic.rpc

Looks like first operation completes but transaction is not committed (see inner exception: Caused by: javax.transaction.NotSupportedException: Nested transaction not supported.)

**dejan** · 13 Feb 2012, 02:26

Code:

I
INFO: === 2012-02-13 11:23:49,134 [0(5)] INFO  Compression - /esdoc-web/: 651 -> 370 bytes

INFO: === 2012-02-13 11:23:49,154 [0(3)] INFO  RequestContext - URL: '/esdoc-web/data_source_loader.jsp', User-Agent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.6; rv:8.0.1) Gecko/20100101 Firefox/8.0.1': Moz (Gecko) with Accept-Encoding header

INFO: === 2012-02-13 11:23:49,160 [0(3)] DEBUG XML - Parsed XML from (in memory stream): 2ms

INFO: === 2012-02-13 11:23:49,161 [0(3)] DEBUG DataSource - In DS.forName() for 'isomorphicXML' with DSRequest: null

INFO: === 2012-02-13 11:23:49,161 [0(3)] DEBUG DataSource - Creating instance of DataSource 'isomorphicXML'

INFO: === 2012-02-13 11:23:49,161 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,162 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,166 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,166 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,167 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,167 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,168 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,169 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,169 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,169 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,170 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,170 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,170 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,171 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,171 [0(3)] DEBUG DataSource - In DS.forName() for 'ValueMap' with DSRequest: null

INFO: === 2012-02-13 11:23:49,172 [0(3)] DEBUG DataSource - Creating instance of DataSource 'ValueMap'

INFO: === 2012-02-13 11:23:49,172 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,172 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,173 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,173 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,173 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,173 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,174 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,175 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,175 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,176 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,176 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,180 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,181 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,181 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,182 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,182 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,182 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,183 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,183 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,183 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,184 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,184 [0(3)] DEBUG DataSource - In DS.forName() for 'ValueMap' with DSRequest: null

INFO: === 2012-02-13 11:23:49,185 [0(3)] DEBUG DataSource - Creating instance of DataSource 'ValueMap'

INFO: === 2012-02-13 11:23:49,185 [0(3)] DEBUG DataSource - In DS.forName() for 'ValueMap' with DSRequest: null

INFO: === 2012-02-13 11:23:49,186 [0(3)] DEBUG DataSource - Creating instance of DataSource 'ValueMap'

INFO: === 2012-02-13 11:23:49,186 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,186 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,186 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,187 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,187 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,187 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,188 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,188 [0(3)] DEBUG DataSource - In DS.forName() for 'ValueMap' with DSRequest: null

INFO: === 2012-02-13 11:23:49,188 [0(3)] DEBUG DataSource - Creating instance of DataSource 'ValueMap'

INFO: === 2012-02-13 11:23:49,189 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,189 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,189 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,189 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,190 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,190 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,190 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,195 [0(3)] DEBUG DataSource - In DS.forName() for 'ValueMap' with DSRequest: null

INFO: === 2012-02-13 11:23:49,195 [0(3)] DEBUG DataSource - Creating instance of DataSource 'ValueMap'

INFO: === 2012-02-13 11:23:49,196 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,196 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,196 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,197 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,197 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,197 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,198 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,198 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,198 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,199 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,199 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,199 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,199 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,200 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,200 [0(3)] DEBUG DataSource - In DS.forName() for 'ValueMap' with DSRequest: null

INFO: === 2012-02-13 11:23:49,200 [0(3)] DEBUG DataSource - Creating instance of DataSource 'ValueMap'

INFO: === 2012-02-13 11:23:49,201 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,201 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,201 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,202 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,202 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,202 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,202 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,203 [0(3)] DEBUG DataSource - In DS.forName() for 'filed' with DSRequest: null

INFO: === 2012-02-13 11:23:49,203 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,204 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,204 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,208 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,208 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,209 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,209 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,210 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,210 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,211 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,211 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,212 [0(3)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,212 [0(3)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,212 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,212 [0(3)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,213 [0(3)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,213 [0(3)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,213 [0(3)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,214 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,214 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,214 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSource' with DSRequest: null

INFO: === 2012-02-13 11:23:49,214 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSource'

INFO: === 2012-02-13 11:23:49,215 [0(3)] DEBUG DataSource - In DS.forName() for 'DataSourceField' with DSRequest: null

INFO: === 2012-02-13 11:23:49,215 [0(3)] DEBUG DataSource - Creating instance of DataSource 'DataSourceField'

INFO: === 2012-02-13 11:23:49,215 [0(3)] DEBUG DataSource - In DS.forName() for 'field' with DSRequest: null

INFO: === 2012-02-13 11:23:49,216 [0(3)] DEBUG DataSource - In DS.forName() for 'ValueMap' with DSRequest: null

INFO: === 2012-02-13 11:23:49,216 [0(3)] DEBUG DataSource - Creating instance of DataSource 'ValueMap'

INFO: === 2012-02-13 11:23:49,219 [0(3)] INFO  Compression - /esdoc-web/data_source_loader.jsp: 14393 -> 1616 bytes


INFO: === 2012-02-13 11:23:49,555 [0(2)] INFO  RequestContext - URL: '/esdoc-web/esdoc/00603E56777E2349048B332AE0B11DEB.cache.html', User-Agent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.6; rv:8.0.1) Gecko/20100101 Firefox/8.0.1': Moz (Gecko) with Accept-Encoding header

INFO: === 2012-02-13 11:23:49,568 [0(2)] INFO  Compression - /esdoc-web/esdoc/00603E56777E2349048B332AE0B11DEB.cache.html: 210787 -> 56469 bytes

INFO: === 2012-02-13 11:23:49,626 [0(5)] INFO  RequestContext - URL: '/esdoc-web/esdoc/sc/IDACall', User-Agent: 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10.6; rv:8.0.1) Gecko/20100101 Firefox/8.0.1': Moz (Gecko) with Accept-Encoding header

INFO: === 2012-02-13 11:23:49,632 [0(5)] DEBUG XML - Parsed XML from (in memory stream): 2ms

INFO: === 2012-02-13 11:23:49,632 [0(5)] DEBUG DataSource - In DS.forName() for 'transaction' with DSRequest: null

INFO: === 2012-02-13 11:23:49,632 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,632 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,633 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,633 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,633 [0(5)] DEBUG DataSource - In DS.forName() for 'List' with DSRequest: null

INFO: === 2012-02-13 11:23:49,634 [0(5)] DEBUG DataSource - Creating instance of DataSource 'List'

INFO: === 2012-02-13 11:23:49,634 [0(5)] DEBUG DataSource - In DS.forName() for 'elem' with DSRequest: null

INFO: === 2012-02-13 11:23:49,634 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,634 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,635 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,635 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,635 [0(5)] DEBUG DataSource - In DS.forName() for 'List' with DSRequest: null

INFO: === 2012-02-13 11:23:49,636 [0(5)] DEBUG DataSource - Creating instance of DataSource 'List'

INFO: === 2012-02-13 11:23:49,636 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,636 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,636 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,636 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,637 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,637 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,637 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,637 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,638 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,638 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,638 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,638 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,639 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,639 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,639 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,639 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,639 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,640 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,640 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,640 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,640 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,640 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,641 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,641 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,641 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,641 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,642 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,642 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,642 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,642 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,643 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,643 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,643 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,643 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,643 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,647 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,648 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,648 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,648 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,648 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,648 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,648 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,649 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,649 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,649 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,649 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,649 [0(5)] DEBUG DataSource - In DS.forName() for 'Object' with DSRequest: null

INFO: === 2012-02-13 11:23:49,650 [0(5)] DEBUG DataSource - Creating instance of DataSource 'Object'

INFO: === 2012-02-13 11:23:49,650 [0(5)] DEBUG RPCManager - Processing 4 requests.

INFO: === 2012-02-13 11:23:49,650 [0(5)] DEBUG RPCManager - Request #1 (RPCRequest) data: {
    appID:"esdoc",
    className:"net.esteh.esdoc.server.rpc.dmi.DMIService",
    methodName:"getUserData",
    arguments:[
    ],
    is_ISC_RPC_DMI:true
}

INFO: === 2012-02-13 11:23:49,651 [0(5)] DEBUG DataSource - In DS.forName() for 'bank' with DSRequest: com.isomorphic.datasource.DSRequest@604cbd

INFO: === 2012-02-13 11:23:49,651 [0(5)] DEBUG DataSource - Creating instance of DataSource 'bank'

INFO: === 2012-02-13 11:23:49,651 [0(5)] DEBUG RPCManager - Request #2 (DSRequest) payload: {
    criteria:{
    },
    operationConfig:{
        dataSource:"bank",
        operationType:"fetch"
    },
    appID:"builtinApplication",
    operation:"bank_fetch",
    oldValues:{
    }
}

INFO: === 2012-02-13 11:23:49,652 [0(5)] DEBUG DataSource - In DS.forName() for 'paymentPurpose' with DSRequest: com.isomorphic.datasource.DSRequest@4e2100

INFO: === 2012-02-13 11:23:49,652 [0(5)] DEBUG DataSource - Creating instance of DataSource 'paymentPurpose'

INFO: === 2012-02-13 11:23:49,652 [0(5)] DEBUG RPCManager - Request #3 (DSRequest) payload: {
    criteria:{
    },
    operationConfig:{
        dataSource:"paymentPurpose",
        operationType:"fetch"
    },
    appID:"builtinApplication",
    operation:"paymentPurpose_fetch",
    oldValues:{
    }
}

INFO: === 2012-02-13 11:23:49,652 [0(5)] DEBUG DataSource - In DS.forName() for 'orderErrorCode' with DSRequest: com.isomorphic.datasource.DSRequest@158ad1c

INFO: === 2012-02-13 11:23:49,653 [0(5)] DEBUG DataSource - Creating instance of DataSource 'orderErrorCode'

INFO: === 2012-02-13 11:23:49,653 [0(5)] DEBUG RPCManager - Request #4 (DSRequest) payload: {
    criteria:{
    },
    operationConfig:{
        dataSource:"orderErrorCode",
        operationType:"fetch"
    },
    appID:"builtinApplication",
    operation:"orderErrorCode_fetch",
    oldValues:{
    }
}

INFO: === 2012-02-13 11:23:49,654 [0(5)] DEBUG RPCDMI - appConfig: isc.Application.create({
    rpcBindings:[
        {
            ID:"DMIService",
            className:"net.esteh.esdoc.server.rpc.dmi.DMIService",
            visibleMethods:[
                {
                    name:"startSimulation"
                },
                {
                    name:"getStatusData"
                },
                {
                    name:"endSimulation"
                },
                {
                    name:"startService"
                },
                {
                    name:"deleteData"
                },
                {
                    name:"isStarted"
                },
                {
                    name:"getCurrentUser"
                },
                {
                    name:"getUserData"
                },
                {
                    name:"logout"
                },
                {
                    name:"getNextOrder"
                },
                {
                    name:"saveProcessedOrder"
                },
                {
                    name:"getClientInfo"
                },
                {
                    name:"reloadConfiguration"
                }
            ]
        }
    ]
})

INFO: Returning map {contextPath=/esdoc-web, user=net.esteh.esdoc.domain.User[ id=3, username=dejan]}
INFO: === 2012-02-13 11:23:49,654 [0(5)] DEBUG RPCDMI - rpc returned data

INFO: === 2012-02-13 11:23:49,655 [0(5)] DEBUG AppBase - [builtinApplication.bank_fetch] No userTypes defined, allowing anyone access to all operations for this application

INFO: === 2012-02-13 11:23:49,655 [0(5)] DEBUG AppBase - [builtinApplication.bank_fetch] No public zero-argument method named '_bank_fetch' found, performing generic datasource operation

INFO: === 2012-02-13 11:23:49,655 [0(5)] DEBUG JPADataSource - [builtinApplication.bank_fetch] Creating EntityManager and starting transaction.

INFO: === 2012-02-13 11:23:49,656 [0(5)] DEBUG DSRequest - [builtinApplication.bank_fetch] Clobbering existing FreeResourcesHandler of type 'com.isomorphic.jpa.JPA2DataSource' with a 'com.isomorphic.jpa.JPA2DataSource'

INFO: === 2012-02-13 11:23:49,656 [0(5)] DEBUG JPADataSource - [builtinApplication.bank_fetch] Executing fetch.

INFO: === 2012-02-13 11:23:49,660 [0(5)] DEBUG JPADataSource - [builtinApplication.bank_fetch] Query string: select _Bank from Bank _Bank

INFO: Hibernate: select bank0_.id as id142_, bank0_.ext_code as ext2_142_, bank0_.name as name142_ from es_bank bank0_
INFO: === 2012-02-13 11:23:49,664 [0(5)] INFO  DSResponse - [builtinApplication.bank_fetch] DSResponse: List with 37 items

INFO: === 2012-02-13 11:23:49,664 [0(5)] DEBUG AppBase - [builtinApplication.paymentPurpose_fetch] No userTypes defined, allowing anyone access to all operations for this application

INFO: === 2012-02-13 11:23:49,665 [0(5)] DEBUG AppBase - [builtinApplication.paymentPurpose_fetch] No public zero-argument method named '_paymentPurpose_fetch' found, performing generic datasource operation

INFO: === 2012-02-13 11:23:49,665 [0(5)] DEBUG JPADataSource - [builtinApplication.paymentPurpose_fetch] Creating EntityManager and starting transaction.

INFO: === 2012-02-13 11:23:49,665 [0(5)] WARN  RequestContext - dsRequest.execute() failed: 
javax.persistence.PersistenceException: Unable to acquire transaction.
	at com.isomorphic.jpa.EMFProviderBMT.getTransaction(EMFProviderBMT.java:181)
	at com.isomorphic.jpa.EMF.getTransaction(EMF.java:183)
	at com.isomorphic.jpa.JPADataSource.execute(JPADataSource.java:423)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:721)
	at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658)
	at com.isomorphic.application.AppBase.execute(AppBase.java:491)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:1948)
	at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:199)
	at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:156)
	at net.esteh.esdoc.server.web.servlet.EstehIDACallServlet.processRPCTransaction(EstehIDACallServlet.java:34)
	at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:121)
	at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754)
	at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847)
	at org.apache.catalina.core.StandardWrapper.service(StandardWrapper.java:1539)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:343)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:217)
	at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:259)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:256)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:217)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:279)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardPipeline.doInvoke(StandardPipeline.java:655)
	at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:595)
	at com.sun.enterprise.web.WebPipeline.invoke(WebPipeline.java:98)
	at com.sun.enterprise.web.PESessionLockingStandardPipeline.invoke(PESessionLockingStandardPipeline.java:91)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:162)
	at org.apache.catalina.connector.CoyoteAdapter.doService(CoyoteAdapter.java:330)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:231)
	at com.sun.enterprise.v3.services.impl.ContainerMapper.service(ContainerMapper.java:174)
	at com.sun.grizzly.http.ProcessorTask.invokeAdapter(ProcessorTask.java:828)
	at com.sun.grizzly.http.ProcessorTask.doProcess(ProcessorTask.java:725)
	at com.sun.grizzly.http.ProcessorTask.process(ProcessorTask.java:1019)
	at com.sun.grizzly.http.DefaultProtocolFilter.execute(DefaultProtocolFilter.java:225)
	at com.sun.grizzly.DefaultProtocolChain.executeProtocolFilter(DefaultProtocolChain.java:137)
	at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:104)
	at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:90)
	at com.sun.grizzly.http.HttpProtocolChain.execute(HttpProtocolChain.java:79)
	at com.sun.grizzly.ProtocolChainContextTask.doCall(ProtocolChainContextTask.java:54)
	at com.sun.grizzly.SelectionKeyContextTask.call(SelectionKeyContextTask.java:59)
	at com.sun.grizzly.ContextTask.run(ContextTask.java:71)
	at com.sun.grizzly.util.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:532)
	at com.sun.grizzly.util.AbstractThreadPool$Worker.run(AbstractThreadPool.java:513)
	at java.lang.Thread.run(Thread.java:680)
Caused by: javax.transaction.NotSupportedException: Nested transaction not supported.
	at com.sun.enterprise.transaction.JavaEETransactionManagerSimplified.begin(JavaEETransactionManagerSimplified.java:800)
	at com.sun.enterprise.transaction.JavaEETransactionManagerSimplified.begin(JavaEETransactionManagerSimplified.java:788)
	at com.sun.enterprise.transaction.UserTransactionImpl.begin(UserTransactionImpl.java:165)
	at com.isomorphic.jpa.EMFProviderBMT.getTransaction(EMFProviderBMT.java:178)
	... 44 more

INFO: === 2012-02-13 11:23:49,666 [0(5)] DEBUG AppBase - [builtinApplication.orderErrorCode_fetch] No userTypes defined, allowing anyone access to all operations for this application

INFO: === 2012-02-13 11:23:49,666 [0(5)] DEBUG AppBase - [builtinApplication.orderErrorCode_fetch] No public zero-argument method named '_orderErrorCode_fetch' found, performing generic datasource operation

INFO: === 2012-02-13 11:23:49,667 [0(5)] DEBUG JPADataSource - [builtinApplication.orderErrorCode_fetch] Creating EntityManager and starting transaction.

INFO: === 2012-02-13 11:23:49,667 [0(5)] WARN  RequestContext - dsRequest.execute() failed: 
javax.persistence.PersistenceException: Unable to acquire transaction.
	at com.isomorphic.jpa.EMFProviderBMT.getTransaction(EMFProviderBMT.java:181)
	at com.isomorphic.jpa.EMF.getTransaction(EMF.java:183)
	at com.isomorphic.jpa.JPADataSource.execute(JPADataSource.java:423)
	at com.isomorphic.application.AppBase.executeDefaultDSOperation(AppBase.java:721)
	at com.isomorphic.application.AppBase.executeAppOperation(AppBase.java:658)
	at com.isomorphic.application.AppBase.execute(AppBase.java:491)
	at com.isomorphic.datasource.DSRequest.execute(DSRequest.java:1948)
	at com.isomorphic.servlet.IDACall.handleDSRequest(IDACall.java:199)
	at com.isomorphic.servlet.IDACall.processRPCTransaction(IDACall.java:156)
	at net.esteh.esdoc.server.web.servlet.EstehIDACallServlet.processRPCTransaction(EstehIDACallServlet.java:34)
	at com.isomorphic.servlet.IDACall.processRequest(IDACall.java:121)
	at com.isomorphic.servlet.IDACall.doPost(IDACall.java:73)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754)
	at com.isomorphic.servlet.BaseServlet.service(BaseServlet.java:152)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847)
	at org.apache.catalina.core.StandardWrapper.service(StandardWrapper.java:1539)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:343)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:217)
	at com.isomorphic.servlet.CompressionFilter.doFilter(CompressionFilter.java:259)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:256)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:217)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:279)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardPipeline.doInvoke(StandardPipeline.java:655)
	at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:595)
	at com.sun.enterprise.web.WebPipeline.invoke(WebPipeline.java:98)
	at com.sun.enterprise.web.PESessionLockingStandardPipeline.invoke(PESessionLockingStandardPipeline.java:91)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:162)
	at org.apache.catalina.connector.CoyoteAdapter.doService(CoyoteAdapter.java:330)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:231)
	at com.sun.enterprise.v3.services.impl.ContainerMapper.service(ContainerMapper.java:174)
	at com.sun.grizzly.http.ProcessorTask.invokeAdapter(ProcessorTask.java:828)
	at com.sun.grizzly.http.ProcessorTask.doProcess(ProcessorTask.java:725)
	at com.sun.grizzly.http.ProcessorTask.process(ProcessorTask.java:1019)
	at com.sun.grizzly.http.DefaultProtocolFilter.execute(DefaultProtocolFilter.java:225)
	at com.sun.grizzly.DefaultProtocolChain.executeProtocolFilter(DefaultProtocolChain.java:137)
	at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:104)
	at com.sun.grizzly.DefaultProtocolChain.execute(DefaultProtocolChain.java:90)
	at com.sun.grizzly.http.HttpProtocolChain.execute(HttpProtocolChain.java:79)
	at com.sun.grizzly.ProtocolChainContextTask.doCall(ProtocolChainContextTask.java:54)
	at com.sun.grizzly.SelectionKeyContextTask.call(SelectionKeyContextTask.java:59)
	at com.sun.grizzly.ContextTask.run(ContextTask.java:71)
	at com.sun.grizzly.util.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:532)
	at com.sun.grizzly.util.AbstractThreadPool$Worker.run(AbstractThreadPool.java:513)
	at java.lang.Thread.run(Thread.java:680)
Caused by: javax.transaction.NotSupportedException: Nested transaction not supported.
	at com.sun.enterprise.transaction.JavaEETransactionManagerSimplified.begin(JavaEETransactionManagerSimplified.java:800)
	at com.sun.enterprise.transaction.JavaEETransactionManagerSimplified.begin(JavaEETransactionManagerSimplified.java:788)
	at com.sun.enterprise.transaction.UserTransactionImpl.begin(UserTransactionImpl.java:165)
	at com.isomorphic.jpa.EMFProviderBMT.getTransaction(EMFProviderBMT.java:178)
	... 44 more

INFO: === 2012-02-13 11:23:49,668 [0(5)] DEBUG RPCManager - Content type for RPC transaction: text/plain; charset=UTF-8

INFO: === 2012-02-13 11:23:49,668 [0(5)] DEBUG JPADataSource - Rolling back current transaction.

INFO: === 2012-02-13 11:23:49,670 [0(5)] WARN  DSResponse - Attempted to call getRecord() on a DSResponse with null DataSource

INFO: === 2012-02-13 11:23:49,670 [0(5)] DEBUG DataSource - In DS.forName() for 'bank' with DSRequest: com.isomorphic.datasource.DSRequest@604cbd

INFO: === 2012-02-13 11:23:49,670 [0(5)] DEBUG DataSource - Creating instance of DataSource 'bank'

INFO: === 2012-02-13 11:23:49,671 [0(5)] DEBUG RPCManager - non-DMI response, dropExtraFields: false

INFO: === 2012-02-13 11:23:49,671 [0(5)] DEBUG DataSource - In DS.forName() for 'paymentPurpose' with DSRequest: com.isomorphic.datasource.DSRequest@4e2100

INFO: === 2012-02-13 11:23:49,671 [0(5)] DEBUG DataSource - Creating instance of DataSource 'paymentPurpose'

INFO: === 2012-02-13 11:23:49,671 [0(5)] DEBUG RPCManager - non-DMI response, dropExtraFields: false

INFO: === 2012-02-13 11:23:49,671 [0(5)] DEBUG DataSource - In DS.forName() for 'orderErrorCode' with DSRequest: com.isomorphic.datasource.DSRequest@158ad1c

INFO: === 2012-02-13 11:23:49,672 [0(5)] DEBUG DataSource - Creating instance of DataSource 'orderErrorCode'

INFO: === 2012-02-13 11:23:49,672 [0(5)] DEBUG RPCManager - non-DMI response, dropExtraFields: true

INFO: === 2012-02-13 11:23:49,683 [0(5)] DEBUG DataSource - In DS.forName() for 'string' with DSRequest: null

INFO: === 2012-02-13 11:23:49,684 [0(5)] DEBUG DataSource - In DS.forName() for 'sequence' with DSRequest: null

INFO: === 2012-02-13 11:23:49,685 [0(5)] DEBUG JPADataSource - Releasing entity manager.

INFO: === 2012-02-13 11:23:49,685 [0(5)] DEBUG JPADataSource - Releasing entity manager.

INFO: === 2012-02-13 11:23:49,685 [0(5)] DEBUG JPADataSource - Releasing entity manager.

INFO: === 2012-02-13 11:23:49,686 [0(5)] INFO  Compression - /esdoc-web/esdoc/sc/IDACall: 3053 -> 1054 bytes


INFO: === 2012-02-13 11:23:49,835 [0(1)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/ToolStrip/background.png

INFO: === 2012-02-13 11:23:49,836 [0(5)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/ToolStrip/button/button_end.png

INFO: === 2012-02-13 11:23:49,836 [0(3)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/ToolStrip/button/button_start.png

INFO: === 2012-02-13 11:23:49,835 [0(2)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/ToolStrip/button/button_stretch.png

INFO: === 2012-02-13 11:23:49,835 [0(4)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/Menu/menu_button.png

INFO: === 2012-02-13 11:23:49,838 [0(1)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/ToolStrip/separator.png


INFO: === 2012-02-13 11:23:51,428 [0(5)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/button/button_Down_start.png

INFO: === 2012-02-13 11:23:51,428 [0(3)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/button/button_Down_stretch.png

INFO: === 2012-02-13 11:23:51,428 [0(2)] INFO  Download - Returning 304: Not modified on conditional get of: /Applications/glassfish3/glassfish/domains/domain1/eclipseApps/esdoc/esdoc-web_war/esdoc/sc/skins/Enterprise/images/button/button_Down_end.png


INFO: Gettint orders from queue OrderQueue{id=17, name=???????????????, statusId=1}
INFO: Gettint orders from queue OrderQueue{id=21, name=???, statusId=1}
INFO: Gettint orders from queue OrderQueue{id=17, name=???????????????, statusId=1}
INFO: Hibernate: select top 2 max(orderbatch0_.forwarded_time) as col_0_0_ from es_order_batch orderbatch0_
INFO: Hibernate: select orderbatch1_.id as id138_, orderbatch1_.batch_type as batch2_138_, orderbatch1_.branch_office_id as branch20_138_, orderbatch1_.client_amount_for_check as client3_138_, orderbatch1_.client_currency_for_check as client4_138_, orderbatch1_.client_id_for_check as client5_138_, orderbatch1_.client_name_for_check as client6_138_, orderbatch1_.client_type as client7_138_, orderbatch1_.created_by_full_name as created8_138_, orderbatch1_.created_time as created9_138_, orderbatch1_.ext_code as ext10_138_, orderbatch1_.forwarded_by_full_name as forwarded11_138_, orderbatch1_.forwarded_time as forwarded12_138_, orderbatch1_.comment as comment138_, orderbatch1_.number_of_orders as number14_138_, orderbatch1_.priority as priority138_, orderbatch1_.specific_number_for_check as specific16_138_, orderbatch1_.status_id as status17_138_, orderbatch1_.status_desc as status18_138_, orderbatch1_.value_date_for_check as value19_138_ from es_order_queue_status orderqueue0_ inner join es_order_batch orderbatch1_ on orderqueue0_.order_batch_id=orderbatch1_.id where orderqueue0_.order_queue_id=? and orderqueue0_.status_id=1
INFO: Hibernate: select top 2 sum(orderbatch1_.number_of_orders) as col_0_0_ from es_order_queue_status orderqueue0_, es_order_batch orderbatch1_ where orderqueue0_.order_batch_id=orderbatch1_.id and orderqueue0_.order_queue_id=? and orderqueue0_.status_id=1
INFO: Hibernate: select orderqueue0_.id as id135_0_, order1_.id as id139_1_, orderqueue0_.date_created as date2_135_0_, orderqueue0_.date_ended as date3_135_0_, orderqueue0_.date_started as date4_135_0_, orderqueue0_.order_id as order6_135_0_, orderqueue0_.order_batch_id as order7_135_0_, orderqueue0_.order_queue_id as order8_135_0_, orderqueue0_.status_id as status5_135_0_, order1_.amount as amount139_1_, order1_.batch_id as batch28_139_1_, order1_.currency_code as currency3_139_1_, order1_.ext_code as ext4_139_1_, order1_.file_path as file5_139_1_, order1_.from_account as from6_139_1_, order1_.from_model as from7_139_1_, order1_.from_reference as from8_139_1_, order1_.ord_num as ord9_139_1_, order1_.order_error_code_id as order29_139_1_, order1_.order_type as order10_139_1_, order1_.payer as payer139_1_, order1_.payment_code as payment12_139_1_, order1_.payment_date as payment13_139_1_, order1_.payment_purpose as payment14_139_1_, order1_.priority as priority139_1_, order1_.receive_date as receive16_139_1_, order1_.receive_place as receive17_139_1_, order1_.receiver as receiver139_1_, order1_.reference as reference139_1_, order1_.status_id as status20_139_1_, order1_.to_account as to21_139_1_, order1_.to_model as to22_139_1_, order1_.to_reference as to23_139_1_, order1_.transaction_status as transac24_139_1_, order1_.transaction_type as transac25_139_1_, order1_.urgent as urgent139_1_, order1_.verified_by_user_id as verified30_139_1_, order1_.verified_on as verified27_139_1_ from es_order_queue_status orderqueue0_ inner join es_order order1_ on orderqueue0_.order_id=order1_.id cross join es_order order2_ where orderqueue0_.order_id=order2_.id and orderqueue0_.order_queue_id=? and orderqueue0_.status_id=? and (order2_.status_id in (?)) order by order2_.ord_num
INFO: Hibernate: select orderqueue0_.id as id135_0_, order1_.id as id139_1_, orderqueue0_.date_created as date2_135_0_, orderqueue0_.date_ended as date3_135_0_, orderqueue0_.date_started as date4_135_0_, orderqueue0_.order_id as order6_135_0_, orderqueue0_.order_batch_id as order7_135_0_, orderqueue0_.order_queue_id as order8_135_0_, orderqueue0_.status_id as status5_135_0_, order1_.amount as amount139_1_, order1_.batch_id as batch28_139_1_, order1_.currency_code as currency3_139_1_, order1_.ext_code as ext4_139_1_, order1_.file_path as file5_139_1_, order1_.from_account as from6_139_1_, order1_.from_model as from7_139_1_, order1_.from_reference as from8_139_1_, order1_.ord_num as ord9_139_1_, order1_.order_error_code_id as order29_139_1_, order1_.order_type as order10_139_1_, order1_.payer as payer139_1_, order1_.payment_code as payment12_139_1_, order1_.payment_date as payment13_139_1_, order1_.payment_purpose as payment14_139_1_, order1_.priority as priority139_1_, order1_.receive_date as receive16_139_1_, order1_.receive_place as receive17_139_1_, order1_.receiver as receiver139_1_, order1_.reference as reference139_1_, order1_.status_id as status20_139_1_, order1_.to_account as to21_139_1_, order1_.to_model as to22_139_1_, order1_.to_reference as to23_139_1_, order1_.transaction_status as transac24_139_1_, order1_.transaction_type as transac25_139_1_, order1_.urgent as urgent139_1_, order1_.verified_by_user_id as verified30_139_1_, order1_.verified_on as verified27_139_1_ from es_order_queue_status orderqueue0_ inner join es_order order1_ on orderqueue0_.order_id=order1_.id cross join es_order order2_ where orderqueue0_.order_id=order2_.id and orderqueue0_.order_queue_id=? and orderqueue0_.status_id=? and (order2_.status_id in (?)) order by order2_.ord_num
INFO: Importing new batches from 2012-01-23 13:20:14.21
INFO: Hibernate: select orderqueue0_.id as id135_0_, order1_.id as id139_1_, orderqueue0_.date_created as date2_135_0_, orderqueue0_.date_ended as date3_135_0_, orderqueue0_.date_started as date4_135_0_, orderqueue0_.order_id as order6_135_0_, orderqueue0_.order_batch_id as order7_135_0_, orderqueue0_.order_queue_id as order8_135_0_, orderqueue0_.status_id as status5_135_0_, order1_.amount as amount139_1_, order1_.batch_id as batch28_139_1_, order1_.currency_code as currency3_139_1_, order1_.ext_code as ext4_139_1_, order1_.file_path as file5_139_1_, order1_.from_account as from6_139_1_, order1_.from_model as from7_139_1_, order1_.from_reference as from8_139_1_, order1_.ord_num as ord9_139_1_, order1_.order_error_code_id as order29_139_1_, order1_.order_type as order10_139_1_, order1_.payer as payer139_1_, order1_.payment_code as payment12_139_1_, order1_.payment_date as payment13_139_1_, order1_.payment_purpose as payment14_139_1_, order1_.priority as priority139_1_, order1_.receive_date as receive16_139_1_, order1_.receive_place as receive17_139_1_, order1_.receiver as receiver139_1_, order1_.reference as reference139_1_, order1_.status_id as status20_139_1_, order1_.to_account as to21_139_1_, order1_.to_model as to22_139_1_, order1_.to_reference as to23_139_1_, order1_.transaction_status as transac24_139_1_, order1_.transaction_type as transac25_139_1_, order1_.urgent as urgent139_1_, order1_.verified_by_user_id as verified30_139_1_, order1_.verified_on as verified27_139_1_ from es_order_queue_status orderqueue0_ inner join es_order order1_ on orderqueue0_.order_id=order1_.id cross join es_order order2_ where orderqueue0_.order_id=order2_.id and orderqueue0_.order_queue_id=? and orderqueue0_.status_id=? and (order2_.status_id in (?)) order by order2_.ord_num
INFO: Current Queue size(18) is grater than maximum allowed ( 15)
INFO: Got 0 batches f

**alius** · 13 Feb 2012, 09:55

Hi,

I've just committed changes (EMFProviderBMT). You can pick it up with next nightly build.

Important to note: all DSRequests executed in the same queue will participate in the same user transaction. Therefore if one request fails then all requests in the same queue will be rolled back.

Please let me know: did it fixed your problem.

Regards,
Alius

**dejan** · 13 Feb 2012, 13:09

Isn't that correct behavior when using queueing (all requests are part of one transaction)?

Regarding nightly builds, should I download 3.0 patched version or 3.1?

**alius** · 13 Feb 2012, 13:39

Queuing is not about transactions - it is about sending several requests in one shot.

Regarding nightly builds: changes committed to both branches so it is up to you.

**dejan** · 13 Feb 2012, 14:04

Hm, I guess I got that impression because queueing examples are under Transactions node in SmartGWT EE showcase explorer.

As I understood Automatic Transaction Management example description, treating several queued requests as one transaction is default behavior which can be overridden. Is it the same with new JPA Data Source code, or "one queue-one transaction" is the only way to work with it?

**alius** · 13 Feb 2012, 14:22

It should be default for POWER and ENTERPRISE.

No - it is different code. Actually single transaction is a side effect of using JTA which binds UserTransaction to current thread. So all JPA DS'es will participate in that transaction.

Announcement

JPADataSource - transaction problem when using queueing

Comment

Comment

Comment

Comment

Comment

Comment

Comment