2 # -*- mode: perl; perl-indent-level: 2; indent-tabs-mode: nil; -*-
6 crunch-job: Execute job steps, save snapshots as requested, collate output.
10 Obtain job details from Arvados, run tasks on compute nodes (typically
11 invoked by scheduler on controller):
13 crunch-job --job x-y-z
15 Obtain job details from command line, run tasks on local machine
16 (typically invoked by application or developer on VM):
18 crunch-job --job '{"script_version":"/path/to/tree","script":"scriptname",...}'
26 If the job is already locked, steal the lock and run it anyway.
30 Path to .git directory where the specified commit is found.
34 Arvados API authorization token to use during the course of the job.
38 Do not clear per-job/task temporary directories during initial job
39 setup. This can speed up development and debugging when running jobs
44 =head1 RUNNING JOBS LOCALLY
46 crunch-job's log messages appear on stderr along with the job tasks'
47 stderr streams. The log is saved in Keep at each checkpoint and when
50 If the job succeeds, the job's output locator is printed on stdout.
52 While the job is running, the following signals are accepted:
56 =item control-C, SIGINT, SIGQUIT
58 Save a checkpoint, terminate any job tasks that are running, and stop.
62 Save a checkpoint and continue.
66 Refresh node allocation (i.e., check whether any nodes have been added
67 or unallocated) and attributes of the Job record that should affect
68 behavior (e.g., cancel job if cancelled_at becomes non-nil).
76 use POSIX ':sys_wait_h';
77 use Fcntl qw(F_GETFL F_SETFL O_NONBLOCK);
85 $ENV{"TMPDIR"} ||= "/tmp";
86 unless (defined $ENV{"CRUNCH_TMP"}) {
87 $ENV{"CRUNCH_TMP"} = $ENV{"TMPDIR"} . "/crunch-job";
88 if ($ENV{"USER"} ne "crunch" && $< != 0) {
89 # use a tmp dir unique for my uid
90 $ENV{"CRUNCH_TMP"} .= "-$<";
93 $ENV{"JOB_WORK"} = $ENV{"CRUNCH_TMP"} . "/work";
94 $ENV{"CRUNCH_INSTALL"} = "$ENV{CRUNCH_TMP}/opt";
95 $ENV{"CRUNCH_WORK"} = $ENV{"JOB_WORK"}; # deprecated
96 mkdir ($ENV{"JOB_WORK"});
100 if (defined $ENV{"ARV_CLI"}) {
101 $arv_cli = $ENV{"ARV_CLI"};
113 GetOptions('force-unlock' => \$force_unlock,
114 'git-dir=s' => \$git_dir,
115 'job=s' => \$jobspec,
116 'job-api-token=s' => \$job_api_token,
117 'no-clear-tmp' => \$no_clear_tmp,
118 'resume-stash=s' => \$resume_stash,
121 if (defined $job_api_token) {
122 $ENV{ARVADOS_API_TOKEN} = $job_api_token;
125 my $have_slurm = exists $ENV{SLURM_JOBID} && exists $ENV{SLURM_NODELIST};
126 my $job_has_uuid = $jobspec =~ /^[-a-z\d]+$/;
127 my $local_job = !$job_has_uuid;
132 $main::ENV{CRUNCH_DEBUG} = 1;
136 $main::ENV{CRUNCH_DEBUG} = 0;
141 my $arv = Arvados->new('apiVersion' => 'v1');
144 my $User = $arv->{'users'}->{'current'}->execute;
152 $Job = $arv->{'jobs'}->{'get'}->execute('uuid' => $jobspec);
153 if (!$force_unlock) {
154 if ($Job->{'is_locked_by_uuid'}) {
155 croak("Job is locked: " . $Job->{'is_locked_by_uuid'});
157 if ($Job->{'success'} ne undef) {
158 croak("Job 'success' flag (" . $Job->{'success'} . ") is not null");
160 if ($Job->{'running'}) {
161 croak("Job 'running' flag is already set");
163 if ($Job->{'started_at'}) {
164 croak("Job 'started_at' time is already set (" . $Job->{'started_at'} . ")");
170 $Job = JSON::decode_json($jobspec);
174 map { croak ("No $_ specified") unless $Job->{$_} }
175 qw(script script_version script_parameters);
178 $Job->{'is_locked_by_uuid'} = $User->{'uuid'};
179 $Job->{'started_at'} = gmtime;
181 $Job = $arv->{'jobs'}->{'create'}->execute('job' => $Job);
185 $job_id = $Job->{'uuid'};
187 my $keep_logfile = $job_id . '.log.txt';
188 my $local_logfile = File::Temp->new();
190 $Job->{'runtime_constraints'} ||= {};
191 $Job->{'runtime_constraints'}->{'max_tasks_per_node'} ||= 0;
192 my $max_ncpus = $Job->{'runtime_constraints'}->{'max_tasks_per_node'};
195 Log (undef, "check slurm allocation");
198 # Should use $ENV{SLURM_TASKS_PER_NODE} instead of sinfo? (eg. "4(x3),2,4(x2)")
202 my $localcpus = 0 + `grep -cw ^processor /proc/cpuinfo` || 1;
203 push @sinfo, "$localcpus localhost";
205 if (exists $ENV{SLURM_NODELIST})
207 push @sinfo, `sinfo -h --format='%c %N' --nodes=\Q$ENV{SLURM_NODELIST}\E`;
211 my ($ncpus, $slurm_nodelist) = split;
212 $ncpus = $max_ncpus if $max_ncpus && $ncpus > $max_ncpus;
215 while ($slurm_nodelist =~ s/^([^\[,]+?(\[.*?\])?)(,|$)//)
218 if ($nodelist =~ /\[((\d+)(-(\d+))?(,(\d+)(-(\d+))?)*)\]/)
221 foreach (split (",", $ranges))
234 push @nodelist, map {
236 $n =~ s/\[[-,\d]+\]/$_/;
243 push @nodelist, $nodelist;
246 foreach my $nodename (@nodelist)
248 Log (undef, "node $nodename - $ncpus slots");
249 my $node = { name => $nodename,
253 foreach my $cpu (1..$ncpus)
255 push @slot, { node => $node,
259 push @node, @nodelist;
264 # Ensure that we get one jobstep running on each allocated node before
265 # we start overloading nodes with concurrent steps
267 @slot = sort { $a->{cpu} <=> $b->{cpu} } @slot;
274 # Claim this job, and make sure nobody else does
275 unless ($Job->update_attributes('is_locked_by_uuid' => $User->{'uuid'}) &&
276 $Job->{'is_locked_by_uuid'} == $User->{'uuid'}) {
277 croak("Error while updating / locking job");
279 $Job->update_attributes('started_at' => scalar gmtime,
282 'tasks_summary' => { 'failed' => 0,
289 Log (undef, "start");
290 $SIG{'INT'} = sub { $main::please_freeze = 1; };
291 $SIG{'QUIT'} = sub { $main::please_freeze = 1; };
292 $SIG{'TERM'} = \&croak;
293 $SIG{'TSTP'} = sub { $main::please_freeze = 1; };
294 $SIG{'ALRM'} = sub { $main::please_info = 1; };
295 $SIG{'CONT'} = sub { $main::please_continue = 1; };
296 $SIG{'HUP'} = sub { $main::please_refresh = 1; };
298 $main::please_freeze = 0;
299 $main::please_info = 0;
300 $main::please_continue = 0;
301 $main::please_refresh = 0;
302 my $jobsteps_must_output_keys = 0; # becomes 1 when any task outputs a key
304 grep { $ENV{$1} = $2 if /^(NOCACHE.*?)=(.*)/ } split ("\n", $$Job{knobs});
305 $ENV{"CRUNCH_JOB_UUID"} = $job_id;
306 $ENV{"JOB_UUID"} = $job_id;
310 my @jobstep_todo = ();
311 my @jobstep_done = ();
312 my @jobstep_tomerge = ();
313 my $jobstep_tomerge_level = 0;
315 my $squeue_kill_checked;
316 my $output_in_keep = 0;
317 my $latest_refresh = scalar time;
321 if (defined $Job->{thawedfromkey})
323 thaw ($Job->{thawedfromkey});
327 my $first_task = $arv->{'job_tasks'}->{'create'}->execute('job_task' => {
328 'job_uuid' => $Job->{'uuid'},
333 push @jobstep, { 'level' => 0,
335 'arvados_task' => $first_task,
337 push @jobstep_todo, 0;
343 must_lock_now("$ENV{CRUNCH_TMP}/.lock", "a job is already running here.");
350 $ENV{"CRUNCH_SRC_COMMIT"} = $Job->{script_version};
352 my $skip_install = ($local_job && $Job->{script_version} =~ m{^/});
355 if (!defined $no_clear_tmp) {
356 my $clear_tmp_cmd = 'rm -rf $JOB_WORK $CRUNCH_TMP/opt $CRUNCH_TMP/src*';
357 system($clear_tmp_cmd) == 0
358 or croak ("`$clear_tmp_cmd` failed: ".($?>>8));
360 $ENV{"CRUNCH_SRC"} = $Job->{script_version};
361 for my $src_path ("$ENV{CRUNCH_SRC}/arvados/sdk/python") {
363 system("virtualenv", "$ENV{CRUNCH_TMP}/opt") == 0
364 or croak ("virtualenv $ENV{CRUNCH_TMP}/opt failed: exit ".($?>>8));
365 system ("cd $src_path && ./build.sh && \$CRUNCH_TMP/opt/bin/python setup.py install")
367 or croak ("setup.py in $src_path failed: exit ".($?>>8));
375 $build_script = <DATA>;
377 Log (undef, "Install revision ".$Job->{script_version});
378 my $nodelist = join(",", @node);
380 if (!defined $no_clear_tmp) {
381 # Clean out crunch_tmp/work, crunch_tmp/opt, crunch_tmp/src*
383 my $cleanpid = fork();
386 srun (["srun", "--nodelist=$nodelist", "-D", $ENV{'TMPDIR'}],
387 ['bash', '-c', 'if mount | grep -q $JOB_WORK/; then sudo /bin/umount $JOB_WORK/* 2>/dev/null; fi; sleep 1; rm -rf $JOB_WORK $CRUNCH_TMP/opt $CRUNCH_TMP/src*']);
392 last if $cleanpid == waitpid (-1, WNOHANG);
393 freeze_if_want_freeze ($cleanpid);
394 select (undef, undef, undef, 0.1);
396 Log (undef, "Clean-work-dir exited $?");
399 # Install requested code version
402 my @srunargs = ("srun",
403 "--nodelist=$nodelist",
404 "-D", $ENV{'TMPDIR'}, "--job-name=$job_id");
406 $ENV{"CRUNCH_SRC_COMMIT"} = $Job->{script_version};
407 $ENV{"CRUNCH_SRC"} = "$ENV{CRUNCH_TMP}/src";
411 my $treeish = $Job->{'script_version'};
413 # If we're running under crunch-dispatch, it will have pulled the
414 # appropriate source tree into its own repository, and given us that
415 # repo's path as $git_dir. If we're running a "local" job, and a
416 # script_version was specified, it's up to the user to provide the
417 # full path to a local repository in Job->{repository}.
419 # TODO: Accept URLs too, not just local paths. Use git-ls-remote and
420 # git-archive --remote where appropriate.
422 # TODO: Accept a locally-hosted Arvados repository by name or
423 # UUID. Use arvados.v1.repositories.list or .get to figure out the
424 # appropriate fetch-url.
425 my $repo = $git_dir || $ENV{'CRUNCH_DEFAULT_GIT_DIR'} || $Job->{'repository'};
427 $ENV{"CRUNCH_SRC_URL"} = $repo;
429 if (-d "$repo/.git") {
430 # We were given a working directory, but we are only interested in
432 $repo = "$repo/.git";
435 # If this looks like a subversion r#, look for it in git-svn commit messages
437 if ($treeish =~ m{^\d{1,4}$}) {
438 my $gitlog = `git --git-dir=\Q$repo\E log --pretty="format:%H" --grep="git-svn-id:.*\@"\Q$treeish\E" " master`;
440 if ($gitlog =~ /^[a-f0-9]{40}$/) {
442 Log (undef, "Using commit $commit for script_version $treeish");
446 # If that didn't work, try asking git to look it up as a tree-ish.
448 if (!defined $commit) {
449 my $found = `git --git-dir=\Q$repo\E rev-list -1 ''\Q$treeish\E`;
451 if ($found =~ /^[0-9a-f]{40}$/s) {
453 if ($commit ne $treeish) {
454 # Make sure we record the real commit id in the database,
455 # frozentokey, logs, etc. -- instead of an abbreviation or a
456 # branch name which can become ambiguous or point to a
457 # different commit in the future.
458 $ENV{"CRUNCH_SRC_COMMIT"} = $commit;
459 Log (undef, "Using commit $commit for tree-ish $treeish");
460 if ($commit ne $treeish) {
461 $Job->{'script_version'} = $commit;
463 $Job->update_attributes('script_version' => $commit) or
464 croak("Error while updating job");
470 if (defined $commit) {
471 $ENV{"CRUNCH_SRC_COMMIT"} = $commit;
472 @execargs = ("sh", "-c",
473 "mkdir -p $ENV{CRUNCH_INSTALL} && cd $ENV{CRUNCH_TMP} && perl -");
474 $git_archive = `git --git-dir=\Q$repo\E archive ''\Q$commit\E`;
477 croak ("could not figure out commit id for $treeish");
480 my $installpid = fork();
481 if ($installpid == 0)
483 srun (\@srunargs, \@execargs, {}, $build_script . $git_archive);
488 last if $installpid == waitpid (-1, WNOHANG);
489 freeze_if_want_freeze ($installpid);
490 select (undef, undef, undef, 0.1);
492 Log (undef, "Install exited $?");
497 # Grab our lock again (we might have deleted and re-created CRUNCH_TMP above)
498 must_lock_now("$ENV{CRUNCH_TMP}/.lock", "a job is already running here.");
501 # If this job requires a Docker image, install that.
502 my $docker_bin = "/usr/bin/docker.io";
503 my $docker_image = $Job->{runtime_constraints}->{docker_image} || "";
505 my $docker_pid = fork();
506 if ($docker_pid == 0)
508 srun (["srun", "--nodelist=" . join(' ', @node)],
509 [$docker_bin, 'pull', $docker_image]);
514 last if $docker_pid == waitpid (-1, WNOHANG);
515 freeze_if_want_freeze ($docker_pid);
516 select (undef, undef, undef, 0.1);
518 # If the Docker image was specified as a hash, pull will fail.
519 # Ignore that error. We'll see what happens when we try to run later.
520 if (($? != 0) && ($docker_image !~ /^[0-9a-fA-F]{5,64}$/))
522 croak("Installing Docker image $docker_image returned exit code $?");
526 foreach (qw (script script_version script_parameters runtime_constraints))
530 (ref($Job->{$_}) ? JSON::encode_json($Job->{$_}) : $Job->{$_}));
532 foreach (split (/\n/, $Job->{knobs}))
534 Log (undef, "knob " . $_);
539 $main::success = undef;
545 my $thisround_succeeded = 0;
546 my $thisround_failed = 0;
547 my $thisround_failed_multiple = 0;
549 @jobstep_todo = sort { $jobstep[$a]->{level} <=> $jobstep[$b]->{level}
550 or $a <=> $b } @jobstep_todo;
551 my $level = $jobstep[$jobstep_todo[0]]->{level};
552 Log (undef, "start level $level");
557 my @freeslot = (0..$#slot);
560 my $progress_is_dirty = 1;
561 my $progress_stats_updated = 0;
563 update_progress_stats();
568 for (my $todo_ptr = 0; $todo_ptr <= $#jobstep_todo; $todo_ptr ++)
570 my $id = $jobstep_todo[$todo_ptr];
571 my $Jobstep = $jobstep[$id];
572 if ($Jobstep->{level} != $level)
577 pipe $reader{$id}, "writer" or croak ($!);
578 my $flags = fcntl ($reader{$id}, F_GETFL, 0) or croak ($!);
579 fcntl ($reader{$id}, F_SETFL, $flags | O_NONBLOCK) or croak ($!);
581 my $childslot = $freeslot[0];
582 my $childnode = $slot[$childslot]->{node};
583 my $childslotname = join (".",
584 $slot[$childslot]->{node}->{name},
585 $slot[$childslot]->{cpu});
586 my $childpid = fork();
589 $SIG{'INT'} = 'DEFAULT';
590 $SIG{'QUIT'} = 'DEFAULT';
591 $SIG{'TERM'} = 'DEFAULT';
593 foreach (values (%reader))
597 fcntl ("writer", F_SETFL, 0) or croak ($!); # no close-on-exec
598 open(STDOUT,">&writer");
599 open(STDERR,">&writer");
604 delete $ENV{"GNUPGHOME"};
605 $ENV{"TASK_UUID"} = $Jobstep->{'arvados_task'}->{'uuid'};
606 $ENV{"TASK_QSEQUENCE"} = $id;
607 $ENV{"TASK_SEQUENCE"} = $level;
608 $ENV{"JOB_SCRIPT"} = $Job->{script};
609 while (my ($param, $value) = each %{$Job->{script_parameters}}) {
610 $param =~ tr/a-z/A-Z/;
611 $ENV{"JOB_PARAMETER_$param"} = $value;
613 $ENV{"TASK_SLOT_NODE"} = $slot[$childslot]->{node}->{name};
614 $ENV{"TASK_SLOT_NUMBER"} = $slot[$childslot]->{cpu};
615 $ENV{"TASK_WORK"} = $ENV{"JOB_WORK"}."/$id.$$";
616 $ENV{"TASK_KEEPMOUNT"} = $ENV{"TASK_WORK"}.".keep";
617 $ENV{"TASK_TMPDIR"} = $ENV{"TASK_WORK"}; # deprecated
618 $ENV{"CRUNCH_NODE_SLOTS"} = $slot[$childslot]->{node}->{ncpus};
619 $ENV{"PATH"} = $ENV{"CRUNCH_INSTALL"} . "/bin:" . $ENV{"PATH"};
625 "--nodelist=".$childnode->{name},
626 qw(-n1 -c1 -N1 -D), $ENV{'TMPDIR'},
627 "--job-name=$job_id.$id.$$",
629 my $build_script_to_send = "";
631 "if [ -e $ENV{TASK_WORK} ]; then rm -rf $ENV{TASK_WORK}; fi; "
632 ."mkdir -p $ENV{JOB_WORK} $ENV{CRUNCH_TMP} $ENV{TASK_WORK} $ENV{TASK_KEEPMOUNT} "
633 ."&& cd $ENV{CRUNCH_TMP} ";
636 $build_script_to_send = $build_script;
640 $command .= "&& exec arv-mount --allow-other $ENV{TASK_KEEPMOUNT} --exec ";
643 $command .= "$docker_bin run -i -a stdin -a stdout -a stderr ";
644 # Dynamically configure the container to use the host system as its
645 # DNS server. Get the host's global addresses from the ip command,
646 # and turn them into docker --dns options using gawk.
648 q{$(ip -o address show scope global |
649 gawk 'match($4, /^([0-9\.:]+)\//, x){print "--dns", x[1]}') };
650 foreach my $env_key (qw(CRUNCH_SRC CRUNCH_TMP TASK_KEEPMOUNT))
652 $command .= "-v \Q$ENV{$env_key}:$ENV{$env_key}:rw\E ";
654 while (my ($env_key, $env_val) = each %ENV)
656 $command .= "-e \Q$env_key=$env_val\E ";
658 $command .= "\Q$docker_image\E ";
660 $command .= "$ENV{CRUNCH_SRC}/crunch_scripts/" . $Job->{"script"};
661 my @execargs = ('bash', '-c', $command);
662 srun (\@srunargs, \@execargs, undef, $build_script_to_send);
666 if (!defined $childpid)
673 $proc{$childpid} = { jobstep => $id,
676 jobstepname => "$job_id.$id.$childpid",
678 croak ("assert failed: \$slot[$childslot]->{'pid'} exists") if exists $slot[$childslot]->{pid};
679 $slot[$childslot]->{pid} = $childpid;
681 Log ($id, "job_task ".$Jobstep->{'arvados_task'}->{'uuid'});
682 Log ($id, "child $childpid started on $childslotname");
683 $Jobstep->{starttime} = time;
684 $Jobstep->{node} = $childnode->{name};
685 $Jobstep->{slotindex} = $childslot;
686 delete $Jobstep->{stderr};
687 delete $Jobstep->{finishtime};
689 splice @jobstep_todo, $todo_ptr, 1;
692 $progress_is_dirty = 1;
696 (@slot > @freeslot && $todo_ptr+1 > $#jobstep_todo))
698 last THISROUND if $main::please_freeze;
699 if ($main::please_info)
701 $main::please_info = 0;
705 update_progress_stats();
712 check_refresh_wanted();
714 update_progress_stats();
715 select (undef, undef, undef, 0.1);
717 elsif (time - $progress_stats_updated >= 30)
719 update_progress_stats();
721 if (($thisround_failed_multiple >= 8 && $thisround_succeeded == 0) ||
722 ($thisround_failed_multiple >= 16 && $thisround_failed_multiple > $thisround_succeeded))
724 my $message = "Repeated failure rate too high ($thisround_failed_multiple/"
725 .($thisround_failed+$thisround_succeeded)
726 .") -- giving up on this round";
727 Log (undef, $message);
731 # move slots from freeslot to holdslot (or back to freeslot) if necessary
732 for (my $i=$#freeslot; $i>=0; $i--) {
733 if ($slot[$freeslot[$i]]->{node}->{hold_until} > scalar time) {
734 push @holdslot, (splice @freeslot, $i, 1);
737 for (my $i=$#holdslot; $i>=0; $i--) {
738 if ($slot[$holdslot[$i]]->{node}->{hold_until} <= scalar time) {
739 push @freeslot, (splice @holdslot, $i, 1);
743 # give up if no nodes are succeeding
744 if (!grep { $_->{node}->{losing_streak} == 0 &&
745 $_->{node}->{hold_count} < 4 } @slot) {
746 my $message = "Every node has failed -- giving up on this round";
747 Log (undef, $message);
754 push @freeslot, splice @holdslot;
755 map { $slot[$freeslot[$_]]->{node}->{losing_streak} = 0 } (0..$#freeslot);
758 Log (undef, "wait for last ".(scalar keys %proc)." children to finish");
761 if ($main::please_continue) {
762 $main::please_continue = 0;
765 $main::please_info = 0, freeze(), collate_output(), save_meta(1) if $main::please_info;
769 check_refresh_wanted();
771 update_progress_stats();
772 select (undef, undef, undef, 0.1);
773 killem (keys %proc) if $main::please_freeze;
777 update_progress_stats();
778 freeze_if_want_freeze();
781 if (!defined $main::success)
784 $thisround_succeeded == 0 &&
785 ($thisround_failed == 0 || $thisround_failed > 4))
787 my $message = "stop because $thisround_failed tasks failed and none succeeded";
788 Log (undef, $message);
797 goto ONELEVEL if !defined $main::success;
800 release_allocation();
803 $Job->update_attributes('output' => &collate_output(),
805 'success' => $Job->{'output'} && $main::success,
806 'finished_at' => scalar gmtime)
809 if ($Job->{'output'})
812 my $manifest_text = `arv keep get ''\Q$Job->{'output'}\E`;
813 $arv->{'collections'}->{'create'}->execute('collection' => {
814 'uuid' => $Job->{'output'},
815 'manifest_text' => $manifest_text,
817 if ($Job->{'output_is_persistent'}) {
818 $arv->{'links'}->{'create'}->execute('link' => {
819 'tail_kind' => 'arvados#user',
820 'tail_uuid' => $User->{'uuid'},
821 'head_kind' => 'arvados#collection',
822 'head_uuid' => $Job->{'output'},
823 'link_class' => 'resources',
829 Log (undef, "Failed to register output manifest: $@");
833 Log (undef, "finish");
840 sub update_progress_stats
842 $progress_stats_updated = time;
843 return if !$progress_is_dirty;
844 my ($todo, $done, $running) = (scalar @jobstep_todo,
845 scalar @jobstep_done,
846 scalar @slot - scalar @freeslot - scalar @holdslot);
847 $Job->{'tasks_summary'} ||= {};
848 $Job->{'tasks_summary'}->{'todo'} = $todo;
849 $Job->{'tasks_summary'}->{'done'} = $done;
850 $Job->{'tasks_summary'}->{'running'} = $running;
852 $Job->update_attributes('tasks_summary' => $Job->{'tasks_summary'});
854 Log (undef, "status: $done done, $running running, $todo todo");
855 $progress_is_dirty = 0;
862 my $pid = waitpid (-1, WNOHANG);
863 return 0 if $pid <= 0;
865 my $whatslot = ($slot[$proc{$pid}->{slot}]->{node}->{name}
867 . $slot[$proc{$pid}->{slot}]->{cpu});
868 my $jobstepid = $proc{$pid}->{jobstep};
869 my $elapsed = time - $proc{$pid}->{time};
870 my $Jobstep = $jobstep[$jobstepid];
872 my $childstatus = $?;
873 my $exitvalue = $childstatus >> 8;
874 my $exitinfo = sprintf("exit %d signal %d%s",
877 ($childstatus & 128 ? ' core dump' : ''));
878 $Jobstep->{'arvados_task'}->reload;
879 my $task_success = $Jobstep->{'arvados_task'}->{success};
881 Log ($jobstepid, "child $pid on $whatslot $exitinfo success=$task_success");
883 if (!defined $task_success) {
884 # task did not indicate one way or the other --> fail
885 $Jobstep->{'arvados_task'}->{success} = 0;
886 $Jobstep->{'arvados_task'}->save;
893 $temporary_fail ||= $Jobstep->{node_fail};
894 $temporary_fail ||= ($exitvalue == 111);
897 ++$thisround_failed_multiple if $Jobstep->{'failures'} >= 1;
899 # Check for signs of a failed or misconfigured node
900 if (++$slot[$proc{$pid}->{slot}]->{node}->{losing_streak} >=
901 2+$slot[$proc{$pid}->{slot}]->{node}->{ncpus}) {
902 # Don't count this against jobstep failure thresholds if this
903 # node is already suspected faulty and srun exited quickly
904 if ($slot[$proc{$pid}->{slot}]->{node}->{hold_until} &&
906 Log ($jobstepid, "blaming failure on suspect node " .
907 $slot[$proc{$pid}->{slot}]->{node}->{name});
908 $temporary_fail ||= 1;
910 ban_node_by_slot($proc{$pid}->{slot});
913 Log ($jobstepid, sprintf('failure (#%d, %s) after %d seconds',
914 ++$Jobstep->{'failures'},
915 $temporary_fail ? 'temporary ' : 'permanent',
918 if (!$temporary_fail || $Jobstep->{'failures'} >= 3) {
919 # Give up on this task, and the whole job
921 $main::please_freeze = 1;
924 # Put this task back on the todo queue
925 push @jobstep_todo, $jobstepid;
927 $Job->{'tasks_summary'}->{'failed'}++;
931 ++$thisround_succeeded;
932 $slot[$proc{$pid}->{slot}]->{node}->{losing_streak} = 0;
933 $slot[$proc{$pid}->{slot}]->{node}->{hold_until} = 0;
934 push @jobstep_done, $jobstepid;
935 Log ($jobstepid, "success in $elapsed seconds");
937 $Jobstep->{exitcode} = $childstatus;
938 $Jobstep->{finishtime} = time;
939 process_stderr ($jobstepid, $task_success);
940 Log ($jobstepid, "output " . $Jobstep->{'arvados_task'}->{output});
942 close $reader{$jobstepid};
943 delete $reader{$jobstepid};
944 delete $slot[$proc{$pid}->{slot}]->{pid};
945 push @freeslot, $proc{$pid}->{slot};
949 my $newtask_list = [];
952 $newtask_results = $arv->{'job_tasks'}->{'list'}->execute(
954 'created_by_job_task_uuid' => $Jobstep->{'arvados_task'}->{uuid}
956 'order' => 'qsequence',
957 'offset' => scalar(@$newtask_list),
959 push(@$newtask_list, @{$newtask_results->{items}});
960 } while (@{$newtask_results->{items}});
961 foreach my $arvados_task (@$newtask_list) {
963 'level' => $arvados_task->{'sequence'},
965 'arvados_task' => $arvados_task
967 push @jobstep, $jobstep;
968 push @jobstep_todo, $#jobstep;
971 $progress_is_dirty = 1;
975 sub check_refresh_wanted
977 my @stat = stat $ENV{"CRUNCH_REFRESH_TRIGGER"};
978 if (@stat && $stat[9] > $latest_refresh) {
979 $latest_refresh = scalar time;
981 my $Job2 = $arv->{'jobs'}->{'get'}->execute('uuid' => $jobspec);
982 for my $attr ('cancelled_at',
983 'cancelled_by_user_uuid',
984 'cancelled_by_client_uuid') {
985 $Job->{$attr} = $Job2->{$attr};
987 if ($Job->{'cancelled_at'}) {
988 Log (undef, "Job cancelled at " . $Job->{cancelled_at} .
989 " by user " . $Job->{cancelled_by_user_uuid});
991 $main::please_freeze = 1;
999 # return if the kill list was checked <4 seconds ago
1000 if (defined $squeue_kill_checked && $squeue_kill_checked > time - 4)
1004 $squeue_kill_checked = time;
1006 # use killem() on procs whose killtime is reached
1009 if (exists $proc{$_}->{killtime}
1010 && $proc{$_}->{killtime} <= time)
1016 # return if the squeue was checked <60 seconds ago
1017 if (defined $squeue_checked && $squeue_checked > time - 60)
1021 $squeue_checked = time;
1025 # here is an opportunity to check for mysterious problems with local procs
1029 # get a list of steps still running
1030 my @squeue = `squeue -s -h -o '%i %j' && echo ok`;
1032 if ($squeue[-1] ne "ok")
1038 # which of my jobsteps are running, according to squeue?
1042 if (/^(\d+)\.(\d+) (\S+)/)
1044 if ($1 eq $ENV{SLURM_JOBID})
1051 # which of my active child procs (>60s old) were not mentioned by squeue?
1052 foreach (keys %proc)
1054 if ($proc{$_}->{time} < time - 60
1055 && !exists $ok{$proc{$_}->{jobstepname}}
1056 && !exists $proc{$_}->{killtime})
1058 # kill this proc if it hasn't exited in 30 seconds
1059 $proc{$_}->{killtime} = time + 30;
1065 sub release_allocation
1069 Log (undef, "release job allocation");
1070 system "scancel $ENV{SLURM_JOBID}";
1078 foreach my $job (keys %reader)
1081 while (0 < sysread ($reader{$job}, $buf, 8192))
1083 print STDERR $buf if $ENV{CRUNCH_DEBUG};
1084 $jobstep[$job]->{stderr} .= $buf;
1085 preprocess_stderr ($job);
1086 if (length ($jobstep[$job]->{stderr}) > 16384)
1088 substr ($jobstep[$job]->{stderr}, 0, 8192) = "";
1097 sub preprocess_stderr
1101 while ($jobstep[$job]->{stderr} =~ /^(.*?)\n/) {
1103 substr $jobstep[$job]->{stderr}, 0, 1+length($line), "";
1104 Log ($job, "stderr $line");
1105 if ($line =~ /srun: error: (SLURM job $ENV{SLURM_JOB_ID} has expired|Unable to confirm allocation for job $ENV{SLURM_JOB_ID})/) {
1107 $main::please_freeze = 1;
1109 elsif ($line =~ /srun: error: (Node failure on|Unable to create job step) /) {
1110 $jobstep[$job]->{node_fail} = 1;
1111 ban_node_by_slot($jobstep[$job]->{slotindex});
1120 my $task_success = shift;
1121 preprocess_stderr ($job);
1124 Log ($job, "stderr $_");
1125 } split ("\n", $jobstep[$job]->{stderr});
1131 my ($keep, $child_out, $output_block);
1133 my $cmd = "$arv_cli keep get \Q$hash\E";
1134 open($keep, '-|', $cmd) or die "fetch_block: $cmd: $!";
1135 sysread($keep, $output_block, 64 * 1024 * 1024);
1137 return $output_block;
1142 Log (undef, "collate");
1144 my ($child_out, $child_in);
1145 my $pid = open2($child_out, $child_in, $arv_cli, 'keep', 'put', '--raw');
1149 next if (!exists $_->{'arvados_task'}->{output} ||
1150 !$_->{'arvados_task'}->{'success'} ||
1151 $_->{'exitcode'} != 0);
1152 my $output = $_->{'arvados_task'}->{output};
1153 if ($output !~ /^[0-9a-f]{32}(\+\S+)*$/)
1155 $output_in_keep ||= $output =~ / [0-9a-f]{32}\S*\+K/;
1156 print $child_in $output;
1158 elsif (@jobstep == 1)
1160 $joboutput = $output;
1163 elsif (defined (my $outblock = fetch_block ($output)))
1165 $output_in_keep ||= $outblock =~ / [0-9a-f]{32}\S*\+K/;
1166 print $child_in $outblock;
1170 Log (undef, "XXX fetch_block($output) failed XXX");
1176 if (!defined $joboutput) {
1177 my $s = IO::Select->new($child_out);
1178 if ($s->can_read(120)) {
1179 sysread($child_out, $joboutput, 64 * 1024 * 1024);
1182 Log (undef, "timed out reading from 'arv keep put'");
1189 Log (undef, "output $joboutput");
1190 $Job->update_attributes('output' => $joboutput) if $job_has_uuid;
1194 Log (undef, "output undef");
1204 my $sig = 2; # SIGINT first
1205 if (exists $proc{$_}->{"sent_$sig"} &&
1206 time - $proc{$_}->{"sent_$sig"} > 4)
1208 $sig = 15; # SIGTERM if SIGINT doesn't work
1210 if (exists $proc{$_}->{"sent_$sig"} &&
1211 time - $proc{$_}->{"sent_$sig"} > 4)
1213 $sig = 9; # SIGKILL if SIGTERM doesn't work
1215 if (!exists $proc{$_}->{"sent_$sig"})
1217 Log ($proc{$_}->{jobstep}, "sending 2x signal $sig to pid $_");
1219 select (undef, undef, undef, 0.1);
1222 kill $sig, $_; # srun wants two SIGINT to really interrupt
1224 $proc{$_}->{"sent_$sig"} = time;
1225 $proc{$_}->{"killedafter"} = time - $proc{$_}->{"time"};
1235 vec($bits,fileno($_),1) = 1;
1241 sub Log # ($jobstep_id, $logmessage)
1243 if ($_[1] =~ /\n/) {
1244 for my $line (split (/\n/, $_[1])) {
1249 my $fh = select STDERR; $|=1; select $fh;
1250 my $message = sprintf ("%s %d %s %s", $job_id, $$, @_);
1251 $message =~ s{([^ -\176])}{"\\" . sprintf ("%03o", ord($1))}ge;
1254 if ($metastream || -t STDERR) {
1255 my @gmtime = gmtime;
1256 $datetime = sprintf ("%04d-%02d-%02d_%02d:%02d:%02d",
1257 $gmtime[5]+1900, $gmtime[4]+1, @gmtime[3,2,1,0]);
1259 print STDERR ((-t STDERR) ? ($datetime." ".$message) : $message);
1262 print $metastream $datetime . " " . $message;
1269 my ($package, $file, $line) = caller;
1270 my $message = "@_ at $file line $line\n";
1271 Log (undef, $message);
1272 freeze() if @jobstep_todo;
1273 collate_output() if @jobstep_todo;
1275 save_meta() if $metastream;
1282 return if !$job_has_uuid;
1283 $Job->update_attributes('running' => 0,
1285 'finished_at' => scalar gmtime);
1291 my $justcheckpoint = shift; # false if this will be the last meta saved
1292 return if $justcheckpoint; # checkpointing is not relevant post-Warehouse.pm
1294 $local_logfile->flush;
1295 my $cmd = "$arv_cli keep put --filename ''\Q$keep_logfile\E "
1296 . quotemeta($local_logfile->filename);
1297 my $loglocator = `$cmd`;
1298 die "system $cmd failed: $?" if $?;
1301 $local_logfile = undef; # the temp file is automatically deleted
1302 Log (undef, "log manifest is $loglocator");
1303 $Job->{'log'} = $loglocator;
1304 $Job->update_attributes('log', $loglocator) if $job_has_uuid;
1308 sub freeze_if_want_freeze
1310 if ($main::please_freeze)
1312 release_allocation();
1315 # kill some srun procs before freeze+stop
1316 map { $proc{$_} = {} } @_;
1319 killem (keys %proc);
1320 select (undef, undef, undef, 0.1);
1322 while (($died = waitpid (-1, WNOHANG)) > 0)
1324 delete $proc{$died};
1339 Log (undef, "Freeze not implemented");
1346 croak ("Thaw not implemented");
1362 $s =~ s{\\(.)}{$1 eq "n" ? "\n" : $1}ge;
1369 my $srunargs = shift;
1370 my $execargs = shift;
1371 my $opts = shift || {};
1373 my $args = $have_slurm ? [@$srunargs, @$execargs] : $execargs;
1374 print STDERR (join (" ",
1375 map { / / ? "'$_'" : $_ }
1378 if $ENV{CRUNCH_DEBUG};
1380 if (defined $stdin) {
1381 my $child = open STDIN, "-|";
1382 defined $child or die "no fork: $!";
1384 print $stdin or die $!;
1385 close STDOUT or die $!;
1390 return system (@$args) if $opts->{fork};
1393 warn "ENV size is ".length(join(" ",%ENV));
1394 die "exec failed: $!: @$args";
1398 sub ban_node_by_slot {
1399 # Don't start any new jobsteps on this node for 60 seconds
1401 $slot[$slotid]->{node}->{hold_until} = 60 + scalar time;
1402 $slot[$slotid]->{node}->{hold_count}++;
1403 Log (undef, "backing off node " . $slot[$slotid]->{node}->{name} . " for 60 seconds");
1408 my ($lockfile, $error_message) = @_;
1409 open L, ">", $lockfile or croak("$lockfile: $!");
1410 if (!flock L, LOCK_EX|LOCK_NB) {
1411 croak("Can't lock $lockfile: $error_message\n");
1418 # checkout-and-build
1422 my $destdir = $ENV{"CRUNCH_SRC"};
1423 my $commit = $ENV{"CRUNCH_SRC_COMMIT"};
1424 my $repo = $ENV{"CRUNCH_SRC_URL"};
1426 open L, ">", "$destdir.lock" or die "$destdir.lock: $!";
1428 if (readlink ("$destdir.commit") eq $commit && -d $destdir) {
1432 unlink "$destdir.commit";
1433 open STDOUT, ">", "$destdir.log";
1434 open STDERR, ">&STDOUT";
1437 my @git_archive_data = <DATA>;
1438 if (@git_archive_data) {
1439 open TARX, "|-", "tar", "-C", $destdir, "-xf", "-";
1440 print TARX @git_archive_data;
1442 die "'tar -C $destdir -xf -' exited $?: $!";
1447 chomp ($pwd = `pwd`);
1448 my $install_dir = $ENV{"CRUNCH_INSTALL"} || "$pwd/opt";
1451 for my $src_path ("$destdir/arvados/sdk/python") {
1453 shell_or_die ("virtualenv", $install_dir);
1454 shell_or_die ("cd $src_path && ./build.sh && $install_dir/bin/python setup.py install");
1458 if (-e "$destdir/crunch_scripts/install") {
1459 shell_or_die ("$destdir/crunch_scripts/install", $install_dir);
1460 } elsif (!-e "./install.sh" && -e "./tests/autotests.sh") {
1462 shell_or_die ("./tests/autotests.sh", $install_dir);
1463 } elsif (-e "./install.sh") {
1464 shell_or_die ("./install.sh", $install_dir);
1468 unlink "$destdir.commit.new";
1469 symlink ($commit, "$destdir.commit.new") or die "$destdir.commit.new: $!";
1470 rename ("$destdir.commit.new", "$destdir.commit") or die "$destdir.commit: $!";
1479 if ($ENV{"DEBUG"}) {
1480 print STDERR "@_\n";
1483 or die "@_ failed: $! exit 0x".sprintf("%x",$?);