Bug 777887 (SOA-415)

Summary: bpm_orchestration2 generates "org.jboss.soa.esb.services.jbpm.cmd.CallbackCommand] Token id" mismatch warnings - on single and multi-cpu systems
Product: [JBoss] JBoss Enterprise SOA Platform 4 Reporter: Len DiMaggio <ldimaggi>
Component: ExamplesAssignee: Kevin Conner <kevin.conner>
Status: CLOSED WORKSFORME QA Contact:
Severity: medium Docs Contact:
Priority: medium    
Version: 4.2 CR3   
Target Milestone: ---   
Target Release: 4.2 CP01   
Hardware: Unspecified   
OS: Unspecified   
URL: http://jira.jboss.org/jira/browse/SOA-415
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
/opt/RC3/soa-4.2.0.CR3.zip /opt/RC3/standalone-soa-4.2.0.CR3.zip
Last Closed: 2008-03-24 14:12:23 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:
Attachments:
Description Flags
server.log
none
production_server.log.gz
none
standalone_server.log.gz none

Description Len DiMaggio 2008-02-11 16:42:14 UTC 
Date of First Response: 2008-02-13 13:20:48
project_key: SOA

Seeing this with the bpm_orchestration2 quickstart in CR3 - any database

2008-02-11 11:00:56,744 WARN  [org.jboss.soa.esb.services.jbpm.cmd.CallbackCommand] Token id 1 from process instance 1 is no longer on expected node, expected 9 but discovered 10

The system in question is:

[ldimaggi@ldimaggi bpm_orchestration2]$ uname -a
Linux ldimaggi.csb 2.6.18-53.1.6.el5 #1 SMP Wed Jan 16 03:56:43 EST 2008 i686 i686 i386 GNU/Linux
[ldimaggi@ldimaggi bpm_orchestration2]$ cat /proc/cpuinfo 
processor       : 0
vendor_id       : GenuineIntel
cpu family      : 6
model           : 13
model name      : Intel(R) Pentium(R) M processor 1.70GHz
stepping        : 6
cpu MHz         : 600.000
cache size      : 2048 KB
fdiv_bug        : no
hlt_bug         : no
f00f_bug        : no
coma_bug        : no
fpu             : yes
fpu_exception   : yes
cpuid level     : 2
wp              : yes
flags           : fpu vme de pse tsc msr mce cx8 mtrr pge mca cmov pat clflush dts acpi mmx fxsr sse sse2 ss tm pbe up est tm2
bogomips        : 1199.41
Comment 1 Len DiMaggio 2008-02-11 16:42:40 UTC 
Server (enbedded/production) log
Comment 2 Len DiMaggio 2008-02-11 16:42:40 UTC 
Attachment: Added: server.log
Comment 3 Aleksandar Kostadinov 2008-02-13 18:20:48 UTC 
I tried this one 100 times with a local mysql instance to avoid network delays and it didn't occur. I tried on 2 CPU dual core AMD system.

I see on DEBUG level:
log/server.log:2008-02-13 11:23:34,701 DEBUG [org.jboss.soa.esb.services.jbpm.cmd.CallbackCommand] Expected nodeId=5, tokenId=414, processNodeVersion=0
log/server.log:2008-02-13 11:23:34,707 DEBUG [org.jboss.soa.esb.services.jbpm.cmd.CallbackCommand] ProcessNodeVersion=0
log/server.log:2008-02-13 11:23:34,861 DEBUG [org.jboss.soa.esb.services.jbpm.cmd.CommandExecutor] org.jboss.soa.esb.services.jbpm.cmd.CallbackCommand@1417a7
log/server.log:2008-02-13 11:23:34,861 DEBUG [org.jbpm.command.impl.CommandServiceImpl] executing org.jboss.soa.esb.services.jbpm.cmd.CallbackCommand@1417a7
log/server.log:2008-02-13 11:23:34,861 DEBUG [org.jboss.soa.esb.services.jbpm.cmd.CallbackCommand] Expected nodeId=9, tokenId=413, processNodeVersion=0
log/server.log:2008-02-13 11:23:34,867 DEBUG [org.jboss.soa.esb.services.jbpm.cmd.CallbackCommand] ProcessNodeVersion=0

But it seems expected. No messages on WARN level.
Comment 4 Aleksandar Kostadinov 2008-02-13 18:23:31 UTC 
Link: Added: This issue is related to SOA-417
Comment 5 Aleksandar Kostadinov 2008-02-13 18:23:48 UTC 
Link: Added: This issue is related to SOA-410
Comment 6 Aleksandar Kostadinov 2008-02-13 18:24:05 UTC 
Link: Added: This issue is related to SOA-409
Comment 7 Aleksandar Kostadinov 2008-02-14 14:55:27 UTC 
All tier1 databases tested to not produce any errors in 100 runs. So probably it's hsqldb that doesn't support transactions. Marking as passed in testify.
Comment 8 Kevin Conner 2008-03-18 14:04:48 UTC 
Link: Removed: This issue is related to SOA-409
Comment 9 Len DiMaggio 2008-03-24 14:11:07 UTC 
This bug is not reproducible with the GA bits - on either the standlaone/default or embedded/production servers - even on the same physical machine where it was originally seen.
Comment 10 Len DiMaggio 2008-03-24 14:11:07 UTC 
Attachment: Added: production_server.log.gz
Attachment: Added: standalone_server.log.gz
Comment 11 Len DiMaggio 2008-03-24 14:12:23 UTC 
This bug is not reproducible with the GA bits - on either the standlaone/default or embedded/production servers - even on the same physical machine where it was originally seen.
Comment 12 Jarkko Lietolahti 2008-08-12 09:18:14 UTC 
We can reproduce this on 4 different machines (using either mysql or hysqldb). 
Happens with 4.3.GA and 4.4.GA, SVN head from 9.8.2008.

12:15:01,919 WARN  [CallbackCommand] Token id 24 from process instance 14 is no longer on expected node, expected 124 but discovered 12

Happens almost (9 of 10) every time on first execution.
Comment 13 nwallace 2008-09-26 06:04:30 UTC 
Link: Added: This issue is related to SOA-869