search

afimb / chouette

chouette is a Java program for validating and exchanging Public Transport datas. Comes with a ruby web app : https://github.com/afimb/chouette2
http://www.chouette.mobi
Other
23 stars 22 forks source link

Large dataset causes Transaction timeout and reaping on GTFS export #24

Closed seime closed 8 years ago

seime commented 8 years ago

Tested using version 3.2.

Steps to reproduce:

  1. Create new data space
  2. Import attached GTFS bundle
  3. Create new GTFS export
11:37:20,995 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=77.0, Hits=2000.0, Avg=123.362, Total=246724.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:20 CET 2016)
11:37:20,995 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=79.0, Hits=2000.0, Avg=125.18, Total=250360.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:20 CET 2016)
11:37:21,080 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper) ARJUNA012117: TransactionReaper::check timeout for TX 0:ffffc0a80008:-1cf78795:56cc0670:1dded6 in state  RUN
11:37:21,084 WARN  [org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorTrackingImpl] (Transaction Reaper Worker 0) HHH000451: Transaction afterCompletion called by a background thread; delaying afterCompletion processing until the original thread can handle it. [status=4]
11:37:21,084 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012121: TransactionReaper::doCancellations worker Thread[Transaction Reaper Worker 0,5,main] successfully canceled TX 0:ffffc0a80008:-1cf78795:56cc0670:1dded6
11:37:21,100 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=89.0, Hits=2001.0, Avg=123.34482758620689, Total=246813.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,100 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=91.0, Hits=2001.0, Avg=125.16291854072963, Total=250451.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,228 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=95.0, Hits=2002.0, Avg=123.33066933066934, Total=246908.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,228 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=97.0, Hits=2002.0, Avg=125.14885114885115, Total=250548.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,342 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=91.0, Hits=2003.0, Avg=123.31452820768847, Total=246999.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,342 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=93.0, Hits=2003.0, Avg=125.1328007988018, Total=250641.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,466 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=108.0, Hits=2004.0, Avg=123.30688622754491, Total=247107.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,466 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=109.0, Hits=2004.0, Avg=125.12475049900199, Total=250750.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,638 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=156.0, Hits=2005.0, Avg=123.32319201995013, Total=247263.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,638 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=158.0, Hits=2005.0, Avg=125.14114713216958, Total=250908.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,733 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=74.0, Hits=2006.0, Avg=123.29860418743769, Total=247337.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,733 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=76.0, Hits=2006.0, Avg=125.11665004985045, Total=250984.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,820 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=72.0, Hits=2007.0, Avg=123.27304434479322, Total=247409.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:21,820 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=74.0, Hits=2007.0, Avg=125.09118086696562, Total=251058.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:21 CET 2016)
11:37:22,073 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=239.0, Hits=2008.0, Avg=123.33067729083665, Total=247648.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:22 CET 2016)
11:37:22,073 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=241.0, Hits=2008.0, Avg=125.14890438247012, Total=251299.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:22 CET 2016)
11:37:22,329 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=231.0, Hits=2009.0, Avg=123.38427078148332, Total=247879.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:22 CET 2016)
11:37:22,329 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=233.0, Hits=2009.0, Avg=125.20258835241414, Total=251532.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:22 CET 2016)
11:37:22,511 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=159.0, Hits=2010.0, Avg=123.40199004975125, Total=248038.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:22 CET 2016)
11:37:22,511 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=160.0, Hits=2010.0, Avg=125.21990049751244, Total=251692.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:22 CET 2016)
11:37:22,813 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=221.0, Hits=2011.0, Avg=123.45052212829438, Total=248259.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:22 CET 2016)
11:37:22,814 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=224.0, Hits=2011.0, Avg=125.26902038786673, Total=251916.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:22 CET 2016)
11:37:23,085 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=140.0, Hits=2012.0, Avg=123.45874751491054, Total=248399.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:23 CET 2016)
11:37:23,085 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=142.0, Hits=2012.0, Avg=125.27733598409543, Total=252058.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:23 CET 2016)
11:37:23,293 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=147.0, Hits=2013.0, Avg=123.47044212617983, Total=248546.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:23 CET 2016)
11:37:23,293 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=149.0, Hits=2013.0, Avg=125.28912071535022, Total=252207.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:23 CET 2016)
11:37:23,529 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=151.0, Hits=2014.0, Avg=123.48411122144985, Total=248697.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:23 CET 2016)
11:37:23,529 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=153.0, Hits=2014.0, Avg=125.30287984111222, Total=252360.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:23 CET 2016)
11:37:23,820 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=207.0, Hits=2015.0, Avg=123.5255583126551, Total=248904.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:23 CET 2016)
11:37:23,820 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=209.0, Hits=2015.0, Avg=125.34441687344913, Total=252569.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:23 CET 2016)
11:37:24,081 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=132.0, Hits=2016.0, Avg=123.5297619047619, Total=249036.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:24 CET 2016)
11:37:24,081 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=134.0, Hits=2016.0, Avg=125.34871031746032, Total=252703.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:24 CET 2016)
11:37:24,281 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsLineProducerCommand, Units=ms.: (LastValue=139.0, Hits=2017.0, Avg=123.53743182944967, Total=249175.0, Min=17.0, Max=627.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:24 CET 2016)
11:37:24,281 INFO  [mobi.chouette.exchange.gtfs.exporter.DaoGtfsLineProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=DaoGtfsLineProducerCommand, Units=ms.: (LastValue=142.0, Hits=2017.0, Avg=125.35696579077839, Total=252845.0, Min=19.0, Max=629.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:20 CET 2016, Last Access=Tue Feb 23 11:37:24 CET 2016)
11:37:24,459 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsSharedDataProducerCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsSharedDataProducerCommand, Units=ms.: (LastValue=85.0, Hits=2.0, Avg=249.0, Total=498.0, Min=85.0, Max=413.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:35 CET 2016, Last Access=Tue Feb 23 11:37:24 CET 2016)
11:37:24,497 INFO  [mobi.chouette.exchange.exporter.SaveMetadataCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=SaveMetadataCommand, Units=ms.: (LastValue=27.0, Hits=2.0, Avg=54.5, Total=109.0, Min=27.0, Max=82.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:35 CET 2016, Last Access=Tue Feb 23 11:37:24 CET 2016)
11:37:24,514 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsTerminateExportCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsTerminateExportCommand, Units=ms.: (LastValue=2.0, Hits=2.0, Avg=10.0, Total=20.0, Min=2.0, Max=18.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:35 CET 2016, Last Access=Tue Feb 23 11:37:24 CET 2016)
11:37:29,088 INFO  [mobi.chouette.exchange.exporter.CompressCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=CompressCommand, Units=ms.: (LastValue=4563.0, Hits=2.0, Avg=2627.0, Total=5254.0, Min=691.0, Max=4563.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:36 CET 2016, Last Access=Tue Feb 23 11:37:29 CET 2016)
11:37:29,111 INFO  [mobi.chouette.exchange.gtfs.exporter.GtfsExporterCommand] (EE-ManagedExecutorService-default-Thread-1) JAMon Label=GtfsExporterCommand, Units=ms.: (LastValue=308032.0, Hits=2.0, Avg=161941.0, Total=323882.0, Min=15850.0, Max=308032.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Tue Feb 23 11:04:36 CET 2016, Last Access=Tue Feb 23 11:37:29 CET 2016)
11:37:29,112 ERROR [org.jboss.as.ejb3.invocation] (EE-ManagedExecutorService-default-Thread-1) JBAS014134: EJB Invocation failed on component GtfsExporterCommand for method public abstract boolean mobi.chouette.common.chain.Command.execute(mobi.chouette.common.Context) throws java.lang.Exception: javax.ejb.EJBTransactionRolledbackException: Transaction rolled back
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.handleEndTransactionException(CMTTxInterceptor.java:139) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.endTransaction(CMTTxInterceptor.java:119) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:277) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:340) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:239) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:43) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:95) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:55) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:64)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:326)
    at org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:439)
    at org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:61)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:326)
    at org.jboss.invocation.PrivilegedWithCombinerInterceptor.processInvocation(PrivilegedWithCombinerInterceptor.java:80)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:185)
    at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:182)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:73)
    at mobi.chouette.common.chain.Command$$$view12.execute(Unknown Source) [mobi.chouette.common.jar:]
    at mobi.chouette.scheduler.MainCommand.execute(MainCommand.java:60) [mobi.chouette.service.jar:]
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0_66]
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0_66]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_66]
    at java.lang.reflect.Method.invoke(Method.java:497) [rt.jar:1.8.0_66]
    at org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
    at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:407)
    at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:82) [wildfly-weld-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:93) [wildfly-weld-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
    at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.jpa.interceptor.SBInvocationInterceptor.processInvocation(SBInvocationInterceptor.java:47) [wildfly-jpa-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:407)
    at org.jboss.weld.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:55) [weld-core-impl-2.2.6.Final.jar:2014-10-03 10:05]
    at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:83) [wildfly-weld-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45) [wildfly-ee-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.NonPooledEJBComponentInstanceAssociatingInterceptor.processInvocation(NonPooledEJBComponentInstanceAssociatingInterceptor.java:59) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:260) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.notSupported(CMTTxInterceptor.java:324) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:237) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:43) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:95) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:55) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:64)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:326)
    at org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:439)
    at org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:61)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:326)
    at org.jboss.invocation.PrivilegedWithCombinerInterceptor.processInvocation(PrivilegedWithCombinerInterceptor.java:80)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:185)
    at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:182)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:73)
    at mobi.chouette.common.chain.Command$$$view32.execute(Unknown Source)
    at mobi.chouette.scheduler.Task.call(Task.java:56) [mobi.chouette.service.jar:]
    at mobi.chouette.scheduler.Task.call(Task.java:22) [mobi.chouette.service.jar:]
    at java.util.concurrent.FutureTask.run(FutureTask.java:266) [rt.jar:1.8.0_66]
    at org.glassfish.enterprise.concurrent.internal.ManagedFutureTask.run(ManagedFutureTask.java:141) [javax.enterprise.concurrent-1.0.jar:]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0_66]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0_66]
    at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_66]
    at org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:250) [javax.enterprise.concurrent-1.0.jar:]
Caused by: javax.transaction.RollbackException: JBAS014585: Transaction 'TransactionImple < ac, BasicAction: 0:ffffc0a80008:-1cf78795:56cc0670:1dded6 status: ActionStatus.ABORTED >' was already rolled back
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.endTransaction(CMTTxInterceptor.java:100) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    ... 111 more

11:37:29,121 WARN  [mobi.chouette.scheduler.MainCommand] (EE-ManagedExecutorService-default-Thread-1) execption bypassed javax.ejb.EJBTransactionRolledbackException: Transaction rolled back
11:37:29,125 INFO  [mobi.chouette.scheduler.Scheduler] (EE-ManagedExecutorService-default-Thread-1) task done : null -> Task(job=JobService(job=Job(id=87, referential=rut, action=exporter, type=gtfs, filename=null, created=2016-02-23 11:32:21.041, started=Tue Feb 23 11:32:21 CET 2016, updated=Tue Feb 23 11:32:21 CET 2016, status=STARTED, links=[Link(type=application/json, rel=parameters), Link(type=application/json, rel=action_params), Link(type=application/json, rel=cancel), Link(type=application/json, rel=action_report)])))
11:37:29,138 INFO  [mobi.chouette.scheduler.Scheduler] (EE-ManagedExecutorService-default-Thread-1) schedule referential rut
11:37:29,139 INFO  [mobi.chouette.scheduler.Scheduler] (EE-ManagedExecutorService-default-Thread-1) nothing to schedule

ruter.zip

metienne commented 8 years ago

sorry, wrong issue reference, this commit closes issue #23

seime commented 8 years ago

Also happens with large datasets using other formats (also tested with ie Neptune)

09:56:24,375 INFO  [mobi.chouette.exchange.neptune.exporter.DaoNeptuneLineProducerCommand] (EE-ManagedExecutorService-default-Thread-2) JAMon Label=DaoNeptuneLineProducerCommand, Units=ms.: (LastValue=843.0, Hits=387.0, Avg=769.9173126614987, Total=297958.0, Min=146.0, Max=3434.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Thu Feb 25 09:51:28 CET 2016, Last Access=Thu Feb 25 09:56:24 CET 2016)
09:56:24,701 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper) ARJUNA012117: TransactionReaper::check timeout for TX 0:ffff0af00521:-24ab8410:56cdabb3:3c8721 in state  RUN
09:56:24,705 WARN  [org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorTrackingImpl] (Transaction Reaper Worker 0) HHH000451: Transaction afterCompletion called by a background thread; delaying afterCompletion processing until the original thread can handle it. [status=4]
09:56:24,706 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012121: TransactionReaper::doCancellations worker Thread[Transaction Reaper Worker 0,5,main] successfully canceled TX 0:ffff0af00521:-24ab8410:56cdabb3:3c8721

...

12:15:48,812 INFO  [mobi.chouette.exchange.neptune.exporter.NeptuneLineProducerCommand] (EE-ManagedExecutorService-default-Thread-2) JAMon Label=NeptuneLineProducerCommand, Units=ms.: (LastValue=393.0, Hits=10082.0, Avg=796.5512795080341, Total=8030830.0, Min=-2355.0, Max=10080.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Thu Feb 25 09:51:28 CET 2016, Last Access=Thu Feb 25 12:15:48 CET 2016)
12:15:48,812 INFO  [mobi.chouette.exchange.neptune.exporter.DaoNeptuneLineProducerCommand] (EE-ManagedExecutorService-default-Thread-2) JAMon Label=DaoNeptuneLineProducerCommand, Units=ms.: (LastValue=394.0, Hits=10082.0, Avg=798.6200158698671, Total=8051687.0, Min=-2353.0, Max=10082.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Thu Feb 25 09:51:28 CET 2016, Last Access=Thu Feb 25 12:15:48 CET 2016)
12:15:49,152 INFO  [mobi.chouette.exchange.exporter.SaveMetadataCommand] (EE-ManagedExecutorService-default-Thread-2) JAMon Label=SaveMetadataCommand, Units=ms.: (LastValue=182.0, Hits=1.0, Avg=182.0, Total=182.0, Min=182.0, Max=182.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Thu Feb 25 12:15:49 CET 2016, Last Access=Thu Feb 25 12:15:49 CET 2016)
12:16:59,938 INFO  [mobi.chouette.exchange.exporter.CompressCommand] (EE-ManagedExecutorService-default-Thread-2) JAMon Label=CompressCommand, Units=ms.: (LastValue=62339.0, Hits=1.0, Avg=62339.0, Total=62339.0, Min=62339.0, Max=62339.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Thu Feb 25 12:16:59 CET 2016, Last Access=Thu Feb 25 12:16:59 CET 2016)
12:17:00,096 INFO  [mobi.chouette.exchange.neptune.exporter.NeptuneExporterCommand] (EE-ManagedExecutorService-default-Thread-2) JAMon Label=NeptuneExporterCommand, Units=ms.: (LastValue=8735394.0, Hits=1.0, Avg=8735394.0, Total=8735394.0, Min=8735394.0, Max=8735394.0, Active=0.0, Avg Active=1.0, Max Active=1.0, First Access=Thu Feb 25 12:17:00 CET 2016, Last Access=Thu Feb 25 12:17:00 CET 2016)
12:17:00,100 ERROR [org.jboss.as.ejb3.invocation] (EE-ManagedExecutorService-default-Thread-2) JBAS014134: EJB Invocation failed on component NeptuneExporterCommand for method public abstract boolean mobi.chouette.common.chain.Command.execute(mobi.chouette.common.Context) throws java.lang.Exception: javax.ejb.EJBTransactionRolledbackException: Transaction rolled back
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.handleEndTransactionException(CMTTxInterceptor.java:139) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.endTransaction(CMTTxInterceptor.java:119) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:277) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:340) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:239) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:43) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:95) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:55) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:64)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:326)
    at org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:439)
    at org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:61)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:326)
    at org.jboss.invocation.PrivilegedWithCombinerInterceptor.processInvocation(PrivilegedWithCombinerInterceptor.java:80)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:185)
    at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:182)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:73)
    at mobi.chouette.common.chain.Command$$$view13.execute(Unknown Source) [mobi.chouette.common.jar:]
    at mobi.chouette.scheduler.MainCommand.execute(MainCommand.java:60) [mobi.chouette.service.jar:]
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0_66]
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0_66]
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_66]
    at java.lang.reflect.Method.invoke(Method.java:497) [rt.jar:1.8.0_66]
    at org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
    at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:407)
    at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:82) [wildfly-weld-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:93) [wildfly-weld-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
    at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.jpa.interceptor.SBInvocationInterceptor.processInvocation(SBInvocationInterceptor.java:47) [wildfly-jpa-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:407)
    at org.jboss.weld.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:55) [weld-core-impl-2.2.6.Final.jar:2014-10-03 10:05]
    at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:83) [wildfly-weld-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45) [wildfly-ee-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.NonPooledEJBComponentInstanceAssociatingInterceptor.processInvocation(NonPooledEJBComponentInstanceAssociatingInterceptor.java:59) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:260) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.notSupported(CMTTxInterceptor.java:324) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:237) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:43) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:95) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:55) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:64)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:326)
    at org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:439)
    at org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:61)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:326)
    at org.jboss.invocation.PrivilegedWithCombinerInterceptor.processInvocation(PrivilegedWithCombinerInterceptor.java:80)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:185)
    at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:182)
    at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
    at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
    at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:73)
    at mobi.chouette.common.chain.Command$$$view29.execute(Unknown Source)
    at mobi.chouette.scheduler.Task.call(Task.java:56) [mobi.chouette.service.jar:]
    at mobi.chouette.scheduler.Task.call(Task.java:22) [mobi.chouette.service.jar:]
    at java.util.concurrent.FutureTask.run(FutureTask.java:266) [rt.jar:1.8.0_66]
    at org.glassfish.enterprise.concurrent.internal.ManagedFutureTask.run(ManagedFutureTask.java:141) [javax.enterprise.concurrent-1.0.jar:]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0_66]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0_66]
    at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_66]
    at org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:250) [javax.enterprise.concurrent-1.0.jar:]
Caused by: javax.transaction.RollbackException: JBAS014585: Transaction 'TransactionImple < ac, BasicAction: 0:ffff0af00521:-24ab8410:56cdabb3:3c8721 status: ActionStatus.ABORTED >' was already rolled back
    at org.jboss.as.ejb3.tx.CMTTxInterceptor.endTransaction(CMTTxInterceptor.java:100) [wildfly-ejb3-8.2.1.Final.jar:8.2.1.Final]
    ... 111 more

12:17:00,110 WARN  [mobi.chouette.scheduler.MainCommand] (EE-ManagedExecutorService-default-Thread-2) execption bypassed javax.ejb.EJBTransactionRolledbackException: Transaction rolled back
12:17:00,156 INFO  [mobi.chouette.scheduler.Scheduler] (EE-ManagedExecutorService-default-Thread-2) task done : null -> Task(job=JobService(job=Job(id=122, referential=nri, action=exporter, type=neptune, filename=null, created=2016-02-25 09:51:24.561, started=Thu Feb 25 09:51:24 CET 2016, updated=Thu Feb 25 09:51:24 CET 2016, status=STARTED, links=[Link(type=application/json, rel=parameters), Link(type=application/json, rel=action_params), Link(type=application/json, rel=cancel), Link(type=application/json, rel=action_report)])))
metienne commented 8 years ago

corrected on tag and version 3.2.1