ViewVC Help
View File | Revision Log | Show Annotations | Download File
/cvs/deliantra/server/lib/cf.pm
(Generate patch)

Comparing deliantra/server/lib/cf.pm (file contents):
Revision 1.527 by root, Fri Apr 23 04:32:47 2010 UTC vs.
Revision 1.575 by root, Sat Aug 13 20:20:30 2011 UTC

1# 1#
2# This file is part of Deliantra, the Roguelike Realtime MMORPG. 2# This file is part of Deliantra, the Roguelike Realtime MMORPG.
3# 3#
4# Copyright (©) 2006,2007,2008,2009,2010 Marc Alexander Lehmann / Robin Redeker / the Deliantra team 4# Copyright (©) 2006,2007,2008,2009,2010,2011 Marc Alexander Lehmann / Robin Redeker / the Deliantra team
5# 5#
6# Deliantra is free software: you can redistribute it and/or modify it under 6# Deliantra is free software: you can redistribute it and/or modify it under
7# the terms of the Affero GNU General Public License as published by the 7# the terms of the Affero GNU General Public License as published by the
8# Free Software Foundation, either version 3 of the License, or (at your 8# Free Software Foundation, either version 3 of the License, or (at your
9# option) any later version. 9# option) any later version.
20# The authors can be reached via e-mail to <support@deliantra.net> 20# The authors can be reached via e-mail to <support@deliantra.net>
21# 21#
22 22
23package cf; 23package cf;
24 24
25use 5.10.0; 25use common::sense;
26use utf8;
27use strict qw(vars subs);
28 26
29use Symbol; 27use Symbol;
30use List::Util; 28use List::Util;
31use Socket; 29use Socket;
32use EV; 30use EV;
78# strictly for debugging 76# strictly for debugging
79$SIG{QUIT} = sub { Carp::cluck "SIGQUIT" }; 77$SIG{QUIT} = sub { Carp::cluck "SIGQUIT" };
80 78
81sub WF_AUTOCANCEL () { 1 } # automatically cancel this watcher on reload 79sub WF_AUTOCANCEL () { 1 } # automatically cancel this watcher on reload
82 80
81our @ORIG_INC;
82
83our %COMMAND = (); 83our %COMMAND = ();
84our %COMMAND_TIME = (); 84our %COMMAND_TIME = ();
85 85
86our @EXTS = (); # list of extension package names 86our @EXTS = (); # list of extension package names
87our %EXTCMD = (); 87our %EXTCMD = ();
93our @EVENT; 93our @EVENT;
94our @REFLECT; # set by XS 94our @REFLECT; # set by XS
95our %REFLECT; # set by us 95our %REFLECT; # set by us
96 96
97our $CONFDIR = confdir; 97our $CONFDIR = confdir;
98
98our $DATADIR = datadir; 99our $DATADIR = datadir;
99our $LIBDIR = "$DATADIR/ext"; 100our $LIBDIR = "$DATADIR/ext";
100our $PODDIR = "$DATADIR/pod"; 101our $PODDIR = "$DATADIR/pod";
101our $MAPDIR = "$DATADIR/" . mapdir; 102our $MAPDIR = "$DATADIR/" . mapdir;
103
102our $LOCALDIR = localdir; 104our $LOCALDIR = localdir;
103our $TMPDIR = "$LOCALDIR/" . tmpdir; 105our $TMPDIR = "$LOCALDIR/" . tmpdir;
104our $UNIQUEDIR = "$LOCALDIR/" . uniquedir; 106our $UNIQUEDIR = "$LOCALDIR/" . uniquedir;
105our $PLAYERDIR = "$LOCALDIR/" . playerdir; 107our $PLAYERDIR = "$LOCALDIR/" . playerdir;
106our $RANDOMDIR = "$LOCALDIR/random"; 108our $RANDOMDIR = "$LOCALDIR/random";
107our $BDBDIR = "$LOCALDIR/db"; 109our $BDBDIR = "$LOCALDIR/db";
108our $PIDFILE = "$LOCALDIR/pid"; 110our $PIDFILE = "$LOCALDIR/pid";
109our $RUNTIMEFILE = "$LOCALDIR/runtime"; 111our $RUNTIMEFILE = "$LOCALDIR/runtime";
110 112
111our %RESOURCE; 113our %RESOURCE; # unused
112 114
113our $OUTPUT_RATE_MIN = 3000; 115our $OUTPUT_RATE_MIN = 3000;
114our $OUTPUT_RATE_MAX = 1000000; 116our $OUTPUT_RATE_MAX = 1000000;
115 117
116our $MAX_LINKS = 32; # how many chained exits to follow 118our $MAX_LINKS = 32; # how many chained exits to follow
117our $VERBOSE_IO = 0;#d# 119our $VERBOSE_IO = 1;
118 120
119our $TICK = MAX_TIME * 1e-6; # this is a CONSTANT(!) 121our $TICK = MAX_TIME * 1e-6; # this is a CONSTANT(!)
120our $NEXT_RUNTIME_WRITE; # when should the runtime file be written 122our $NEXT_RUNTIME_WRITE; # when should the runtime file be written
121our $NEXT_TICK; 123our $NEXT_TICK;
122our $USE_FSYNC = 1; # use fsync to write maps - default on 124our $USE_FSYNC = 1; # use fsync to write maps - default on
124our $BDB_DEADLOCK_WATCHER; 126our $BDB_DEADLOCK_WATCHER;
125our $BDB_CHECKPOINT_WATCHER; 127our $BDB_CHECKPOINT_WATCHER;
126our $BDB_TRICKLE_WATCHER; 128our $BDB_TRICKLE_WATCHER;
127our $DB_ENV; 129our $DB_ENV;
128 130
129our @EXTRA_MODULES = qw(pod match mapscript); 131our @EXTRA_MODULES = qw(pod match mapscript incloader);
130 132
131our %CFG; 133our %CFG;
132 134
133our $UPTIME; $UPTIME ||= time; 135our $UPTIME; $UPTIME ||= time;
134our $RUNTIME; 136our $RUNTIME = 0;
137our $SERVER_TICK = 0;
135our $NOW; 138our $NOW;
136 139
137our (%PLAYER, %PLAYER_LOADING); # all users 140our (%PLAYER, %PLAYER_LOADING); # all users
138our (%MAP, %MAP_LOADING ); # all maps 141our (%MAP, %MAP_LOADING ); # all maps
139our $LINK_MAP; # the special {link} map, which is always available 142our $LINK_MAP; # the special {link} map, which is always available
148 151
149our @POST_INIT; 152our @POST_INIT;
150 153
151our $REATTACH_ON_RELOAD; # set to true to force object reattach on reload (slow) 154our $REATTACH_ON_RELOAD; # set to true to force object reattach on reload (slow)
152our $REALLY_UNLOOP; # never set to true, please :) 155our $REALLY_UNLOOP; # never set to true, please :)
156
157our $WAIT_FOR_TICK = new Coro::Signal;
158our @WAIT_FOR_TICK_BEGIN;
153 159
154binmode STDOUT; 160binmode STDOUT;
155binmode STDERR; 161binmode STDERR;
156 162
157# read virtual server time, if available 163# read virtual server time, if available
191 197
192=over 4 198=over 4
193 199
194=item $cf::UPTIME 200=item $cf::UPTIME
195 201
196The timestamp of the server start (so not actually an uptime). 202The timestamp of the server start (so not actually an "uptime").
203
204=item $cf::SERVER_TICK
205
206An unsigned integer that starts at zero when the server is started and is
207incremented on every tick.
208
209=item $cf::NOW
210
211The (real) time of the last (current) server tick - updated before and
212after tick processing, so this is useful only as a rough "what time is it
213now" estimate.
214
215=item $cf::TICK
216
217The interval between each server tick, in seconds.
197 218
198=item $cf::RUNTIME 219=item $cf::RUNTIME
199 220
200The time this server has run, starts at 0 and is increased by $cf::TICK on 221The time this server has run, starts at 0 and is increased by $cf::TICK on
201every server tick. 222every server tick.
207Various directories - "/etc", read-only install directory, perl-library 228Various directories - "/etc", read-only install directory, perl-library
208directory, pod-directory, read-only maps directory, "/var", "/var/tmp", 229directory, pod-directory, read-only maps directory, "/var", "/var/tmp",
209unique-items directory, player file directory, random maps directory and 230unique-items directory, player file directory, random maps directory and
210database environment. 231database environment.
211 232
212=item $cf::NOW
213
214The time of the last (current) server tick.
215
216=item $cf::TICK
217
218The interval between server ticks, in seconds.
219
220=item $cf::LOADAVG 233=item $cf::LOADAVG
221 234
222The current CPU load on the server (alpha-smoothed), as a value between 0 235The current CPU load on the server (alpha-smoothed), as a value between 0
223(none) and 1 (overloaded), indicating how much time is spent on processing 236(none) and 1 (overloaded), indicating how much time is spent on processing
224objects per tick. Healthy values are < 0.5. 237objects per tick. Healthy values are < 0.5.
233from wherever your confdir points to. 246from wherever your confdir points to.
234 247
235=item cf::wait_for_tick, cf::wait_for_tick_begin 248=item cf::wait_for_tick, cf::wait_for_tick_begin
236 249
237These are functions that inhibit the current coroutine one tick. cf::wait_for_tick_begin only 250These are functions that inhibit the current coroutine one tick. cf::wait_for_tick_begin only
238returns directly I<after> the tick processing (and consequently, can only wake one process 251returns directly I<after> the tick processing (and consequently, can only wake one thread
239per tick), while cf::wait_for_tick wakes up all waiters after tick processing. 252per tick), while cf::wait_for_tick wakes up all waiters after tick processing.
253
254Note that cf::Wait_for_tick will immediately return when the server is not
255ticking, making it suitable for small pauses in threads that need to run
256when the server is paused. If that is not applicable (i.e. you I<really>
257want to wait, use C<$cf::WAIT_FOR_TICK>).
258
259=item $cf::WAIT_FOR_TICK
260
261Note that C<cf::wait_for_tick> is probably the correct thing to use. This
262variable contains a L<Coro::Signal> that is broadcats after every server
263tick. Calling C<< ->wait >> on it will suspend the caller until after the
264next server tick.
265
266=cut
267
268sub wait_for_tick();
269sub wait_for_tick_begin();
240 270
241=item @cf::INVOKE_RESULTS 271=item @cf::INVOKE_RESULTS
242 272
243This array contains the results of the last C<invoke ()> call. When 273This array contains the results of the last C<invoke ()> call. When
244C<cf::override> is called C<@cf::INVOKE_RESULTS> is set to the parameters of 274C<cf::override> is called C<@cf::INVOKE_RESULTS> is set to the parameters of
252 282
253=back 283=back
254 284
255=cut 285=cut
256 286
287sub error(@) { LOG llevError, join "", @_ }
288sub warn (@) { LOG llevWarn , join "", @_ }
289sub info (@) { LOG llevInfo , join "", @_ }
290sub debug(@) { LOG llevDebug, join "", @_ }
291sub trace(@) { LOG llevTrace, join "", @_ }
292
257$Coro::State::WARNHOOK = sub { 293$Coro::State::WARNHOOK = sub {
258 my $msg = join "", @_; 294 my $msg = join "", @_;
259 295
260 $msg .= "\n" 296 $msg .= "\n"
261 unless $msg =~ /\n$/; 297 unless $msg =~ /\n$/;
262 298
263 $msg =~ s/([\x00-\x08\x0b-\x1f])/sprintf "\\x%02x", ord $1/ge; 299 $msg =~ s/([\x00-\x08\x0b-\x1f])/sprintf "\\x%02x", ord $1/ge;
264 300
265 LOG llevError, $msg; 301 LOG llevWarn, $msg;
266}; 302};
267 303
268$Coro::State::DIEHOOK = sub { 304$Coro::State::DIEHOOK = sub {
269 return unless $^S eq 0; # "eq", not "==" 305 return unless $^S eq 0; # "eq", not "=="
270 306
271 warn Carp::longmess $_[0]; 307 error Carp::longmess $_[0];
272 308
273 if (in_main) {#d# 309 if (in_main) {#d#
274 warn "DIEHOOK called in main context, Coro bug?\n";#d# 310 error "DIEHOOK called in main context, Coro bug?\n";#d#
275 return;#d# 311 return;#d#
276 }#d# 312 }#d#
277 313
278 # kill coroutine otherwise 314 # kill coroutine otherwise
279 Coro::terminate 315 Coro::terminate
301} 337}
302 338
303$EV::DIED = sub { 339$EV::DIED = sub {
304 Carp::cluck "error in event callback: @_"; 340 Carp::cluck "error in event callback: @_";
305}; 341};
342
343#############################################################################
344
345sub fork_call(&@);
346sub get_slot($;$$);
306 347
307############################################################################# 348#############################################################################
308 349
309=head2 UTILITY FUNCTIONS 350=head2 UTILITY FUNCTIONS
310 351
331 $d =~ s/([\x00-\x07\x09\x0b\x0c\x0e-\x1f])/sprintf "\\x%02x", ord($1)/ge; 372 $d =~ s/([\x00-\x07\x09\x0b\x0c\x0e-\x1f])/sprintf "\\x%02x", ord($1)/ge;
332 $d 373 $d
333 } || "[unable to dump $_[0]: '$@']"; 374 } || "[unable to dump $_[0]: '$@']";
334} 375}
335 376
377=item $scalar = load_file $path
378
379Loads the given file from path and returns its contents. Croaks on error
380and can block.
381
382=cut
383
384sub load_file($) {
385 0 <= aio_load $_[0], my $data
386 or Carp::croak "$_[0]: $!";
387
388 $data
389}
390
336=item $ref = cf::decode_json $json 391=item $ref = cf::decode_json $json
337 392
338Converts a JSON string into the corresponding perl data structure. 393Converts a JSON string into the corresponding perl data structure.
339 394
340=item $json = cf::encode_json $ref 395=item $json = cf::encode_json $ref
346our $json_coder = JSON::XS->new->utf8->max_size (1e6); # accept ~1mb max 401our $json_coder = JSON::XS->new->utf8->max_size (1e6); # accept ~1mb max
347 402
348sub encode_json($) { $json_coder->encode ($_[0]) } 403sub encode_json($) { $json_coder->encode ($_[0]) }
349sub decode_json($) { $json_coder->decode ($_[0]) } 404sub decode_json($) { $json_coder->decode ($_[0]) }
350 405
406=item $ref = cf::decode_storable $scalar
407
408Same as Coro::Storable::thaw, so blocks.
409
410=cut
411
412BEGIN { *decode_storable = \&Coro::Storable::thaw }
413
414=item $ref = cf::decode_yaml $scalar
415
416Same as YAML::XS::Load, but doesn't leak, because it forks (and thus blocks).
417
418=cut
419
420sub decode_yaml($) {
421 fork_call { YAML::XS::Load $_[0] } @_
422}
423
424=item $scalar = cf::unlzf $scalar
425
426Same as Compress::LZF::compress, but takes server ticks into account, so
427blocks.
428
429=cut
430
431sub unlzf($) {
432 # we assume 100mb/s minimum decompression speed (noncompressible data on a ~2ghz machine)
433 cf::get_slot +(length $_[0]) / 100_000_000, 0, "unlzf";
434 Compress::LZF::decompress $_[0]
435}
436
351=item cf::post_init { BLOCK } 437=item cf::post_init { BLOCK }
352 438
353Execute the given codeblock, I<after> all extensions have been (re-)loaded, 439Execute the given codeblock, I<after> all extensions have been (re-)loaded,
354but I<before> the server starts ticking again. 440but I<before> the server starts ticking again.
355 441
356The cdoeblock will have a single boolean argument to indicate whether this 442The codeblock will have a single boolean argument to indicate whether this
357is a reload or not. 443is a reload or not.
358 444
359=cut 445=cut
360 446
361sub post_init(&) { 447sub post_init(&) {
362 push @POST_INIT, shift; 448 push @POST_INIT, shift;
449}
450
451sub _post_init {
452 trace "running post_init jobs";
453
454 # run them in parallel...
455
456 my @join;
457
458 while () {
459 push @join, map &Coro::async ($_, 0), @POST_INIT;
460 @POST_INIT = ();
461
462 @join or last;
463
464 (pop @join)->join;
465 }
363} 466}
364 467
365=item cf::lock_wait $string 468=item cf::lock_wait $string
366 469
367Wait until the given lock is available. See cf::lock_acquire. 470Wait until the given lock is available. See cf::lock_acquire.
420 EV::periodic $start, $interval, 0, $cb 523 EV::periodic $start, $interval, 0, $cb
421} 524}
422 525
423=item cf::get_slot $time[, $priority[, $name]] 526=item cf::get_slot $time[, $priority[, $name]]
424 527
425Allocate $time seconds of blocking CPU time at priority C<$priority>: 528Allocate $time seconds of blocking CPU time at priority C<$priority>
426This call blocks and returns only when you have at least C<$time> seconds 529(default: 0): This call blocks and returns only when you have at least
427of cpu time till the next tick. The slot is only valid till the next cede. 530C<$time> seconds of cpu time till the next tick. The slot is only valid
531till the next cede.
532
533Background jobs should use a priority les than zero, interactive jobs
534should use 100 or more.
428 535
429The optional C<$name> can be used to identify the job to run. It might be 536The optional C<$name> can be used to identify the job to run. It might be
430used for statistical purposes and should identify the same time-class. 537used for statistical purposes and should identify the same time-class.
431 538
432Useful for short background jobs. 539Useful for short background jobs.
461 } 568 }
462 } 569 }
463 570
464 if (@SLOT_QUEUE) { 571 if (@SLOT_QUEUE) {
465 # we do not use wait_for_tick() as it returns immediately when tick is inactive 572 # we do not use wait_for_tick() as it returns immediately when tick is inactive
466 push @cf::WAIT_FOR_TICK, $signal; 573 $WAIT_FOR_TICK->wait;
467 $signal->wait;
468 } else { 574 } else {
469 $busy = 0; 575 $busy = 0;
470 Coro::schedule; 576 Coro::schedule;
471 } 577 }
472 } 578 }
512=cut 618=cut
513 619
514sub sync_job(&) { 620sub sync_job(&) {
515 my ($job) = @_; 621 my ($job) = @_;
516 622
517 if ($Coro::current == $Coro::main) { 623 if (in_main) {
518 my $time = AE::time; 624 my $time = AE::time;
519 625
520 # this is the main coro, too bad, we have to block 626 # this is the main coro, too bad, we have to block
521 # till the operation succeeds, freezing the server :/ 627 # till the operation succeeds, freezing the server :/
522 628
523 LOG llevError, Carp::longmess "sync job";#d# 629 #LOG llevError, Carp::longmess "sync job";#d#
524 630
525 my $freeze_guard = freeze_mainloop; 631 my $freeze_guard = freeze_mainloop;
526 632
527 my $busy = 1; 633 my $busy = 1;
528 my @res; 634 my @res;
529 635
530 (async { 636 (async {
531 $Coro::current->desc ("sync job coro"); 637 $Coro::current->desc ("sync job coro");
532 @res = eval { $job->() }; 638 @res = eval { $job->() };
533 warn $@ if $@; 639 error $@ if $@;
534 undef $busy; 640 undef $busy;
535 })->prio (Coro::PRIO_MAX); 641 })->prio (Coro::PRIO_MAX);
536 642
537 while ($busy) { 643 while ($busy) {
538 if (Coro::nready) { 644 if (Coro::nready) {
572 $EXT_CORO{$coro+0} = $coro; 678 $EXT_CORO{$coro+0} = $coro;
573 679
574 $coro 680 $coro
575} 681}
576 682
577=item fork_call { }, $args 683=item fork_call { }, @args
578 684
579Executes the given code block with the given arguments in a seperate 685Executes the given code block with the given arguments in a seperate
580process, returning the results. Everything must be serialisable with 686process, returning the results. Everything must be serialisable with
581Coro::Storable. May, of course, block. Note that the executed sub may 687Coro::Storable. May, of course, block. Note that the executed sub may
582never block itself or use any form of event handling. 688never block itself or use any form of event handling.
583 689
584=cut 690=cut
585 691
692sub post_fork {
693 reset_signals;
694}
695
586sub fork_call(&@) { 696sub fork_call(&@) {
587 my ($cb, @args) = @_; 697 my ($cb, @args) = @_;
588 698
589 # we seemingly have to make a local copy of the whole thing, 699 # we seemingly have to make a local copy of the whole thing,
590 # otherwise perl prematurely frees the stuff :/ 700 # otherwise perl prematurely frees the stuff :/
591 # TODO: investigate and fix (likely this will be rather laborious) 701 # TODO: investigate and fix (likely this will be rather laborious)
592 702
593 my @res = Coro::Util::fork_eval { 703 my @res = Coro::Util::fork_eval {
594 reset_signals; 704 cf::post_fork;
595 &$cb 705 &$cb
596 }, @args; 706 } @args;
597 707
598 wantarray ? @res : $res[-1] 708 wantarray ? @res : $res[-1]
599} 709}
600 710
601sub objinfo { 711sub objinfo {
651within each server. 761within each server.
652 762
653=cut 763=cut
654 764
655sub db_table($) { 765sub db_table($) {
766 cf::error "db_get called from main context"
767 if $Coro::current == $Coro::main;
768
656 my ($name) = @_; 769 my ($name) = @_;
657 my $db = BDB::db_create $DB_ENV; 770 my $db = BDB::db_create $DB_ENV;
658 771
659 eval { 772 eval {
660 $db->set_flags (BDB::CHKSUM); 773 $db->set_flags (BDB::CHKSUM);
670} 783}
671 784
672our $DB; 785our $DB;
673 786
674sub db_init { 787sub db_init {
675 cf::sync_job {
676 $DB ||= db_table "db"; 788 $DB ||= db_table "db";
677 };
678} 789}
679 790
680sub db_get($$) { 791sub db_get($$) {
681 my $key = "$_[0]/$_[1]"; 792 my $key = "$_[0]/$_[1]";
682 793
683 cf::sync_job { 794 cf::error "db_get called from main context"
795 if $Coro::current == $Coro::main;
796
684 BDB::db_get $DB, undef, $key, my $data; 797 BDB::db_get $DB, undef, $key, my $data;
685 798
686 $! ? () 799 $! ? ()
687 : $data 800 : $data
688 }
689} 801}
690 802
691sub db_put($$$) { 803sub db_put($$$) {
692 BDB::dbreq_pri 4; 804 BDB::dbreq_pri 4;
693 BDB::db_put $DB, undef, "$_[0]/$_[1]", $_[2], 0, sub { }; 805 BDB::db_put $DB, undef, "$_[0]/$_[1]", $_[2], 0, sub { };
721 833
722 my @data; 834 my @data;
723 my $md5; 835 my $md5;
724 836
725 for (0 .. $#$src) { 837 for (0 .. $#$src) {
726 0 <= aio_load $src->[$_], $data[$_] 838 $data[$_] = load_file $src->[$_];
727 or Carp::croak "$src->[$_]: $!";
728 } 839 }
729 840
730 # if processing is expensive, check 841 # if processing is expensive, check
731 # checksum first 842 # checksum first
732 if (1) { 843 if (1) {
749 860
750 my $t1 = Time::HiRes::time; 861 my $t1 = Time::HiRes::time;
751 my $data = $process->(\@data); 862 my $data = $process->(\@data);
752 my $t2 = Time::HiRes::time; 863 my $t2 = Time::HiRes::time;
753 864
754 warn "cache: '$id' processed in ", $t2 - $t1, "s\n"; 865 info "cache: '$id' processed in ", $t2 - $t1, "s\n";
755 866
756 db_put cache => "$id/data", $data; 867 db_put cache => "$id/data", $data;
757 db_put cache => "$id/md5" , $md5; 868 db_put cache => "$id/md5" , $md5;
758 db_put cache => "$id/meta", $meta; 869 db_put cache => "$id/meta", $meta;
759 870
769 880
770=cut 881=cut
771 882
772sub datalog($@) { 883sub datalog($@) {
773 my ($type, %kv) = @_; 884 my ($type, %kv) = @_;
774 warn "DATALOG ", JSON::XS->new->ascii->encode ({ %kv, type => $type }); 885 info "DATALOG ", JSON::XS->new->ascii->encode ({ %kv, type => $type });
775} 886}
776 887
777=back 888=back
778 889
779=cut 890=cut
974 1085
975 } elsif (exists $cb_id{$type}) { 1086 } elsif (exists $cb_id{$type}) {
976 _attach_cb $registry, $cb_id{$type}, $prio, shift @arg; 1087 _attach_cb $registry, $cb_id{$type}, $prio, shift @arg;
977 1088
978 } elsif (ref $type) { 1089 } elsif (ref $type) {
979 warn "attaching objects not supported, ignoring.\n"; 1090 error "attaching objects not supported, ignoring.\n";
980 1091
981 } else { 1092 } else {
982 shift @arg; 1093 shift @arg;
983 warn "attach argument '$type' not supported, ignoring.\n"; 1094 error "attach argument '$type' not supported, ignoring.\n";
984 } 1095 }
985 } 1096 }
986} 1097}
987 1098
988sub _object_attach { 1099sub _object_attach {
998 _attach $registry, $klass, @attach; 1109 _attach $registry, $klass, @attach;
999 } 1110 }
1000 1111
1001 $obj->{$name} = \%arg; 1112 $obj->{$name} = \%arg;
1002 } else { 1113 } else {
1003 warn "object uses attachment '$name' which is not available, postponing.\n"; 1114 info "object uses attachment '$name' which is not available, postponing.\n";
1004 } 1115 }
1005 1116
1006 $obj->{_attachment}{$name} = undef; 1117 $obj->{_attachment}{$name} = undef;
1007} 1118}
1008 1119
1067 1178
1068 for (@$callbacks) { 1179 for (@$callbacks) {
1069 eval { &{$_->[1]} }; 1180 eval { &{$_->[1]} };
1070 1181
1071 if ($@) { 1182 if ($@) {
1072 warn "$@";
1073 warn "... while processing $EVENT[$event][0](@_) event, skipping processing altogether.\n"; 1183 error "$@", "... while processing $EVENT[$event][0](@_) event, skipping processing altogether.\n";
1074 override; 1184 override;
1075 } 1185 }
1076 1186
1077 return 1 if $override; 1187 return 1 if $override;
1078 } 1188 }
1157 for (@$attach) { 1267 for (@$attach) {
1158 my ($klass, @attach) = @$_; 1268 my ($klass, @attach) = @$_;
1159 _attach $registry, $klass, @attach; 1269 _attach $registry, $klass, @attach;
1160 } 1270 }
1161 } else { 1271 } else {
1162 warn "object uses attachment '$name' that is not available, postponing.\n"; 1272 info "object uses attachment '$name' that is not available, postponing.\n";
1163 } 1273 }
1164 } 1274 }
1165} 1275}
1166 1276
1167cf::attachable->attach ( 1277cf::attachable->attach (
1194 my ($filename, $rdata, $objs) = @_; 1304 my ($filename, $rdata, $objs) = @_;
1195 1305
1196 sync_job { 1306 sync_job {
1197 if (length $$rdata) { 1307 if (length $$rdata) {
1198 utf8::decode (my $decname = $filename); 1308 utf8::decode (my $decname = $filename);
1199 warn sprintf "saving %s (%d,%d)\n", 1309 trace sprintf "saving %s (%d,%d)\n",
1200 $decname, length $$rdata, scalar @$objs 1310 $decname, length $$rdata, scalar @$objs
1201 if $VERBOSE_IO; 1311 if $VERBOSE_IO;
1202 1312
1203 if (my $fh = aio_open "$filename~", O_WRONLY | O_CREAT, 0600) { 1313 if (my $fh = aio_open "$filename~", O_WRONLY | O_CREAT, 0600) {
1204 aio_chmod $fh, SAVE_MODE; 1314 aio_chmod $fh, SAVE_MODE;
1205 aio_write $fh, 0, (length $$rdata), $$rdata, 0; 1315 aio_write $fh, 0, (length $$rdata), $$rdata, 0;
1228 aio_rename "$filename~", $filename; 1338 aio_rename "$filename~", $filename;
1229 1339
1230 $filename =~ s%/[^/]+$%%; 1340 $filename =~ s%/[^/]+$%%;
1231 aio_pathsync $filename if $cf::USE_FSYNC; 1341 aio_pathsync $filename if $cf::USE_FSYNC;
1232 } else { 1342 } else {
1233 warn "unable to save objects: $filename~: $!\n"; 1343 error "unable to save objects: $filename~: $!\n";
1234 } 1344 }
1235 } else { 1345 } else {
1236 aio_unlink $filename; 1346 aio_unlink $filename;
1237 aio_unlink "$filename.pst"; 1347 aio_unlink "$filename.pst";
1238 } 1348 }
1262 my $st = eval { Coro::Storable::thaw $av }; 1372 my $st = eval { Coro::Storable::thaw $av };
1263 $av = $st->{objs}; 1373 $av = $st->{objs};
1264 } 1374 }
1265 1375
1266 utf8::decode (my $decname = $filename); 1376 utf8::decode (my $decname = $filename);
1267 warn sprintf "loading %s (%d,%d)\n", 1377 trace sprintf "loading %s (%d,%d)\n",
1268 $decname, length $data, scalar @{$av || []} 1378 $decname, length $data, scalar @{$av || []}
1269 if $VERBOSE_IO; 1379 if $VERBOSE_IO;
1270 1380
1271 ($data, $av) 1381 ($data, $av)
1272} 1382}
1273 1383
1366 1476
1367 $pl->ext_reply ($reply, @reply) 1477 $pl->ext_reply ($reply, @reply)
1368 if $reply; 1478 if $reply;
1369 1479
1370 } else { 1480 } else {
1371 warn "player " . ($pl->ob->name) . " sent unparseable ext message: <$buf>\n"; 1481 error "player " . ($pl->ob->name) . " sent unparseable ext message: <$buf>\n";
1372 } 1482 }
1373 1483
1374 cf::override; 1484 cf::override;
1375 }, 1485 },
1376); 1486);
1387 1497
1388 $grp 1498 $grp
1389} 1499}
1390 1500
1391sub load_extensions { 1501sub load_extensions {
1502 info "loading extensions...";
1503
1392 cf::sync_job { 1504 cf::sync_job {
1393 my %todo; 1505 my %todo;
1394 1506
1395 for my $path (<$LIBDIR/*.ext>) { 1507 for my $path (<$LIBDIR/*.ext>) {
1396 next unless -r $path; 1508 next unless -r $path;
1414 1526
1415 $ext{meta} = { map { (split /=/, $_, 2)[0, 1] } split /\s+/, $1 } 1527 $ext{meta} = { map { (split /=/, $_, 2)[0, 1] } split /\s+/, $1 }
1416 if $source =~ /\A#!.*?perl.*?#\s*(.*)$/m; 1528 if $source =~ /\A#!.*?perl.*?#\s*(.*)$/m;
1417 1529
1418 $ext{source} = 1530 $ext{source} =
1419 "package $pkg; use 5.10.0; use strict 'vars', 'subs'; use utf8;\n" 1531 "package $pkg; use common::sense;\n"
1420 . "#line 1 \"$path\"\n{\n" 1532 . "#line 1 \"$path\"\n{\n"
1421 . $source 1533 . $source
1422 . "\n};\n1"; 1534 . "\n};\n1";
1423 1535
1424 $todo{$base} = \%ext; 1536 $todo{$base} = \%ext;
1436 for (split /,\s*/, $v->{meta}{depends}) { 1548 for (split /,\s*/, $v->{meta}{depends}) {
1437 next ext 1549 next ext
1438 unless exists $done{$_}; 1550 unless exists $done{$_};
1439 } 1551 }
1440 1552
1441 warn "... pass $pass, loading '$k' into '$v->{pkg}'\n"; 1553 trace "... pass $pass, loading '$k' into '$v->{pkg}'\n";
1442 1554
1443 my $active = eval $v->{source}; 1555 my $active = eval $v->{source};
1444 1556
1445 if (length $@) { 1557 if (length $@) {
1446 warn "$v->{path}: $@\n"; 1558 error "$v->{path}: $@\n";
1447 1559
1448 cf::cleanup "mandatory extension '$k' failed to load, exiting." 1560 cf::cleanup "mandatory extension '$k' failed to load, exiting."
1449 if exists $v->{meta}{mandatory}; 1561 if exists $v->{meta}{mandatory};
1450 1562
1451 warn "$v->{base}: optional extension cannot be loaded, skipping.\n"; 1563 warn "$v->{base}: optional extension cannot be loaded, skipping.\n";
1453 } else { 1565 } else {
1454 $done{$k} = delete $todo{$k}; 1566 $done{$k} = delete $todo{$k};
1455 push @EXTS, $v->{pkg}; 1567 push @EXTS, $v->{pkg};
1456 $progress = 1; 1568 $progress = 1;
1457 1569
1458 warn "$v->{base}: extension inactive.\n" 1570 info "$v->{base}: extension inactive.\n"
1459 unless $active; 1571 unless $active;
1460 } 1572 }
1461 } 1573 }
1462 1574
1463 unless ($progress) { 1575 unless ($progress) {
1465 1577
1466 while (my ($k, $v) = each %todo) { 1578 while (my ($k, $v) = each %todo) {
1467 cf::cleanup "mandatory extension '$k' has unresolved dependencies, exiting." 1579 cf::cleanup "mandatory extension '$k' has unresolved dependencies, exiting."
1468 if exists $v->{meta}{mandatory}; 1580 if exists $v->{meta}{mandatory};
1469 } 1581 }
1582
1583 last;
1470 } 1584 }
1471 } 1585 }
1472 }; 1586 };
1473} 1587}
1474 1588
1632 my $name = $pl->ob->name; 1746 my $name = $pl->ob->name;
1633 1747
1634 $pl->{deny_save} = 1; 1748 $pl->{deny_save} = 1;
1635 $pl->password ("*"); # this should lock out the player until we have nuked the dir 1749 $pl->password ("*"); # this should lock out the player until we have nuked the dir
1636 1750
1637 $pl->invoke (cf::EVENT_PLAYER_LOGOUT, 1) if $pl->active; 1751 $pl->invoke (cf::EVENT_PLAYER_LOGOUT, 1) if $pl->ns;
1638 $pl->deactivate; 1752 $pl->deactivate;
1753
1639 my $killer = cf::arch::get "killer_quit"; $pl->killer ($killer); $killer->destroy; 1754 my $killer = cf::arch::get "killer_quit"; $pl->killer ($killer); $killer->destroy;
1640 $pl->ob->check_score;
1641 $pl->invoke (cf::EVENT_PLAYER_QUIT); 1755 $pl->invoke (cf::EVENT_PLAYER_QUIT) if $pl->ns;
1756 ext::highscore::check ($pl->ob);
1757
1642 $pl->ns->destroy if $pl->ns; 1758 $pl->ns->destroy if $pl->ns;
1643 1759
1644 my $path = playerdir $pl; 1760 my $path = playerdir $pl;
1645 my $temp = "$path~$cf::RUNTIME~deleting~"; 1761 my $temp = "$path~$cf::RUNTIME~deleting~";
1646 aio_rename $path, $temp; 1762 aio_rename $path, $temp;
1812sub generate_random_map { 1928sub generate_random_map {
1813 my ($self, $rmp) = @_; 1929 my ($self, $rmp) = @_;
1814 1930
1815 my $lock = cf::lock_acquire "generate_random_map"; # the random map generator is NOT reentrant ATM 1931 my $lock = cf::lock_acquire "generate_random_map"; # the random map generator is NOT reentrant ATM
1816 1932
1817 # mit "rum" bekleckern, nicht
1818 $self->_create_random_map ( 1933 $self->_create_random_map ($rmp);
1819 $rmp->{wallstyle}, $rmp->{wall_name}, $rmp->{floorstyle}, $rmp->{monsterstyle},
1820 $rmp->{treasurestyle}, $rmp->{layoutstyle}, $rmp->{doorstyle}, $rmp->{decorstyle}, $rmp->{miningstyle},
1821 $rmp->{origin_map}, $rmp->{final_map}, $rmp->{exitstyle}, $rmp->{this_map},
1822 $rmp->{exit_on_final_map},
1823 $rmp->{xsize}, $rmp->{ysize},
1824 $rmp->{expand2x}, $rmp->{layoutoptions1}, $rmp->{layoutoptions2}, $rmp->{layoutoptions3},
1825 $rmp->{symmetry}, $rmp->{difficulty}, $rmp->{difficulty_given}, $rmp->{difficulty_increase},
1826 $rmp->{dungeon_level}, $rmp->{dungeon_depth}, $rmp->{decoroptions}, $rmp->{orientation},
1827 $rmp->{origin_y}, $rmp->{origin_x}, $rmp->{random_seed}, $rmp->{total_map_hp},
1828 $rmp->{map_layout_style}, $rmp->{treasureoptions}, $rmp->{symmetry_used},
1829 (cf::region::find $rmp->{region}), $rmp->{custom}
1830 )
1831} 1934}
1832 1935
1833=item cf::map->register ($regex, $prio) 1936=item cf::map->register ($regex, $prio)
1834 1937
1835Register a handler for the map path matching the given regex at the 1938Register a handler for the map path matching the given regex at the
1840 1943
1841sub register { 1944sub register {
1842 my (undef, $regex, $prio) = @_; 1945 my (undef, $regex, $prio) = @_;
1843 my $pkg = caller; 1946 my $pkg = caller;
1844 1947
1845 no strict;
1846 push @{"$pkg\::ISA"}, __PACKAGE__; 1948 push @{"$pkg\::ISA"}, __PACKAGE__;
1847 1949
1848 $EXT_MAP{$pkg} = [$prio, qr<$regex>]; 1950 $EXT_MAP{$pkg} = [$prio, qr<$regex>];
1849} 1951}
1850 1952
1862} 1964}
1863 1965
1864sub normalise { 1966sub normalise {
1865 my ($path, $base) = @_; 1967 my ($path, $base) = @_;
1866 1968
1867 $path = "$path"; # make sure its a string 1969 $path = "$path"; # make sure it's a string
1868 1970
1869 $path =~ s/\.map$//; 1971 $path =~ s/\.map$//;
1870 1972
1871 # map plan: 1973 # map plan:
1872 # 1974 #
1887 $base =~ s{[^/]+/?$}{}; 1989 $base =~ s{[^/]+/?$}{};
1888 $path = "$base/$path"; 1990 $path = "$base/$path";
1889 } 1991 }
1890 1992
1891 for ($path) { 1993 for ($path) {
1892 redo if s{//}{/};
1893 redo if s{/\.?/}{/}; 1994 redo if s{/\.?/}{/};
1894 redo if s{/[^/]+/\.\./}{/}; 1995 redo if s{/[^/]+/\.\./}{/};
1895 } 1996 }
1896 1997
1897 $path 1998 $path
1911 $self->init; # pass $1 etc. 2012 $self->init; # pass $1 etc.
1912 return $self; 2013 return $self;
1913 } 2014 }
1914 } 2015 }
1915 2016
1916 Carp::cluck "unable to resolve path '$path' (base '$base')."; 2017 Carp::cluck "unable to resolve path '$path' (base '$base')";
1917 () 2018 ()
1918} 2019}
1919 2020
2021# may re-bless or do other evil things
1920sub init { 2022sub init {
1921 my ($self) = @_; 2023 my ($self) = @_;
1922 2024
1923 $self 2025 $self
1924} 2026}
1989 $self->{load_path} = $path; 2091 $self->{load_path} = $path;
1990 2092
1991 1 2093 1
1992} 2094}
1993 2095
2096# used to laod the header of an original map
1994sub load_header_orig { 2097sub load_header_orig {
1995 my ($self) = @_; 2098 my ($self) = @_;
1996 2099
1997 $self->load_header_from ($self->load_path) 2100 $self->load_header_from ($self->load_path)
1998} 2101}
1999 2102
2103# used to laod the header of an instantiated map
2000sub load_header_temp { 2104sub load_header_temp {
2001 my ($self) = @_; 2105 my ($self) = @_;
2002 2106
2003 $self->load_header_from ($self->save_path) 2107 $self->load_header_from ($self->save_path)
2004} 2108}
2005 2109
2110# called after loading the header from an instantiated map
2006sub prepare_temp { 2111sub prepare_temp {
2007 my ($self) = @_; 2112 my ($self) = @_;
2008 2113
2009 $self->last_access ((delete $self->{last_access}) 2114 $self->last_access ((delete $self->{last_access})
2010 || $cf::RUNTIME); #d# 2115 || $cf::RUNTIME); #d#
2011 # safety 2116 # safety
2012 $self->{instantiate_time} = $cf::RUNTIME 2117 $self->{instantiate_time} = $cf::RUNTIME
2013 if $self->{instantiate_time} > $cf::RUNTIME; 2118 if $self->{instantiate_time} > $cf::RUNTIME;
2014} 2119}
2015 2120
2121# called after loading the header from an original map
2016sub prepare_orig { 2122sub prepare_orig {
2017 my ($self) = @_; 2123 my ($self) = @_;
2018 2124
2019 $self->{load_original} = 1; 2125 $self->{load_original} = 1;
2020 $self->{instantiate_time} = $cf::RUNTIME; 2126 $self->{instantiate_time} = $cf::RUNTIME;
2044 2150
2045sub find; 2151sub find;
2046sub find { 2152sub find {
2047 my ($path, $origin) = @_; 2153 my ($path, $origin) = @_;
2048 2154
2155 cf::cede_to_tick;
2156
2049 $path = normalise $path, $origin && $origin->path; 2157 $path = normalise $path, $origin;
2050 2158
2051 my $guard1 = cf::lock_acquire "map_data:$path";#d#remove 2159 my $guard1 = cf::lock_acquire "map_data:$path";#d#remove
2052 my $guard2 = cf::lock_acquire "map_find:$path"; 2160 my $guard2 = cf::lock_acquire "map_find:$path";
2053 2161
2054 $cf::MAP{$path} || do { 2162 $cf::MAP{$path} || do {
2085 2193
2086 { 2194 {
2087 my $guard = cf::lock_acquire "map_data:$path"; 2195 my $guard = cf::lock_acquire "map_data:$path";
2088 2196
2089 return unless $self->valid; 2197 return unless $self->valid;
2090 return unless $self->in_memory == cf::MAP_SWAPPED; 2198 return unless $self->state == cf::MAP_SWAPPED;
2091
2092 $self->in_memory (cf::MAP_LOADING);
2093 2199
2094 $self->alloc; 2200 $self->alloc;
2095 2201
2096 $self->pre_load; 2202 $self->pre_load;
2097 cf::cede_to_tick; 2203 cf::cede_to_tick;
2098 2204
2205 if (exists $self->{load_path}) {
2099 my $f = new_from_file cf::object::thawer $self->{load_path}; 2206 my $f = new_from_file cf::object::thawer $self->{load_path};
2100 $f->skip_block; 2207 $f->skip_block;
2101 $self->_load_objects ($f) 2208 $self->_load_objects ($f)
2102 or return; 2209 or return;
2103 2210
2104 $self->post_load_original 2211 $self->post_load_original
2105 if delete $self->{load_original}; 2212 if delete $self->{load_original};
2106 2213
2107 if (my $uniq = $self->uniq_path) { 2214 if (my $uniq = $self->uniq_path) {
2108 utf8::encode $uniq; 2215 utf8::encode $uniq;
2109 unless (aio_stat $uniq) { 2216 unless (aio_stat $uniq) {
2110 if (my $f = new_from_file cf::object::thawer $uniq) { 2217 if (my $f = new_from_file cf::object::thawer $uniq) {
2111 $self->clear_unique_items; 2218 $self->clear_unique_items;
2112 $self->_load_objects ($f); 2219 $self->_load_objects ($f);
2113 $f->resolve_delayed_derefs; 2220 $f->resolve_delayed_derefs;
2221 }
2114 } 2222 }
2115 } 2223 }
2116 }
2117 2224
2118 $f->resolve_delayed_derefs; 2225 $f->resolve_delayed_derefs;
2226 } else {
2227 $self->post_load_original
2228 if delete $self->{load_original};
2229 }
2230
2231 $self->state (cf::MAP_INACTIVE);
2119 2232
2120 cf::cede_to_tick; 2233 cf::cede_to_tick;
2121 # now do the right thing for maps 2234 # now do the right thing for maps
2122 $self->link_multipart_objects; 2235 $self->link_multipart_objects;
2123 $self->difficulty ($self->estimate_difficulty) 2236 $self->difficulty ($self->estimate_difficulty)
2127 unless ($self->{deny_activate}) { 2240 unless ($self->{deny_activate}) {
2128 $self->decay_objects; 2241 $self->decay_objects;
2129 $self->fix_auto_apply; 2242 $self->fix_auto_apply;
2130 $self->update_buttons; 2243 $self->update_buttons;
2131 cf::cede_to_tick; 2244 cf::cede_to_tick;
2132 $self->activate; 2245 #$self->activate; # no longer activate maps automatically
2133 } 2246 }
2134 2247
2135 $self->{last_save} = $cf::RUNTIME; 2248 $self->{last_save} = $cf::RUNTIME;
2136 $self->last_access ($cf::RUNTIME); 2249 $self->last_access ($cf::RUNTIME);
2137
2138 $self->in_memory (cf::MAP_ACTIVE);
2139 } 2250 }
2140 2251
2141 $self->post_load; 2252 $self->post_load;
2253
2254 1
2142} 2255}
2143 2256
2144# customize the map for a given player, i.e. 2257# customize the map for a given player, i.e.
2145# return the _real_ map. used by e.g. per-player 2258# return the _real_ map. used by e.g. per-player
2146# maps to change the path to ~playername/mappath 2259# maps to change the path to ~playername/mappath
2154# if $self->per_party; 2267# if $self->per_party;
2155 2268
2156 $self 2269 $self
2157} 2270}
2158 2271
2159# find and load all maps in the 3x3 area around a map
2160sub load_neighbours {
2161 my ($map) = @_;
2162
2163 my @neigh; # diagonal neighbours
2164
2165 for (0 .. 3) {
2166 my $neigh = $map->tile_path ($_)
2167 or next;
2168 $neigh = find $neigh, $map
2169 or next;
2170 $neigh->load;
2171
2172 # now find the diagonal neighbours
2173 push @neigh,
2174 [$neigh->tile_path (($_ + 3) % 4), $neigh],
2175 [$neigh->tile_path (($_ + 1) % 4), $neigh];
2176 }
2177
2178 for (grep defined $_->[0], @neigh) {
2179 my ($path, $origin) = @$_;
2180 my $neigh = find $path, $origin
2181 or next;
2182 $neigh->load;
2183 }
2184}
2185
2186sub find_sync { 2272sub find_sync {
2187 my ($path, $origin) = @_; 2273 my ($path, $origin) = @_;
2188 2274
2189 cf::sync_job { find $path, $origin } 2275 # it's a bug to call this from the main context
2276 return cf::LOG cf::llevError | cf::logBacktrace, "do_find_sync"
2277 if $Coro::current == $Coro::main;
2278
2279 find $path, $origin
2190} 2280}
2191 2281
2192sub do_load_sync { 2282sub do_load_sync {
2193 my ($map) = @_; 2283 my ($map) = @_;
2194 2284
2285 # it's a bug to call this from the main context
2195 cf::LOG cf::llevDebug | cf::logBacktrace, "do_load_sync" 2286 return cf::LOG cf::llevError | cf::logBacktrace, "do_load_sync"
2196 if $Coro::current == $Coro::main; 2287 if $Coro::current == $Coro::main;
2197 2288
2198 cf::sync_job { $map->load }; 2289 $map->load;
2199} 2290}
2200 2291
2201our %MAP_PREFETCH; 2292our %MAP_PREFETCH;
2202our $MAP_PREFETCHER = undef; 2293our $MAP_PREFETCHER = undef;
2203 2294
2204sub find_async { 2295sub find_async {
2205 my ($path, $origin, $load) = @_; 2296 my ($path, $origin, $load) = @_;
2206 2297
2207 $path = normalise $path, $origin && $origin->{path}; 2298 $path = normalise $path, $origin;
2208 2299
2209 if (my $map = $cf::MAP{$path}) { 2300 if (my $map = $cf::MAP{$path}) {
2210 return $map if !$load || $map->in_memory == cf::MAP_ACTIVE; 2301 return $map if !$load || $map->linkable;
2211 } 2302 }
2212 2303
2213 $MAP_PREFETCH{$path} |= $load; 2304 $MAP_PREFETCH{$path} |= $load;
2214 2305
2215 $MAP_PREFETCHER ||= cf::async { 2306 $MAP_PREFETCHER ||= cf::async {
2274sub swap_out { 2365sub swap_out {
2275 my ($self) = @_; 2366 my ($self) = @_;
2276 2367
2277 my $lock = cf::lock_acquire "map_data:$self->{path}"; 2368 my $lock = cf::lock_acquire "map_data:$self->{path}";
2278 2369
2279 return if $self->in_memory != cf::MAP_ACTIVE; 2370 return if !$self->linkable;
2280 return if $self->{deny_save}; 2371 return if $self->{deny_save};
2281 return if $self->players; 2372 return if $self->players;
2282 2373
2283 # first deactivate the map and "unlink" it from the core 2374 # first deactivate the map and "unlink" it from the core
2284 $self->deactivate; 2375 $self->deactivate;
2285 $_->clear_links_to ($self) for values %cf::MAP; 2376 $_->clear_links_to ($self) for values %cf::MAP;
2286 $self->in_memory (cf::MAP_SWAPPED); 2377 $self->state (cf::MAP_SWAPPED);
2287 2378
2288 # then atomically save 2379 # then atomically save
2289 $self->_save; 2380 $self->_save;
2290 2381
2291 # then free the map 2382 # then free the map
2315 2406
2316 my $lock = cf::lock_acquire "map_data:$self->{path}"; 2407 my $lock = cf::lock_acquire "map_data:$self->{path}";
2317 2408
2318 return if $self->players; 2409 return if $self->players;
2319 2410
2320 warn "resetting map ", $self->path, "\n"; 2411 cf::trace "resetting map ", $self->path, "\n";
2321 2412
2322 $self->in_memory (cf::MAP_SWAPPED); 2413 $self->state (cf::MAP_SWAPPED);
2323 2414
2324 # need to save uniques path 2415 # need to save uniques path
2325 unless ($self->{deny_save}) { 2416 unless ($self->{deny_save}) {
2326 my $uniq = $self->uniq_path; utf8::encode $uniq; 2417 my $uniq = $self->uniq_path; utf8::encode $uniq;
2327 2418
2657 # use -1 or undef as default coordinates, not 0, 0 2748 # use -1 or undef as default coordinates, not 0, 0
2658 ($x, $y) = ($map->enter_x, $map->enter_y) 2749 ($x, $y) = ($map->enter_x, $map->enter_y)
2659 if $x <= 0 && $y <= 0; 2750 if $x <= 0 && $y <= 0;
2660 2751
2661 $map->load; 2752 $map->load;
2662 $map->load_neighbours;
2663 2753
2664 return unless $self->contr->active; 2754 return unless $self->contr->active;
2665 2755
2666 local $self->{_prev_pos} = $link_pos; # ugly hack for rent.ext 2756 local $self->{_prev_pos} = $link_pos; # ugly hack for rent.ext
2667 if ($self->enter_map ($map, $x, $y)) { 2757 if ($self->enter_map ($map, $x, $y)) {
2690 2780
2691sub cf::object::player::goto { 2781sub cf::object::player::goto {
2692 my ($self, $path, $x, $y, $check, $done) = @_; 2782 my ($self, $path, $x, $y, $check, $done) = @_;
2693 2783
2694 if ($self->{_link_recursion} >= $MAX_LINKS) { 2784 if ($self->{_link_recursion} >= $MAX_LINKS) {
2695 warn "FATAL: link recursion exceeded, ", $self->name, " goto $path $x $y, redirecting."; 2785 error "FATAL: link recursion exceeded, ", $self->name, " goto $path $x $y, redirecting.";
2696 $self->failmsg ("Something went wrong inside the server - please contact an administrator!"); 2786 $self->failmsg ("Something went wrong inside the server - please contact an administrator!");
2697 ($path, $x, $y) = @$EMERGENCY_POSITION; 2787 ($path, $x, $y) = @$EMERGENCY_POSITION;
2698 } 2788 }
2699 2789
2700 # do generation counting so two concurrent goto's will be executed in-order 2790 # do generation counting so two concurrent goto's will be executed in-order
2723 ($path, $x, $y) = (undef, undef, undef); 2813 ($path, $x, $y) = (undef, undef, undef);
2724 } 2814 }
2725 } 2815 }
2726 2816
2727 my $map = eval { 2817 my $map = eval {
2728 my $map = defined $path ? cf::map::find $path : undef; 2818 my $map = defined $path ? cf::map::find $path, $self->map : undef;
2729 2819
2730 if ($map) { 2820 if ($map) {
2731 $map = $map->customise_for ($self); 2821 $map = $map->customise_for ($self);
2732 $map = $check->($map, $x, $y, $self) if $check && $map; 2822 $map = $check->($map, $x, $y, $self) if $check && $map;
2733 } else { 2823 } else {
2823 $Coro::current->{desc} = "enter_exit"; 2913 $Coro::current->{desc} = "enter_exit";
2824 2914
2825 unless (eval { 2915 unless (eval {
2826 $self->deactivate_recursive; # just to be sure 2916 $self->deactivate_recursive; # just to be sure
2827 2917
2828 # random map handling
2829 {
2830 my $guard = cf::lock_acquire "exit_prepare:$exit";
2831
2832 prepare_random_map $exit
2833 if $exit->slaying eq "/!";
2834 }
2835
2836 my $map = cf::map::normalise $exit->slaying, $exit->map && $exit->map->path; 2918 my $map = cf::map::normalise $exit->slaying, $exit->map;
2837 my $x = $exit->stats->hp; 2919 my $x = $exit->stats->hp;
2838 my $y = $exit->stats->sp; 2920 my $y = $exit->stats->sp;
2921
2922 # special map handling
2923 my $slaying = $exit->slaying;
2924
2925 # special map handling
2926 if ($slaying eq "/!") {
2927 my $guard = cf::lock_acquire "exit_prepare:$exit";
2928
2929 prepare_random_map $exit
2930 if $exit->slaying eq "/!"; # need to re-check after getting the lock
2931
2932 $map = $exit->slaying;
2933
2934 } elsif ($slaying eq '!up') {
2935 $map = $exit->map->tile_path (cf::TILE_UP);
2936 $x = $exit->x;
2937 $y = $exit->y;
2938
2939 } elsif ($slaying eq '!down') {
2940 $map = $exit->map->tile_path (cf::TILE_DOWN);
2941 $x = $exit->x;
2942 $y = $exit->y;
2943 }
2839 2944
2840 $self->goto ($map, $x, $y); 2945 $self->goto ($map, $x, $y);
2841 2946
2842 # if exit is damned, update players death & WoR home-position 2947 # if exit is damned, update players death & WoR home-position
2843 $self->contr->savebed ($map, $x, $y) 2948 $self->contr->savebed ($map, $x, $y)
2848 $self->message ("Something went wrong deep within the deliantra server. " 2953 $self->message ("Something went wrong deep within the deliantra server. "
2849 . "I'll try to bring you back to the map you were before. " 2954 . "I'll try to bring you back to the map you were before. "
2850 . "Please report this to the dungeon master!", 2955 . "Please report this to the dungeon master!",
2851 cf::NDI_UNIQUE | cf::NDI_RED); 2956 cf::NDI_UNIQUE | cf::NDI_RED);
2852 2957
2853 warn "ERROR in enter_exit: $@"; 2958 error "ERROR in enter_exit: $@";
2854 $self->leave_link; 2959 $self->leave_link;
2855 } 2960 }
2856 })->prio (1); 2961 })->prio (1);
2857} 2962}
2858 2963
3171 3276
3172 $ns->ext_reply ($reply, @reply) 3277 $ns->ext_reply ($reply, @reply)
3173 if $reply; 3278 if $reply;
3174 3279
3175 } else { 3280 } else {
3176 warn "client " . ($ns->pl ? $ns->pl->ob->name : $ns->host) . " sent unparseable exti message: <$buf>\n"; 3281 error "client " . ($ns->pl ? $ns->pl->ob->name : $ns->host) . " sent unparseable exti message: <$buf>\n";
3177 } 3282 }
3178 3283
3179 cf::override; 3284 cf::override;
3180 }, 3285 },
3181); 3286);
3262 decrease split destroy change_exp value msg lore send_msg)], 3367 decrease split destroy change_exp value msg lore send_msg)],
3263 ["cf::object::player" => qw(player)], 3368 ["cf::object::player" => qw(player)],
3264 ["cf::player" => qw(peaceful send_msg)], 3369 ["cf::player" => qw(peaceful send_msg)],
3265 ["cf::map" => qw(trigger)], 3370 ["cf::map" => qw(trigger)],
3266) { 3371) {
3267 no strict 'refs';
3268 my ($pkg, @funs) = @$_; 3372 my ($pkg, @funs) = @$_;
3269 *{"safe::$pkg\::$_"} = $safe_hole->wrap (\&{"$pkg\::$_"}) 3373 *{"safe::$pkg\::$_"} = $safe_hole->wrap (\&{"$pkg\::$_"})
3270 for @funs; 3374 for @funs;
3271} 3375}
3272 3376
3311 local @cf::_safe_eval_args = values %vars; 3415 local @cf::_safe_eval_args = values %vars;
3312 @res = wantarray ? eval eval : scalar eval $eval; 3416 @res = wantarray ? eval eval : scalar eval $eval;
3313 } 3417 }
3314 3418
3315 if ($@) { 3419 if ($@) {
3316 warn "$@"; 3420 warn "$@",
3317 warn "while executing safe code '$code'\n"; 3421 "while executing safe code '$code'\n",
3318 warn "with arguments " . (join " ", %vars) . "\n"; 3422 "with arguments " . (join " ", %vars) . "\n";
3319 } 3423 }
3320 3424
3321 wantarray ? @res : $res[0] 3425 wantarray ? @res : $res[0]
3322} 3426}
3323 3427
3357 # for this (global event?) 3461 # for this (global event?)
3358 %ext::player_env::MUSIC_FACE_CACHE = (); 3462 %ext::player_env::MUSIC_FACE_CACHE = ();
3359 3463
3360 my $enc = JSON::XS->new->utf8->canonical->relaxed; 3464 my $enc = JSON::XS->new->utf8->canonical->relaxed;
3361 3465
3362 warn "loading facedata from $path\n"; 3466 trace "loading facedata from $path\n";
3363 3467
3364 my $facedata; 3468 my $facedata = decode_storable load_file $path;
3365 0 < aio_load $path, $facedata
3366 or die "$path: $!";
3367
3368 $facedata = Coro::Storable::thaw $facedata;
3369 3469
3370 $facedata->{version} == 2 3470 $facedata->{version} == 2
3371 or cf::cleanup "$path: version mismatch, cannot proceed."; 3471 or cf::cleanup "$path: version mismatch, cannot proceed.";
3372 3472
3373 # patch in the exptable 3473 # patch in the exptable
3387 3487
3388 cf::face::set_visibility $idx, $info->{visibility}; 3488 cf::face::set_visibility $idx, $info->{visibility};
3389 cf::face::set_magicmap $idx, $info->{magicmap}; 3489 cf::face::set_magicmap $idx, $info->{magicmap};
3390 cf::face::set_data $idx, 0, $info->{data32}, $info->{hash32}; 3490 cf::face::set_data $idx, 0, $info->{data32}, $info->{hash32};
3391 cf::face::set_data $idx, 1, $info->{data64}, $info->{hash64}; 3491 cf::face::set_data $idx, 1, $info->{data64}, $info->{hash64};
3492 cf::face::set_data $idx, 2, $info->{glyph} , $info->{glyph} ;
3392 3493
3393 cf::cede_to_tick; 3494 cf::cede_to_tick;
3394 } 3495 }
3395 3496
3396 while (my ($face, $info) = each %$faces) { 3497 while (my ($face, $info) = each %$faces) {
3401 3502
3402 if (my $smooth = cf::face::find $info->{smooth}) { 3503 if (my $smooth = cf::face::find $info->{smooth}) {
3403 cf::face::set_smooth $idx, $smooth; 3504 cf::face::set_smooth $idx, $smooth;
3404 cf::face::set_smoothlevel $idx, $info->{smoothlevel}; 3505 cf::face::set_smoothlevel $idx, $info->{smoothlevel};
3405 } else { 3506 } else {
3406 warn "smooth face '$info->{smooth}' not found for face '$face'"; 3507 error "smooth face '$info->{smooth}' not found for face '$face'";
3407 } 3508 }
3408 3509
3409 cf::cede_to_tick; 3510 cf::cede_to_tick;
3410 } 3511 }
3411 } 3512 }
3429 my $idx = (cf::face::find $name) || cf::face::alloc $name; 3530 my $idx = (cf::face::find $name) || cf::face::alloc $name;
3430 3531
3431 cf::face::set_data $idx, 0, $info->{data}, $info->{hash}; 3532 cf::face::set_data $idx, 0, $info->{data}, $info->{hash};
3432 cf::face::set_type $idx, $info->{type}; 3533 cf::face::set_type $idx, $info->{type};
3433 } else { 3534 } else {
3434 $RESOURCE{$name} = $info; 3535 $RESOURCE{$name} = $info; # unused
3435 } 3536 }
3436 3537
3437 cf::cede_to_tick; 3538 cf::cede_to_tick;
3438 } 3539 }
3439 } 3540 }
3440 3541
3441 cf::global->invoke (EVENT_GLOBAL_RESOURCE_UPDATE); 3542 cf::global->invoke (EVENT_GLOBAL_RESOURCE_UPDATE);
3442 3543
3443 1 3544 1
3444} 3545}
3445
3446cf::global->attach (on_resource_update => sub {
3447 if (my $soundconf = $RESOURCE{"res/sound.conf"}) {
3448 $soundconf = JSON::XS->new->utf8->relaxed->decode ($soundconf->{data});
3449
3450 for (0 .. SOUND_CAST_SPELL_0 - 1) {
3451 my $sound = $soundconf->{compat}[$_]
3452 or next;
3453
3454 my $face = cf::face::find "sound/$sound->[1]";
3455 cf::sound::set $sound->[0] => $face;
3456 cf::sound::old_sound_index $_, $face; # gcfclient-compat
3457 }
3458
3459 while (my ($k, $v) = each %{$soundconf->{event}}) {
3460 my $face = cf::face::find "sound/$v";
3461 cf::sound::set $k => $face;
3462 }
3463 }
3464});
3465 3546
3466register_exticmd fx_want => sub { 3547register_exticmd fx_want => sub {
3467 my ($ns, $want) = @_; 3548 my ($ns, $want) = @_;
3468 3549
3469 while (my ($k, $v) = each %$want) { 3550 while (my ($k, $v) = each %$want) {
3508sub reload_treasures { 3589sub reload_treasures {
3509 load_resource_file "$DATADIR/treasures" 3590 load_resource_file "$DATADIR/treasures"
3510 or die "unable to load treasurelists\n"; 3591 or die "unable to load treasurelists\n";
3511} 3592}
3512 3593
3594sub reload_sound {
3595 trace "loading sound config from $DATADIR/sound\n";
3596
3597 my $soundconf = JSON::XS->new->utf8->relaxed->decode (load_file "$DATADIR/sound");
3598
3599 for (0 .. SOUND_CAST_SPELL_0 - 1) {
3600 my $sound = $soundconf->{compat}[$_]
3601 or next;
3602
3603 my $face = cf::face::find "sound/$sound->[1]";
3604 cf::sound::set $sound->[0] => $face;
3605 cf::sound::old_sound_index $_, $face; # gcfclient-compat
3606 }
3607
3608 while (my ($k, $v) = each %{$soundconf->{event}}) {
3609 my $face = cf::face::find "sound/$v";
3610 cf::sound::set $k => $face;
3611 }
3612}
3613
3513sub reload_resources { 3614sub reload_resources {
3514 warn "reloading resource files...\n"; 3615 trace "reloading resource files...\n";
3515 3616
3617 reload_exp_table;
3618 reload_materials;
3516 reload_facedata; 3619 reload_facedata;
3620 reload_sound;
3517 reload_archetypes; 3621 reload_archetypes;
3518 reload_regions; 3622 reload_regions;
3519 reload_treasures; 3623 reload_treasures;
3520 3624
3521 warn "finished reloading resource files\n"; 3625 trace "finished reloading resource files\n";
3522} 3626}
3523 3627
3524sub reload_config { 3628sub reload_config {
3525 warn "reloading config file...\n"; 3629 trace "reloading config file...\n";
3526 3630
3527 open my $fh, "<:utf8", "$CONFDIR/config" 3631 my $config = load_file "$CONFDIR/config";
3528 or return; 3632 utf8::decode $config;
3529 3633 *CFG = decode_yaml $config;
3530 local $/;
3531 *CFG = YAML::XS::Load scalar <$fh>;
3532 3634
3533 $EMERGENCY_POSITION = $CFG{emergency_position} || ["/world/world_104_115", 49, 38]; 3635 $EMERGENCY_POSITION = $CFG{emergency_position} || ["/world/world_104_115", 49, 38];
3534 3636
3535 $cf::map::MAX_RESET = $CFG{map_max_reset} if exists $CFG{map_max_reset}; 3637 $cf::map::MAX_RESET = $CFG{map_max_reset} if exists $CFG{map_max_reset};
3536 $cf::map::DEFAULT_RESET = $CFG{map_default_reset} if exists $CFG{map_default_reset}; 3638 $cf::map::DEFAULT_RESET = $CFG{map_default_reset} if exists $CFG{map_default_reset};
3540 $CFG{mlockall} ? eval "mlockall()" : eval "munlockall()" 3642 $CFG{mlockall} ? eval "mlockall()" : eval "munlockall()"
3541 and die "WARNING: m(un)lockall failed: $!\n"; 3643 and die "WARNING: m(un)lockall failed: $!\n";
3542 }; 3644 };
3543 warn $@ if $@; 3645 warn $@ if $@;
3544 } 3646 }
3545
3546 warn "finished reloading resource files\n";
3547} 3647}
3548 3648
3549sub pidfile() { 3649sub pidfile() {
3550 sysopen my $fh, $PIDFILE, O_RDWR | O_CREAT 3650 sysopen my $fh, $PIDFILE, O_RDWR | O_CREAT
3551 or die "$PIDFILE: $!"; 3651 or die "$PIDFILE: $!";
3564 seek $fh, 0, 0; 3664 seek $fh, 0, 0;
3565 print $fh $$; 3665 print $fh $$;
3566} 3666}
3567 3667
3568sub main_loop { 3668sub main_loop {
3569 warn "EV::loop starting\n"; 3669 trace "EV::loop starting\n";
3570 if (1) { 3670 if (1) {
3571 EV::loop; 3671 EV::loop;
3572 } 3672 }
3573 warn "EV::loop returned\n"; 3673 trace "EV::loop returned\n";
3574 goto &main_loop unless $REALLY_UNLOOP; 3674 goto &main_loop unless $REALLY_UNLOOP;
3575} 3675}
3576 3676
3577sub main { 3677sub main {
3578 cf::init_globals; # initialise logging 3678 cf::init_globals; # initialise logging
3579 3679
3580 LOG llevInfo, "Welcome to Deliantra, v" . VERSION; 3680 LOG llevInfo, "Welcome to Deliantra, v" . VERSION;
3581 LOG llevInfo, "Copyright (C) 2005-2008 Marc Alexander Lehmann / Robin Redeker / the Deliantra team."; 3681 LOG llevInfo, "Copyright (C) 2005-2011 Marc Alexander Lehmann / Robin Redeker / the Deliantra team.";
3582 LOG llevInfo, "Copyright (C) 1994 Mark Wedel."; 3682 LOG llevInfo, "Copyright (C) 1994 Mark Wedel.";
3583 LOG llevInfo, "Copyright (C) 1992 Frank Tore Johansen."; 3683 LOG llevInfo, "Copyright (C) 1992 Frank Tore Johansen.";
3584 3684
3585 $Coro::current->prio (Coro::PRIO_MAX); # give the main loop max. priority 3685 $Coro::current->prio (Coro::PRIO_MAX); # give the main loop max. priority
3586 3686
3594 }; 3694 };
3595 3695
3596 evthread_start IO::AIO::poll_fileno; 3696 evthread_start IO::AIO::poll_fileno;
3597 3697
3598 cf::sync_job { 3698 cf::sync_job {
3599 cf::init_experience; 3699 cf::incloader::init ();
3700
3600 cf::init_anim; 3701 cf::init_anim;
3601 cf::init_attackmess; 3702 cf::init_attackmess;
3602 cf::init_dynamic; 3703 cf::init_dynamic;
3603 3704
3604 cf::load_settings; 3705 cf::load_settings;
3605 cf::load_materials;
3606 3706
3607 reload_resources; 3707 reload_resources;
3608 reload_config; 3708 reload_config;
3609 db_init; 3709 db_init;
3610 3710
3622 3722
3623 # no (long-running) fork's whatsoever before this point(!) 3723 # no (long-running) fork's whatsoever before this point(!)
3624 use POSIX (); 3724 use POSIX ();
3625 POSIX::close delete $ENV{LOCKUTIL_LOCK_FD} if exists $ENV{LOCKUTIL_LOCK_FD}; 3725 POSIX::close delete $ENV{LOCKUTIL_LOCK_FD} if exists $ENV{LOCKUTIL_LOCK_FD};
3626 3726
3627 (pop @POST_INIT)->(0) while @POST_INIT; 3727 cf::_post_init 0;
3628 }; 3728 };
3629 3729
3630 cf::object::thawer::errors_are_fatal 0; 3730 cf::object::thawer::errors_are_fatal 0;
3631 warn "parse errors in files are no longer fatal from this point on.\n"; 3731 info "parse errors in files are no longer fatal from this point on.\n";
3632 3732
3733 AE::postpone {
3734 undef &main; # free gobs of memory :)
3735 };
3736
3633 main_loop; 3737 goto &main_loop;
3634} 3738}
3635 3739
3636############################################################################# 3740#############################################################################
3637# initialisation and cleanup 3741# initialisation and cleanup
3638 3742
3677 or return; 3781 or return;
3678 3782
3679 aio_rename "$RUNTIMEFILE~", $RUNTIMEFILE 3783 aio_rename "$RUNTIMEFILE~", $RUNTIMEFILE
3680 and return; 3784 and return;
3681 3785
3682 warn sprintf "runtime file written (%gs).\n", AE::time - $t0; 3786 trace sprintf "runtime file written (%gs).\n", AE::time - $t0;
3683 3787
3684 1 3788 1
3685} 3789}
3686 3790
3687our $uuid_lock; 3791our $uuid_lock;
3699 or return; 3803 or return;
3700 3804
3701 my $value = uuid_seq uuid_cur; 3805 my $value = uuid_seq uuid_cur;
3702 3806
3703 unless ($value) { 3807 unless ($value) {
3704 warn "cowardly refusing to write zero uuid value!\n"; 3808 info "cowardly refusing to write zero uuid value!\n";
3705 return; 3809 return;
3706 } 3810 }
3707 3811
3708 my $value = uuid_str $value + $uuid_skip; 3812 my $value = uuid_str $value + $uuid_skip;
3709 $uuid_skip = 0; 3813 $uuid_skip = 0;
3719 or return; 3823 or return;
3720 3824
3721 aio_rename "$uuid~", $uuid 3825 aio_rename "$uuid~", $uuid
3722 and return; 3826 and return;
3723 3827
3724 warn "uuid file written ($value).\n"; 3828 trace "uuid file written ($value).\n";
3725 3829
3726 1 3830 1
3727 3831
3728} 3832}
3729 3833
3735} 3839}
3736 3840
3737sub emergency_save() { 3841sub emergency_save() {
3738 my $freeze_guard = cf::freeze_mainloop; 3842 my $freeze_guard = cf::freeze_mainloop;
3739 3843
3740 warn "emergency_perl_save: enter\n"; 3844 info "emergency_perl_save: enter\n";
3845
3846 # this is a trade-off: we want to be very quick here, so
3847 # save all maps without fsync, and later call a global sync
3848 # (which in turn might be very very slow)
3849 local $USE_FSYNC = 0;
3741 3850
3742 cf::sync_job { 3851 cf::sync_job {
3743 # this is a trade-off: we want to be very quick here, so 3852 cf::write_runtime_sync; # external watchdog should not bark
3744 # save all maps without fsync, and later call a global sync
3745 # (which in turn might be very very slow)
3746 local $USE_FSYNC = 0;
3747 3853
3748 # use a peculiar iteration method to avoid tripping on perl 3854 # use a peculiar iteration method to avoid tripping on perl
3749 # refcount bugs in for. also avoids problems with players 3855 # refcount bugs in for. also avoids problems with players
3750 # and maps saved/destroyed asynchronously. 3856 # and maps saved/destroyed asynchronously.
3751 warn "emergency_perl_save: begin player save\n"; 3857 info "emergency_perl_save: begin player save\n";
3752 for my $login (keys %cf::PLAYER) { 3858 for my $login (keys %cf::PLAYER) {
3753 my $pl = $cf::PLAYER{$login} or next; 3859 my $pl = $cf::PLAYER{$login} or next;
3754 $pl->valid or next; 3860 $pl->valid or next;
3755 delete $pl->{unclean_save}; # not strictly necessary, but cannot hurt 3861 delete $pl->{unclean_save}; # not strictly necessary, but cannot hurt
3756 $pl->save; 3862 $pl->save;
3757 } 3863 }
3758 warn "emergency_perl_save: end player save\n"; 3864 info "emergency_perl_save: end player save\n";
3759 3865
3866 cf::write_runtime_sync; # external watchdog should not bark
3867
3760 warn "emergency_perl_save: begin map save\n"; 3868 info "emergency_perl_save: begin map save\n";
3761 for my $path (keys %cf::MAP) { 3869 for my $path (keys %cf::MAP) {
3762 my $map = $cf::MAP{$path} or next; 3870 my $map = $cf::MAP{$path} or next;
3763 $map->valid or next; 3871 $map->valid or next;
3764 $map->save; 3872 $map->save;
3765 } 3873 }
3766 warn "emergency_perl_save: end map save\n"; 3874 info "emergency_perl_save: end map save\n";
3767 3875
3876 cf::write_runtime_sync; # external watchdog should not bark
3877
3768 warn "emergency_perl_save: begin database checkpoint\n"; 3878 info "emergency_perl_save: begin database checkpoint\n";
3769 BDB::db_env_txn_checkpoint $DB_ENV; 3879 BDB::db_env_txn_checkpoint $DB_ENV;
3770 warn "emergency_perl_save: end database checkpoint\n"; 3880 info "emergency_perl_save: end database checkpoint\n";
3771 3881
3772 warn "emergency_perl_save: begin write uuid\n"; 3882 info "emergency_perl_save: begin write uuid\n";
3773 write_uuid_sync 1; 3883 write_uuid_sync 1;
3774 warn "emergency_perl_save: end write uuid\n"; 3884 info "emergency_perl_save: end write uuid\n";
3885
3886 cf::write_runtime_sync; # external watchdog should not bark
3887
3888 trace "emergency_perl_save: syncing database to disk";
3889 BDB::db_env_txn_checkpoint $DB_ENV;
3890
3891 info "emergency_perl_save: starting sync\n";
3892 IO::AIO::aio_sync sub {
3893 info "emergency_perl_save: finished sync\n";
3894 };
3895
3896 cf::write_runtime_sync; # external watchdog should not bark
3897
3898 trace "emergency_perl_save: flushing outstanding aio requests";
3899 while (IO::AIO::nreqs || BDB::nreqs) {
3900 Coro::AnyEvent::sleep 0.01; # let the sync_job do it's thing
3901 }
3902
3903 cf::write_runtime_sync; # external watchdog should not bark
3775 }; 3904 };
3776 3905
3777 warn "emergency_perl_save: starting sync()\n";
3778 IO::AIO::aio_sync sub {
3779 warn "emergency_perl_save: finished sync()\n";
3780 };
3781
3782 warn "emergency_perl_save: leave\n"; 3906 info "emergency_perl_save: leave\n";
3783} 3907}
3784 3908
3785sub post_cleanup { 3909sub post_cleanup {
3786 my ($make_core) = @_; 3910 my ($make_core) = @_;
3787 3911
3912 IO::AIO::flush;
3913
3788 warn Carp::longmess "post_cleanup backtrace" 3914 error Carp::longmess "post_cleanup backtrace"
3789 if $make_core; 3915 if $make_core;
3790 3916
3791 my $fh = pidfile; 3917 my $fh = pidfile;
3792 unlink $PIDFILE if <$fh> == $$; 3918 unlink $PIDFILE if <$fh> == $$;
3793} 3919}
3817 } 3943 }
3818} 3944}
3819 3945
3820sub do_reload_perl() { 3946sub do_reload_perl() {
3821 # can/must only be called in main 3947 # can/must only be called in main
3822 if (in_main) { 3948 unless (in_main) {
3823 warn "can only reload from main coroutine"; 3949 error "can only reload from main coroutine";
3824 return; 3950 return;
3825 } 3951 }
3826 3952
3827 return if $RELOAD++; 3953 return if $RELOAD++;
3828 3954
3829 my $t1 = AE::time; 3955 my $t1 = AE::time;
3830 3956
3831 while ($RELOAD) { 3957 while ($RELOAD) {
3832 warn "reloading..."; 3958 cf::get_slot 0.1, -1, "reload_perl";
3959 info "perl_reload: reloading...";
3833 3960
3834 warn "entering sync_job"; 3961 trace "perl_reload: entering sync_job";
3835 3962
3836 cf::sync_job { 3963 cf::sync_job {
3837 cf::write_runtime_sync; # external watchdog should not bark
3838 cf::emergency_save; 3964 #cf::emergency_save;
3839 cf::write_runtime_sync; # external watchdog should not bark
3840 3965
3841 warn "syncing database to disk";
3842 BDB::db_env_txn_checkpoint $DB_ENV;
3843
3844 # if anything goes wrong in here, we should simply crash as we already saved
3845
3846 warn "flushing outstanding aio requests";
3847 while (IO::AIO::nreqs || BDB::nreqs) {
3848 Coro::EV::timer_once 0.01; # let the sync_job do it's thing
3849 }
3850
3851 warn "cancelling all extension coros"; 3966 trace "perl_reload: cancelling all extension coros";
3852 $_->cancel for values %EXT_CORO; 3967 $_->cancel for values %EXT_CORO;
3853 %EXT_CORO = (); 3968 %EXT_CORO = ();
3854 3969
3855 warn "removing commands"; 3970 trace "perl_reload: removing commands";
3856 %COMMAND = (); 3971 %COMMAND = ();
3857 3972
3858 warn "removing ext/exti commands"; 3973 trace "perl_reload: removing ext/exti commands";
3859 %EXTCMD = (); 3974 %EXTCMD = ();
3860 %EXTICMD = (); 3975 %EXTICMD = ();
3861 3976
3862 warn "unloading/nuking all extensions"; 3977 trace "perl_reload: unloading/nuking all extensions";
3863 for my $pkg (@EXTS) { 3978 for my $pkg (@EXTS) {
3864 warn "... unloading $pkg"; 3979 trace "... unloading $pkg";
3865 3980
3866 if (my $cb = $pkg->can ("unload")) { 3981 if (my $cb = $pkg->can ("unload")) {
3867 eval { 3982 eval {
3868 $cb->($pkg); 3983 $cb->($pkg);
3869 1 3984 1
3870 } or warn "$pkg unloaded, but with errors: $@"; 3985 } or error "$pkg unloaded, but with errors: $@";
3871 } 3986 }
3872 3987
3873 warn "... clearing $pkg"; 3988 trace "... clearing $pkg";
3874 clear_package $pkg; 3989 clear_package $pkg;
3875 } 3990 }
3876 3991
3877 warn "unloading all perl modules loaded from $LIBDIR"; 3992 trace "perl_reload: unloading all perl modules loaded from $LIBDIR";
3878 while (my ($k, $v) = each %INC) { 3993 while (my ($k, $v) = each %INC) {
3879 next unless $v =~ /^\Q$LIBDIR\E\/.*\.pm$/; 3994 next unless $v =~ /^\Q$LIBDIR\E\/.*\.pm$/;
3880 3995
3881 warn "... unloading $k"; 3996 trace "... unloading $k";
3882 delete $INC{$k}; 3997 delete $INC{$k};
3883 3998
3884 $k =~ s/\.pm$//; 3999 $k =~ s/\.pm$//;
3885 $k =~ s/\//::/g; 4000 $k =~ s/\//::/g;
3886 4001
3889 } 4004 }
3890 4005
3891 clear_package $k; 4006 clear_package $k;
3892 } 4007 }
3893 4008
3894 warn "getting rid of safe::, as good as possible"; 4009 trace "perl_reload: getting rid of safe::, as good as possible";
3895 clear_package "safe::$_" 4010 clear_package "safe::$_"
3896 for qw(cf::attachable cf::object cf::object::player cf::client cf::player cf::map cf::party cf::region); 4011 for qw(cf::attachable cf::object cf::object::player cf::client cf::player cf::map cf::party cf::region);
3897 4012
3898 warn "unloading cf.pm \"a bit\""; 4013 trace "perl_reload: unloading cf.pm \"a bit\"";
3899 delete $INC{"cf.pm"}; 4014 delete $INC{"cf.pm"};
3900 delete $INC{"cf/$_.pm"} for @EXTRA_MODULES; 4015 delete $INC{"cf/$_.pm"} for @EXTRA_MODULES;
3901 4016
3902 # don't, removes xs symbols, too, 4017 # don't, removes xs symbols, too,
3903 # and global variables created in xs 4018 # and global variables created in xs
3904 #clear_package __PACKAGE__; 4019 #clear_package __PACKAGE__;
3905 4020
3906 warn "unload completed, starting to reload now"; 4021 info "perl_reload: unload completed, starting to reload now";
3907 4022
3908 warn "reloading cf.pm"; 4023 trace "perl_reload: reloading cf.pm";
3909 require cf; 4024 require cf;
3910 cf::_connect_to_perl_1; 4025 cf::_connect_to_perl_1;
3911 4026
3912 warn "loading config and database again"; 4027 trace "perl_reload: loading config and database again";
3913 cf::reload_config; 4028 cf::reload_config;
3914 4029
3915 warn "loading extensions"; 4030 trace "perl_reload: loading extensions";
3916 cf::load_extensions; 4031 cf::load_extensions;
3917 4032
3918 if ($REATTACH_ON_RELOAD) { 4033 if ($REATTACH_ON_RELOAD) {
3919 warn "reattaching attachments to objects/players"; 4034 trace "perl_reload: reattaching attachments to objects/players";
3920 _global_reattach; # objects, sockets 4035 _global_reattach; # objects, sockets
3921 warn "reattaching attachments to maps"; 4036 trace "perl_reload: reattaching attachments to maps";
3922 reattach $_ for values %MAP; 4037 reattach $_ for values %MAP;
3923 warn "reattaching attachments to players"; 4038 trace "perl_reload: reattaching attachments to players";
3924 reattach $_ for values %PLAYER; 4039 reattach $_ for values %PLAYER;
3925 } 4040 }
3926 4041
3927 warn "running post_init jobs"; 4042 cf::_post_init 1;
3928 (pop @POST_INIT)->(1) while @POST_INIT;
3929 4043
3930 warn "leaving sync_job"; 4044 trace "perl_reload: leaving sync_job";
3931 4045
3932 1 4046 1
3933 } or do { 4047 } or do {
3934 warn $@; 4048 error $@;
3935 cf::cleanup "error while reloading, exiting."; 4049 cf::cleanup "perl_reload: error, exiting.";
3936 }; 4050 };
3937 4051
3938 warn "reloaded";
3939 --$RELOAD; 4052 --$RELOAD;
3940 } 4053 }
3941 4054
3942 $t1 = AE::time - $t1; 4055 $t1 = AE::time - $t1;
3943 warn "reload completed in ${t1}s\n"; 4056 info "perl_reload: completed in ${t1}s\n";
3944}; 4057};
3945 4058
3946our $RELOAD_WATCHER; # used only during reload 4059our $RELOAD_WATCHER; # used only during reload
3947 4060
3948sub reload_perl() { 4061sub reload_perl() {
3969 reload_perl; 4082 reload_perl;
3970 }; 4083 };
3971 } 4084 }
3972}; 4085};
3973 4086
3974unshift @INC, $LIBDIR; 4087#############################################################################
3975 4088
3976my $bug_warning = 0; 4089my $bug_warning = 0;
3977 4090
3978our @WAIT_FOR_TICK;
3979our @WAIT_FOR_TICK_BEGIN;
3980
3981sub wait_for_tick { 4091sub wait_for_tick() {
3982 return Coro::cede if tick_inhibit || $Coro::current == $Coro::main; 4092 return Coro::AnyEvent::poll if tick_inhibit || $Coro::current == $Coro::main;
3983 4093
3984 my $signal = new Coro::Signal; 4094 $WAIT_FOR_TICK->wait;
3985 push @WAIT_FOR_TICK, $signal;
3986 $signal->wait;
3987} 4095}
3988 4096
3989sub wait_for_tick_begin { 4097sub wait_for_tick_begin() {
3990 return Coro::cede if tick_inhibit || $Coro::current == $Coro::main; 4098 return Coro::AnyEvent::poll if tick_inhibit || $Coro::current == $Coro::main;
3991 4099
3992 my $signal = new Coro::Signal; 4100 my $signal = new Coro::Signal;
3993 push @WAIT_FOR_TICK_BEGIN, $signal; 4101 push @WAIT_FOR_TICK_BEGIN, $signal;
3994 $signal->wait; 4102 $signal->wait;
3995} 4103}
3999 Carp::cluck "major BUG: server tick called outside of main coro, skipping it" 4107 Carp::cluck "major BUG: server tick called outside of main coro, skipping it"
4000 unless ++$bug_warning > 10; 4108 unless ++$bug_warning > 10;
4001 return; 4109 return;
4002 } 4110 }
4003 4111
4004 cf::server_tick; # one server iteration 4112 cf::one_tick; # one server iteration
4005 4113
4006 #for(1..3e6){} AE::now_update; $NOW=AE::now; # generate load #d# 4114 #for(1..3e6){} AE::now_update; $NOW=AE::now; # generate load #d#
4007 4115
4008 if ($NOW >= $NEXT_RUNTIME_WRITE) { 4116 if ($NOW >= $NEXT_RUNTIME_WRITE) {
4009 $NEXT_RUNTIME_WRITE = List::Util::max $NEXT_RUNTIME_WRITE + 10, $NOW + 5.; 4117 $NEXT_RUNTIME_WRITE = List::Util::max $NEXT_RUNTIME_WRITE + 10, $NOW + 5.;
4010 Coro::async_pool { 4118 Coro::async_pool {
4011 $Coro::current->{desc} = "runtime saver"; 4119 $Coro::current->{desc} = "runtime saver";
4012 write_runtime_sync 4120 write_runtime_sync
4013 or warn "ERROR: unable to write runtime file: $!"; 4121 or error "ERROR: unable to write runtime file: $!";
4014 }; 4122 };
4015 } 4123 }
4016 4124
4017 if (my $sig = shift @WAIT_FOR_TICK_BEGIN) { 4125 if (my $sig = shift @WAIT_FOR_TICK_BEGIN) {
4018 $sig->send; 4126 $sig->send;
4019 } 4127 }
4020 while (my $sig = shift @WAIT_FOR_TICK) { 4128 $WAIT_FOR_TICK->broadcast;
4021 $sig->send;
4022 }
4023 4129
4024 $LOAD = ($NOW - $TICK_START) / $TICK; 4130 $LOAD = ($NOW - $TICK_START) / $TICK;
4025 $LOADAVG = $LOADAVG * 0.75 + $LOAD * 0.25; 4131 $LOADAVG = $LOADAVG * 0.75 + $LOAD * 0.25;
4026 4132
4027 if (0) { 4133 if (0) {
4028 if ($NEXT_TICK) { 4134 if ($NEXT_TICK) {
4029 my $jitter = $TICK_START - $NEXT_TICK; 4135 my $jitter = $TICK_START - $NEXT_TICK;
4030 $JITTER = $JITTER * 0.75 + $jitter * 0.25; 4136 $JITTER = $JITTER * 0.75 + $jitter * 0.25;
4031 warn "jitter $JITTER\n";#d# 4137 debug "jitter $JITTER\n";#d#
4032 } 4138 }
4033 } 4139 }
4034} 4140}
4035 4141
4036{ 4142{
4037 # configure BDB 4143 # configure BDB
4038 4144
4039 BDB::min_parallel 16; 4145 BDB::min_parallel 16;
4040 BDB::max_poll_reqs $TICK * 0.1; 4146 BDB::max_poll_reqs $TICK * 0.1;
4041 $AnyEvent::BDB::WATCHER->priority (1); 4147 #$AnyEvent::BDB::WATCHER->priority (1);
4042 4148
4043 unless ($DB_ENV) { 4149 unless ($DB_ENV) {
4044 $DB_ENV = BDB::db_env_create; 4150 $DB_ENV = BDB::db_env_create;
4045 $DB_ENV->set_flags (BDB::AUTO_COMMIT | BDB::REGION_INIT); 4151 $DB_ENV->set_flags (BDB::AUTO_COMMIT | BDB::REGION_INIT);
4046 $DB_ENV->set_flags (&BDB::LOG_AUTOREMOVE ) if BDB::VERSION v0, v4.7; 4152 $DB_ENV->set_flags (&BDB::LOG_AUTOREMOVE ) if BDB::VERSION v0, v4.7;
4081 IO::AIO::min_parallel 8; 4187 IO::AIO::min_parallel 8;
4082 IO::AIO::max_poll_time $TICK * 0.1; 4188 IO::AIO::max_poll_time $TICK * 0.1;
4083 undef $AnyEvent::AIO::WATCHER; 4189 undef $AnyEvent::AIO::WATCHER;
4084} 4190}
4085 4191
4086my $_log_backtrace; 4192our $_log_backtrace;
4193our $_log_backtrace_last;
4087 4194
4088sub _log_backtrace { 4195sub _log_backtrace {
4089 my ($msg, @addr) = @_; 4196 my ($msg, @addr) = @_;
4090 4197
4091 $msg =~ s/\n//; 4198 $msg =~ s/\n$//;
4092 4199
4200 if ($_log_backtrace_last eq $msg) {
4201 LOG llevInfo, "[ABT] $msg\n";
4202 LOG llevInfo, "[ABT] [duplicate, suppressed]\n";
4093 # limit the # of concurrent backtraces 4203 # limit the # of concurrent backtraces
4094 if ($_log_backtrace < 2) { 4204 } elsif ($_log_backtrace < 2) {
4205 $_log_backtrace_last = $msg;
4095 ++$_log_backtrace; 4206 ++$_log_backtrace;
4096 my $perl_bt = Carp::longmess $msg; 4207 my $perl_bt = Carp::longmess $msg;
4097 async { 4208 async {
4098 $Coro::current->{desc} = "abt $msg"; 4209 $Coro::current->{desc} = "abt $msg";
4099 4210
4119 LOG llevInfo, "[ABT] $_\n" for @bt; 4230 LOG llevInfo, "[ABT] $_\n" for @bt;
4120 --$_log_backtrace; 4231 --$_log_backtrace;
4121 }; 4232 };
4122 } else { 4233 } else {
4123 LOG llevInfo, "[ABT] $msg\n"; 4234 LOG llevInfo, "[ABT] $msg\n";
4124 LOG llevInfo, "[ABT] [suppressed]\n"; 4235 LOG llevInfo, "[ABT] [overload, suppressed]\n";
4125 } 4236 }
4126} 4237}
4127 4238
4128# load additional modules 4239# load additional modules
4129require "cf/$_.pm" for @EXTRA_MODULES; 4240require "cf/$_.pm" for @EXTRA_MODULES;

Diff Legend

Removed lines
+ Added lines
< Changed lines
> Changed lines