Scripts not running with JMeter 5.2.1 with Java 1.8.0_221-b11 that work with JMeter 5.2.1 with Java 1.8.0_181-b13

Previous Topic Next Topic
 
classic Classic list List threaded Threaded
6 messages Options
Reply | Threaded
Open this post in threaded view
|

Scripts not running with JMeter 5.2.1 with Java 1.8.0_221-b11 that work with JMeter 5.2.1 with Java 1.8.0_181-b13

ljmwaugh
This has driven us nuts.

We have a script that works fine in JMeter 5.2.1 with Java 1.8.0_181-b13.  It produces data in the tree view and summary view and runs clean.

We try the same script with JMeter 5.2.1 with Java 1.8.0_221-b11 and the tree view doesn’t display at all.  An error message about:

 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
java.lang.Throwable: Invalid call sequence
at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]

And the start time in the output from the file writer shows a start time of 0.

Here is the entire log:

2019-12-12 09:37:54,639 INFO o.a.j.e.StandardJMeterEngine: Running the test!
2019-12-12 09:37:54,639 INFO o.a.j.s.SampleEvent: List of sample_variables: []
2019-12-12 09:37:54,640 INFO o.a.j.g.u.JMeterMenuBar: setRunning(true, *local*)
2019-12-12 09:37:54,878 INFO o.a.j.e.StandardJMeterEngine: Starting ThreadGroup: 1 : Transaction Services
2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Starting 1 threads for group Transaction Services.
2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Thread will continue on error
2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Starting thread group... number=1 threads=1 ramp-up=0 delayedStart=false
2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Started thread group number 1
2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: All thread groups have been started
2019-12-12 09:37:54,880 INFO o.a.j.t.JMeterThread: Thread started: Transaction Services 1-1
2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/ibm/QuoteHub/Credentials.csv
2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
2019-12-12 09:37:54,881 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
2019-12-12 09:37:54,882 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
java.lang.Throwable: Invalid call sequence
at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
2019-12-12 09:37:54,887 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
java.lang.Throwable: Invalid call sequence
at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
2019-12-12 09:37:54,891 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
java.lang.Throwable: Invalid call sequence
at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread is done: Transaction Services 1-1
2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread finished: Transaction Services 1-1
2019-12-12 09:37:54,894 INFO o.a.j.e.StandardJMeterEngine: Notifying test listeners of end of test
2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
2019-12-12 09:37:54,895 INFO o.a.j.s.FileServer: Close: /Users/perftest/ibm/QuoteHub/Credentials.csv
2019-12-12 09:37:54,895 INFO o.a.j.g.u.JMeterMenuBar: setRunning(false, *local*)

Any ideas why?

Regards,

Lisa JM Waugh
Performance Test Engineer
Pricing@IBM
Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
E-mail: [hidden email]<mailto:[hidden email]>

3039 E Cornwallis Rd
Research Triangle Park, NC 27709-2195
United States

Reply | Threaded
Open this post in threaded view
|

Re: Scripts not running with JMeter 5.2.1 with Java 1.8.0_221-b11 that work with JMeter 5.2.1 with Java 1.8.0_181-b13

Felix Schumacher

Am 12.12.19 um 15:48 schrieb Lisa Waugh - [hidden email]:

> This has driven us nuts.
>
> We have a script that works fine in JMeter 5.2.1 with Java 1.8.0_181-b13.  It produces data in the tree view and summary view and runs clean.
>
> We try the same script with JMeter 5.2.1 with Java 1.8.0_221-b11 and the tree view doesn’t display at all.  An error message about:
>
>  ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> java.lang.Throwable: Invalid call sequence
> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
>
> And the start time in the output from the file writer shows a start time of 0.
>
> Here is the entire log:
>
> 2019-12-12 09:37:54,639 INFO o.a.j.e.StandardJMeterEngine: Running the test!
> 2019-12-12 09:37:54,639 INFO o.a.j.s.SampleEvent: List of sample_variables: []
> 2019-12-12 09:37:54,640 INFO o.a.j.g.u.JMeterMenuBar: setRunning(true, *local*)
> 2019-12-12 09:37:54,878 INFO o.a.j.e.StandardJMeterEngine: Starting ThreadGroup: 1 : Transaction Services
> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Starting 1 threads for group Transaction Services.
> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Thread will continue on error
> 2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Starting thread group... number=1 threads=1 ramp-up=0 delayedStart=false
> 2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Started thread group number 1
> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: All thread groups have been started
> 2019-12-12 09:37:54,880 INFO o.a.j.t.JMeterThread: Thread started: Transaction Services 1-1
> 2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/ibm/QuoteHub/Credentials.csv
> 2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
> 2019-12-12 09:37:54,881 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
> 2019-12-12 09:37:54,882 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> java.lang.Throwable: Invalid call sequence
> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> 2019-12-12 09:37:54,887 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> java.lang.Throwable: Invalid call sequence
> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> 2019-12-12 09:37:54,891 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> java.lang.Throwable: Invalid call sequence
> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> 2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread is done: Transaction Services 1-1
> 2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread finished: Transaction Services 1-1
> 2019-12-12 09:37:54,894 INFO o.a.j.e.StandardJMeterEngine: Notifying test listeners of end of test
> 2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
> 2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
> 2019-12-12 09:37:54,895 INFO o.a.j.s.FileServer: Close: /Users/perftest/ibm/QuoteHub/Credentials.csv
> 2019-12-12 09:37:54,895 INFO o.a.j.g.u.JMeterMenuBar: setRunning(false, *local*)
>
> Any ideas why?

Can you give a simple test plan, that we can use to reproduce the issue?

If I use a simple test plan with a JSR223 Sampler and one Debug Post
Processor with Java 8 U232 and get no such error. So we are probably
looking for a sampler that doesn't call setStartTime early enough.

(On the tomcat project we had a report of errors after changing minor
versions of Java 8 where a class had done too much overwriting over a
parent class (jsp tags), maybe we have something similar here)

Felix

>
> Regards,
>
> Lisa JM Waugh
> Performance Test Engineer
> Pricing@IBM
> Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
> E-mail: [hidden email]<mailto:[hidden email]>
>
> 3039 E Cornwallis Rd
> Research Triangle Park, NC 27709-2195
> United States
>

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: Scripts not running with JMeter 5.2.1 with Java 1.8.0_221-b11 that work with JMeter 5.2.1 with Java 1.8.0_181-b13

ljmwaugh
Hi,
I’m sorry this took me awhile to get figured out.  I think I have a small imx with the issue at this link:

https://www.dropbox.com/sh/mxzuyuwcdziii6c/AAD5e1nZHlJA4cWKlvie2mzCa?dl=0

Please let me know if you have any questions or issues.

Regards,

Lisa JM Waugh
Performance Test Engineer
Pricing@IBM
Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
E-mail: [hidden email]<mailto:[hidden email]>

3039 E Cornwallis Rd
Research Triangle Park, NC 27709-2195
United States

On Dec 25, 2019, at 6:14 AM, Felix Schumacher <[hidden email]<mailto:[hidden email]>> wrote:


Am 12.12.19 um 15:48 schrieb Lisa Waugh - [hidden email]<mailto:[hidden email]>:
This has driven us nuts.

We have a script that works fine in JMeter 5.2.1 with Java 1.8.0_181-b13.  It produces data in the tree view and summary view and runs clean.

We try the same script with JMeter 5.2.1 with Java 1.8.0_221-b11 and the tree view doesn’t display at all.  An error message about:

ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
java.lang.Throwable: Invalid call sequence
at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]

And the start time in the output from the file writer shows a start time of 0.

Here is the entire log:

2019-12-12 09:37:54,639 INFO o.a.j.e.StandardJMeterEngine: Running the test!
2019-12-12 09:37:54,639 INFO o.a.j.s.SampleEvent: List of sample_variables: []
2019-12-12 09:37:54,640 INFO o.a.j.g.u.JMeterMenuBar: setRunning(true, *local*)
2019-12-12 09:37:54,878 INFO o.a.j.e.StandardJMeterEngine: Starting ThreadGroup: 1 : Transaction Services
2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Starting 1 threads for group Transaction Services.
2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Thread will continue on error
2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Starting thread group... number=1 threads=1 ramp-up=0 delayedStart=false
2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Started thread group number 1
2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: All thread groups have been started
2019-12-12 09:37:54,880 INFO o.a.j.t.JMeterThread: Thread started: Transaction Services 1-1
2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/ibm/QuoteHub/Credentials.csv
2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
2019-12-12 09:37:54,881 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
2019-12-12 09:37:54,882 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
java.lang.Throwable: Invalid call sequence
at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
2019-12-12 09:37:54,887 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
java.lang.Throwable: Invalid call sequence
at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
2019-12-12 09:37:54,891 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
java.lang.Throwable: Invalid call sequence
at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread is done: Transaction Services 1-1
2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread finished: Transaction Services 1-1
2019-12-12 09:37:54,894 INFO o.a.j.e.StandardJMeterEngine: Notifying test listeners of end of test
2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
2019-12-12 09:37:54,895 INFO o.a.j.s.FileServer: Close: /Users/perftest/ibm/QuoteHub/Credentials.csv
2019-12-12 09:37:54,895 INFO o.a.j.g.u.JMeterMenuBar: setRunning(false, *local*)

Any ideas why?

Can you give a simple test plan, that we can use to reproduce the issue?

If I use a simple test plan with a JSR223 Sampler and one Debug Post
Processor with Java 8 U232 and get no such error. So we are probably
looking for a sampler that doesn't call setStartTime early enough.

(On the tomcat project we had a report of errors after changing minor
versions of Java 8 where a class had done too much overwriting over a
parent class (jsp tags), maybe we have something similar here)

Felix


Regards,

Lisa JM Waugh
Performance Test Engineer
Pricing@IBM
Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
E-mail: [hidden email]<mailto:[hidden email]><mailto:[hidden email]>

3039 E Cornwallis Rd
Research Triangle Park, NC 27709-2195
United States


---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]<mailto:[hidden email]>
For additional commands, e-mail: [hidden email]<mailto:[hidden email]>

Reply | Threaded
Open this post in threaded view
|

Re: Scripts not running with JMeter 5.2.1 with Java 1.8.0_221-b11 that work with JMeter 5.2.1 with Java 1.8.0_181-b13

Felix Schumacher

Am 22.01.20 um 22:10 schrieb Lisa Waugh - [hidden email]:
> Hi,
> I’m sorry this took me awhile to get figured out.  I think I have a small imx with the issue at this link:
>
> https://www.dropbox.com/sh/mxzuyuwcdziii6c/AAD5e1nZHlJA4cWKlvie2mzCa?dl=0
>
> Please let me know if you have any questions or issues.

OK, I can reproduce the issue using openjdk 1.8.0 Update 232. Will look
into it, but I am not unhappy if anyone else is faster than me :)

Felix

>
> Regards,
>
> Lisa JM Waugh
> Performance Test Engineer
> Pricing@IBM
> Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
> E-mail: [hidden email]<mailto:[hidden email]>
>
> 3039 E Cornwallis Rd
> Research Triangle Park, NC 27709-2195
> United States
>
> On Dec 25, 2019, at 6:14 AM, Felix Schumacher <[hidden email]<mailto:[hidden email]>> wrote:
>
>
> Am 12.12.19 um 15:48 schrieb Lisa Waugh - [hidden email]<mailto:[hidden email]>:
> This has driven us nuts.
>
> We have a script that works fine in JMeter 5.2.1 with Java 1.8.0_181-b13.  It produces data in the tree view and summary view and runs clean.
>
> We try the same script with JMeter 5.2.1 with Java 1.8.0_221-b11 and the tree view doesn’t display at all.  An error message about:
>
> ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> java.lang.Throwable: Invalid call sequence
> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
>
> And the start time in the output from the file writer shows a start time of 0.
>
> Here is the entire log:
>
> 2019-12-12 09:37:54,639 INFO o.a.j.e.StandardJMeterEngine: Running the test!
> 2019-12-12 09:37:54,639 INFO o.a.j.s.SampleEvent: List of sample_variables: []
> 2019-12-12 09:37:54,640 INFO o.a.j.g.u.JMeterMenuBar: setRunning(true, *local*)
> 2019-12-12 09:37:54,878 INFO o.a.j.e.StandardJMeterEngine: Starting ThreadGroup: 1 : Transaction Services
> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Starting 1 threads for group Transaction Services.
> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Thread will continue on error
> 2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Starting thread group... number=1 threads=1 ramp-up=0 delayedStart=false
> 2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Started thread group number 1
> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: All thread groups have been started
> 2019-12-12 09:37:54,880 INFO o.a.j.t.JMeterThread: Thread started: Transaction Services 1-1
> 2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/ibm/QuoteHub/Credentials.csv
> 2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
> 2019-12-12 09:37:54,881 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
> 2019-12-12 09:37:54,882 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> java.lang.Throwable: Invalid call sequence
> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> 2019-12-12 09:37:54,887 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> java.lang.Throwable: Invalid call sequence
> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> 2019-12-12 09:37:54,891 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> java.lang.Throwable: Invalid call sequence
> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> 2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread is done: Transaction Services 1-1
> 2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread finished: Transaction Services 1-1
> 2019-12-12 09:37:54,894 INFO o.a.j.e.StandardJMeterEngine: Notifying test listeners of end of test
> 2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
> 2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
> 2019-12-12 09:37:54,895 INFO o.a.j.s.FileServer: Close: /Users/perftest/ibm/QuoteHub/Credentials.csv
> 2019-12-12 09:37:54,895 INFO o.a.j.g.u.JMeterMenuBar: setRunning(false, *local*)
>
> Any ideas why?
>
> Can you give a simple test plan, that we can use to reproduce the issue?
>
> If I use a simple test plan with a JSR223 Sampler and one Debug Post
> Processor with Java 8 U232 and get no such error. So we are probably
> looking for a sampler that doesn't call setStartTime early enough.
>
> (On the tomcat project we had a report of errors after changing minor
> versions of Java 8 where a class had done too much overwriting over a
> parent class (jsp tags), maybe we have something similar here)
>
> Felix
>
>
> Regards,
>
> Lisa JM Waugh
> Performance Test Engineer
> Pricing@IBM
> Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
> E-mail: [hidden email]<mailto:[hidden email]><mailto:[hidden email]>
>
> 3039 E Cornwallis Rd
> Research Triangle Park, NC 27709-2195
> United States
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: [hidden email]<mailto:[hidden email]>
> For additional commands, e-mail: [hidden email]<mailto:[hidden email]>
>

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: Scripts not running with JMeter 5.2.1 with Java 1.8.0_221-b11 that work with JMeter 5.2.1 with Java 1.8.0_181-b13

Felix Schumacher

Am 24.01.20 um 18:37 schrieb Felix Schumacher:
> Am 22.01.20 um 22:10 schrieb Lisa Waugh - [hidden email]:
>> Hi,
>> I’m sorry this took me awhile to get figured out.  I think I have a small imx with the issue at this link:
>>
>> https://www.dropbox.com/sh/mxzuyuwcdziii6c/AAD5e1nZHlJA4cWKlvie2mzCa?dl=0
>>
>> Please let me know if you have any questions or issues.
> OK, I can reproduce the issue using openjdk 1.8.0 Update 232. Will look
> into it, but I am not unhappy if anyone else is faster than me :)

It seems, as there is some change in behaviour of parsing hostnames from
Update 212 to 222. The test plan uses hostname/path as the hostname.

That scheme worked for a long time, but it doesn't work in Lisa's plan.
If you transfer the path into the "HTTP Request Path" field, the error
will go away.

That leads to a few questions.

 * Should we warn more strongly about misuse of the hostname field?

 * Should we investigate into the originally reported problem of the
invalid call sequence?

Felix

>
> Felix
>
>> Regards,
>>
>> Lisa JM Waugh
>> Performance Test Engineer
>> Pricing@IBM
>> Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
>> E-mail: [hidden email]<mailto:[hidden email]>
>>
>> 3039 E Cornwallis Rd
>> Research Triangle Park, NC 27709-2195
>> United States
>>
>> On Dec 25, 2019, at 6:14 AM, Felix Schumacher <[hidden email]<mailto:[hidden email]>> wrote:
>>
>>
>> Am 12.12.19 um 15:48 schrieb Lisa Waugh - [hidden email]<mailto:[hidden email]>:
>> This has driven us nuts.
>>
>> We have a script that works fine in JMeter 5.2.1 with Java 1.8.0_181-b13.  It produces data in the tree view and summary view and runs clean.
>>
>> We try the same script with JMeter 5.2.1 with Java 1.8.0_221-b11 and the tree view doesn’t display at all.  An error message about:
>>
>> ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
>> java.lang.Throwable: Invalid call sequence
>> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
>>
>> And the start time in the output from the file writer shows a start time of 0.
>>
>> Here is the entire log:
>>
>> 2019-12-12 09:37:54,639 INFO o.a.j.e.StandardJMeterEngine: Running the test!
>> 2019-12-12 09:37:54,639 INFO o.a.j.s.SampleEvent: List of sample_variables: []
>> 2019-12-12 09:37:54,640 INFO o.a.j.g.u.JMeterMenuBar: setRunning(true, *local*)
>> 2019-12-12 09:37:54,878 INFO o.a.j.e.StandardJMeterEngine: Starting ThreadGroup: 1 : Transaction Services
>> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Starting 1 threads for group Transaction Services.
>> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Thread will continue on error
>> 2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Starting thread group... number=1 threads=1 ramp-up=0 delayedStart=false
>> 2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Started thread group number 1
>> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: All thread groups have been started
>> 2019-12-12 09:37:54,880 INFO o.a.j.t.JMeterThread: Thread started: Transaction Services 1-1
>> 2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/ibm/QuoteHub/Credentials.csv
>> 2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
>> 2019-12-12 09:37:54,881 INFO o.a.j.s.FileServer: Stored: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
>> 2019-12-12 09:37:54,882 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
>> java.lang.Throwable: Invalid call sequence
>> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
>> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
>> 2019-12-12 09:37:54,887 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
>> java.lang.Throwable: Invalid call sequence
>> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
>> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
>> 2019-12-12 09:37:54,891 ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
>> java.lang.Throwable: Invalid call sequence
>> at org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92) [ApacheJMeter_components.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490) [ApacheJMeter_core.jar:5.2.1]
>> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257) [ApacheJMeter_core.jar:5.2.1]
>> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
>> 2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread is done: Transaction Services 1-1
>> 2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread finished: Transaction Services 1-1
>> 2019-12-12 09:37:54,894 INFO o.a.j.e.StandardJMeterEngine: Notifying test listeners of end of test
>> 2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
>> 2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close: /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
>> 2019-12-12 09:37:54,895 INFO o.a.j.s.FileServer: Close: /Users/perftest/ibm/QuoteHub/Credentials.csv
>> 2019-12-12 09:37:54,895 INFO o.a.j.g.u.JMeterMenuBar: setRunning(false, *local*)
>>
>> Any ideas why?
>>
>> Can you give a simple test plan, that we can use to reproduce the issue?
>>
>> If I use a simple test plan with a JSR223 Sampler and one Debug Post
>> Processor with Java 8 U232 and get no such error. So we are probably
>> looking for a sampler that doesn't call setStartTime early enough.
>>
>> (On the tomcat project we had a report of errors after changing minor
>> versions of Java 8 where a class had done too much overwriting over a
>> parent class (jsp tags), maybe we have something similar here)
>>
>> Felix
>>
>>
>> Regards,
>>
>> Lisa JM Waugh
>> Performance Test Engineer
>> Pricing@IBM
>> Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
>> E-mail: [hidden email]<mailto:[hidden email]><mailto:[hidden email]>
>>
>> 3039 E Cornwallis Rd
>> Research Triangle Park, NC 27709-2195
>> United States
>>
>>
>> ---------------------------------------------------------------------
>> To unsubscribe, e-mail: [hidden email]<mailto:[hidden email]>
>> For additional commands, e-mail: [hidden email]<mailto:[hidden email]>
>>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: [hidden email]
> For additional commands, e-mail: [hidden email]
>

---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]

Reply | Threaded
Open this post in threaded view
|

Re: Scripts not running with JMeter 5.2.1 with Java 1.8.0_221-b11 that work with JMeter 5.2.1 with Java 1.8.0_181-b13

Mariusz W
Hi,
I can also reproduce this error, on
openjdk-1.8.0.191-1.b12.ojdkbuild.windows.x86_64 I don't have
this error: "setEndTime must be called after setStartTime". In openjdk
version "11.0.5" 2019-10-15 LTS  I see error but it is specific to "Debug
PostProcessor" though (it is reaction to the root cause - parsing
hostname). If "Debug PostProcessor" is disabled error disappear - and we
only see error described by Felix (parsing hostname):

java.net.MalformedURLException: Illegal character found in host: '/'

it is seen in "View Result Tree" listener - and it is true problem I think.

But what Lisa is trying to achieve (I think based on her plan from dropbox)
is to get url which is build from  "HTTP Request Defaults/Protocol" + "HTTP
Request Defaults/Server Name or IP" + "HTTP Request sampler/Path" (in
attached jmx plan from dropbox: https +
nothing.api.ibm.com/nothing/${environment}/so2o/rest/hub
<http://nothing.api.ibm.com/nothing/$%7Benvironment%7D/so2o/rest/hub> +
/auth/user => POST
https://nothing.api.ibm.com/nothing/test/so2o/rest/hub/auth/user)

In my test plans I use different approach:
I define schema + server in "user defined variables" and next use this
variabes/properties to build full url in "HTTP Request Sampler/Path" only
eg.
${server_schema}://${server_env}/nothing/${environment}/so2o/rest/hub/auth/user
- this way I can use properties from property file when plan is run from
command line because in "user defined variables" I use ${__P(...)}. Of
course another aproaches are possible but I don't use server/path pattern
in "HTTP Request Defaults/Server Name or IP"

This use of Path in sampler is described in docs:
https://jmeter.apache.org/usermanual/component_reference.html#HTTP_Request
Path: "As a special case, if the path starts with "http://" or "https://"
then this is used as the full URL. "

and here there is not info about path in server field in "HTTP Request
Defaults":
https://jmeter.apache.org/usermanual/component_reference.html#HTTP_Request_Defaults
Server: "Domain name or IP address of the web server. E.g. www.example.com.
[Do not include the http:// prefix."

IMHO: Lisa's plan should be changed  because it depend on undocumented
feature. Regarding error "setEndTime must be called after setStartTime" it
was not main problem - script was not runnig beacuse sampler is not even
constructed and it's hard to require proper behaviour from preprocessors in
this situation. "Debug PostProcessor" is working (it log
properties/variables in "View Result Tree" though plan is not starting and
jmeter.log has this "setEndTime must be called after setStartTime" line in
it.

Regards,
Mariusz

On Sat, 25 Jan 2020 at 15:07, Felix Schumacher <
[hidden email]> wrote:

>
> Am 24.01.20 um 18:37 schrieb Felix Schumacher:
> > Am 22.01.20 um 22:10 schrieb Lisa Waugh - [hidden email]:
> >> Hi,
> >> I’m sorry this took me awhile to get figured out.  I think I have a
> small imx with the issue at this link:
> >>
> >>
> https://www.dropbox.com/sh/mxzuyuwcdziii6c/AAD5e1nZHlJA4cWKlvie2mzCa?dl=0
> >>
> >> Please let me know if you have any questions or issues.
> > OK, I can reproduce the issue using openjdk 1.8.0 Update 232. Will look
> > into it, but I am not unhappy if anyone else is faster than me :)
>
> It seems, as there is some change in behaviour of parsing hostnames from
> Update 212 to 222. The test plan uses hostname/path as the hostname.
>
> That scheme worked for a long time, but it doesn't work in Lisa's plan.
> If you transfer the path into the "HTTP Request Path" field, the error
> will go away.
>
> That leads to a few questions.
>
>  * Should we warn more strongly about misuse of the hostname field?
>
>  * Should we investigate into the originally reported problem of the
> invalid call sequence?
>
> Felix
>
> >
> > Felix
> >
> >> Regards,
> >>
> >> Lisa JM Waugh
> >> Performance Test Engineer
> >> Pricing@IBM
> >> Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
> >> E-mail: [hidden email]<mailto:[hidden email]>
> >>
> >> 3039 E Cornwallis Rd
> >> Research Triangle Park, NC 27709-2195
> >> United States
> >>
> >> On Dec 25, 2019, at 6:14 AM, Felix Schumacher <
> [hidden email]<mailto:[hidden email]>>
> wrote:
> >>
> >>
> >> Am 12.12.19 um 15:48 schrieb Lisa Waugh - [hidden email]<mailto:
> [hidden email]>:
> >> This has driven us nuts.
> >>
> >> We have a script that works fine in JMeter 5.2.1 with Java
> 1.8.0_181-b13.  It produces data in the tree view and summary view and runs
> clean.
> >>
> >> We try the same script with JMeter 5.2.1 with Java 1.8.0_221-b11 and
> the tree view doesn’t display at all.  An error message about:
> >>
> >> ERROR o.a.j.s.SampleResult: setEndTime must be called after setStartTime
> >> java.lang.Throwable: Invalid call sequence
> >> at
> org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106)
> [ApacheJMeter_core.jar:5.2.1]
> >>
> >> And the start time in the output from the file writer shows a start
> time of 0.
> >>
> >> Here is the entire log:
> >>
> >> 2019-12-12 09:37:54,639 INFO o.a.j.e.StandardJMeterEngine: Running the
> test!
> >> 2019-12-12 09:37:54,639 INFO o.a.j.s.SampleEvent: List of
> sample_variables: []
> >> 2019-12-12 09:37:54,640 INFO o.a.j.g.u.JMeterMenuBar: setRunning(true,
> *local*)
> >> 2019-12-12 09:37:54,878 INFO o.a.j.e.StandardJMeterEngine: Starting
> ThreadGroup: 1 : Transaction Services
> >> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Starting 1
> threads for group Transaction Services.
> >> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: Thread will
> continue on error
> >> 2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Starting thread
> group... number=1 threads=1 ramp-up=0 delayedStart=false
> >> 2019-12-12 09:37:54,879 INFO o.a.j.t.ThreadGroup: Started thread group
> number 1
> >> 2019-12-12 09:37:54,879 INFO o.a.j.e.StandardJMeterEngine: All thread
> groups have been started
> >> 2019-12-12 09:37:54,880 INFO o.a.j.t.JMeterThread: Thread started:
> Transaction Services 1-1
> >> 2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored:
> /Users/perftest/ibm/QuoteHub/Credentials.csv
> >> 2019-12-12 09:37:54,880 INFO o.a.j.s.FileServer: Stored:
> /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
> >> 2019-12-12 09:37:54,881 INFO o.a.j.s.FileServer: Stored:
> /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
> >> 2019-12-12 09:37:54,882 ERROR o.a.j.s.SampleResult: setEndTime must be
> called after setStartTime
> >> java.lang.Throwable: Invalid call sequence
> >> at
> org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92)
> [ApacheJMeter_components.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490)
> [ApacheJMeter_core.jar:5.2.1]
> >> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257)
> [ApacheJMeter_core.jar:5.2.1]
> >> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> >> 2019-12-12 09:37:54,887 ERROR o.a.j.s.SampleResult: setEndTime must be
> called after setStartTime
> >> java.lang.Throwable: Invalid call sequence
> >> at
> org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92)
> [ApacheJMeter_components.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490)
> [ApacheJMeter_core.jar:5.2.1]
> >> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257)
> [ApacheJMeter_core.jar:5.2.1]
> >> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> >> 2019-12-12 09:37:54,891 ERROR o.a.j.s.SampleResult: setEndTime must be
> called after setStartTime
> >> java.lang.Throwable: Invalid call sequence
> >> at
> org.apache.jmeter.samplers.SampleResult.setEndTime(SampleResult.java:1106)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:654)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.samplers.SampleResult.addSubResult(SampleResult.java:623)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.extractor.DebugPostProcessor.process(DebugPostProcessor.java:92)
> [ApacheJMeter_components.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.runPostProcessors(JMeterThread.java:931)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:569)
> [ApacheJMeter_core.jar:5.2.1]
> >> at
> org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:490)
> [ApacheJMeter_core.jar:5.2.1]
> >> at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:257)
> [ApacheJMeter_core.jar:5.2.1]
> >> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_221]
> >> 2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread is done:
> Transaction Services 1-1
> >> 2019-12-12 09:37:54,894 INFO o.a.j.t.JMeterThread: Thread finished:
> Transaction Services 1-1
> >> 2019-12-12 09:37:54,894 INFO o.a.j.e.StandardJMeterEngine: Notifying
> test listeners of end of test
> >> 2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close:
> /Users/perftest/github/quote-hub-test-automation/Jmeter/Json.csv
> >> 2019-12-12 09:37:54,894 INFO o.a.j.s.FileServer: Close:
> /Users/perftest/github/quote-hub-test-automation/Jmeter/Non_Json.csv
> >> 2019-12-12 09:37:54,895 INFO o.a.j.s.FileServer: Close:
> /Users/perftest/ibm/QuoteHub/Credentials.csv
> >> 2019-12-12 09:37:54,895 INFO o.a.j.g.u.JMeterMenuBar: setRunning(false,
> *local*)
> >>
> >> Any ideas why?
> >>
> >> Can you give a simple test plan, that we can use to reproduce the issue?
> >>
> >> If I use a simple test plan with a JSR223 Sampler and one Debug Post
> >> Processor with Java 8 U232 and get no such error. So we are probably
> >> looking for a sampler that doesn't call setStartTime early enough.
> >>
> >> (On the tomcat project we had a report of errors after changing minor
> >> versions of Java 8 where a class had done too much overwriting over a
> >> parent class (jsp tags), maybe we have something similar here)
> >>
> >> Felix
> >>
> >>
> >> Regards,
> >>
> >> Lisa JM Waugh
> >> Performance Test Engineer
> >> Pricing@IBM
> >> Phone: 1-720-342-5373 | Mobile: 1-704-907-7008
> >> E-mail: [hidden email]<mailto:[hidden email]><mailto:
> [hidden email]>
> >>
> >> 3039 E Cornwallis Rd
> >> Research Triangle Park, NC 27709-2195
> >> United States
> >>
> >>
> >> ---------------------------------------------------------------------
> >> To unsubscribe, e-mail: [hidden email]<mailto:
> [hidden email]>
> >> For additional commands, e-mail: [hidden email]<mailto:
> [hidden email]>
> >>
> > ---------------------------------------------------------------------
> > To unsubscribe, e-mail: [hidden email]
> > For additional commands, e-mail: [hidden email]
> >
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: [hidden email]
> For additional commands, e-mail: [hidden email]
>
>