From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (qmail 12306 invoked by alias); 10 Oct 2006 15:45:59 -0000 Received: (qmail 12298 invoked by uid 22791); 10 Oct 2006 15:45:58 -0000 X-Spam-Status: No, hits=-2.4 required=5.0 tests=AWL,BAYES_00,SPF_PASS X-Spam-Check-By: sourceware.org Received: from e33.co.us.ibm.com (HELO e33.co.us.ibm.com) (32.97.110.151) by sourceware.org (qpsmtpd/0.31) with ESMTP; Tue, 10 Oct 2006 15:45:53 +0000 Received: from d03relay04.boulder.ibm.com (d03relay04.boulder.ibm.com [9.17.195.106]) by e33.co.us.ibm.com (8.13.8/8.12.11) with ESMTP id k9AFjhxY028421 for ; Tue, 10 Oct 2006 11:45:43 -0400 Received: from d03av02.boulder.ibm.com (d03av02.boulder.ibm.com [9.17.195.168]) by d03relay04.boulder.ibm.com (8.13.6/8.13.6/NCO v8.1.1) with ESMTP id k9AFjhq8362484 for ; Tue, 10 Oct 2006 09:45:43 -0600 Received: from d03av02.boulder.ibm.com (loopback [127.0.0.1]) by d03av02.boulder.ibm.com (8.12.11.20060308/8.13.3) with ESMTP id k9AFjgOf005667 for ; Tue, 10 Oct 2006 09:45:42 -0600 Received: from [9.67.75.77] (wecm-9-67-75-77.wecm.ibm.com [9.67.75.77]) by d03av02.boulder.ibm.com (8.12.11.20060308/8.12.11) with ESMTP id k9AFjdMm005291 for ; Tue, 10 Oct 2006 09:45:40 -0600 Message-ID: <452BC8D8.4070809@us.ibm.com> Date: Tue, 10 Oct 2006 15:45:00 -0000 From: David Wilder User-Agent: Mozilla Thunderbird 1.0.7 (X11/20050923) X-Accept-Language: en-us, en MIME-Version: 1.0 To: systemtap@sourceware.org Subject: Timouts on s390 Content-Type: multipart/mixed; boundary="------------020503090900030000080004" X-Virus-Checked: Checked by ClamAV on sourceware.org X-IsSubscribed: yes Mailing-List: contact systemtap-help@sourceware.org; run by ezmlm Precedence: bulk List-Subscribe: List-Post: List-Help: , Sender: systemtap-owner@sourceware.org X-SW-Source: 2006-q4/txt/msg00062.txt.bz2 This is a multi-part message in MIME format. --------------020503090900030000080004 Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit Content-length: 2405 Last week on the call I reported three of the "installcheck" tests were timing out on s390. In all cases the expect scripts were using a 30sec time out values. Looking at the times reported by stap it appears that 30 seconds is just not enough for the s390. In all cases "Pass 4: compiled C into.." used most or all of the 30 sec. Unfortunately it is not saying much for the speed of the s390 CPUs, but that is not the strong point of the s390 :). To be honest the system I am using is 3000 miles away and I have no idea how current it is. According to /proc/cpuinfo bogomips per cpu: 614.40. Below is the results of my testing. I have attached a patch to increase the timeouts from 30 sec to 60 sec. for the affected tests. Test: systemtap.samples/profile.exp Pass 1: parsed user script and 52 library script(s) in 1350usr/50sys/1460real ms. Pass 2: analyzed script: 4 probe(s), 9 function(s), 5 global(s) in 970usr/100sys/1131real ms. Pass 3: translated to C into "/tmp/stapQxqUib/stap_23009.c" in 790usr/2130sys/3092real ms. Pass 4: compiled C into "stap_23009.ko" in 18460usr/1150sys/20024real ms. Pass 5: starting run. Pass 5: run completed in 20usr/110sys/27758real ms. Sum of pass 1 through pass 4: 1460+1131+3092+20024=25707ms ---- Test: systemtap.samples/queue_demo.exp Pass 1: parsed user script and 52 library script(s) in 1360usr/50sys/1492real ms. Pass 2: analyzed script: 10 probe(s), 17 function(s), 10 global(s) in 30usr/0sys/30real ms. Pass 3: translated to C into "/tmp/stapoWzHet/stap_25264.c" in 800usr/2140sys/3154real ms. Pass 4: compiled C into "stap_25264.ko" in 33090usr/1450sys/42043real ms. Pass 5: starting run. Pass 5: run completed in 20usr/70sys/10144real ms. Sum of pass 1 through pass 4: 1492+30+3154+42043= 46719ms ---- Test: systemtap.samples/pfaults.stp Pass 1: parsed user script and 52 library script(s) in 1360usr/50sys/1509real ms. Pass 2: analyzed script: 7 probe(s), 9 function(s), 7 global(s) in 810usr/40sys/876real ms. Pass 3: translated to C into "/tmp/stapURoVU6/stap_25860.c" in 780usr/2130sys/3098real ms. Pass 4: compiled C into "stap_25860.ko" in 19610usr/1170sys/22107real ms. Pass 5: starting run. Pass 5: run completed in 20usr/70sys/10184real ms. Sum of pass 1 through pass 4: 1487+1509+876+3098+22107=29077ms -- David Wilder IBM Linux Technology Center Beaverton, Oregon, USA dwilder@us.ibm.com (503)578-3789 --------------020503090900030000080004 Content-Type: text/x-patch; name="60sec-test_timeouts.patch" Content-Transfer-Encoding: 7bit Content-Disposition: inline; filename="60sec-test_timeouts.patch" Content-length: 1664 diff --git a/src/testsuite/systemtap.samples/pfaults.exp b/src/testsuite/systemtap.samples/pfaults.exp index b230cc6..1abf823 100644 --- a/src/testsuite/systemtap.samples/pfaults.exp +++ b/src/testsuite/systemtap.samples/pfaults.exp @@ -5,9 +5,9 @@ spawn stap -g $srcdir/$subdir/pfaults.st set pid $spawn_id set ok 0 expect { - -timeout 30 + -timeout 60 -re "Page fault tracking, start time" { incr ok; exp_continue } - -timeout 30 + -timeout 60 -re "Page fault tracking, end time" { incr ok; exp_continue } timeout { fail "$test (timeout)" } eof { } diff --git a/src/testsuite/systemtap.samples/profile.exp b/src/testsuite/systemtap.samples/profile.exp index 39b4587..f1dc1b7 100644 --- a/src/testsuite/systemtap.samples/profile.exp +++ b/src/testsuite/systemtap.samples/profile.exp @@ -4,7 +4,7 @@ if {![installtest_p]} { untested $test; spawn stap $srcdir/$subdir/profile.stp set ok 0 expect { - -timeout 30 -re {kernel.function[^\r]*ttime=[0-9]*\r} { incr ok; exp_continue } + -timeout 60 -re {kernel.function[^\r]*ttime=[0-9]*\r} { incr ok; exp_continue } timeout { fail "$test (timeout)" } eof { } } diff --git a/src/testsuite/systemtap.samples/queue_demo.exp b/src/testsuite/systemtap.samples/queue_demo.exp index 71feddd..bd98b7f 100644 --- a/src/testsuite/systemtap.samples/queue_demo.exp +++ b/src/testsuite/systemtap.samples/queue_demo.exp @@ -4,7 +4,7 @@ if {![installtest_p]} { untested $test; spawn stap $srcdir/$subdir/queue_demo.stp set ok 0 expect { - -timeout 30 + -timeout 60 -re {block-[^\r]*} { incr ok; exp_continue } timeout { fail "$test (timeout)" } eof { } --------------020503090900030000080004--