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/03/08 18:23:19 UTC

Build failed in Jenkins: JMeter-trunk #6013

See <https://builds.apache.org/job/JMeter-trunk/6013/display/redirect?page=changes>

Changes:

[mchassagneux] Fix NPE for addThreadLock transient object in remote mode
Bugzilla Id: 60829

------------------------------------------
[...truncated 463.97 KB...]
     [echo] Starting Bug52968 with file Bug52968.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Bug52968.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:18:13 UTC 2017 (1488997093285)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =     30 in 00:00:13 =    2.3/s Avg:   219 Min:   104 Max:   346 Err:     9 (30.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:18:27 UTC 2017 (1488997107561)
   [jmeter] ... end of run
     [echo] Bug52968 output files compared OK

batchtest:
     [echo] Starting Bug50898 with file Bug50898.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Bug50898.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:18:31 UTC 2017 (1488997111951)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      1 in 00:00:00 =    2.0/s Avg:   266 Min:   266 Max:   266 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary +      1 in 00:00:00 =    3.1/s Avg:   314 Min:   314 Max:   314 Err:     0 (0.00%) Active: 0 Started: 1 Finished: 1
   [jmeter] summary =      2 in 00:00:01 =    2.5/s Avg:   290 Min:   266 Max:   314 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:18:34 UTC 2017 (1488997114116)
   [jmeter] ... end of run
     [echo] Bug50898 output files compared OK

batchtest:
     [echo] Starting Bug56243 with file Bug56243.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Bug56243.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:18:38 UTC 2017 (1488997118748)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      9 in 00:00:00 =   23.1/s Avg:     0 Min:     0 Max:     6 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:18:40 UTC 2017 (1488997120464)
   [jmeter] ... end of run
     [echo] Bug56243 output files compared OK

batchtest:
     [echo] Starting Bug54685 with file Bug54685.jmx using -X -Dsample_variables=REFERENCE,JSESSIONID
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Bug54685.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:18:44 UTC 2017 (1488997124869)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      1 in 00:00:01 =    1.8/s Avg:   339 Min:   339 Max:   339 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:18:46 UTC 2017 (1488997126928)
   [jmeter] ... end of run
     [echo] Bug54685 output files compared OK

batchtest:
     [echo] Starting Bug55375 with file Bug55375.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Bug55375.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:18:51 UTC 2017 (1488997131160)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      1 in 00:00:01 =    1.9/s Avg:   209 Min:   209 Max:   209 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:18:53 UTC 2017 (1488997133303)
   [jmeter] ... end of run
     [echo] Bug55375 output files compared OK

batchtestserver:

batchtest:
     [echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2339 -Dsample_variables=REFERENCE,JSESSIONID
   [server] Created remote object: UnicastServerRef [liveRef: [endpoint:[10.10.3.184:47436](local),objID:[-51f1208c:15aaf2537fb:-7fff, -7325025615582494627]]]
   [client] Creating summariser <summary>
   [client] Created the tree successfully using testfiles/Bug54685.jmx
   [client] Configuring remote engine: localhost:2339
   [client] Starting remote engines
   [client] Starting the test @ Wed Mar 08 18:19:00 UTC 2017 (1488997140654)
   [client] Remote engines have been started
   [client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [server] Starting the test on host localhost:2339 @ Wed Mar 08 18:19:04 UTC 2017 (1488997144143)
   [client] summary +      1 in 00:00:01 =    1.7/s Avg:   238 Min:   238 Max:   238 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [client] summary =      1 in 00:00:01 =    1.7/s Avg:   238 Min:   238 Max:   238 Err:     0 (0.00%)
   [client] Tidying up remote @ Wed Mar 08 18:19:05 UTC 2017 (1488997145966)
   [server] Finished the test on host localhost:2339 @ Wed Mar 08 18:19:05 UTC 2017 (1488997145958) - exit requested.
   [client] ... end of run
     [echo] Bug54685 output files compared OK

batchtest:
     [echo] Starting Bug56811 with file Bug56811.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Bug56811.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:19:16 UTC 2017 (1488997156423)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      9 in 00:00:10 =    0.9/s Avg:   141 Min:     0 Max:   316 Err:     3 (33.33%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:19:28 UTC 2017 (1488997168109)
   [jmeter] ... end of run
     [echo] Bug56811 output files compared OK

batchtest:

batchtest:
     [echo] Starting Bug60607 with file Bug60607.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Bug60607.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:19:33 UTC 2017 (1488997173163)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      1 in 00:00:01 =    1.0/s Avg:   417 Min:   417 Max:   417 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary +      1 in 00:00:30 =    0.0/s Avg: 30130 Min: 30130 Max: 30130 Err:     0 (0.00%) Active: 1 Started: 2 Finished: 1
   [jmeter] summary =      2 in 00:00:31 =    0.1/s Avg: 15273 Min:   417 Max: 30130 Err:     0 (0.00%)
   [jmeter] summary =      2 in 00:00:31 =    0.1/s Avg: 15273 Min:   417 Max: 30130 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:20:05 UTC 2017 (1488997205960)
   [jmeter] ... end of run
     [echo] Bug60607 output files compared OK

batchtest:
     [echo] Starting SlowCharsFeature_HttpClient4 with file SlowCharsFeature.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/SlowCharsFeature.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:20:11 UTC 2017 (1488997211443)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      3 in 00:00:20 =    0.2/s Avg:  6116 Min:   981 Max: 11389 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary =      3 in 00:00:20 =    0.2/s Avg:  6116 Min:   981 Max: 11389 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:20:32 UTC 2017 (1488997232275)
   [jmeter] ... end of run
     [echo] SlowCharsFeature_HttpClient4 output files compared OK

batchtest:
     [echo] Starting SlowCharsFeature_Java with file SlowCharsFeature.jmx using -X -Djmeter.httpsampler=Java
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/SlowCharsFeature.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:20:37 UTC 2017 (1488997237898)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      2 in 00:00:14 =    0.1/s Avg:  6422 Min:   901 Max: 11943 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:20:52 UTC 2017 (1488997252719)
   [jmeter] ... end of run
     [echo] SlowCharsFeature_Java output files compared OK

batchtest:
     [echo] Starting Http4ImplPreemptiveBasicAuth with file Http4ImplPreemptiveBasicAuth.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Http4ImplPreemptiveBasicAuth.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:20:56 UTC 2017 (1488997256996)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      1 in 00:00:02 =    0.5/s Avg:   429 Min:   429 Max:   429 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary +     11 in 00:00:06 =    1.8/s Avg:   334 Min:   102 Max:  1412 Err:     0 (0.00%) Active: 0 Started: 6 Finished: 6
   [jmeter] summary =     12 in 00:00:08 =    1.5/s Avg:   342 Min:   102 Max:  1412 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:21:07 UTC 2017 (1488997267018)
   [jmeter] ... end of run
     [echo] Http4ImplPreemptiveBasicAuth output files compared OK

batchtest:
     [echo] Starting Http4ImplPreemptiveBasicAuth with file Http4ImplPreemptiveBasicAuth.jmx using -X -Djmeter.httpsampler=Java
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/Http4ImplPreemptiveBasicAuth.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:21:11 UTC 2017 (1488997271282)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =     12 in 00:00:07 =    1.8/s Avg:   217 Min:   103 Max:  1293 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:21:20 UTC 2017 (1488997280207)
   [jmeter] ... end of run
     [echo] Http4ImplPreemptiveBasicAuth output files compared OK

batchtest:
     [echo] Starting TestKeepAlive with file TestKeepAlive.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/TestKeepAlive.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:21:24 UTC 2017 (1488997284662)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      4 in 00:00:04 =    1.1/s Avg:   324 Min:   103 Max:   747 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary +      3 in 00:00:59 =    0.1/s Avg:   190 Min:   106 Max:   239 Err:     0 (0.00%) Active: 0 Started: 1 Finished: 1
   [jmeter] summary =      7 in 00:01:03 =    0.1/s Avg:   266 Min:   103 Max:   747 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:22:29 UTC 2017 (1488997349235)
   [jmeter] ... end of run
     [echo] TestKeepAlive output files compared OK

batchtest:
     [echo] Starting TestHeaderManager with file TestHeaderManager.jmx using -X -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/TestHeaderManager.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:22:33 UTC 2017 (1488997353378)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      1 in 00:00:01 =    1.8/s Avg:   100 Min:   100 Max:   100 Err:     0 (0.00%) Active: 1 Started: 1 Finished: 0
   [jmeter] summary +      2 in 00:00:00 =   25.3/s Avg:     6 Min:     3 Max:     9 Err:     0 (0.00%) Active: 0 Started: 2 Finished: 2
   [jmeter] summary =      3 in 00:00:01 =    4.8/s Avg:    37 Min:     3 Max:   100 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:22:35 UTC 2017 (1488997355277)
   [jmeter] ... end of run
     [echo] TestHeaderManager output files compared OK

batchtest:
     [echo] Starting TestHeaderManager with file TestHeaderManager.jmx using -X -Djmeter.httpsampler=Java
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/TestHeaderManager.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:22:39 UTC 2017 (1488997359334)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      3 in 00:00:00 =    6.8/s Avg:    17 Min:     0 Max:    44 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:22:41 UTC 2017 (1488997361354)
   [jmeter] ... end of run
     [echo] TestHeaderManager output files compared OK

batchtest:
     [echo] Starting TEST_HTTP_HttpClient4 with file TEST_HTTP.jmx using -X -Djmeter.httpsampler=HttpClient4
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/TEST_HTTP.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:22:46 UTC 2017 (1488997366912)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =     21 in 00:00:02 =    9.4/s Avg:    67 Min:     2 Max:   782 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:22:50 UTC 2017 (1488997370443)
   [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 -Djmeter.httpsampler=Java
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/TEST_HTTP.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:22:57 UTC 2017 (1488997377099)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary +      5 in 00:00:02 =    3.0/s Avg:   194 Min:     7 Max:   813 Err:     0 (0.00%) Active: 1 Started: 2 Finished: 1
   [jmeter] summary +     14 in 00:00:00 =   40.5/s Avg:    21 Min:     0 Max:   142 Err:     0 (0.00%) Active: 0 Started: 1 Finished: 1
   [jmeter] summary =     19 in 00:00:02 =    9.5/s Avg:    66 Min:     0 Max:   813 Err:     0 (0.00%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:23:00 UTC 2017 (1488997380647)
   [jmeter] ... end of run
   [concat] 2017-03-08 18:23:00,567 ERROR o.a.j.p.h.s.HTTPJavaImpl: readResponse: java.net.ProtocolException: Server redirected too many  times (20)
   [concat] 2017-03-08 18:23:00,575 ERROR o.a.j.p.h.s.HTTPJavaImpl: Cause: java.net.ProtocolException: Server redirected too many  times (20)
   [concat] 2017-03-08 18:23:00,575 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:r1786026]
   [concat] 	at org.apache.jmeter.protocol.http.sampler.HTTPJavaImpl.sample(HTTPJavaImpl.java:612) [ApacheJMeter_http.jar:r1786026]
   [concat] 	at org.apache.jmeter.protocol.http.sampler.HTTPSamplerProxy.sample(HTTPSamplerProxy.java:74) [ApacheJMeter_http.jar:r1786026]
   [concat] 	at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1189) [ApacheJMeter_http.jar:r1786026]
   [concat] 	at org.apache.jmeter.protocol.http.sampler.HTTPSamplerBase.sample(HTTPSamplerBase.java:1178) [ApacheJMeter_http.jar:r1786026]
   [concat] 	at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:491) [ApacheJMeter_core.jar:r1786026]
   [concat] 	at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:425) [ApacheJMeter_core.jar:r1786026]
   [concat] 	at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:254) [ApacheJMeter_core.jar:r1786026]
   [concat] 	at java.lang.Thread.run(Thread.java:745) [?:1.8.0_121]
   [concat] 2017-03-08 18:23:00,579 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 -Ddummy=dummy
   [jmeter] Creating summariser <summary>
   [jmeter] Created the tree successfully using testfiles/TestSchedulerWithTimer.jmx
   [jmeter] Starting the test @ Wed Mar 08 18:23:05 UTC 2017 (1488997385829)
   [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445
   [jmeter] summary =      6 in 00:00:06 =    0.9/s Avg:   173 Min:     0 Max:   738 Err:     1 (16.67%)
   [jmeter] Tidying up ...    @ Wed Mar 08 18:23:14 UTC 2017 (1488997394292)
   [jmeter] ... end of run

BUILD FAILED
<https://builds.apache.org/job/JMeter-trunk/ws/trunk/build.xml>:2779: The following error occurred while executing this line:
<https://builds.apache.org/job/JMeter-trunk/ws/trunk/build.xml>:2597: 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: 13 minutes 40 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 #6014

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/JMeter-trunk/6014/display/redirect?page=changes>