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 14:53:48 UTC

Build failed in Jenkins: JMeter Windows #586

See <https://builds.apache.org/job/JMeter%20Windows/586/display/redirect?page=changes>

Changes:

[mchassagneux] Don't cumul intial delay + ramp-up delay for a new thread create in the fly.
Bugzilla Id: 60530

[sebb] Debug Jenkins error: add a log message in case test fails

------------------------------------------
[...truncated 124.41 KB...]
   [jmeter] Tidying up ...    @ Mon Apr 10 14:49:27 UTC 2017 (1491835767957)
   [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:49:31 UTC 2017 (1491835771370)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      1 in 00:00:00 =    4.7/s Avg:    12 Min:    12 Max:    12 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary +      8 in 00:00:00 =  173.9/s Avg:     0 Min:     0 Max:     1 Err:     0 (0.00%) Active: 0 Started: 1 Finished: 1
   [jmeter] summary =      9 in 00:00:00 =   34.7/s Avg:     1 Min:     0 Max:    12 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:49:32 UTC 2017 (1491835772497)
   [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:49:35 UTC 2017 (1491835775912)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      1 in 00:00:00 =    2.3/s Avg:   216 Min:   216 Max:   216 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:49:37 UTC 2017 (1491835777513)
   [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:49:40 UTC 2017 (1491835780588)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      1 in 00:00:00 =    2.5/s Avg:   269 Min:   269 Max:   269 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:49:41 UTC 2017 (1491835781953)
   [jmeter] ... end of run
     [echo] Bug55375 output files compared OK

batchtestserver:

batchtest:
     [echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2112 -Jsample_variables=REFERENCE,JSESSIONID
   [server] Created remote object: UnicastServerRef [liveRef: [endpoint:[10.20.2.147:63062](local),objID:[-39dc2ae6:15b58578d96:-7fff, 1372807733813254184]]]
   [client] Creating summariser <summary>
   [client] Created the tree successfully using testfiles/Bug54685.jmx
   [client] Configuring remote engine: localhost:2112
   [client] Starting remote engines
   [client] Starting the test @ Mon Apr 10 14:49:47 UTC 2017 (1491835787554)
   [client] Remote engines have been started
   [client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [server] Starting the test on host localhost:2112 @ Mon Apr 10 14:49:49 UTC 2017 (1491835789364)
   [client] summary =      1 in 00:00:01 =    1.9/s Avg:   353 Min:   353 Max:   353 Err:     0 (0.00%)
   [client] Tidying up remote @ Mon Apr 10 14:49:50 UTC 2017 (1491835790815)
   [server] Finished the test on host localhost:2112 @ Mon Apr 10 14:49:50 UTC 2017 (1491835790815) - 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:49:59 UTC 2017 (1491835799151)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      9 in 00:00:09 =    1.0/s Avg:   178 Min:     1 Max:   373 Err:     3 (33.33%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:50:09 UTC 2017 (1491835809119)
   [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:50:12 UTC 2017 (1491835812201)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      2 in 00:00:31 =    0.1/s Avg: 15170 Min:   227 Max: 30114 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:50:43 UTC 2017 (1491835843757)
   [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:50:47 UTC 2017 (1491835847563)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      3 in 00:00:18 =    0.2/s Avg:  5851 Min:   640 Max: 11130 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:51:06 UTC 2017 (1491835866542)
   [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:51:10 UTC 2017 (1491835870420)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      2 in 00:00:13 =    0.2/s Avg:  6084 Min:   633 Max: 11535 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:51:23 UTC 2017 (1491835883974)
   [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:51:27 UTC 2017 (1491835887082)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      1 in 00:00:01 =    0.7/s Avg:   222 Min:   222 Max:   222 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary +     11 in 00:00:04 =    3.1/s Avg:   119 Min:    14 Max:   830 Err:     0 (0.00%) Active: 0 Started: 6 Finished: 6
   [jmeter] summary =     12 in 00:00:05 =    2.4/s Avg:   128 Min:    14 Max:   830 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:51:34 UTC 2017 (1491835894235)
   [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:51:37 UTC 2017 (1491835897450)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =     12 in 00:00:04 =    2.8/s Avg:    80 Min:    14 Max:   755 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:51:43 UTC 2017 (1491835903313)
   [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:51:47 UTC 2017 (1491835907326)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      7 in 00:01:02 =    0.1/s Avg:   147 Min:    16 Max:   705 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:52:50 UTC 2017 (1491835970336)
   [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:52:54 UTC 2017 (1491835974043)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      3 in 00:00:07 =    0.5/s Avg:  2046 Min:  2002 Max:  2133 Err:     0 (0.00%) Active: 1 Started: 2 Finished: 1
   [jmeter] summary =      3 in 00:00:07 =    0.5/s Avg:  2046 Min:  2002 Max:  2133 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:53:01 UTC 2017 (1491835981631)
   [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:53:05 UTC 2017 (1491835985031)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      3 in 00:00:03 =    0.9/s Avg:  1015 Min:  1003 Max:  1033 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:53:09 UTC 2017 (1491835989180)
   [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:53:13 UTC 2017 (1491835993405)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =     21 in 00:00:02 =    9.7/s Avg:    70 Min:     2 Max:   735 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:53:16 UTC 2017 (1491835996381)
   [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:53:21 UTC 2017 (1491836001257)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =     19 in 00:00:02 =   11.4/s Avg:    64 Min:     1 Max:   656 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:53:23 UTC 2017 (1491836003768)
   [jmeter] ... end of run
   [concat] 2017-04-10 14:53:23,657 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many  times (20)
   [concat] 2017-04-10 14:53:23,661 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many  times (20)
   [concat] 2017-04-10 14:53:23,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:r1790839]
   [concat] 	at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1790839]
   [concat] 	at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1790839]
   [concat] 	at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1790839]
   [concat] 	at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1790839]
   [concat] 	at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1790839]
   [concat] 	at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1790839]
   [concat] 	at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1790839]
   [concat] 	at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
   [concat] 2017-04-10 14:53:23,674 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:53:28 UTC 2017 (1491836008092)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      1 in 00:00:01 =    1.5/s Avg:   642 Min:   642 Max:   642 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary +      5 in 00:00:05 =    0.9/s Avg:    30 Min:     0 Max:   149 Err:     0 (0.00%) Active: 0 Started: 1 Finished: 1
   [jmeter] summary =      6 in 00:00:06 =    1.0/s Avg:   132 Min:     0 Max:   642 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Mon Apr 10 14:53:35 UTC 2017 (1491836015631)
   [jmeter] ... end of run
     [echo] TestSchedulerWithTimer output files compared OK

_allbatchtests:

test:

download_checkstyle:

_process_checkstyle_jars:

_check_exists:

_check_jarfile:
     [echo] Checking checkstyle-6.13-all.jar
     [echo] Deleting checkstyle-6.13-all.jar (excluding checkstyle-6.13-all.jar)

setproxy:

_get_jarfile:

setproxy:

_get_zipfile:

checkstyle:

_process_checkstyle_jars:

_check_exists:

_check_jarfile:
[checkstyle] Running Checkstyle 6.13 on 1045 files
[checkstyle] <https://builds.apache.org/job/JMeter%20Windows/ws/trunk\src\core\org\apache\jmeter\threads\ThreadGroup.java>:388:1: error: File contains tab characters (this is the first instance).
Target 'checkstyle' failed with message 'Got 1 errors and 0 warnings.'.

BUILD FAILED
<https://builds.apache.org/job/JMeter%20Windows/ws/trunk\build.xml>:3380: Got 1 errors and 0 warnings.

Total time: 9 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure

Jenkins build is back to normal : JMeter Windows #587

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/JMeter%20Windows/587/display/redirect?page=changes>