2 # The RBN connection system
4 # Copyright (c) 2020 Dirk Koopman G1TLH
21 use Math::Round qw(nearest nearest_floor);
23 use Time::HiRes qw(gettimeofday);
70 our $DATA_VERSION = 1;
72 our @ISA = qw(DXChannel);
74 our $startup_delay = 5*60; # don't send anything out until this timer has expired
75 # this is to allow the feed to "warm up" with duplicates
76 # so that the "big rush" doesn't happen.
78 our $minspottime = 30*60; # the time between respots of a callsign - if a call is
79 # still being spotted (on the same freq) and it has been
80 # spotted before, it's spotted again after this time
81 # until the next minspottime has passed.
83 our $beacontime = 5*60; # same as minspottime, but for beacons (and shorter)
85 our $dwelltime = 10; # the amount of time to wait for duplicates before issuing
86 # a spot to the user (no doubt waiting with bated breath).
88 our $limbotime = 5*60; # if there are fewer than $minqual candidates and $dwelltime
89 # has expired then allow this spot to live a bit longer. It may
90 # simply be that it is not in standard spot coverage. (ask G4PIQ
93 our $filterdef = $Spot::filterdef; # we use the same filter as the Spot system. Can't think why :-).
95 my $spots; # the GLOBAL spot cache
97 my %runtime; # how long each channel has been running
99 our $cachefn = localdata('rbn_cache');
100 our $cache_valid = 4*60; # The cache file is considered valid if it is not more than this old
102 our $maxqrgdiff = 10; # the maximum
103 our $minqual = 2; # the minimum quality we will accept for output
104 our $maxqual = 9; # if there is enough quality, then short circuit any remaining dwelltime.
107 my $noinrush = 0; # override the inrushpreventor if set
108 our $maxdeviants = 5; # the number of deviant QRGs to record for skimmer records
117 $spots = {VERSION=>$DATA_VERSION};
119 if (defined $DB::VERSION) {
128 my $self = DXChannel::alloc(@_);
130 # routing, this must go out here to prevent race condx
137 $self->{nouser} = {};
139 $self->{noraw10} = 0;
140 $self->{nospot10} = 0;
141 $self->{nouser10} = {};
142 $self->{norbn10} = 0;
143 $self->{nospothour} = 0;
144 $self->{nouserhour} = {};
145 $self->{norbnhour} = 0;
146 $self->{norawhour} = 0;
148 $self->{lasttime} = $main::systime;
149 $self->{minspottime} = $minspottime;
150 $self->{beacontime} = $beacontime;
151 $self->{showstats} = 0;
152 $self->{pingint} = 0;
153 $self->{nopings} = 0;
161 my ($self, $line, $sort) = @_;
162 my $user = $self->{user};
163 my $call = $self->{call};
164 my $name = $user->{name};
167 my $host = $self->{conn}->peerhost;
169 $self->{hostname} = $host;
171 $self->{name} = $name ? $name : $call;
172 $self->state('prompt'); # a bit of room for further expansion, passwords etc
173 $self->{lang} = $user->lang || $main::lang || 'en';
174 if ($line =~ /host=/) {
175 my ($h) = $line =~ /host=(\d+\.\d+\.\d+\.\d+)/;
176 $line =~ s/\s*host=\d+\.\d+\.\d+\.\d+// if $h;
178 ($h) = $line =~ /host=([\da..fA..F:]+)/;
179 $line =~ s/\s*host=[\da..fA..F:]+// if $h;
183 $self->{hostname} = $h;
186 $self->{width} = 80 unless $self->{width} && $self->{width} > 80;
187 $self->{consort} = $line; # save the connection type
189 LogDbg('DXCommand', "$call connected from $self->{hostname}");
191 # set some necessary flags on the user if they are connecting
192 $self->{registered} = 1;
193 # sort out privilege reduction
198 $nossid =~ s/-\d+$//;
200 $self->{inrbnfilter} = Filter::read_in('rbn', $call, 1)
201 || Filter::read_in('rbn', 'node_default', 1);
203 # clean up qra locators
204 my $qra = $user->qra;
205 $qra = undef if ($qra && !DXBearing::is_qra($qra));
207 my $lat = $user->lat;
208 my $long = $user->long;
209 $user->qra(DXBearing::lltoqra($lat, $long)) if (defined $lat && defined $long);
212 # if we have been running and stopped for a while
213 # if the cache is warm enough don't operate the inrush preventor
214 $self->{inrushpreventor} = exists $runtime{$call} && $runtime{$call} > $startup_delay || $noinrush ? 0 : $main::systime + $startup_delay;
215 dbg("RBN: noinrush: $noinrush, setting inrushpreventor on $self->{call} to $self->{inrushpreventor}");
218 my @queue; # the queue of spots ready to send
225 my $dbgrbn = isdbg('rbn');
227 # remove leading and trailing spaces
234 my $now = $main::systime;
237 dbg "RBN:RAW,$line" if isdbg('rbnraw');
238 return unless $line=~/^DX\s+de/;
240 my (undef, undef, $origin, $qrg, $call, $mode, $s, $m, $spd, $u, $sort, $t, $tx) = split /[:\s]+/, $line;
242 # fix up FT8 spots from 7001
243 $t = $u, $u = '' if !$t && is_ztime($u);
244 $t = $sort, $sort = '' if !$t && is_ztime($sort);
245 my $qra = $spd, $spd = '' if is_qra($spd);
248 # is this anything like a callsign?
249 unless (is_callsign($call)) {
250 dbg("RBN: ERROR $call from $origin on $qrg is invalid, dumped");
254 $origin =~ s/\-(?:\d{1,2}\-)?\#$//; # get rid of all the crap we aren't interested in
260 dbg qq{RBN:input decode or:$origin qr:$qrg ca:$call mo:$mode s:$s m:$m sp:$spd u:$u sort:$sort t:$t tx:$tx qra:$qra} if $dbgrbn && isdbg('rbn');
264 ++$self->{norawhour};
270 # fix up times for things like 'NXDXF B' etc
271 if ($tx && is_ztime($t)) {
280 if ($sort && $sort eq 'NCDXF') {
284 if ($sort && $sort eq 'BEACON') {
287 if ($mode =~ /^PSK/) {
290 if ($mode eq 'RTTY') {
294 # The main de-duping key is [call, $frequency], but we probe a bit around that frequency to find a
295 # range of concurrent frequencies that might be in play.
297 # The key to this is deducing the true callsign by "majority voting" (the greater the number of spotters
298 # the more effective this is) together with some lexical analsys probably in conjuction with DXSpider
299 # data sources (for singleton spots) to then generate a "centre" from and to zone (whatever that will mean if it isn't the usual one)
300 # and some heuristical "Kwalitee" rating given distance from the zone centres of spotter, recipient user
301 # and spotted. A map can be generated once per user and spotter as they are essentially mostly static.
302 # The spotted will only get a coarse position unless other info is available. Programs that parse
303 # DX bulletins and the online data online databases could be be used and then cached.
305 # Obviously users have to opt in to receiving RBN spots and other users will simply be passed over and
308 # Clearly this will only work in the 'mojo' branch of DXSpider where it is possible to pass off external
309 # data requests to ephemeral or semi resident forked processes that do any grunt work and the main
310 # process to just the standard "message passing" which has been shown to be able to sustain over 5000
311 # per second (limited by the test program's output and network speed, rather than DXSpider's handling).
314 my $nqrg = nearest(1, $qrg * 10); # normalised to nearest Khz
315 my $sp = "$call|$nqrg"; # hopefully the skimmers will be calibrated at least this well!
318 my $cand = $spots->{$sp};
321 for ($i = $nqrg; !$cand && $i <= $nqrg+$search; $i += 1) {
323 $cand = $spots->{$new}, last if exists $spots->{$new};
326 my $diff = $i - $nqrg;
327 dbg(qq{RBN: QRG Diff using $new (+$diff) for $sp for qrg $qrg}) if (isdbg('rbnqrg') || ($dbgrbn && isdbg('rbn')));
333 for ($i = $nqrg; !$cand && $i >= $nqrg-$search; $i -= 1) {
335 $cand = $spots->{$new}, last if exists $spots->{$new};
338 my $diff = $nqrg - $i;
339 dbg(qq{RBN: QRG Diff using $new (-$diff) for $sp for qrg $qrg}) if (isdbg('rbnqrg') || ($dbgrbn && isdbg('rbn')));
344 # if we have one and there is only one slot and that slot's time isn't expired for respot then return
346 if ($cand && ref $cand) {
347 if (@$cand <= CData) {
348 if ($self->{minspottime} > 0 && $now - $cand->[CTime] < $self->{minspottime}) {
349 dbg("RBN: key: '$sp' call: $call qrg: $qrg DUPE \@ ". atime(int $cand->[CTime])) if $dbgrbn && isdbg('rbn');
353 dbg("RBN: key: '$sp' RESPOTTING call: $call qrg: $qrg last seen \@ ". atime(int $cand->[CTime])) if $dbgrbn && isdbg('rbn');
354 $cand->[CTime] = $now;
358 # otherwise we have a spot being built up at the moment
360 dbg("RBN: key '$sp' = '$cand' not ref");
363 # new spot / frequency
364 $spots->{$sp} = $cand = [$now, 0];
365 dbg("RBN: key: '$sp' call: $call qrg: $qrg NEW" . ($respot ? ' RESPOT' : '')) if $dbgrbn && isdbg('rbn');
368 # add me to the display queue unless we are waiting for initial in rush to finish
369 return unless $noinrush || $self->{inrushpreventor} < $main::systime;
371 # build up a new record and store it in the buildup
372 # deal with the unix time
373 my ($hh,$mm) = $t =~ /(\d\d)(\d\d)Z$/;
374 my $utz = $hh*3600 + $mm*60 + $main::systime_daystart; # possible issue with late spot from previous day
375 $utz -= 86400 if $utz > $now+3600; # too far ahead, drag it back one day
377 # create record and add into the buildup
378 my $r = [$origin, nearest(.1, $qrg), $call, $mode, $s, $t, $utz, $respot, $u];
379 my @s = Spot::prepare($r->[RQrg], $r->[RCall], $r->[RUtz], '', $r->[ROrigin]);
381 dbg("RBN: ERROR invalid prefix/callsign $call from $origin-# on $qrg, dumped");
385 if ($self->{inrbnfilter}) {
386 my ($want, undef) = $self->{inrbnfilter}->it($s);
389 $r->[RSpotData] = \@s;
391 ++$self->{queue}->{$sp};# unless @$cand>= CData; # queue the KEY (not the record)
393 dbg("RBN: key: '$sp' ADD RECORD call: $call qrg: $qrg origin: $origin respot: $respot") if $dbgrbn && isdbg('rbn');
398 dbg "RBN:DATA,$line" if $dbgrbn && isdbg('rbn');
402 # we should get the spot record minus the time, so just an array of record (arrays)
411 ++$self->{norbnhour};
413 # $r = [$origin, $qrg, $call, $mode, $s, $utz, $respot];
415 my $mode = $cand->[CData]->[RMode]; # as all the modes will be the same;
417 my @dxchan = DXChannel::get_all();
419 foreach my $dxchan (@dxchan) {
420 next unless $dxchan->is_user;
421 my $user = $dxchan->{user};
422 next unless $user && $user->wantrbn;
424 # does this user want this sort of spot at all?
426 ++$want if $user->wantbeacon && $mode =~ /^BCN|DXF/;
427 ++$want if $user->wantcw && $mode =~ /^CW/;
428 ++$want if $user->wantrtty && $mode =~ /^RTT/;
429 ++$want if $user->wantpsk && $mode =~ /^PSK|FSK|MSK/;
430 ++$want if $user->wantft && $mode =~ /^FT/;
432 dbg(sprintf("RBN: spot selection for $dxchan->{call} mode: '$mode' want: $want flags rbn:%d ft:%d bcn:%d cw:%d psk:%d rtty:%d",
439 )) if isdbg('rbnll');
441 # send one spot to one user out of the ones that we have
442 $self->dx_spot($dxchan, $quality, $cand) if $want;
452 my $call = $dxchan->{call};
453 my $seeme = $dxchan->user->rbnseeme();
454 my $strength = 100; # because it could if we talk about FTx
460 ++$self->{nousers}->{$call};
461 ++$self->{nousers10}->{$call};
462 ++$self->{nousershour}->{$call};
465 my $rf = $dxchan->{rbnfilter} || $dxchan->{spotsfilter};
468 foreach my $r (@$cand) {
469 # $r = [$origin, $qrg, $call, $mode, $s, $t, $utz, $respot, $qra];
470 # Spot::prepare($qrg, $call, $utz, $comment, $origin);
471 next unless $r && ref $r;
473 $qra = $r->[RQra] if !$qra && $r->[RQra] && is_qra($r->[RQra]);
475 $comment = sprintf "%-3s %2ddB $quality", $r->[RMode], $r->[RStrength];
476 my $s = $r->[RSpotData]; # the prepared spot
477 $s->[SComment] = $comment; # apply new generated comment
479 ++$zone{$s->[SZone]}; # save the spotter's zone
481 # if the 'see me' flag is set, then show all the spots without further adornment (see set/rbnseeme for more info)
483 send_final($dxchan, $s);
487 # save the lowest strength one
488 if ($r->[RStrength] < $strength) {
489 $strength = $r->[RStrength];
491 dbg("RBN: STRENGTH spot: $s->[SCall] qrg: $s->[SQrg] origin: $s->[SOrigin] dB: $r->[RStrength] < $strength") if isdbg 'rbnll';
495 my ($want, undef) = $rf->it($s);
496 dbg("RBN: FILTERING for $call spot: $s->[SCall] qrg: $s->[SQrg] origin: $s->[SOrigin] dB: $r->[RStrength] com: '$s->[SComment]' want: " . ($want ? 'YES':'NO')) if isdbg 'rbnll';
503 $saver = $filtered; # if nothing passed the filter's lips then $saver == $filtered == undef !
508 # create a zone list of spotters
509 delete $zone{$saver->[SZone]}; # remove this spotter's zone (leaving all the other zones)
510 my $z = join ',', sort {$a <=> $b} keys %zone;
512 # alter spot data accordingly
513 $saver->[SComment] .= " Z:$z" if $z;
515 send_final($dxchan, $saver);
519 ++$self->{nospothour};
522 my $user = DXUser::get_current($saver->[SCall]) || DXUser->new($saver->[SCall]);
523 unless ($user->qra && is_qra($user->qra)) {
525 dbg("RBN: update qra on $saver->[SCall] to $qra");
536 my $call = $dxchan->{call};
539 dbg("RBN: SENDING to $call spot: $saver->[SCall] qrg: $saver->[SQrg] origin: $saver->[SOrigin] $saver->[SComment]") if isdbg 'rbnll';
540 if ($dxchan->{ve7cc}) {
541 my $call = $saver->[SOrigin];
542 $saver->[SOrigin] .= '-#';
543 $buf = VE7CC::dx_spot($dxchan, @$saver);
544 $saver->[SOrigin] = $call;
546 my $call = $saver->[SOrigin];
547 $saver->[SOrigin] = substr($call, 0, 6);
548 $saver->[SOrigin] .= '-#';
549 $buf = $dxchan->format_dx_spot(@$saver);
550 $saver->[SOrigin] = $call;
552 $dxchan->local_send('N', $buf);
558 my $rbnskim = isdbg('rbnskim');
560 foreach my $dxchan (DXChannel::get_all()) {
561 next unless $dxchan->is_rbn;
563 # At this point we run the queue to see if anything can be sent onwards to the punter
564 my $now = $main::systime;
565 my $ta = [gettimeofday];
568 # now run the waiting queue which just contains KEYS ($call|$qrg)
569 foreach my $sp (keys %{$dxchan->{queue}}) {
570 my $cand = $spots->{$sp};
573 unless ($cand && $cand->[CTime]) {
574 dbg "RBN Cand $sp " . ($cand ? 'def' : 'undef') . " [CTime] " . ($cand->[CTime] ? 'def' : 'undef') . " dwell $dwelltime";
575 delete $spots->{$sp};
576 delete $dxchan->{queue}->{$sp}; # remove
580 my $ctime = $cand->[CTime];
581 my $quality = @$cand - CData;
582 my $dwellsecs = $now - $ctime;
583 if ($quality >= $maxqual || $dwellsecs >= $dwelltime || $dwellsecs >= $limbotime) {
584 # we have a candidate, create qualitee value(s);
585 unless (@$cand > CData) {
586 dbg "RBN: QUEUE key '$sp' MISSING RECORDS, IGNORED" . dd($cand) if isdbg 'rbnqueue';
587 delete $spots->{$sp}; # don't remember it either - this means that a spot HAS to come in with sufficient spotters to be processed.
588 delete $dxchan->{queue}->{$sp};
591 dbg "RBN: QUEUE PROCESSING key: '$sp' $now >= $cand->[CTime]" if isdbg 'rbnqueue';
592 my $spotters = $quality;
594 # dump it and remove it from the queue if it is of unadequate quality, but only if it is no longer in Limbo and can be reasonably passed on to its demise
595 my $r = $cand->[CData];
596 if ($dwellsecs > $limbotime && $quality < $minqual) {
597 if ( $rbnskim && isdbg('rbnskim')) {
600 my $lastin = difft($ctime, $now, 2);
601 my $s = "RBN:SKIM time in Limbo exceeded DUMPED (lastin: $lastin Q:$quality < Q:$minqual) key: '$sp' = $r->[RCall] on $r->[RQrg] by $r->[ROrigin] \@ $r->[RTime] route: $dxchan->{call}";
605 delete $spots->{$sp}; # don't remember it either - this means that a spot HAS to come in with sufficient spotters to be processed.
606 delete $dxchan->{queue}->{$sp};
610 # we have a possible removal from Limbo, check for more than one skimmer and reset the quality if required
611 # DOES THIS TEST CAUSE RACES?
612 if (!$r->[Respot] && $quality >= $minqual && $dwellsecs > $dwelltime+1) {
614 # because we don't need to check for repeats by the same skimmer in the normal case, we do here
617 foreach my $wr (@$cand) {
619 push @origin, $wr->[ROrigin];
620 if (exists $seen{$wr->[ROrigin]}) {
623 $seen{$wr->[ROrigin]} = $wr;
625 # reset the quality to ignore dupes
627 $quality = keys %seen;
628 if ($quality >= $minqual) {
629 if ( $rbnskim && isdbg('rbnskim')) {
630 my $lastin = difft($ctime, $now, 2);
631 my $sk = join ' ', keys %seen;
632 my $or = join ' ', @origin;
633 my $s = "RBN:SKIM promoted from Limbo - key: '$sp' (lastin: $lastin Q now: $quality was $oq skimmers now: $sk";
634 $s .= " was $or" if $or ne $sk;
638 } elsif ($oq != $quality) {
639 if ( $rbnskim && isdbg('rbnskim')) {
640 my $lastin = difft($ctime, $now, 2);
641 my $sk = join ' ', keys %seen;
642 my $or = join ' ', @origin;
643 my $s = "RBN:SKIM quality reset key: '$sp' (lastin: $lastin Q now: $quality was $oq skimmers now: $sk was: $or)";
647 my @ncand = (@$cand[CTime, CQual], values %seen);
648 $spots->{$sp} = \@ncand;
652 # we now kick this spot into Limbo
653 if ($quality < $minqual) {
657 $quality = 9 if $quality > 9;
658 $cand->[CQual] = $quality if $quality > $cand->[CQual];
660 # this scores each candidate according to its skimmer's QRG score (i.e. how often it agrees with its peers)
661 # what happens is hash of all QRGs in candidates are incremented by that skimmer's reputation for "accuracy"
662 # or, more exactly, past agreement with the consensus. This score can be from -5 -> +5.
668 foreach $r (@$cand) {
670 if (exists $seen{$r->[ROrigin]}) {
674 $seen{$r->[ROrigin]} = 1;
675 $band ||= int $r->[RQrg] / 1000;
676 $sk = "SKIM|$r->[ROrigin]|$band"; # thus only once per set of candidates
677 $skimmer = $spots->{$sk};
679 $skimmer = $spots->{$sk} = [1, 0, 0, $now, []]; # this first time, this new skimmer gets the benefit of the doubt on frequency.
680 dbg("RBN:SKIM new slot $sk " . $json->encode($skimmer)) if $rbnskim && isdbg('rbnskim');
682 $qrg{$r->[RQrg]} += ($skimmer->[DScore] || 1);
685 # determine the most likely qrg and then set it - NOTE (-)ve votes, generated by the skimmer scoring system above, are ignored
690 while (my ($k, $votes) = each %qrg) {
698 # Ignore possible spots with 0 QRG score - as determined by the skimmer scoring system above - as they are likely to be wrong
700 if ( $rbnskim && isdbg('rbnskim')) {
702 while (my ($k, $v) = (each %qrg)) {
707 foreach $r (@$cand) {
708 next unless $r && ref $r;
709 dbg "RBN:SKIM cand $i QRG likely wrong from '$sp' = $r->[RCall] on $r->[RQrg] by $r->[ROrigin] \@ $r->[RTime] (qrgs: $keys c: $c) route: $dxchan->{call}, ignored";
713 delete $spots->{$sp}; # get rid
714 delete $dxchan->{queue}->{$sp};
718 # detemine and spit out the deviants. Then adjust the scores according to whether it is a deviant or good
719 # NOTE: deviant nodes can become good (or less bad), and good nodes bad (or less good) on each spot that
720 # they generate. This is based solely on each skimmer's agreement (or not) with the "consensus" score generated
721 # above ($qrg). The resultant score + good + bad is stored per band and will be used the next time a spot
722 # appears on this band from each skimmer.
723 foreach $r (@$cand) {
724 next unless $r && ref $r;
725 my $diff = $c > 1 ? nearest(.1, $r->[RQrg] - $qrg) : 0;
726 $sk = "SKIM|$r->[ROrigin]|$band";
727 $skimmer = $spots->{$sk};
729 ++$skimmer->[DBad] if $skimmer->[DBad] < $maxdeviants;
730 --$skimmer->[DGood] if $skimmer->[DGood] > 0;
731 push @deviant, sprintf("$r->[ROrigin]:%+.1f", $diff);
732 push @{$skimmer->[DEviants]}, $diff;
733 shift @{$skimmer->[DEviants]} while @{$skimmer->[DEviants]} > $maxdeviants;
735 ++$skimmer->[DGood] if $skimmer->[DGood] < $maxdeviants;
736 --$skimmer->[DBad] if $skimmer->[DBad] > 0;
737 shift @{$skimmer->[DEviants]};
739 $skimmer->[DScore] = $skimmer->[DGood] - $skimmer->[DBad];
740 if ($rbnskim && isdbg('rbnskim')) {
741 my $lastin = difft($skimmer->[DLastin], $now, 2);
742 my $difflist = join(', ', @{$skimmer->[DEviants]});
743 $difflist = " band qrg diffs: $difflist" if $difflist;
744 dbg("RBN:SKIM key $sp slot $sk $r->[RQrg] - $qrg = $diff Skimmer score: $skimmer->[DGood] - $skimmer->[DBad] = $skimmer->[DScore] lastseen:$lastin ago$difflist");
746 $skimmer->[DLastin] = $now;
747 $r->[RSpotData]->[SQrg] = $qrg if $qrg && $c > 1; # set all the QRGs to the agreed value
750 $qrg = (sprintf "%.1f", $qrg)+0;
753 my $squality = "Q:$cand->[CQual]";
754 $squality .= '*' if $c > 1;
755 $squality .= '+' if $r->[Respot];
757 if (isdbg('progress')) {
758 my $rt = difft($ctime, $now, 2);
759 my $s = "RBN: SPOT key: '$sp' = $r->[RCall] on $r->[RQrg] by $r->[ROrigin] \@ $r->[RTime] $squality route: $dxchan->{call} dwell:$rt";
761 $s .= " QRGScore: $mv Deviants: $td/$spotters";
762 $s .= ' (' . join(', ', sort @deviant) . ')' if $td;
766 # finally send it out to any waiting public
767 send_dx_spot($dxchan, $squality, $cand);
769 # clear out the data and make this now just "spotted", but no further action required until respot time
770 dbg "RBN: QUEUE key '$sp' cleared" if isdbg 'rbn';
772 delete $dxchan->{queue}->{$sp};
774 # calculate new sp (which will be 70% likely the same as the old one)
775 # we do this to cope with the fact that the first spotter may well be "wrongly calibrated" giving a qrg that disagrees with the majority.
776 # and we want to store the key that corresponds to majority opinion.
777 my $nqrg = nearest(1, $qrg * 10); # normalised to nearest Khz
778 my $nsp = "$r->[RCall]|$nqrg";
780 dbg("RBN:SKIM CHANGE KEY sp '$sp' -> '$nsp' for storage") if $rbnskim && isdbg('rbnskim');
781 delete $spots->{$sp};
782 $spots->{$nsp} = [$now, $cand->[CQual]];
784 $spots->{$sp} = [$now, $cand->[CQual]];
788 dbg sprintf("RBN: QUEUE key: '$sp' SEND time not yet reached %.1f secs left", $cand->[CTime] + $dwelltime - $now) if isdbg 'rbnqueue';
791 if (isdbg('rbntimer')) {
792 my $diff = _diffus($ta);
793 dbg "RBN: TIMER process queue for call: $dxchan->{call} $items spots $diff uS";
800 foreach my $dxchan (DXChannel::get_all()) {
801 next unless $dxchan->is_rbn;
802 dbg "RBN:STATS minute $dxchan->{call} raw: $dxchan->{noraw} retrieved spots: $dxchan->{norbn} delivered: $dxchan->{nospot} after filtering to users: " . scalar keys %{$dxchan->{nousers}} if isdbg('rbnstats');
803 if ($dxchan->{noraw} == 0 && $dxchan->{lasttime} > 60) {
804 LogDbg('RBN', "RBN: no input from $dxchan->{call}, disconnecting");
807 $dxchan->{noraw} = $dxchan->{norbn} = $dxchan->{nospot} = 0; $dxchan->{nousers} = {};
808 $runtime{$dxchan->{call}} += 60;
811 # save the spot cache
812 write_cache() unless $main::systime + $startup_delay < $main::systime;;
819 while (my ($k,$cand) = each %{$spots}) {
820 next if $k eq 'VERSION';
821 next if $k =~ /^O\|/;
822 next if $k =~ /^SKIM\|/;
824 if ($main::systime - $cand->[CTime] > $minspottime*2) {
832 dbg "RBN:STATS spot cache remain: $count removed: $removed"; # if isdbg('rbn');
833 foreach my $dxchan (DXChannel::get_all()) {
834 next unless $dxchan->is_rbn;
835 my $nq = keys %{$dxchan->{queue}};
836 my $pc = $dxchan->{noraw10} ? sprintf("%.1f%%",$dxchan->{norbn10}*100/$dxchan->{noraw10}) : '0.0%';
837 dbg "RBN:STATS 10-minute $dxchan->{call} queue: $nq raw: $dxchan->{noraw10} retrieved spots: $dxchan->{norbn10} ($pc) delivered: $dxchan->{nospot10} after filtering to users: " . scalar keys %{$dxchan->{nousers10}};
838 $dxchan->{noraw10} = $dxchan->{norbn10} = $dxchan->{nospot10} = 0; $dxchan->{nousers10} = {};
844 foreach my $dxchan (DXChannel::get_all()) {
845 next unless $dxchan->is_rbn;
846 my $nq = keys %{$dxchan->{queue}};
847 my $pc = $dxchan->{norawhour} ? sprintf("%.1f%%",$dxchan->{norbnhour}*100/$dxchan->{norawhour}) : '0.0%';
848 dbg "RBN:STATS hour $dxchan->{call} queue: $nq raw: $dxchan->{norawhour} retrieved spots: $dxchan->{norbnhour} ($pc) delivered: $dxchan->{nospothour} after filtering to users: " . scalar keys %{$dxchan->{nousershour}};
849 $dxchan->{norawhour} = $dxchan->{norbnhour} = $dxchan->{nospothour} = 0; $dxchan->{nousershour} = {};
860 my $ta = [ gettimeofday ];
861 $json->indent(1)->canonical(1) if isdbg 'rbncache';
862 my $s = eval {$json->encode($spots)};
864 my $fh = IO::File->new(">$cachefn") or confess("writing $cachefn $!");
868 dbg("RBN:Write_cache error '$@'");
871 $json->indent(0)->canonical(0);
872 my $diff = _diffms($ta);
873 my $size = sprintf('%.3fKB', (length($s) / 1000));
874 dbg("RBN:WRITE_CACHE size: $size time to write: $diff mS");
880 my $mt = (stat($cachefn))[9];
881 my $t = $main::systime - $mt || 1;
882 my $p = difft($mt, 2);
883 if ($t < $cache_valid) {
884 dbg("RBN:check_cache '$cachefn' spot cache exists, created $p ago and not too old");
885 my $fh = IO::File->new($cachefn);
890 dbg("RBN:check_cache cache read size " . length $s);
893 dbg("RBN:check_cache file read error $!");
897 eval {$spots = $json->decode($s)};
898 if ($spots && ref $spots) {
899 if (exists $spots->{VERSION} && $spots->{VERSION} == $DATA_VERSION) {
900 # now clean out anything that has spot build ups in progress
901 while (my ($k, $cand) = each %$spots) {
902 next if $k eq 'VERSION';
903 next if $k =~ /^O\|/;
904 next if $k =~ /^SKIM\|/;
905 if (@$cand > CData) {
906 $spots->{$k} = [$cand->[CTime], $cand->[CQual]];
909 dbg("RBN:check_cache spot cache restored");
913 dbg("RBN::checkcache error decoding $@");
916 my $d = difft($main::systime-$cache_valid);
917 dbg("RBN::checkcache '$cachefn' created $p ago is too old (> $d), ignored");
920 dbg("RBN:check_cache '$cachefn' spot cache not present");