jmeter-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: JMeter-trunk #6097
Date Mon, 10 Apr 2017 15:03:01 GMT
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

Mime
View raw message