See <https://builds.apache.org/job/JMeter%20Windows/609/display/redirect?page=changes>
Changes: [pmouawad] Fix test failure [pmouawad] Improve logging Fix SONAR error Clariy code Use try with resource ------------------------------------------ [...truncated 128.99 KB...] [jmeter] Starting the test @ Mon May 01 11:05:39 UTC 2017 (1493636739382) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:00 = 4.6/s Avg: 145 Min: 116 Max: 174 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:05:40 UTC 2017 (1493636740801) [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 May 01 11:05:44 UTC 2017 (1493636744014) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 9 in 00:00:00 = 36.1/s Avg: 1 Min: 0 Max: 12 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:05:45 UTC 2017 (1493636745089) [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 May 01 11:05:48 UTC 2017 (1493636748233) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 1 in 00:00:00 = 2.7/s Avg: 231 Min: 231 Max: 231 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:05:49 UTC 2017 (1493636749468) [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 May 01 11:05:52 UTC 2017 (1493636752616) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 1 in 00:00:00 = 2.2/s Avg: 305 Min: 305 Max: 305 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:05:53 UTC 2017 (1493636753948) [jmeter] ... end of run [echo] Bug55375 output files compared OK batchtestserver: batchtest: [echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2580 -Jsample_variables=REFERENCE,JSESSIONID [server] Created remote object: UnicastServerRef [liveRef: [endpoint:[10.20.2.147:57384](local),objID:[7f17eb42:15bc3b03479:-7fff, 5623934805124253294]]] [client] Creating summariser <summary> [client] Created the tree successfully using testfiles/Bug54685.jmx [client] Configuring remote engine: localhost:2580 [client] Starting remote engines [client] Starting the test @ Mon May 01 11:05:59 UTC 2017 (1493636759485) [server] Starting the test on host localhost:2580 @ Mon May 01 11:06:01 UTC 2017 (1493636761161) [client] Remote engines have been started [client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [client] summary + 1 in 00:00:00 = 3.4/s Avg: 134 Min: 134 Max: 134 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [client] summary = 1 in 00:00:00 = 3.3/s Avg: 134 Min: 134 Max: 134 Err: 0 (0.00%) [client] Tidying up remote @ Mon May 01 11:06:02 UTC 2017 (1493636762306) [server] Finished the test on host localhost:2580 @ Mon May 01 11:06:02 UTC 2017 (1493636762306) - 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 May 01 11:06:10 UTC 2017 (1493636770549) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 9 in 00:00:09 = 1.0/s Avg: 146 Min: 1 Max: 346 Err: 3 (33.33%) [jmeter] Tidying up ... @ Mon May 01 11:06:20 UTC 2017 (1493636780496) [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 May 01 11:06:23 UTC 2017 (1493636783863) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15318 Min: 533 Max: 30103 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:06:55 UTC 2017 (1493636815716) [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 May 01 11:06:59 UTC 2017 (1493636819573) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:01 = 1.5/s Avg: 644 Min: 644 Max: 644 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary + 2 in 00:00:18 = 0.1/s Avg: 8757 Min: 5920 Max: 11595 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1 [jmeter] summary = 3 in 00:00:19 = 0.2/s Avg: 6053 Min: 644 Max: 11595 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:07:19 UTC 2017 (1493636839120) [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 May 01 11:07:23 UTC 2017 (1493636843012) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:13 = 0.2/s Avg: 6332 Min: 640 Max: 12024 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:07:37 UTC 2017 (1493636857099) [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 May 01 11:07:40 UTC 2017 (1493636860184) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 12 in 00:00:09 = 1.3/s Avg: 461 Min: 158 Max: 1472 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:07:50 UTC 2017 (1493636870763) [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 May 01 11:07:54 UTC 2017 (1493636874030) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 4 in 00:00:05 = 0.9/s Avg: 730 Min: 169 Max: 1308 Err: 0 (0.00%) Active: 1 Started: 2 Finished: 1 [jmeter] summary + 8 in 00:00:03 = 2.6/s Avg: 185 Min: 161 Max: 319 Err: 0 (0.00%) Active: 0 Started: 6 Finished: 6 [jmeter] summary = 12 in 00:00:08 = 1.6/s Avg: 367 Min: 161 Max: 1308 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:08:03 UTC 2017 (1493636883196) [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 May 01 11:08:06 UTC 2017 (1493636886462) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 7 in 00:01:03 = 0.1/s Avg: 332 Min: 169 Max: 646 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:09:10 UTC 2017 (1493636950854) [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 May 01 11:09:13 UTC 2017 (1493636953998) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 3 in 00:00:06 = 0.5/s Avg: 2043 Min: 2000 Max: 2127 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:09:21 UTC 2017 (1493636961310) [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 May 01 11:09:24 UTC 2017 (1493636964372) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 3 in 00:00:03 = 0.9/s Avg: 1013 Min: 1002 Max: 1035 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:09:28 UTC 2017 (1493636968639) [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 May 01 11:09:32 UTC 2017 (1493636972468) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:01 = 1.4/s Avg: 664 Min: 664 Max: 664 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary + 20 in 00:00:01 = 17.3/s Avg: 33 Min: 2 Max: 204 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1 [jmeter] summary = 21 in 00:00:02 = 11.1/s Avg: 63 Min: 2 Max: 664 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:09:35 UTC 2017 (1493636975135) [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 May 01 11:09:39 UTC 2017 (1493636979908) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 19 in 00:00:02 = 11.4/s Avg: 63 Min: 2 Max: 699 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:09:42 UTC 2017 (1493636982320) [jmeter] ... end of run [concat] 2017-05-01 11:09:42,164 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20) [concat] 2017-05-01 11:09:42,170 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20) [concat] 2017-05-01 11:09:42,172 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:r1793304] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1793304] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1793304] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1793304] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1793304] [concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1793304] [concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1793304] [concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1793304] [concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121] [concat] 2017-05-01 11:09:42,186 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 May 01 11:09:46 UTC 2017 (1493636986327) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 6 in 00:00:06 = 1.0/s Avg: 127 Min: 0 Max: 636 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon May 01 11:09:53 UTC 2017 (1493636993643) [jmeter] ... end of run [echo] TestSchedulerWithTimer output files compared OK _allbatchtests: Cannot execute 'test' - '_test' failed or was not executed. download_checkstyle: _process_checkstyle_jars: _check_exists: _check_jarfile: [echo] Checking checkstyle-6.13-all.jar [echo] Deleting checkstyle-6.13-all.jar (excluding checkstyle-6.13-all.jar) setproxy: _get_jarfile: setproxy: _get_zipfile: checkstyle: _process_checkstyle_jars: _check_exists: _check_jarfile: [checkstyle] Running Checkstyle 6.13 on 1045 files BUILD FAILED <https://builds.apache.org/job/JMeter%20Windows/ws/trunk\build.xml>:2848: Java returned: 1 Total time: 9 minutes 15 seconds Build step 'Invoke Ant' marked build as failure