See
<https://builds.apache.org/job/JMeter-trunk/6092/display/redirect?page=changes>
Changes:
[sebb] Add a simpler test to try and debug the Windows failure
------------------------------------------
[...truncated 144.07 KB...]
[jmeter] summary + 1 in 00:00:01 = 1.4/s Avg: 13 Min: 13 Max:
13 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 2 in 00:00:03 = 0.7/s Avg: 1074 Min: 477 Max:
1672 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 3 in 00:00:03 = 0.9/s Avg: 720 Min: 13 Max:
1672 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:24:07 UTC 2017 (1491823447720)
[jmeter] ... end of run
[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 11:24:13 UTC 2017 (1491823453431)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 30 in 00:00:13 = 2.3/s Avg: 219 Min: 106 Max:
352 Err: 9 (30.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:24:28 UTC 2017 (1491823468079)
[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 11:24:31 UTC 2017 (1491823471863)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 1 in 00:00:01 = 1.5/s Avg: 173 Min: 173 Max:
173 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 1 in 00:00:00 = 8.4/s Avg: 111 Min: 111 Max:
111 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 2 in 00:00:01 = 2.6/s Avg: 142 Min: 111 Max:
173 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:24:34 UTC 2017 (1491823474298)
[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 11:24:38 UTC 2017 (1491823478885)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 9 in 00:00:01 = 17.1/s Avg: 1 Min: 0 Max:
10 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:24:40 UTC 2017 (1491823480874)
[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 11:24:45 UTC 2017 (1491823485126)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 1 in 00:00:01 = 1.2/s Avg: 316 Min: 316 Max:
316 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:24:47 UTC 2017 (1491823487995)
[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 11:24:52 UTC 2017 (1491823492724)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 1 in 00:00:01 = 1.5/s Avg: 272 Min: 272 Max:
272 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:24:54 UTC 2017 (1491823494786)
[jmeter] ... end of run
[echo] Bug55375 output files compared OK
batchtestserver:
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2177
-Jsample_variables=REFERENCE,JSESSIONID
[server] Created remote object: UnicastServerRef [liveRef:
[endpoint:[67.195.81.186:41843](local),objID:[36b701e6:15b579c14b7:-7fff,
-3013493004499926883]]]
[client] Creating summariser <summary>
[client] Created the tree successfully using testfiles/Bug54685.jmx
[client] Configuring remote engine: localhost:2177
[client] Starting remote engines
[client] Starting the test @ Mon Apr 10 11:25:02 UTC 2017 (1491823502704)
[server] Starting the test on host localhost:2177 @ Mon Apr 10 11:25:06 UTC
2017 (1491823506663)
[client] Remote engines have been started
[client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[client] summary = 1 in 00:00:01 = 1.5/s Avg: 267 Min: 267 Max:
267 Err: 0 (0.00%)
[client] Tidying up remote @ Mon Apr 10 11:25:08 UTC 2017 (1491823508423)
[server] Finished the test on host localhost:2177 @ Mon Apr 10 11:25:08 UTC
2017 (1491823508418) - exit requested.
[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 11:25:19 UTC 2017 (1491823519123)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 9 in 00:00:09 = 1.0/s Avg: 141 Min: 0 Max:
309 Err: 3 (33.33%)
[jmeter] Tidying up ... @ Mon Apr 10 11:25:29 UTC 2017 (1491823529969)
[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 11:25:34 UTC 2017 (1491823534906)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15340 Min: 501 Max:
30180 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:26:07 UTC 2017 (1491823567306)
[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 11:26:13 UTC 2017 (1491823573053)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 3 in 00:00:20 = 0.2/s Avg: 6196 Min: 1039 Max:
11656 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary = 3 in 00:00:20 = 0.2/s Avg: 6196 Min: 1039 Max:
11656 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:26:34 UTC 2017 (1491823594592)
[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 11:26:40 UTC 2017 (1491823600489)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 2 in 00:00:15 = 0.1/s Avg: 6661 Min: 953 Max:
12370 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:26:56 UTC 2017 (1491823616598)
[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 11:27:01 UTC 2017 (1491823621140)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 12 in 00:00:10 = 1.2/s Avg: 427 Min: 154 Max:
1347 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:27:13 UTC 2017 (1491823633169)
[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 11:27:17 UTC 2017 (1491823637708)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 12 in 00:00:07 = 1.7/s Avg: 271 Min: 152 Max:
1329 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:27:27 UTC 2017 (1491823647645)
[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 11:27:32 UTC 2017 (1491823652706)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 1 in 00:00:01 = 1.2/s Avg: 741 Min: 741 Max:
741 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 6 in 00:01:02 = 0.1/s Avg: 256 Min: 154 Max:
432 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 7 in 00:01:03 = 0.1/s Avg: 325 Min: 154 Max:
741 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:28:37 UTC 2017 (1491823717998)
[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 11:28:43 UTC 2017 (1491823723058)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 3 in 00:00:02 = 1.6/s Avg: 95 Min: 3 Max:
279 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:28:46 UTC 2017 (1491823726481)
[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 11:28:50 UTC 2017 (1491823730915)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 3 in 00:00:01 = 3.2/s Avg: 10 Min: 1 Max:
29 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:28:53 UTC 2017 (1491823733799)
[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 11:28:59 UTC 2017 (1491823739609)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary + 1 in 00:00:01 = 1.1/s Avg: 726 Min: 726 Max:
726 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 20 in 00:00:01 = 15.7/s Avg: 23 Min: 3 Max:
124 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 21 in 00:00:02 = 9.7/s Avg: 56 Min: 3 Max:
726 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:29:03 UTC 2017 (1491823743474)
[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 11:29:10 UTC 2017 (1491823750435)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 19 in 00:00:03 = 7.1/s Avg: 87 Min: 1 Max:
875 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:29:14 UTC 2017 (1491823754296)
[jmeter] ... end of run
[concat] 2017-04-10 11:29:13,950 ERROR o.a.j.p.h.s.HTTPJavaImpl:
readResponse: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 11:29:13,952 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause:
java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 11:29:13,959 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:r1790803]
[concat] at
org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612)
[ApacheJMeter_http.jar:r1790803]
[concat] at
org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74)
[ApacheJMeter_http.jar:r1790803]
[concat] at
org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189)
[ApacheJMeter_http.jar:r1790803]
[concat] at
org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178)
[ApacheJMeter_http.jar:r1790803]
[concat] at
org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491)
[ApacheJMeter_core.jar:r1790803]
[concat] at
org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425)
[ApacheJMeter_core.jar:r1790803]
[concat] at
org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254)
[ApacheJMeter_core.jar:r1790803]
[concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
[concat] 2017-04-10 11:29:13,965 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 11:29:18 UTC 2017 (1491823758993)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port
4445
[jmeter] summary = 6 in 00:00:07 = 0.9/s Avg: 169 Min: 0 Max:
775 Err: 1 (16.67%)
[jmeter] Tidying up ... @ Mon Apr 10 11:29:28 UTC 2017 (1491823768788)
[jmeter] ... end of run
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 38 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
