See <https://builds.apache.org/job/JMeter%20Windows/581/display/redirect>
------------------------------------------ [...truncated 127.52 KB...] [jmeter] Tidying up ... @ Mon Apr 10 07:06:54 UTC 2017 (1491808014283) [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 07:06:57 UTC 2017 (1491808017445) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 9 in 00:00:00 = 37.8/s Avg: 1 Min: 0 Max: 11 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:06:58 UTC 2017 (1491808018505) [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 07:07:01 UTC 2017 (1491808021638) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:00 = 2.3/s Avg: 280 Min: 280 Max: 280 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary = 1 in 00:00:00 = 2.3/s Avg: 280 Min: 280 Max: 280 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:07:02 UTC 2017 (1491808022932) [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 07:07:06 UTC 2017 (1491808026283) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 1 in 00:00:00 = 4.0/s Avg: 106 Min: 106 Max: 106 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:07:07 UTC 2017 (1491808027425) [jmeter] ... end of run [echo] Bug55375 output files compared OK batchtestserver: batchtest: [echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2123 -Jsample_variables=REFERENCE,JSESSIONID [server] Created remote object: UnicastServerRef [liveRef: [endpoint:[10.20.2.147:52388](local),objID:[24039ea9:15b56b00d67:-7fff, -7586543240254022236]]] [client] Creating summariser <summary> [client] Created the tree successfully using testfiles/Bug54685.jmx [client] Configuring remote engine: localhost:2123 [client] Starting remote engines [client] Starting the test @ Mon Apr 10 07:07:13 UTC 2017 (1491808033036) [client] Remote engines have been started [client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [server] Starting the test on host localhost:2123 @ Mon Apr 10 07:07:14 UTC 2017 (1491808034794) [client] summary = 1 in 00:00:00 = 3.4/s Avg: 128 Min: 128 Max: 128 Err: 0 (0.00%) [client] Tidying up remote @ Mon Apr 10 07:07:15 UTC 2017 (1491808035915) [server] Finished the test on host localhost:2123 @ Mon Apr 10 07:07:15 UTC 2017 (1491808035915) - 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 07:07:24 UTC 2017 (1491808044691) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:09 = 0.1/s Avg: 259 Min: 259 Max: 259 Err: 0 (0.00%) Active: 1 Started: 4 Finished: 3 [jmeter] summary + 8 in 00:00:01 = 6.5/s Avg: 151 Min: 0 Max: 312 Err: 3 (37.50%) Active: 0 Started: 4 Finished: 4 [jmeter] summary = 9 in 00:00:10 = 0.9/s Avg: 163 Min: 0 Max: 312 Err: 3 (33.33%) [jmeter] Tidying up ... @ Mon Apr 10 07:07:35 UTC 2017 (1491808055458) [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 07:07:38 UTC 2017 (1491808058550) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15191 Min: 229 Max: 30154 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:08:10 UTC 2017 (1491808090288) [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 07:08:14 UTC 2017 (1491808094253) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 3 in 00:00:18 = 0.2/s Avg: 5843 Min: 642 Max: 11002 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary = 3 in 00:00:18 = 0.2/s Avg: 5843 Min: 642 Max: 11002 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:08:33 UTC 2017 (1491808113185) [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 07:08:37 UTC 2017 (1491808117567) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:13 = 0.2/s Avg: 6104 Min: 635 Max: 11573 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:08:51 UTC 2017 (1491808131017) [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 07:08:54 UTC 2017 (1491808134178) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 10 in 00:00:04 = 2.2/s Avg: 137 Min: 14 Max: 835 Err: 0 (0.00%) Active: 1 Started: 5 Finished: 4 [jmeter] summary + 2 in 00:00:01 = 3.3/s Avg: 92 Min: 19 Max: 165 Err: 0 (0.00%) Active: 0 Started: 6 Finished: 6 [jmeter] summary = 12 in 00:00:05 = 2.4/s Avg: 129 Min: 14 Max: 835 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:09:00 UTC 2017 (1491808140677) [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 07:09:03 UTC 2017 (1491808143895) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 12 in 00:00:04 = 2.8/s Avg: 82 Min: 16 Max: 739 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:09:09 UTC 2017 (1491808149770) [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 07:09:12 UTC 2017 (1491808152879) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 7 in 00:01:01 = 0.1/s Avg: 146 Min: 17 Max: 691 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:10:15 UTC 2017 (1491808215895) [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 07:10:19 UTC 2017 (1491808219448) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 3 in 00:00:07 = 0.5/s Avg: 2043 Min: 2004 Max: 2121 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:10:26 UTC 2017 (1491808226853) [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 07:10:30 UTC 2017 (1491808230173) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:01 = 0.8/s Avg: 1029 Min: 1029 Max: 1029 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary + 2 in 00:00:02 = 1.0/s Avg: 1002 Min: 1002 Max: 1003 Err: 0 (0.00%) Active: 0 Started: 2 Finished: 2 [jmeter] summary = 3 in 00:00:03 = 0.9/s Avg: 1011 Min: 1002 Max: 1029 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:10:34 UTC 2017 (1491808234313) [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 07:10:38 UTC 2017 (1491808238293) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 21 in 00:00:02 = 10.5/s Avg: 67 Min: 2 Max: 663 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:10:41 UTC 2017 (1491808241096) [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 07:10:46 UTC 2017 (1491808246171) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 19 in 00:00:02 = 10.7/s Avg: 67 Min: 1 Max: 643 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:10:48 UTC 2017 (1491808248779) [jmeter] ... end of run [concat] 2017-04-10 07:10:48,650 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20) [concat] 2017-04-10 07:10:48,654 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20) [concat] 2017-04-10 07:10:48,656 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:r1790747] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790747] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790747] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790747] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790747] [concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790747] [concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790747] [concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790747] [concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121] [concat] 2017-04-10 07:10:48,663 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 07:10:52 UTC 2017 (1491808252785) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 5 in 00:00:06 = 0.9/s Avg: 124 Min: 0 Max: 608 Err: 0 (0.00%) Active: 1 Started: 3 Finished: 2 [jmeter] summary + 1 in 00:00:00 = 6.5/s Avg: 121 Min: 121 Max: 121 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1 [jmeter] summary = 6 in 00:00:06 = 1.0/s Avg: 123 Min: 0 Max: 608 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 07:11:00 UTC 2017 (1491808260192) [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>:2846: Java returned: 1 Total time: 9 minutes 7 seconds Build step 'Invoke Ant' marked build as failure