ASF Bugzilla – Attachment 36774 Details for
Bug 63723
Distributed testing: JMeter master ends distributed test though some threads still are active
Home
|
New
|
Browse
|
Search
|
[?]
|
Reports
|
Help
|
New Account
|
Log In
Remember
[x]
|
Forgot Password
Login:
[x]
Master log file
jmeter.log (text/plain), 12.97 KB, created by
isuk
on 2019-09-10 05:18:09 UTC
(
hide
)
Description:
Master log file
Filename:
MIME Type:
Creator:
isuk
Created:
2019-09-10 05:18:09 UTC
Size:
12.97 KB
patch
obsolete
>2019-09-10 14:02:34,930 INFO o.a.j.u.JMeterUtils: Setting Locale to en_EN >2019-09-10 14:02:34,946 INFO o.a.j.JMeter: Loading user properties from: user.properties >2019-09-10 14:02:34,962 INFO o.a.j.JMeter: Loading system properties from: system.properties >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: Copyright (c) 1998-2019 The Apache Software Foundation >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: Version 5.1.1 r1855137 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: java.version=11.0.4 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: java.vm.name=Java HotSpot(TM) 64-Bit Server VM >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: os.name=Windows 10 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: os.arch=amd64 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: os.version=10.0 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: file.encoding=Cp1252 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: java.awt.headless=true >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: Max memory =8589934592 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: Available Processors =8 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: Default Locale=English (EN) >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: JMeter Locale=English (EN) >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: JMeterHome=C:\Users\iskadigamuwa\Downloads\JMeter\apache-jmeter-5.1.1 >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: user.dir =C:\Users\iskadigamuwa\Downloads\JMeter\apache-jmeter-5.1.1\bin >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: PWD =C:\Users\iskadigamuwa\Downloads\JMeter\apache-jmeter-5.1.1\bin >2019-09-10 14:02:34,963 INFO o.a.j.JMeter: IP: 10.30.5.14 Name: NSFGHDQH2 FullName: NSFGHDQH2.acustaff.acu.edu.au >2019-09-10 14:02:34,979 INFO o.a.j.s.FileServer: Default base='C:\Users\iskadigamuwa\Downloads\JMeter\apache-jmeter-5.1.1\bin' >2019-09-10 14:02:34,979 INFO o.a.j.s.FileServer: Set new base='C:\Users\iskadigamuwa\Downloads\JMeter\Examples\Pre-QA - 1000 PStudentns at Feed page' >2019-09-10 14:02:35,097 INFO o.a.j.s.SaveService: Testplan (JMX) version: 2.2. Testlog (JTL) version: 2.2 >2019-09-10 14:02:35,120 INFO o.a.j.s.SaveService: Using SaveService properties version 5.0 >2019-09-10 14:02:35,123 INFO o.a.j.s.SaveService: Using SaveService properties file encoding UTF-8 >2019-09-10 14:02:35,126 INFO o.a.j.s.SaveService: Loading file: C:\Users\iskadigamuwa\Downloads\JMeter\Examples\Pre-QA - 1000 PStudentns at Feed page\Test Plan.jmx >2019-09-10 14:02:35,180 INFO o.a.j.p.h.s.HTTPSamplerBase: Parser for text/html is org.apache.jmeter.protocol.http.parser.LagartoBasedHtmlParser >2019-09-10 14:02:35,180 INFO o.a.j.p.h.s.HTTPSamplerBase: Parser for application/xhtml+xml is org.apache.jmeter.protocol.http.parser.LagartoBasedHtmlParser >2019-09-10 14:02:35,180 INFO o.a.j.p.h.s.HTTPSamplerBase: Parser for application/xml is org.apache.jmeter.protocol.http.parser.LagartoBasedHtmlParser >2019-09-10 14:02:35,180 INFO o.a.j.p.h.s.HTTPSamplerBase: Parser for text/xml is org.apache.jmeter.protocol.http.parser.LagartoBasedHtmlParser >2019-09-10 14:02:35,180 INFO o.a.j.p.h.s.HTTPSamplerBase: Parser for text/vnd.wap.wml is org.apache.jmeter.protocol.http.parser.RegexpHTMLParser >2019-09-10 14:02:35,180 INFO o.a.j.p.h.s.HTTPSamplerBase: Parser for text/css is org.apache.jmeter.protocol.http.parser.CssParser >2019-09-10 14:02:35,496 INFO o.a.j.e.KeyToolUtils: keytool found at 'keytool' >2019-09-10 14:02:35,496 INFO o.a.j.p.h.p.ProxyControl: HTTP(S) Test Script Recorder SSL Proxy will use keys that support embedded 3rd party resources in file C:\Users\iskadigamuwa\Downloads\JMeter\apache-jmeter-5.1.1\bin\proxyserver.jks >2019-09-10 14:02:35,518 INFO o.a.j.JMeter: Creating summariser <summary> >2019-09-10 14:02:35,521 INFO o.a.j.e.DistributedRunner: Configuring remote engine: 192.168.29.89:1099 >2019-09-10 14:02:35,522 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:35,730 INFO o.a.j.e.DistributedRunner: Configuring remote engine: 192.168.29.90:1099 >2019-09-10 14:02:35,730 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:35,747 INFO o.a.j.e.DistributedRunner: Starting remote engines >2019-09-10 14:02:35,747 INFO o.a.j.e.DistributedRunner: Starting the test @ Tue Sep 10 14:02:35 AEST 2019 (1568088155747) >2019-09-10 14:02:35,747 INFO o.a.j.e.ClientJMeterEngine: running clientengine run method >2019-09-10 14:02:35,747 INFO o.a.j.e.u.CompoundVariable: Note: Function class names must contain the string: '.functions.' >2019-09-10 14:02:35,747 INFO o.a.j.e.u.CompoundVariable: Note: Function class names must not contain the string: '.gui.' >2019-09-10 14:02:35,979 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:35,979 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:35,980 INFO o.a.j.s.SampleEvent: List of sample_variables: [] >2019-09-10 14:02:35,980 INFO o.a.j.s.StandardSampleSender: Using StandardSampleSender for this test run >2019-09-10 14:02:35,980 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:35,980 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,330 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,330 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,330 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,330 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,330 INFO o.a.j.s.StandardSampleSender: Using StandardSampleSender for this test run >2019-09-10 14:02:36,447 INFO o.a.j.e.ClientJMeterEngine: sent test to 192.168.29.89:1099 basedir='.' >2019-09-10 14:02:36,447 INFO o.a.j.e.ClientJMeterEngine: Sending properties {} >2019-09-10 14:02:36,447 INFO o.a.j.e.ClientJMeterEngine: sent run command to 192.168.29.89:1099 >2019-09-10 14:02:36,447 INFO o.a.j.e.ClientJMeterEngine: running clientengine run method >2019-09-10 14:02:36,447 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,447 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,447 INFO o.a.j.s.StandardSampleSender: Using StandardSampleSender for this test run >2019-09-10 14:02:36,447 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,447 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,463 INFO o.a.j.JMeter: Started remote host: 192.168.29.89:1099 (1568088156463) >2019-09-10 14:02:36,463 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,463 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,463 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,463 INFO o.a.j.r.RmiUtils: Disabling SSL for RMI as server.rmi.ssl.disable is set to 'true' >2019-09-10 14:02:36,463 INFO o.a.j.s.StandardSampleSender: Using StandardSampleSender for this test run >2019-09-10 14:02:36,521 INFO o.a.j.e.ClientJMeterEngine: sent test to 192.168.29.90:1099 basedir='.' >2019-09-10 14:02:36,521 INFO o.a.j.e.ClientJMeterEngine: Sending properties {} >2019-09-10 14:02:36,525 INFO o.a.j.e.ClientJMeterEngine: sent run command to 192.168.29.90:1099 >2019-09-10 14:02:36,525 INFO o.a.j.e.DistributedRunner: Remote engines have been started >2019-09-10 14:02:36,530 INFO o.a.j.JMeter: Started remote host: 192.168.29.90:1099 (1568088156530) >2019-09-10 14:02:36,930 INFO o.a.j.s.SampleResult: Note: Sample TimeStamps are START times >2019-09-10 14:02:36,930 INFO o.a.j.s.SampleResult: sampleresult.default.encoding is set to ISO-8859-1 >2019-09-10 14:02:36,930 INFO o.a.j.s.SampleResult: sampleresult.useNanoTime=true >2019-09-10 14:02:36,930 INFO o.a.j.s.SampleResult: sampleresult.nanoThreadSleep=5000 >2019-09-10 14:03:00,043 INFO o.a.j.r.Summariser: summary + 1000 in 00:00:24 = 42.4/s Avg: 913 Min: 28 Max: 21464 Err: 1 (0.10%) Active: 1000 Started: 1000 Finished: 0 >2019-09-10 14:03:30,013 INFO o.a.j.r.Summariser: summary + 901 in 00:00:30 = 30.1/s Avg: 3858 Min: 9 Max: 52243 Err: 5 (0.55%) Active: 1000 Started: 1000 Finished: 0 >2019-09-10 14:03:30,014 INFO o.a.j.r.Summariser: summary = 1901 in 00:00:54 = 35.5/s Avg: 2308 Min: 9 Max: 52243 Err: 6 (0.32%) >2019-09-10 14:04:00,030 INFO o.a.j.r.Summariser: summary + 2114 in 00:00:30 = 70.4/s Avg: 3570 Min: 4 Max: 78870 Err: 1 (0.05%) Active: 994 Started: 1000 Finished: 6 >2019-09-10 14:04:00,045 INFO o.a.j.r.Summariser: summary = 4015 in 00:01:24 = 48.0/s Avg: 2972 Min: 4 Max: 78870 Err: 7 (0.17%) >2019-09-10 14:04:30,071 INFO o.a.j.r.Summariser: summary + 1360 in 00:00:30 = 45.3/s Avg: 3658 Min: 9 Max: 108103 Err: 4 (0.29%) Active: 978 Started: 1000 Finished: 22 >2019-09-10 14:04:30,072 INFO o.a.j.r.Summariser: summary = 5375 in 00:01:54 = 47.3/s Avg: 3146 Min: 4 Max: 108103 Err: 11 (0.20%) >2019-09-10 14:05:00,030 INFO o.a.j.r.Summariser: summary + 1061 in 00:00:30 = 35.4/s Avg: 3898 Min: 5 Max: 100882 Err: 2 (0.19%) Active: 960 Started: 1000 Finished: 40 >2019-09-10 14:05:00,030 INFO o.a.j.r.Summariser: summary = 6436 in 00:02:24 = 44.8/s Avg: 3270 Min: 4 Max: 108103 Err: 13 (0.20%) >2019-09-10 14:05:30,002 INFO o.a.j.r.Summariser: summary + 2195 in 00:00:30 = 73.2/s Avg: 5176 Min: 2 Max: 159410 Err: 1 (0.05%) Active: 949 Started: 1000 Finished: 51 >2019-09-10 14:05:30,003 INFO o.a.j.r.Summariser: summary = 8631 in 00:02:54 = 49.7/s Avg: 3755 Min: 2 Max: 159410 Err: 14 (0.16%) >2019-09-10 14:06:00,014 INFO o.a.j.r.Summariser: summary + 4419 in 00:00:30 = 147.2/s Avg: 4192 Min: 2 Max: 188816 Err: 0 (0.00%) Active: 931 Started: 1000 Finished: 69 >2019-09-10 14:06:00,014 INFO o.a.j.r.Summariser: summary = 13050 in 00:03:24 = 64.1/s Avg: 3903 Min: 2 Max: 188816 Err: 14 (0.11%) >2019-09-10 14:06:30,014 INFO o.a.j.r.Summariser: summary + 3956 in 00:00:30 = 131.9/s Avg: 2256 Min: 1 Max: 147366 Err: 0 (0.00%) Active: 866 Started: 1000 Finished: 134 >2019-09-10 14:06:30,014 INFO o.a.j.r.Summariser: summary = 17006 in 00:03:54 = 72.8/s Avg: 3520 Min: 1 Max: 188816 Err: 14 (0.08%) >2019-09-10 14:07:00,031 INFO o.a.j.r.Summariser: summary + 3855 in 00:00:30 = 128.4/s Avg: 3112 Min: 1 Max: 169342 Err: 0 (0.00%) Active: 821 Started: 1000 Finished: 179 >2019-09-10 14:07:00,031 INFO o.a.j.r.Summariser: summary = 20861 in 00:04:24 = 79.1/s Avg: 3444 Min: 1 Max: 188816 Err: 14 (0.07%) >2019-09-10 14:07:30,003 INFO o.a.j.r.Summariser: summary + 6828 in 00:00:30 = 227.8/s Avg: 1549 Min: 1 Max: 179383 Err: 0 (0.00%) Active: 744 Started: 1000 Finished: 256 >2019-09-10 14:07:30,004 INFO o.a.j.r.Summariser: summary = 27689 in 00:04:54 = 94.3/s Avg: 2977 Min: 1 Max: 188816 Err: 14 (0.05%) >2019-09-10 14:08:00,015 INFO o.a.j.r.Summariser: summary + 8108 in 00:00:30 = 270.2/s Avg: 976 Min: 1 Max: 233208 Err: 0 (0.00%) Active: 590 Started: 1000 Finished: 410 >2019-09-10 14:08:00,015 INFO o.a.j.r.Summariser: summary = 35797 in 00:05:24 = 110.6/s Avg: 2524 Min: 1 Max: 233208 Err: 14 (0.04%) >2019-09-10 14:08:30,000 INFO o.a.j.r.Summariser: summary + 6578 in 00:00:30 = 219.4/s Avg: 3657 Min: 1 Max: 346061 Err: 9 (0.14%) Active: 417 Started: 1000 Finished: 583 >2019-09-10 14:08:30,001 INFO o.a.j.r.Summariser: summary = 42375 in 00:05:54 = 119.9/s Avg: 2700 Min: 1 Max: 346061 Err: 23 (0.05%) >2019-09-10 14:09:00,005 INFO o.a.j.r.Summariser: summary + 9262 in 00:00:30 = 308.7/s Avg: 2113 Min: 1 Max: 372109 Err: 2 (0.02%) Active: 244 Started: 1000 Finished: 756 >2019-09-10 14:09:00,006 INFO o.a.j.r.Summariser: summary = 51637 in 00:06:24 = 134.6/s Avg: 2594 Min: 1 Max: 372109 Err: 25 (0.05%) >2019-09-10 14:13:30,000 INFO o.a.j.r.Summariser: summary + 8155 in 00:04:30 = 30.2/s Avg: 419 Min: 1 Max: 641443 Err: 0 (0.00%) Active: 4 Started: 1000 Finished: 996 >2019-09-10 14:13:30,000 INFO o.a.j.r.Summariser: summary = 59792 in 00:10:54 = 91.5/s Avg: 2298 Min: 1 Max: 641443 Err: 25 (0.04%) >2019-09-10 14:14:00,051 INFO o.a.j.r.Summariser: summary + 153 in 00:00:30 = 5.1/s Avg: 8824 Min: 1 Max: 676464 Err: 2 (1.31%) Active: 2 Started: 1000 Finished: 998 >2019-09-10 14:14:00,051 INFO o.a.j.r.Summariser: summary = 59945 in 00:11:24 = 87.7/s Avg: 2314 Min: 1 Max: 676464 Err: 27 (0.05%) >2019-09-10 14:14:00,366 INFO o.a.j.JMeter: Finished remote host: 192.168.29.90:1099 (1568088840366) >2019-09-10 14:14:05,384 INFO o.a.j.JMeter: Interrupting RMI Reaper >2019-09-10 14:14:05,384 INFO o.a.j.r.ResultCollector: Shutdown hook started >2019-09-10 14:14:05,384 INFO o.a.j.r.ResultCollector: Shutdown hook ended
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 63723
:
36754
|
36755
|
36756
|
36757
|
36772
|
36773
| 36774 |
36775
|
36776
|
36777
|
36778
|
36780
|
36781
|
36785
|
36786
|
36787
|
36788
|
36795
|
36796
|
36865