last few lines shown at 5+ second pause: { [...] rt_sigaction(SIGINT, {sa_handler=0x561aaecdaae0, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 wait4(-1, } The 'scan last' command was taking 5 or more seconds. Normally there is no noticiable delay. --- $ ( set -x; strace command scan last ) + strace command scan last execve("/usr/bin/command", ["command", "scan", "last"], 0x7ffd3dd38f50 /* 44 vars */) = 0 brk(NULL) = 0x561aaf9b8000 arch_prctl(0x3001 /* ARCH_??? */, 0x7ffd006b0ac0) = -1 EINVAL (Invalid argument) access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory) openat(AT_FDCWD, "/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3 fstat(3, {st_mode=S_IFREG|0644, st_size=129364, ...}) = 0 mmap(NULL, 129364, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f1f8c1ac000 close(3) = 0 openat(AT_FDCWD, "/lib64/libtinfo.so.6", O_RDONLY|O_CLOEXEC) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\363\0\0\0\0\0\0"..., 832) = 832 lseek(3, 169616, SEEK_SET) = 169616 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32 fstat(3, {st_mode=S_IFREG|0755, st_size=205480, ...}) = 0 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f1f8c1aa000 lseek(3, 169616, SEEK_SET) = 169616 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32 mmap(NULL, 190848, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f1f8c17b000 mmap(0x7f1f8c189000, 61440, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xe000) = 0x7f1f8c189000 mmap(0x7f1f8c198000, 53248, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1d000) = 0x7f1f8c198000 mmap(0x7f1f8c1a5000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x29000) = 0x7f1f8c1a5000 close(3) = 0 openat(AT_FDCWD, "/lib64/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0p\22\0\0\0\0\0\0"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0755, st_size=29320, ...}) = 0 mmap(NULL, 20784, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f1f8c175000 mmap(0x7f1f8c176000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0x7f1f8c176000 mmap(0x7f1f8c178000, 4096, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7f1f8c178000 mmap(0x7f1f8c179000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7f1f8c179000 close(3) = 0 openat(AT_FDCWD, "/lib64/libc.so.6", O_RDONLY|O_CLOEXEC) = 3 read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 E\2\0\0\0\0\0"..., 832) = 832 lseek(3, 792, SEEK_SET) = 792 read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\273\200t>\36\363\345\222B\324P\35\374\257\331h"..., 68) = 68 fstat(3, {st_mode=S_IFREG|0755, st_size=2786576, ...}) = 0 lseek(3, 792, SEEK_SET) = 792 read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\273\200t>\36\363\345\222B\324P\35\374\257\331h"..., 68) = 68 lseek(3, 864, SEEK_SET) = 864 read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32 mmap(NULL, 1857568, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f1f8bfaf000 mprotect(0x7f1f8bfd1000, 1679360, PROT_NONE) = 0 mmap(0x7f1f8bfd1000, 1363968, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x22000) = 0x7f1f8bfd1000 mmap(0x7f1f8c11e000, 311296, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16f000) = 0x7f1f8c11e000 mmap(0x7f1f8c16b000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1bb000) = 0x7f1f8c16b000 mmap(0x7f1f8c171000, 14368, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f1f8c171000 close(3) = 0 mmap(NULL, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f1f8bfac000 arch_prctl(ARCH_SET_FS, 0x7f1f8bfac740) = 0 mprotect(0x7f1f8c16b000, 16384, PROT_READ) = 0 mprotect(0x7f1f8c179000, 4096, PROT_READ) = 0 mprotect(0x7f1f8c1a5000, 16384, PROT_READ) = 0 mprotect(0x561aaed8e000, 16384, PROT_READ) = 0 mprotect(0x7f1f8c1f6000, 4096, PROT_READ) = 0 munmap(0x7f1f8c1ac000, 129364) = 0 openat(AT_FDCWD, "/dev/tty", O_RDWR|O_NONBLOCK) = 3 close(3) = 0 brk(NULL) = 0x561aaf9b8000 brk(0x561aaf9d9000) = 0x561aaf9d9000 brk(NULL) = 0x561aaf9d9000 openat(AT_FDCWD, "/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = 3 fstat(3, {st_mode=S_IFREG|0644, st_size=217750496, ...}) = 0 mmap(NULL, 217750496, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f1f7f002000 close(3) = 0 getuid() = 4187 getgid() = 35476 geteuid() = 4187 getegid() = 35476 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0 ioctl(-1, TIOCGPGRP, 0x7ffd006b0934) = -1 EBADF (Bad file descriptor) sysinfo({uptime=57713, loads=[0, 0, 0], totalram=3144318976, freeram=250703872, sharedram=2048000, bufferram=197287936, totalswap=4294963200, freeswap=4287885312, procs=507, totalhigh=0, freehigh=0, mem_unit=1}) = 0 rt_sigaction(SIGCHLD, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 rt_sigaction(SIGCHLD, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f1f8bfe7600}, 8) = 0 rt_sigaction(SIGINT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 rt_sigaction(SIGINT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 rt_sigaction(SIGQUIT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 rt_sigaction(SIGQUIT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 rt_sigaction(SIGTSTP, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 rt_sigaction(SIGTSTP, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 rt_sigaction(SIGTTIN, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 rt_sigaction(SIGTTIN, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 rt_sigaction(SIGTTOU, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0 rt_sigaction(SIGTTOU, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0 rt_sigaction(SIGQUIT, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 uname({sysname="Linux", nodename="ibisbil.mumble.com", ...}) = 0 stat("/var/local/team/mke/log/fixme", {st_mode=S_IFDIR|0775, st_size=12288, ...}) = 0 stat(".", {st_mode=S_IFDIR|0775, st_size=12288, ...}) = 0 stat("/var", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 stat("/var/local", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 stat("/var/local/team", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 stat("/var/local/team/mke", {st_mode=S_IFDIR|0775, st_size=4096, ...}) = 0 stat("/var/local/team/mke/log", {st_mode=S_IFDIR|0775, st_size=4096, ...}) = 0 stat("/var/local/team/mke/log/fixme", {st_mode=S_IFDIR|0775, st_size=12288, ...}) = 0 stat("/a/moobt/tmp/alerts2wall", {st_mode=S_IFDIR|0775, st_size=98304, ...}) = 0 getpid() = 991 openat(AT_FDCWD, "/usr/lib64/gconv/gconv-modules.cache", O_RDONLY) = 3 fstat(3, {st_mode=S_IFREG|0644, st_size=26398, ...}) = 0 mmap(NULL, 26398, PROT_READ, MAP_SHARED, 3, 0) = 0x7f1f8c1c5000 close(3) = 0 getppid() = 988 clock_gettime(CLOCK_REALTIME, {tv_sec=1567960368, tv_nsec=712640873}) = 0 getpid() = 991 getpgrp() = 987 ioctl(2, TIOCGPGRP, [987]) = 0 rt_sigaction(SIGCHLD, {sa_handler=0x561aaecde0d0, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7f1f8bfe7600}, 8) = 0 prlimit64(0, RLIMIT_NPROC, NULL, {rlim_cur=256, rlim_max=256}) = 0 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0 openat(AT_FDCWD, "/usr/bin/command", O_RDONLY) = 3 stat("/usr/bin/command", {st_mode=S_IFREG|0755, st_size=35, ...}) = 0 ioctl(3, TCGETS, 0x7ffd006b08c0) = -1 ENOTTY (Inappropriate ioctl for device) lseek(3, 0, SEEK_CUR) = 0 read(3, "#!/usr/bin/sh\nbuiltin command \"$"..., 80) = 35 lseek(3, 0, SEEK_SET) = 0 prlimit64(0, RLIMIT_NOFILE, NULL, {rlim_cur=1024, rlim_max=4*1024}) = 0 fcntl(255, F_GETFD) = -1 EBADF (Bad file descriptor) dup2(3, 255) = 255 close(3) = 0 fcntl(255, F_SETFD, FD_CLOEXEC) = 0 fcntl(255, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE) fstat(255, {st_mode=S_IFREG|0755, st_size=35, ...}) = 0 lseek(255, 0, SEEK_CUR) = 0 read(255, "#!/usr/bin/sh\nbuiltin command \"$"..., 35) = 35 stat(".", {st_mode=S_IFDIR|0775, st_size=12288, ...}) = 0 stat("/a/moobt/.local/bin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/usr/local/bin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/usr/bin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/usr/local/sbin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/usr/sbin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/a/moobt/bin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/usr/local/share/3reu/7Rq/commands/cur/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/usr/local/share/3reu/gb4y/package/main/shar/bin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/a/moobt/_bin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/usr/local/share/3reu/3rdpty-xd/bin/scan", 0x7ffd006b0320) = -1 ENOENT (No such file or directory) stat("/usr/local/nmh/bin/scan", {st_mode=S_IFREG|0755, st_size=668616, ...}) = 0 stat("/usr/local/nmh/bin/scan", {st_mode=S_IFREG|0755, st_size=668616, ...}) = 0 geteuid() = 4187 getegid() = 35476 getuid() = 4187 getgid() = 35476 access("/usr/local/nmh/bin/scan", X_OK) = 0 stat("/usr/local/nmh/bin/scan", {st_mode=S_IFREG|0755, st_size=668616, ...}) = 0 geteuid() = 4187 getegid() = 35476 getuid() = 4187 getgid() = 35476 access("/usr/local/nmh/bin/scan", R_OK) = 0 stat("/usr/local/nmh/bin/scan", {st_mode=S_IFREG|0755, st_size=668616, ...}) = 0 stat("/usr/local/nmh/bin/scan", {st_mode=S_IFREG|0755, st_size=668616, ...}) = 0 geteuid() = 4187 getegid() = 35476 getuid() = 4187 getgid() = 35476 access("/usr/local/nmh/bin/scan", X_OK) = 0 stat("/usr/local/nmh/bin/scan", {st_mode=S_IFREG|0755, st_size=668616, ...}) = 0 geteuid() = 4187 getegid() = 35476 getuid() = 4187 getgid() = 35476 access("/usr/local/nmh/bin/scan", R_OK) = 0 rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0 clone(child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7f1f8bfaca10) = 992 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0 rt_sigaction(SIGINT, {sa_handler=0x561aaecdaae0, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 wait4(-1, 17047+ 09/08 10:31-05 To:moobt Output from your job 429<<+ command dist -whatnowproc /a/moobt/Mail/auto_send -annotate -from 'nym moob ' -to '"James O [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 992 rt_sigaction(SIGINT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, {sa_handler=0x561aaecdaae0, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7f1f8bfe7600}, 8) = 0 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=992, si_uid=4187, si_status=0, si_utime=0, si_stime=0} --- wait4(-1, 0x7ffd006afe90, WNOHANG, NULL) = -1 ECHILD (No child processes) rt_sigreturn({mask=[]}) = 0 read(255, "", 35) = 0 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 exit_group(0) = ? +++ exited with 0 +++ $