tilln / jmeter-iso8583

ISO8583 Plugin for JMeter
MIT License
62 stars 32 forks source link

Getting "[receiving] : In 1210 *Remaining ISO Message" but no Response Data in JMeter View Results Tree Listener. #55

Closed usamaibrahim005 closed 2 years ago

usamaibrahim005 commented 2 years ago

I have an ISO 8583 v1993 Message with the MTI 1200 Configured in JMeter using ISO 8583 Plugin. JMeter logging information is enhanced to see ISO Message related information in more detail. When I send the ISO Message the message goes out and I see "[receive]: In 1210 Remaining ISO Message". SS Attached below for reference.
That does this "[receive]: In 1210
Remaining ISO Message". If it means that I am receiving the response for the sent ISO Message Request, then I should be able to see the Response in View Result Tree Listener but this not the case. I am not getting anything in Response Data. Please guide if otherwise. I have been suggested to used MTI Mapping. Can you guide if "0123456789 0123456789 0022446789" will be correct to use for the response starting with 1210. Please guide in case of other suggestions. Thank You.

tilln commented 2 years ago

Your MTI Mapping should be correct.

[receive]: In 1210 *Remaining ISO Message"

This means that your channel successfully received a response with MTI 1210 and fields 11 and 41. Those 3 fields are by default used to map the response to a request. In your screenshots those don't seem to match (unless they are from different messages?), so you should be getting a timeout (which is not visible from logs or results viewer).

So you may want to double check that the request and response contain the same values for DE11 and DE41 or otherwise adjust the Mux's MTI Key configuration to use fields that do match.

usamaibrahim005 commented 2 years ago

Can you guide in using MTI Mapping or Key Configuration for this scenario. Is "0123456789 0123456789 0022446789" correct MTI Mapping for this scenario?

tilln commented 2 years ago

As mentioned above, yes, your MTI Mapping should be correct.

Key Configuration depends on your message contents, i.e. what fields are identical in request and response.

So you may want to double check that the request and response contain the same values for DE11 and DE41.

usamaibrahim005 commented 2 years ago

Okay Thank You I think I have got the understanding. I will apply MTI Mapping and Key Configurations today and if it works will close this discussion. Thank You for your support.

usamaibrahim005 commented 2 years ago

MTI (field 0) and field 11 and 41 needs to be same in the request as well response. After making it sure, I was able to receive the request successfully. Thank You for the support. Closing this issue.

arvind003github commented 1 year ago

Hi @tilln i am testing iSO 8583 packet but i am not able to receive the response i have doubts like MUX key is defined by programmer or its not dependent on

kindly Guide me on this ersion: GnuPG v1.4.9 (Darwin)

iQEcBAEBAgAGBQJMolHDAAoJEOQyeO71nYtFv74H/3OgehDGEy1VXp2U3/GcAobg HH2eZjPUz53r38ARPiU3pzm9LwDa3WZgJJaa/b9VrJwKvbPwe9+0kY3gScDE1skT ladHt+KHHmGQArEutkzHlpZa73RbroFEIa1qmN6MaDEHGoxZqDh0Sv2cpvOaVYGO St8ZaddLBPC17bSjAPWo9sWbvL7FgPFOHhnPmbeux8SLtnfWxXWsgo5hLBanKmO1 1z+I/w/6DL6ZYZU6bAJUk+eyVVImJqw0x3IEElI07Nh9MC6BA4iJ77ejobj8HI2r q9ulRPEqH9NR79619lNKVUkE206dVlXo7xHmJS1QZy5v/GT66xBxyDVfTduPFXk= =oP+v -----END PGP SIGNATURE-----

2022-11-09 09:56:48,525 DEBUG n.c.b.j.i.ISO8583Config: 'ISO8583 Connection Configuration' setting up QBeans jmeter-581ec9e3 2022-11-09 09:56:48,548 DEBUG n.c.b.j.i.ISO8583Config: Deploying 6022jmeter-581ec9e3-sendjmeter-581ec9e3-receivejmeter-581ec9e3.readyALL 2022-11-09 09:56:48,598 INFO n.c.b.j.i.ISO8583Config: Waiting 60 seconds for incoming client connection 2022-11-09 09:56:48,598 DEBUG n.c.b.j.i.Q2: (jmeter-581ec9e3-server.server) [iso-server] listening on port 6022 2022-11-09 09:56:49,602 INFO n.c.b.j.i.ISO8583Config: Waiting 58 seconds for incoming client connection 2022-11-09 09:56:50,610 INFO n.c.b.j.i.ISO8583Config: Waiting 57 seconds for incoming client connection 2022-11-09 09:56:51,613 INFO n.c.b.j.i.ISO8583Config: Waiting 56 seconds for incoming client connection 2022-11-09 09:56:52,629 INFO n.c.b.j.i.ISO8583Config: Waiting 55 seconds for incoming client connection 2022-11-09 09:56:53,635 INFO n.c.b.j.i.ISO8583Config: Waiting 54 seconds for incoming client connection 2022-11-09 09:56:54,637 INFO n.c.b.j.i.ISO8583Config: Waiting 53 seconds for incoming client connection 2022-11-09 09:56:55,648 INFO n.c.b.j.i.ISO8583Config: Waiting 52 seconds for incoming client connection 2022-11-09 09:56:56,657 DEBUG n.c.b.j.i.ISO8583Config: Deploying jmeter-581ec9e3-receivejmeter-581ec9e3-sendjmeter-581ec9e3-unhandledjmeter-581ec9e3.ready 2022-11-09 09:56:56,675 INFO o.a.j.g.u.JMeterMenuBar: setRunning(true, local) 2022-11-09 09:56:56,828 INFO o.a.j.e.StandardJMeterEngine: Starting ThreadGroup: 1 : Thread Group 2022-11-09 09:56:56,828 INFO o.a.j.e.StandardJMeterEngine: Starting 1 threads for group Thread Group. 2022-11-09 09:56:56,829 INFO o.a.j.e.StandardJMeterEngine: Thread will continue on error 2022-11-09 09:56:56,829 INFO o.a.j.t.ThreadGroup: Starting thread group... number=1 threads=1 ramp-up=10 delayedStart=false 2022-11-09 09:56:56,829 DEBUG o.a.j.t.ThreadGroup: Computed delayForNextThreadInMillis:0 for thread:37 2022-11-09 09:56:56,839 INFO o.a.j.t.ThreadGroup: Started thread group number 1 2022-11-09 09:56:56,839 INFO o.a.j.e.StandardJMeterEngine: All thread groups have been started 2022-11-09 09:56:56,840 DEBUG o.a.j.t.TestCompiler: Subtracting node, stack size = 2 2022-11-09 09:56:56,841 DEBUG o.a.j.t.TestCompiler: Subtracting node, stack size = 2 2022-11-09 09:56:56,842 DEBUG o.a.j.t.TestCompiler: adding controller: org.apache.jmeter.threads.ThreadGroup@38ef2d56 to sampler config 2022-11-09 09:56:56,843 DEBUG o.a.j.t.TestCompiler: Subtracting node, stack size = 2 2022-11-09 09:56:56,843 DEBUG o.a.j.t.TestCompiler: Subtracting node, stack size = 1 2022-11-09 09:56:56,843 INFO o.a.j.t.JMeterThread: Thread started: Thread Group 1-1 2022-11-09 09:56:56,848 DEBUG o.a.j.c.GenericController: Calling next on: class org.apache.jmeter.control.GenericController 2022-11-09 09:56:56,848 DEBUG o.a.j.t.TestBeanHelper: Preparing class nz.co.breakpoint.jmeter.iso8583.ISO8583Sampler 2022-11-09 09:56:56,849 DEBUG o.a.j.t.TestBeanHelper: Ignoring property 'request' in nz.co.breakpoint.jmeter.iso8583.ISO8583Sampler 2022-11-09 09:56:56,849 DEBUG o.a.j.t.TestBeanHelper: Ignoring property 'response' in nz.co.breakpoint.jmeter.iso8583.ISO8583Sampler 2022-11-09 09:56:56,849 DEBUG o.a.j.t.TestBeanHelper: Ignoring property 'runningVersion' in nz.co.breakpoint.jmeter.iso8583.ISO8583Sampler 2022-11-09 09:56:56,849 DEBUG o.a.j.t.TestBeanHelper: Setting configKey= 2022-11-09 09:56:56,850 DEBUG o.a.j.t.TestBeanHelper: Setting header= 2022-11-09 09:56:56,850 DEBUG o.a.j.t.TestBeanHelper: Setting trailer= 2022-11-09 09:56:56,850 DEBUG o.a.j.t.TestBeanHelper: Setting fields=[DE0=0800, DE7=1108064450, DE11=000715, DE37=231206000715, DE63=8000000002, DE70=071] 2022-11-09 09:56:56,850 DEBUG o.a.j.t.TestBeanHelper: Setting timeout=60000 2022-11-09 09:56:56,851 DEBUG o.a.j.t.TestBeanHelper: Setting responseCodeField=39 2022-11-09 09:56:56,851 DEBUG o.a.j.t.TestBeanHelper: Setting successResponseCode=00 2022-11-09 09:56:56,851 DEBUG n.c.b.j.i.ISO8583Sampler: Applying config 'ISO8583 Connection Configuration' 2022-11-09 09:56:56,852 DEBUG n.c.b.j.i.ISO8583Config: Creating packager from 'C:/Users/BUDDY/OneDrive/Desktop/apache-jmeter-5.4.3/apache-jmeter-5.4.3/lib/ext/iso87binary.xml' 2022-11-09 09:56:56,898 DEBUG o.a.j.t.TestBeanHelper: Preparing class nz.co.breakpoint.jmeter.iso8583.ISO8583Sampler 2022-11-09 09:56:56,899 DEBUG o.a.j.t.TestBeanHelper: Ignoring property 'request' in nz.co.breakpoint.jmeter.iso8583.ISO8583Sampler 2022-11-09 09:56:56,899 DEBUG o.a.j.t.TestBeanHelper: Ignoring property 'response' in nz.co.breakpoint.jmeter.iso8583.ISO8583Sampler 2022-11-09 09:56:56,899 DEBUG o.a.j.t.TestBeanHelper: Ignoring property 'runningVersion' in nz.co.breakpoint.jmeter.iso8583.ISO8583Sampler 2022-11-09 09:56:56,899 DEBUG o.a.j.t.TestBeanHelper: Setting configKey= 2022-11-09 09:56:56,899 DEBUG o.a.j.t.TestBeanHelper: Setting header= 2022-11-09 09:56:56,899 DEBUG o.a.j.t.TestBeanHelper: Setting trailer= 2022-11-09 09:56:56,900 DEBUG o.a.j.t.TestBeanHelper: Setting fields=[DE0=0800, DE7=1108064450, DE11=000715, DE37=231206000715, DE63=8000000002, DE70=071] 2022-11-09 09:56:56,900 DEBUG o.a.j.t.TestBeanHelper: Setting timeout=60000 2022-11-09 09:56:56,900 DEBUG o.a.j.t.TestBeanHelper: Setting responseCodeField=39 2022-11-09 09:56:56,900 DEBUG o.a.j.t.TestBeanHelper: Setting successResponseCode=00 2022-11-09 09:56:56,905 DEBUG n.c.b.j.i.ISO8583Sampler: sampleStart 2022-11-09 09:56:56,920 DEBUG n.c.b.j.i.Q2: (channel/IP:48258) [send] Out: 0800 000715 2022-11-09 09:57:02,858 DEBUG o.a.j.g.GuiPackage: Updating current node View Results Tree 2022-11-09 09:57:02,859 DEBUG o.a.j.g.GuiPackage: Gui retrieved = org.apache.jmeter.visualizers.ViewResultsFullVisualizer[View Results Tree,0,0,1141x738,layout=java.awt.BorderLayout,alignmentX=0.0,alignmentY=0.0,border=javax.swing.border.EmptyBorder@10fe4b,flags=9,maximumSize=,minimumSize=,preferredSize=] 2022-11-09 09:57:02,859 DEBUG o.a.j.g.AbstractJMeterGuiComponent: setting element to enabled: true 2022-11-09 09:57:02,860 DEBUG o.a.j.g.GuiPackage: Gui retrieved = org.apache.jmeter.visualizers.ViewResultsFullVisualizer[View Results Tree,0,0,1141x738,layout=java.awt.BorderLayout,alignmentX=0.0,alignmentY=0.0,border=javax.swing.border.EmptyBorder@10fe4b,flags=9,maximumSize=,minimumSize=,preferredSize=] 2022-11-09 09:57:56,922 DEBUG n.c.b.j.i.ISO8583Sampler: sampleEnd 2022-11-09 09:57:56,925 DEBUG n.c.b.j.i.ISO8583Sampler: Packed request '0800822000000800000204000000000000001108064450000715f2f3f1f2f0f6f0f0f0f7f1f50580000000020071' 2022-11-09 09:57:56,927 DEBUG o.a.j.c.GenericController: Calling next on: class org.apache.jmeter.control.GenericController 2022-11-09 09:57:56,927 INFO o.a.j.t.JMeterThread: Thread is done: Thread Group 1-1 2022-11-09 09:57:56,927 INFO o.a.j.t.JMeterThread: Thread finished: Thread Group 1-1 2022-11-09 09:57:56,927 DEBUG o.a.j.t.ThreadGroup: Ending thread Thread Group 1-1 2022-11-09 09:57:56,928 INFO o.a.j.e.StandardJMeterEngine: Notifying test listeners of end of test 2022-11-09 09:57:56,928 DEBUG n.c.b.j.i.ISO8583Config: Shutting down QBeans 2022-11-09 09:57:56,928 DEBUG n.c.b.j.i.ISO8583Config: Undeploying jmeter-581ec9e3-mux 2022-11-09 09:57:56,929 DEBUG n.c.b.j.i.ISO8583Config: Undeploying jmeter-581ec9e3-server 2022-11-09 09:57:56,933 INFO n.c.b.j.i.Q2: (Q2.system) shutting down 2022-11-09 09:57:56,936 INFO n.c.b.j.i.Q2: (Q2.system) shutdown done 2022-11-09 09:57:56,937 INFO o.a.j.g.u.JMeterMenuBar: setRunning(false, local)

tilln commented 1 year ago

Hi @arvind003github

Please open a separate issue for this.

I doubt this is Mux key related as the channel is not logging an incoming response but only...

2022-11-09 09:56:56,920 DEBUG n.c.b.j.i.Q2: (channel/IP:48258) [send] Out: 0800 000715

You may have to change your Mux keys for 0800 messages if those don't contain DE41.

Also refer to the FAQ section.

arvind003github commented 1 year ago

@tilln i created a separate issue can you please help me on this