See <https://builds.apache.org/job/JMeter-trunk/6372/display/redirect?page=changes>
Changes: [pmouawad] Bug 61642 - Improve FTP test coverage Avoid error log on close error Bugzilla Id: 61642 [pmouawad] Bug 61651 - Improve TCP test coverage Bugzilla Id: 61651 ------------------------------------------ [...truncated 156.83 KB...] [client] Starting the test @ Mon Oct 23 16:03:05 UTC 2017 (1508774585971) [client] Remote engines have been started [client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [server] Starting the test on host localhost:2369 @ Mon Oct 23 16:03:07 UTC 2017 (1508774587519) [client] summary = 133 in 00:00:51 = 2.6/s Avg: 131 Min: 0 Max: 255 Err: 16 (12.03%) [client] Tidying up remote @ Mon Oct 23 16:03:59 UTC 2017 (1508774639287) [server] Finished the test on host localhost:2369 @ Mon Oct 23 16:03:59 UTC 2017 (1508774639287) - exit requested. [client] ... end of run [echo] BatchTestLocal output files compared OK batch_scripts: batchtest: [echo] Starting HTMLParserTestFile_2 with file HTMLParserTestFile_2.jmx using -X -Jdummy=dummy [jmeter] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/HTMLParserTestFile_2.jmx [jmeter] Starting the test @ Mon Oct 23 16:04:06 UTC 2017 (1508774646518) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 1 in 00:00:00 = 3.7/s Avg: 117 Min: 117 Max: 117 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:04:07 UTC 2017 (1508774647375) [jmeter] ... end of run [echo] HTMLParserTestFile_2 output files compared OK batchtest: [echo] Starting Bug52310 with file Bug52310.jmx using -X -Jdummy=dummy [jmeter] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/Bug52310.jmx [jmeter] Starting the test @ Mon Oct 23 16:04:09 UTC 2017 (1508774649565) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 3 in 00:00:01 = 3.1/s Avg: 116 Min: 10 Max: 295 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:04:11 UTC 2017 (1508774651099) [jmeter] ... end of run [echo] Bug52310 output files compared OK batchtest: [echo] Starting Bug52968 with file Bug52968.jmx using -X -Jdummy=dummy [jmeter] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/Bug52968.jmx [jmeter] Starting the test @ Mon Oct 23 16:04:13 UTC 2017 (1508774653270) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 30 in 00:00:13 = 2.4/s Avg: 226 Min: 112 Max: 350 Err: 9 (30.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:04:26 UTC 2017 (1508774666532) [jmeter] ... end of run [echo] Bug52968 output files compared OK batchtest: [echo] Starting Bug50898 with file Bug50898.jmx using -X -Jdummy=dummy [jmeter] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/Bug50898.jmx [jmeter] Starting the test @ Mon Oct 23 16:04:28 UTC 2017 (1508774668736) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:01 = 3.0/s Avg: 254 Min: 250 Max: 259 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:04:29 UTC 2017 (1508774669971) [jmeter] ... end of run [echo] Bug50898 output files compared OK batchtest: [echo] Starting Bug56243 with file Bug56243.jmx using -X -Jdummy=dummy [jmeter] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/Bug56243.jmx [jmeter] Starting the test @ Mon Oct 23 16:04:32 UTC 2017 (1508774672113) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:00 = 5.4/s Avg: 5 Min: 5 Max: 5 Err: 0 (0.00%) Active: 1 Started: 1 Finished: 0 [jmeter] summary + 8 in 00:00:00 = 266.7/s Avg: 0 Min: 0 Max: 1 Err: 0 (0.00%) Active: 0 Started: 1 Finished: 1 [jmeter] summary = 9 in 00:00:00 = 41.9/s Avg: 0 Min: 0 Max: 5 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:04:32 UTC 2017 (1508774672903) [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] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/Bug54685.jmx [jmeter] Starting the test @ Mon Oct 23 16:04:35 UTC 2017 (1508774675051) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 1 in 00:00:00 = 2.7/s Avg: 214 Min: 214 Max: 214 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:04:36 UTC 2017 (1508774676010) [jmeter] ... end of run [echo] Bug54685 output files compared OK batchtest: [echo] Starting Bug55375 with file Bug55375.jmx using -X -Jdummy=dummy [jmeter] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/Bug55375.jmx [jmeter] Starting the test @ Mon Oct 23 16:04:38 UTC 2017 (1508774678198) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 1 in 00:00:00 = 2.1/s Avg: 321 Min: 321 Max: 321 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:04:39 UTC 2017 (1508774679284) [jmeter] ... end of run [echo] Bug55375 output files compared OK batchtestserver: [server] SLF4J: Class path contains multiple SLF4J bindings. [server] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [server] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [server] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [server] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [server] Created remote object: UnicastServerRef [liveRef: [endpoint:[67.195.81.190:52118](local),objID:[44d10d72:15f49fa999b:-7fff, 8197833485152176656]]] batchtest: [echo] Starting Bug54685 with file Bug54685.jmx using -Rlocalhost:2369 -Jsample_variables=REFERENCE,JSESSIONID [client] SLF4J: Class path contains multiple SLF4J bindings. [client] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [client] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [client] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [client] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [client] Creating summariser <summary> [client] Created the tree successfully using testfiles/Bug54685.jmx [client] Configuring remote engine: localhost:2369 [client] Starting remote engines [client] Starting the test @ Mon Oct 23 16:04:43 UTC 2017 (1508774683607) [server] Starting the test on host localhost:2369 @ Mon Oct 23 16:04:45 UTC 2017 (1508774685048) [client] Remote engines have been started [client] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [client] summary = 1 in 00:00:00 = 2.3/s Avg: 268 Min: 268 Max: 268 Err: 0 (0.00%) [client] Tidying up remote @ Mon Oct 23 16:04:46 UTC 2017 (1508774686100) [server] Finished the test on host localhost:2369 @ Mon Oct 23 16:04:46 UTC 2017 (1508774686100) - 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] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/Bug56811.jmx [jmeter] Starting the test @ Mon Oct 23 16:04:53 UTC 2017 (1508774693378) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary + 1 in 00:00:08 = 0.1/s Avg: 231 Min: 231 Max: 231 Err: 0 (0.00%) Active: 1 Started: 4 Finished: 3 [jmeter] summary + 8 in 00:00:01 = 7.8/s Avg: 126 Min: 0 Max: 343 Err: 3 (37.50%) Active: 0 Started: 4 Finished: 4 [jmeter] summary = 9 in 00:00:09 = 1.0/s Avg: 138 Min: 0 Max: 343 Err: 3 (33.33%) [jmeter] Tidying up ... @ Mon Oct 23 16:05:02 UTC 2017 (1508774702563) [jmeter] ... end of run [echo] Bug56811 output files compared OK batchtest: [echo] Starting TEST_HTTPS with file TEST_HTTPS.jmx using -X -Jdummy=dummy [jmeter] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/TEST_HTTPS.jmx [jmeter] Starting the test @ Mon Oct 23 16:05:04 UTC 2017 (1508774704709) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] summary = 2 in 00:00:01 = 1.9/s Avg: 389 Min: 168 Max: 611 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:05:06 UTC 2017 (1508774706339) [jmeter] ... end of run [echo] TEST_HTTPS output files compared OK batchtest: [echo] Starting TCP_TESTS with file TCP_TESTS.jmx using -X -Jdummy=dummy [jmeter] SLF4J: Class path contains multiple SLF4J bindings. [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/log4j-slf4j-impl-2.8.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: Found binding in [jar:<https://builds.apache.org/job/JMeter-trunk/ws/trunk/lib/opt/activemq-all-5.15.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]> [jmeter] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [jmeter] SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [jmeter] Creating summariser <summary> [jmeter] Created the tree successfully using testfiles/TCP_TESTS.jmx [jmeter] Starting the test @ Mon Oct 23 16:05:08 UTC 2017 (1508774708572) [jmeter] Waiting for possible Shutdown/StopTestNow/Heapdump message on port 4445 [jmeter] 23/10/2017 16:05:11 [jmeter] [jmeter] [jmeter] summary = 9 in 00:00:04 = 2.2/s Avg: 419 Min: 1 Max: 1020 Err: 0 (0.00%) [jmeter] Tidying up ... @ Mon Oct 23 16:05:13 UTC 2017 (1508774713514) [jmeter] ... end of run [concat] 2017-10-23 16:05:11,438 ERROR o.a.j.p.t.s.TCPSampler: [concat] org.apache.jmeter.protocol.tcp.sampler.ReadException: Error reading from server, bytes read: 0 [concat] at org.apache.jmeter.protocol.tcp.sampler.TCPClientImpl.read(TCPClientImpl.java:131) ~[ApacheJMeter_tcp.jar:r1813037] [concat] at org.apache.jmeter.protocol.tcp.sampler.TCPSampler.sample(TCPSampler.java:403) [ApacheJMeter_tcp.jar:r1813037] [concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:498) [ApacheJMeter_core.jar:r1813037] [concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:424) [ApacheJMeter_core.jar:r1813037] [concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:255) [ApacheJMeter_core.jar:r1813037] [concat] at java.lang.Thread.run(Thread.java:748) [?:1.8.0_144] [concat] Caused by: java.net.SocketTimeoutException: Read timed out [concat] at java.net.SocketInputStream.socketRead0(Native Method) ~[?:1.8.0_144] [concat] at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) ~[?:1.8.0_144] [concat] at java.net.SocketInputStream.read(SocketInputStream.java:171) ~[?:1.8.0_144] [concat] at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_144] [concat] at java.net.SocketInputStream.read(SocketInputStream.java:127) ~[?:1.8.0_144] [concat] at org.apache.jmeter.protocol.tcp.sampler.TCPClientImpl.read(TCPClientImpl.java:114) ~[ApacheJMeter_tcp.jar:r1813037] [concat] ... 5 more [concat] 2017-10-23 16:05:12,509 ERROR o.a.j.p.t.s.TCPSampler: [concat] org.apache.jmeter.protocol.tcp.sampler.ReadException: [concat] at org.apache.jmeter.protocol.tcp.sampler.BinaryTCPClientImpl.read(BinaryTCPClientImpl.java:152) ~[ApacheJMeter_tcp.jar:r1813037] [concat] at org.apache.jmeter.protocol.tcp.sampler.TCPSampler.sample(TCPSampler.java:403) [ApacheJMeter_tcp.jar:r1813037] [concat] at org.apache.jmeter.threads.JMeterThread.executeSamplePackage(JMeterThread.java:498) [ApacheJMeter_core.jar:r1813037] [concat] at org.apache.jmeter.threads.JMeterThread.processSampler(JMeterThread.java:424) [ApacheJMeter_core.jar:r1813037] [concat] at org.apache.jmeter.threads.JMeterThread.run(JMeterThread.java:255) [ApacheJMeter_core.jar:r1813037] [concat] at java.lang.Thread.run(Thread.java:748) [?:1.8.0_144] [concat] Caused by: java.net.SocketTimeoutException: Read timed out [concat] at java.net.SocketInputStream.socketRead0(Native Method) ~[?:1.8.0_144] [concat] at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) ~[?:1.8.0_144] [concat] at java.net.SocketInputStream.read(SocketInputStream.java:171) ~[?:1.8.0_144] [concat] at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_144] [concat] at java.net.SocketInputStream.read(SocketInputStream.java:127) ~[?:1.8.0_144] [concat] at org.apache.jmeter.protocol.tcp.sampler.BinaryTCPClientImpl.read(BinaryTCPClientImpl.java:134) ~[ApacheJMeter_tcp.jar:r1813037] [concat] ... 5 more [echo] TCP_TESTS output files compared OK BUILD FAILED <https://builds.apache.org/job/JMeter-trunk/ws/trunk/build.xml>:2739: The following error occurred while executing this line: <https://builds.apache.org/job/JMeter-trunk/ws/trunk/build.xml>:2656: Error detected in log file. See above. Total time: 5 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