| … | |
… | |
| 90 | our $LINK_MAP; # the special {link} map, which is always available |
90 | our $LINK_MAP; # the special {link} map, which is always available |
| 91 | |
91 | |
| 92 | # used to convert map paths into valid unix filenames by replacing / by ∕ |
92 | # used to convert map paths into valid unix filenames by replacing / by ∕ |
| 93 | our $PATH_SEP = "∕"; # U+2215, chosen purely for visual reasons |
93 | our $PATH_SEP = "∕"; # U+2215, chosen purely for visual reasons |
| 94 | |
94 | |
|
|
95 | our $LOAD; # a number between 0 (idle) and 1 (too many objects) |
|
|
96 | our $LOADAVG; # same thing, but with alpha-smoothing |
|
|
97 | our $tick_start; # for load detecting purposes |
|
|
98 | |
| 95 | binmode STDOUT; |
99 | binmode STDOUT; |
| 96 | binmode STDERR; |
100 | binmode STDERR; |
| 97 | |
101 | |
| 98 | # read virtual server time, if available |
102 | # read virtual server time, if available |
| 99 | unless ($RUNTIME || !-e "$LOCALDIR/runtime") { |
103 | unless ($RUNTIME || !-e "$LOCALDIR/runtime") { |
| … | |
… | |
| 139 | |
143 | |
| 140 | =item $cf::TICK |
144 | =item $cf::TICK |
| 141 | |
145 | |
| 142 | The interval between server ticks, in seconds. |
146 | The interval between server ticks, in seconds. |
| 143 | |
147 | |
|
|
148 | =item $cf::LOADAVG |
|
|
149 | |
|
|
150 | The current CPU load on the server (alpha-smoothed), as a value between 0 |
|
|
151 | (none) and 1 (overloaded), indicating how much time is spent on processing |
|
|
152 | objects per tick. Healthy values are < 0.5. |
|
|
153 | |
|
|
154 | =item $cf::LOAD |
|
|
155 | |
|
|
156 | The raw value load value from the last tick. |
|
|
157 | |
| 144 | =item %cf::CFG |
158 | =item %cf::CFG |
| 145 | |
159 | |
| 146 | Configuration for the server, loaded from C</etc/crossfire/config>, or |
160 | Configuration for the server, loaded from C</etc/crossfire/config>, or |
| 147 | from wherever your confdir points to. |
161 | from wherever your confdir points to. |
| 148 | |
162 | |
| … | |
… | |
| 161 | my $msg = join "", @_; |
175 | my $msg = join "", @_; |
| 162 | |
176 | |
| 163 | $msg .= "\n" |
177 | $msg .= "\n" |
| 164 | unless $msg =~ /\n$/; |
178 | unless $msg =~ /\n$/; |
| 165 | |
179 | |
| 166 | $msg =~ s/([\x00-\x09\x0b-\x1f])/sprintf "\\x%02x", ord $1/ge; |
180 | $msg =~ s/([\x00-\x08\x0b-\x1f])/sprintf "\\x%02x", ord $1/ge; |
| 167 | |
181 | |
| 168 | utf8::encode $msg; |
182 | utf8::encode $msg; |
| 169 | LOG llevError, $msg; |
183 | LOG llevError, $msg; |
| 170 | }; |
184 | }; |
| 171 | } |
185 | } |
| … | |
… | |
| 318 | |
332 | |
| 319 | sub sync_job(&) { |
333 | sub sync_job(&) { |
| 320 | my ($job) = @_; |
334 | my ($job) = @_; |
| 321 | |
335 | |
| 322 | if ($Coro::current == $Coro::main) { |
336 | if ($Coro::current == $Coro::main) { |
|
|
337 | my $time = Event::time; |
|
|
338 | |
| 323 | # this is the main coro, too bad, we have to block |
339 | # this is the main coro, too bad, we have to block |
| 324 | # till the operation succeeds, freezing the server :/ |
340 | # till the operation succeeds, freezing the server :/ |
| 325 | |
341 | |
| 326 | # TODO: use suspend/resume instead |
342 | # TODO: use suspend/resume instead |
| 327 | # (but this is cancel-safe) |
343 | # (but this is cancel-safe) |
| … | |
… | |
| 338 | |
354 | |
| 339 | while ($busy) { |
355 | while ($busy) { |
| 340 | Coro::cede or Event::one_event; |
356 | Coro::cede or Event::one_event; |
| 341 | } |
357 | } |
| 342 | |
358 | |
|
|
359 | $time = Event::time - $time; |
|
|
360 | |
|
|
361 | LOG llevError | logBacktrace, Carp::longmess "long sync job" |
|
|
362 | if $time > $TICK * 0.5 && $TICK_WATCHER->is_active; |
|
|
363 | |
|
|
364 | $tick_start += $time; # do not account sync jobs to server load |
|
|
365 | |
| 343 | wantarray ? @res : $res[0] |
366 | wantarray ? @res : $res[0] |
| 344 | } else { |
367 | } else { |
| 345 | # we are in another coroutine, how wonderful, everything just works |
368 | # we are in another coroutine, how wonderful, everything just works |
| 346 | |
369 | |
| 347 | $job->() |
370 | $job->() |
| … | |
… | |
| 367 | |
390 | |
| 368 | $coro |
391 | $coro |
| 369 | } |
392 | } |
| 370 | |
393 | |
| 371 | sub write_runtime { |
394 | sub write_runtime { |
|
|
395 | my $runtime = "$LOCALDIR/runtime"; |
|
|
396 | |
|
|
397 | # first touch the runtime file to show we are still running: |
|
|
398 | # the fsync below can take a very very long time. |
|
|
399 | |
|
|
400 | if (my $fh = aio_open $runtime, O_WRONLY, 0) { |
|
|
401 | utime undef, undef, $fh; |
|
|
402 | } |
|
|
403 | |
| 372 | my $guard = cf::lock_acquire "write_runtime"; |
404 | my $guard = cf::lock_acquire "write_runtime"; |
| 373 | |
|
|
| 374 | my $runtime = "$LOCALDIR/runtime"; |
|
|
| 375 | |
405 | |
| 376 | my $fh = aio_open "$runtime~", O_WRONLY | O_CREAT, 0644 |
406 | my $fh = aio_open "$runtime~", O_WRONLY | O_CREAT, 0644 |
| 377 | or return; |
407 | or return; |
| 378 | |
408 | |
| 379 | my $value = $cf::RUNTIME + 90 + 10; |
409 | my $value = $cf::RUNTIME + 90 + 10; |
| … | |
… | |
| 385 | |
415 | |
| 386 | # always fsync - this file is important |
416 | # always fsync - this file is important |
| 387 | aio_fsync $fh |
417 | aio_fsync $fh |
| 388 | and return; |
418 | and return; |
| 389 | |
419 | |
|
|
420 | # touch it again to show we are up-to-date |
|
|
421 | utime undef, undef, $fh; |
|
|
422 | |
| 390 | close $fh |
423 | close $fh |
| 391 | or return; |
424 | or return; |
| 392 | |
425 | |
| 393 | aio_rename "$runtime~", $runtime |
426 | aio_rename "$runtime~", $runtime |
| 394 | and return; |
427 | and return; |
|
|
428 | |
|
|
429 | warn "runtime file written.\n";#d# |
| 395 | |
430 | |
| 396 | 1 |
431 | 1 |
| 397 | } |
432 | } |
| 398 | |
433 | |
| 399 | =item cf::datalog type => key => value, ... |
434 | =item cf::datalog type => key => value, ... |
| … | |
… | |
| 1490 | my ($self) = @_; |
1525 | my ($self) = @_; |
| 1491 | |
1526 | |
| 1492 | local $self->{deny_reset} = 1; # loading can take a long time |
1527 | local $self->{deny_reset} = 1; # loading can take a long time |
| 1493 | |
1528 | |
| 1494 | my $path = $self->{path}; |
1529 | my $path = $self->{path}; |
|
|
1530 | |
|
|
1531 | { |
| 1495 | my $guard = cf::lock_acquire "map_load:$path"; |
1532 | my $guard = cf::lock_acquire "map_load:$path"; |
| 1496 | |
1533 | |
| 1497 | return if $self->in_memory != cf::MAP_SWAPPED; |
1534 | return if $self->in_memory != cf::MAP_SWAPPED; |
| 1498 | |
1535 | |
| 1499 | $self->in_memory (cf::MAP_LOADING); |
1536 | $self->in_memory (cf::MAP_LOADING); |
| 1500 | |
1537 | |
| 1501 | $self->alloc; |
1538 | $self->alloc; |
| 1502 | |
1539 | |
| 1503 | $self->pre_load; |
1540 | $self->pre_load; |
| 1504 | Coro::cede; |
|
|
| 1505 | |
|
|
| 1506 | $self->_load_objects ($self->{load_path}, 1) |
|
|
| 1507 | or return; |
|
|
| 1508 | |
|
|
| 1509 | $self->set_object_flag (cf::FLAG_OBJ_ORIGINAL, 1) |
|
|
| 1510 | if delete $self->{load_original}; |
|
|
| 1511 | |
|
|
| 1512 | if (my $uniq = $self->uniq_path) { |
|
|
| 1513 | utf8::encode $uniq; |
|
|
| 1514 | if (aio_open $uniq, O_RDONLY, 0) { |
|
|
| 1515 | $self->clear_unique_items; |
|
|
| 1516 | $self->_load_objects ($uniq, 0); |
|
|
| 1517 | } |
|
|
| 1518 | } |
|
|
| 1519 | |
|
|
| 1520 | Coro::cede; |
|
|
| 1521 | # now do the right thing for maps |
|
|
| 1522 | $self->link_multipart_objects; |
|
|
| 1523 | Coro::cede; |
|
|
| 1524 | |
|
|
| 1525 | unless ($self->{deny_activate}) { |
|
|
| 1526 | $self->decay_objects; |
|
|
| 1527 | $self->fix_auto_apply; |
|
|
| 1528 | $self->update_buttons; |
|
|
| 1529 | Coro::cede; |
1541 | Coro::cede; |
| 1530 | $self->set_darkness_map; |
1542 | |
|
|
1543 | $self->_load_objects ($self->{load_path}, 1) |
|
|
1544 | or return; |
|
|
1545 | |
|
|
1546 | $self->set_object_flag (cf::FLAG_OBJ_ORIGINAL, 1) |
|
|
1547 | if delete $self->{load_original}; |
|
|
1548 | |
|
|
1549 | if (my $uniq = $self->uniq_path) { |
|
|
1550 | utf8::encode $uniq; |
|
|
1551 | if (aio_open $uniq, O_RDONLY, 0) { |
|
|
1552 | $self->clear_unique_items; |
|
|
1553 | $self->_load_objects ($uniq, 0); |
|
|
1554 | } |
|
|
1555 | } |
|
|
1556 | |
|
|
1557 | Coro::cede; |
|
|
1558 | # now do the right thing for maps |
|
|
1559 | $self->link_multipart_objects; |
| 1531 | $self->difficulty ($self->estimate_difficulty) |
1560 | $self->difficulty ($self->estimate_difficulty) |
| 1532 | unless $self->difficulty; |
1561 | unless $self->difficulty; |
| 1533 | Coro::cede; |
1562 | Coro::cede; |
|
|
1563 | |
|
|
1564 | unless ($self->{deny_activate}) { |
|
|
1565 | $self->decay_objects; |
|
|
1566 | $self->fix_auto_apply; |
|
|
1567 | $self->update_buttons; |
|
|
1568 | Coro::cede; |
|
|
1569 | $self->set_darkness_map; |
|
|
1570 | Coro::cede; |
| 1534 | $self->activate; |
1571 | $self->activate; |
| 1535 | Coro::cede; |
1572 | } |
|
|
1573 | |
|
|
1574 | $self->in_memory (cf::MAP_IN_MEMORY); |
| 1536 | } |
1575 | } |
| 1537 | |
1576 | |
| 1538 | $self->post_load; |
1577 | $self->post_load; |
| 1539 | Coro::cede; |
|
|
| 1540 | |
|
|
| 1541 | $self->in_memory (cf::MAP_IN_MEMORY); |
|
|
| 1542 | } |
1578 | } |
| 1543 | |
1579 | |
| 1544 | sub customise_for { |
1580 | sub customise_for { |
| 1545 | my ($self, $ob) = @_; |
1581 | my ($self, $ob) = @_; |
| 1546 | |
1582 | |
| … | |
… | |
| 2434 | |
2470 | |
| 2435 | die $$res unless "ARRAY" eq ref $res; |
2471 | die $$res unless "ARRAY" eq ref $res; |
| 2436 | |
2472 | |
| 2437 | return wantarray ? @$res : $res->[-1]; |
2473 | return wantarray ? @$res : $res->[-1]; |
| 2438 | } else { |
2474 | } else { |
|
|
2475 | reset_signals; |
| 2439 | local $SIG{__WARN__}; |
2476 | local $SIG{__WARN__}; |
|
|
2477 | local $SIG{__DIE__}; |
| 2440 | eval { |
2478 | eval { |
| 2441 | local $SIG{__DIE__}; |
|
|
| 2442 | close $fh1; |
2479 | close $fh1; |
| 2443 | |
2480 | |
| 2444 | my @res = eval { $cb->(@args) }; |
2481 | my @res = eval { $cb->(@args) }; |
| 2445 | syswrite $fh2, Coro::Storable::freeze +($@ ? \"$@" : \@res); |
2482 | syswrite $fh2, Coro::Storable::freeze +($@ ? \"$@" : \@res); |
| 2446 | }; |
2483 | }; |
| … | |
… | |
| 2822 | Carp::cluck "major BUG: server tick called outside of main coro, skipping it" |
2859 | Carp::cluck "major BUG: server tick called outside of main coro, skipping it" |
| 2823 | unless ++$bug_warning > 10; |
2860 | unless ++$bug_warning > 10; |
| 2824 | return; |
2861 | return; |
| 2825 | } |
2862 | } |
| 2826 | |
2863 | |
| 2827 | $NOW = Event::time; |
2864 | $NOW = $tick_start = Event::time; |
| 2828 | |
2865 | |
| 2829 | cf::server_tick; # one server iteration |
2866 | cf::server_tick; # one server iteration |
| 2830 | |
2867 | |
| 2831 | $RUNTIME += $TICK; |
2868 | $RUNTIME += $TICK; |
| 2832 | $NEXT_TICK += $TICK; |
2869 | $NEXT_TICK += $TICK; |
| … | |
… | |
| 2840 | } |
2877 | } |
| 2841 | |
2878 | |
| 2842 | # my $AFTER = Event::time; |
2879 | # my $AFTER = Event::time; |
| 2843 | # warn $AFTER - $NOW;#d# |
2880 | # warn $AFTER - $NOW;#d# |
| 2844 | |
2881 | |
|
|
2882 | if (my $sig = shift @WAIT_FOR_TICK_BEGIN) { |
|
|
2883 | $sig->send; |
|
|
2884 | } |
|
|
2885 | while (my $sig = shift @WAIT_FOR_TICK) { |
|
|
2886 | $sig->send; |
|
|
2887 | } |
|
|
2888 | |
|
|
2889 | $NOW = Event::time; |
|
|
2890 | |
| 2845 | # if we are delayed by four ticks or more, skip them all |
2891 | # if we are delayed by four ticks or more, skip them all |
| 2846 | $NEXT_TICK = Event::time if Event::time >= $NEXT_TICK + $TICK * 4; |
2892 | $NEXT_TICK = $NOW if $NOW >= $NEXT_TICK + $TICK * 4; |
| 2847 | |
2893 | |
| 2848 | $TICK_WATCHER->at ($NEXT_TICK); |
2894 | $TICK_WATCHER->at ($NEXT_TICK); |
| 2849 | $TICK_WATCHER->start; |
2895 | $TICK_WATCHER->start; |
| 2850 | |
2896 | |
| 2851 | if (my $sig = shift @WAIT_FOR_TICK_BEGIN) { |
2897 | $LOAD = ($NOW - $tick_start) / $TICK; |
| 2852 | $sig->send; |
2898 | $LOADAVG = $LOADAVG * 0.75 + $LOAD * 0.25; |
| 2853 | } |
|
|
| 2854 | while (my $sig = shift @WAIT_FOR_TICK) { |
|
|
| 2855 | $sig->send; |
|
|
| 2856 | } |
|
|
| 2857 | |
2899 | |
| 2858 | _post_tick; |
2900 | _post_tick; |
|
|
2901 | |
|
|
2902 | |
| 2859 | }, |
2903 | }, |
| 2860 | ); |
2904 | ); |
| 2861 | |
2905 | |
| 2862 | { |
2906 | { |
| 2863 | BDB::max_poll_time $TICK * 0.1; |
2907 | BDB::max_poll_time $TICK * 0.1; |
| … | |
… | |
| 2922 | prio => 6, |
2966 | prio => 6, |
| 2923 | cb => \&IO::AIO::poll_cb, |
2967 | cb => \&IO::AIO::poll_cb, |
| 2924 | ); |
2968 | ); |
| 2925 | } |
2969 | } |
| 2926 | |
2970 | |
|
|
2971 | my $_log_backtrace; |
|
|
2972 | |
|
|
2973 | sub _log_backtrace { |
|
|
2974 | my ($msg, @addr) = @_; |
|
|
2975 | |
|
|
2976 | $msg =~ s/\n//; |
|
|
2977 | |
|
|
2978 | # limit the # of concurrent backtraces |
|
|
2979 | if ($_log_backtrace < 2) { |
|
|
2980 | ++$_log_backtrace; |
|
|
2981 | async { |
|
|
2982 | my @bt = fork_call { |
|
|
2983 | @addr = map { sprintf "%x", $_ } @addr; |
|
|
2984 | my $self = (-f "/proc/$$/exe") ? "/proc/$$/exe" : $^X; |
|
|
2985 | open my $fh, "exec addr2line -C -f -i -e \Q$self\E @addr 2>&1 |" |
|
|
2986 | or die "addr2line: $!"; |
|
|
2987 | |
|
|
2988 | my @funcs; |
|
|
2989 | my @res = <$fh>; |
|
|
2990 | chomp for @res; |
|
|
2991 | while (@res) { |
|
|
2992 | my ($func, $line) = splice @res, 0, 2, (); |
|
|
2993 | push @funcs, "[$func] $line"; |
|
|
2994 | } |
|
|
2995 | |
|
|
2996 | @funcs |
|
|
2997 | }; |
|
|
2998 | |
|
|
2999 | LOG llevInfo, "[ABT] $msg\n"; |
|
|
3000 | LOG llevInfo, "[ABT] $_\n" for @bt; |
|
|
3001 | --$_log_backtrace; |
|
|
3002 | }; |
|
|
3003 | } else { |
|
|
3004 | LOG llevInfo, "[ABT] $msg\n"; |
|
|
3005 | LOG llevInfo, "[ABT] [suppressed]\n"; |
|
|
3006 | } |
|
|
3007 | } |
|
|
3008 | |
| 2927 | # load additional modules |
3009 | # load additional modules |
| 2928 | use cf::pod; |
3010 | use cf::pod; |
| 2929 | |
3011 | |
| 2930 | END { cf::emergency_save } |
3012 | END { cf::emergency_save } |
| 2931 | |
3013 | |
