[pid 3673] write(5, "[2014-03-20T00:34:49+00:00] INFO"..., 80[2014-03-20T00:34:49+00:00] INFO: Executing ls -d /etc/chef/client.rb on simple
) = 80
[pid 3673] ppoll([{fd=1, events=POLLOUT}], 1, NULL, NULL, 8) = 1 ([{fd=1, revents=POLLOUT}])
[pid 3673] write(1, "Executing ls -d /etc/chef/client"..., 45Executing ls -d /etc/chef/client.rb on simple) = 45
[pid 3673] ppoll([{fd=1, events=POLLOUT}], 1, NULL, NULL, 8) = 1 ([{fd=1, revents=POLLOUT}])
[pid 3673] write(1, "\n", 1
) = 1
[pid 3673] pipe([20, 21]) = 0
[pid 3673] fstat(20, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
[pid 3673] fstat(21, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
[pid 3673] fcntl(20, F_GETFL) = 0 (flags O_RDONLY)
[pid 3673] fstat(20, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
[pid 3673] ioctl(20, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, 0x7fff0cd40ec0) = -1 ENOTTY (Inappropriate ioctl for device)
[pid 3673] fcntl(21, F_GETFL) = 0x1 (flags O_WRONLY)
[pid 3673] fstat(21, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
[pid 3673] ioctl(21, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, 0x7fff0cd40ec0) = -1 ENOTTY (Inappropriate ioctl for device)
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 22
[pid 3673] connect(22, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(22, {msg_name(0)=NULL, msg_iov(1)=[{"\4\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] recvmsg(22, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\234j\0\0\0\0\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] close(22) = 0
[pid 3673] open("/proc/27292/status", O_RDONLY) = 22
[pid 3673] fstat(22, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fdbf4de4000
[pid 3673] read(22, "Name:\tinit\nState:\tS (sleeping)\nT"..., 1024) = 777
[pid 3673] close(22) = 0
[pid 3673] munmap(0x7fdbf4de4000, 4096) = 0
[pid 3673] open("/proc/27292/personality", O_RDONLY) = 22
[pid 3673] fstat(22, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fdbf4de4000
[pid 3673] read(22, "00000000\n", 1024) = 9
Process 3800 attached
[pid 3673] close(22 <unfinished ...>
[pid 3800] set_robust_list(0x7fdbef4709e0, 24 <unfinished ...>
[pid 3673] <... close resumed> ) = 0
[pid 3800] <... set_robust_list resumed> ) = 0
[pid 3673] munmap(0x7fdbf4de4000, 4096 <unfinished ...>
[pid 3800] sched_getaffinity(3800, 32, <unfinished ...>
[pid 3673] <... munmap resumed> ) = 0
[pid 3800] <... sched_getaffinity resumed> {1, 0, 0, 0}) = 32
[pid 3673] open("/proc/27292/attr/current", O_RDONLY <unfinished ...>
[pid 3800] sigaltstack({ss_sp=0x2fb8db0, ss_flags=0, ss_size=16384} <unfinished ...>
[pid 3673] <... open resumed> ) = 22
[pid 3800] <... sigaltstack resumed> , {ss_sp=0, ss_flags=SS_DISABLE, ss_size=0}) = 0
[pid 3673] fstat(22, <unfinished ...>
[pid 3800] write(4, "!", 1 <unfinished ...>
[pid 3673] <... fstat resumed> {st_mode=S_IFREG|0666, st_size=0, ...}) = 0
[pid 3800] <... write resumed> ) = 1
[pid 3793] <... select resumed> ) = 1 (in [3])
[pid 3800] futex(0x948f64, FUTEX_WAIT_PRIVATE, 1, NULLProcess 3799 attached
<unfinished ...>
[pid 3793] read(3, <unfinished ...>
[pid 3799] set_robust_list(0x7fdbef2ee9e0, 24 <unfinished ...>
[pid 3793] <... read resumed> "!", 1024) = 1
[pid 3799] <... set_robust_list resumed> ) = 0
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3799] sched_getaffinity(3799, 32, <unfinished ...>
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...>
[pid 3799] <... sched_getaffinity resumed> {1, 0, 0, 0}) = 32
[pid 3673] <... mmap resumed> ) = 0x7fdbf4de4000
[pid 3799] sigaltstack({ss_sp=0x2cf5a70, ss_flags=0, ss_size=16384} <unfinished ...>
[pid 3673] read(22, <unfinished ...>
[pid 3799] <... sigaltstack resumed> , {ss_sp=0, ss_flags=SS_DISABLE, ss_size=0}) = 0
[pid 3673] <... read resumed> "lxc-container-default (enforce)\n", 1024) = 32
[pid 3799] futex(0x948f64, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3673] read(22, "", 1024) = 0
[pid 3673] close(22) = 0
[pid 3673] munmap(0x7fdbf4de4000, 4096) = 0
[pid 3673] geteuid() = 0
[pid 3673] mkdir("/", 0755) = -1 EEXIST (File exists)
[pid 3673] mkdir("/run/", 0755) = -1 EEXIST (File exists)
[pid 3673] mkdir("/run/lock/", 0755) = -1 EEXIST (File exists)
[pid 3673] mkdir("/run/lock/lxc//", 0755) = -1 EEXIST (File exists)
[pid 3673] mkdir("/run/lock/lxc//var/", 0755) = -1 EEXIST (File exists)
[pid 3673] mkdir("/run/lock/lxc//var/lib/", 0755) = -1 EEXIST (File exists)
[pid 3673] mkdir("/run/lock/lxc//var/lib/lxc", 0755) = -1 EEXIST (File exists)
[pid 3673] stat("/var/lib/lxc/simple/config", {st_mode=S_IFREG|0644, st_size=554, ...}) = 0
[pid 3673] open("/run/lock/lxc//var/lib/lxc/simple", O_RDWR|O_CREAT, 0600) = 22
[pid 3673] fcntl(22, F_SETLKW, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}) = 0
[pid 3673] access("/var/lib/lxc/simple/config", R_OK) = 0
[pid 3673] open("/var/lib/lxc/simple/config", O_RDONLY) = 23
[pid 3673] fstat(23, {st_mode=S_IFREG|0644, st_size=554, ...}) = 0
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fdbf4de4000
[pid 3673] read(23, "# Template used to create this c"..., 4096) = 554
[pid 3673] access("/usr/share/lxc/config/ubuntu.common.conf", R_OK) = 0
[pid 3673] open("/usr/share/lxc/config/ubuntu.common.conf", O_RDONLY) = 24
[pid 3673] fstat(24, {st_mode=S_IFREG|0644, st_size=2537, ...}) = 0
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fdbf4de3000
[pid 3673] read(24, "# Default pivot location\nlxc.piv"..., 4096) = 2537
[pid 3673] read(24, "", 4096) = 0
[pid 3673] close(24) = 0
[pid 3673] munmap(0x7fdbf4de3000, 4096) = 0
[pid 3673] open("/dev/urandom", O_RDONLY) = 24
[pid 3673] fstat(24, {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 9), ...}) = 0
[pid 3673] ioctl(24, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, 0x7fff0cd3fd70) = -1 EINVAL (Invalid argument)
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fdbf4de3000
[pid 3673] read(24, "5\200G5\311(\370\316p8\6\337\35\315f\317\204\266\300<\27\372\215\f4M\23w\214:,\216"..., 4096) = 4096
[pid 3673] close(24) = 0
[pid 3673] munmap(0x7fdbf4de3000, 4096) = 0
[pid 3673] read(23, "", 4096) = 0
[pid 3673] close(23) = 0
[pid 3673] munmap(0x7fdbf4de4000, 4096) = 0
[pid 3673] fcntl(22, F_SETLK, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0
[pid 3673] close(22) = 0
[pid 3673] stat("/var/lib/lxc/simple/partial", 0x7fff0cd40020) = -1 ENOENT (No such file or directory)
[pid 3673] getcwd("/", 4096) = 2
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 22
[pid 3673] connect(22, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(22, {msg_name(0)=NULL, msg_iov(1)=[{"\5\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] recvmsg(22, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\0\0\0\0\0\0\2l\0\0\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] close(22) = 0
[pid 3673] socketpair(PF_LOCAL, SOCK_STREAM|SOCK_CLOEXEC, 0, [22, 23]) = 0
[pid 3673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fdbf4ddda10) = 3801
[pid 3673] close(23) = 0
[pid 3673] geteuid() = 0
[pid 3673] open("/etc/lxc/lxc.conf", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
[pid 3673] open("/proc/cgroups", O_RDONLY|O_CLOEXEC) = 23
[pid 3673] fcntl(23, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
[pid 3673] fstat(23, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fdbf4de4000
[pid 3673] lseek(23, 0, SEEK_CUR) = 0
[pid 3673] read(23, "#subsys_name\thierarchy\tnum_cgrou"..., 1024) = 175
[pid 3673] read(23, "", 1024) = 0
[pid 3673] close(23) = 0
[pid 3673] munmap(0x7fdbf4de4000, 4096) = 0
[pid 3673] open("/proc/self/cgroup", O_RDONLY|O_CLOEXEC) = 23
[pid 3673] fcntl(23, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
[pid 3673] fstat(23, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fdbf4de4000
[pid 3673] lseek(23, 0, SEEK_CUR) = 0
[pid 3673] read(23, "11:hugetlb:/\n10:perf_event:/\n9:b"..., 1024) = 146
[pid 3673] read(23, "", 1024) = 0
[pid 3673] close(23) = 0
[pid 3673] munmap(0x7fdbf4de4000, 4096) = 0
[pid 3673] open("/proc/self/mountinfo", O_RDONLY|O_CLOEXEC) = 23
[pid 3673] fcntl(23, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
[pid 3673] fstat(23, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
[pid 3673] mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fdbf4de4000
[pid 3673] lseek(23, 0, SEEK_CUR) = 0
[pid 3673] read(23, "16 21 0:15 / /sys rw,nosuid,node"..., 1024) = 1024
[pid 3673] read(23, "/pstore rw,relatime - pstore non"..., 1024) = 1005
[pid 3673] read(23, "", 1024) = 0
[pid 3673] close(23) = 0
[pid 3673] munmap(0x7fdbf4de4000, 4096) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\7\0\0\0\300\257\360\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] sendto(23, "cpuset\0", 7, 0, NULL, 0) = 7
[pid 3673] recvmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0`4\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] recvfrom(23, "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\4\0\0\0\200 \371\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] sendto(23, "cpu\0", 4, 0, NULL, 0) = 4
[pid 3673] recvmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0\3607\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] recvfrom(23, "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\10\0\0\0\220\37\371\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0 <unfinished ...>
[pid 3674] <... select resumed> ) = 0 (Timeout)
[pid 3674] select(4, [3], NULL, NULL, NULL <unfinished ...>
[pid 3673] <... sendmsg resumed> ) = 16
Process 3801 attached
[pid 3673] sendto(23, "cpuacct\0", 8, 0, NULL, 0 <unfinished ...>
[pid 3801] set_robust_list(0x7fdbf4ddda20, 24 <unfinished ...>
[pid 3673] <... sendto resumed> ) = 8
[pid 3801] <... set_robust_list resumed> ) = 0
[pid 3673] recvmsg(23, <unfinished ...>
[pid 3801] close(22 <unfinished ...>
[pid 3673] <... recvmsg resumed> {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0\220\36\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3801] <... close resumed> ) = 0
[pid 3673] recvfrom(23, <unfinished ...>
[pid 3801] read(23, <unfinished ...>
[pid 3673] <... recvfrom resumed> "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\7\0\0\0\240\36\371\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] sendto(23, "memory\0", 7, 0, NULL, 0) = 7
[pid 3673] recvmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0p\36\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] recvfrom(23, "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\10\0\0\0\260\35\371\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] sendto(23, "devices\0", 8, 0, NULL, 0) = 8
[pid 3673] recvmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0P\36\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] recvfrom(23, "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\10\0\0\0\300\34\371\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] sendto(23, "freezer\0", 8, 0, NULL, 0) = 8
[pid 3673] recvmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0000\36\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] recvfrom(23, "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\6\0\0\0\320\33\371\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] sendto(23, "blkio\0", 6, 0, NULL, 0) = 6
[pid 3673] recvmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0\20\36\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] recvfrom(23, "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\v\0\0\0\340\32\371\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] sendto(23, "perf_event\0", 11, 0, NULL, 0) = 11
[pid 3673] recvmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0\360\35\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] recvfrom(23, "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] socket(PF_LOCAL, SOCK_STREAM, 0) = 23
[pid 3673] connect(23, {sa_family=AF_LOCAL, sun_path=@"/var/lib/lxc/simple/command"}, 30) = 0
[pid 3673] getuid() = 0
[pid 3673] getgid() = 0
[pid 3673] sendmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\6\0\0\0\10\0\0\0\20\337\263\2\0\0\0\0", 16}], msg_controllen=32, {cmsg_len=28, cmsg_level=SOL_SOCKET, cmsg_type=SCM_CREDENTIALS{pid=3673, uid=0, gid=0}}, msg_flags=0}, 0) = 16
[pid 3673] sendto(23, "hugetlb\0", 8, 0, NULL, 0) = 8
[pid 3673] recvmsg(23, {msg_name(0)=NULL, msg_iov(1)=[{"\0\0\0\0\17\0\0\0\320\35\257\244\342\177\0\0", 16}], msg_controllen=0, msg_flags=0}, 0) = 16
[pid 3673] recvfrom(23, "/lxc/simple-14\0", 15, 0, NULL, NULL) = 15
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/cpuset/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/cpu/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/cpuacct/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/memory/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/devices/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/freezer/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/blkio/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/perf_event/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] open("/sys/fs/cgroup/hugetlb/lxc/simple-14/tasks", O_WRONLY|O_CREAT|O_TRUNC|O_CLOEXEC, 0666) = 23
[pid 3673] write(23, "3801", 4) = 4
[pid 3673] close(23) = 0
[pid 3673] write(22, "\0\0\0\0", 4 <unfinished ...>
[pid 3801] <... read resumed> "\0\0\0\0", 4) = 4
[pid 3801] access("/proc/27292/ns", X_OK) = 0
[pid 3801] open("/proc/27292/ns/mnt", O_RDONLY|O_CLOEXEC) = 22
[pid 3801] open("/proc/27292/ns/pid", O_RDONLY|O_CLOEXEC) = 24
[pid 3801] open("/proc/27292/ns/uts", O_RDONLY|O_CLOEXEC) = 25
[pid 3801] open("/proc/27292/ns/ipc", O_RDONLY|O_CLOEXEC) = 26
[pid 3801] open("/proc/27292/ns/net", O_RDONLY|O_CLOEXEC) = 27
[pid 3801] close(-1) = -1 EBADF (Bad file descriptor)
[pid 3801] setns(22, 0) = 0
[pid 3801] close(22) = 0
[pid 3801] setns(24, 0) = 0
[pid 3801] close(24) = 0
[pid 3801] setns(25, 0) = 0
[pid 3801] close(25) = 0
[pid 3801] setns(26, 0) = 0
[pid 3801] close(26) = 0
[pid 3801] setns(27, 0) = 0
[pid 3801] close(27) = 0
[pid 3801] chdir("/") = 0
[pid 3801] clone(Process 3802 attached
<unfinished ...>
[pid 3802] getpid() = 1864
[pid 3802] read(23, <unfinished ...>
[pid 3801] <... clone resumed> child_stack=0x7fff0cd40130, flags=CLONE_PARENT|SIGCHLD) = 3802
[pid 3801] write(23, "\332\16\0\0", 4) = 4
[pid 3801] exit_group(0) = ?
[pid 3801] +++ exited with 0 +++
[pid 3793] <... select resumed> ) = ? ERESTARTNOHAND (To be restarted if no handler)
[pid 3793] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=3801, si_status=0, si_utime=0, si_stime=0} ---
[pid 3793] select(4, [3], NULL, NULL, {0, 64762} <unfinished ...>
[pid 3673] <... write resumed> ) = 4
[pid 3673] read(22, "\332\16\0\0", 4) = 4
[pid 3673] wait4(3801, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 3801
[pid 3673] write(22, "\0\0\0\0", 4) = 4
[pid 3802] <... read resumed> "\0\0\0\0", 4) = 4
[pid 3673] read(22, <unfinished ...>
[pid 3802] personality(PER_LINUX) = 0
[pid 3802] open("/proc/sys/kernel/cap_last_cap", O_RDONLY) = 22
[pid 3802] read(22, "36\n", 31) = 3
[pid 3802] close(22) = 0
[pid 3802] prctl(PR_CAPBSET_DROP, 0x10, 0, 0, 0) = 0
[pid 3802] prctl(PR_CAPBSET_DROP, 0x19, 0, 0, 0) = 0
[pid 3802] prctl(PR_CAPBSET_DROP, 0x20, 0, 0, 0) = 0
[pid 3802] prctl(PR_CAPBSET_DROP, 0x21, 0, 0, 0) = 0
[pid 3802] write(23, "\1\0\0\0", 4) = 4
[pid 3802] read(23, <unfinished ...>
[pid 3673] <... read resumed> "\1\0\0\0", 4) = 4
[pid 3673] write(22, "\2\0\0\0", 4 <unfinished ...>
[pid 3802] <... read resumed> "\2\0\0\0", 4) = 4
[pid 3802] shutdown(23, SHUT_RDWR) = 0
[pid 3802] close(23) = 0
[pid 3802] gettid() = 1864
[pid 3802] open("/proc/1864/attr/current", O_WRONLY) = 22
[pid 3802] write(22, "changeprofile lxc-container-defa"..., 35) = 35
[pid 3802] close(22) = 0
[pid 3802] fcntl(0, F_GETFL <unfinished ...>
[pid 3673] <... write resumed> ) = 4
[pid 3802] <... fcntl resumed> ) = 0x8002 (flags O_RDWR|O_LARGEFILE)
[pid 3673] shutdown(22, SHUT_RDWR <unfinished ...>
[pid 3802] fcntl(1, F_GETFL <unfinished ...>
[pid 3673] <... shutdown resumed> ) = 0
[pid 3802] <... fcntl resumed> ) = 0x8001 (flags O_WRONLY|O_LARGEFILE)
[pid 3802] fcntl(1, F_SETFL, O_RDONLY|O_LARGEFILE) = 0
[pid 3802] fcntl(2, F_GETFL) = 0x8001 (flags O_WRONLY|O_LARGEFILE)
[pid 3802] fcntl(2, F_SETFL, O_RDONLY|O_LARGEFILE) = 0
[pid 3802] futex(0x948f64, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x948f60, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3673] close(22 <unfinished ...>
[pid 3802] <... futex resumed> ) = 0
[pid 3673] <... close resumed> ) = 0
[pid 3802] futex(0x948f9c, FUTEX_WAIT_PRIVATE, 1, NULL <unfinished ...>
[pid 3673] wait4(3802, <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe8007704, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x7fdbe8007700, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x7fdbe8007768, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x7fdbe8007768, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x7fdbe8007768, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3796] write(4, "!", 1) = 1
[pid 3796] futex(0x948f64, FUTEX_WAIT_PRIVATE, 31, NULL <unfinished ...>
[pid 3674] <... select resumed> ) = 1 (in [3])
[pid 3674] read(3, "!", 1024) = 1
[pid 3674] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x948f64, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x948f60, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x948f30, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x948f30, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x948f98, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x948f98, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x948f9c, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x948f98, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 0
[pid 3796] futex(0x948f30, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3796] futex(0x7fdbe0000b7c, FUTEX_WAIT_PRIVATE, 13, NULL <unfinished ...>
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x948f9c, FUTEX_WAIT_PRIVATE, 11, NULL) = -1 EAGAIN (Resource temporarily unavailable)
[pid 3671] futex(0x948f30, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0000b7c, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x7fdbe0000b78, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x7fdbe0000b50, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x7fdbe0000b50, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x7fdbe0000b50, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3796] write(4, "!", 1) = 1
[pid 3796] futex(0x948f64, FUTEX_WAIT_PRIVATE, 33, NULL <unfinished ...>
[pid 3674] <... select resumed> ) = 1 (in [3], left {0, 98435})
[pid 3674] read(3, "!", 1024) = 1
[pid 3674] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x948f64, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x948f60, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x948f30, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x948f30, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3796] <... futex resumed> ) = 0
[pid 3796] futex(0x948f30, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3796] madvise(0x7fdbef6f4000, 503808, MADV_DONTNEED) = 0
[pid 3796] _exit(0) = ?
[pid 3796] +++ exited with 0 +++
[pid 3671] <... futex resumed> ) = 1
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 11, {159277, 367045440}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3674] <... select resumed> ) = 0 (Timeout)
[pid 3674] select(4, [3], NULL, NULL, NULL <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 13, {159278, 367499450}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 15, {159279, 368090826}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 17, {159280, 368695673}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 19, {159281, 369136653}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 21, {159282, 370001261}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 23, {159283, 371380349}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 25, {159284, 372872392}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 27, {159285, 373304285}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 29, {159286, 374915523}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 31, {159287, 375883776}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 33, {159288, 377937284}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 35, {159289, 379412017}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 37, {159290, 380920819}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 39, {159291, 381404025}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 41, {159292, 383433819}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 43, {159293, 384906665}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 45, {159294, 386396352}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 47, {159295, 388762101}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 49, {159296, 390391081}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3671] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3671] futex(0x7fdbe0005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3671] futex(0x7fdbe0005c44, FUTEX_WAIT_BITSET_PRIVATE, 51, {159297, 391238955}, ffffffff <unfinished ...>
[pid 3793] <... select resumed> ) = 0 (Timeout)
[pid 3793] select(4, [3], NULL, NULL, {0, 100000}Process 3663 detached
Process 3673 detached
[2014-03-20T00:35:09+00:00] FATAL: SIGINT received, stopping
Process 3674 detached
Process 3668 detached
Process 3671 detached
Process 3670 detached
Process 3672 detached
Process 3800 detached
Process 3793 detached
<detached ...>
Process 3798 detached
Process 3799 detached
Process 3802 detached
================================================================================
Error executing action `create` on resource 'machine[simple]'
================================================================================
SystemExit
----------
exit
Resource Declaration:
---------------------
# In /root/.chef/local-mode-cache/cache/cookbooks/lxctests/recipes/simple2.rb
6: machine 'simple'
Compiled Resource:
------------------
# Declared in /root/.chef/local-mode-cache/cache/cookbooks/lxctests/recipes/simple2.rb:6:in `from_file'
machine("simple") do
action :create
retries 0
retry_delay 2
chef_server {:chef_server_url=>"http://127.0.0.1:8889", :options=>{:client_name=>"test", :signing_key_filename=>nil}}
provisioner #<ChefMetalLXC::LXCProvisioner:0x00000001fa0838 @convergence_strategy=#<ChefMetal::ConvergenceStrategy::InstallCached:0x00000000d8b288 @client_rb_path="/etc/chef/client.rb", @client_pem_path="/etc/chef/client.pem", @chef_version=nil, @prerelease=nil, @package_cache_path="/root/.chef/package_cache", @package_cache={}, @tmp_dir="/tmp", @download_lock=#<Mutex:0x00000001dbaf78>>>
provisioner_options {"template"=>"ubuntu", "template_options"=>["-r", "precise"]}
cookbook_name "lxctests"
recipe_name "simple2"
end
[2014-03-20T00:35:19+00:00] INFO: Running queued delayed notifications before re-raising exception
[2014-03-20T00:35:19+00:00] DEBUG: Re-raising exception: SystemExit - machine[simple] (lxctests::simple2 line 6) had an error: SystemExit: exit
/var/lib/gems/1.9.1/gems/lxc-extra-0.0.2/lib/lxc/extra.rb:9:in `execute'
/var/lib/gems/1.9.1/gems/chef-metal-lxc-0.1/lib/chef_metal_lxc/lxc_transport.rb:42:in `execute'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/machine/unix_machine.rb:29:in `file_exists?'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/machine/unix_machine.rb:34:in `files_different?'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/machine/basic_machine.rb:49:in `write_file'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/convergence_strategy/precreate_chef_objects.rb:32:in `setup_convergence'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/convergence_strategy/install_cached.rb:23:in `setup_convergence'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/machine/basic_machine.rb:19:in `setup_convergence'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef/provider/machine.rb:18:in `block in <class:Machine>'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/provider/lwrp_base.rb:60:in `instance_eval'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/provider/lwrp_base.rb:60:in `recipe_eval_with_update_check'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/provider/lwrp_base.rb:45:in `block in action'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/provider.rb:120:in `run_action'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource.rb:625:in `run_action'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:49:in `run_action'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:81:in `block (2 levels) in converge'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:81:in `each'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:81:in `block in converge'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection.rb:98:in `block in execute_each_resource'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:116:in `call'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:116:in `call_iterator_block'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:85:in `step'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:104:in `iterate'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:55:in `each_with_index'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection.rb:96:in `execute_each_resource'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:80:in `converge'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:335:in `converge'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:415:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-20T00:35:19+00:00] ERROR: Running exception handlers
[2014-03-20T00:35:19+00:00] ERROR: Exception handlers complete
[2014-03-20T00:35:19+00:00] FATAL: Stacktrace dumped to /root/.chef/local-mode-cache/cache/chef-stacktrace.out
[2014-03-20T00:35:19+00:00] DEBUG: SystemExit: machine[simple] (lxctests::simple2 line 6) had an error: SystemExit: exit
/var/lib/gems/1.9.1/gems/lxc-extra-0.0.2/lib/lxc/extra.rb:9:in `execute'
/var/lib/gems/1.9.1/gems/chef-metal-lxc-0.1/lib/chef_metal_lxc/lxc_transport.rb:42:in `execute'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/machine/unix_machine.rb:29:in `file_exists?'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/machine/unix_machine.rb:34:in `files_different?'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/machine/basic_machine.rb:49:in `write_file'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/convergence_strategy/precreate_chef_objects.rb:32:in `setup_convergence'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/convergence_strategy/install_cached.rb:23:in `setup_convergence'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef_metal/machine/basic_machine.rb:19:in `setup_convergence'
/var/lib/gems/1.9.1/gems/chef-metal-0.2.1/lib/chef/provider/machine.rb:18:in `block in <class:Machine>'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/provider/lwrp_base.rb:60:in `instance_eval'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/provider/lwrp_base.rb:60:in `recipe_eval_with_update_check'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/provider/lwrp_base.rb:45:in `block in action'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/provider.rb:120:in `run_action'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource.rb:625:in `run_action'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:49:in `run_action'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:81:in `block (2 levels) in converge'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:81:in `each'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:81:in `block in converge'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection.rb:98:in `block in execute_each_resource'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:116:in `call'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:116:in `call_iterator_block'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:85:in `step'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:104:in `iterate'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection/stepable_iterator.rb:55:in `each_with_index'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/resource_collection.rb:96:in `execute_each_resource'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/runner.rb:80:in `converge'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:335:in `converge'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:415:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-20T00:35:19+00:00] DEBUG: Server doesn't support resource history, skipping resource report.
[2014-03-20T00:35:19+00:00] ERROR: machine[simple] (lxctests::simple2 line 6) had an error: SystemExit: exit
[pid 3454] <... read resumed> "\0\0\0\0", 4) = 4
[pid 3454] access("/proc/27292/ns", X_OK) = 0
[pid 3454] open("/proc/27292/ns/mnt", O_RDONLY|O_CLOEXEC) = 22
[pid 3454] open("/proc/27292/ns/pid", O_RDONLY|O_CLOEXEC) = 24
[pid 3454] open("/proc/27292/ns/uts", O_RDONLY|O_CLOEXEC) = 25
[pid 3454] open("/proc/27292/ns/ipc", O_RDONLY|O_CLOEXEC) = 26
[pid 3454] open("/proc/27292/ns/net", O_RDONLY|O_CLOEXEC) = 27
[pid 3454] close(-1) = -1 EBADF (Bad file descriptor)
[pid 3454] setns(22, 0) = 0
[pid 3454] close(22) = 0
[pid 3454] setns(24, 0) = 0
[pid 3454] close(24) = 0
[pid 3454] setns(25, 0) = 0
[pid 3454] close(25) = 0
[pid 3454] setns(26, 0) = 0
[pid 3454] close(26) = 0
[pid 3454] setns(27, 0) = 0
[pid 3454] close(27) = 0
[pid 3454] chdir("/") = 0
[pid 3454] clone(Process 3455 attached
<unfinished ...>
[pid 3455] getpid() = 1847
[pid 3455] read(23, <unfinished ...>
[pid 3454] <... clone resumed> child_stack=0x7fffe7822020, flags=CLONE_PARENT|SIGCHLD) = 3455
[pid 3454] write(23, "\177\r\0\0", 4) = 4
[pid 3454] exit_group(0) = ?
[pid 3454] +++ exited with 0 +++
[pid 3445] <... select resumed> ) = ? ERESTARTNOHAND (To be restarted if no handler)
[pid 3445] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=3454, si_status=0, si_utime=0, si_stime=0} ---
[pid 3445] select(4, [3], NULL, NULL, {0, 65435} <unfinished ...>
[pid 3333] <... write resumed> ) = 4
[pid 3333] read(22, "\177\r\0\0", 4) = 4
[pid 3333] wait4(3454, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 3454
[pid 3333] write(22, "\0\0\0\0", 4 <unfinished ...>
[pid 3455] <... read resumed> "\0\0\0\0", 4) = 4
[pid 3455] personality(PER_LINUX) = 0
[pid 3455] open("/proc/sys/kernel/cap_last_cap", O_RDONLY) = 22
[pid 3455] read(22, "36\n", 31) = 3
[pid 3455] close(22) = 0
[pid 3455] prctl(PR_CAPBSET_DROP, 0x10, 0, 0, 0) = 0
[pid 3455] prctl(PR_CAPBSET_DROP, 0x19, 0, 0, 0) = 0
[pid 3455] prctl(PR_CAPBSET_DROP, 0x20, 0, 0, 0) = 0
[pid 3455] prctl(PR_CAPBSET_DROP, 0x21, 0, 0, 0) = 0
[pid 3455] write(23, "\1\0\0\0", 4) = 4
[pid 3455] read(23, <unfinished ...>
[pid 3333] <... write resumed> ) = 4
[pid 3333] read(22, "\1\0\0\0", 4) = 4
[pid 3333] write(22, "\2\0\0\0", 4 <unfinished ...>
[pid 3455] <... read resumed> "\2\0\0\0", 4) = 4
[pid 3455] shutdown(23, SHUT_RDWR) = 0
[pid 3455] close(23) = 0
[pid 3455] gettid() = 1847
[pid 3455] open("/proc/1847/attr/current", O_WRONLY) = 22
[pid 3455] write(22, "changeprofile lxc-container-defa"..., 35) = 35
[pid 3455] close(22) = 0
[pid 3455] fcntl(0, F_GETFL <unfinished ...>
[pid 3333] <... write resumed> ) = 4
[pid 3455] <... fcntl resumed> ) = 0x8002 (flags O_RDWR|O_LARGEFILE)
[pid 3333] shutdown(22, SHUT_RDWR <unfinished ...>
[pid 3455] fcntl(1, F_GETFL <unfinished ...>
[pid 3333] <... shutdown resumed> ) = 0
[pid 3455] <... fcntl resumed> ) = 0x8001 (flags O_WRONLY|O_LARGEFILE)
[pid 3333] close(22 <unfinished ...>
[pid 3455] fcntl(1, F_SETFL, O_RDONLY|O_LARGEFILE) = 0
[pid 3455] fcntl(2, F_GETFL) = 0x8001 (flags O_WRONLY|O_LARGEFILE)
[pid 3455] fcntl(2, F_SETFL, O_RDONLY|O_LARGEFILE) = 0
[pid 3455] futex(0x208bf64, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x208bf60, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3333] <... close resumed> ) = 0
[pid 3455] <... futex resumed> ) = 0
[pid 3333] wait4(3455, <unfinished ...>
[pid 3455] futex(0x208bf9c, FUTEX_WAIT_PRIVATE, 1, NULL <unfinished ...>
[pid 3445] <... select resumed> ) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3331] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3331] futex(0x7f0438005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3331] futex(0x7f043000c4b4, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x7f043000c4b0, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x7f043000c518, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x7f043000c518, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x7f043000c518, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3449] write(4, "!", 1) = 1
[pid 3449] futex(0x208bf64, FUTEX_WAIT_PRIVATE, 29, NULL <unfinished ...>
[pid 3334] <... select resumed> ) = 1 (in [3])
[pid 3334] read(3, "!", 1024) = 1
[pid 3334] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x208bf64, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x208bf60, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x208bf30, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x208bf30, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x208bf98, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x208bf98, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x208bf9c, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x208bf98, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 0
[pid 3449] futex(0x208bf30, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3449] futex(0x7f0438000b7c, FUTEX_WAIT_PRIVATE, 13, NULL <unfinished ...>
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x208bf9c, FUTEX_WAIT_PRIVATE, 9, NULL) = -1 EAGAIN (Resource temporarily unavailable)
[pid 3331] futex(0x208bf30, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3331] futex(0x7f0438000b7c, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x7f0438000b78, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x7f0438000b50, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x7f0438000b50, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x7f0438000b50, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3449] write(4, "!", 1) = 1
[pid 3449] futex(0x208bf64, FUTEX_WAIT_PRIVATE, 31, NULL <unfinished ...>
[pid 3334] <... select resumed> ) = 1 (in [3], left {0, 98524})
[pid 3334] read(3, "!", 1024) = 1
[pid 3334] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x208bf64, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x208bf60, {FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1} <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x208bf30, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x208bf30, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid 3449] <... futex resumed> ) = 0
[pid 3449] futex(0x208bf30, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3449] madvise(0x7f043d25d000, 503808, MADV_DONTNEED) = 0
[pid 3449] _exit(0) = ?
[pid 3449] +++ exited with 0 +++
[pid 3331] <... futex resumed> ) = 1
[pid 3331] futex(0x7f0438005c44, FUTEX_WAIT_BITSET_PRIVATE, 11, {157269, 309359547}, ffffffff <unfinished ...>
[pid 3445] <... select resumed> ) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3334] <... select resumed> ) = 0 (Timeout)
[pid 3334] select(4, [3], NULL, NULL, NULL <unfinished ...>
[pid 3445] <... select resumed> ) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3331] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3331] futex(0x7f0438005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3331] futex(0x7f0438005c44, FUTEX_WAIT_BITSET_PRIVATE, 13, {157270, 310645051}, ffffffff <unfinished ...>
[pid 3445] <... select resumed> ) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3331] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3331] futex(0x7f0438005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3331] futex(0x7f0438005c44, FUTEX_WAIT_BITSET_PRIVATE, 15, {157271, 312790995}, ffffffff <unfinished ...>
[pid 3445] <... select resumed> ) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000} <unfinished ...>
[pid 3331] <... futex resumed> ) = -1 ETIMEDOUT (Connection timed out)
[pid 3331] futex(0x7f0438005ca8, FUTEX_WAKE_PRIVATE, 1) = 0
[pid 3331] futex(0x7f0438005c44, FUTEX_WAIT_BITSET_PRIVATE, 17, {157272, 314519716}, ffffffff <unfinished ...>
[pid 3445] <... select resumed> ) = 0 (Timeout)
[pid 3445] select(4, [3], NULL, NULL, {0, 100000}) = 0 (Timeout)
[pid 3445] select(4,
[pid 554] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7f52c4d1ba10) = 678
Process 678 attached
[pid 678] clone(Process 679 attached
child_stack=0x7fff865a27d0, flags=CLONE_PARENT|SIGCHLD) = 679
[pid 678] exit_group(0) = ?
[pid 678] +++ exited with 0 +++
[pid 674] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=678, si_status=0, si_utime=0, si_stime=0} ---
[pid 554] wait4(678, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 678
[pid 554] clone(Container: listening to 127.0.0.1:8889child_stack=0x7f52bf22bfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f52bf22c9d0, tls=0x7f52bf22c700, child_tidptr=0x7f52bf22c9d0) = 680
[pid 554] clone(
child_stack=0x7f52bf3adfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f52bf3ae9d0, tls=0x7f52bf3ae700, child_tidptr=0x7f52bf3ae9d0) = 681
Finished forwarding
Container: main loop
Selecting [#<TCPServer:fd 20>, #<IO:fd 18>]
[2014-03-19T23:47:19+00:00] INFO: Executing ls -d /etc/chef/client.rb on simple
Executing ls -d /etc/chef/client.rb on simple
Process 681 attached
Process 680 attached
[pid 554] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7f52c4d1ba10) = 682
Process 682 attached
[pid 682] clone(Process 683 attached
child_stack=0x7fff865a27d0, flags=CLONE_PARENT|SIGCHLD) = 683
[pid 682] exit_group(0) = ?
[pid 554] wait4(682, <unfinished ...>
[pid 682] +++ exited with 0 +++
[pid 674] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=682, si_status=0, si_utime=0, si_stime=0} ---
[pid 554] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 682
[pid 554] wait4(683, <unfinished ...>
[pid 677] _exit(0) = ?
[pid 677] +++ exited with 0 +++
[2014-03-19T23:46:10+00:00] DEBUG: Forwarding container port 8889 to local port 8889
[pid 401] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7f72accdba10) = 530
Process 530 attached
[pid 530] clone(Process 531 attached
child_stack=0x7fff00786fd0, flags=CLONE_PARENT|SIGCHLD) = 531
[pid 530] exit_group(0) = ?
[pid 530] +++ exited with 0 +++
[pid 524] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=530, si_status=0, si_utime=0, si_stime=0} ---
[pid 401] wait4(530, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 530
[pid 401] clone(child_stack=0x7f72a71ebfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f72a71ec9d0, tls=0x7f72a71ec700, child_tidptr=0x7f72a71ec9d0) = 532
[pid 401] clone(child_stack=0x7f72a736dfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f72a736e9d0, tls=0x7f72a736e700, child_tidptr=0x7f72a736e9d0) = 533
Finished forwarding
[2014-03-19T23:46:10+00:00] INFO: Executing ls -d /etc/chef/client.rb on simple
Container: listening to 127.0.0.1:8889Executing ls -d /etc/chef/client.rb on simple
Container: main loop
Selecting [#<TCPServer:fd 20>, #<IO:fd 18>]
Process 533 attached
Process 532 attached
[pid 401] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7f72accdba10) = 534
Process 534 attached
[pid 534] clone(Process 535 attached
child_stack=0x7fff00786fd0, flags=CLONE_PARENT|SIGCHLD) = 535
[pid 534] exit_group(0) = ?
[pid 534] +++ exited with 0 +++
[pid 524] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=534, si_status=0, si_utime=0, si_stime=0} ---
[pid 401] wait4(534, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 534
[pid 401] wait4(535, <unfinished ...>
[pid 527] _exit(0) = ?
[pid 527] +++ exited with 0 +++
root@test:/mnt/host_src/chef-metal-lxc/test# strace -f -e process chef-client -z -l debug -o lxctests::simple2
execve("/usr/local/bin/chef-client", ["chef-client", "-z", "-l", "debug", "-o", "lxctests::simple2"], [/* 23 vars */]) = 0
arch_prctl(ARCH_SET_FS, 0x7fc526822740) = 0
clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32664
Process 32664 attached
[pid 32664] _exit(0) = ?
[pid 32664] +++ exited with 0 +++
clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32665
clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32666
Process 32666 attached
Process 32665 attached
[pid 32665] execve("/usr/bin/ruby1.9.1", ["/usr/bin/ruby1.9.1", "-e", "42"], [/* 23 vars */] <unfinished ...>
[pid 32663] wait4(32665, <unfinished ...>
[pid 32665] <... execve resumed> ) = 0
[pid 32665] arch_prctl(ARCH_SET_FS, 0x7f5752c92740) = 0
[pid 32665] clone(child_stack=0x7f5752c9cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f5752c9d9d0, tls=0x7f5752c9d700, child_tidptr=0x7f5752c9d9d0) = 32667
Process 32667 attached
[pid 32667] _exit(0) = ?
[pid 32667] +++ exited with 0 +++
[pid 32665] exit_group(0) = ?
[pid 32665] +++ exited with 0 +++
[pid 32663] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32665
[pid 32666] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32665, si_status=0, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:46+00:00] WARN: No config file found or specified on command line, using command line options.
[2014-03-19T23:26:46+00:00] INFO: Auto-discovered chef repository at /mnt/host_src/chef-metal-lxc/test
[2014-03-19T23:26:46+00:00] INFO: Starting chef-zero on port 8889 with repository at repository at /mnt/host_src/chef-metal-lxc/test
One version per cookbook
[pid 32663] clone(child_stack=0x7fc52133afb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52133b9d0, tls=0x7fc52133b700, child_tidptr=0x7fc52133b9d0) = 32668
[pid 32663] clone(child_stack=0x7fc5211b8fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc5211b99d0, tls=0x7fc5211b9700, child_tidptr=0x7fc5211b99d0) = 32669
Process 32669 attached
Process 32668 attached
[pid 32668] clone(child_stack=0x7fc521036fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc5210379d0, tls=0x7fc521037700, child_tidptr=0x7fc5210379d0) = 32670
[pid 32668] clone(child_stack=0x7fc520eb4fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc520eb59d0, tls=0x7fc520eb5700, child_tidptr=0x7fc520eb59d0) = 32671
Process 32671 attached
Process 32670 attached
[pid 32668] clone(child_stack=0x7fc520d32fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc520d339d0, tls=0x7fc520d33700, child_tidptr=0x7fc520d339d0) = 32672
[pid 32669] _exit(0) = ?
[pid 32669] +++ exited with 0 +++
[2014-03-19T23:26:46+00:00] INFO: Forking chef instance to converge...
[pid 32666] _exit(0) = ?
[pid 32666] +++ exited with 0 +++
[pid 32663] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32673
[pid 32663] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32674
[2014-03-19T23:26:46+00:00] DEBUG: Fork successful. Waiting for new chef pid: 32673
[pid 32663] wait4(32673, Process 32674 attached
Process 32673 attached
<unfinished ...>
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32675
[2014-03-19T23:26:46+00:00] DEBUG: Forked instance now converging
Process 32672 attached
Process 32675 attached
Starting Chef Client, version 11.10.4
[2014-03-19T23:26:46+00:00] INFO: *** Chef 11.10.4 ***
[2014-03-19T23:26:46+00:00] INFO: Chef-client pid: 32673
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin os
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin kernel
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin ruby
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin languages
[pid 32675] _exit(0) = ?
[pid 32675] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32676
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32677
Process 32677 attached
Process 32676 attached
[pid 32676] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32678
Process 32678 attached
[pid 32678] _exit(0) = ?
[pid 32678] +++ exited with 0 +++
[pid 32676] execve("/bin/sh", ["sh", "-c", "ruby -e \"require 'rbconfig'; put"...], [/* 25 vars */]) = 0
[pid 32676] arch_prctl(ARCH_SET_FS, 0x7fc4ee5ed740) = 0
[pid 32676] clone(Process 32679 attached
<unfinished ...>
[pid 32679] execve("/usr/bin/ruby", ["ruby", "-e", "require 'rbconfig'; puts %Q(plat"...], [/* 25 vars */]) = 0
[pid 32679] arch_prctl(ARCH_SET_FS, 0x7ff7ea578740) = 0
[pid 32676] <... clone resumed> child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc4ee5eda10) = 32679
[pid 32676] wait4(-1, <unfinished ...>
[pid 32679] clone(child_stack=0x7ff7ea582fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7ff7ea5839d0, tls=0x7ff7ea583700, child_tidptr=0x7ff7ea5839d0) = 32680
Process 32680 attached
[pid 32673] wait4(32676, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32673] wait4(32676, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32680] _exit(0) = ?
[pid 32680] +++ exited with 0 +++
[pid 32679] exit_group(0) = ?
[pid 32679] +++ exited with 0 +++
[pid 32676] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32679
[pid 32676] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32679, si_status=0, si_utime=1, si_stime=0} ---
[pid 32676] exit_group(0) = ?
[pid 32673] wait4(32676, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32673] wait4(32676, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32673] wait4(32676, <unfinished ...>
[pid 32676] +++ exited with 0 +++
[pid 32677] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32676, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32676
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin ruby -e "require 'rbconfig'; puts %Q(platform=#{RUBY_PLATFORM},version=#{RUBY_VERSION},release_date=#{RUBY_RELEASE_DATE},target=#{::Config::CONFIG['target']},target_cpu=#{::Config::CONFIG['target_cpu']},target_vendor=#{::Config::CONFIG['target_vendor']},target_os=#{::Config::CONFIG['target_os']},host=#{::Config::CONFIG['host']},host_cpu=#{::Config::CONFIG['host_cpu']},host_os=#{::Config::CONFIG['host_os']},host_vendor=#{::Config::CONFIG['host_vendor']},bin_dir=#{::Config::CONFIG['bindir']},ruby_bin=#{::File.join(::Config::CONFIG['bindir'], ::Config::CONFIG['ruby_install_name'])},)" STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: platform=x86_64-linux,version=1.9.3,release_date=2013-11-22,target=x86_64-pc-linux-gnu,target_cpu=x86_64,target_vendor=pc,target_os=linux,host=x86_64-pc-linux-gnu,host_cpu=x86_64,host_os=linux-gnu,host_vendor=pc,bin_dir=/usr/bin,ruby_bin=/usr/bin/ruby1.9.1,
[2014-03-19T23:26:46+00:00] DEBUG: ---- End ruby -e "require 'rbconfig'; puts %Q(platform=#{RUBY_PLATFORM},version=#{RUBY_VERSION},release_date=#{RUBY_RELEASE_DATE},target=#{::Config::CONFIG['target']},target_cpu=#{::Config::CONFIG['target_cpu']},target_vendor=#{::Config::CONFIG['target_vendor']},target_os=#{::Config::CONFIG['target_os']},host=#{::Config::CONFIG['host']},host_cpu=#{::Config::CONFIG['host_cpu']},host_os=#{::Config::CONFIG['host_os']},host_vendor=#{::Config::CONFIG['host_vendor']},bin_dir=#{::Config::CONFIG['bindir']},ruby_bin=#{::File.join(::Config::CONFIG['bindir'], ::Config::CONFIG['ruby_install_name'])},)" STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin ruby -e "require 'rbconfig'; puts %Q(platform=#{RUBY_PLATFORM},version=#{RUBY_VERSION},release_date=#{RUBY_RELEASE_DATE},target=#{::Config::CONFIG['target']},target_cpu=#{::Config::CONFIG['target_cpu']},target_vendor=#{::Config::CONFIG['target_vendor']},target_os=#{::Config::CONFIG['target_os']},host=#{::Config::CONFIG['host']},host_cpu=#{::Config::CONFIG['host_cpu']},host_os=#{::Config::CONFIG['host_os']},host_vendor=#{::Config::CONFIG['host_vendor']},bin_dir=#{::Config::CONFIG['bindir']},ruby_bin=#{::File.join(::Config::CONFIG['bindir'], ::Config::CONFIG['ruby_install_name'])},)" STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: -e:1: Use RbConfig instead of obsolete and deprecated Config.
[2014-03-19T23:26:46+00:00] DEBUG: ---- End ruby -e "require 'rbconfig'; puts %Q(platform=#{RUBY_PLATFORM},version=#{RUBY_VERSION},release_date=#{RUBY_RELEASE_DATE},target=#{::Config::CONFIG['target']},target_cpu=#{::Config::CONFIG['target_cpu']},target_vendor=#{::Config::CONFIG['target_vendor']},target_os=#{::Config::CONFIG['target_os']},host=#{::Config::CONFIG['host']},host_cpu=#{::Config::CONFIG['host_cpu']},host_os=#{::Config::CONFIG['host_os']},host_vendor=#{::Config::CONFIG['host_vendor']},bin_dir=#{::Config::CONFIG['bindir']},ruby_bin=#{::File.join(::Config::CONFIG['bindir'], ::Config::CONFIG['ruby_install_name'])},)" STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (ruby -e "require 'rbconfig'; puts %Q(platform=#{RUBY_PLATFORM},version=#{RUBY_VERSION},release_date=#{RUBY_RELEASE_DATE},target=#{::Config::CONFIG['target']},target_cpu=#{::Config::CONFIG['target_cpu']},target_vendor=#{::Config::CONFIG['target_vendor']},target_os=#{::Config::CONFIG['target_os']},host=#{::Config::CONFIG['host']},host_cpu=#{::Config::CONFIG['host_cpu']},host_os=#{::Config::CONFIG['host_os']},host_vendor=#{::Config::CONFIG['host_vendor']},bin_dir=#{::Config::CONFIG['bindir']},ruby_bin=#{::File.join(::Config::CONFIG['bindir'], ::Config::CONFIG['ruby_install_name'])},)") returned 0
[pid 32677] _exit(0) = ?
[pid 32677] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32681
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32682
Process 32682 attached
Process 32681 attached
[pid 32681] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32683
Process 32683 attached
[pid 32683] _exit(0) = ?
[pid 32683] +++ exited with 0 +++
[pid 32681] execve("/bin/sh", ["sh", "-c", "ruby -e \"require 'rbconfig'; req"...], [/* 25 vars */]) = 0
[pid 32681] arch_prctl(ARCH_SET_FS, 0x7f10f44c7740) = 0
[pid 32681] clone(Process 32684 attached
<unfinished ...>
[pid 32684] execve("/usr/bin/ruby", ["ruby", "-e", "require 'rbconfig'; require 'rub"...], [/* 25 vars */]) = 0
[pid 32684] arch_prctl(ARCH_SET_FS, 0x7f396633b740) = 0
[pid 32681] <... clone resumed> child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7f10f44c7a10) = 32684
[pid 32681] wait4(-1, <unfinished ...>
[pid 32684] clone(child_stack=0x7f3966345fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f39663469d0, tls=0x7f3966346700, child_tidptr=0x7f39663469d0) = 32685
Process 32685 attached
[pid 32673] wait4(32681, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32685] _exit(0) = ?
[pid 32685] +++ exited with 0 +++
[pid 32684] exit_group(0) = ?
[pid 32684] +++ exited with 0 +++
[pid 32681] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32684
[pid 32681] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32684, si_status=0, si_utime=1, si_stime=0} ---
[pid 32681] exit_group(0) = ?
[pid 32673] wait4(32681, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32673] wait4(32681, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32673] wait4(32681, <unfinished ...>
[pid 32681] +++ exited with 0 +++
[pid 32682] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32681, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32681
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin ruby -e "require 'rbconfig'; require 'rubygems'; puts ::Gem.default_exec_format % 'gem'" STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: gem1.9.1
[2014-03-19T23:26:46+00:00] DEBUG: ---- End ruby -e "require 'rbconfig'; require 'rubygems'; puts ::Gem.default_exec_format % 'gem'" STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin ruby -e "require 'rbconfig'; require 'rubygems'; puts ::Gem.default_exec_format % 'gem'" STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG:
[2014-03-19T23:26:46+00:00] DEBUG: ---- End ruby -e "require 'rbconfig'; require 'rubygems'; puts ::Gem.default_exec_format % 'gem'" STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (ruby -e "require 'rbconfig'; require 'rubygems'; puts ::Gem.default_exec_format % 'gem'") returned 0
[pid 32682] _exit(0) = ?
[pid 32682] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32686
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32687
Process 32687 attached
Process 32686 attached
[pid 32686] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32688
Process 32688 attached
[pid 32688] _exit(0) = ?
[pid 32688] +++ exited with 0 +++
[pid 32686] execve("/bin/sh", ["sh", "-c", "ruby -e \"require 'rbconfig'; put"...], [/* 25 vars */]) = 0
[pid 32686] arch_prctl(ARCH_SET_FS, 0x7fe08bab0740) = 0
[pid 32686] clone(Process 32689 attached
<unfinished ...>
[pid 32689] execve("/usr/bin/ruby", ["ruby", "-e", "require 'rbconfig'; puts %x{/usr"...], [/* 25 vars */]) = 0
[pid 32689] arch_prctl(ARCH_SET_FS, 0x7fa1b8fc8740) = 0
[pid 32686] <... clone resumed> child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fe08bab0a10) = 32689
[pid 32686] wait4(-1, <unfinished ...>
[pid 32689] clone(Process 32690 attached
child_stack=0x7fa1b8fd2fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fa1b8fd39d0, tls=0x7fa1b8fd3700, child_tidptr=0x7fa1b8fd39d0) = 32690
[pid 32690] _exit(0) = ?
[pid 32690] +++ exited with 0 +++
[pid 32689] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fa1b8fc8a10) = 32691
[pid 32689] clone(child_stack=0x7fa1b8fd2fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fa1b8fd39d0, tls=0x7fa1b8fd3700, child_tidptr=0x7fa1b8fd39d0) = 32692
Process 32692 attached
Process 32691 attached
[pid 32691] execve("/usr/bin/ruby1.9.1", ["/usr/bin/ruby1.9.1", "/usr/bin/gem1.9.1", "env", "gemdir"], [/* 25 vars */]) = 0
[pid 32691] arch_prctl(ARCH_SET_FS, 0x7f0bc7dcd740) = 0
[pid 32691] clone(child_stack=0x7f0bc7dd7fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7f0bc7dd89d0, tls=0x7f0bc7dd8700, child_tidptr=0x7f0bc7dd89d0) = 32693
Process 32693 attached
[pid 32693] _exit(0) = ?
[pid 32693] +++ exited with 0 +++
[pid 32691] exit_group(0) = ?
[pid 32689] wait4(32691, <unfinished ...>
[pid 32691] +++ exited with 0 +++
[pid 32692] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32691, si_status=0, si_utime=6, si_stime=2} ---
[pid 32689] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32691
[pid 32673] wait4(32686, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32692] _exit(0) = ?
[pid 32692] +++ exited with 0 +++
[pid 32689] exit_group(0) = ?
[pid 32689] +++ exited with 0 +++
[pid 32686] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32689
[pid 32686] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32689, si_status=0, si_utime=1, si_stime=0} ---
[pid 32686] exit_group(0) = ?
[pid 32673] wait4(32686, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32673] wait4(32686, 0x7fff1169038c, WNOHANG, NULL) = 0
[pid 32673] wait4(32686, <unfinished ...>
[pid 32686] +++ exited with 0 +++
[pid 32687] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32686, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32686
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin ruby -e "require 'rbconfig'; puts %x{/usr/bin/ruby1.9.1 /usr/bin/gem1.9.1 env gemdir}.chomp!" STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: /var/lib/gems/1.9.1
[2014-03-19T23:26:46+00:00] DEBUG: ---- End ruby -e "require 'rbconfig'; puts %x{/usr/bin/ruby1.9.1 /usr/bin/gem1.9.1 env gemdir}.chomp!" STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin ruby -e "require 'rbconfig'; puts %x{/usr/bin/ruby1.9.1 /usr/bin/gem1.9.1 env gemdir}.chomp!" STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG:
[2014-03-19T23:26:46+00:00] DEBUG: ---- End ruby -e "require 'rbconfig'; puts %x{/usr/bin/ruby1.9.1 /usr/bin/gem1.9.1 env gemdir}.chomp!" STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (ruby -e "require 'rbconfig'; puts %x{/usr/bin/ruby1.9.1 /usr/bin/gem1.9.1 env gemdir}.chomp!") returned 0
[pid 32687] _exit(0) = ?
[pid 32687] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32694
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32695
Process 32695 attached
Process 32694 attached
[pid 32694] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32696
Process 32696 attached
[pid 32696] _exit(0) = ?
[pid 32696] +++ exited with 0 +++
[pid 32694] execve("/bin/uname", ["uname", "-s"], [/* 25 vars */]) = 0
[pid 32694] arch_prctl(ARCH_SET_FS, 0x7f7589074740) = 0
[pid 32694] exit_group(0) = ?
[pid 32694] +++ exited with 0 +++
[pid 32673] wait4(32694, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32694
[pid 32695] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32694, si_status=0, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin uname -s STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: Linux
[2014-03-19T23:26:46+00:00] DEBUG: ---- End uname -s STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin uname -s STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG:
[2014-03-19T23:26:46+00:00] DEBUG: ---- End uname -s STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (uname -s) returned 0
[pid 32695] _exit(0) = ?
[pid 32695] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32697
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32698
Process 32697 attached
Process 32698 attached
[pid 32697] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32699
Process 32699 attached
[pid 32699] _exit(0) = ?
[pid 32699] +++ exited with 0 +++
[pid 32697] execve("/bin/uname", ["uname", "-r"], [/* 25 vars */]) = 0
[pid 32697] arch_prctl(ARCH_SET_FS, 0x7f5e2e6ba740) = 0
[pid 32697] exit_group(0) = ?
[pid 32697] +++ exited with 0 +++
[pid 32673] wait4(32697, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32697
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin uname -r STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: 3.13.0-11-generic
[2014-03-19T23:26:46+00:00] DEBUG: ---- End uname -r STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin uname -r STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG:
[2014-03-19T23:26:46+00:00] DEBUG: ---- End uname -r STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (uname -r) returned 0
[pid 32698] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32697, si_status=0, si_utime=0, si_stime=0} ---
[pid 32698] _exit(0) = ?
[pid 32698] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32700
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32701
Process 32700 attached
Process 32701 attached
[pid 32700] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32702
Process 32702 attached
[pid 32702] _exit(0) = ?
[pid 32702] +++ exited with 0 +++
[pid 32700] execve("/bin/uname", ["uname", "-v"], [/* 25 vars */]) = 0
[pid 32700] arch_prctl(ARCH_SET_FS, 0x7f9ee1dbc740) = 0
[pid 32700] exit_group(0) = ?
[pid 32700] +++ exited with 0 +++
[pid 32673] wait4(32700, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32700
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin uname -v STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: #31-Ubuntu SMP Wed Feb 19 19:57:21 UTC 2014
[2014-03-19T23:26:46+00:00] DEBUG: ---- End uname -v STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin uname -v STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG:
[2014-03-19T23:26:46+00:00] DEBUG: ---- End uname -v STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (uname -v) returned 0
[pid 32701] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32700, si_status=0, si_utime=0, si_stime=0} ---
[pid 32701] _exit(0) = ?
[pid 32701] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32703
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32704
Process 32704 attached
Process 32703 attached
[pid 32703] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32705
Process 32705 attached
[pid 32705] _exit(0) = ?
[pid 32705] +++ exited with 0 +++
[pid 32703] execve("/bin/uname", ["uname", "-m"], [/* 25 vars */]) = 0
[pid 32703] arch_prctl(ARCH_SET_FS, 0x7f5accbec740) = 0
[pid 32703] exit_group(0) = ?
[pid 32703] +++ exited with 0 +++
[pid 32673] wait4(32703, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32703
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin uname -m STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: x86_64
[2014-03-19T23:26:46+00:00] DEBUG: ---- End uname -m STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin uname -m STDERR ----
[pid 32704] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32703, si_status=0, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:46+00:00] DEBUG:
[2014-03-19T23:26:46+00:00] DEBUG: ---- End uname -m STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (uname -m) returned 0
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin ohai
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin eucalyptus
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin hostname
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin linux::hostname
[pid 32704] _exit(0) = ?
[pid 32704] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32706
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32707
Process 32707 attached
Process 32706 attached
[pid 32706] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32708
Process 32708 attached
[pid 32708] _exit(0) = ?
[pid 32708] +++ exited with 0 +++
[pid 32706] execve("/bin/hostname", ["hostname", "-s"], [/* 25 vars */]) = 0
[pid 32706] arch_prctl(ARCH_SET_FS, 0x7f21dbc72740) = 0
[pid 32706] exit_group(0) = ?
[pid 32706] +++ exited with 0 +++
[pid 32673] wait4(32706, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32706
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin hostname -s STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: test
[2014-03-19T23:26:46+00:00] DEBUG: ---- End hostname -s STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin hostname -s STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG:
[2014-03-19T23:26:46+00:00] DEBUG: ---- End hostname -s STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (hostname -s) returned 0
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32706, si_status=0, si_utime=0, si_stime=0} ---
[pid 32707] _exit(0) = ?
[pid 32707] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32709
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32710
Process 32710 attached
Process 32709 attached
[pid 32709] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32711
Process 32711 attached
[pid 32711] _exit(0) = ?
[pid 32711] +++ exited with 0 +++
[pid 32709] execve("/bin/hostname", ["hostname", "--fqdn"], [/* 25 vars */]) = 0
[pid 32709] arch_prctl(ARCH_SET_FS, 0x7f10fc6ff740) = 0
[pid 32709] exit_group(0) = ?
[pid 32709] +++ exited with 0 +++
[pid 32673] wait4(32709, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32709
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin hostname --fqdn STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: test
[pid 32710] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32709, si_status=0, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:46+00:00] DEBUG: ---- End hostname --fqdn STDOUT ----
[2014-03-19T23:26:46+00:00] DEBUG: ---- Begin hostname --fqdn STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG:
[2014-03-19T23:26:46+00:00] DEBUG: ---- End hostname --fqdn STDERR ----
[2014-03-19T23:26:46+00:00] DEBUG: Ran (hostname --fqdn) returned 0
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin network
[2014-03-19T23:26:46+00:00] DEBUG: Loading plugin linux::network
[pid 32710] _exit(0) = ?
[pid 32710] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32712
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32713
Process 32713 attached
Process 32712 attached
[pid 32712] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32714
Process 32714 attached
[pid 32714] _exit(0) = ?
[pid 32714] +++ exited with 0 +++
[pid 32712] execve("/sbin/ip", ["ip", "addr"], [/* 25 vars */]) = 0
[pid 32712] arch_prctl(ARCH_SET_FS, 0x7f761f75a740) = 0
[pid 32712] exit_group(0) = ?
[pid 32712] +++ exited with 0 +++
[pid 32673] wait4(32712, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32712
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32712, si_status=0, si_utime=0, si_stime=0} ---
[pid 32713] _exit(0) = ?
[pid 32713] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32715
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32716
Process 32716 attached
Process 32715 attached
[pid 32715] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32717
Process 32717 attached
[pid 32717] _exit(0) = ?
[pid 32717] +++ exited with 0 +++
[pid 32715] execve("/sbin/ip", ["ip", "-d", "-s", "link"], [/* 25 vars */]) = 0
[pid 32715] arch_prctl(ARCH_SET_FS, 0x7f144238d740) = 0
[pid 32715] exit_group(0) = ?
[pid 32715] +++ exited with 0 +++
[pid 32673] wait4(32715, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32715
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32715, si_status=0, si_utime=0, si_stime=0} ---
[pid 32716] _exit(0) = ?
[pid 32716] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32718
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32719
Process 32719 attached
Process 32718 attached
[pid 32718] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32720
Process 32720 attached
[pid 32720] _exit(0) = ?
[pid 32720] +++ exited with 0 +++
[pid 32718] execve("/sbin/ip", ["ip", "-f", "inet", "neigh", "show"], [/* 25 vars */]) = 0
[pid 32718] arch_prctl(ARCH_SET_FS, 0x7f8149147740) = 0
[pid 32718] exit_group(0) = ?
[pid 32718] +++ exited with 0 +++
[pid 32673] wait4(32718, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32718
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32718, si_status=0, si_utime=0, si_stime=0} ---
[pid 32719] _exit(0) = ?
[pid 32719] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32721
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32722
Process 32722 attached
Process 32721 attached
[pid 32721] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32723
Process 32723 attached
[pid 32723] _exit(0) = ?
[pid 32723] +++ exited with 0 +++
[pid 32721] execve("/sbin/ip", ["ip", "-f", "inet", "route", "show"], [/* 25 vars */]) = 0
[pid 32721] arch_prctl(ARCH_SET_FS, 0x7fb5c49f8740) = 0
[pid 32721] exit_group(0) = ?
[pid 32721] +++ exited with 0 +++
[pid 32673] wait4(32721, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32721
[2014-03-19T23:26:46+00:00] DEBUG: default_interface set to eth0
[2014-03-19T23:26:46+00:00] DEBUG: default_gateway set to 10.0.2.2
[pid 32722] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32721, si_status=0, si_utime=0, si_stime=0} ---
[pid 32722] _exit(0) = ?
[pid 32722] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32724
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32725
Process 32725 attached
Process 32724 attached
[pid 32724] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32726
Process 32726 attached
[pid 32726] _exit(0) = ?
[pid 32726] +++ exited with 0 +++
[pid 32724] execve("/sbin/ip", ["ip", "-f", "inet6", "neigh", "show"], [/* 25 vars */]) = 0
[pid 32724] arch_prctl(ARCH_SET_FS, 0x7f49e4fb9740) = 0
[pid 32724] exit_group(0) = ?
[pid 32724] +++ exited with 0 +++
[pid 32673] wait4(32724, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32724
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32724, si_status=0, si_utime=0, si_stime=0} ---
[pid 32725] _exit(0) = ?
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32727
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32728
[pid 32725] +++ exited with 0 +++
Process 32727 attached
Process 32728 attached
[pid 32727] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32729
Process 32729 attached
[pid 32729] _exit(0) = ?
[pid 32729] +++ exited with 0 +++
[pid 32727] execve("/sbin/ip", ["ip", "-f", "inet6", "route", "show"], [/* 25 vars */]) = 0
[pid 32727] arch_prctl(ARCH_SET_FS, 0x7f154ba8f740) = 0
[pid 32727] exit_group(0) = ?
[pid 32727] +++ exited with 0 +++
[pid 32673] wait4(32727, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32727
[2014-03-19T23:26:47+00:00] DEBUG: Unable to determine default inet6 interface
[2014-03-19T23:26:47+00:00] DEBUG: [inet] Using default interface eth0 and default gateway 10.0.2.2 to set the default ip to 10.0.2.15
[pid 32728] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32727, si_status=0, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:47+00:00] DEBUG: [inet6] no default interface, picking the first ipaddress
[2014-03-19T23:26:47+00:00] DEBUG: has_euca_mac? == false
[2014-03-19T23:26:47+00:00] DEBUG: has_euca_mac? == false
[2014-03-19T23:26:47+00:00] DEBUG: has_euca_mac? == false
[2014-03-19T23:26:47+00:00] DEBUG: has_euca_mac? == false
[2014-03-19T23:26:47+00:00] DEBUG: has_euca_mac? == false
[2014-03-19T23:26:47+00:00] DEBUG: has_euca_mac? == false
[2014-03-19T23:26:47+00:00] DEBUG: looks_like_euca? == false
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin c
[pid 32728] _exit(0) = ?
[pid 32728] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32730
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32731
Process 32731 attached
Process 32730 attached
[pid 32730] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32732
Process 32732 attached
[pid 32732] _exit(0) = ?
[pid 32732] +++ exited with 0 +++
[pid 32730] execve("/usr/bin/gcc", ["gcc", "-v"], [/* 25 vars */]) = 0
[pid 32730] arch_prctl(ARCH_SET_FS, 0x7fc2ce281740) = 0
[pid 32730] exit_group(0) = ?
[pid 32730] +++ exited with 0 +++
[pid 32673] wait4(32730, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32730
[2014-03-19T23:26:47+00:00] DEBUG: ---- Begin gcc -v STDOUT ----
[2014-03-19T23:26:47+00:00] DEBUG:
[2014-03-19T23:26:47+00:00] DEBUG: ---- End gcc -v STDOUT ----
[2014-03-19T23:26:47+00:00] DEBUG: ---- Begin gcc -v STDERR ----
[2014-03-19T23:26:47+00:00] DEBUG: Using built-in specs.
COLLECT_GCC=gcc
COLLECT_LTO_WRAPPER=/usr/lib/gcc/x86_64-linux-gnu/4.8/lto-wrapper
Target: x86_64-linux-gnu
Configured with: ../src/configure -v --with-pkgversion='Ubuntu 4.8.2-16ubuntu1' --with-bugurl=file:///usr/share/doc/gcc-4.8/README.Bugs --enable-languages=c,c++,java,go,d,fortran,objc,obj-c++ --prefix=/usr --program-suffix=-4.8 --enable-shared --enable-linker-build-id --libexecdir=/usr/lib --without-included-gettext --enable-threads=posix --with-gxx-include-dir=/usr/include/c++/4.8 --libdir=/usr/lib --enable-nls --with-sysroot=/ --enable-clocale=gnu --enable-libstdcxx-debug --enable-libstdcxx-time=yes --enable-gnu-unique-object --disable-libmudflap --enable-plugin --with-system-zlib --disable-browser-plugin --enable-java-awt=gtk --enable-gtk-cairo --with-java-home=/usr/lib/jvm/java-1.5.0-gcj-4.8-amd64/jre --enable-java-home --with-jvm-root-dir=/usr/lib/jvm/java-1.5.0-gcj-4.8-amd64 --with-jvm-jar-dir=/usr/lib/jvm-exports/java-1.5.0-gcj-4.8-amd64 --with-arch-directory=amd64 --with-ecj-jar=/usr/share/java/eclipse-ecj.jar --enable-objc-gc --enable-multiarch --disable-werror --with-arch-32=i686 --with-abi=m64 --with-multilib-list=m32,m64,mx32 --with-tune=generic --enable-checking=release --build=x86_64-linux-gnu --host=x86_64-linux-gnu --target=x86_64-linux-gnu
Thread model: posix
gcc version 4.8.2 (Ubuntu 4.8.2-16ubuntu1)
[2014-03-19T23:26:47+00:00] DEBUG: ---- End gcc -v STDERR ----
[2014-03-19T23:26:47+00:00] DEBUG: Ran (gcc -v) returned 0
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32730, si_status=0, si_utime=0, si_stime=0} ---
[pid 32731] _exit(0) = ?
[pid 32731] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32733
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32734
Process 32734 attached
Process 32733 attached
[pid 32733] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32735
Process 32735 attached
[pid 32735] _exit(0) = ?
[pid 32735] +++ exited with 0 +++
[pid 32733] execve("/lib/libc.so.6", ["/lib/libc.so.6"], [/* 25 vars */]) = -1 ENOENT (No such file or directory)
[pid 32733] clone(Process 32736 attached
child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32736
[pid 32733] exit_group(1) = ?
[pid 32736] +++ exited with 1 +++
[pid 32733] +++ exited with 1 +++
[pid 32673] wait4(32733, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 32733
[2014-03-19T23:26:47+00:00] DEBUG: Plugin c threw exception #<Ohai::Exceptions::Exec: command /lib/libc.so.6 doesn't exist or is not in the PATH> /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:346:in `rescue in popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:350:in `popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:98:in `run_command_unix'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:50:in `block in run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `chdir'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/c.rb:41:in `block in from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/c.rb:40:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/c.rb:40:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `instance_eval'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:216:in `block in require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:140:in `block (2 levels) in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `block in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `all_plugins'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:279:in `run_ohai'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:399:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin command
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin gce
[pid 32734] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32733, si_status=1, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:47+00:00] DEBUG: No gce at /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/gce.rb
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin php
[pid 32734] _exit(0) = ?
[pid 32734] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32737
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32738
Process 32738 attached
Process 32737 attached
[pid 32737] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32739
Process 32739 attached
[pid 32737] exit_group(1) = ?
[pid 32739] +++ exited with 1 +++
[pid 32737] +++ exited with 1 +++
[pid 32673] wait4(32737, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 32737
[2014-03-19T23:26:47+00:00] DEBUG: Plugin php threw exception #<Ohai::Exceptions::Exec: command php -v doesn't exist or is not in the PATH> /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:346:in `rescue in popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:350:in `popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:98:in `run_command_unix'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:50:in `block in run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `chdir'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/php.rb:27:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `instance_eval'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:216:in `block in require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:140:in `block (2 levels) in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `block in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `all_plugins'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:279:in `run_ohai'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:399:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin perl
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32737, si_status=1, si_utime=0, si_stime=0} ---
[pid 32738] _exit(0) = ?
[pid 32738] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32740
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32741
Process 32741 attached
Process 32740 attached
[pid 32740] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32742
Process 32742 attached
[pid 32742] _exit(0) = ?
[pid 32742] +++ exited with 0 +++
[pid 32740] execve("/usr/bin/perl", ["perl", "-V:version", "-V:archname"], [/* 25 vars */]) = 0
[pid 32740] arch_prctl(ARCH_SET_FS, 0x7f8b2d915740) = 0
[pid 32673] wait4(32740, 0x7fff11690cac, WNOHANG, NULL) = 0
[pid 32673] wait4(32740, 0x7fff11690cac, WNOHANG, NULL) = 0
[pid 32740] exit_group(0) = ?
[pid 32673] wait4(32740, 0x7fff11690cac, WNOHANG, NULL) = 0
[pid 32673] wait4(32740, 0x7fff11690cac, WNOHANG, NULL) = 0
[pid 32673] wait4(32740, <unfinished ...>
[pid 32740] +++ exited with 0 +++
[pid 32741] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32740, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32740
[2014-03-19T23:26:47+00:00] DEBUG: ---- Begin perl -V:version -V:archname STDOUT ----
[2014-03-19T23:26:47+00:00] DEBUG: version='5.18.2';
archname='x86_64-linux-gnu-thread-multi';
[2014-03-19T23:26:47+00:00] DEBUG: ---- End perl -V:version -V:archname STDOUT ----
[2014-03-19T23:26:47+00:00] DEBUG: ---- Begin perl -V:version -V:archname STDERR ----
[2014-03-19T23:26:47+00:00] DEBUG:
[2014-03-19T23:26:47+00:00] DEBUG: ---- End perl -V:version -V:archname STDERR ----
[2014-03-19T23:26:47+00:00] DEBUG: Ran (perl -V:version -V:archname) returned 0
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin dmi
[pid 32741] _exit(0) = ?
[pid 32741] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32743
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32744
Process 32743 attached
[pid 32743] clone(Process 32744 attached
child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32745
Process 32745 attached
[pid 32745] _exit(0) = ?
[pid 32745] +++ exited with 0 +++
[pid 32743] execve("/usr/sbin/dmidecode", ["dmidecode"], [/* 25 vars */]) = 0
[pid 32743] arch_prctl(ARCH_SET_FS, 0x7fed13566740) = 0
[pid 32743] exit_group(0) = ?
[pid 32743] +++ exited with 0 +++
[pid 32673] wait4(32743, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32743
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin rackspace
[pid 32744] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32743, si_status=0, si_utime=0, si_stime=0} ---
[pid 32744] _exit(0) = ?
[pid 32744] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32746
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32747
Process 32747 attached
Process 32746 attached
[pid 32746] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32748
Process 32748 attached
[pid 32746] exit_group(1) = ?
[pid 32748] +++ exited with 1 +++
[pid 32746] +++ exited with 1 +++
[pid 32673] wait4(32746, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 32746
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin root_group
[pid 32747] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32746, si_status=1, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin erlang
[pid 32747] _exit(0) = ?
[pid 32747] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32749
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32750
Process 32750 attached
Process 32749 attached
[pid 32749] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32751
Process 32751 attached
[pid 32749] exit_group(1) = ?
[pid 32751] +++ exited with 1 +++
[pid 32749] +++ exited with 1 +++
[pid 32673] wait4(32749, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 32749
[2014-03-19T23:26:47+00:00] DEBUG: Plugin erlang threw exception #<Ohai::Exceptions::Exec: command erl +V doesn't exist or is not in the PATH> /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:346:in `rescue in popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:350:in `popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:98:in `run_command_unix'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:50:in `block in run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `chdir'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/erlang.rb:26:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `instance_eval'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:216:in `block in require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:140:in `block (2 levels) in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `block in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `all_plugins'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:279:in `run_ohai'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:399:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin ip_scopes
[pid 32750] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32749, si_status=1, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:47+00:00] DEBUG: ip_scopes: cannot load gem, plugin disabled: cannot load such file -- ipaddr_extensions
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin virtualization
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::virtualization
[pid 32750] _exit(0) = ?
[pid 32750] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32752
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32753
Process 32753 attached
Process 32752 attached
[pid 32752] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32754
Process 32754 attached
[pid 32754] _exit(0) = ?
[pid 32754] +++ exited with 0 +++
[pid 32752] execve("/usr/sbin/dmidecode", ["dmidecode"], [/* 25 vars */]) = 0
[pid 32752] arch_prctl(ARCH_SET_FS, 0x7fe2ebeda740) = 0
[pid 32752] exit_group(0) = ?
[pid 32752] +++ exited with 0 +++
[pid 32673] wait4(32752, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 32752
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin keys
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin nodejs
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32752, si_status=0, si_utime=0, si_stime=0} ---
[pid 32753] _exit(0) = ?
[pid 32753] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32755
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32756
Process 32756 attached
Process 32755 attached
[pid 32755] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32757
Process 32757 attached
[pid 32755] exit_group(1) = ?
[pid 32757] +++ exited with 1 +++
[pid 32755] +++ exited with 1 +++
[pid 32673] wait4(32755, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 32755
[2014-03-19T23:26:47+00:00] DEBUG: Plugin nodejs threw exception #<Ohai::Exceptions::Exec: command node -v doesn't exist or is not in the PATH> /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:346:in `rescue in popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:350:in `popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:98:in `run_command_unix'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:50:in `block in run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `chdir'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/nodejs.rb:27:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `instance_eval'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:216:in `block in require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:140:in `block (2 levels) in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `block in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `all_plugins'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:279:in `run_ohai'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:399:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin lua
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32755, si_status=1, si_utime=0, si_stime=0} ---
[pid 32756] _exit(0) = ?
[pid 32756] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32758
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32759
Process 32759 attached
Process 32758 attached
[pid 32758] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32760
Process 32760 attached
[pid 32758] exit_group(1) = ?
[pid 32760] +++ exited with 1 +++
[pid 32758] +++ exited with 1 +++
[pid 32673] wait4(32758, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 32758
[2014-03-19T23:26:47+00:00] DEBUG: Plugin lua threw exception #<Ohai::Exceptions::Exec: command lua -v doesn't exist or is not in the PATH> /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:346:in `rescue in popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:350:in `popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:98:in `run_command_unix'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:50:in `block in run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `chdir'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/lua.rb:27:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `instance_eval'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:216:in `block in require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:140:in `block (2 levels) in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `block in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `all_plugins'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:279:in `run_ohai'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:399:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin ssh_host_key
[pid 32759] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32758, si_status=1, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:47+00:00] DEBUG: No ssh_host_key at /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/ssh_host_key.rb
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin network_listeners
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin ohai_time
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin ec2
[2014-03-19T23:26:47+00:00] DEBUG: has_ec2_mac? == false
[2014-03-19T23:26:47+00:00] DEBUG: looks_like_ec2? == false
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin platform
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::platform
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::lsb
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin passwd
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin groovy
[pid 32759] _exit(0) = ?
[pid 32759] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32761
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32762
Process 32762 attached
Process 32761 attached
[pid 32761] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32763
Process 32763 attached
[pid 32761] exit_group(1) = ?
[pid 32763] +++ exited with 1 +++
[pid 32761] +++ exited with 1 +++
[pid 32673] wait4(32761, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 32761
[2014-03-19T23:26:47+00:00] DEBUG: Plugin groovy threw exception #<Ohai::Exceptions::Exec: command groovy -v doesn't exist or is not in the PATH> /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:346:in `rescue in popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:350:in `popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:98:in `run_command_unix'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:50:in `block in run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `chdir'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/groovy.rb:27:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `instance_eval'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:216:in `block in require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:140:in `block (2 levels) in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `block in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `all_plugins'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:279:in `run_ohai'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:399:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin uptime
[pid 32762] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32761, si_status=1, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin azure
[2014-03-19T23:26:47+00:00] DEBUG: No hints present for azure.
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin python
[pid 32762] _exit(0) = ?
[pid 32762] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 32764
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32765
Process 32765 attached
Process 32764 attached
[pid 32764] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 32766
Process 32766 attached
[pid 32766] _exit(0) = ?
[pid 32766] +++ exited with 0 +++
[pid 32764] execve("/bin/sh", ["sh", "-c", "python -c \"import sys; print sys"...], [/* 25 vars */]) = 0
[pid 32764] arch_prctl(ARCH_SET_FS, 0x7fe2c2979740) = 0
[pid 32764] clone(Process 32767 attached
<unfinished ...>
[pid 32767] execve("/usr/bin/python", ["python", "-c", "import sys; print sys.version"], [/* 25 vars */]) = 0
[pid 32767] arch_prctl(ARCH_SET_FS, 0x7f7230ad2740) = 0
[pid 32764] <... clone resumed> child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fe2c2979a10) = 32767
[pid 32764] wait4(-1, <unfinished ...>
[pid 32673] wait4(32764, 0x7fff11690cac, WNOHANG, NULL) = 0
[pid 32673] wait4(32764, 0x7fff11690cac, WNOHANG, NULL) = 0
[pid 32767] exit_group(0) = ?
[pid 32767] +++ exited with 0 +++
[pid 32764] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32767
[pid 32764] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32767, si_status=0, si_utime=0, si_stime=0} ---
[pid 32764] exit_group(0) = ?
[pid 32673] wait4(32764, 0x7fff11690cac, WNOHANG, NULL) = 0
[pid 32673] wait4(32764, 0x7fff11690cac, WNOHANG, NULL) = 0
[pid 32673] wait4(32764, <unfinished ...>
[pid 32764] +++ exited with 0 +++
[pid 32765] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=32764, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] <... wait4 resumed> [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 32764
[2014-03-19T23:26:47+00:00] DEBUG: ---- Begin python -c "import sys; print sys.version" STDOUT ----
[2014-03-19T23:26:47+00:00] DEBUG: 2.7.6 (default, Jan 12 2014, 08:16:39)
[GCC 4.8.2]
[2014-03-19T23:26:47+00:00] DEBUG: ---- End python -c "import sys; print sys.version" STDOUT ----
[2014-03-19T23:26:47+00:00] DEBUG: ---- Begin python -c "import sys; print sys.version" STDERR ----
[2014-03-19T23:26:47+00:00] DEBUG:
[2014-03-19T23:26:47+00:00] DEBUG: ---- End python -c "import sys; print sys.version" STDERR ----
[2014-03-19T23:26:47+00:00] DEBUG: Ran (python -c "import sys; print sys.version") returned 0
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin dmi_common
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin chef
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin java
[pid 32765] _exit(0) = ?
[pid 32765] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 300
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 301
Process 301 attached
Process 300 attached
[pid 300] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 304
Process 304 attached
[pid 300] exit_group(1) = ?
[pid 304] +++ exited with 1 +++
[pid 300] +++ exited with 1 +++
[pid 32673] wait4(300, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 300
[2014-03-19T23:26:47+00:00] DEBUG: Plugin java threw exception #<Ohai::Exceptions::Exec: command java -version doesn't exist or is not in the PATH> /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:346:in `rescue in popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:350:in `popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:98:in `run_command_unix'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:50:in `block in run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `chdir'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/java.rb:30:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `instance_eval'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:216:in `block in require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:140:in `block (2 levels) in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `block in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `all_plugins'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:279:in `run_ohai'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:399:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin mono
[pid 301] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=300, si_status=1, si_utime=0, si_stime=0} ---
[pid 301] _exit(0) = ?
[pid 301] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 305
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 306
Process 306 attached
Process 305 attached
[pid 305] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 308
Process 308 attached
[pid 305] exit_group(1) = ?
[pid 308] +++ exited with 1 +++
[pid 305] +++ exited with 1 +++
[pid 32673] wait4(305, [{WIFEXITED(s) && WEXITSTATUS(s) == 1}], 0, NULL) = 305
[2014-03-19T23:26:47+00:00] DEBUG: Plugin mono threw exception #<Ohai::Exceptions::Exec: command mono -V doesn't exist or is not in the PATH> /var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:346:in `rescue in popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:350:in `popen4'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:98:in `run_command_unix'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:50:in `block in run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `chdir'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/command.rb:49:in `run_command'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/plugins/mono.rb:27:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `instance_eval'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/mixin/from_file.rb:29:in `from_file'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:216:in `block in require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:211:in `require_plugin'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:140:in `block (2 levels) in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:133:in `block in all_plugins'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `each'
/var/lib/gems/1.9.1/gems/ohai-6.20.0/lib/ohai/system.rb:131:in `all_plugins'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:279:in `run_ohai'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:399:in `do_run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:200:in `block in run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `fork'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/client.rb:194:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:215:in `run_chef_client'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:314:in `block in run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `loop'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application/client.rb:306:in `run_application'
/var/lib/gems/1.9.1/gems/chef-11.10.4/lib/chef/application.rb:66:in `run'
/var/lib/gems/1.9.1/gems/chef-11.10.4/bin/chef-client:26:in `<top (required)>'
/usr/local/bin/chef-client:23:in `load'
/usr/local/bin/chef-client:23:in `<main>'
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linode
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin openstack
[pid 306] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=305, si_status=1, si_utime=0, si_stime=0} ---
[2014-03-19T23:26:47+00:00] DEBUG: NOT ohai openstack
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin cloud
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::ps
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::block_device
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::kernel
[pid 306] _exit(0) = ?
[pid 306] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 309
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 310
Process 310 attached
Process 309 attached
[pid 309] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 311
Process 311 attached
[pid 311] _exit(0) = ?
[pid 311] +++ exited with 0 +++
[pid 309] execve("/bin/uname", ["uname", "-o"], [/* 25 vars */]) = 0
[pid 309] arch_prctl(ARCH_SET_FS, 0x7f0733381740) = 0
[pid 309] exit_group(0) = ?
[pid 309] +++ exited with 0 +++
[pid 32673] wait4(309, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 309
[2014-03-19T23:26:47+00:00] DEBUG: ---- Begin uname -o STDOUT ----
[2014-03-19T23:26:47+00:00] DEBUG: GNU/Linux
[2014-03-19T23:26:47+00:00] DEBUG: ---- End uname -o STDOUT ----
[2014-03-19T23:26:47+00:00] DEBUG: ---- Begin uname -o STDERR ----
[2014-03-19T23:26:47+00:00] DEBUG:
[2014-03-19T23:26:47+00:00] DEBUG: ---- End uname -o STDERR ----
[2014-03-19T23:26:47+00:00] DEBUG: Ran (uname -o) returned 0
[pid 32673] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=309, si_status=0, si_utime=0, si_stime=0} ---
[pid 310] _exit(0) = ?
[pid 310] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 312
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 313
Process 312 attached
[pid 312] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 314
Process 314 attached
[pid 314] _exit(0) = ?
[pid 314] +++ exited with 0 +++
[pid 312] execve("/usr/bin/env", ["env", "lsmod"], [/* 25 vars */]) = 0
[pid 312] arch_prctl(ARCH_SET_FS, 0x7f5849997740) = 0
[pid 312] execve("/usr/local/sbin/lsmod", ["lsmod"], [/* 25 vars */]) = -1 ENOENT (No such file or directory)
[pid 312] execve("/usr/local/bin/lsmod", ["lsmod"], [/* 25 vars */]) = -1 ENOENT (No such file or directory)
[pid 312] execve("/usr/sbin/lsmod", ["lsmod"], [/* 25 vars */]) = -1 ENOENT (No such file or directory)
[pid 312] execve("/usr/bin/lsmod", ["lsmod"], [/* 25 vars */]) = -1 ENOENT (No such file or directory)
[pid 312] execve("/sbin/lsmod", ["lsmod"], [/* 25 vars */]) = 0
[pid 312] arch_prctl(ARCH_SET_FS, 0x7fcfceee0740) = 0
Process 313 attached
[pid 312] exit_group(0) = ?
[pid 312] +++ exited with 0 +++
[pid 313] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=312, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] wait4(312, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 312
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::cpu
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::uptime
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::filesystem
[pid 313] _exit(0) = ?
[pid 313] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 315
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 316
Process 315 attached
Process 316 attached
[pid 315] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 317
Process 317 attached
[pid 317] _exit(0) = ?
[pid 317] +++ exited with 0 +++
[pid 315] execve("/bin/df", ["df", "-P"], [/* 25 vars */]) = 0
[pid 315] arch_prctl(ARCH_SET_FS, 0x7f8171ca1740) = 0
[pid 315] exit_group(0) = ?
[pid 315] +++ exited with 0 +++
[pid 32673] wait4(315, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 315
[pid 316] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=315, si_status=0, si_utime=0, si_stime=0} ---
[pid 316] _exit(0) = ?
[pid 316] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 318
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 319
Process 318 attached
[pid 318] clone(Process 319 attached
child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 320
Process 320 attached
[pid 320] _exit(0) = ?
[pid 320] +++ exited with 0 +++
[pid 318] execve("/bin/mount", ["mount"], [/* 25 vars */]) = 0
[pid 318] arch_prctl(ARCH_SET_FS, 0x7f32a338b880) = 0
[pid 318] exit_group(0) = ?
[pid 318] +++ exited with 0 +++
[pid 32673] wait4(318, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 318
[pid 319] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=318, si_status=0, si_utime=0, si_stime=0} ---
[pid 319] _exit(0) = ?
[pid 319] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 321
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 322
Process 322 attached
Process 321 attached
[pid 321] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 323
Process 323 attached
[pid 323] _exit(0) = ?
[pid 323] +++ exited with 0 +++
[pid 321] execve("/sbin/blkid", ["blkid", "-s", "TYPE"], [/* 25 vars */]) = 0
[pid 321] arch_prctl(ARCH_SET_FS, 0x7f6f6702c780) = 0
[pid 321] exit_group(0) = ?
[pid 321] +++ exited with 0 +++
[pid 322] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=321, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] wait4(321, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 321
[pid 322] _exit(0) = ?
[pid 322] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 324
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 325
Process 325 attached
Process 324 attached
[pid 324] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 326
Process 326 attached
[pid 326] _exit(0) = ?
[pid 326] +++ exited with 0 +++
[pid 324] execve("/sbin/blkid", ["blkid", "-s", "UUID"], [/* 25 vars */]) = 0
[pid 324] arch_prctl(ARCH_SET_FS, 0x7f96aacfd780) = 0
[pid 324] exit_group(0) = ?
[pid 324] +++ exited with 0 +++
[pid 325] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=324, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] wait4(324, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 324
[pid 325] _exit(0) = ?
[pid 325] +++ exited with 0 +++
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 327
[pid 32673] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 328
Process 328 attached
Process 327 attached
[pid 327] clone(child_stack=0x7fc52682cfb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc52682d9d0, tls=0x7fc52682d700, child_tidptr=0x7fc52682d9d0) = 329
Process 329 attached
[pid 329] _exit(0) = ?
[pid 329] +++ exited with 0 +++
[pid 327] execve("/sbin/blkid", ["blkid", "-s", "LABEL"], [/* 25 vars */]) = 0
[pid 327] arch_prctl(ARCH_SET_FS, 0x7f4898f33780) = 0
[pid 327] exit_group(0) = ?
[pid 327] +++ exited with 0 +++
[pid 328] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=327, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] wait4(327, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], WNOHANG, NULL) = 327
[2014-03-19T23:26:47+00:00] DEBUG: Loading plugin linux::memory
[pid 32673] wait4(-1, 0x7fff1169206c, WNOHANG, NULL) = -1 ECHILD (No child processes)
[2014-03-19T23:26:47+00:00] DEBUG: Client key is unspecified - skipping registration
[2014-03-19T23:26:47+00:00] DEBUG: Building node object for test
[2014-03-19T23:26:47+00:00] DEBUG: Initiating GET to http://127.0.0.1:8889/nodes/test
[2014-03-19T23:26:47+00:00] DEBUG: ---- HTTP Request Header Data: ----
[2014-03-19T23:26:47+00:00] DEBUG: Accept: application/json
[2014-03-19T23:26:47+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3
[pid 32672] clone(child_stack=0x7fc5211b8fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc5211b99d0, tls=0x7fc5211b9700, child_tidptr=0x7fc5211b99d0) = 330
Process 330 attached
[2014-03-19T23:26:47+00:00] DEBUG: GET nodes/test
[2014-03-19T23:26:47+00:00] DEBUG: No chefignore file found at /mnt/host_src/chef-metal-lxc/test/cookbooks/chefignore no files will be ignored
[2014-03-19T23:26:47+00:00] DEBUG:
--- RESPONSE (200) ---
{
"name": "test",
"normal": {
"provisioner_options": {
"vagrant_config": " config.vm.synced_folder \"../../..\", \"/mnt/host_src\"\n",
"vagrant_options": {
"vm.box": "trusty64",
"vm.box_url": "https://cloud-images.ubuntu.com/vagrant/trusty/current/trusty-server-cloudimg-amd64-vagrant-disk1.box"
}
},
"provisioner_output": {
"provisioner_url": "vagrant_cluster:///Users/jkeiser/oc/code/opscode/chef-metal-lxc/test/machinetest",
"vm_name": "test",
"vm_file_path": "/Users/jkeiser/oc/code/opscode/chef-metal-lxc/test/machinetest/test.vm",
"forwarded_ports": {
"22": "2222"
}
}
},
"run_list": [
"recipe[lxctests::install_metal]",
"recipe[lxctests::simple]"
],
"json_class": "Chef::Node",
"chef_type": "node",
"chef_environment": "_default",
"override": {
},
"default": {
},
"automatic": {
}
}
--- END RESPONSE ---
[2014-03-19T23:26:47+00:00] DEBUG: ---- HTTP Status and Header Data: ----
[2014-03-19T23:26:47+00:00] DEBUG: HTTP 1.1 200 OK
[2014-03-19T23:26:47+00:00] DEBUG: content-type: application/json
[2014-03-19T23:26:47+00:00] DEBUG: server: chef-zero
[2014-03-19T23:26:47+00:00] DEBUG: connection: close
[2014-03-19T23:26:47+00:00] DEBUG: content-length: 916
[2014-03-19T23:26:47+00:00] DEBUG: ---- End HTTP Status/Header Data ----
[2014-03-19T23:26:47+00:00] DEBUG: Content-Length header = 916
[2014-03-19T23:26:47+00:00] DEBUG: Response body length = 916
[2014-03-19T23:26:47+00:00] DEBUG: Extracting run list from JSON attributes provided on command line
[2014-03-19T23:26:47+00:00] DEBUG: Applying attributes from json file
[2014-03-19T23:26:47+00:00] DEBUG: Platform is ubuntu version 14.04
[2014-03-19T23:26:47+00:00] WARN: Run List override has been provided.
[2014-03-19T23:26:47+00:00] WARN: Original Run List: [recipe[lxctests::install_metal], recipe[lxctests::simple]]
[2014-03-19T23:26:47+00:00] WARN: Overridden Run List: [recipe[lxctests::simple2]]
[2014-03-19T23:26:47+00:00] INFO: Run List is [recipe[lxctests::simple2]]
[2014-03-19T23:26:47+00:00] INFO: Run List expands to [lxctests::simple2]
[2014-03-19T23:26:47+00:00] INFO: Starting Chef Run for test
[2014-03-19T23:26:47+00:00] INFO: Running start handlers
[2014-03-19T23:26:47+00:00] INFO: Start handlers complete.
[2014-03-19T23:26:47+00:00] DEBUG: Initiating POST to http://127.0.0.1:8889/reports/nodes/test/runs
[2014-03-19T23:26:47+00:00] DEBUG: ---- HTTP Request Header Data: ----
[2014-03-19T23:26:47+00:00] DEBUG: X-Ops-Reporting-Protocol-Version: 0.1.0
[2014-03-19T23:26:47+00:00] DEBUG: Content-Type: application/json
[2014-03-19T23:26:47+00:00] DEBUG: Accept: application/json
[2014-03-19T23:26:47+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3
[2014-03-19T23:26:47+00:00] DEBUG: Content-Length: 107
[2014-03-19T23:26:47+00:00] DEBUG: POST reports/nodes/test/runs
--- POST BODY ---
{"action":"start","run_id":"e4a26f77-2867-497c-bce0-ab7ec059d1e1","start_time":"2014-03-19 23:26:47 +0000"}
--- END POST BODY ---
[2014-03-19T23:26:47+00:00] DEBUG:
--- RESPONSE (404) ---
{
"error": [
"Object not found: /reports/nodes/test/runs"
]
}
--- END RESPONSE ---
[2014-03-19T23:26:47+00:00] DEBUG: ---- HTTP Status and Header Data: ----
[2014-03-19T23:26:47+00:00] DEBUG: HTTP 1.1 404 Not Found
[2014-03-19T23:26:47+00:00] DEBUG: content-type: application/json
[2014-03-19T23:26:47+00:00] DEBUG: server: chef-zero
[2014-03-19T23:26:47+00:00] DEBUG: connection: close
[2014-03-19T23:26:47+00:00] DEBUG: content-length: 69
[2014-03-19T23:26:47+00:00] DEBUG: ---- End HTTP Status/Header Data ----
[2014-03-19T23:26:47+00:00] DEBUG: Content-Length header = 69
[2014-03-19T23:26:47+00:00] DEBUG: Response body length = 69
[2014-03-19T23:26:47+00:00] INFO: HTTP Request Returned 404 Not Found: Object not found: /reports/nodes/test/runs
[2014-03-19T23:26:47+00:00] DEBUG: Reporting error starting run. URL: reports/nodes/test/runs Received 404. Disabling reporting for run.
[2014-03-19T23:26:47+00:00] DEBUG: Synchronizing cookbooks
resolving cookbooks for run list: ["lxctests::simple2"]
[2014-03-19T23:26:47+00:00] DEBUG: Initiating POST to http://127.0.0.1:8889/environments/_default/cookbook_versions
[2014-03-19T23:26:47+00:00] DEBUG: ---- HTTP Request Header Data: ----
[2014-03-19T23:26:47+00:00] DEBUG: Content-Type: application/json
[2014-03-19T23:26:47+00:00] DEBUG: Accept: application/json
[2014-03-19T23:26:47+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3
[2014-03-19T23:26:47+00:00] DEBUG: Content-Length: 34
[2014-03-19T23:26:47+00:00] DEBUG: POST environments/_default/cookbook_versions
--- POST BODY ---
{"run_list":["lxctests::simple2"]}
--- END POST BODY ---
[2014-03-19T23:26:47+00:00] DEBUG:
--- RESPONSE (200) ---
{
"lxctests": {
"recipes": [
{
"name": "install_lxc.rb",
"path": "recipes/install_lxc.rb",
"checksum": "59ed2fa450bc4bf491d8318b935c16f2",
"specificity": "default",
"url": "http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/install_lxc.rb"
},
{
"name": "install_metal.rb",
"path": "recipes/install_metal.rb",
"checksum": "3cbb96d0de11293b5122d21ce6f02549",
"specificity": "default",
"url": "http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/install_metal.rb"
},
{
"name": "simple.rb",
"path": "recipes/simple.rb",
"checksum": "03a98bdbf5c7fa291f14d41cf13433de",
"specificity": "default",
"url": "http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/simple.rb"
},
{
"name": "simple2.rb",
"path": "recipes/simple2.rb",
"checksum": "ee7b2465b6781c6292e7fac3757cd480",
"specificity": "default",
"url": "http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/simple2.rb"
}
],
"definitions": [
],
"libraries": [
],
"attributes": [
],
"files": [
],
"templates": [
],
"resources": [
],
"providers": [
],
"root_files": [
],
"cookbook_name": "lxctests",
"metadata": {
"name": "lxctests",
"description": "A fabulous new cookbook",
"maintainer": "YOUR_COMPANY_NAME",
"maintainer_email": "YOUR_EMAIL",
"license": "none",
"platforms": {
},
"dependencies": {
},
"recommendations": {
},
"suggestions": {
},
"conflicting": {
},
"providing": {
"lxctests::install_lxc": ">= 0.0.0",
"lxctests::install_metal": ">= 0.0.0",
"lxctests::simple": ">= 0.0.0",
"lxctests::simple2": ">= 0.0.0"
},
"replacing": {
},
"groupings": {
},
"recipes": {
"lxctests::install_lxc": "",
"lxctests::install_metal": "",
"lxctests::simple": "",
"lxctests::simple2": ""
},
"version": "0.0.0"
},
"version": "0.0.0",
"name": "lxctests-0.0.0",
"frozen?": false,
"chef_type": "cookbook_version",
"json_class": "Chef::CookbookVersion"
}
}
--- END RESPONSE ---
[2014-03-19T23:26:47+00:00] DEBUG: ---- HTTP Status and Header Data: ----
[2014-03-19T23:26:47+00:00] DEBUG: HTTP 1.1 200 OK
[2014-03-19T23:26:47+00:00] DEBUG: content-type: application/json
[2014-03-19T23:26:47+00:00] DEBUG: server: chef-zero
[2014-03-19T23:26:47+00:00] DEBUG: connection: close
[2014-03-19T23:26:47+00:00] DEBUG: content-length: 2372
[2014-03-19T23:26:47+00:00] DEBUG: ---- End HTTP Status/Header Data ----
[2014-03-19T23:26:47+00:00] DEBUG: Content-Length header = 2372
[2014-03-19T23:26:47+00:00] DEBUG: Response body length = 2372
[2014-03-19T23:26:47+00:00] INFO: Loading cookbooks [lxctests]
[2014-03-19T23:26:47+00:00] DEBUG: Cookbooks detail: [#<Chef::CookbookVersion:0x00000002f1b830 @name="lxctests", @frozen=false, @attribute_filenames=[], @definition_filenames=[], @template_filenames=[], @file_filenames=[], @recipe_filenames=["install_lxc.rb", "install_metal.rb", "simple.rb", "simple2.rb"], @recipe_filenames_by_name={"install_lxc"=>"install_lxc.rb", "install_metal"=>"install_metal.rb", "simple"=>"simple.rb", "simple2"=>"simple2.rb"}, @library_filenames=[], @resource_filenames=[], @provider_filenames=[], @metadata_filenames=[], @root_dir=nil, @root_filenames=[], @status=:ready, @manifest={"recipes"=>[{"name"=>"install_lxc.rb", "path"=>"recipes/install_lxc.rb", "checksum"=>"59ed2fa450bc4bf491d8318b935c16f2", "specificity"=>"default", "url"=>"http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/install_lxc.rb"}, {"name"=>"install_metal.rb", "path"=>"recipes/install_metal.rb", "checksum"=>"3cbb96d0de11293b5122d21ce6f02549", "specificity"=>"default", "url"=>"http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/install_metal.rb"}, {"name"=>"simple.rb", "path"=>"recipes/simple.rb", "checksum"=>"03a98bdbf5c7fa291f14d41cf13433de", "specificity"=>"default", "url"=>"http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/simple.rb"}, {"name"=>"simple2.rb", "path"=>"recipes/simple2.rb", "checksum"=>"ee7b2465b6781c6292e7fac3757cd480", "specificity"=>"default", "url"=>"http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/simple2.rb"}], "definitions"=>[], "libraries"=>[], "attributes"=>[], "files"=>[], "templates"=>[], "resources"=>[], "providers"=>[], "root_files"=>[], "cookbook_name"=>"lxctests", "metadata"=>{"name"=>"lxctests", "description"=>"A fabulous new cookbook", "long_description"=>"", "maintainer"=>"YOUR_COMPANY_NAME", "maintainer_email"=>"YOUR_EMAIL", "license"=>"none", "platforms"=>{}, "dependencies"=>{}, "recommendations"=>{}, "suggestions"=>{}, "conflicting"=>{}, "providing"=>{"lxctests::install_lxc"=>">= 0.0.0", "lxctests::install_metal"=>">= 0.0.0", "lxctests::simple"=>">= 0.0.0", "lxctests::simple2"=>">= 0.0.0"}, "replacing"=>{}, "attributes"=>{}, "groupings"=>{}, "recipes"=>{"lxctests::install_lxc"=>"", "lxctests::install_metal"=>"", "lxctests::simple"=>"", "lxctests::simple2"=>""}, "version"=>"0.0.0"}, "version"=>"0.0.0", "name"=>"lxctests-0.0.0", "frozen?"=>false, "chef_type"=>"cookbook_version", "json_class"=>"Chef::CookbookVersion"}, @file_vendor=nil, @metadata=#<Chef::Cookbook::Metadata:0x00000002f1a570 @cookbook=nil, @name="lxctests", @long_description="", @maintainer="YOUR_COMPANY_NAME", @maintainer_email="YOUR_EMAIL", @license="none", @description="A fabulous new cookbook", @platforms={}, @dependencies={}, @recommendations={}, @suggestions={}, @conflicting={}, @providing={"lxctests::install_lxc"=>">= 0.0.0", "lxctests::install_metal"=>">= 0.0.0", "lxctests::simple"=>">= 0.0.0", "lxctests::simple2"=>">= 0.0.0"}, @replacing={}, @attributes={}, @groupings={}, @recipes={"lxctests::install_lxc"=>"", "lxctests::install_metal"=>"", "lxctests::simple"=>"", "lxctests::simple2"=>""}, @version="0.0.0">, @checksums={"59ed2fa450bc4bf491d8318b935c16f2"=>nil, "3cbb96d0de11293b5122d21ce6f02549"=>nil, "03a98bdbf5c7fa291f14d41cf13433de"=>nil, "ee7b2465b6781c6292e7fac3757cd480"=>nil}, @manifest_records_by_path={"recipes/install_lxc.rb"=>{"name"=>"install_lxc.rb", "path"=>"recipes/install_lxc.rb", "checksum"=>"59ed2fa450bc4bf491d8318b935c16f2", "specificity"=>"default", "url"=>"http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/install_lxc.rb"}, "recipes/install_metal.rb"=>{"name"=>"install_metal.rb", "path"=>"recipes/install_metal.rb", "checksum"=>"3cbb96d0de11293b5122d21ce6f02549", "specificity"=>"default", "url"=>"http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/install_metal.rb"}, "recipes/simple.rb"=>{"name"=>"simple.rb", "path"=>"recipes/simple.rb", "checksum"=>"03a98bdbf5c7fa291f14d41cf13433de", "specificity"=>"default", "url"=>"http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/simple.rb"}, "recipes/simple2.rb"=>{"name"=>"simple2.rb", "path"=>"recipes/simple2.rb", "checksum"=>"ee7b2465b6781c6292e7fac3757cd480", "specificity"=>"default", "url"=>"http://127.0.0.1:8889/file_store/repo/cookbooks/lxctests/recipes/simple2.rb"}}, @attribute_filenames_by_short_filename={}>]
Synchronizing Cookbooks:
[2014-03-19T23:26:47+00:00] DEBUG: Synchronizing cookbook lxctests
[2014-03-19T23:26:47+00:00] DEBUG: Not storing cookbooks/lxctests/recipes/install_lxc.rb, as the cache is up to date.
[2014-03-19T23:26:47+00:00] DEBUG: Not storing cookbooks/lxctests/recipes/install_metal.rb, as the cache is up to date.
[2014-03-19T23:26:47+00:00] DEBUG: Not storing cookbooks/lxctests/recipes/simple.rb, as the cache is up to date.
[2014-03-19T23:26:47+00:00] DEBUG: Not storing cookbooks/lxctests/recipes/simple2.rb, as the cache is up to date.
- lxctests
Compiling Cookbooks...
[2014-03-19T23:26:47+00:00] DEBUG: Cookbooks to compile: [:lxctests]
[2014-03-19T23:26:47+00:00] DEBUG: Loading Recipe lxctests::simple2 via include_recipe
[2014-03-19T23:26:47+00:00] DEBUG: Found recipe simple2 in cookbook lxctests
Converging 1 resources
[2014-03-19T23:26:48+00:00] DEBUG: Converging node test
Recipe: lxctests::simple2
* machine[simple] action create[2014-03-19T23:26:48+00:00] INFO: Processing machine[simple] action create (lxctests::simple2 line 6)
[2014-03-19T23:26:48+00:00] DEBUG: Platform ubuntu version 14.04 found
[2014-03-19T23:26:48+00:00] DEBUG: Initiating GET to http://127.0.0.1:8889/nodes/simple
[2014-03-19T23:26:48+00:00] DEBUG: ---- HTTP Request Header Data: ----
[2014-03-19T23:26:48+00:00] DEBUG: Accept: application/json
[2014-03-19T23:26:48+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3
[2014-03-19T23:26:48+00:00] DEBUG: GET nodes/simple
[2014-03-19T23:26:48+00:00] DEBUG:
--- RESPONSE (200) ---
{
"name": "simple",
"normal": {
"provisioner_options": {
"template": "ubuntu",
"template_options": [
"-r",
"precise"
]
},
"provisioner_output": {
"provisioner_url": "lxc:///var/lib/lxc",
"name": "simple"
}
},
"json_class": "Chef::Node",
"chef_type": "node",
"chef_environment": "_default",
"override": {
},
"default": {
},
"automatic": {
},
"run_list": [
]
}
--- END RESPONSE ---
[2014-03-19T23:26:48+00:00] DEBUG: ---- HTTP Status and Header Data: ----
[2014-03-19T23:26:48+00:00] DEBUG: HTTP 1.1 200 OK
[2014-03-19T23:26:48+00:00] DEBUG: content-type: application/json
[2014-03-19T23:26:48+00:00] DEBUG: server: chef-zero
[2014-03-19T23:26:48+00:00] DEBUG: connection: close
[2014-03-19T23:26:48+00:00] DEBUG: content-length: 449
[2014-03-19T23:26:48+00:00] DEBUG: ---- End HTTP Status/Header Data ----
[2014-03-19T23:26:48+00:00] DEBUG: Platform ubuntu version 14.04 found
[2014-03-19T23:26:48+00:00] DEBUG: Initiating GET to http://127.0.0.1:8889/clients/simple
[2014-03-19T23:26:48+00:00] DEBUG: ---- HTTP Request Header Data: ----
[2014-03-19T23:26:48+00:00] DEBUG: Accept: application/json
[2014-03-19T23:26:48+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3
[2014-03-19T23:26:48+00:00] DEBUG: GET clients/simple
[2014-03-19T23:26:48+00:00] DEBUG:
--- RESPONSE (200) ---
{
"name": "simple",
"public_key": "-----BEGIN PUBLIC KEY-----\nMIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAwLHyWc/lYVGBcIrA43Kt\nuMZtLHdA5Ke2+hUWltwxry1lWvIbNedVTcbil/F19n+UIbrKkn7E6fvmkhhYwRNv\n0878FRIdfrjfCO6mFEDU1g3wAMt3uekMpg+XuHEZy0u5I1vBvKFEwqb68aPdMeO9\nBtMEtXO4/PT1w8z/dUb5PUGD+lGZ9+YCqMtTSdoTUBZTMACa2FI+q0jzzIwSbL/I\n9lIMO0XcN4pxmzfjJv/j2wgh3/PWcaBFojLm3UVjd7V7gYEQj0EIjgfzWbbupZCi\ng2wZuvjC61+LxJFQbFcVtzXm3rNkW2eWd/dDx1jDFDVXFUF6+Z+MuM6QG2Vk5vWy\n7QIDAQAB\n-----END PUBLIC KEY-----\n",
"admin": false,
"validator": false,
"json_class": "Chef::ApiClient",
"chef_type": "client"
}
--- END RESPONSE ---
[2014-03-19T23:26:48+00:00] DEBUG: ---- HTTP Status and Header Data: ----
[2014-03-19T23:26:48+00:00] DEBUG: HTTP 1.1 200 OK
[2014-03-19T23:26:48+00:00] DEBUG: content-type: application/json
[2014-03-19T23:26:48+00:00] DEBUG: server: chef-zero
[2014-03-19T23:26:48+00:00] DEBUG: connection: close
[2014-03-19T23:26:48+00:00] DEBUG: content-length: 602
[2014-03-19T23:26:48+00:00] DEBUG: ---- End HTTP Status/Header Data ----
[2014-03-19T23:26:48+00:00] DEBUG: Platform ubuntu version 14.04 found
[2014-03-19T23:26:48+00:00] DEBUG: Initiating GET to http://127.0.0.1:8889/nodes/simple
[2014-03-19T23:26:48+00:00] DEBUG: ---- HTTP Request Header Data: ----
[2014-03-19T23:26:48+00:00] DEBUG: Accept: application/json
[2014-03-19T23:26:48+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3
[2014-03-19T23:26:48+00:00] DEBUG: GET nodes/simple
[2014-03-19T23:26:48+00:00] DEBUG:
--- RESPONSE (200) ---
{
"name": "simple",
"normal": {
"provisioner_options": {
"template": "ubuntu",
"template_options": [
"-r",
"precise"
]
},
"provisioner_output": {
"provisioner_url": "lxc:///var/lib/lxc",
"name": "simple"
}
},
"json_class": "Chef::Node",
"chef_type": "node",
"chef_environment": "_default",
"override": {
},
"default": {
},
"automatic": {
},
"run_list": [
]
}
--- END RESPONSE ---
[2014-03-19T23:26:48+00:00] DEBUG: ---- HTTP Status and Header Data: ----
[2014-03-19T23:26:48+00:00] DEBUG: HTTP 1.1 200 OK
[2014-03-19T23:26:48+00:00] DEBUG: content-type: application/json
[2014-03-19T23:26:48+00:00] DEBUG: server: chef-zero
[2014-03-19T23:26:48+00:00] DEBUG: connection: close
[2014-03-19T23:26:48+00:00] DEBUG: content-length: 449
[2014-03-19T23:26:48+00:00] DEBUG: ---- End HTTP Status/Header Data ----
[2014-03-19T23:26:48+00:00] DEBUG: Forwarding container port 8889 to local port 8889
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 331
Process 331 attached
[pid 331] clone(Process 332 attached
child_stack=0x7fff1168fe30, flags=CLONE_PARENT|SIGCHLD) = 332
[pid 331] exit_group(0) = ?
[pid 331] +++ exited with 0 +++
[pid 328] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=331, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] wait4(331, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 331
[pid 32673] clone(child_stack=0x7fc520d32fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc520d339d0, tls=0x7fc520d33700, child_tidptr=0x7fc520d339d0) = 333
[pid 32673] clone(child_stack=0x7fc520eb4fb0, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tidptr=0x7fc520eb59d0, tls=0x7fc520eb5700, child_tidptr=0x7fc520eb59d0) = 334
Finished forwarding
Container: listening to 127.0.0.1:8889
[2014-03-19T23:26:48+00:00] INFO: Executing ls -d /etc/chef/client.rb on simple
Container: main loop
Executing ls -d /etc/chef/client.rb on simpleSelecting [#<TCPServer:fd 20>, #<IO:fd 18>]
Process 334 attached
Process 333 attached
[pid 32673] clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fc526822a10) = 335
Process 335 attached
[pid 335] clone(Process 336 attached
child_stack=0x7fff1168fe30, flags=CLONE_PARENT|SIGCHLD) = 336
[pid 335] exit_group(0) = ?
[pid 335] +++ exited with 0 +++
[pid 328] --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=335, si_status=0, si_utime=0, si_stime=0} ---
[pid 32673] wait4(335, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 335
[pid 32673] wait4(336, <unfinished ...>
[pid 330] _exit(0) = ?
[pid 330] +++ exited with 0 +++