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 15:16:44 UTC
Build failed in Jenkins: JMeter-trunk #6098
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
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>