X-Git-Url: http://git.shadowcat.co.uk/gitweb/gitweb.cgi?p=scpubgit%2FObject-Remote.git;a=blobdiff_plain;f=lib%2FObject%2FRemote%2FMiniLoop.pm;h=faa4a38d371911d60ecfaa452447ba3f8eb5c772;hp=932d0b9f196c5346f6716f70bbcb20acf10cb9a8;hb=8c3529062a426181861d58ee59fb8f10e0be68e5;hpb=a6786ddab559c869f448c1cb963e83faeb5efd40 diff --git a/lib/Object/Remote/MiniLoop.pm b/lib/Object/Remote/MiniLoop.pm index 932d0b9..faa4a38 100644 --- a/lib/Object/Remote/MiniLoop.pm +++ b/lib/Object/Remote/MiniLoop.pm @@ -2,12 +2,20 @@ package Object::Remote::MiniLoop; use IO::Select; use Time::HiRes qw(time); -use Object::Remote::Logging qw( :log :dlog ); +use Object::Remote::Logging qw( :log :dlog router ); use Moo; -# this is ro because we only actually set it using local in sub run +BEGIN { + $SIG{PIPE} = sub { log_debug { "Got a PIPE signal" } }; + + router()->exclude_forwarding +} +# this is ro because we only actually set it using local in sub run has is_running => (is => 'ro', clearer => 'stop'); +#maximum duration that select() will block - undef means indefinite, +#0 means no blocking, otherwise maximum time in seconds +has block_duration => ( is => 'rw' ); has _read_watches => (is => 'ro', default => sub { {} }); has _read_select => (is => 'ro', default => sub { IO::Select->new }); @@ -37,14 +45,15 @@ sub pass_watches_to { sub watch_io { my ($self, %watch) = @_; my $fh = $watch{handle}; - log_debug { my $type = ref($fh); "Adding watch for ref of type '$type'" }; + Dlog_debug { "Adding IO watch for $_" } $fh; + if (my $cb = $watch{on_read_ready}) { - log_trace { "IO watcher on_read_ready has been invoked" }; + log_trace { "IO watcher is registering with select for reading" }; $self->_read_select->add($fh); $self->_read_watches->{$fh} = $cb; } if (my $cb = $watch{on_write_ready}) { - log_trace { "IO watcher on_write_ready has been invoked" }; + log_trace { "IO watcher is registering with select for writing" }; $self->_write_select->add($fh); $self->_write_watches->{$fh} = $cb; } @@ -54,35 +63,57 @@ sub watch_io { sub unwatch_io { my ($self, %watch) = @_; my $fh = $watch{handle}; - log_debug { my $type = ref($fh); "Removing watch for ref of type '$type'" }; + Dlog_debug { "Removing IO watch for $_" } $fh; if ($watch{on_read_ready}) { + log_trace { "IO watcher is removing read from select()" }; $self->_read_select->remove($fh); delete $self->_read_watches->{$fh}; } if ($watch{on_write_ready}) { + log_trace { "IO watcher is removing write from select()" }; $self->_write_select->remove($fh); delete $self->_write_watches->{$fh}; } return; } +sub _sort_timers { + my ($self, @new) = @_; + my $timers = $self->_timers; + + log_trace { "Sorting timers" }; + + @{$timers} = sort { $a->[0] <=> $b->[0] } @{$timers}, @new; + return; +} + sub watch_time { my ($self, %watch) = @_; - my $at = $watch{at} || do { - die "watch_time requires at or after" unless my $after = $watch{after}; - time() + $after; - }; + my $at; + + Dlog_trace { "watch_time() invoked with $_" } \%watch; + + if (exists($watch{every})) { + $at = time() + $watch{every}; + } elsif (exists($watch{after})) { + $at = time() + $watch{after}; + } elsif (exists($watch{at})) { + $at = $watch{at}; + } else { + die "watch_time requires every, after or at"; + } + die "watch_time requires code" unless my $code = $watch{code}; my $timers = $self->_timers; - my $new = [ $at => $code ]; - @{$timers} = sort { $a->[0] <=> $b->[0] } @{$timers}, $new; - log_debug { "Created new timer that expires at '$at'" }; + my $new = [ $at => $code, $watch{every} ]; + $self->_sort_timers($new); + log_debug { "Created new timer with id '$new' that expires at '$at'" }; return "$new"; } sub unwatch_time { my ($self, $id) = @_; - log_debug { "Removing timer with id of '$id'" }; + log_trace { "Removing timer with id of '$id'" }; @$_ = grep !($_ eq $id), @$_ for $self->_timers; return; } @@ -90,11 +121,7 @@ sub unwatch_time { sub _next_timer_expires_delay { my ($self) = @_; my $timers = $self->_timers; - #undef means no timeout, select only returns - #when data is ready - when the system - #deadlocks the chatter from the timeout in - #select clogs up the logs - my $delay_max = undef; + my $delay_max = $self->block_duration; return $delay_max unless @$timers; my $duration = $timers->[0]->[0] - time; @@ -107,8 +134,6 @@ sub _next_timer_expires_delay { $duration = $delay_max; } - log_trace { "returning $duration as select() timeout period" } - return $duration; } @@ -116,16 +141,19 @@ sub loop_once { my ($self) = @_; my $read = $self->_read_watches; my $write = $self->_write_watches; + our $Loop_Entered = 1; my $read_count = 0; my $write_count = 0; my @c = caller; my $wait_time = $self->_next_timer_expires_delay; - log_debug { sprintf("Run loop: loop_once() has been invoked by $c[1]:$c[2] with read:%i write:%i select timeout:%s", - scalar(keys(%$read)), scalar(keys(%$write)), defined $wait_time ? $wait_time : 'indefinite' ) }; + log_trace { + sprintf("Run loop: loop_once() has been invoked by $c[1]:$c[2] with read:%i write:%i select timeout:%s", + scalar(keys(%$read)), scalar(keys(%$write)), defined $wait_time ? $wait_time : 'indefinite' ) + }; my ($readable, $writeable) = IO::Select->select( $self->_read_select, $self->_write_select, undef, $wait_time ); - log_debug { + log_trace { my $readable_count = defined $readable ? scalar(@$readable) : 0; my $writable_count = defined $writeable ? scalar(@$writeable) : 0; "Run loop: select returned readable:$readable_count writeable:$writable_count"; @@ -133,41 +161,61 @@ sub loop_once { # I would love to trap errors in the select call but IO::Select doesn't # differentiate between an error and a timeout. # -- no, love, mst. + + local $Loop_Entered; + log_trace { "Reading from all ready filehandles" }; foreach my $fh (@$readable) { next unless $read->{$fh}; $read_count++; $read->{$fh}(); -# $read->{$fh}() if $read->{$fh}; + last if $Loop_Entered; } log_trace { "Writing to all ready filehandles" }; foreach my $fh (@$writeable) { next unless $write->{$fh}; $write_count++; $write->{$fh}(); -# $write->{$fh}() if $write->{$fh}; + last if $Loop_Entered; } + log_trace { "Read from $read_count filehandles; wrote to $write_count filehandles" }; my $timers = $self->_timers; my $now = time(); log_trace { "Checking timers" }; while (@$timers and $timers->[0][0] <= $now) { - Dlog_debug { "Found timer that needs to be executed: $_" } $timers->[0]; - (shift @$timers)->[1]->(); + my $active = $timers->[0]; + Dlog_trace { "Found timer that needs to be executed: '$active'" }; + + if (defined($active->[2])) { + #handle the case of an 'every' timer + $active->[0] = time() + $active->[2]; + Dlog_trace { "scheduling timer for repeat execution at $_"} $active->[0]; + $self->_sort_timers; + } else { + #it doesn't repeat again so get rid of it + shift(@$timers); + } + + #execute the timer + $active->[1]->(); + + last if $Loop_Entered; } - log_debug { "Run loop: single loop is completed" }; + + log_trace { "Run loop: single loop is completed" }; return; } sub want_run { my ($self) = @_; - Dlog_debug { "Run loop: Incrimenting want_running, is now $_" } + Dlog_debug { "Run loop: Incremeting want_running, is now $_" } ++$self->{want_running}; } sub run_while_wanted { my ($self) = @_; - log_debug { "Run loop: run_while_wanted() invoked" }; + log_debug { my $wr = $self->{want_running}; "Run loop: run_while_wanted() invoked; want_running: $wr" }; $self->loop_once while $self->{want_running}; log_debug { "Run loop: run_while_wanted() completed" }; return; @@ -185,12 +233,12 @@ sub want_stop { sub run { my ($self) = @_; - log_info { "Run loop: run() invoked" }; + log_trace { "Run loop: run() invoked" }; local $self->{is_running} = 1; while ($self->is_running) { $self->loop_once; } - log_info { "Run loop: run() completed" }; + log_trace { "Run loop: run() completed" }; return; }