Timouts on s390

Timouts on s390

[David Wilder]

[-- Attachment #1: Type: text/plain, Size: 2405 bytes --]

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


[-- Attachment #2: 60sec-test_timeouts.patch --]
[-- Type: text/x-patch, Size: 1664 bytes --]

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 { }