See
<https://builds.apache.org/job/JMeter-trunk/6097/display/redirect?page=changes>
Changes:
[sebb] Increase allowable delay to allow for slower Jenkins node
[mchassagneux] Don't cumul intial delay + ramp-up delay for a new thread create
in the fly.
Bugzilla Id: 60530
------------------------------------------
[...truncated 144.73 KB...]
[echo] Bug52310 output files compared OK
batchtest:
[echo] Starting Bug52968 with file Bug52968.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/Bug52968.jmx
[jmeter] Starting the test @ Mon Apr 10 14:57:45 UTC 2017 (1491836265118)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 30 in 00:00:13 = 2.4/s Avg: 222 Min: 101 Max:
350 Err: 9 (30.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:57:59 UTC 2017 (1491836279195)
[jmeter] ... end of run
[echo] Bug52968 output files compared OK
batchtest:
[echo] Starting Bug50898 with file Bug50898.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/Bug50898.jmx
[jmeter] Starting the test @ Mon Apr 10 14:58:04 UTC 2017 (1491836284153)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 1 in 00:00:01 = 1.8/s Avg: 242 Min: 242 Max:
242 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 1 in 00:00:00 = 3.9/s Avg: 249 Min: 249 Max:
249 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 2 in 00:00:01 = 2.5/s Avg: 245 Min: 242 Max:
249 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:58:06 UTC 2017 (1491836286028)
[jmeter] ... end of run
[echo] Bug50898 output files compared OK
batchtest:
[echo] Starting Bug56243 with file Bug56243.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/Bug56243.jmx
[jmeter] Starting the test @ Mon Apr 10 14:58:11 UTC 2017 (1491836291948)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 9 in 00:00:01 = 17.6/s Avg: 1 Min: 0 Max:
9 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:58:13 UTC 2017 (1491836293392)
[jmeter] ... end of run
[echo] Bug56243 output files compared OK
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -X
-Jsample_variables=REFERENCE,JSESSIONID
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/Bug54685.jmx
[jmeter] Starting the test @ Mon Apr 10 14:58:18 UTC 2017 (1491836298292)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 1 in 00:00:01 = 1.4/s Avg: 271 Min: 271 Max:
271 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:58:20 UTC 2017 (1491836300112)
[jmeter] ... end of run
[echo] Bug54685 output files compared OK
batchtest:
[echo] Starting Bug55375 with file Bug55375.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/Bug55375.jmx
[jmeter] Starting the test @ Mon Apr 10 14:58:24 UTC 2017 (1491836304346)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 1 in 00:00:00 = 2.3/s Avg: 183 Min: 183 Max:
183 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:58:26 UTC 2017 (1491836306312)
[jmeter] ... end of run
[echo] Bug55375 output files compared OK
batchtestserver:
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2458
-Jsample_variables=REFERENCE,JSESSIONID
[server] Created remote object: UnicastServerRef [liveRef:
[endpoint:[67.195.81.186:55285](local),objID:[4a6b3480:15b585f9169:-7fff,
-3758136218631321805]]]
[client] Creating summariser <summary>
[client] Created the tree successfully using testfiles/Bug54685.jmx
[client] Configuring remote engine: localhost:2458
[client] Starting remote engines
[client] Starting the test @ Mon Apr 10 14:58:34 UTC 2017 (1491836314284)
[server] Starting the test on host localhost:2458 @ Mon Apr 10 14:58:39 UTC
2017 (1491836319636)
[client] Remote engines have been started
[client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[server] Finished the test on host localhost:2458 @ Mon Apr 10 14:58:41 UTC
2017 (1491836321760) - exit requested.
[client] summary = 1 in 00:00:01 = 1.3/s Avg: 345 Min: 345 Max:
345 Err: 0 (0.00%)
[client] Tidying up remote @ Mon Apr 10 14:58:41 UTC 2017 (1491836321762)
[client] ... end of run
[echo] Bug54685 output files compared OK
batchtest:
[echo] Starting Bug56811 with file Bug56811.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/Bug56811.jmx
[jmeter] Starting the test @ Mon Apr 10 14:58:52 UTC 2017 (1491836332538)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 1 in 00:00:09 = 0.1/s Avg: 215 Min: 215 Max:
215 Err: 0 (0.00%) Active: 1 Started: 4 Finished: 3
[jmeter] summary + 8 in 00:00:01 = 9.2/s Avg: 105 Min: 1 Max:
219 Err: 3 (37.50%) Active: 0 Started: 4 Finished: 4
[jmeter] summary = 9 in 00:00:10 = 0.9/s Avg: 117 Min: 1 Max:
219 Err: 3 (33.33%)
[jmeter] Tidying up ... @ Mon Apr 10 14:59:03 UTC 2017 (1491836343943)
[jmeter] ... end of run
[echo] Bug56811 output files compared OK
batchtest:
batchtest:
[echo] Starting Bug60607 with file Bug60607.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/Bug60607.jmx
[jmeter] Starting the test @ Mon Apr 10 14:59:10 UTC 2017 (1491836350512)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15274 Min: 439 Max:
30109 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:59:42 UTC 2017 (1491836382784)
[jmeter] ... end of run
[echo] Bug60607 output files compared OK
batchtest:
[echo] Starting SlowCharsFeature_HttpClient4 with file
SlowCharsFeature.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/SlowCharsFeature.jmx
[jmeter] Starting the test @ Mon Apr 10 14:59:48 UTC 2017 (1491836388147)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 3 in 00:00:20 = 0.2/s Avg: 6226 Min: 882 Max:
11640 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:00:09 UTC 2017 (1491836409657)
[jmeter] ... end of run
[echo] SlowCharsFeature_HttpClient4 output files compared OK
batchtest:
[echo] Starting SlowCharsFeature_Java with file SlowCharsFeature.jmx using
-X -Jjmeter.httpsampler=Java
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/SlowCharsFeature.jmx
[jmeter] Starting the test @ Mon Apr 10 15:00:15 UTC 2017 (1491836415521)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 2 in 00:00:14 = 0.1/s Avg: 6577 Min: 905 Max:
12250 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary = 2 in 00:00:14 = 0.1/s Avg: 6577 Min: 905 Max:
12250 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:00:30 UTC 2017 (1491836430786)
[jmeter] ... end of run
[echo] SlowCharsFeature_Java output files compared OK
batchtest:
[echo] Starting Http4ImplPreemptiveBasicAuth with file
Http4ImplPreemptiveBasicAuth.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using
testfiles/Http4ImplPreemptiveBasicAuth.jmx
[jmeter] Starting the test @ Mon Apr 10 15:00:35 UTC 2017 (1491836435509)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 12 in 00:00:09 = 1.3/s Avg: 442 Min: 153 Max:
1578 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:00:46 UTC 2017 (1491836446523)
[jmeter] ... end of run
[echo] Http4ImplPreemptiveBasicAuth output files compared OK
batchtest:
[echo] Starting Http4ImplPreemptiveBasicAuth with file
Http4ImplPreemptiveBasicAuth.jmx using -X -Jjmeter.httpsampler=Java
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using
testfiles/Http4ImplPreemptiveBasicAuth.jmx
[jmeter] Starting the test @ Mon Apr 10 15:00:51 UTC 2017 (1491836451743)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 9 in 00:00:07 = 1.4/s Avg: 324 Min: 155 Max:
1452 Err: 0 (0.00%) Active: 1 Started: 5 Finished: 4
[jmeter] summary + 3 in 00:00:01 = 2.7/s Avg: 157 Min: 156 Max:
158 Err: 0 (0.00%) Active: 0 Started: 6 Finished: 6
[jmeter] summary = 12 in 00:00:08 = 1.6/s Avg: 282 Min: 155 Max:
1452 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:01:01 UTC 2017 (1491836461121)
[jmeter] ... end of run
[echo] Http4ImplPreemptiveBasicAuth output files compared OK
batchtest:
[echo] Starting TestKeepAlive with file TestKeepAlive.jmx using -X
-Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/TestKeepAlive.jmx
[jmeter] Starting the test @ Mon Apr 10 15:01:06 UTC 2017 (1491836466005)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 7 in 00:01:04 = 0.1/s Avg: 342 Min: 153 Max:
758 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:02:11 UTC 2017 (1491836531755)
[jmeter] ... end of run
[echo] TestKeepAlive output files compared OK
batchtest:
[echo] Starting TestHeaderManager with file TestHeaderManager.jmx using -X
-Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/TestHeaderManager.jmx
[jmeter] Starting the test @ Mon Apr 10 15:02:16 UTC 2017 (1491836536653)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 3 in 00:00:01 = 3.7/s Avg: 31 Min: 2 Max:
87 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:02:18 UTC 2017 (1491836538461)
[jmeter] ... end of run
[echo] TestHeaderManager output files compared OK
batchtest:
[echo] Starting TestHeaderManager with file TestHeaderManager.jmx using -X
-Jjmeter.httpsampler=Java
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/TestHeaderManager.jmx
[jmeter] Starting the test @ Mon Apr 10 15:02:23 UTC 2017 (1491836543751)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 3 in 00:00:00 = 6.7/s Avg: 17 Min: 1 Max:
38 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:02:25 UTC 2017 (1491836545285)
[jmeter] ... end of run
[echo] TestHeaderManager output files compared OK
batchtest:
[echo] Starting TEST_HTTP_HttpClient4 with file TEST_HTTP.jmx using -X
-Jjmeter.httpsampler=HttpClient4
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/TEST_HTTP.jmx
[jmeter] Starting the test @ Mon Apr 10 15:02:31 UTC 2017 (1491836551178)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 1 in 00:00:01 = 1.4/s Avg: 538 Min: 538 Max:
538 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 20 in 00:00:02 = 10.2/s Avg: 55 Min: 2 Max:
399 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 21 in 00:00:03 = 7.9/s Avg: 78 Min: 2 Max:
538 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:02:34 UTC 2017 (1491836554674)
[jmeter] ... end of run
[echo] TEST_HTTP_HttpClient4 output files compared OK
batchtest:
[echo] Starting TEST_HTTP_Java with file TEST_HTTP.jmx using -X
-Jjmeter.httpsampler=Java
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using testfiles/TEST_HTTP.jmx
[jmeter] Starting the test @ Mon Apr 10 15:02:40 UTC 2017 (1491836560636)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 19 in 00:00:02 = 7.9/s Avg: 72 Min: 1 Max:
1089 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:02:45 UTC 2017 (1491836565020)
[jmeter] ... end of run
[concat] 2017-04-10 15:02:44,814 ERROR o.a.j.p.h.s.HTTPJavaImpl:
readResponse: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 15:02:44,816 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause:
java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 15:02:44,817 ERROR o.a.j.s.SampleResult: sampleEnd
called twice
[concat] java.lang.Throwable: Invalid call sequence
[concat] at
org.apache.jmeter.samplers.SampleResult.sampleEnd(SampleResult.java:1099)
[ApacheJMeter_core.jar:r1790841]
[concat] at
org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612)
[ApacheJMeter_http.jar:r1790841]
[concat] at
org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74)
[ApacheJMeter_http.jar:r1790841]
[concat] at
org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189)
[ApacheJMeter_http.jar:r1790841]
[concat] at
org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178)
[ApacheJMeter_http.jar:r1790841]
[concat] at
org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491)
[ApacheJMeter_core.jar:r1790841]
[concat] at
org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425)
[ApacheJMeter_core.jar:r1790841]
[concat] at
org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254)
[ApacheJMeter_core.jar:r1790841]
[concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
[concat] 2017-04-10 15:02:44,821 ERROR o.a.j.p.h.c.HttpMirrorThread: Invalid
request received:''
[echo] TEST_HTTP_Java output files compared OK
batchtest:
[echo] Starting TestSchedulerWithTimer with file
TestSchedulerWithTimer.jmx using -X -Jdummy=dummy
[jmeter] Creating summariser <summary>
[jmeter] Created the tree successfully using
testfiles/TestSchedulerWithTimer.jmx
[jmeter] Starting the test @ Mon Apr 10 15:02:51 UTC 2017 (1491836571615)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 5 in 00:00:06 = 0.8/s Avg: 125 Min: 0 Max:
622 Err: 0 (0.00%) Active: 1 Started: 3 Finished: 2
[jmeter] summary + 1 in 00:00:00 = 3.9/s Avg: 201 Min: 201 Max:
201 Err: 1 (100.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 6 in 00:00:07 = 0.9/s Avg: 137 Min: 0 Max:
622 Err: 1 (16.67%)
[jmeter] Tidying up ... @ Mon Apr 10 15:03:00 UTC 2017 (1491836580325)
[jmeter] ... end of run
[concat] 2017-04-10 15:03:00,299 WARN o.a.j.p.j.s.JSR223Sampler: Duration
5999 exceeded expected duration of 5700
BUILD FAILED
<https://builds.apache.org/job/JMeter-trunk/ws/trunk/build.xml>:2793: The
following error occurred while executing this line:
<https://builds.apache.org/job/JMeter-trunk/ws/trunk/build.xml>:2603: CSV Files
are not identical.
<https://builds.apache.org/job/JMeter-trunk/ws/trunk/bin/testfiles/TestSchedulerWithTimer.csv>
JSRCheckDuration,200,tearDown Thread Group 1-1,text,true
<https://builds.apache.org/job/JMeter-trunk/ws/trunk/bin/TestSchedulerWithTimer.csv>
JSRCheckDuration,200,tearDown Thread Group 1-1,text,false
Total time: 12 minutes 42 seconds
Build step 'Invoke Ant' marked build as failure
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Archiving artifacts
