--- deliantra/server/ext/schmorplog.ext 2008/05/03 11:14:50 1.19 +++ deliantra/server/ext/schmorplog.ext 2012/02/04 01:12:33 1.34 @@ -1,10 +1,19 @@ -#! perl # depends=irc +#! perl # depends=irc mandatory # statistics-package use Fcntl; use Coro::AIO; +CONF EXPORT_RECENTLOG = undef +CONF EXPORT_RECENTLOG_INTERVAL = 300 + +############################################################################# +# stuffs + +our %PLAYERSEEN; + +# EV, because we call ->start our $UPDATE_LOGINS = EV::idle sub { $_[0]->stop; @@ -45,51 +54,58 @@ cf::player->attach ( on_login => sub { my ($pl) = @_; + $pl->ob->kv_set (schmorplog_last_login => time); $pl->ob->kv_set (schmorplog_login_count => 1 + $pl->ob->kv_get ("schmorplog_login_count")); - (my $client = $pl->ns->version) =~ s/\n/\\n/g; + $pl->ob->kv_set (schmorplog_client => $pl->ns->{who_version}); - $pl->ob->kv_set (schmorplog_client => $client); + my $name = $pl->ob->name; - ext::irc::do_notice (sprintf "%s logged in", $pl->ob->name); + ext::irc::do_notice $name . " logged in"; + undef $PLAYERSEEN{$name}; $UPDATE_LOGINS->start; - warn "LOGIN: ", $pl->ob->name, " from ", $pl->ns->host; + cf::trace "LOGIN: ", $pl->ob->name, " from ", $pl->ns->host; }, on_logout => sub { my ($pl, $cleanly) = @_; $pl->ob->kv_set (schmorplog_last_logout => time); - ext::irc::do_notice (sprintf "%s left", $pl->ob->name); + ext::irc::do_notice $pl->ob->name . " left"; $UPDATE_LOGINS->start; - warn "LOGOUT: ", $pl->ob->name, " from ", $pl->ns->host, " ($cleanly)"; + cf::trace "LOGOUT: ", $pl->ob->name, " from ", $pl->ns->host, " ($cleanly)"; }, on_birth => sub { my ($pl) = @_; - ext::irc::do_notice (sprintf "%s was just born", $pl->ob->name); $pl->ob->kv_set (schmorplog_birthdate => time); + ext::irc::do_notice $pl->ob->name . " was just born"; - warn "BIRTH: ", $pl->ob->name, " from ", $pl->ns->host; + cf::trace "BIRTH: ", $pl->ob->name, " from ", $pl->ns->host; }, on_quit => sub { my ($pl) = @_; - ext::irc::do_notice (sprintf "%s quit the game", $pl->ob->name); + ext::irc::do_notice $pl->ob->name . " quit the game"; - warn "QUIT: ", $pl->ob->name, " from ", $pl->ns->host; + cf::trace "QUIT: ", $pl->ob->name, " from ", $pl->ns->host; }, on_death => sub { my ($pl) = @_; - ext::irc::do_notice (sprintf "%s was killed by %s", $pl->ob->name, $pl->killer_name); + + my $msg = $pl->expand_cfpod ($pl->ob->name . " was killed by " . $pl->killer_name . "."); + ext::irc::do_notice $msg; ++$pl->ob->{stats_death}{$pl->killer_name}; $pl->ob->kv_set (schmorplog_death_count => 1 + $pl->ob->kv_get ("schmorplog_death_count")); + + $_->send_msg ("" => $msg, cf::NDI_VERBATIM) + for cf::player::list; }, on_load => sub { my ($pl, $path) = @_; @@ -116,10 +132,10 @@ my $s = $pl->ob->{stats_death} or return $who->reply ($npc, "$args didn't die even once."); - my $rep = "$args died a total of " . $pl->ob->kv_set ("schmorplog_death_count") . " times, among them:\n\n\n\n"; + my $rep = "$args died a total of " . $pl->ob->kv_get ("schmorplog_death_count") . " times, among them:\n\n"; for (sort { $s->{$b} <=> $s->{$a} } keys %$s) { - $rep .= "$s->{$_} time(s) due to $_.\n\n"; + $rep .= sprintf " C<%4d> time(s) due to %s.\n", $s->{$_}, $_; } $who->reply ($npc, $rep); @@ -132,9 +148,9 @@ my $s = $pl->ob->{stats_kill} or return $who->reply ($npc, "I don't know of I that $args has killed so far."); - my $rep = sprintf "\n\nC<%6s> %s\n\n", "Kills", "Name"; - for (sort { $s->{$a} <=> $s->{$b} } keys %$s) { - $rep .= sprintf "C<%6s> %s\n\n", $s->{$_}, $_; + my $rep = sprintf "Here is a list of all the kills I know about:\n\n"; + for (sort { $s->{$b} <=> $s->{$a} } keys %$s) { + $rep .= sprintf " C<%6s> %s\n", $s->{$_}, $_; } $who->reply ($npc, $rep); @@ -142,8 +158,8 @@ } elsif ($cmd eq "hi") { $who->reply ($npc, "Hello!\n\n" . "I am a statistician, I keep statistics about all people here.\n\n" - . "To know how often somebody died, ask C I\n\n" - . "To know how many kills somebody scored, ask C I"); + . " - To know how often somebody died, ask C I\n" + . " - To know how many kills somebody scored, ask C I"); } else { $who->reply ($npc, "No idea what you want of me, how about saying 'hi' first?"); } @@ -151,3 +167,163 @@ $cmd = lc $cmd; }; +# log "crash" messages, i.e. client exit reasons +cf::register_exticmd clientlog => sub { + my ($ns, $msg) = @_; + + my $name = $ns->pl && $ns->pl->ob ? $ns->pl->ob->name : ""; + + $msg =~ y/\x0a\x20-\x7f//cd; + $msg =~ s/\s+$//; + + cf::error sprintf "clientlog [%s/%s]: %s\n", $ns->host, $name, $msg; + + () +}; + +############################################################################# +# log max playercount every minute + +our $STATSDIR = "$LOCALDIR/maxplayers"; + +mkdir $STATSDIR; + +our $WRITE_MAXPLAYERS = EV::periodic 0, 60, undef, sub { + my $now = AE::now; + my $cnt = scalar keys %PLAYERSEEN; + + %PLAYERSEEN = map +($_->ob->name => undef), grep $_->ns, cf::player::list; + + my @time = gmtime $now; + + my $path = sprintf "%s/%04d-%02d-%02d", $STATSDIR, $time[5] + 1900, $time[4] + 1, $time[3]; + my $offs = $time [2] * 60 + $time [1]; + + $cnt++; + $cnt = 254 if $cnt > 254; + $cnt = chr $cnt; + + IO::AIO::aio_open $path, O_WRONLY | O_CREAT, 0666, sub { + my $fh = shift + or return; + + # the truncate is 1440 extra syscalls, but saves 1439 + # slow metadata updates. + IO::AIO::aio_truncate $fh, 1440, sub { + IO::AIO::aio_write $fh, $offs, 1, $cnt, 0, sub { + IO::AIO::aio_close $fh; + }; + }; + }; +}; + +############################################################################# +# export recentlog + +our %RECENT; +our $EXPORT_RECENTLOG_GRACE = 120; + +our $update_w; +our %need_update; + +sub _update_login { + my ($login) = @_; + + my $path = (cf::player::playerdir $login) . "/playerdata"; + + if (0 >= aio_load $path, my $data) { + delete $RECENT{$login}; + } else { + local $_ = $data; + + my $birthdate = /^schmorplog_birthdate (\S+)$/m ? $1 : undef; + my $login_count = /^schmorplog_login_count (\S+)$/m ? $1 : 0; + my $death_count = /^schmorplog_death_count (\S+)$/m ? $1 : 0; + my $last_save = /^schmorplog_last_save (\S+)$/m ? $1 : undef; + my $last_login = /^schmorplog_last_login (\S+)$/m ? $1 : undef; + my $last_logout = /^schmorplog_last_logout (\S+)$/m ? $1 : undef; + my $client = /^schmorplog_client (.*)$/m ? $1 : "?"; + my $map = /^map (.*)$/m ? $1 : "?"; + + return delete $RECENT{$login} unless $last_login; + + $last_logout = $last_save if $last_save > $last_logout && $last_login > $last_logout && $last_save < $NOW - 10 * 60; + $last_logout = undef if $last_logout < $last_login; + + return delete $RECENT{$login} unless $last_login > $NOW - 86400 * ($login_count * 7 + 10); + +# next if $count < 3 && $login < $NOW - 86400*2; + $RECENT{$login} = [$login, $birthdate, $last_login, $login_count, $last_logout, $client, $death_count, $map]; + } +} + +sub _update { + cf::async_ext { + $Coro::current->nice (1); + $Coro::current->{desc} = "recentlog updater"; + + my $lock = cf::lock_acquire "export_recentlog"; + + my @logins = keys %need_update; %need_update = (); + undef $update_w; + + Coro::AnyEvent::sleep $EXPORT_RECENTLOG_GRACE; # grace time to allow file-saves + + my $t0 = EV::now; + + _update_login $_ + for @logins; + + cf::get_slot 0.1, 0, "recentlog serialise"; + + my $NOW = $cf::NOW; + + cf::replace_file $EXPORT_RECENTLOG, cf::encode_json { + version => 1, + date => $NOW, + data => [ + sort { ($b->[4] || $NOW) <=> ($a->[4] || $NOW) } + values %RECENT + ], + } or warn "$EXPORT_RECENTLOG: $!"; + + cf::trace "recentlog updated (", EV::now - $t0, "s).\n"; + }; +} + +sub update { + return unless defined $EXPORT_RECENTLOG; + + $update_w ||= AE::timer $EXPORT_RECENTLOG_INTERVAL - $EXPORT_RECENTLOG_GRACE, 0, \&_update; +} + +sub reload { + return unless defined $EXPORT_RECENTLOG; + + my $lock = cf::lock_acquire "export_recentlog"; + + cf::async_ext { + $lock; + + $Coro::current->{desc} = "recentlog reloader"; + + undef $need_update{$_} + for @{ +cf::player::list_logins }; + + _update; + }; +} + +cf::player->attach ( + on_login => sub { undef $need_update{$_[0]->ob->name}; update }, + on_logout => sub { undef $need_update{$_[0]->ob->name}; update }, + on_birth => sub { undef $need_update{$_[0]->ob->name}; update }, + on_death => sub { undef $need_update{$_[0]->ob->name}; update }, +# on_load => sub { undef $need_update{$_[0]->ob->name}; update }, + on_save => sub { undef $need_update{$_[0]->ob->name}; update }, +); + +cf::post_init { + reload; +}; +