See <https://builds.apache.org/job/JMeter%20Windows/584/display/redirect?page=changes>
Changes: [sebb] More Windows debug ------------------------------------------ [...truncated 129.27 KB...] [jmeter] summary + 1 in 00:00:00 = 6.8/s Avg: 141 Min: 141 Max: 141 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1 [jmeter] summary = 2 in 00:00:00 = 4.2/s Avg: 163 Min: 141 Max: 186 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:49:32 UTC 2017 (1491828572316) [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 12:49:35 UTC 2017 (1491828575568) [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: 12 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:49:36 UTC 2017 (1491828576659) [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 12:49:39 UTC 2017 (1491828579699) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 1 in 00:00:00 = 2.3/s Avg: 287 Min: 287 Max: 287 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:49:41 UTC 2017 (1491828581045) [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 12:49:44 UTC 2017 (1491828584150) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 1 in 00:00:01 = 2.0/s Avg: 255 Min: 255 Max: 255 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:49:45 UTC 2017 (1491828585623) [jmeter] ... end of run [echo] Bug55375 output files compared OK batchtestserver: batchtest: [echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2901 -Jsample_variables=REFERENCE,JSESSIONID [server] Created remote object: UnicastServerRef [liveRef: [endpoint:[10.20.2.147:60083](local),objID:[4ec25566:15b57e9c080:-7fff, -3430134673322555913]]] [client] Creating summariser <summary> [client] Created the tree successfully using testfiles/Bug54685.jmx [client] Configuring remote engine: localhost:2901 [client] Starting remote engines [client] Starting the test @ Mon Apr 10 12:49:51 UTC 2017 (1491828591239) [client] Remote engines have been started [client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [server] Starting the test on host localhost:2901 @ Mon Apr 10 12:49:52 UTC 2017 (1491828592869) [client] summary = 1 in 00:00:01 = 1.9/s Avg: 354 Min: 354 Max: 354 Err: 0 (0.00%) [server] Finished the test on host localhost:2901 @ Mon Apr 10 12:49:54 UTC 2017 (1491828594208) - exit requested. [client] Tidying up remote @ Mon Apr 10 12:49:54 UTC 2017 (1491828594208) [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 12:50:02 UTC 2017 (1491828602828) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 9 in 00:00:10 = 0.9/s Avg: 154 Min: 0 Max: 301 Err: 3 (33.33%) [jmeter] Tidying up ... @ Mon Apr 10 12:50:13 UTC 2017 (1491828613948) [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 12:50:16 UTC 2017 (1491828616958) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15162 Min: 221 Max: 30104 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:50:48 UTC 2017 (1491828648516) [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 12:50:52 UTC 2017 (1491828652358) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 2 in 00:00:07 = 0.3/s Avg: 3265 Min: 751 Max: 5779 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary + 1 in 00:00:11 = 0.1/s Avg: 11266 Min: 11266 Max: 11266 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1 [jmeter] summary = 3 in 00:00:18 = 0.2/s Avg: 5932 Min: 751 Max: 11266 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:51:11 UTC 2017 (1491828671699) [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 12:51:15 UTC 2017 (1491828675737) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:13 = 0.2/s Avg: 6169 Min: 673 Max: 11666 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:51:29 UTC 2017 (1491828689455) [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 12:51:33 UTC 2017 (1491828693518) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 12 in 00:00:05 = 2.4/s Avg: 130 Min: 14 Max: 857 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:51:40 UTC 2017 (1491828700281) [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 12:51:43 UTC 2017 (1491828703493) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 12 in 00:00:04 = 2.7/s Avg: 86 Min: 15 Max: 801 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:51:49 UTC 2017 (1491828709501) [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 12:51:53 UTC 2017 (1491828713298) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 5 in 00:00:06 = 0.9/s Avg: 194 Min: 13 Max: 723 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary + 2 in 00:00:56 = 0.0/s Avg: 35 Min: 33 Max: 37 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1 [jmeter] summary = 7 in 00:01:02 = 0.1/s Avg: 149 Min: 13 Max: 723 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:52:56 UTC 2017 (1491828776452) [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 12:52:59 UTC 2017 (1491828779675) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:02 = 0.4/s Avg: 2119 Min: 2119 Max: 2119 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary + 2 in 00:00:04 = 0.5/s Avg: 2015 Min: 1998 Max: 2032 Err: 0 (0.00%) Active: 0 Started: 2 Finished: 2 [jmeter] summary = 3 in 00:00:07 = 0.5/s Avg: 2049 Min: 1998 Max: 2119 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:53:07 UTC 2017 (1491828787075) [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 12:53:10 UTC 2017 (1491828790346) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 3 in 00:00:03 = 0.9/s Avg: 1012 Min: 1001 Max: 1031 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:53:14 UTC 2017 (1491828794569) [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 12:53:18 UTC 2017 (1491828798459) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 21 in 00:00:02 = 10.6/s Avg: 67 Min: 1 Max: 639 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:53:21 UTC 2017 (1491828801262) [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 12:53:26 UTC 2017 (1491828806361) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 19 in 00:00:02 = 11.3/s Avg: 65 Min: 2 Max: 671 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:53:28 UTC 2017 (1491828808865) [jmeter] ... end of run [concat] 2017-04-10 12:53:28,734 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20) [concat] 2017-04-10 12:53:28,740 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20) [concat] 2017-04-10 12:53:28,742 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:r1790808] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790808] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790808] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790808] [concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790808] [concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790808] [concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790808] [concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790808] [concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121] [concat] 2017-04-10 12:53:28,753 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 12:53:32 UTC 2017 (1491828812945) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:01 = 1.4/s Avg: 656 Min: 656 Max: 656 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary + 5 in 00:00:05 = 1.0/s Avg: 26 Min: 0 Max: 121 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1 [jmeter] summary = 6 in 00:00:06 = 1.0/s Avg: 131 Min: 0 Max: 656 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Apr 10 12:53:40 UTC 2017 (1491828820382) [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 8 seconds Build step 'Invoke Ant' marked build as failure