----- testRefresh testRefresh: setUp... !SESSION 2019-06-06 08:33:42.242 ----------------------------------------------- eclipse.buildId=4.11.0.I20190307-0500 java.version=1.8.0_201 java.vendor=Oracle Corporation BootLoader constants: OS=linux, ARCH=x86_64, WS=gtk, NL=en_US Framework arguments: -application org.eclipse.test.uitestapplication formatter=org.apache.tools.ant.taskdefs.optional.junit.XMLJUnitResultFormatter,/home/genie.releng/jenkins/workspace/ep412I-perf-lin64-baseline/workarea/I20190605-1800/eclipse-testing/test-eclipse/eclipse/org.eclipse.jface.tests.performance.JFacePerformanceSuite.xml -testPluginName org.eclipse.ui.tests.performance -className org.eclipse.jface.tests.performance.JFacePerformanceSuite -timeout 7200000 -junitReportOutput /home/genie.releng/jenkins/workspace/ep412I-perf-lin64-baseline/workarea/I20190605-1800/eclipse-testing/results/ep412I-perf-lin64-baseline_linux.gtk.x86_64_8.0 Command-line arguments: -application org.eclipse.test.uitestapplication -data /home/genie.releng/jenkins/workspace/ep412I-perf-lin64-baseline/workarea/I20190605-1800/eclipse-testing/test-eclipse/eclipse/performance-workspace-platform-jface formatter=org.apache.tools.ant.taskdefs.optional.junit.XMLJUnitResultFormatter,/home/genie.releng/jenkins/workspace/ep412I-perf-lin64-baseline/workarea/I20190605-1800/eclipse-testing/test-eclipse/eclipse/org.eclipse.jface.tests.performance.JFacePerformanceSuite.xml -testPluginName org.eclipse.ui.tests.performance -className org.eclipse.jface.tests.performance.JFacePerformanceSuite -os linux -ws gtk -arch x86_64 -consolelog -timeout 7200000 -junitReportOutput /home/genie.releng/jenkins/workspace/ep412I-perf-lin64-baseline/workarea/I20190605-1800/eclipse-testing/results/ep412I-perf-lin64-baseline_linux.gtk.x86_64_8.0 !ENTRY org.eclipse.test.performance 1 0 2019-06-06 08:34:20.104 !MESSAGE System property eclipse.perf.dbloc found to equal //172.25.25.57:1527 in getDBLocation !ENTRY org.eclipse.test.performance 1 0 2019-06-06 08:34:20.105 !MESSAGE System property eclipse.perf.dbloc found to equal //172.25.25.57:1527 in getDBName Trying to connect over network with // jdbc protocol; org.apache.derby.jdbc.ClientDriver to //172.25.25.57:1527/perfDB ... !ENTRY org.eclipse.test.performance 1 0 2019-06-06 08:34:20.111 !MESSAGE System property eclipse.perf.dbloc found to equal //172.25.25.57:1527 in getDBUser !ENTRY org.eclipse.test.performance 1 0 2019-06-06 08:34:20.112 !MESSAGE System property eclipse.perf.dbloc found to equal //172.25.25.57:1527 in getDBPassword SQLException: java.sql.SQLNonTransientConnectionException: java.net.ConnectException : Error connecting to server 172.25.25.57 on port 1,527 with message Connection timed out (Connection timed out). !ENTRY org.eclipse.test.performance 4 1 2019-06-06 08:36:27.414 !MESSAGE No suitable driver found for jdbc:cloudscape://172.25.25.57:1527/perfDB Scenario 'org.eclipse.jface.tests.performance.ListViewerRefreshTest#testRefresh()' (average over 20 samples): System Time: 1.27s (95% in [1.16s, 1.38s]) Measurable effect: 215ms (0.9 SDs) (required sample size for an effect of 5% of mean: 229) Used Java Heap: 2.83M (95% in [2.13M, 3.54M]) Measurable effect: 1.35M (0.9 SDs) (required sample size for an effect of 5% of mean: 1806) Working Set: 1.12M (95% in [-120.59K, 2.36M]) Measurable effect: 2.37M (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Elapsed Process: 1.27s (95% in [1.16s, 1.38s]) Measurable effect: 215ms (0.9 SDs) (required sample size for an effect of 5% of mean: 229) Kernel time: 2ms (95% in [0ms, 4ms]) Measurable effect: 3ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) CPU Time: 1.28s (95% in [1.16s, 1.4s]) Measurable effect: 228ms (0.9 SDs) (required sample size for an effect of 5% of mean: 253) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 413 (95% in [270, 555]) Measurable effect: 271 (0.9 SDs) (required sample size for an effect of 5% of mean: 3463) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: -422.2K (95% in [-1.25M, 434.54K]) Measurable effect: 1.6M (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:36:27.425 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testRefresh: tearDown... ----- testRefreshSmall testRefreshSmall: setUp... Scenario 'org.eclipse.jface.tests.performance.ComboViewerRefreshTest#testRefreshSmall()' (average over 20 samples): System Time: 3.52s (95% in [3.47s, 3.56s]) Measurable effect: 90ms (0.9 SDs) Used Java Heap: -1.73M (95% in [-34.39M, 30.94M]) Measurable effect: 62.43M (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) Working Set: 4.63M (95% in [354.91K, 8.91M]) Measurable effect: 8.18M (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Elapsed Process: 3.52s (95% in [3.47s, 3.56s]) Measurable effect: 90ms (0.9 SDs) Kernel time: 6ms (95% in [0ms, 11ms]) Measurable effect: 9ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) CPU Time: 3.55s (95% in [3.48s, 3.61s]) Measurable effect: 123ms (0.9 SDs) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 1.19K (95% in [91, 2.29K]) Measurable effect: 2.1K (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:37:37.872 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testRefreshSmall: tearDown... ----- testRefreshMultiple testRefreshMultiple: setUp... Scenario 'org.eclipse.jface.tests.performance.FastTableViewerRefreshTest#testRefreshMultiple()' (average over 20 samples): System Time: 154ms (95% in [9ms, 300ms]) Measurable effect: 277ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Used Java Heap: -6.8M (95% in [-33.66M, 20.06M]) Measurable effect: 51.33M (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) Working Set: 1,005.6K (95% in [-1,000.34K, 2.94M]) Measurable effect: 3.74M (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Elapsed Process: 154ms (95% in [9ms, 300ms]) Measurable effect: 277ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Kernel time: 1ms (95% in [0ms, 3ms]) Measurable effect: 4ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) CPU Time: 203ms (95% in [35ms, 370ms]) Measurable effect: 319ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 251 (95% in [-254, 758]) Measurable effect: 967 (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:37:42.766 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testRefreshMultiple: tearDown... ----- testUpdateMultiple testUpdateMultiple: setUp... Scenario 'org.eclipse.jface.tests.performance.FastTableViewerRefreshTest#testUpdateMultiple()' (average over 20 samples): System Time: 134ms (95% in [9ms, 259ms]) Measurable effect: 238ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Used Java Heap: 5.8M (95% in [5.04M, 6.56M]) Measurable effect: 1.45M (0.9 SDs) (required sample size for an effect of 5% of mean: 501) Working Set: 4.8K (95% in [-5.25K, 14.85K]) Measurable effect: 19.2K (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Elapsed Process: 134ms (95% in [9ms, 259ms]) Measurable effect: 238ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Kernel time: 0ms (95% in [0ms, 1ms]) Measurable effect: 2ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) CPU Time: 142ms (95% in [5ms, 279ms]) Measurable effect: 262ms (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 2 (95% in [-1, 6]) Measurable effect: 7 (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:37:47.128 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testUpdateMultiple: tearDown... ----- testAddTenTenTimes testAddTenTenTimes: setUp... Scenario 'org.eclipse.jface.tests.performance.FastTreeTest#testAddTenTenTimes()' (average over 46 samples): System Time: 219ms (95% in [214ms, 224ms]) Measurable effect: 10ms (0.6 SDs) Used Java Heap: -1.81M (95% in [-14.51M, 10.89M]) Measurable effect: 25.22M (0.6 SDs) (required sample size for an effect of 5% of stdev: 6400) Working Set: 337.39K (95% in [-17.2K, 691.98K]) Measurable effect: 704.21K (0.6 SDs) (required sample size for an effect of 5% of stdev: 6400) Elapsed Process: 219ms (95% in [214ms, 224ms]) Measurable effect: 10ms (0.6 SDs) Kernel time: 2ms (95% in [1ms, 3ms]) Measurable effect: 2ms (0.6 SDs) (required sample size for an effect of 5% of stdev: 6401) CPU Time: 239ms (95% in [221ms, 257ms]) Measurable effect: 35ms (0.6 SDs) (required sample size for an effect of 5% of mean: 401) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 86 (95% in [-4, 177]) Measurable effect: 180 (0.6 SDs) (required sample size for an effect of 5% of stdev: 6401) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:37:57.366 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testAddTenTenTimes: tearDown... ----- testAddFiftyTenTimes testAddFiftyTenTimes: setUp... Scenario 'org.eclipse.jface.tests.performance.FastTreeTest#testAddFiftyTenTimes()' (average over 100 samples): System Time: 92ms (95% in [91ms, 94ms]) Measurable effect: 2ms (0.4 SDs) Used Java Heap: -1.41M (95% in [-7.01M, 4.19M]) Measurable effect: 11.29M (0.4 SDs) (required sample size for an effect of 5% of stdev: 6400) Working Set: 2.4K (95% in [90, 4.71K]) Measurable effect: 4.66K (0.4 SDs) (required sample size for an effect of 5% of stdev: 6401) Elapsed Process: 92ms (95% in [91ms, 94ms]) Measurable effect: 2ms (0.4 SDs) Kernel time: 0ms (95% in [0ms, 0ms]) Measurable effect: 0ms (0.4 SDs) (required sample size for an effect of 5% of stdev: 6400) CPU Time: 97ms (95% in [93ms, 100ms]) Measurable effect: 6ms (0.4 SDs) (required sample size for an effect of 5% of mean: 189) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 0 (95% in [0, 1]) Measurable effect: 1 (0.4 SDs) (required sample size for an effect of 5% of stdev: 6400) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:38:06.835 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testAddFiftyTenTimes: tearDown... ----- testAddThousand testAddThousand: setUp... Scenario 'org.eclipse.jface.tests.performance.TreeAddTest#testAddThousand()' (average over 49 samples): System Time: 199ms (95% in [195ms, 203ms]) Measurable effect: 7ms (0.6 SDs) Used Java Heap: -1.02M (95% in [-12.77M, 10.73M]) Measurable effect: 23.38M (0.6 SDs) (required sample size for an effect of 5% of stdev: 6400) Working Set: 5.39K (95% in [-5.44K, 16.22K]) Measurable effect: 21.55K (0.6 SDs) (required sample size for an effect of 5% of stdev: 6400) Elapsed Process: 199ms (95% in [195ms, 203ms]) Measurable effect: 7ms (0.6 SDs) Kernel time: 0ms (95% in [0ms, 0ms]) Measurable effect: 0ms (0.6 SDs) (required sample size for an effect of 5% of stdev: 6401) CPU Time: 204ms (95% in [199ms, 209ms]) Measurable effect: 10ms (0.6 SDs) (required sample size for an effect of 5% of mean: 52) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 0 (95% in [0, 0]) Measurable effect: 0 (0.6 SDs) (required sample size for an effect of 5% of stdev: 6401) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:38:16.960 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testAddThousand: tearDown... ----- testAddHundredTenTimes testAddHundredTenTimes: setUp... LOCAL JFace - Add 10000 items 100 at a time TreeViewer 10 times Scenario 'org.eclipse.jface.tests.performance.FastTreeTest#testAddHundredTenTimes()' (average over 58 samples): System Time: 172ms (95% in [171ms, 174ms]) Measurable effect: 3ms (0.5 SDs) Used Java Heap: 3.48M (95% in [2.76M, 4.2M]) Measurable effect: 1.44M (0.5 SDs) (required sample size for an effect of 5% of mean: 3971) Working Set: 0 (95% in [0, 0]) Elapsed Process: 172ms (95% in [171ms, 174ms]) Measurable effect: 3ms (0.5 SDs) Kernel time: 0ms (95% in [0ms, 1ms]) Measurable effect: 1ms (0.5 SDs) (required sample size for an effect of 5% of stdev: 6400) CPU Time: 174ms (95% in [170ms, 177ms]) Measurable effect: 6ms (0.5 SDs) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 0 (95% in [0, 0]) Measurable effect: 0 (0.5 SDs) (required sample size for an effect of 5% of stdev: 6401) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:38:27.111 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testAddHundredTenTimes: tearDown... ----- testAddThousandPreSort testAddThousandPreSort: setUp... GLOBAL JFace - Add 2000 items in 2 blocks to TreeViewer Scenario 'org.eclipse.jface.tests.performance.TreeAddTest#testAddThousandPreSort()' (average over 51 samples): System Time: 169ms (95% in [167ms, 171ms]) Measurable effect: 3ms (0.6 SDs) Used Java Heap: -3.97M (95% in [-14.8M, 6.86M]) Measurable effect: 21.57M (0.6 SDs) (required sample size for an effect of 5% of stdev: 6400) Working Set: -181.1K (95% in [-544.85K, 182.66K]) Measurable effect: 724.39K (0.6 SDs) (required sample size for an effect of 5% of stdev: 6401) Elapsed Process: 169ms (95% in [167ms, 171ms]) Measurable effect: 3ms (0.6 SDs) Kernel time: 0ms (95% in [0ms, 0ms]) Measurable effect: 1ms (0.6 SDs) (required sample size for an effect of 5% of stdev: 6400) CPU Time: 176ms (95% in [172ms, 180ms]) Measurable effect: 7ms (0.6 SDs) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 0 (95% in [0, 0]) Measurable effect: 0 (0.6 SDs) (required sample size for an effect of 5% of stdev: 6400) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:38:37.273 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testAddThousandPreSort: tearDown... ----- testLongNames testLongNames: setUp... Scenario 'org.eclipse.jface.tests.performance.ProgressMonitorDialogPerformanceTest#testLongNames()' (average over 20 samples): System Time: 1.53s (95% in [1.5s, 1.56s]) Measurable effect: 51ms (0.9 SDs) Used Java Heap: -4.06M (95% in [-42.82M, 34.69M]) Measurable effect: 74.07M (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Working Set: -871.6K (95% in [-2.16M, 467.75K]) Measurable effect: 2.5M (0.9 SDs) (required sample size for an effect of 5% of stdev: 6400) Elapsed Process: 1.53s (95% in [1.5s, 1.56s]) Measurable effect: 51ms (0.9 SDs) Kernel time: 7ms (95% in [4ms, 9ms]) Measurable effect: 4ms (0.9 SDs) (required sample size for an effect of 5% of mean: 2888) CPU Time: 1.56s (95% in [1.5s, 1.61s]) Measurable effect: 104ms (0.9 SDs) (required sample size for an effect of 5% of mean: 37) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 14 (95% in [-2, 30]) Measurable effect: 30 (0.9 SDs) (required sample size for an effect of 5% of stdev: 6401) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:39:08.152 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testLongNames: tearDown... ----- testTreeViewerRefresh testTreeViewerRefresh: setUp... Scenario 'org.eclipse.jface.tests.performance.ShrinkingTreeTest#testTreeViewerRefresh()' (average over 100 samples): System Time: 14ms (95% in [14ms, 15ms]) Measurable effect: 0ms (0.4 SDs) (required sample size for an effect of 5% of mean: 108) Used Java Heap: 425K (95% in [150.43K, 699.56K]) Measurable effect: 553.5K (0.4 SDs) (required sample size for an effect of 5% of stdev: 6400) Working Set: 3.12K (95% in [-1012, 7.23K]) Measurable effect: 8.28K (0.4 SDs) (required sample size for an effect of 5% of stdev: 6401) Elapsed Process: 14ms (95% in [14ms, 15ms]) Measurable effect: 0ms (0.4 SDs) (required sample size for an effect of 5% of mean: 108) Kernel time: 0ms (95% in [0ms, 0ms]) Measurable effect: 0ms (0.4 SDs) (required sample size for an effect of 5% of stdev: 6401) CPU Time: 16ms (95% in [14ms, 17ms]) Measurable effect: 2ms (0.4 SDs) (required sample size for an effect of 5% of mean: 1152) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 0 (95% in [0, 0]) Measurable effect: 0 (0.4 SDs) (required sample size for an effect of 5% of stdev: 6401) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:39:14.266 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testTreeViewerRefresh: tearDown... ----- testCollator testCollator: setUp... Scenario 'org.eclipse.jface.tests.performance.CollatorPerformanceTest#testCollator()' (average over 15 samples): System Time: 13ms (95% in [8ms, 17ms]) Measurable effect: 8ms (1.0 SDs) (required sample size for an effect of 5% of mean: 2294) Used Java Heap: 350.08K (95% in [-352.75K, 1.03M]) Measurable effect: 1.28M (1.0 SDs) (required sample size for an effect of 5% of stdev: 6400) Working Set: 7.2K (95% in [-3.72K, 18.12K]) Measurable effect: 20.37K (1.0 SDs) (required sample size for an effect of 5% of stdev: 6401) Elapsed Process: 13ms (95% in [8ms, 17ms]) Measurable effect: 8ms (1.0 SDs) (required sample size for an effect of 5% of mean: 2294) Kernel time: 0ms (95% in [0ms, 2ms]) Measurable effect: 2ms (1.0 SDs) (required sample size for an effect of 5% of stdev: 6400) CPU Time: 20ms (95% in [9ms, 30ms]) Measurable effect: 18ms (1.0 SDs) (required sample size for an effect of 5% of mean: 5258) Hard Page Faults: 0 (95% in [0, 0]) Soft Page Faults: 3 (95% in [-1, 7]) Measurable effect: 8 (1.0 SDs) (required sample size for an effect of 5% of stdev: 6400) Text Size: 0 (95% in [0, 0]) Data Size: 0 (95% in [0, 0]) Library Size: 0 (95% in [0, 0]) !ENTRY org.eclipse.test.performance 2 0 2019-06-06 08:39:14.488 !MESSAGE refkeys was null. eclipse.perf.assertAgainst was null testCollator: tearDown... no new datapoints in DB disconnecting from DB