Note: This bug is displayed in read-only format because the product is no longer active in Red Hat Bugzilla.

Bug 899712 (JBPAPP6-139)

Summary: IllegalStateException deploying an app with EJB Timer
Product: [JBoss] JBoss Enterprise Application Platform 6 Reporter: Marek Schmidt <maschmid>
Component: EJBAssignee: Ondrej Chaloupka <ochaloup>
Status: CLOSED NEXTRELEASE QA Contact:
Severity: high Docs Contact:
Priority: high    
Version: 6.0.0CC: atangrin, maschmid
Target Milestone: ---   
Target Release: EAP 6.0.0   
Hardware: Unspecified   
OS: Unspecified   
URL: http://jira.jboss.org/jira/browse/JBPAPP6-139
Whiteboard: ejb timers
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
EAP6.0.0.DR10b, EAP6.0.0.DR11, AS7.1.0.CR1b
Last Closed: 2012-11-05 02:45:59 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Attachments:
Description Flags
weld-pastecode.war none

Description Marek Schmidt 2012-01-13 11:46:31 UTC
Steps to Reproduce: 0. start server
1. deploy weld-pastecode.war
2. undeploy weld-pastecode.war
3. restart server
4. deploy weld-pastecode.war
5. notice the error in the logs.
project_key: JBPAPP6

The following error shows in the logs while deploying the Weld Pastecode example on EAP6.0.0.DR10b. 

This problem seems to also be present in AS7.1.0.CR1b, but it appears fixed in the current AS7.1.0.Final-SNAPSHOT.

{noformat}
12:28:54,884 ERROR [org.jboss.as.ejb3] (pool-9-thread-2) JBAS014122: Error during retyring timeout for timer: [id=09625bae-93c5-401d-8114-204aaaa6e52d timedObjectId=weld-pastecode.weld-pastecode.CodeFragmentPrinterImpl auto-timer?:false persistent?:true timerService=org.jboss.as.ejb3.timerservice.TimerServiceImpl@30307ae3 initialExpiration=Fri Jan 13 12:26:57 CET 2012 intervalDuration(in milli sec)=60000 nextExpiration=Fri Jan 13 12:28:57 CET 2012 timerState=RETRY_TIMEOUT: java.lang.IllegalStateException
	at org.jboss.msc.value.InjectedValue.getValue(InjectedValue.java:47) [jboss-msc-1.0.1.GA-redhat-1.jar:1.0.1.GA-redhat-1]
	at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:68)
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final-redhat-1.jar:1.1.1.Final-redhat-1]
	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.0.CR1-redhat-1.jar:7.1.0.CR1-redhat-1]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final-redhat-1.jar:1.1.1.Final-redhat-1]
	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.0.CR1-redhat-1.jar:7.1.0.CR1-redhat-1]
	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final-redhat-1.jar:1.1.1.Final-redhat-1]
	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final-redhat-1.jar:1.1.1.Final-redhat-1]
	at org.jboss.as.ejb3.timerservice.TimedObjectInvokerImpl.callTimeout(TimedObjectInvokerImpl.java:101) [jboss-as-ejb3-7.1.0.CR1-redhat-1.jar:7.1.0.CR1-redhat-1]
	at org.jboss.as.ejb3.timerservice.TimedObjectInvokerImpl.callTimeout(TimedObjectInvokerImpl.java:111) [jboss-as-ejb3-7.1.0.CR1-redhat-1.jar:7.1.0.CR1-redhat-1]
	at org.jboss.as.ejb3.timerservice.task.TimerTask.callTimeout(TimerTask.java:148) [jboss-as-ejb3-7.1.0.CR1-redhat-1.jar:7.1.0.CR1-redhat-1]
	at org.jboss.as.ejb3.timerservice.task.TimerTask.run(TimerTask.java:126) [jboss-as-ejb3-7.1.0.CR1-redhat-1.jar:7.1.0.CR1-redhat-1]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) [:1.6.0_24]
	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) [:1.6.0_24]
	at java.util.concurrent.FutureTask.run(FutureTask.java:138) [:1.6.0_24]
	at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_24]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_24]
	at java.lang.Thread.run(Thread.java:662) [:1.6.0_24]
	at org.jboss.threads.JBossThread.run(JBossThread.java:122) [jboss-threads-2.0.0.GA-redhat-1.jar:2.0.0.GA-redhat-1]
2:28:55,238 INFO  [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (MSC service thread 1-2) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
12:28:55,529 INFO  [org.hibernate.dialect.Dialect] (MSC service thread 1-2) HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
12:28:55,536 WARN  [org.hibernate.dialect.H2Dialect] (MSC service thread 1-2) HHH000431: Unable to determine H2 database version, certain features may not work
12:28:55,558 INFO  [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (MSC service thread 1-2) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory
12:28:55,565 INFO  [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (MSC service thread 1-2) HHH000397: Using ASTQueryTranslatorFactory
12:28:55,652 INFO  [org.hibernate.validator.util.Version] (MSC service thread 1-2) Hibernate Validator 4.2.0.Final-redhat-1
12:28:56,312 INFO  [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-2) HHH000227: Running hbm2ddl schema export
12:28:56,337 ERROR [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-2) HHH000389: Unsuccessful: drop sequence hibernate_sequence
12:28:56,337 ERROR [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-2) Sequence "HIBERNATE_SEQUENCE" not found; SQL statement:
drop sequence hibernate_sequence [90036-161]
12:28:56,341 INFO  [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-2) HHH000230: Schema export complete
12:28:56,393 INFO  [org.jboss.weld] (MSC service thread 1-1) Starting weld service
12:28:57,622 INFO  [javax.enterprise.resource.webcontainer.jsf.config] (MSC service thread 1-2) Initializing Mojarra 2.1.5 (SNAPSHOT 20111214) for context '/weld-pastecode'
12:28:58,059 INFO  [org.jboss.weld.examples.pastecode.session.PopulateDatabase] (MSC service thread 1-3) Successfully imported data!
12:29:01,280 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/weld-pastecode]] (MSC service thread 1-2) org.tuckey.web.filters.urlrewrite.UrlRewriteFilter INFO: loaded (conf ok)
12:29:01,309 INFO  [org.jboss.web] (MSC service thread 1-2) registering web context: /weld-pastecode
12:29:01,345 INFO  [org.jboss.as.server] (management-handler-threads - 1) JBAS018559: Deployed "weld-pastecode.war"
12:29:54,757 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-3) No fragments pasted in the last minute
12:29:54,964 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-4) No fragments pasted in the last minute
12:29:59,714 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-5) No fragments pasted in the last minute
12:30:54,695 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-6) No fragments pasted in the last minute
12:30:54,796 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-7) No fragments pasted in the last minute
12:30:57,549 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-8) No fragments pasted in the last minute
12:31:54,740 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-9) No fragments pasted in the last minute
12:31:54,791 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-10) No fragments pasted in the last minute
12:31:57,544 INFO  [org.jboss.weld.examples.pastecode.session.CodeFragmentPrinterImpl] (pool-9-thread-1) No fragments pasted in the last minute
{noformat}

Comment 1 Marek Schmidt 2012-01-13 11:47:00 UTC
Attachment: Added: weld-pastecode.war


Comment 2 Marek Schmidt 2012-01-13 12:59:50 UTC
Steps to Reproduce: Added: 0. start server
1. deploy weld-pastecode.war
2. undeploy weld-pastecode.war
3. restart server
4. deploy weld-pastecode.war
5. notice the error in the logs.


Comment 3 Marek Schmidt 2012-01-13 13:05:18 UTC
Link: Added: This issue relates to AS7-2529


Comment 4 Marek Schmidt 2012-01-13 13:05:19 UTC
Looks like AS7-2529

Comment 5 Rajesh Rajasekaran 2012-02-02 22:00:02 UTC
Marek, is this still an issue? 

Comment 6 Marek Schmidt 2012-02-03 12:42:25 UTC
Rajesh, yes, the error message can still be reproduced in EAP 6.0.0 DR 12. However, after further investigation it seems to me that despite the displayed error message, the timer works correctly (or, at least my original comment about multiple firing of the timer was wrong, as the example actually creates new persistent programmatic timer on every deployment, so this behaviour is correct)

Comment 7 Rajesh Rajasekaran 2012-02-03 14:27:17 UTC
Ondrej, can you investigate on this issue and create an upstream jira if required? 

Comment 8 Ondrej Chaloupka 2012-02-10 11:45:08 UTC
Labels: Added: ejb timers
Release Notes Text: Added: Re-testested. 
The problem does not occur more for EAP DR13 and in JBoss AS7 nightbuild. It was fixed meanwhile.


Comment 9 Marek Schmidt 2012-03-01 08:29:11 UTC
Verified on EAP6.0.0.ER2

Comment 10 Anne-Louise Tangring 2012-11-04 14:32:58 UTC
Release Notes Text: Removed: Re-testested. 
The problem does not occur more for EAP DR13 and in JBoss AS7 nightbuild. It was fixed meanwhile. 
Docs QE Status: Removed: NEW 


Comment 11 Anne-Louise Tangring 2012-11-05 02:45:15 UTC
Fixing Affects and Fix in Version