You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2013/10/29 02:05:48 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #1739
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1739/changes>
Changes:
[vinod] Fixed Makefile to properly refer to README.md.
------------------------------------------
[...truncated 493 lines...]
if test -d "mesos-0.16.0"; then find "mesos-0.16.0" -type d ! -perm -200 -exec chmod u+w {} ';' && rm -rf "mesos-0.16.0" || { sleep 5 && rm -rf "mesos-0.16.0"; }; else :; fi
test -d "mesos-0.16.0" || mkdir "mesos-0.16.0"
(cd 3rdparty && make top_distdir=../mesos-0.16.0 distdir=../mesos-0.16.0/3rdparty \
am__remove_distdir=: am__skip_length_check=: am__skip_mode_fix=: distdir)
make[1]: Entering directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/3rdparty'
(cd libprocess && make top_distdir=../../mesos-0.16.0 distdir=../../mesos-0.16.0/3rdparty/libprocess \
am__remove_distdir=: am__skip_length_check=: am__skip_mode_fix=: distdir)
make[2]: Entering directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/3rdparty/libprocess'
:
test -d "../../mesos-0.16.0/3rdparty/libprocess" || mkdir "../../mesos-0.16.0/3rdparty/libprocess"
(cd 3rdparty && make top_distdir=../../../mesos-0.16.0 distdir=../../../mesos-0.16.0/3rdparty/libprocess/3rdparty \
am__remove_distdir=: am__skip_length_check=: am__skip_mode_fix=: distdir)
make[3]: Entering directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/3rdparty/libprocess/3rdparty'
(cd stout && make top_distdir=../../../../mesos-0.16.0 distdir=../../../../mesos-0.16.0/3rdparty/libprocess/3rdparty/stout \
am__remove_distdir=: am__skip_length_check=: am__skip_mode_fix=: distdir)
make[4]: Entering directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/3rdparty/libprocess/3rdparty/stout'
:
test -d "../../../../mesos-0.16.0/3rdparty/libprocess/3rdparty/stout" || mkdir "../../../../mesos-0.16.0/3rdparty/libprocess/3rdparty/stout"
test -n ":" \
|| find "../../../../mesos-0.16.0/3rdparty/libprocess/3rdparty/stout" -type d ! -perm -755 \
-exec chmod u+rwx,go+rx {} \; -o \
! -type d ! -perm -444 -links 1 -exec chmod a+r {} \; -o \
! -type d ! -perm -400 -exec chmod a+r {} \; -o \
! -type d ! -perm -444 -exec /bin/bash /x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/3rdparty/libprocess/3rdparty/stout/install-sh -c -m a+r {} {} \; \
|| chmod -R a+r "../../../../mesos-0.16.0/3rdparty/libprocess/3rdparty/stout"
make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/3rdparty/libprocess/3rdparty/stout'
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/3rdparty/libprocess/3rdparty'
test -n ":" \
|| find "../../mesos-0.16.0/3rdparty/libprocess" -type d ! -perm -755 \
-exec chmod u+rwx,go+rx {} \; -o \
! -type d ! -perm -444 -links 1 -exec chmod a+r {} \; -o \
! -type d ! -perm -400 -exec chmod a+r {} \; -o \
! -type d ! -perm -444 -exec /bin/bash /x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/3rdparty/libprocess/install-sh -c -m a+r {} {} \; \
|| chmod -R a+r "../../mesos-0.16.0/3rdparty/libprocess"
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/3rdparty/libprocess'
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/3rdparty'
(cd src && make top_distdir=../mesos-0.16.0 distdir=../mesos-0.16.0/src \
am__remove_distdir=: am__skip_length_check=: am__skip_mode_fix=: distdir)
make[1]: Entering directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/src'
(cd ec2 && make top_distdir=../mesos-0.16.0 distdir=../mesos-0.16.0/ec2 \
am__remove_distdir=: am__skip_length_check=: am__skip_mode_fix=: distdir)
make[1]: Entering directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/ec2'
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/ec2'
test -n "" \
|| find "mesos-0.16.0" -type d ! -perm -755 \
-exec chmod u+rwx,go+rx {} \; -o \
! -type d ! -perm -444 -links 1 -exec chmod a+r {} \; -o \
! -type d ! -perm -400 -exec chmod a+r {} \; -o \
! -type d ! -perm -444 -exec /bin/bash /x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/install-sh -c -m a+r {} {} \; \
|| chmod -R a+r "mesos-0.16.0"
tardir=mesos-0.16.0 && ${TAR-tar} chof - "$tardir" | GZIP=--best gzip -c >mesos-0.16.0.tar.gz
if test -d "mesos-0.16.0"; then find "mesos-0.16.0" -type d ! -perm -200 -exec chmod u+w {} ';' && rm -rf "mesos-0.16.0" || { sleep 5 && rm -rf "mesos-0.16.0"; }; else :; fi
case 'mesos-0.16.0.tar.gz' in \
*.tar.gz*) \
GZIP=--best gzip -dc mesos-0.16.0.tar.gz | ${TAR-tar} xf - ;;\
*.tar.bz2*) \
bzip2 -dc mesos-0.16.0.tar.bz2 | ${TAR-tar} xf - ;;\
*.tar.lzma*) \
lzma -dc mesos-0.16.0.tar.lzma | ${TAR-tar} xf - ;;\
*.tar.lz*) \
lzip -dc mesos-0.16.0.tar.lz | ${TAR-tar} xf - ;;\
*.tar.xz*) \
xz -dc mesos-0.16.0.tar.xz | ${TAR-tar} xf - ;;\
*.tar.Z*) \
uncompress -c mesos-0.16.0.tar.Z | ${TAR-tar} xf - ;;\
*.shar.gz*) \
GZIP=--best gzip -dc mesos-0.16.0.shar.gz | unshar ;;\
*.zip*) \
unzip mesos-0.16.0.zip ;;\
esac
chmod -R a-w mesos-0.16.0; chmod a+w mesos-0.16.0
mkdir mesos-0.16.0/_build
mkdir mesos-0.16.0/_inst
chmod a-w mesos-0.16.0
test -d mesos-0.16.0/_build || exit 0; \
dc_install_base=`CDPATH="${ZSH_VERSION+.}:" && cd mesos-0.16.0/_inst && pwd | sed -e 's,^[^:\\/]:[\\/],/,'` \
&& dc_destdir="${TMPDIR-/tmp}/am-dc-$$/" \
&& am__cwd=`pwd` \
&& CDPATH="${ZSH_VERSION+.}:" && cd mesos-0.16.0/_build \
&& ../configure --srcdir=.. --prefix="$dc_install_base" \
\
\
&& make \
&& make dvi \
&& make check \
&& make install \
&& make installcheck \
&& make uninstall \
&& make distuninstallcheck_dir="$dc_install_base" \
distuninstallcheck \
&& chmod -R a-w "$dc_install_base" \
&& ({ \
(cd ../.. && umask 077 && mkdir "$dc_destdir") \
&& make DESTDIR="$dc_destdir" install \
&& make DESTDIR="$dc_destdir" uninstall \
&& make DESTDIR="$dc_destdir" \
distuninstallcheck_dir="$dc_destdir" distuninstallcheck; \
} || { rm -rf "$dc_destdir"; exit 1; }) \
&& rm -rf "$dc_destdir" \
&& make dist \
&& rm -rf mesos-0.16.0.tar.gz \
&& make distcleancheck \
&& cd "$am__cwd" \
|| exit 1
checking build system type... x86_64-unknown-linux-gnu
checking host system type... x86_64-unknown-linux-gnu
checking target system type... x86_64-unknown-linux-gnu
checking for a BSD-compatible install... /usr/bin/install -c
checking whether build environment is sane... yes
checking for a thread-safe mkdir -p... /bin/mkdir -p
checking for gawk... no
checking for mawk... mawk
checking whether make sets $(MAKE)... yes
checking for style of include used by make... GNU
checking for g++... g++
checking whether the C++ compiler works... yes
checking for C++ compiler default output file name... a.out
checking for suffix of executables...
checking whether we are cross compiling... no
checking for suffix of object files... o
checking whether we are using the GNU C++ compiler... yes
checking whether g++ accepts -g... yes
checking dependency style of g++... gcc3
checking for ar... ar
checking the archiver (ar) interface... ar
checking how to print strings... printf
checking for gcc... gcc
checking whether we are using the GNU C compiler... yes
checking whether gcc accepts -g... yes
checking for gcc option to accept ISO C89... none needed
checking dependency style of gcc... gcc3
checking for a sed that does not truncate output... /bin/sed
checking for grep that handles long lines and -e... /bin/grep
checking for egrep... /bin/grep -E
checking for fgrep... /bin/grep -F
checking for ld used by gcc... /usr/bin/ld
checking if the linker (/usr/bin/ld) is GNU ld... yes
checking for BSD- or MS-compatible name lister (nm)... /usr/bin/nm -B
checking the name lister (/usr/bin/nm -B) interface... BSD nm
checking whether ln -s works... yes
checking the maximum length of command line arguments... 3458764513820540925
checking whether the shell understands some XSI constructs... yes
checking whether the shell understands "+="... yes
checking how to convert x86_64-unknown-linux-gnu file names to x86_64-unknown-linux-gnu format... func_convert_file_noop
checking how to convert x86_64-unknown-linux-gnu file names to toolchain format... func_convert_file_noop
checking for /usr/bin/ld option to reload object files... -r
checking for objdump... objdump
checking how to recognize dependent libraries... pass_all
checking for dlltool... no
checking how to associate runtime and link libraries... printf %s\n
checking for archiver @FILE support... @
checking for strip... strip
checking for ranlib... ranlib
checking command to parse /usr/bin/nm -B output from gcc object... ok
checking for sysroot... no
checking for mt... mt
checking if mt is a manifest tool... no
checking how to run the C preprocessor... gcc -E
checking for ANSI C header files... yes
checking for sys/types.h... yes
checking for sys/stat.h... yes
checking for stdlib.h... yes
checking for string.h... yes
checking for memory.h... yes
checking for strings.h... yes
checking for inttypes.h... yes
checking for stdint.h... yes
checking for unistd.h... yes
checking for dlfcn.h... yes
checking for objdir... .libs
checking if gcc supports -fno-rtti -fno-exceptions... no
checking for gcc option to produce PIC... -fPIC -DPIC
checking if gcc PIC flag -fPIC -DPIC works... yes
checking if gcc static flag -static works... yes
checking if gcc supports -c -o file.o... yes
checking if gcc supports -c -o file.o... (cached) yes
checking whether the gcc linker (/usr/bin/ld -m elf_x86_64) supports shared libraries... yes
checking whether -lc should be explicitly linked in... no
checking dynamic linker characteristics... GNU/Linux ld.so
checking how to hardcode library paths into programs... immediate
checking whether stripping libraries is possible... yes
checking if libtool supports shared libraries... yes
checking whether to build shared libraries... yes
checking whether to build static libraries... yes
checking how to run the C++ preprocessor... g++ -E
checking for ld used by g++... /usr/bin/ld -m elf_x86_64
checking if the linker (/usr/bin/ld -m elf_x86_64) is GNU ld... yes
checking whether the g++ linker (/usr/bin/ld -m elf_x86_64) supports shared libraries... yes
checking for g++ option to produce PIC... -fPIC -DPIC
checking if g++ PIC flag -fPIC -DPIC works... yes
checking if g++ static flag -static works... yes
checking if g++ supports -c -o file.o... yes
checking if g++ supports -c -o file.o... (cached) yes
checking whether the g++ linker (/usr/bin/ld -m elf_x86_64) supports shared libraries... yes
checking dynamic linker characteristics... (cached) GNU/Linux ld.so
checking how to hardcode library paths into programs... immediate
configure: creating ./config.lt
config.lt: creating libtool
===========================================================
Setting up build environment for x86_64 linux-gnu
===========================================================
checking whether we are using the GNU C++ compiler... (cached) yes
checking whether g++ accepts -g... (cached) yes
checking dependency style of g++... (cached) gcc3
checking whether we are using the GNU C compiler... (cached) yes
checking whether gcc accepts -g... (cached) yes
checking for gcc option to accept ISO C89... (cached) none needed
checking dependency style of gcc... (cached) gcc3
checking for the pthreads library -lpthreads... no
checking whether pthreads work without any flags... no
checking whether pthreads work with -Kthread... no
checking whether pthreads work with -kthread... no
checking for the pthreads library -llthread... no
checking whether pthreads work with -pthread... yes
checking for joinable pthread attribute... PTHREAD_CREATE_JOINABLE
checking if more special flags are required for pthreads... no
checking whether to check for GCC pthread/shared inconsistencies... yes
checking whether -pthread is sufficient with -shared... yes
checking for backtrace in -lunwind... no
checking whether or not we can build with JNI... yes
checking for a Python interpreter with version >= 2.6... python
checking for python... /usr/bin/python
checking for python version... 2.7
checking for python platform... linux2
checking for python script directory... ${prefix}/lib/python2.7/site-packages
checking for python extension module directory... ${exec_prefix}/lib/python2.7/site-packages
checking for python2.7... (cached) /usr/bin/python
checking for a version of Python >= '2.1.0'... yes
checking for a version of Python >= '2.6'... yes
checking for the distutils Python package... yes
checking for Python include path... -I/usr/include/python2.7
checking for Python library path... -L/usr/lib -lpython2.7
checking for Python site-packages path... /usr/lib/python2.7/dist-packages
checking python extra libraries... -lpthread -ldl -lutil
checking python extra linking flags... -Xlinker -export-dynamic -Wl,-O1 -Wl,-Bsymbolic-functions
checking consistency of all components of python development environment... yes
checking for gzread in -lz... yes
checking for BN_init in -lcrypto... yes
checking for SSL_accept in -lssl... yes
checking for curl_global_init in -lcurl... no
configure: error: cannot find libcurl
-------------------------------------------------------------------
You can avoid this with --without-curl, but it will mean executor
and task resources cannot be downloaded over http.
-------------------------------------------------------------------
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #1741
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1741/changes>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #1740
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/1740/changes>
Changes:
[dlester] Renames filenames of documentation to be lowercase and updates some documentation templates for consistency.
------------------------------------------
[...truncated 15503 lines...]
I1029 04:34:33.445878 15490 slave.cpp:1856] Status update manager successfully handled status update TASK_RUNNING (UUID: d20cafd8-2c47-4b00-9a9e-c0156afeee36) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:33.445894 15490 slave.cpp:1862] Sending acknowledgement for status update TASK_RUNNING (UUID: d20cafd8-2c47-4b00-9a9e-c0156afeee36) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000 to executor(1)@67.195.138.60:56740
I1029 04:34:33.446005 15493 master.cpp:1425] Status update TASK_RUNNING (UUID: d20cafd8-2c47-4b00-9a9e-c0156afeee36) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000 from slave(94)@67.195.138.60:34501
I1029 04:34:33.446101 15489 sched.cpp:527] Scheduler::statusUpdate took 26294ns
I1029 04:34:33.446226 17018 exec.cpp:331] Executor received status update acknowledgement d20cafd8-2c47-4b00-9a9e-c0156afeee36 for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:33.446290 17026 process.cpp:986] Socket closed while receiving
I1029 04:34:33.446297 15490 status_update_manager.cpp:380] Received status update acknowledgement (UUID: d20cafd8-2c47-4b00-9a9e-c0156afeee36) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:33.446321 15490 status_update_manager.hpp:337] Checkpointing ACK for status update TASK_RUNNING (UUID: d20cafd8-2c47-4b00-9a9e-c0156afeee36) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:33.490413 15490 slave.cpp:1343] Status update manager successfully handled status update acknowledgement (UUID: d20cafd8-2c47-4b00-9a9e-c0156afeee36) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:33.721442 15497 process.cpp:986] Socket closed while receiving
I1029 04:34:34.168241 15497 process.cpp:986] Socket closed while receiving
I1029 04:34:34.276727 15491 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 54625ns
I1029 04:34:34.299937 15490 monitor.cpp:187] Publishing resource usage for executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework '201310290434-1015726915-34501-15179-0000'
Command exited with status 0 (pid: 17027)
I1029 04:34:34.350019 17021 exec.cpp:505] Executor sending status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.350441 15496 slave.cpp:1737] Handling status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000 from executor(1)@67.195.138.60:56740
I1029 04:34:34.350486 15496 slave.cpp:3173] Terminating task acce0134-da31-4953-a32c-df058c9ece20
I1029 04:34:34.350675 15496 status_update_manager.cpp:305] Received status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.350695 15496 status_update_manager.hpp:337] Checkpointing UPDATE for status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.403280 15497 process.cpp:986] Socket closed while receiving
I1029 04:34:34.448339 15496 status_update_manager.cpp:356] Forwarding status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000 to master@67.195.138.60:34501
I1029 04:34:34.448765 15495 master.cpp:1425] Status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000 from slave(94)@67.195.138.60:34501
I1029 04:34:34.448879 15495 master.hpp:403] Removing task acce0134-da31-4953-a32c-df058c9ece20 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:34.449038 15495 slave.cpp:1856] Status update manager successfully handled status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.449055 15495 slave.cpp:1862] Sending acknowledgement for status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000 to executor(1)@67.195.138.60:56740
I1029 04:34:34.449065 15496 sched.cpp:527] Scheduler::statusUpdate took 12543ns
I1029 04:34:34.449477 17026 process.cpp:986] Socket closed while receiving
I1029 04:34:34.449569 17024 exec.cpp:331] Executor received status update acknowledgement c4f5385a-0e6d-403a-9660-08fd7bd9778e for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.449988 15495 status_update_manager.cpp:380] Received status update acknowledgement (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.450022 15495 status_update_manager.hpp:337] Checkpointing ACK for status update TASK_FINISHED (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.450268 15489 hierarchical_allocator_process.hpp:637] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 201310290434-1015726915-34501-15179-0 from framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.498437 15495 status_update_manager.cpp:507] Cleaning up status update stream for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.498764 15495 slave.cpp:1343] Status update manager successfully handled status update acknowledgement (UUID: c4f5385a-0e6d-403a-9660-08fd7bd9778e) for task acce0134-da31-4953-a32c-df058c9ece20 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:34.498783 15495 slave.cpp:3196] Completing task acce0134-da31-4953-a32c-df058c9ece20
I1029 04:34:35.277515 15492 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 to framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:35.277709 15492 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 269342ns
I1029 04:34:35.277796 15492 master.hpp:413] Adding offer 201310290434-1015726915-34501-15179-1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:35.277851 15492 master.cpp:1668] Sending 1 offers to framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:35.277987 15492 sched.cpp:472] Scheduler::resourceOffers took 10964ns
I1029 04:34:35.313392 15491 monitor.cpp:187] Publishing resource usage for executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework '201310290434-1015726915-34501-15179-0000'
I1029 04:34:35.350929 15497 process.cpp:986] Socket closed while receiving
I1029 04:34:36.277330 15490 process_isolator.cpp:479] Telling slave of terminated executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.277477 15491 slave.cpp:2122] Executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework 201310290434-1015726915-34501-15179-0000 has exited with status 0
I1029 04:34:36.277828 15491 slave.cpp:2257] Cleaning up executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.278204 15495 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20/runs/d198a374-c2be-4983-ad6e-67c02f1e9333' for gc 6.99999678226667days in the future
I1029 04:34:36.278331 15495 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 34415ns
I1029 04:34:36.278640 15495 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20' for gc 6.99999678125037days in the future
I1029 04:34:36.278702 15495 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20/runs/d198a374-c2be-4983-ad6e-67c02f1e9333' for gc 6.99999677504593days in the future
I1029 04:34:36.278714 15491 slave.cpp:2328] Cleaning up framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.278769 15495 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20' for gc 6.9999967745363days in the future
I1029 04:34:36.278825 15495 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000' for gc 6.99999677376days in the future
I1029 04:34:36.278878 15495 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000' for gc 6.99999677328593days in the future
I1029 04:34:36.279021 15495 status_update_manager.cpp:267] Closing status update streams for framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.279225 15491 slave.cpp:388] Slave terminating
W1029 04:34:36.290081 15490 process_isolator.cpp:265] Failed to kill the process tree rooted at pid 16985: Failed to find process 16985
I1029 04:34:36.290217 15491 master.cpp:615] Slave 201310290434-1015726915-34501-15179-0 (janus.apache.org) disconnected
I1029 04:34:36.290344 15491 master.hpp:423] Removing offer 201310290434-1015726915-34501-15179-1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:36.290429 15489 hierarchical_allocator_process.hpp:484] Slave 201310290434-1015726915-34501-15179-0 disconnected
I1029 04:34:36.290477 15491 sched.cpp:483] Rescinded offer 201310290434-1015726915-34501-15179-1
I1029 04:34:36.290511 15491 sched.cpp:494] Scheduler::offerRescinded took 13233ns
I1029 04:34:36.290606 15489 hierarchical_allocator_process.hpp:637] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 201310290434-1015726915-34501-15179-0 from framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.291677 15492 slave.cpp:109] Slave started on 95)@67.195.138.60:34501
I1029 04:34:36.291800 15492 slave.cpp:209] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1029 04:34:36.292729 15492 slave.cpp:481] New master detected at master@67.195.138.60:34501
I1029 04:34:36.292763 15492 slave.cpp:496] Postponing registration until recovery is complete
I1029 04:34:36.292788 15492 status_update_manager.cpp:158] New master detected at master@67.195.138.60:34501
I1029 04:34:36.292789 15489 state.cpp:33] Recovering state from '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/meta'
I1029 04:34:36.294476 15492 slave.cpp:2798] Recovering framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.294497 15492 slave.cpp:2979] Recovering executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.294608 15492 slave.cpp:3173] Terminating task acce0134-da31-4953-a32c-df058c9ece20
I1029 04:34:36.294644 15492 slave.cpp:3196] Completing task acce0134-da31-4953-a32c-df058c9ece20
I1029 04:34:36.294883 15489 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20/runs/d198a374-c2be-4983-ad6e-67c02f1e9333' for gc 6.99999658877333days in the future
I1029 04:34:36.295022 15489 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20/runs/d198a374-c2be-4983-ad6e-67c02f1e9333' for gc 6.99999658796148days in the future
I1029 04:34:36.295035 15492 slave.cpp:2328] Cleaning up framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.295124 15489 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20' for gc 6.99999658757037days in the future
I1029 04:34:36.295136 15496 status_update_manager.cpp:267] Closing status update streams for framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.295239 15489 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20' for gc 6.99999658686519days in the future
I1029 04:34:36.295318 15489 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000' for gc 6.99999658465481days in the future
I1029 04:34:36.295428 15489 gc.cpp:56] Scheduling '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000' for gc 6.99999658408593days in the future
I1029 04:34:36.295699 15490 status_update_manager.cpp:180] Recovering status update manager
I1029 04:34:36.295850 15490 status_update_manager.cpp:188] Recovering executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.295892 15490 status_update_manager.cpp:213] Skipping recovering updates of executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework 201310290434-1015726915-34501-15179-0000 because its latest run d198a374-c2be-4983-ad6e-67c02f1e9333 is completed
I1029 04:34:36.296020 15492 slave.cpp:463] Successfully attached file '/tmp/SlaveRecoveryTest_0_RecoverCompletedExecutor_6yy8Lq/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/acce0134-da31-4953-a32c-df058c9ece20/runs/d198a374-c2be-4983-ad6e-67c02f1e9333'
I1029 04:34:36.296097 15496 process_isolator.cpp:314] Recovering isolator
I1029 04:34:36.296187 15496 process_isolator.cpp:322] Recovering executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:36.296218 15496 process_isolator.cpp:345] Skipping recovery of executor 'acce0134-da31-4953-a32c-df058c9ece20' of framework 201310290434-1015726915-34501-15179-0000 because its latest run d198a374-c2be-4983-ad6e-67c02f1e9333 is completed
I1029 04:34:36.296526 15493 slave.cpp:2736] Finished recovery
W1029 04:34:36.296783 15489 master.cpp:1281] Slave at slave(95)@67.195.138.60:34501 (janus.apache.org) is being allowed to re-register with an already in use id (201310290434-1015726915-34501-15179-0)
I1029 04:34:36.296861 15495 slave.cpp:594] Re-registered with master master@67.195.138.60:34501
I1029 04:34:36.296872 15491 hierarchical_allocator_process.hpp:498] Slave 201310290434-1015726915-34501-15179-0 reconnected
I1029 04:34:37.279456 15495 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 to framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.279630 15495 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 242859ns
I1029 04:34:37.279695 15496 master.hpp:413] Adding offer 201310290434-1015726915-34501-15179-2 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:37.279754 15496 master.cpp:1668] Sending 1 offers to framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.279908 15495 sched.cpp:472] Scheduler::resourceOffers took 24077ns
I1029 04:34:37.280086 15496 sched.cpp:630] Stopping framework '201310290434-1015726915-34501-15179-0000'
I1029 04:34:37.280129 15179 master.cpp:557] Master terminating
I1029 04:34:37.280166 15179 master.cpp:209] Shutting down master
I1029 04:34:37.280200 15492 slave.cpp:1937] master@67.195.138.60:34501 exited
I1029 04:34:37.280208 15179 master.hpp:423] Removing offer 201310290434-1015726915-34501-15179-2 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
W1029 04:34:37.280226 15492 slave.cpp:1940] Master disconnected! Waiting for a new master to be elected
I1029 04:34:37.280288 15179 master.cpp:252] Removing slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:37.280555 15493 slave.cpp:388] Slave terminating
[ OK ] SlaveRecoveryTest/0.RecoverCompletedExecutor (4007 ms)
[ RUN ] SlaveRecoveryTest/0.CleanupExecutor
I1029 04:34:37.282032 15496 master.cpp:284] Master started on 67.195.138.60:34501
I1029 04:34:37.282068 15496 master.cpp:299] Master ID: 201310290434-1015726915-34501-15179
I1029 04:34:37.282075 15496 master.cpp:302] Master only allowing authenticated frameworks to register!
I1029 04:34:37.282377 15492 master.cpp:85] No whitelist given. Advertising offers for all slaves
I1029 04:34:37.282385 15493 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : master@67.195.138.60:34501
I1029 04:34:37.282665 15496 master.cpp:697] Elected as master!
I1029 04:34:37.282894 15496 slave.cpp:109] Slave started on 96)@67.195.138.60:34501
I1029 04:34:37.283005 15496 slave.cpp:209] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1029 04:34:37.283407 15489 sched.cpp:195] New master at master@67.195.138.60:34501
I1029 04:34:37.283429 15489 sched.cpp:281] Authenticating with master master@67.195.138.60:34501
I1029 04:34:37.283668 15491 authenticatee.hpp:124] Creating new client SASL connection
I1029 04:34:37.283725 15493 state.cpp:33] Recovering state from '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta'
I1029 04:34:37.283799 15489 master.cpp:1702] Authenticating framework at scheduler(102)@67.195.138.60:34501
I1029 04:34:37.283833 15496 slave.cpp:481] New master detected at master@67.195.138.60:34501
I1029 04:34:37.283866 15496 slave.cpp:496] Postponing registration until recovery is complete
I1029 04:34:37.283870 15493 status_update_manager.cpp:158] New master detected at master@67.195.138.60:34501
I1029 04:34:37.283994 15495 authenticator.hpp:140] Creating new server SASL connection
I1029 04:34:37.284092 15495 authenticatee.hpp:212] Received SASL authentication mechanisms: CRAM-MD5
I1029 04:34:37.284112 15495 authenticatee.hpp:238] Attempting to authenticate with mechanism 'CRAM-MD5'
I1029 04:34:37.284137 15495 authenticator.hpp:243] Received SASL authentication start
I1029 04:34:37.284188 15495 authenticator.hpp:325] Authentication requires more steps
I1029 04:34:37.284211 15495 authenticatee.hpp:258] Received SASL authentication step
I1029 04:34:37.284247 15495 authenticator.hpp:271] Received SASL authentication step
I1029 04:34:37.284268 15495 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'janus.apache.org' server FQDN: 'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1029 04:34:37.284277 15495 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I1029 04:34:37.284292 15495 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1029 04:34:37.284304 15495 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'janus.apache.org' server FQDN: 'janus.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1029 04:34:37.284312 15495 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1029 04:34:37.284317 15495 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1029 04:34:37.284330 15495 authenticator.hpp:317] Authentication success
I1029 04:34:37.284370 15496 master.cpp:1742] Successfully authenticated framework at scheduler(102)@67.195.138.60:34501
I1029 04:34:37.284385 15491 status_update_manager.cpp:180] Recovering status update manager
I1029 04:34:37.284474 15490 authenticatee.hpp:298] Authentication success
I1029 04:34:37.284550 15494 process_isolator.cpp:314] Recovering isolator
I1029 04:34:37.284811 15496 sched.cpp:326] Successfully authenticated with master master@67.195.138.60:34501
I1029 04:34:37.284827 15492 slave.cpp:2736] Finished recovery
**** DEADLOCK DETECTED! ****
You are waiting on process authenticatee(113)@67.195.138.60:34501 that it is currently executing.
I1029 04:34:37.284875 15494 master.cpp:755] Received registration request from scheduler(102)@67.195.138.60:34501
I1029 04:34:37.285012 15494 master.cpp:773] Registering framework 201310290434-1015726915-34501-15179-0000 at scheduler(102)@67.195.138.60:34501
I1029 04:34:37.285083 15496 sched.cpp:365] Framework registered with 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.285092 15492 hierarchical_allocator_process.hpp:332] Added framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.285096 15494 master.cpp:1223] Attempting to register slave on janus.apache.org at slave(96)@67.195.138.60:34501
I1029 04:34:37.285105 15492 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I1029 04:34:37.285110 15496 sched.cpp:379] Scheduler::registered took 12545ns
I1029 04:34:37.285114 15492 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 7973ns
I1029 04:34:37.285109 15494 master.cpp:2481] Adding slave 201310290434-1015726915-34501-15179-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1029 04:34:37.285189 15489 slave.cpp:547] Registered with master master@67.195.138.60:34501; given slave ID 201310290434-1015726915-34501-15179-0
I1029 04:34:37.285282 15496 hierarchical_allocator_process.hpp:445] Added slave 201310290434-1015726915-34501-15179-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I1029 04:34:37.285343 15489 slave.cpp:560] Checkpointing SlaveInfo to '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta/slaves/201310290434-1015726915-34501-15179-0/slave.info'
I1029 04:34:37.285344 15496 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 to framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.285477 15496 hierarchical_allocator_process.hpp:708] Performed allocation for slave 201310290434-1015726915-34501-15179-0 in 158125ns
I1029 04:34:37.285527 15489 master.hpp:413] Adding offer 201310290434-1015726915-34501-15179-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:37.285580 15489 master.cpp:1668] Sending 1 offers to framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.285694 15493 sched.cpp:472] Scheduler::resourceOffers took 25865ns
I1029 04:34:37.287274 15493 master.cpp:1994] Processing reply for offer 201310290434-1015726915-34501-15179-0 on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org) for framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.287416 15493 master.hpp:385] Adding task 8af83bcf-e8fa-485c-958a-8691963a7ed4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:37.287475 15493 master.cpp:2118] Launching task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:37.287570 15492 slave.cpp:720] Got assigned task 8af83bcf-e8fa-485c-958a-8691963a7ed4 for framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.287612 15493 master.hpp:423] Removing offer 201310290434-1015726915-34501-15179-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 201310290434-1015726915-34501-15179-0 (janus.apache.org)
I1029 04:34:37.287669 15492 slave.cpp:2864] Checkpointing FrameworkInfo to '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/framework.info'
I1029 04:34:37.287804 15492 slave.cpp:2871] Checkpointing framework pid 'scheduler(102)@67.195.138.60:34501' to '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/framework.pid'
I1029 04:34:37.288033 15492 slave.cpp:831] Launching task 8af83bcf-e8fa-485c-958a-8691963a7ed4 for framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.289496 15492 slave.cpp:3128] Checkpointing ExecutorInfo to '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/8af83bcf-e8fa-485c-958a-8691963a7ed4/executor.info'
I1029 04:34:37.289847 15492 slave.cpp:3217] Checkpointing TaskInfo to '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/8af83bcf-e8fa-485c-958a-8691963a7ed4/runs/f8b91e0f-076e-4192-97f3-efd6d0835a6f/tasks/8af83bcf-e8fa-485c-958a-8691963a7ed4/task.info'
I1029 04:34:37.289896 15491 process_isolator.cpp:100] Launching 8af83bcf-e8fa-485c-958a-8691963a7ed4 (<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src/mesos-executor)> in /tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/8af83bcf-e8fa-485c-958a-8691963a7ed4/runs/f8b91e0f-076e-4192-97f3-efd6d0835a6f with resources ' for framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.290007 15492 slave.cpp:941] Queuing task '8af83bcf-e8fa-485c-958a-8691963a7ed4' for executor 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework '201310290434-1015726915-34501-15179-0000
I1029 04:34:37.290053 15492 slave.cpp:463] Successfully attached file '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/8af83bcf-e8fa-485c-958a-8691963a7ed4/runs/f8b91e0f-076e-4192-97f3-efd6d0835a6f'
I1029 04:34:37.292917 15491 process_isolator.cpp:163] Forked executor at 18153
I1029 04:34:37.293428 15491 slave.cpp:2066] Monitoring executor 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000 forked at pid 18153
Checkpointing executor's forked pid 18153 to '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/8af83bcf-e8fa-485c-958a-8691963a7ed4/runs/f8b91e0f-076e-4192-97f3-efd6d0835a6f/pids/forked.pid'
Fetching resources into '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/8af83bcf-e8fa-485c-958a-8691963a7ed4/runs/f8b91e0f-076e-4192-97f3-efd6d0835a6f'
WARNING: Logging before InitGoogleLogging() is written to STDERR
I1029 04:34:37.353030 18171 process.cpp:1555] libprocess is initialized on 67.195.138.60:44340 for 8 cpus
I1029 04:34:37.355249 18192 exec.cpp:174] Executor started at: executor(1)@67.195.138.60:44340 with pid 18171
I1029 04:34:37.355718 15491 slave.cpp:1403] Got registration for executor '8af83bcf-e8fa-485c-958a-8691963a7ed4' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.355774 15491 slave.cpp:1488] Checkpointing executor pid 'executor(1)@67.195.138.60:44340' to '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/8af83bcf-e8fa-485c-958a-8691963a7ed4/runs/f8b91e0f-076e-4192-97f3-efd6d0835a6f/pids/libprocess.pid'
I1029 04:34:37.356240 15491 slave.cpp:1524] Flushing queued task 8af83bcf-e8fa-485c-958a-8691963a7ed4 for executor '8af83bcf-e8fa-485c-958a-8691963a7ed4' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.356504 18185 exec.cpp:198] Executor registered on slave 201310290434-1015726915-34501-15179-0
I1029 04:34:37.357520 18193 process.cpp:986] Socket closed while receiving
Registered executor on janus.apache.org
I1029 04:34:37.357643 18185 exec.cpp:210] Executor::registered took 116448ns
I1029 04:34:37.357772 18193 process.cpp:986] Socket closed while receiving
I1029 04:34:37.357839 18189 exec.cpp:285] Executor asked to run task '8af83bcf-e8fa-485c-958a-8691963a7ed4'
Starting task 8af83bcf-e8fa-485c-958a-8691963a7ed4
I1029 04:34:37.357905 18189 exec.cpp:294] Executor::launchTask took 43114ns
sh -c 'sleep 1000'
Forked command at 18194
I1029 04:34:37.359791 18186 exec.cpp:505] Executor sending status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.360224 15489 slave.cpp:1737] Handling status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000 from executor(1)@67.195.138.60:44340
I1029 04:34:37.360462 15494 status_update_manager.cpp:305] Received status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.360488 15494 status_update_manager.cpp:476] Creating StatusUpdate stream for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.360710 15494 status_update_manager.hpp:337] Checkpointing UPDATE for status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.572690 15494 status_update_manager.cpp:356] Forwarding status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000 to master@67.195.138.60:34501
I1029 04:34:37.573014 15493 master.cpp:1425] Status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000 from slave(96)@67.195.138.60:34501
I1029 04:34:37.573024 15495 slave.cpp:1856] Status update manager successfully handled status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.573076 15495 slave.cpp:1862] Sending acknowledgement for status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000 to executor(1)@67.195.138.60:44340
I1029 04:34:37.573144 15496 sched.cpp:527] Scheduler::statusUpdate took 19154ns
I1029 04:34:37.573372 15495 status_update_manager.cpp:380] Received status update acknowledgement (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.573423 18189 exec.cpp:331] Executor received status update acknowledgement 37dce63e-4300-43df-9985-aedddb6d75c6 for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.573436 15495 status_update_manager.hpp:337] Checkpointing ACK for status update TASK_RUNNING (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.573421 18193 process.cpp:986] Socket closed while receiving
I1029 04:34:37.648041 15496 slave.cpp:1343] Status update manager successfully handled status update acknowledgement (UUID: 37dce63e-4300-43df-9985-aedddb6d75c6) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.648247 15489 slave.cpp:388] Slave terminating
I1029 04:34:37.648334 15489 master.cpp:615] Slave 201310290434-1015726915-34501-15179-0 (janus.apache.org) disconnected
I1029 04:34:37.648412 15496 hierarchical_allocator_process.hpp:484] Slave 201310290434-1015726915-34501-15179-0 disconnected
I1029 04:34:37.649474 15496 slave.cpp:109] Slave started on 97)@67.195.138.60:34501
I1029 04:34:37.649595 15496 slave.cpp:209] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1029 04:34:37.649775 15493 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 27215ns
I1029 04:34:37.650348 15492 state.cpp:33] Recovering state from '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/meta'
I1029 04:34:37.650465 15496 slave.cpp:481] New master detected at master@67.195.138.60:34501
I1029 04:34:37.650496 15496 slave.cpp:490] Skipping registration because slave is in 'cleanup' mode
I1029 04:34:37.650521 15489 status_update_manager.cpp:158] New master detected at master@67.195.138.60:34501
I1029 04:34:37.651867 15496 slave.cpp:2798] Recovering framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.651887 15496 slave.cpp:2979] Recovering executor '8af83bcf-e8fa-485c-958a-8691963a7ed4' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.652328 15495 status_update_manager.cpp:180] Recovering status update manager
I1029 04:34:37.652511 15495 status_update_manager.cpp:188] Recovering executor '8af83bcf-e8fa-485c-958a-8691963a7ed4' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.652578 15495 status_update_manager.cpp:476] Creating StatusUpdate stream for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.652591 15496 slave.cpp:463] Successfully attached file '/tmp/SlaveRecoveryTest_0_CleanupExecutor_4uNwxr/slaves/201310290434-1015726915-34501-15179-0/frameworks/201310290434-1015726915-34501-15179-0000/executors/8af83bcf-e8fa-485c-958a-8691963a7ed4/runs/f8b91e0f-076e-4192-97f3-efd6d0835a6f'
I1029 04:34:37.652727 15495 status_update_manager.hpp:301] Replaying status update stream for task 8af83bcf-e8fa-485c-958a-8691963a7ed4
I1029 04:34:37.653077 15496 process_isolator.cpp:314] Recovering isolator
I1029 04:34:37.653225 15496 process_isolator.cpp:322] Recovering executor '8af83bcf-e8fa-485c-958a-8691963a7ed4' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.653676 15495 slave.cpp:2691] Sending shutdown to executor '8af83bcf-e8fa-485c-958a-8691963a7ed4' of framework 201310290434-1015726915-34501-15179-0000 to executor(1)@67.195.138.60:44340
I1029 04:34:37.653699 15495 slave.cpp:2399] Shutting down executor '8af83bcf-e8fa-485c-958a-8691963a7ed4' of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:37.653990 18193 process.cpp:986] Socket closed while receiving
I1029 04:34:37.654047 18190 exec.cpp:371] Executor asked to shutdown
I1029 04:34:37.654114 18190 exec.cpp:386] Executor::shutdown took 15212ns
Shutting down
Killing process tree at pid 18194
I1029 04:34:37.654134 18191 exec.cpp:76] Scheduling shutdown of the executor
I1029 04:34:37.654183 15495 slave.cpp:2736] Finished recovery
Killed the following process trees:
[
-+- 18194 sh -c sleep 1000
\--- 18195 sleep 1000
]
Command terminated with signal Killed (pid: 18194)
I1029 04:34:38.355167 18188 exec.cpp:505] Executor sending status update TASK_KILLED (UUID: 2db8aaa8-2e81-42e6-a91d-6a71672de78d) for task 8af83bcf-e8fa-485c-958a-8691963a7ed4 of framework 201310290434-1015726915-34501-15179-0000
I1029 04:34:39.356071 15497 process.cpp:986] Socket closed while receiving
Build timed out (after 120 minutes). Marking the build as failed.
make: *** wait: No child processes. Stop.
make: *** Waiting for unfinished jobs....
make: *** wait: No child processes. Stop.