stated.in 25.6 KB
Newer Older
Robert Ricci's avatar
Robert Ricci committed
1
#!/usr/bin/perl -w
Leigh B. Stoller's avatar
Leigh B. Stoller committed
2 3 4 5 6 7
#
# EMULAB-COPYRIGHT
# Copyright (c) 2000-2002 University of Utah and the Flux Group.
# All rights reserved.
#

Robert Ricci's avatar
Robert Ricci committed
8 9 10
#
# stated - A daemon to monitor the states of nodes in the testbed. Recives
# state change notification through the event system, and writes the new
11 12
# state into the database. Also watches for invalid transitions, timeouts, 
# and performs other state-related control functions.
Robert Ricci's avatar
Robert Ricci committed
13 14 15 16
#
# Send it a HUP signal to get it to reload the timeout and transition
# information. Periodically reloads this information regardless, though.
#
17
# Will restart when sent SIGUSR1, by exec'ing its executable again.
Robert Ricci's avatar
Robert Ricci committed
18
#
19

Robert Ricci's avatar
Robert Ricci committed
20 21
# Configure variables
use lib '@prefix@/lib';
22
my $TB = "@prefix@";
Robert Ricci's avatar
Robert Ricci committed
23
my $BOSSNODE = "@BOSSNODE@";
24
my $TBOPS = '@TBSTATEDEMAIL@';
25
my $REALTBOPS = "@TBOPSEMAIL@";
26
my $TBDBNAME = "@TBDBNAME@";
27
my $osselect = "$TB/bin/os_select";
Robert Ricci's avatar
Robert Ricci committed
28 29 30 31 32 33 34

$| = 1;

use event;
use libdb;
use libtestbed;
use Getopt::Std;
35
#use strict;
Robert Ricci's avatar
Robert Ricci committed
36
use English;
37
use POSIX; # for strftime, and sigprocmask and friends
38
use Fcntl; # file constants for pidfile
Mac Newbold's avatar
Mac Newbold committed
39 40 41 42 43
use Sys::Syslog;
# Important note about syslog: It defaults to using an inet socket,
# but 'syslogd -s' (the default) doesn't listen for one. So either
# run syslogd without -s, or use setlogsock('unix') before openlog.
# (To get setlocksock: 'use Sys::Syslog qw(:DEFAULT setlogsock);' )
Robert Ricci's avatar
Robert Ricci committed
44 45 46 47

# Number of iterations (roughly, seconds) after which we'll reload 
# information from the database. This is so we don't end up with information
# that's _too_ out of sync.
48 49
my $reload_time = 600;
my $last_reload = 0;
Robert Ricci's avatar
Robert Ricci committed
50 51 52 53 54

# Process command-line arguments

sub usage {
	print << "END";
55 56 57
Usage: $0 [-h] [-d] [-s server] [-p port]
-h              This message
-d              Turn on debugging output, and don't go into the background
Robert Ricci's avatar
Robert Ricci committed
58 59
-s server       Use specified server, instead of this site's bossnode
-p port	        Use specified port
60
Send SIGHUP to reload database state, or SIGUSR1 to restart completely.
Robert Ricci's avatar
Robert Ricci committed
61 62 63
END
}

64
my @args = @ARGV; # save a copy for restart before we mess with them.
Robert Ricci's avatar
Robert Ricci committed
65
my %opt = ();
66
getopts("ds:p:h",\%opt);
Robert Ricci's avatar
Robert Ricci committed
67 68 69 70

if ($opt{h}) { exit &usage; }
if (@ARGV) { exit &usage; }

71
my ($server,$port,$debug);
Robert Ricci's avatar
Robert Ricci committed
72 73 74 75
if ($opt{s}) { $server = $opt{s}; } else { $server = $BOSSNODE; }
if ($opt{p}) { $port = $opt{p}; }
if ($opt{d}) { $debug = 1; } else { $debug = 0; }

76 77 78 79 80 81 82 83 84
# Grab some constants into variables
my $TBRESET      = TBDB_TBCONTROL_RESET;
my $TBRELOADDONE = TBDB_TBCONTROL_RELOADDONE;
my $TBTIMEOUT    = TBDB_TBCONTROL_TIMEOUT;
my $TBNOTIMEOUT  = TBDB_NO_STATE_TIMEOUT;
my $TBNODESTATE  = TBDB_TBEVENT_NODESTATE;
my $TBNODEOPMODE = TBDB_TBEVENT_NODEOPMODE;
my $TBCONTROL    = TBDB_TBEVENT_TBCONTROL;

Mac Newbold's avatar
Mac Newbold committed
85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100
my $pidfile;
$pidfile = "$TB/locks/stated.pid";
debug("Using pidfile $pidfile\n");
if (-e $pidfile) {
  my $otherpid = `cat $pidfile`;
  my $running = `ps -auxww | grep $otherpid | grep -v grep`;
  if ($running ne "") {
    fatal("Lockfile $pidfile exists, and process $otherpid appears to be ".
	  "running.\n");
  } else {
    notify("Lockfile exists, but process $otherpid appears to be dead.\n".
	   "Removing lock file...\n");
  }
  system("rm $pidfile") &&
    fatal("Couldn't remove $pidfile: $? $!\n");
}
Robert Ricci's avatar
Robert Ricci committed
101
# Background
102 103 104
if (!$debug) {
  # We use syslog, so redirect the output to nothing
  if (TBBackGround("/dev/null")) { exit(0); }
Robert Ricci's avatar
Robert Ricci committed
105
}
Mac Newbold's avatar
Mac Newbold committed
106 107
# set up syslog
openlog("stated","pid","user");
Mac Newbold's avatar
Mac Newbold committed
108 109 110 111 112 113
sysopen(PIDFILE, $pidfile, O_WRONLY | O_EXCL | O_CREAT) ||
  fatal("Couldn't create '$pidfile': $? $!\n");
print PIDFILE "$$";
close PIDFILE;
# If I make it to here, I'll need to clean up the lock file
my $lockfile=$pidfile;
Robert Ricci's avatar
Robert Ricci committed
114

115 116 117
# Change my $0 so that it is easier to see in a ps/top
$0 = "$0";

Robert Ricci's avatar
Robert Ricci committed
118 119 120 121 122
my $URL = "elvin://$server";
if ($port) { $URL .= ":$port"; }

# Connect to the event system, and subscribe the the events we want 
my $handle = event_register($URL,0);
Mac Newbold's avatar
Mac Newbold committed
123
if (!$handle) { fatal("Unable to register with event system\n"); }
Robert Ricci's avatar
Robert Ricci committed
124 125

my $tuple = address_tuple_alloc();
Mac Newbold's avatar
Mac Newbold committed
126
if (!$tuple) { fatal("Could not allocate an address tuple\n"); }
Robert Ricci's avatar
Robert Ricci committed
127

128 129
%$tuple = ( objtype => join(",",$TBNODESTATE,$TBNODEOPMODE,$TBCONTROL) );

Robert Ricci's avatar
Robert Ricci committed
130
if (!event_subscribe($handle,\&handleEvent,$tuple)) {
Mac Newbold's avatar
Mac Newbold committed
131
	fatal("Could not subscribe to events\n");
Robert Ricci's avatar
Robert Ricci committed
132 133 134 135
}

# Read in the pre-existing node states, and timeout and valid transition
# information from the database
136 137 138 139 140
my %nodes     = readStates();
my %timeouts  = getTimeouts();
my %valid     = getValid();
my %modeTrans = getModeTrans();
my %triggers  = getTriggers();
Robert Ricci's avatar
Robert Ricci committed
141 142 143 144

# Gets set if a reload of state from the database should happen.
my $do_reload = 0;

Mac Newbold's avatar
Mac Newbold committed
145 146 147 148 149 150
# Set when I've got a child pinging a node for me.
my $have_children = 0;

# Keep track of which pid was pinging which node.
my %children= (); # $children($child} = "pcXXX";

Robert Ricci's avatar
Robert Ricci committed
151 152 153
# Make the daemon reload database state on a sighup - but I'm worried
# about what would happen if we tried to do this mid-loop. So, we'll
# just set a flag and do it when we're done with our current pass.
154 155
$SIG{HUP}  = sub { info("SIGHUP - Reloading DB state\n"); $do_reload = 1; };

Mac Newbold's avatar
Mac Newbold committed
156
# Set up other signals.
157 158 159
$SIG{USR1} = \&restart;
$SIG{USR2} = \&cleanup;
$SIG{INT}  = \&cleanup;
160 161 162 163
$SIG{QUIT} = \&cleanup;
$SIG{ABRT} = \&cleanup;
$SIG{TERM} = \&cleanup;
$SIG{KILL} = \&cleanup;
Robert Ricci's avatar
Robert Ricci committed
164

Mac Newbold's avatar
Mac Newbold committed
165
# Set up some notification throttling
166
my $mailgap = 15; # in seconds
167
my $lastmail = time - $mailgap + 2; # Send a digest of startup msgs after 2s.
Mac Newbold's avatar
Mac Newbold committed
168 169
my %msgs = ();

170 171 172 173 174 175 176 177 178 179 180 181 182 183 184 185
# Track if I handled an event or not
my $event_count = 0;

notify("Stated starting up\n");

sub process_event_queue() {
    $event_count=0;
    my $lastcount=-1;
    while ($event_count != $lastcount) {
	$lastcount = $event_count;
	event_poll($handle);
    }
    if ($event_count > 0) {
      debug("Handled $event_count event(s).\n");
    }
}
Robert Ricci's avatar
Robert Ricci committed
186

187
# Now, we just poll for events, and watch for timeouts
Robert Ricci's avatar
Robert Ricci committed
188
while (1) {
189
	process_event_queue;
Robert Ricci's avatar
Robert Ricci committed
190 191 192 193 194
	my $now = time();
	#
	# Look for nodes that have passed their timeout
	#
	while (my ($node, $value) = each %nodes) {
195 196 197 198 199 200 201 202 203 204 205 206 207 208 209
	    my $state    = $value->{state};
	    my $mode     = $value->{mode};
	    my $time     = $value->{timestamp};
	    my $notified = $value->{notified};
	    my ($timeout,$action);
	    if ($mode && $state && $timeouts{$mode} &&
		$timeouts{$mode}{$state}) {
		($timeout, $action) = @{$timeouts{$mode}{$state}};
	    }
	    if ((!$notified) && $time && $timeout && 
		$timeout!= $TBNOTIMEOUT && (($time + $timeout) < $now)) {
		handleCtrlEvent($node,$TBTIMEOUT);
		$value->{notified} = 1;
		
	    }
Robert Ricci's avatar
Robert Ricci committed
210 211
	}

212
	if ($do_reload || ($now - $last_reload > $reload_time)) {
Robert Ricci's avatar
Robert Ricci committed
213 214 215 216
		reload();
		$do_reload = 0;
	}

Mac Newbold's avatar
Mac Newbold committed
217 218 219 220 221 222 223 224
	if ($have_children) {
	  # Check for kids that have finished
	  handleChild();
	}

	# Send any messages in the queue if it is time
	notify("",1);

Robert Ricci's avatar
Robert Ricci committed
225 226 227
	sleep(1);
}

Mac Newbold's avatar
Mac Newbold committed
228 229
exit(0);

Robert Ricci's avatar
Robert Ricci committed
230
# Read the current states of nodes from the database
231 232 233
sub readStates(;@) {
    	my %oldnodes = @_;

234 235 236 237 238 239 240
	#
	# Guard against undefined variable warnings
	#
	if (! defined(%oldnodes)) {
		%oldnodes = ();
	}

241
	#debug("readStates called\n");
Robert Ricci's avatar
Robert Ricci committed
242
	my $result = DBQueryFatal("SELECT node_id, eventstate, " .
243 244
				  "state_timestamp, op_mode, " .
				  "op_mode_timestamp FROM nodes");
Robert Ricci's avatar
Robert Ricci committed
245 246

	my %nodes;
247 248
	while (my ($node_id, $state, $timestamp, $mode, $mode_timestamp)
			= $result->fetchrow()) {
249 250 251 252 253 254
	    	#
		# If there's an entry in oldnodes for this node, and it
		# hasn't changed state or time, use the old entry (so that
		# we don't lose information about which nodes we've already
		# notified the ops about, etc.)
		#
255 256
		if ($oldnodes{$node_id} && $state && $timestamp &&
		   ($oldnodes{$node_id}{state} eq $state) &&
257
		   ($oldnodes{$node_id}{mode} eq $mode) &&
258
		   ($oldnodes{$node_id}{timestamp} == $timestamp)) {
259 260
		       $nodes{$node_id} = $oldnodes{$node_id};
		} else { 
261 262 263 264
		    $nodes{$node_id}{state}          = $state;
		    $nodes{$node_id}{timestamp}      = $timestamp;
		    $nodes{$node_id}{mode}           = $mode;
		    $nodes{$node_id}{mode_timestamp} = $mode_timestamp;
265
		}
Robert Ricci's avatar
Robert Ricci committed
266 267 268 269 270 271 272 273
	}
	return %nodes;
}

#
# Read timeouts for various states from the database
#
sub getTimeouts() {
274
	#debug("getTimeouts called\n");
275
	my $result = DBQueryFatal("SELECT op_mode, state, timeout, action " .
Robert Ricci's avatar
Robert Ricci committed
276 277 278
				  "FROM state_timeouts");

	my %timeouts;
279 280
	while (my ($op_mode, $state, $timeout, $action) = $result->fetchrow()) {
		$timeouts{$op_mode}{$state} = [ $timeout, $action ];
Robert Ricci's avatar
Robert Ricci committed
281 282 283 284 285 286 287 288
	}
	return %timeouts;
}

#
# Read the list of valid state transitions from the database
#
sub getValid() {
289
	#debug("getValid called\n");
290
	my $result = DBQueryFatal("SELECT op_mode, state1, state2 " .
Robert Ricci's avatar
Robert Ricci committed
291 292 293
				  "FROM state_transitions");

	my %valid;
294 295
	while (my ($mode,$state1, $state2) = $result->fetchrow()) {
		$valid{$mode}{$state1}{$state2} = 1;
Robert Ricci's avatar
Robert Ricci committed
296 297 298 299
	}
	return %valid;
}

300 301 302 303 304 305 306 307 308 309 310 311 312 313 314 315 316 317 318 319 320 321 322 323 324 325 326 327 328 329 330 331 332 333 334 335 336
#
# Read the list of valid mode transitions from the database
#
sub getModeTrans() {
	#debug("getModeTrans called\n");
	my $result = 
	    DBQueryFatal("SELECT op_mode1, state1, op_mode2, state2 " .
			 "FROM mode_transitions order by op_mode1,state1");

	my %modeTrans;
	while (my ($mode1,$state1, $mode2, $state2) = $result->fetchrow()) {
	    if (!defined($modeTrans{"$mode1:$state1"})) {
		$modeTrans{"$mode1:$state1"}= ["$mode2:$state2"];
	    } else {
		my @l = @{$modeTrans{"$mode1:$state1"}};
		push(@l, "$mode2:$state2");
		$modeTrans{"$mode1:$state1"}= \@l;
	    }
	}
	return %modeTrans;
}

#
# Read the list of states which trigger an action
#
sub getTriggers() {
	#debug("getTriggers called\n");
	my $result = 
	    DBQueryFatal("SELECT op_mode, state, trigger " .
			 "FROM state_triggers order by op_mode,state");
	my %t;
	while (my ($mode,$state, $trig) = $result->fetchrow()) {
	    $t{"$mode:$state"} = $trig;
	}
	return %t;
}

Robert Ricci's avatar
Robert Ricci committed
337 338 339 340 341
#
# Gets called for every event that we recieve
#
sub handleEvent($$$) {
	my ($handle,$notification,$data) = @_;
342
	my $objtype = event_notification_get_objtype($handle,$notification);
Robert Ricci's avatar
Robert Ricci committed
343 344 345
	my $objname = event_notification_get_objname($handle,$notification);
	my $eventtype = event_notification_get_eventtype($handle,$notification);

346
	$event_count++;
347
	info("Got an event: ($objtype,$objname,$eventtype)\n");
348

349 350 351 352
	#
	# Check to see if another instance is supposed to be handling this node
	#
	if (!checkDBRedirect($objname)) {
353
	    info("Got an event for node $objname, which isn't mine\n");
354 355 356
	    return;
	}

357 358
	SWITCH: for ($objtype) {

359
	    (/$TBNODESTATE/) && do {
360 361 362
		stateTransition($objname,$eventtype);
		last;
	    };
363
	    (/$TBNODEOPMODE/) && do {
364
		opModeTransition($objname,$eventtype);
365 366 367 368
		notify("Use of deprecated event TBNODEOPMODE:\n".
		       "$objname->$eventtype\n");
		last;
	    };
369
	    (/$TBCONTROL/) && do {
370
		handleCtrlEvent($objname,$eventtype);
371 372 373 374 375 376 377 378 379
		last;
	    };

	}

}

sub stateTransition($$) {

380
    my ($node,$newstate) = @_;
Robert Ricci's avatar
Robert Ricci committed
381

382 383 384 385 386 387 388 389
    # Check for invalid transitions
    my ($oldstate, $mode);
    if ($nodes{$node}) {
	$oldstate = $nodes{$node}{state};
	$mode = $nodes{$node}{mode};
    } else {
	# Try reloading the cache once before we give up on this node
	reload();
390
	if ($nodes{$node}) {
391 392
	    $oldstate = $nodes{$node}{state};
	    $mode = $nodes{$node}{mode};
Robert Ricci's avatar
Robert Ricci committed
393
	} else {
394
	    notify("Got an event for a node ($node) I don't know about\n");
Robert Ricci's avatar
Robert Ricci committed
395
	}
396 397 398 399
    }
    if ($oldstate && $mode && $valid{$mode} && $valid{$mode}{$oldstate} &&
	!$valid{$mode}{$oldstate}{$newstate}) {
	notify("Invalid transition for node $node from $mode/$oldstate " .
400
	       "to $newstate\n");
401
    }
Robert Ricci's avatar
Robert Ricci committed
402

403 404 405 406
    my $now = time();
    $nodes{$node}{state}     = $newstate;
    $nodes{$node}{timestamp} = $now;
    $nodes{$node}{notified}  = 0;
407

408 409 410
    info("$node: $mode/$oldstate => $mode/$newstate\n");
    DBQueryFatal("UPDATE nodes SET eventstate='$newstate', " .
		 "state_timestamp='$now' WHERE node_id='$node'");
411

412 413 414 415 416
    # Check if this is TBDB_NODESTATE_BOOTING , which has actions
    if ($newstate eq TBDB_NODESTATE_BOOTING) {
	my $osid = TBBootWhat($node,$debug);
	info("$node: Current OS is '$osid'\n");
	DBQueryFatal("UPDATE nodes SET osid='$osid' WHERE node_id='$node'");
417

418 419
	checkGenISUP($node);
    }
420

421 422 423 424 425 426 427 428 429 430 431 432 433 434 435 436 437 438 439
    # Check if this state is the "successful boot" state
    if (defined($triggers{"$mode:$newstate"})) {
	my @trigs = split(/\s*,\s*/,$triggers{"$mode:$newstate"});
	foreach ( @trigs ) {
	    my $trig = $_;
	    /^$TBRESET$/ && do {
		# Check if we really need to do a reset
		my $r = DBQueryWarn("select osid,def_boot_osid from nodes ".
				    "where node_id='$node'");
		my ($osid,$defosid) = $r->fetchrow();
		if ($osid ne $defosid) {
		    handleCtrlEvent($node,$trig);
		}
		next;
	    };
	    /^$TBRELOADDONE$/ && do {
		handleCtrlEvent($node,$trig);
		next;
	    };
440
	    notify("Unknown trigger '$trig' for $node in $mode/$newstate!\n");
441 442
	}
    }
443

444 445 446 447 448 449 450 451 452 453 454
    # Check if this state can trigger a mode transition
    if (defined($modeTrans{"$mode:$newstate"})) {
	info("$node: Checking for mode transition\n");
	my $r = DBQueryWarn("select next_op_mode from nodes ".
			    "where node_id='$node'");
	my ($nextmode) = $r->fetchrow();
	if ($nextmode) {
	    opModeTransition($node,$nextmode);
	} else { debug("No next mode.\n"); }
    }
}
455

456
sub opModeTransition($$) {
Mac Newbold's avatar
Mac Newbold committed
457

458
    my ($node,$newmode) = @_;
Mac Newbold's avatar
Mac Newbold committed
459

460 461 462 463 464 465 466 467 468 469 470 471 472 473 474 475 476 477 478 479 480 481 482 483 484 485 486
    info("$node: Mode change to $newmode requested\n");
    # Check for invalid transitions
    my ($oldstate, $mode, $nextstate);
    if ($nodes{$node}) {
	$oldstate = $nodes{$node}{state};
	$mode = $nodes{$node}{mode};
    } else {
	# Try reloading the cache once before we give up on this node
	reload();
	if ($nodes{$node}) {
	    $oldstate = $nodes{$node}{state};
	    $mode = $nodes{$node}{mode};
	} else {
	    notify("Got an event for a node ($node) I don't know about\n");
	}
    }
    if (defined($modeTrans{"$mode:$oldstate"})) {
	debug("Mode Transition check:\n");
	my $translist = join(",",@{$modeTrans{"$mode:$oldstate"}});
	#debug("translist=$translist\n");
	#debug("splitlist=".join(", ",split(/[:,]/,$translist))."\n");
	my %trans = split(/[:,]/,$translist);
	debug("Valid transitions from $mode/$oldstate are:\n");
	foreach my $k (sort keys %trans) { debug("$k => $trans{$k}\n"); }
	if (defined($trans{$newmode})) {
	    $nextstate=$trans{$newmode};
	} else {
487
	    notify("Invalid mode transition for $node from ".
488 489 490
		   "$mode/$oldstate to $newmode!\n");
	}
    } else {
491
	notify("Invalid mode transition for $node from $mode/$oldstate: ".
492 493 494 495 496 497 498 499 500 501
	       "Not a valid mode transition state!\n");
    }
    if (!$nextstate) { $nextstate=$oldstate; }

    my $now = time();
    $nodes{$node}{state}     = $nextstate;
    $nodes{$node}{timestamp} = $now;
    $nodes{$node}{mode}           = $newmode;
    $nodes{$node}{mode_timestamp} = $now;
    $nodes{$node}{notified}       = 0;
Mac Newbold's avatar
Mac Newbold committed
502

503 504 505 506 507 508 509 510 511 512 513 514 515 516
    info("$node: $mode/$oldstate => $newmode/$nextstate\n");
    DBQueryFatal("UPDATE nodes SET eventstate='$nextstate', ".
		 "next_op_mode='', op_mode='$newmode', ".
		 "state_timestamp='$now', ".
		 "op_mode_timestamp='$now' WHERE node_id='$node'");
}

sub handleCtrlEvent($$) {
    my ($node,$event) = @_;

    info("CtrlEvent: $node, $event\n");

    foreach ($event) {
	/^$TBRESET$/ && do {
Mac Newbold's avatar
Mac Newbold committed
517 518 519 520
	    my $result = DBQueryFatal("SELECT pxe_boot_path, def_boot_osid ".
				      "FROM nodes where node_id='$node'");
	    my ($pxepath,$osid) = $result->fetchrow();

521 522 523 524 525 526 527 528 529 530
	    # Important note on ordering here:
	    # Because setting a normal osid resets pxe path to PXEBOOT,
	    # We need to read it out first, then set the osid, then set
	    # the pxepath back to its original value at the end.

	    $cmd = "$osselect $osid $node";
	    system($cmd) and
		notify("$node/$event: Couldn't clear next_boot_*\n".
		       "\tcmd=$cmd\n\t*** $!\n");

531 532
	    $pxepath = "-p ".$pxepath;
	    if ($pxepath eq "-p ") { $pxepath="PXEBOOT"; };
Mac Newbold's avatar
Mac Newbold committed
533
	    my $cmd = "$osselect -m $pxepath $node";
534 535 536
	    system($cmd) and
		notify("$node/$event: Couldn't clear next_pxe_boot_path\n".
		       "\tcmd=$cmd\n\t*** $!\n");
537

Mac Newbold's avatar
Mac Newbold committed
538
	    info("Performed RESET for $node to $osid/$pxepath\n");
539 540 541 542 543 544 545 546 547 548 549 550 551 552 553 554 555 556 557 558 559 560 561 562 563
	    next;
	};
	/^$TBRELOADDONE$/ && do {
	    info("Clearing reload info for $node\n");
	    DBQueryFatal("delete from current_reloads where node_id='$node'");
	    my ($pid,$eid);
	    NodeidToExp($node,\$pid,\$eid);
	    if (($pid eq NODERELOADING_PID) && ($eid eq NODERELOADING_EID)) {
		DBQueryFatal("delete from scheduled_reloads ".
			     "where node_id='$node'");
		DBQueryFatal("delete from reserved where node_id='$node'");
		info("Released $node from $pid/$eid\n");
	    }
	    next;
	};
	/^$TBTIMEOUT$/ && do {
	    my $state = $nodes{$node}{state};
	    my $mode = $nodes{$node}{mode};
	    my ($timeout,$action);
	    if ($mode && $state && $timeouts{$mode} &&
		$timeouts{$mode}{$state}) {
		($timeout, $action) = @{$timeouts{$mode}{$state}};
	    }
	    notify("Node $node has timed out in state $mode/$state".
		   ($action ne "" ? "\n\tRequested action $action." : "").
564
		   "\n");
565 566
	    next;
	};
567
	notify("$node: Unknown CtrlEvent: $event\n");
568 569
    }
}
Robert Ricci's avatar
Robert Ricci committed
570

571 572 573 574
#
# Check if we need to generate an ISUP
#
sub checkGenISUP($) {
Mac Newbold's avatar
Mac Newbold committed
575 576 577 578 579 580 581 582 583 584
  my ($node) = @_;
  info("$node: Checking ISUP Generation\n");
  my $r = DBQueryWarn("select osfeatures from os_info as o ".
		      "left join nodes as n on o.osid=n.osid ".
		      "where node_id='$node' and osfeatures is not null");
  my $osfeatures="";
  # If we don't get anything back, assume it has no features.
  if ($r->num_rows() > 0) {
    ($osfeatures) = $r->fetchrow();
  }
Robert Ricci's avatar
Robert Ricci committed
585

Mac Newbold's avatar
Mac Newbold committed
586 587 588 589 590 591 592 593 594 595 596 597 598 599 600 601 602 603 604 605 606 607 608 609
  my @features = split(",",$osfeatures);
  # Make sure features I care about are defined
  my %can=("ping"=>0, "isup"=>0);
  foreach my $f (@features) {
    $can{"\L$f"}=1;		# make sure it's all lowercase
  }

  # If os will send ISUP on its own, do nothing here.
  if ($can{"isup"}) {
    debug("$node: Will send own ISUP\n"); return 0;
  }

  # If os doesn't support isup but can ping, fork and ping it every
  # few seconds and send isup when it pings, or timeout after too long.
  if ($can{"ping"}) {
    debug("$node: Needs to be pinged\n");
    my $pid = fork();
    if ($pid) {
      $children{$pid}= $node;
      $have_children = 1;
      # don't wait, return and go on with life
      return 0;
    } else {
      info("Forked process $$ to ping $node\n");
610
      $lockfile = ""; # Don't clean up my pidfile, since I'm a child
Mac Newbold's avatar
Mac Newbold committed
611 612 613 614 615 616 617 618 619 620 621 622 623 624 625 626 627 628 629 630 631 632 633 634 635 636 637 638 639 640 641 642 643 644 645 646 647 648 649 650 651 652 653

      my $wait=5;		# 5 seconds between ping attempts
      my $maxtime=600;		# Set the timer for 10 minutes

      # XXX : If our maxtime is very different from os_setup's
      #idea of how long a node should take to reboot, then we've
      #got a problem, since it might reboot the nodes.

      # Set an alarm in case it never comes up...
      local $SIG{ALRM} = sub { 
	notify("$node: checkGenISUP timed out waiting ".
	       "for ping responses after $maxtime seconds.\n");
	exit(4);
      };

      alarm $maxtime;

      my $n=0;
      while ( $n <= ($maxtime/$wait)) {
	my $status=system("/sbin/ping -c 1 -t 1 $node > /dev/null");
	my $rv = $status >> 8;
	my $sig = $status & 127;
	debug("checkGenISUP: ping returned $status ($rv / $sig)\n");
	if ($rv==0) {
	  # we got a response
	  info("$node: ping response received... sending ISUP\n");
	  # We want to send an ISUP event for the node. But we
	  # can't just call stateTransition, because that will
	  # only make the change happen in our state, not the real
	  # stated that we forked from. We do this by exit(0)
	  # and the handleChild function will pick it up and
	  # send the ISUP for us.
	  exit(0);
	} elsif ($rv==2) {
	  # no response
	  debug("$node: No ping response, waiting $wait seconds...\n");
	} elsif ($rv==68) {
	  # ping: cannot resolve $node: Unknown host
	  notify("$node: ping couldn't resolve $node!\n");
	  exit(2);
	} else {
	  notify("$node: checkGenISUP ping returned $rv!\n");
	  exit(3);
654
	}
Mac Newbold's avatar
Mac Newbold committed
655 656 657 658 659 660
	sleep $wait;
	$n+=1;
      }
      notify("$node: Sent $n pings in ".($n*$wait)." seconds ".
	     "with no response.\n");
      exit(1);
661
    }
Mac Newbold's avatar
Mac Newbold committed
662 663 664 665 666 667
  }
  # If os doesn't support ping or isup, stated sets it to ISUP at the
  # same time.

  debug("$node: OS doesn't ping - sending ISUP\n");
  stateTransition($node, TBDB_NODESTATE_ISUP);
Robert Ricci's avatar
Robert Ricci committed
668 669
}

670 671 672 673 674 675 676 677 678 679 680 681 682 683 684 685 686 687 688 689 690 691 692 693 694 695 696
# Figure out if this node belongs to us (ie. if it's using our database.)
# Returns 1 if it does, 0 if not
sub checkDBRedirect($) {

	my ($node) = @_;

	#
	# XXX: I don't want to do this every time, for performance reaons,
	# but we need to make sure that we don't get into an inconsistent 
	# state
	#
	my $result = DBQueryFatal("SELECT testdb FROM nodes as n " .
		"LEFT JOIN reserved as r ON n.node_id = r.node_id " .
		"LEFT JOIN experiments as e ON r.pid = e.pid " .
			"AND r.eid = e.eid " .
		"WHERE n.node_id = '$node'");

	if (!$result->num_rows()) {
		notify("Got an event for a node ($node) I don't know about\n");
		return 0;
	}

	my ($testdb) = $result->fetchrow();

	#
	# XXX: It's hokey to hardcode tbdb here, but....
	#
697 698

	#debug("checkDBRedirect: $node => $testdb (I'm $TBDBNAME)\n");
699 700
	if ((!$testdb && ($TBDBNAME eq "tbdb")) ||
	    ($testdb && ($testdb eq $TBDBNAME))) {
701
	  return 1;
702
	} else {
703
	  return 0;
704 705 706
	}
}

Robert Ricci's avatar
Robert Ricci committed
707 708
# Reload state from the database
sub reload() {
709 710
  debug("Reloading state from database\n");
  $last_reload = time();
Mac Newbold's avatar
Mac Newbold committed
711 712 713 714 715 716 717 718 719 720 721 722 723 724 725 726 727 728 729 730 731 732 733 734 735 736 737 738 739 740 741 742 743 744 745 746
  %nodes     = readStates(%nodes);
  %timeouts  = getTimeouts();
  %valid     = getValid();
  %modeTrans = getModeTrans();
  %triggers  = getTriggers();
}

# This gets called to check for forked pids that have finished.  Right
# now we only fork in checkGenISUP to ping nodes.
sub handleChild() {
  my $child = wait;
  if ($child==-1) { return 0; }
  my $node = $children{$child};
  if (!defined($node)) { $node=""; }
  my $stat = $?;
  my $rv = $stat >> 8;
  my $sig = $stat & 127;
  debug("Child = $child, I am $$, children are: (".
	join(",",sort keys %children).")\n");
  if (($node ne "") && ($child!=-1) && ($child!=$$)) {
    info("handleChild: pid $child (node $node), exited $rv (sig $sig)\n");
    delete $children{$child};
    if (($sig == 0) && ($rv == 0)) {
      # Node is pingable, send isup
      info("handleChild: Sending ISUP for $node)\n");
      stateTransition($node, TBDB_NODESTATE_ISUP);
    } else {
      notify("handleChild: Caught a child that failed!\n".
	     "pid $child (node $node), exited $rv (sig $sig)\n");
    }
  }
  if (0+%children == 0) {
    debug("No more children now.\n");
    $have_children=0;
  }
  return 0;
Robert Ricci's avatar
Robert Ricci committed
747 748
}

Mac Newbold's avatar
Mac Newbold committed
749

Robert Ricci's avatar
Robert Ricci committed
750
sub debug(@) {
Mac Newbold's avatar
Mac Newbold committed
751 752 753 754 755 756 757
  if ($debug) { print @_; }
}

sub fatal($) {
  my $msg = shift;
  notify($msg);
  die($msg);
Robert Ricci's avatar
Robert Ricci committed
758 759
}

Mac Newbold's avatar
Mac Newbold committed
760
sub showqueue() {
761
  if ($debug < 2) { return; }
Mac Newbold's avatar
Mac Newbold committed
762 763 764 765 766 767 768 769 770 771
  if ((keys %msgs) > 0) {
    debug("\nMAILQUEUE:\n");
  }
  foreach $k (sort keys %msgs) {
    my @l = @{$msgs{$k}};
    debug("MSGS:\n$k==> (".(@l+0).",'".join("','",@l)."')\n");
  }
}

sub notify($;$) {
Mac Newbold's avatar
Mac Newbold committed
772
  my $message = shift;
Mac Newbold's avatar
Mac Newbold committed
773 774 775 776 777 778 779 780 781 782 783 784 785 786
  my $checkonly = shift || 0;
  # Use a timestamp, now that we're throttling mail
  my $tstamp=strftime("%b %e %H:%M:%S",localtime);
  showqueue();
  if (!$checkonly) {
    info($message);
    # Queue up the message
    # (The queue is a hash of lists of timestamps, keyed by message
    if (defined($msgs{$message})) {
      push(@{$msgs{$message}},$tstamp);
    } else {
      $msgs{$message} = [$tstamp];
    }
    showqueue();
Mac Newbold's avatar
Mac Newbold committed
787
  }
Mac Newbold's avatar
Mac Newbold committed
788 789 790 791 792 793 794 795 796 797 798 799 800 801 802 803 804 805 806 807 808 809 810 811 812 813 814
  my $now = time;
  if ($now - $lastmail >= $mailgap) {
    if ((keys %msgs)>0) {
      debug("SENDING MAILQUEUE\n"."(now $now, lastmail $lastmail, ".
	    ($now-$lastmail).">=$mailgap)\n");
      my $mailbody="";
      my $sep = '-'x5;
      # We're okay to send. Make a digest of all the queued messages.
      foreach my $msg (sort keys %msgs) {
	my @tlist = @{$msgs{$msg}};
	my $count = 0+@tlist;
	$mailbody .= "\n$msg\n";
	if ($count > 1) {
	  my $first = shift @tlist;
	  my $last = pop @tlist;
	  $mailbody .= "($count copies from $first to $last)\n";
	} else {
	  $mailbody .= "($count copy at $tlist[0])\n";
	}
	$mailbody .= "$sep\n";
      }
      # Now reset the mail queue
      %msgs = ();
      showqueue();
      $lastmail = time;
      if (!$debug) {
	SENDMAIL("Stated List <".$TBOPS.">",
815
		 "Stated Messsage",$mailbody,
Mac Newbold's avatar
Mac Newbold committed
816 817 818 819 820 821 822
		 "Stated Daemon <".$TBOPS.">");
      } else {
	debug("notify: Not sending mail in debug mode\n");
	debug("MAIL CONTAINS:\n".$mailbody."\n");
      }
    }
  }  # else do nothing, not time yet
823 824
}

825 826 827 828 829 830 831 832 833 834 835 836 837 838 839
sub announce($) {
  my $message = shift;
  my $tstamp=strftime("%b %e %H:%M:%S",localtime);
  notify("ANNOUCEMENT: ".$message."\n\n(Sent to $REALTBOPS)\n");
  $mailbody = "\n$message\n\n$tstamp\n";
  if (!$debug) {
    SENDMAIL($REALTBOPS,
	     "Stated Messsage",$mailbody,
	     "Stated Daemon <".$TBOPS.">");
  } else {
    debug("announce: Not sending mail in debug mode\n");
    debug("MAIL CONTAINS:\n".$mailbody."\n");
  }
}

840
sub info($;$) {
841
    my $message = shift;
842
    my $notice = shift || 0;
Mac Newbold's avatar
Mac Newbold committed
843 844 845 846
    # Use syslog
    my $prio="info";
    if ($notice) { $prio = "notice"; }
    if ($debug) {
847 848 849
      # Print out log entries like this:
      # Sep 20 09:36:00 stated[238]: Reloading state from database
      print strftime("%b %e %H:%M:%S",localtime)." stated[$$]: $message";
Mac Newbold's avatar
Mac Newbold committed
850
      $message = "DEBUG: ".$message;
851
    }
Mac Newbold's avatar
Mac Newbold committed
852
    syslog($prio,$message) || notify("syslog failed: $? $!\n");
853 854 855 856
}

# This gets called if we catch a signal USR1
sub restart {
Mac Newbold's avatar
Mac Newbold committed
857
  info("SIGUSER1 received: Performing final event poll before restarting\n");
858
  process_event_queue;
859 860 861 862
  my $params = join(" ",@args);
  my $prog = "";
  # If we're started from an abosolute path, use that.
  if ($0 =~ /^\//) { $prog = $0; } else { $prog = "$TB/sbin/stated"; }
Mac Newbold's avatar
Mac Newbold committed
863
  info("Restarting from '$prog".($params ne "" ? " $params" : "")."'\n");
864 865 866
  if ($handle && event_unregister($handle) == 0) {
    warn "Unable to unregister with event system\n";
  }
Mac Newbold's avatar
Mac Newbold committed
867
  if (defined($lockfile) && $lockfile ne "") { unlink $lockfile; }
868 869 870 871
  if (!defined(sigprocmask(SIG_UNBLOCK, POSIX::SigSet->new(SIGUSR1,SIGHUP)))) {
    notify("sigprocmask: sig unblock failed! $?, $!\n");
    die("\n");
  }
872
  announce("Stated restarted\n");
873 874 875 876
  exec("$prog $params") or 
    do {
      my $msg = "Couldn't restart stated! cmd='$prog $params'\n".
	"Error: ($?) $!\n";
877
      announce($msg);
878 879
      die($msg);
    };
Robert Ricci's avatar
Robert Ricci committed
880 881
}

882 883
# This gets called if we catch a signal (TERM, etc.)
sub cleanup {
884
  notify("Signal received, exiting\n");
885 886
  # now do the normal exit stuff in END {}
  exit(0);
887 888
}

Robert Ricci's avatar
Robert Ricci committed
889 890
# This gets called if we die of 'natural causes' (exit, die, etc.)
END {
891 892 893 894 895 896 897
  if (defined($lockfile) && $lockfile ne "") {
    unlink $lockfile;
    announce("Stated exiting, cleaning up\n");
  } else {
    # Must be a child
    info("Stated child exiting\n");
  }
Mac Newbold's avatar
Mac Newbold committed
898 899
  # clean up Syslog
  closelog();
900 901 902
  if ($handle) {
    if (event_unregister($handle) == 0) {
      die "Unable to unregister with event system\n";
903
    }
904
  }
Robert Ricci's avatar
Robert Ricci committed
905
}
906