You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@jmeter.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/04/10 11:10:28 UTC
Build failed in Jenkins: JMeter-trunk #6091
See <https://builds.apache.org/job/JMeter-trunk/6091/display/redirect?page=changes>
Changes:
[sebb] Generics
------------------------------------------
[...truncated 144.17 KB...]
[jmeter] Tidying up ... @ Mon Apr 10 11:05:02 UTC 2017 (1491822302577)
[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:05:06 UTC 2017 (1491822306686)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 30 in 00:00:12 = 2.4/s Avg: 210 Min: 101 Max: 354 Err: 9 (30.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:05:20 UTC 2017 (1491822320195)
[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:05:25 UTC 2017 (1491822325912)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:01 = 3.2/s Avg: 148 Min: 119 Max: 177 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:05:27 UTC 2017 (1491822327831)
[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:05:33 UTC 2017 (1491822333720)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:00 = 2.9/s Avg: 7 Min: 7 Max: 7 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 8 in 00:00:00 = 129.0/s Avg: 0 Min: 0 Max: 4 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 9 in 00:00:00 = 22.1/s Avg: 1 Min: 0 Max: 7 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:05:35 UTC 2017 (1491822335095)
[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:05:40 UTC 2017 (1491822340900)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:01 = 1.8/s Avg: 152 Min: 152 Max: 152 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:05:42 UTC 2017 (1491822342448)
[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:05:47 UTC 2017 (1491822347859)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:00 = 2.0/s Avg: 218 Min: 218 Max: 218 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:05:49 UTC 2017 (1491822349466)
[jmeter] ... end of run
[echo] Bug55375 output files compared OK
batchtestserver:
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2416 -Jsample_variables=REFERENCE,JSESSIONID
[server] Created remote object: UnicastServerRef [liveRef: [endpoint:[67.195.81.186:60676](local),objID:[5af1f406:15b578a998f:-7fff, -3491014063495515085]]]
[client] Creating summariser <summary>
[client] Created the tree successfully using testfiles/Bug54685.jmx
[client] Configuring remote engine: localhost:2416
[client] Starting remote engines
[client] Starting the test @ Mon Apr 10 11:05:57 UTC 2017 (1491822357234)
[server] Starting the test on host localhost:2416 @ Mon Apr 10 11:06:02 UTC 2017 (1491822362602)
[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.8/s Avg: 168 Min: 168 Max: 168 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[client] summary = 1 in 00:00:01 = 1.8/s Avg: 168 Min: 168 Max: 168 Err: 0 (0.00%)
[client] Tidying up remote @ Mon Apr 10 11:06:04 UTC 2017 (1491822364538)
[server] Finished the test on host localhost:2416 @ Mon Apr 10 11:06:04 UTC 2017 (1491822364538) - 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:06:14 UTC 2017 (1491822374012)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:10 = 0.9/s Avg: 161 Min: 0 Max: 351 Err: 3 (33.33%)
[jmeter] Tidying up ... @ Mon Apr 10 11:06:26 UTC 2017 (1491822386100)
[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:06:30 UTC 2017 (1491822390030)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:36 = 0.1/s Avg: 17827 Min: 5555 Max: 30099 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:07:07 UTC 2017 (1491822427428)
[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:07:12 UTC 2017 (1491822432534)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 3 in 00:00:20 = 0.2/s Avg: 6284 Min: 1452 Max: 11508 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary = 3 in 00:00:20 = 0.2/s Avg: 6284 Min: 1452 Max: 11508 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:07:33 UTC 2017 (1491822453783)
[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:07:39 UTC 2017 (1491822459206)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:14 = 0.1/s Avg: 6712 Min: 768 Max: 12656 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:07:54 UTC 2017 (1491822474710)
[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:08:00 UTC 2017 (1491822480060)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:02 = 0.4/s Avg: 496 Min: 496 Max: 496 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 11 in 00:00:07 = 1.6/s Avg: 418 Min: 153 Max: 1334 Err: 0 (0.00%) Active: 0 Started: 6 Finished: 6
[jmeter] summary = 12 in 00:00:09 = 1.3/s Avg: 424 Min: 153 Max: 1334 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:08:11 UTC 2017 (1491822491453)
[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:08:15 UTC 2017 (1491822495342)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 12 in 00:00:07 = 1.7/s Avg: 270 Min: 153 Max: 1293 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:08:26 UTC 2017 (1491822506010)
[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:08:30 UTC 2017 (1491822510599)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 0.9/s Avg: 833 Min: 833 Max: 833 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 6 in 00:01:02 = 0.1/s Avg: 253 Min: 154 Max: 429 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 7 in 00:01:03 = 0.1/s Avg: 336 Min: 154 Max: 833 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:09:37 UTC 2017 (1491822577231)
[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:09:41 UTC 2017 (1491822581256)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:02 = 2.0/s Avg: 82 Min: 1 Max: 243 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:09:44 UTC 2017 (1491822584376)
[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:09:48 UTC 2017 (1491822588273)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:01 = 2.5/s Avg: 29 Min: 3 Max: 81 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:09:51 UTC 2017 (1491822591586)
[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:09:57 UTC 2017 (1491822597037)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 2 in 00:00:02 = 1.0/s Avg: 690 Min: 370 Max: 1010 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 19 in 00:00:01 = 33.5/s Avg: 17 Min: 1 Max: 88 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 21 in 00:00:03 = 8.1/s Avg: 81 Min: 1 Max: 1010 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:10:01 UTC 2017 (1491822601215)
[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:10:08 UTC 2017 (1491822608875)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 19 in 00:00:02 = 11.4/s Avg: 46 Min: 1 Max: 613 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 11:10:11 UTC 2017 (1491822611373)
[jmeter] ... end of run
[concat] 2017-04-10 11:10:11,197 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 11:10:11,199 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 11:10:11,200 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:r1790800]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790800]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790800]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790800]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790800]
[concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790800]
[concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790800]
[concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790800]
[concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
[concat] 2017-04-10 11:10:11,217 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:10:17 UTC 2017 (1491822617520)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 6 in 00:00:07 = 0.8/s Avg: 228 Min: 0 Max: 795 Err: 1 (16.67%)
[jmeter] Tidying up ... @ Mon Apr 10 11:10:27 UTC 2017 (1491822627233)
[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 52 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
Jenkins build is back to normal : JMeter-trunk #6099
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/JMeter-trunk/6099/display/redirect?page=changes>
Build failed in Jenkins: JMeter-trunk #6098
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/JMeter-trunk/6098/display/redirect?page=changes>
Changes:
[sebb] Tab police
------------------------------------------
[...truncated 143.78 KB...]
[jmeter] summary = 3 in 00:00:03 = 1.1/s Avg: 549 Min: 23 Max: 1149 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:11:21 UTC 2017 (1491837081712)
[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 15:11:26 UTC 2017 (1491837086960)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 5 in 00:00:02 = 2.5/s Avg: 247 Min: 144 Max: 326 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 25 in 00:00:11 = 2.3/s Avg: 220 Min: 103 Max: 344 Err: 9 (36.00%) Active: 0 Started: 10 Finished: 10
[jmeter] summary = 30 in 00:00:13 = 2.3/s Avg: 224 Min: 103 Max: 344 Err: 9 (30.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:11:40 UTC 2017 (1491837100949)
[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 15:11:46 UTC 2017 (1491837106142)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:01 = 2.1/s Avg: 289 Min: 268 Max: 311 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:11:48 UTC 2017 (1491837108271)
[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 15:11:53 UTC 2017 (1491837113891)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:00 = 20.0/s Avg: 2 Min: 0 Max: 19 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:11:55 UTC 2017 (1491837115186)
[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 15:12:00 UTC 2017 (1491837120519)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.1/s Avg: 255 Min: 255 Max: 255 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary = 1 in 00:00:01 = 1.1/s Avg: 255 Min: 255 Max: 255 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:12:02 UTC 2017 (1491837122474)
[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 15:12:07 UTC 2017 (1491837127962)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:01 = 1.7/s Avg: 255 Min: 255 Max: 255 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:12:09 UTC 2017 (1491837129606)
[jmeter] ... end of run
[echo] Bug55375 output files compared OK
batchtestserver:
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2084 -Jsample_variables=REFERENCE,JSESSIONID
[server] Created remote object: UnicastServerRef [liveRef: [endpoint:[67.195.81.186:53198](local),objID:[-47d5d732:15b586c24be:-7fff, 5700648009848628964]]]
[client] Creating summariser <summary>
[client] Created the tree successfully using testfiles/Bug54685.jmx
[client] Configuring remote engine: localhost:2084
[client] Starting remote engines
[client] Starting the test @ Mon Apr 10 15:12:17 UTC 2017 (1491837137050)
[server] Starting the test on host localhost:2084 @ Mon Apr 10 15:12:21 UTC 2017 (1491837141693)
[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.9/s Avg: 135 Min: 135 Max: 135 Err: 0 (0.00%)
[client] Tidying up remote @ Mon Apr 10 15:12:23 UTC 2017 (1491837143696)
[server] Finished the test on host localhost:2084 @ Mon Apr 10 15:12:23 UTC 2017 (1491837143692) - 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 15:12:32 UTC 2017 (1491837152821)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:10 = 0.9/s Avg: 147 Min: 1 Max: 313 Err: 3 (33.33%)
[jmeter] Tidying up ... @ Mon Apr 10 15:12:44 UTC 2017 (1491837164462)
[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 15:12:49 UTC 2017 (1491837169715)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15346 Min: 494 Max: 30199 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:13:22 UTC 2017 (1491837202144)
[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 15:13:28 UTC 2017 (1491837208186)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 0.7/s Avg: 1228 Min: 1228 Max: 1228 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 2 in 00:00:19 = 0.1/s Avg: 8952 Min: 6114 Max: 11790 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 3 in 00:00:21 = 0.1/s Avg: 6377 Min: 1228 Max: 11790 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:13:50 UTC 2017 (1491837230398)
[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:13:56 UTC 2017 (1491837236619)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:14 = 0.1/s Avg: 6618 Min: 962 Max: 12275 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:14:12 UTC 2017 (1491837252061)
[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:14:18 UTC 2017 (1491837258119)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 12 in 00:00:10 = 1.2/s Avg: 449 Min: 153 Max: 1524 Err: 0 (0.00%) Active: 1 Started: 6 Finished: 5
[jmeter] summary = 12 in 00:00:10 = 1.2/s Avg: 449 Min: 153 Max: 1524 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:14:30 UTC 2017 (1491837270279)
[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:14:34 UTC 2017 (1491837274219)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 12 in 00:00:07 = 1.6/s Avg: 269 Min: 153 Max: 1326 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:14:44 UTC 2017 (1491837284461)
[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:14:48 UTC 2017 (1491837288905)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 7 in 00:01:03 = 0.1/s Avg: 336 Min: 153 Max: 774 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:15:54 UTC 2017 (1491837354723)
[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:15:59 UTC 2017 (1491837359082)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.0/s Avg: 178 Min: 178 Max: 178 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 2 in 00:00:00 = 33.3/s Avg: 3 Min: 1 Max: 5 Err: 0 (0.00%) Active: 0 Started: 2 Finished: 2
[jmeter] summary = 3 in 00:00:01 = 2.8/s Avg: 61 Min: 1 Max: 178 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:16:01 UTC 2017 (1491837361615)
[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:16:06 UTC 2017 (1491837366344)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:01 = 3.5/s Avg: 13 Min: 1 Max: 38 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:16:08 UTC 2017 (1491837368259)
[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:16:14 UTC 2017 (1491837374874)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 21 in 00:00:03 = 8.0/s Avg: 69 Min: 2 Max: 759 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:16:19 UTC 2017 (1491837379149)
[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:16:26 UTC 2017 (1491837386236)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 19 in 00:00:02 = 11.4/s Avg: 53 Min: 1 Max: 605 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 15:16:28 UTC 2017 (1491837388888)
[jmeter] ... end of run
[concat] 2017-04-10 15:16:28,650 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 15:16:28,652 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 15:16:28,688 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:r1790845]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790845]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790845]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790845]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790845]
[concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790845]
[concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790845]
[concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790845]
[concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
[concat] 2017-04-10 15:16:28,695 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:16:34 UTC 2017 (1491837394448)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 6 in 00:00:07 = 0.9/s Avg: 166 Min: 0 Max: 714 Err: 1 (16.67%)
[jmeter] Tidying up ... @ Mon Apr 10 15:16:43 UTC 2017 (1491837403410)
[jmeter] ... end of run
[concat] 2017-04-10 15:16:43,373 WARN o.a.j.p.j.s.JSR223Sampler: Duration 5829 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 37 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
Build failed in Jenkins: JMeter-trunk #6097
Posted by Apache Jenkins Server <je...@builds.apache.org>.
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
Build failed in Jenkins: JMeter-trunk #6096
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/JMeter-trunk/6096/display/redirect?page=changes>
Changes:
[sebb] Debug Jenkins error: add a log message in case test fails
------------------------------------------
[...truncated 144.23 KB...]
[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 14:29:05 UTC 2017 (1491834545317)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 30 in 00:00:13 = 2.3/s Avg: 233 Min: 100 Max: 351 Err: 9 (30.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:29:20 UTC 2017 (1491834560300)
[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:29:24 UTC 2017 (1491834564668)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:01 = 1.9/s Avg: 209 Min: 127 Max: 291 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:29:26 UTC 2017 (1491834566902)
[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:29:31 UTC 2017 (1491834571934)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:00 = 3.2/s Avg: 6 Min: 6 Max: 6 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 8 in 00:00:00 = 108.1/s Avg: 0 Min: 0 Max: 2 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 9 in 00:00:00 = 22.9/s Avg: 1 Min: 0 Max: 6 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:29:33 UTC 2017 (1491834573325)
[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:29:38 UTC 2017 (1491834578719)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:01 = 1.4/s Avg: 353 Min: 353 Max: 353 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:29:40 UTC 2017 (1491834580304)
[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:29:45 UTC 2017 (1491834585128)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:01 = 1.4/s Avg: 248 Min: 248 Max: 248 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:29:46 UTC 2017 (1491834586986)
[jmeter] ... end of run
[echo] Bug55375 output files compared OK
batchtestserver:
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2443 -Jsample_variables=REFERENCE,JSESSIONID
[server] Created remote object: UnicastServerRef [liveRef: [endpoint:[67.195.81.186:50384](local),objID:[-567354d9:15b58455748:-7fff, 2559313711584255725]]]
[client] Creating summariser <summary>
[client] Created the tree successfully using testfiles/Bug54685.jmx
[client] Configuring remote engine: localhost:2443
[client] Starting remote engines
[client] Starting the test @ Mon Apr 10 14:29:54 UTC 2017 (1491834594126)
[client] Remote engines have been started
[client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[server] Starting the test on host localhost:2443 @ Mon Apr 10 14:29:59 UTC 2017 (1491834599110)
[client] summary + 1 in 00:00:01 = 1.8/s Avg: 215 Min: 215 Max: 215 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[client] summary = 1 in 00:00:01 = 1.8/s Avg: 215 Min: 215 Max: 215 Err: 0 (0.00%)
[client] Tidying up remote @ Mon Apr 10 14:30:00 UTC 2017 (1491834600716)
[server] Finished the test on host localhost:2443 @ Mon Apr 10 14:30:00 UTC 2017 (1491834600716) - 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 14:30:10 UTC 2017 (1491834610829)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:10 = 0.9/s Avg: 167 Min: 0 Max: 351 Err: 3 (33.33%)
[jmeter] Tidying up ... @ Mon Apr 10 14:30:22 UTC 2017 (1491834622447)
[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:30:27 UTC 2017 (1491834627970)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 2 in 00:00:31 = 0.1/s Avg: 15301 Min: 444 Max: 30159 Err: 0 (0.00%) Active: 1 Started: 2 Finished: 1
[jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15301 Min: 444 Max: 30159 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:31:00 UTC 2017 (1491834660073)
[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:31:05 UTC 2017 (1491834665540)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:19 = 0.2/s Avg: 6011 Min: 722 Max: 11419 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:31:25 UTC 2017 (1491834685824)
[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 14:31:31 UTC 2017 (1491834691213)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.2/s Avg: 717 Min: 717 Max: 717 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 1 in 00:00:13 = 0.1/s Avg: 11976 Min: 11976 Max: 11976 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 2 in 00:00:13 = 0.1/s Avg: 6346 Min: 717 Max: 11976 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:31:46 UTC 2017 (1491834706525)
[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 14:31:51 UTC 2017 (1491834711550)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 7 in 00:00:06 = 1.1/s Avg: 532 Min: 156 Max: 1568 Err: 0 (0.00%) Active: 1 Started: 4 Finished: 3
[jmeter] summary + 5 in 00:00:03 = 1.8/s Avg: 326 Min: 154 Max: 829 Err: 0 (0.00%) Active: 0 Started: 6 Finished: 6
[jmeter] summary = 12 in 00:00:09 = 1.3/s Avg: 446 Min: 154 Max: 1568 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:32:03 UTC 2017 (1491834723199)
[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 14:32:08 UTC 2017 (1491834728257)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 12 in 00:00:05 = 2.3/s Avg: 100 Min: 13 Max: 941 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:32:15 UTC 2017 (1491834735582)
[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 14:32:20 UTC 2017 (1491834740193)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 7 in 00:01:02 = 0.1/s Avg: 175 Min: 13 Max: 653 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:33:25 UTC 2017 (1491834805125)
[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 14:33:28 UTC 2017 (1491834808986)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 0.9/s Avg: 203 Min: 203 Max: 203 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 2 in 00:00:00 = 37.0/s Avg: 2 Min: 1 Max: 4 Err: 0 (0.00%) Active: 0 Started: 2 Finished: 2
[jmeter] summary = 3 in 00:00:01 = 2.7/s Avg: 69 Min: 1 Max: 203 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:33:31 UTC 2017 (1491834811541)
[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 14:33:35 UTC 2017 (1491834815739)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:01 = 2.7/s Avg: 6 Min: 2 Max: 14 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:33:37 UTC 2017 (1491834817941)
[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 14:33:43 UTC 2017 (1491834823683)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 21 in 00:00:03 = 8.0/s Avg: 71 Min: 3 Max: 756 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:33:47 UTC 2017 (1491834827776)
[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 14:33:55 UTC 2017 (1491834835331)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 19 in 00:00:01 = 13.1/s Avg: 45 Min: 1 Max: 618 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 14:33:57 UTC 2017 (1491834837774)
[jmeter] ... end of run
[concat] 2017-04-10 14:33:57,659 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 14:33:57,661 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 14:33:57,663 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:r1790834]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790834]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790834]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790834]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790834]
[concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790834]
[concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790834]
[concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790834]
[concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
[concat] 2017-04-10 14:33:57,668 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 14:34:04 UTC 2017 (1491834844028)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 6 in 00:00:07 = 0.9/s Avg: 177 Min: 0 Max: 818 Err: 1 (16.67%)
[jmeter] Tidying up ... @ Mon Apr 10 14:34:12 UTC 2017 (1491834852845)
[jmeter] ... end of run
[concat] 2017-04-10 14:34:12,782 WARN o.a.j.p.j.s.JSR223Sampler: Duration 5652 exceeded expected duration of 5500
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
Build failed in Jenkins: JMeter-trunk #6095
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/JMeter-trunk/6095/display/redirect?page=changes>
Changes:
[sebb] getClass().getResource() does not work as a File if the classpath contains spaces
Bugzilla Id: 60966
------------------------------------------
[...truncated 144.84 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 13:54:38 UTC 2017 (1491832478552)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 30 in 00:00:14 = 2.2/s Avg: 244 Min: 130 Max: 352 Err: 9 (30.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:54:53 UTC 2017 (1491832493935)
[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 13:54:57 UTC 2017 (1491832497717)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:01 = 1.9/s Avg: 242 Min: 153 Max: 332 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:54:59 UTC 2017 (1491832499951)
[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 13:55:04 UTC 2017 (1491832504978)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:01 = 17.0/s Avg: 1 Min: 0 Max: 10 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:55:06 UTC 2017 (1491832506558)
[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 13:55:11 UTC 2017 (1491832511032)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:00 = 2.3/s Avg: 146 Min: 146 Max: 146 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:55:12 UTC 2017 (1491832512601)
[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 13:55:17 UTC 2017 (1491832517314)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:01 = 1.8/s Avg: 162 Min: 162 Max: 162 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:55:19 UTC 2017 (1491832519026)
[jmeter] ... end of run
[echo] Bug55375 output files compared OK
batchtestserver:
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2508 -Jsample_variables=REFERENCE,JSESSIONID
[server] Created remote object: UnicastServerRef [liveRef: [endpoint:[67.195.81.186:46847](local),objID:[3fce67e9:15b5825c831:-7fff, 4669881312261778101]]]
[client] Creating summariser <summary>
[client] Created the tree successfully using testfiles/Bug54685.jmx
[client] Configuring remote engine: localhost:2508
[client] Starting remote engines
[client] Starting the test @ Mon Apr 10 13:55:26 UTC 2017 (1491832526474)
[server] Starting the test on host localhost:2508 @ Mon Apr 10 13:55:30 UTC 2017 (1491832530908)
[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.8/s Avg: 287 Min: 287 Max: 287 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[client] summary = 1 in 00:00:01 = 1.8/s Avg: 287 Min: 287 Max: 287 Err: 0 (0.00%)
[client] Tidying up remote @ Mon Apr 10 13:55:32 UTC 2017 (1491832532583)
[server] Finished the test on host localhost:2508 @ Mon Apr 10 13:55:32 UTC 2017 (1491832532567) - 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 13:55:41 UTC 2017 (1491832541877)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:09 = 0.9/s Avg: 162 Min: 0 Max: 349 Err: 3 (33.33%)
[jmeter] Tidying up ... @ Mon Apr 10 13:55:53 UTC 2017 (1491832553286)
[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 13:55:57 UTC 2017 (1491832557367)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 0.7/s Avg: 465 Min: 465 Max: 465 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 1 in 00:00:30 = 0.0/s Avg: 30105 Min: 30105 Max: 30105 Err: 0 (0.00%) Active: 1 Started: 2 Finished: 1
[jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15285 Min: 465 Max: 30105 Err: 0 (0.00%)
[jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15285 Min: 465 Max: 30105 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:56:30 UTC 2017 (1491832590468)
[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 13:56:36 UTC 2017 (1491832596952)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:19 = 0.2/s Avg: 5981 Min: 618 Max: 11450 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:56:56 UTC 2017 (1491832616598)
[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 13:57:01 UTC 2017 (1491832621639)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 0.9/s Avg: 946 Min: 946 Max: 946 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 1 in 00:00:13 = 0.1/s Avg: 11946 Min: 11946 Max: 11946 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 2 in 00:00:14 = 0.1/s Avg: 6446 Min: 946 Max: 11946 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:57:17 UTC 2017 (1491832637084)
[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 13:57:20 UTC 2017 (1491832640845)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 8 in 00:00:07 = 1.1/s Avg: 476 Min: 153 Max: 1424 Err: 0 (0.00%) Active: 1 Started: 4 Finished: 3
[jmeter] summary + 4 in 00:00:02 = 1.7/s Avg: 372 Min: 154 Max: 825 Err: 0 (0.00%) Active: 0 Started: 6 Finished: 6
[jmeter] summary = 12 in 00:00:09 = 1.3/s Avg: 442 Min: 153 Max: 1424 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:57:32 UTC 2017 (1491832652515)
[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 13:57:36 UTC 2017 (1491832656952)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 12 in 00:00:07 = 1.7/s Avg: 261 Min: 154 Max: 1236 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:57:46 UTC 2017 (1491832666180)
[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 13:57:50 UTC 2017 (1491832670456)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 7 in 00:01:03 = 0.1/s Avg: 343 Min: 157 Max: 846 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:58:56 UTC 2017 (1491832736244)
[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 13:59:01 UTC 2017 (1491832741703)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 0.9/s Avg: 120 Min: 120 Max: 120 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 2 in 00:00:00 = 50.0/s Avg: 1 Min: 1 Max: 2 Err: 0 (0.00%) Active: 0 Started: 2 Finished: 2
[jmeter] summary = 3 in 00:00:01 = 2.5/s Avg: 41 Min: 1 Max: 120 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:59:04 UTC 2017 (1491832744712)
[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 13:59:09 UTC 2017 (1491832749696)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:01 = 3.7/s Avg: 12 Min: 1 Max: 35 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:59:11 UTC 2017 (1491832751992)
[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 13:59:17 UTC 2017 (1491832757836)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 21 in 00:00:02 = 11.1/s Avg: 57 Min: 1 Max: 727 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:59:21 UTC 2017 (1491832761082)
[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 13:59:28 UTC 2017 (1491832768532)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.0/s Avg: 739 Min: 739 Max: 739 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 18 in 00:00:01 = 12.9/s Avg: 30 Min: 1 Max: 102 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 19 in 00:00:02 = 7.9/s Avg: 67 Min: 1 Max: 739 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:59:31 UTC 2017 (1491832771759)
[jmeter] ... end of run
[concat] 2017-04-10 13:59:31,455 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 13:59:31,458 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 13:59:31,459 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:r1790830]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790830]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790830]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790830]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790830]
[concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790830]
[concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790830]
[concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790830]
[concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
[concat] 2017-04-10 13:59:31,477 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 13:59:36 UTC 2017 (1491832776508)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 6 in 00:00:06 = 0.9/s Avg: 162 Min: 0 Max: 818 Err: 1 (16.67%)
[jmeter] Tidying up ... @ Mon Apr 10 13:59:45 UTC 2017 (1491832785336)
[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 32 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
Build failed in Jenkins: JMeter-trunk #6094
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/JMeter-trunk/6094/display/redirect?page=changes>
Changes:
[sebb] Don't hide IOError in the log file
[sebb] More Windows debug
------------------------------------------
[...truncated 143.63 KB...]
[jmeter] Tidying up ... @ Mon Apr 10 13:04:46 UTC 2017 (1491829486919)
[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 13:04:52 UTC 2017 (1491829492494)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 26 in 00:00:07 = 3.9/s Avg: 240 Min: 103 Max: 346 Err: 9 (34.62%) Active: 1 Started: 5 Finished: 4
[jmeter] summary + 4 in 00:00:06 = 0.6/s Avg: 199 Min: 117 Max: 287 Err: 0 (0.00%) Active: 0 Started: 10 Finished: 10
[jmeter] summary = 30 in 00:00:13 = 2.3/s Avg: 234 Min: 103 Max: 346 Err: 9 (30.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:05:06 UTC 2017 (1491829506342)
[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 13:05:11 UTC 2017 (1491829511077)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:01 = 2.6/s Avg: 230 Min: 151 Max: 309 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:05:13 UTC 2017 (1491829513543)
[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 13:05:17 UTC 2017 (1491829517844)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:01 = 17.2/s Avg: 1 Min: 0 Max: 11 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:05:19 UTC 2017 (1491829519487)
[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 13:05:23 UTC 2017 (1491829523856)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:01 = 1.2/s Avg: 320 Min: 320 Max: 320 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:05:26 UTC 2017 (1491829526660)
[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 13:05:31 UTC 2017 (1491829531316)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.2/s Avg: 282 Min: 282 Max: 282 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary = 1 in 00:00:01 = 1.1/s Avg: 282 Min: 282 Max: 282 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:05:34 UTC 2017 (1491829534016)
[jmeter] ... end of run
[echo] Bug55375 output files compared OK
batchtestserver:
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2547 -Jsample_variables=REFERENCE,JSESSIONID
[server] Created remote object: UnicastServerRef [liveRef: [endpoint:[67.195.81.186:49625](local),objID:[7a969f6e:15b57f83e2e:-7fff, 6302144080664983958]]]
[client] Creating summariser <summary>
[client] Created the tree successfully using testfiles/Bug54685.jmx
[client] Configuring remote engine: localhost:2547
[client] Starting remote engines
[client] Starting the test @ Mon Apr 10 13:05:41 UTC 2017 (1491829541703)
[client] Remote engines have been started
[client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[server] Starting the test on host localhost:2547 @ Mon Apr 10 13:05:45 UTC 2017 (1491829545206)
[client] summary = 1 in 00:00:00 = 2.1/s Avg: 131 Min: 131 Max: 131 Err: 0 (0.00%)
[client] Tidying up remote @ Mon Apr 10 13:05:46 UTC 2017 (1491829546718)
[server] Finished the test on host localhost:2547 @ Mon Apr 10 13:05:46 UTC 2017 (1491829546718) - 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 13:05:57 UTC 2017 (1491829557836)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:10 = 0.9/s Avg: 153 Min: 0 Max: 315 Err: 3 (33.33%)
[jmeter] Tidying up ... @ Mon Apr 10 13:06:08 UTC 2017 (1491829568754)
[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 13:06:13 UTC 2017 (1491829573642)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:31 = 0.1/s Avg: 15184 Min: 249 Max: 30120 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:06:46 UTC 2017 (1491829606153)
[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 13:06:52 UTC 2017 (1491829612634)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 2 in 00:00:07 = 0.3/s Avg: 3319 Min: 741 Max: 5897 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 1 in 00:00:11 = 0.1/s Avg: 11354 Min: 11354 Max: 11354 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 3 in 00:00:19 = 0.2/s Avg: 5997 Min: 741 Max: 11354 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:07:13 UTC 2017 (1491829633687)
[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 13:07:19 UTC 2017 (1491829639123)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 2 in 00:00:14 = 0.1/s Avg: 6567 Min: 1026 Max: 12108 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary = 2 in 00:00:14 = 0.1/s Avg: 6567 Min: 1026 Max: 12108 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:07:35 UTC 2017 (1491829655569)
[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 13:07:40 UTC 2017 (1491829660439)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 12 in 00:00:06 = 1.8/s Avg: 162 Min: 14 Max: 948 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:07:49 UTC 2017 (1491829669402)
[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 13:07:54 UTC 2017 (1491829674372)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 3 in 00:00:04 = 0.7/s Avg: 602 Min: 154 Max: 1278 Err: 0 (0.00%) Active: 1 Started: 2 Finished: 1
[jmeter] summary + 9 in 00:00:03 = 2.7/s Avg: 154 Min: 153 Max: 156 Err: 0 (0.00%) Active: 0 Started: 6 Finished: 6
[jmeter] summary = 12 in 00:00:08 = 1.6/s Avg: 266 Min: 153 Max: 1278 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:08:04 UTC 2017 (1491829684340)
[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 13:08:09 UTC 2017 (1491829689517)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 7 in 00:01:03 = 0.1/s Avg: 299 Min: 39 Max: 1029 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:09:15 UTC 2017 (1491829755053)
[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 13:09:19 UTC 2017 (1491829759521)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:01 = 2.0/s Avg: 47 Min: 1 Max: 137 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:09:22 UTC 2017 (1491829762208)
[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 13:09:27 UTC 2017 (1491829767161)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:01 = 4.6/s Avg: 14 Min: 1 Max: 24 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:09:28 UTC 2017 (1491829768758)
[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 13:09:34 UTC 2017 (1491829774477)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 21 in 00:00:04 = 5.5/s Avg: 99 Min: 3 Max: 1394 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:09:39 UTC 2017 (1491829779638)
[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 13:09:46 UTC 2017 (1491829786630)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 19 in 00:00:01 = 13.4/s Avg: 44 Min: 1 Max: 564 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 13:09:49 UTC 2017 (1491829789337)
[jmeter] ... end of run
[concat] 2017-04-10 13:09:49,168 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 13:09:49,170 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 13:09:49,171 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:r1790818]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790818]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790818]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790818]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790818]
[concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790818]
[concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790818]
[concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790818]
[concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
[concat] 2017-04-10 13:09:49,175 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 13:09:55 UTC 2017 (1491829795142)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 5 in 00:00:07 = 0.7/s Avg: 164 Min: 0 Max: 819 Err: 0 (0.00%) Active: 1 Started: 3 Finished: 2
[jmeter] summary + 1 in 00:00:00 = 6.7/s Avg: 120 Min: 120 Max: 120 Err: 1 (100.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 6 in 00:00:07 = 0.8/s Avg: 157 Min: 0 Max: 819 Err: 1 (16.67%)
[jmeter] Tidying up ... @ Mon Apr 10 13:10:03 UTC 2017 (1491829803917)
[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 33 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
Build failed in Jenkins: JMeter-trunk #6093
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/JMeter-trunk/6093/display/redirect?page=changes>
Changes:
[sebb] More Windows debug
------------------------------------------
[...truncated 143.82 KB...]
[jmeter] summary = 3 in 00:00:03 = 1.1/s Avg: 547 Min: 12 Max: 1135 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:04:52 UTC 2017 (1491825892970)
[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 12:04:58 UTC 2017 (1491825898116)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 2 in 00:00:01 = 2.2/s Avg: 199 Min: 114 Max: 284 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 28 in 00:00:12 = 2.4/s Avg: 232 Min: 103 Max: 350 Err: 9 (32.14%) Active: 0 Started: 10 Finished: 10
[jmeter] summary = 30 in 00:00:13 = 2.4/s Avg: 230 Min: 103 Max: 350 Err: 9 (30.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:05:11 UTC 2017 (1491825911940)
[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 12:05:17 UTC 2017 (1491825917792)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:01 = 2.4/s Avg: 256 Min: 250 Max: 263 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:05:19 UTC 2017 (1491825919798)
[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:05:24 UTC 2017 (1491825924718)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:00 = 24.9/s Avg: 1 Min: 0 Max: 7 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:05:26 UTC 2017 (1491825926180)
[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:05:31 UTC 2017 (1491825931015)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.9/s Avg: 172 Min: 172 Max: 172 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary = 1 in 00:00:01 = 1.9/s Avg: 172 Min: 172 Max: 172 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:05:33 UTC 2017 (1491825933656)
[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:05:39 UTC 2017 (1491825939084)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 1 in 00:00:01 = 1.5/s Avg: 311 Min: 311 Max: 311 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:05:40 UTC 2017 (1491825940814)
[jmeter] ... end of run
[echo] Bug55375 output files compared OK
batchtestserver:
batchtest:
[echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2973 -Jsample_variables=REFERENCE,JSESSIONID
[server] Created remote object: UnicastServerRef [liveRef: [endpoint:[67.195.81.186:41297](local),objID:[-2a433b19:15b57c16891:-7fff, 2945606472864281663]]]
[client] Creating summariser <summary>
[client] Created the tree successfully using testfiles/Bug54685.jmx
[client] Configuring remote engine: localhost:2973
[client] Starting remote engines
[client] Starting the test @ Mon Apr 10 12:05:48 UTC 2017 (1491825948776)
[server] Starting the test on host localhost:2973 @ Mon Apr 10 12:05:52 UTC 2017 (1491825952969)
[client] Remote engines have been started
[client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[server] Finished the test on host localhost:2973 @ Mon Apr 10 12:05:55 UTC 2017 (1491825955052) - exit requested.
[client] summary = 1 in 00:00:01 = 1.9/s Avg: 259 Min: 259 Max: 259 Err: 0 (0.00%)
[client] Tidying up remote @ Mon Apr 10 12:05:55 UTC 2017 (1491825955056)
[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:06:04 UTC 2017 (1491825964874)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 9 in 00:00:10 = 0.9/s Avg: 164 Min: 0 Max: 309 Err: 3 (33.33%)
[jmeter] Tidying up ... @ Mon Apr 10 12:06:16 UTC 2017 (1491825976347)
[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:06:20 UTC 2017 (1491825980825)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:32 = 0.1/s Avg: 15333 Min: 584 Max: 30082 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:06:53 UTC 2017 (1491826013995)
[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:07:00 UTC 2017 (1491826020470)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.3/s Avg: 602 Min: 602 Max: 602 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 2 in 00:00:18 = 0.1/s Avg: 8679 Min: 5940 Max: 11419 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 3 in 00:00:19 = 0.2/s Avg: 5987 Min: 602 Max: 11419 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:07:20 UTC 2017 (1491826040584)
[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:07:25 UTC 2017 (1491826045914)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 2 in 00:00:15 = 0.1/s Avg: 6697 Min: 1383 Max: 12011 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:07:42 UTC 2017 (1491826062372)
[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:07:47 UTC 2017 (1491826067305)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 12 in 00:00:09 = 1.3/s Avg: 435 Min: 153 Max: 1386 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:07:59 UTC 2017 (1491826079192)
[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:08:03 UTC 2017 (1491826083809)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 12 in 00:00:07 = 1.7/s Avg: 286 Min: 152 Max: 1523 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:08:12 UTC 2017 (1491826092753)
[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:08:18 UTC 2017 (1491826098238)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 7 in 00:01:03 = 0.1/s Avg: 306 Min: 154 Max: 617 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:09:23 UTC 2017 (1491826163122)
[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:09:28 UTC 2017 (1491826168122)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.3/s Avg: 72 Min: 72 Max: 72 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 2 in 00:00:00 = 38.5/s Avg: 2 Min: 1 Max: 3 Err: 0 (0.00%) Active: 0 Started: 2 Finished: 2
[jmeter] summary = 3 in 00:00:01 = 3.7/s Avg: 25 Min: 1 Max: 72 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:09:30 UTC 2017 (1491826170374)
[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:09:35 UTC 2017 (1491826175069)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 3 in 00:00:01 = 4.0/s Avg: 15 Min: 1 Max: 43 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:09:37 UTC 2017 (1491826177297)
[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:09:43 UTC 2017 (1491826183145)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 21 in 00:00:02 = 10.6/s Avg: 61 Min: 2 Max: 778 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:09:46 UTC 2017 (1491826186552)
[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:09:53 UTC 2017 (1491826193368)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary = 19 in 00:00:02 = 7.9/s Avg: 67 Min: 1 Max: 791 Err: 0 (0.00%)
[jmeter] Tidying up ... @ Mon Apr 10 12:09:56 UTC 2017 (1491826196696)
[jmeter] ... end of run
[concat] 2017-04-10 12:09:56,542 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 12:09:56,545 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many times (20)
[concat] 2017-04-10 12:09:56,547 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:r1790804]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790804]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790804]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790804]
[concat] at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790804]
[concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790804]
[concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790804]
[concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790804]
[concat] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
[concat] 2017-04-10 12:09:56,554 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:10:02 UTC 2017 (1491826202099)
[jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
[jmeter] summary + 1 in 00:00:01 = 1.3/s Avg: 727 Min: 727 Max: 727 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0
[jmeter] summary + 5 in 00:00:05 = 0.9/s Avg: 26 Min: 0 Max: 126 Err: 1 (20.00%) Active: 0 Started: 1 Finished: 1
[jmeter] summary = 6 in 00:00:06 = 1.0/s Avg: 143 Min: 0 Max: 727 Err: 1 (16.67%)
[jmeter] Tidying up ... @ Mon Apr 10 12:10:11 UTC 2017 (1491826211442)
[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 45 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
Build failed in Jenkins: JMeter-trunk #6092
Posted by Apache Jenkins Server <je...@builds.apache.org>.
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