stated.in 26.5 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
# 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;
84
my $TB_OSID_MBKERNEL = TB_OSID_MBKERNEL;
85

Mac Newbold's avatar
Mac Newbold committed
86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101
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
102
# Background
103 104 105
if (!$debug) {
  # We use syslog, so redirect the output to nothing
  if (TBBackGround("/dev/null")) { exit(0); }
Robert Ricci's avatar
Robert Ricci committed
106
}
Mac Newbold's avatar
Mac Newbold committed
107 108
# set up syslog
openlog("stated","pid","user");
Mac Newbold's avatar
Mac Newbold committed
109 110 111 112 113 114
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
115

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

Robert Ricci's avatar
Robert Ricci committed
119 120 121 122 123
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
124
if (!$handle) { fatal("Unable to register with event system\n"); }
Robert Ricci's avatar
Robert Ricci committed
125 126

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

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

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

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

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

Mac Newbold's avatar
Mac Newbold committed
146 147 148 149 150 151
# 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
152 153 154
# 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.
155 156
$SIG{HUP}  = sub { info("SIGHUP - Reloading DB state\n"); $do_reload = 1; };

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

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

171 172 173 174 175 176 177 178 179 180 181 182 183 184 185 186
# 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
187

188
# Now, we just poll for events, and watch for timeouts
Robert Ricci's avatar
Robert Ricci committed
189
while (1) {
190
	process_event_queue;
Robert Ricci's avatar
Robert Ricci committed
191 192 193 194 195
	my $now = time();
	#
	# Look for nodes that have passed their timeout
	#
	while (my ($node, $value) = each %nodes) {
196 197 198 199 200 201 202 203 204 205 206 207 208 209 210
	    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
211 212
	}

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

Mac Newbold's avatar
Mac Newbold committed
218 219 220 221 222 223 224 225
	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
226 227 228
	sleep(1);
}

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

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

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

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

	my %nodes;
248 249
	while (my ($node_id, $state, $timestamp, $mode, $mode_timestamp)
			= $result->fetchrow()) {
250 251 252 253 254 255
	    	#
		# 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.)
		#
256 257
		if ($oldnodes{$node_id} && $state && $timestamp &&
		   ($oldnodes{$node_id}{state} eq $state) &&
258
		   ($oldnodes{$node_id}{mode} eq $mode) &&
259
		   ($oldnodes{$node_id}{timestamp} == $timestamp)) {
260 261
		       $nodes{$node_id} = $oldnodes{$node_id};
		} else { 
262 263 264 265
		    $nodes{$node_id}{state}          = $state;
		    $nodes{$node_id}{timestamp}      = $timestamp;
		    $nodes{$node_id}{mode}           = $mode;
		    $nodes{$node_id}{mode_timestamp} = $mode_timestamp;
266
		}
Robert Ricci's avatar
Robert Ricci committed
267 268 269 270 271 272 273 274
	}
	return %nodes;
}

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

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

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

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

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

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

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

358 359
	SWITCH: for ($objtype) {

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

	}

}

sub stateTransition($$) {

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

383 384 385 386 387 388 389 390
    # 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();
391
	if ($nodes{$node}) {
392 393
	    $oldstate = $nodes{$node}{state};
	    $mode = $nodes{$node}{mode};
Robert Ricci's avatar
Robert Ricci committed
394
	} else {
395
	    notify("Got an event for a node ($node) I don't know about\n");
Robert Ricci's avatar
Robert Ricci committed
396
	}
397 398 399 400
    }
    if ($oldstate && $mode && $valid{$mode} && $valid{$mode}{$oldstate} &&
	!$valid{$mode}{$oldstate}{$newstate}) {
	notify("Invalid transition for node $node from $mode/$oldstate " .
401
	       "to $newstate\n");
402
    }
Robert Ricci's avatar
Robert Ricci committed
403

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

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

413 414 415
    # Check if this is TBDB_NODESTATE_BOOTING , which has actions
    if ($newstate eq TBDB_NODESTATE_BOOTING) {
	my $osid = TBBootWhat($node,$debug);
416 417
	my $os_op_mode = os_opmode($osid);
	info("$node: Current OS is '$osid', OS mode is '$os_op_mode'\n");
418
	DBQueryFatal("UPDATE nodes SET osid='$osid' WHERE node_id='$node'");
419
	if ($os_op_mode ne $mode) {
420 421
	    my $str = "Node $node is running OS '$osid' but in mode '$mode' ".
	      "instead of mode '$os_op_mode'!\n";
422 423 424 425 426 427 428 429 430 431
	    if ($os_op_mode ne "") {
		DBQueryFatal("UPDATE nodes SET op_mode='$os_op_mode', ".
			     "op_mode_timestamp=unix_timestamp(now()) ".
			     "WHERE node_id='$node'");
		$nodes{$node}{mode} = $os_op_mode;
		$nodes{$node}{mode_timestamp} = $now;
		$str .= "Forced op_mode to '$os_op_mode'.\n";
	    }
	    notify($str);
	}
432 433
	checkGenISUP($node);
    }
434

435 436 437 438 439 440 441 442 443 444 445 446 447 448 449 450 451 452 453
    # 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;
	    };
454
	    notify("Unknown trigger '$trig' for $node in $mode/$newstate!\n");
455 456
	}
    }
457

458 459 460 461 462 463 464 465 466 467 468
    # 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"); }
    }
}
469

470
sub opModeTransition($$) {
Mac Newbold's avatar
Mac Newbold committed
471

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

474 475 476 477 478 479 480 481 482 483 484 485 486 487 488 489 490 491 492 493 494 495 496 497 498 499 500
    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 {
501
	    notify("Invalid mode transition for $node from ".
502 503 504
		   "$mode/$oldstate to $newmode!\n");
	}
    } else {
505
	notify("Invalid mode transition for $node from $mode/$oldstate: ".
506 507 508 509 510 511 512 513 514 515
	       "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
516

517 518 519 520 521 522 523 524 525 526 527 528 529 530
    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
531 532 533 534
	    my $result = DBQueryFatal("SELECT pxe_boot_path, def_boot_osid ".
				      "FROM nodes where node_id='$node'");
	    my ($pxepath,$osid) = $result->fetchrow();

535 536 537 538 539 540 541 542 543 544
	    # 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");

545 546
	    $pxepath = "-p ".$pxepath;
	    if ($pxepath eq "-p ") { $pxepath="PXEBOOT"; };
Mac Newbold's avatar
Mac Newbold committed
547
	    my $cmd = "$osselect -m $pxepath $node";
548 549 550
	    system($cmd) and
		notify("$node/$event: Couldn't clear next_pxe_boot_path\n".
		       "\tcmd=$cmd\n\t*** $!\n");
551

Mac Newbold's avatar
Mac Newbold committed
552
	    info("Performed RESET for $node to $osid/$pxepath\n");
553 554 555 556 557 558 559 560 561 562 563 564 565 566 567 568 569 570 571 572 573 574 575 576 577
	    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." : "").
578
		   "\n");
579 580
	    next;
	};
581
	notify("$node: Unknown CtrlEvent: $event\n");
582 583
    }
}
Robert Ricci's avatar
Robert Ricci committed
584

585 586 587 588
#
# Check if we need to generate an ISUP
#
sub checkGenISUP($) {
Mac Newbold's avatar
Mac Newbold committed
589 590 591 592 593 594 595 596 597 598
  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
599

Mac Newbold's avatar
Mac Newbold committed
600 601 602 603 604 605 606 607 608 609 610 611 612 613 614 615 616 617 618 619 620 621 622 623
  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");
624
      $lockfile = ""; # Don't clean up my pidfile, since I'm a child
Mac Newbold's avatar
Mac Newbold committed
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 654 655 656 657 658 659 660 661 662 663 664 665 666 667

      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);
668
	}
Mac Newbold's avatar
Mac Newbold committed
669 670 671 672 673 674
	sleep $wait;
	$n+=1;
      }
      notify("$node: Sent $n pings in ".($n*$wait)." seconds ".
	     "with no response.\n");
      exit(1);
675
    }
Mac Newbold's avatar
Mac Newbold committed
676 677 678 679 680 681
  }
  # 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
682 683
}

684 685 686 687 688 689 690 691 692 693 694 695 696 697 698 699 700 701 702 703 704 705 706 707 708 709 710
# 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....
	#
711 712

	#debug("checkDBRedirect: $node => $testdb (I'm $TBDBNAME)\n");
713 714
	if ((!$testdb && ($TBDBNAME eq "tbdb")) ||
	    ($testdb && ($testdb eq $TBDBNAME))) {
715
	  return 1;
716
	} else {
717
	  return 0;
718 719 720
	}
}

Robert Ricci's avatar
Robert Ricci committed
721 722
# Reload state from the database
sub reload() {
723 724
  debug("Reloading state from database\n");
  $last_reload = time();
Mac Newbold's avatar
Mac Newbold committed
725 726 727 728 729 730 731 732 733 734 735 736 737 738 739 740 741 742 743 744 745 746 747 748 749 750 751 752 753 754 755 756 757 758 759 760
  %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
761 762
}

763 764
sub os_opmode() {
    my $osid = shift || "";
765
    if ($osid eq $TB_OSID_MBKERNEL) { return "MINIMAL"; }
766 767 768 769 770 771 772 773 774
    my $cmd = "select op_mode from os_info where osid='$osid';";
    my $q = DBQueryFatal($cmd);
    if ($q->numrows() < 1) { return ""; }
    my @r = $q->fetchrow_array();
    my $opmode=$r[0];
    debug("OpMode for '$osid' is '$opmode'\n");
    if (defined($opmode) && $opmode ne "") { return $opmode; }
    return "";
}
Mac Newbold's avatar
Mac Newbold committed
775

Robert Ricci's avatar
Robert Ricci committed
776
sub debug(@) {
Mac Newbold's avatar
Mac Newbold committed
777 778 779 780 781 782 783
  if ($debug) { print @_; }
}

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

Mac Newbold's avatar
Mac Newbold committed
786
sub showqueue() {
787
  if ($debug < 2) { return; }
Mac Newbold's avatar
Mac Newbold committed
788 789 790 791 792 793 794 795 796 797
  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
798
  my $message = shift;
Mac Newbold's avatar
Mac Newbold committed
799 800 801 802 803 804 805 806 807 808 809 810 811 812
  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
813
  }
Mac Newbold's avatar
Mac Newbold committed
814 815 816 817 818 819 820 821 822 823 824 825 826 827 828 829 830 831 832 833 834 835 836 837 838 839 840
  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.">",
841
		 "Stated Messsage",$mailbody,
Mac Newbold's avatar
Mac Newbold committed
842 843 844 845 846 847 848
		 "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
849 850
}

851 852 853 854 855 856 857 858 859 860 861 862 863 864 865
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");
  }
}

866
sub info($;$) {
867
    my $message = shift;
868
    my $notice = shift || 0;
Mac Newbold's avatar
Mac Newbold committed
869 870 871 872
    # Use syslog
    my $prio="info";
    if ($notice) { $prio = "notice"; }
    if ($debug) {
873 874 875
      # 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
876
      $message = "DEBUG: ".$message;
877
    }
Mac Newbold's avatar
Mac Newbold committed
878
    syslog($prio,$message) || notify("syslog failed: $? $!\n");
879 880 881 882
}

# This gets called if we catch a signal USR1
sub restart {
Mac Newbold's avatar
Mac Newbold committed
883
  info("SIGUSER1 received: Performing final event poll before restarting\n");
884
  process_event_queue;
885 886 887 888
  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
889
  info("Restarting from '$prog".($params ne "" ? " $params" : "")."'\n");
890 891 892
  if ($handle && event_unregister($handle) == 0) {
    warn "Unable to unregister with event system\n";
  }
Mac Newbold's avatar
Mac Newbold committed
893
  if (defined($lockfile) && $lockfile ne "") { unlink $lockfile; }
894 895 896 897
  if (!defined(sigprocmask(SIG_UNBLOCK, POSIX::SigSet->new(SIGUSR1,SIGHUP)))) {
    notify("sigprocmask: sig unblock failed! $?, $!\n");
    die("\n");
  }
898
  announce("Stated restarted\n");
899 900 901 902
  exec("$prog $params") or 
    do {
      my $msg = "Couldn't restart stated! cmd='$prog $params'\n".
	"Error: ($?) $!\n";
903
      announce($msg);
904 905
      die($msg);
    };
Robert Ricci's avatar
Robert Ricci committed
906 907
}

908 909
# This gets called if we catch a signal (TERM, etc.)
sub cleanup {
910
  notify("Signal received, exiting\n");
911 912
  # now do the normal exit stuff in END {}
  exit(0);
913 914
}

Robert Ricci's avatar
Robert Ricci committed
915 916
# This gets called if we die of 'natural causes' (exit, die, etc.)
END {
917 918 919 920 921 922 923
  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
924 925
  # clean up Syslog
  closelog();
926 927 928
  if ($handle) {
    if (event_unregister($handle) == 0) {
      die "Unable to unregister with event system\n";
929
    }
930
  }
Robert Ricci's avatar
Robert Ricci committed
931
}
932