use IO::Select;
use Time::HiRes qw(time);
-use Object::Remote::Logging qw( :log );
+use Object::Remote::Logging qw( :log :dlog );
use Moo;
# this is ro because we only actually set it using local in sub run
my $fh = $watch{handle};
log_debug { my $type = ref($fh); "Adding watch for ref of type '$type'" };
if (my $cb = $watch{on_read_ready}) {
+ log_trace { "IO watcher on_read_ready has been invoked" };
$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" };
$self->_write_select->add($fh);
$self->_write_watches->{$fh} = $cb;
}
my $timers = $self->_timers;
my $new = [ $at => $code ];
@{$timers} = sort { $a->[0] <=> $b->[0] } @{$timers}, $new;
- log_debug { "Created new timer with id of '$new' that expires at '$at'" };
+ log_debug { "Created new timer that expires at '$at'" };
return "$new";
}
return;
}
+sub _next_timer_expires_delay {
+ my ($self) = @_;
+ my $timers = $self->_timers;
+ #undef means no timeout, 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;
+
+ return $delay_max unless @$timers;
+ my $duration = $timers->[0]->[0] - time;
+
+ log_trace { "next timer fires in '$duration' seconds " };
+
+ if ($duration < 0) {
+ $duration = 0;
+ } elsif (! defined($delay_max)) {
+ $duration = undef;
+ } elsif ($duration > $delay_max) {
+ $duration = $delay_max;
+ }
+
+ return $duration;
+}
+
sub loop_once {
my ($self) = @_;
my $read = $self->_read_watches;
my $write = $self->_write_watches;
+ my $read_count = 0;
+ my $write_count = 0;
my @c = caller;
- log_trace { sprintf("Run loop: loop_once() has been invoked by $c[1]:$c[2] with read:%i write:%i", scalar(keys(%$read)), scalar(keys(%$write))) };
+ 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' ) };
my ($readable, $writeable) = IO::Select->select(
- $self->_read_select, $self->_write_select, undef, 0.5
- );
+ $self->_read_select, $self->_write_select, undef, $wait_time
+ );
log_debug {
- my $readable_count = defined $readable ? scalar(@$readable) : 0;
- my $writable_count = defined $writeable ? scalar(@$writeable) : 0;
- "run loop has readable:$readable_count writeable:$writable_count";
+ 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";
};
# 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.
log_trace { "Reading from all ready filehandles" };
foreach my $fh (@$readable) {
- $read->{$fh}() if $read->{$fh};
+ next unless $read->{$fh};
+ $read_count++;
+ $read->{$fh}();
+# $read->{$fh}() if $read->{$fh};
}
log_trace { "Writing to all ready filehandles" };
foreach my $fh (@$writeable) {
- $write->{$fh}() if $write->{$fh};
+ next unless $write->{$fh};
+ $write_count++;
+ $write->{$fh}();
+# $write->{$fh}() if $write->{$fh};
}
+ 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]->();
}
log_debug { "Run loop: single loop is completed" };
sub want_run {
my ($self) = @_;
- $self->{want_running}++;
+ Dlog_debug { "Run loop: Incrimenting want_running, is now $_" }
+ ++$self->{want_running};
}
sub run_while_wanted {
sub want_stop {
my ($self) = @_;
- $self->{want_running}-- if $self->{want_running};
+ if (! $self->{want_running}) {
+ log_debug { "Run loop: want_stop() was called but want_running was not true" };
+ return;
+ }
+ Dlog_debug { "Run loop: decrimenting want_running, is now $_" }
+ --$self->{want_running};
}
sub run {