[Octave-bug-tracker] [bug #55940] pause() takes too long if you call it a bunch

[Andrew Janke]

Follow-up Comment #37, bug #55940 (project octave):

Behavior seems about the same with the new code. I built an Octave from the
head of default, and the slow pause behavior still happens, kicking in after
about 200 calls to pause().


$ octave-default -q -f
octave:1> ver
----------------------------------------------------------------------
GNU Octave Version: 6.0.0 (hg id: 5fa8d1459b35 + patches)
GNU Octave License: GNU General Public License
Operating System: Darwin 18.6.0 Darwin Kernel Version 18.6.0: Thu Apr 25
23:16:27 PDT 2019; root:xnu-4903.261.4~2/RELEASE_X86_64 x86_64
----------------------------------------------------------------------
Package Name  | Version | Installation directory
--------------+---------+-----------------------
     control  |   3.1.0 | /Users/janke/octave/control-3.1.0
       image  |   2.8.1 | /Users/janke/octave/image-2.8.1
octave:2> for i = 1:100; t0 = tic; pause(0.1); te = toc(t0); fprintf('%.3f  ',
te); endfor
0.104  0.105  0.102  0.105  0.102  0.104  0.104  0.104  0.102  0.104  0.103 
0.104  0.104  0.102  0.101  0.103  0.101  0.102  0.100  0.104  0.104  0.100 
0.100  0.103  0.102  0.102  0.101  0.105  0.105  0.100  0.103  0.104  0.103 
0.104  0.100  0.100  0.105  0.101  0.102  0.104  0.104  0.100  0.101  0.105 
0.103  0.105  0.103  0.100  0.102  0.102  0.101  0.103  0.101  0.102  0.103 
0.101  0.103  0.102  0.102  0.101  0.103  0.105  0.100  0.103  0.105  0.102 
0.105  0.105  0.100  0.105  0.103  0.105  0.102  0.100  0.103  0.102  0.102 
0.100  0.104  0.105  0.105  0.101  0.101  0.105  0.102  0.103  0.105  0.102 
0.102  0.103  0.101  0.101  0.103  0.101  0.100  0.103  0.100  0.105  0.102 
0.103  octave:3>
octave:3> for i = 1:100; t0 = tic; pause(0.1); te = toc(t0); fprintf('%.3f  ',
te); endfor
0.100  0.105  0.101  0.104  0.104  0.104  0.100  0.105  0.102  0.100  0.102 
0.104  0.104  0.104  0.102  0.102  0.105  0.100  0.100  0.100  0.104  0.105 
0.103  0.102  0.100  0.101  0.105  0.103  0.101  0.105  0.105  0.103  0.105 
0.103  0.102  0.101  0.102  0.102  0.102  0.103  0.104  0.102  0.100  0.105 
0.103  0.103  0.102  0.104  0.101  0.102  0.103  0.104  0.102  0.100  0.100 
0.105  0.103  0.105  0.103  0.101  0.103  0.100  0.101  0.103  0.102  0.104 
0.103  0.101  0.101  0.101  0.100  0.101  0.103  0.104  0.105  0.101  0.104 
0.100  0.105  0.102  0.102  0.100  0.100  0.100  0.103  0.100  0.103  0.101 
0.102  0.105  0.104  0.102  0.100  0.100  0.105  0.103  0.101  0.102  0.103 
0.102  octave:4>
octave:4> for i = 1:100; t0 = tic; pause(0.1); te = toc(t0); fprintf('%.3f  ',
te); endfor
9.886  10.100  1.995  9.004  9.492  2.193  0.100  9.885  0.268  10.100  10.100
 10.100  10.100  10.100  7.839  10.100  10.100  10.100  10.100  10.100  10.100
 10.100  10.100  10.100  10.100  4.101  0.100  0.100  0.100  10.100  10.100 
10.100  10.100  3.927  10.100  10.100  5.528  10.100  0.446  4.933  0.100 
1.471  3.391  0.464  0.158  0.100  5.041  10.100  10.100  10.100  10.100 
0.630  10.100  5.920  10.100  10.100  10.100  10.100  10.100  10.100  10.100 
10.100  10.100  10.100  10.100  10.100  10.100  10.100  10.100  10.100  3.500 
10.100  10.100  10.100  10.100  10.100  10.100  0.356  3.030  0.120  0.104 
0.103  0.102  0.102  0.223  0.549  0.412  7.863  10.100  0.875  1.553  0.100 
10.100  10.100  10.100  4.155  1.534  10.100  5.539  10.100  octave:5>


> If it is a process scheduling issue, what happens if you give it an
increased priority using the nice shell command? 

I tried the `renice -n -20 -p PID` approach, and it had no apparent effect.
Pauses continued to take around 10 seconds for many of the calls:


octave:6> for i = 1:100; t0 = tic; pause(0.1); te = toc(t0); fprintf('%.3f  ',
te); endfor
1.545  0.100  0.800  10.100  5.822  5.668  1.427  1.456  0.848  0.252  4.243 
0.100  10.100  10.100  10.100  10.100  10.100  10.100  10.100  4.016  10.100


Similar behavior with `--no-line-editing`: after about 150 calls to pause, it
suddenly slows way down:


$ octave-default -q -f --no-line-editing
octave:1> ^[[A^[[A^C

octave:1> for i = 1:100; t0 = tic; pause(0.1); te = toc(t0); fprintf('%.3f  ',
te); endfor
0.104  0.105  0.100  0.100  0.102  0.103  0.101  0.101  0.103  0.100  0.100 
0.101  0.100  0.105  0.103  0.103  0.103  0.102  0.101  0.102  0.103  0.102 
0.103  0.101  0.102  0.101  0.102  0.102  0.102  0.101  0.100  0.104  0.100 
0.103  0.102  0.104  0.103  0.104  0.101  0.103  0.102  0.102  0.101  0.100 
0.105  0.102  0.103  0.100  0.100  0.103  0.101  0.103  0.104  0.100  0.101 
0.103  0.100  0.105  0.102  0.101  0.105  0.103  0.102  0.101  0.102  0.100 
0.103  0.105  0.100  0.100  0.100  0.101  0.103  0.101  0.102  0.102  0.102 
0.105  0.100  0.103  0.105  0.100  0.103  0.101  0.103  0.100  0.105  0.102 
0.105  0.104  0.101  0.103  0.102  0.103  0.101  0.101  0.103  0.103  0.104 
0.105  octave:2> for i = 1:100; t0 = tic; pause(0.1); te = toc(t0);
fprintf('%.3f  ', te); endfor
0.104  0.101  0.103  0.102  0.102  0.104  0.105  0.102  0.101  0.105  0.104 
0.102  0.102  0.105  0.103  0.105  0.102  0.102  0.104  0.102  0.100  0.101 
0.100  0.102  0.104  0.100  0.103  0.103  0.102  0.102  0.101  0.103  0.105 
0.103  0.103  0.104  0.100  0.105  0.105  0.102  0.104  0.103  0.103  0.102 
0.101  0.103  0.105  0.102  0.100  0.100  0.103  0.102  8.904  10.100  10.100 
10.100  10.100  10.100  10.100  10.100  10.100  10.100  10.100  7.395  7.880 
9.794  3.160  2.323  0.214  0.101  0.101  0.101  0.105  0.101  0.102  0.102 
0.105  0.100  0.100  0.103  0.135  0.623  0.253  10.100  10.100  10.100  8.501
 0.171  10.100  6.027  10.100  10.100  10.100  10.100  0.847  0.100  0.334 
0.100  0.111  0.109  octave:3>





    _______________________________________________________

Reply to this item at:

  <https://savannah.gnu.org/bugs/?55940>

_______________________________________________
  Message sent via Savannah
  https://savannah.gnu.org/