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 2015/03/12 03:45:36 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #2530
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2530/changes>
Changes:
[yujie.jay] Removed tailing white spaces in TestFramework.java.
[yujie.jay] Added Java binding for the new acceptOffers API.
[yujie.jay] Added Python binding for the acceptOffers API.
------------------------------------------
[...truncated 82054 lines...]
I0312 02:45:07.716696 29515 master.cpp:1592] Received registration request for framework 'default' at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340
I0312 02:45:07.716773 29515 master.cpp:1453] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0312 02:45:07.717164 29518 master.cpp:1656] Registering framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340
I0312 02:45:07.717530 29503 hierarchical.hpp:321] Added framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.717656 29506 sched.cpp:448] Framework registered with 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.717737 29506 sched.cpp:462] Scheduler::registered took 51119ns
I0312 02:45:07.718034 29503 hierarchical.hpp:741] Performed allocation for 1 slaves in 472433ns
I0312 02:45:07.718425 29517 master.cpp:3757] Sending 1 offers to framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340
I0312 02:45:07.719357 29503 sched.cpp:611] Scheduler::resourceOffers took 631687ns
I0312 02:45:07.720173 29509 master.cpp:2285] Processing ACCEPT call for offers: [ 20150312-024507-3142697795-56340-29473-O0 ] on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org) for framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340
I0312 02:45:07.720221 29509 master.cpp:2130] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins'
W0312 02:45:07.721595 29501 validation.cpp:326] Executor executor-1 for task 0 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0312 02:45:07.721635 29501 validation.cpp:338] Executor executor-1 for task 0 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0312 02:45:07.721938 29501 master.hpp:802] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20150312-024507-3142697795-56340-29473-S0 (pomona.apache.org)
I0312 02:45:07.722028 29501 master.cpp:2557] Launching task 0 of framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340 with resources cpus(*):2; mem(*):1024 on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:07.722451 29514 slave.cpp:1109] Got assigned task 0 for framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.722523 29516 hierarchical.hpp:648] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20150312-024507-3142697795-56340-29473-S0 from framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.722568 29516 hierarchical.hpp:684] Framework 20150312-024507-3142697795-56340-29473-0000 filtered slave 20150312-024507-3142697795-56340-29473-S0 for 5secs
I0312 02:45:07.722978 29514 slave.cpp:1219] Launching task 0 for framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.725734 29514 slave.cpp:4149] Launching executor executor-1 of framework 20150312-024507-3142697795-56340-29473-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000/executors/executor-1/runs/fdecabef-7fad-44c7-ab3a-16c7e355b3dd'
I0312 02:45:07.728689 29514 exec.cpp:132] Version: 0.23.0
I0312 02:45:07.728942 29501 exec.cpp:182] Executor started at: executor(81)@67.195.81.187:56340 with pid 29473
I0312 02:45:07.729079 29514 slave.cpp:1365] Queuing task '0' for executor executor-1 of framework '20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.729199 29514 slave.cpp:565] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000/executors/executor-1/runs/fdecabef-7fad-44c7-ab3a-16c7e355b3dd'
I0312 02:45:07.729336 29514 slave.cpp:3099] Monitoring executor 'executor-1' of framework '20150312-024507-3142697795-56340-29473-0000' in container 'fdecabef-7fad-44c7-ab3a-16c7e355b3dd'
I0312 02:45:07.729509 29514 slave.cpp:2117] Got registration for executor 'executor-1' of framework 20150312-024507-3142697795-56340-29473-0000 from executor(81)@67.195.81.187:56340
I0312 02:45:07.729868 29501 exec.cpp:206] Executor registered on slave 20150312-024507-3142697795-56340-29473-S0
I0312 02:45:07.730242 29514 slave.cpp:1517] Sending queued task '0' to executor 'executor-1' of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.732177 29501 exec.cpp:218] Executor::registered took 26389ns
I0312 02:45:07.732369 29501 exec.cpp:293] Executor asked to run task '0'
I0312 02:45:07.732446 29501 exec.cpp:302] Executor::launchTask took 54476ns
I0312 02:45:07.734768 29501 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
2015-03-12 02:45:07,777:29473(0x2b0fd289c700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:51522] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0312 02:45:07.893046 29519 process.cpp:2067] Dropped / Lost event for PID: hierarchical-allocator(266)@67.195.81.187:56340
I0312 02:45:07.923867 29518 slave.cpp:2484] Handling status update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 from executor(81)@67.195.81.187:56340
I0312 02:45:07.924146 29508 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.924193 29508 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.924430 29508 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to the slave
I0312 02:45:07.924705 29518 slave.cpp:2721] Forwarding the update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to master@67.195.81.187:56340
I0312 02:45:07.924908 29518 slave.cpp:2648] Status update manager successfully handled status update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.924947 29518 slave.cpp:2654] Sending acknowledgement for status update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to executor(81)@67.195.81.187:56340
I0312 02:45:07.925009 29505 master.cpp:3299] Status update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 from slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:07.925055 29505 master.cpp:3338] Forwarding status update TASK_RUNNING (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.925115 29518 exec.cpp:339] Executor received status update acknowledgement 16d143c5-b5bb-4af8-ba2d-4bfcd9727393 for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.925222 29505 master.cpp:4617] Updating the latest state of task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to TASK_RUNNING
I0312 02:45:07.925382 29518 sched.cpp:717] Scheduler::statusUpdate took 54073ns
I0312 02:45:07.925673 29509 master.cpp:2794] Forwarding status update acknowledgement 16d143c5-b5bb-4af8-ba2d-4bfcd9727393 for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340 to slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:07.926080 29501 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.926360 29509 slave.cpp:2057] Status update manager successfully handled status update acknowledgement (UUID: 16d143c5-b5bb-4af8-ba2d-4bfcd9727393) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.926455 29509 slave.cpp:3157] Executor 'executor-1' of framework 20150312-024507-3142697795-56340-29473-0000 exited with status 0
I0312 02:45:07.928824 29509 slave.cpp:2484] Handling status update TASK_LOST (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 from @0.0.0.0:0
I0312 02:45:07.928910 29509 slave.cpp:4457] Terminating task 0
I0312 02:45:07.929466 29510 master.cpp:3390] Executor executor-1 of framework 20150312-024507-3142697795-56340-29473-0000 on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org) exited with status 0
I0312 02:45:07.929574 29510 master.cpp:4711] Removing executor 'executor-1' with resources of framework 20150312-024507-3142697795-56340-29473-0000 on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:07.929590 29517 status_update_manager.cpp:317] Received status update TASK_LOST (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.929702 29517 status_update_manager.cpp:371] Forwarding update TASK_LOST (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to the slave
I0312 02:45:07.929935 29509 slave.cpp:2721] Forwarding the update TASK_LOST (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to master@67.195.81.187:56340
I0312 02:45:07.930140 29509 slave.cpp:2648] Status update manager successfully handled status update TASK_LOST (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.930197 29514 master.cpp:3299] Status update TASK_LOST (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 from slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:07.930238 29514 master.cpp:3338] Forwarding status update TASK_LOST (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.930400 29514 master.cpp:4617] Updating the latest state of task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to TASK_LOST
I0312 02:45:07.930497 29512 sched.cpp:717] Scheduler::statusUpdate took 17375ns
I0312 02:45:07.930655 29516 hierarchical.hpp:648] Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20150312-024507-3142697795-56340-29473-S0 from framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.930945 29514 master.cpp:4682] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20150312-024507-3142697795-56340-29473-0000 on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:07.931094 29514 master.cpp:2794] Forwarding status update acknowledgement 5c05a342-a507-4ef4-9dc6-8825c65a3684 for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340 to slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:07.931382 29509 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.931466 29509 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.931673 29509 slave.cpp:2057] Status update manager successfully handled status update acknowledgement (UUID: 5c05a342-a507-4ef4-9dc6-8825c65a3684) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.931728 29509 slave.cpp:4498] Completing task 0
I0312 02:45:07.931767 29509 slave.cpp:3266] Cleaning up executor 'executor-1' of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:07.932015 29516 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000/executors/executor-1/runs/fdecabef-7fad-44c7-ab3a-16c7e355b3dd' for gc 6.99998928066074days in the future
I0312 02:45:07.932041 29509 slave.cpp:3345] Cleaning up framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.135900 29516 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000/executors/executor-1' for gc 6.99998928066074days in the future
I0312 02:45:08.135948 29501 status_update_manager.cpp:279] Closing status update streams for framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.136018 29516 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000' for gc 1.00000012210497weeks in the future
I0312 02:45:08.161496 29514 hierarchical.hpp:741] Performed allocation for 1 slaves in 491201ns
I0312 02:45:08.161810 29503 master.cpp:3757] Sending 1 offers to framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340
I0312 02:45:08.162856 29503 sched.cpp:611] Scheduler::resourceOffers took 753814ns
I0312 02:45:08.163683 29503 master.cpp:2285] Processing ACCEPT call for offers: [ 20150312-024507-3142697795-56340-29473-O1 ] on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org) for framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340
I0312 02:45:08.163728 29503 master.cpp:2130] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins'
W0312 02:45:08.164958 29503 validation.cpp:326] Executor executor-2 for task 0 uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0312 02:45:08.164999 29503 validation.cpp:338] Executor executor-2 for task 0 uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0312 02:45:08.165266 29503 master.hpp:802] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20150312-024507-3142697795-56340-29473-S0 (pomona.apache.org)
I0312 02:45:08.165374 29503 master.cpp:2557] Launching task 0 of framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340 with resources cpus(*):2; mem(*):1024 on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:08.165716 29501 slave.cpp:1109] Got assigned task 0 for framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.165828 29510 hierarchical.hpp:648] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20150312-024507-3142697795-56340-29473-S0 from framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.165886 29510 hierarchical.hpp:684] Framework 20150312-024507-3142697795-56340-29473-0000 filtered slave 20150312-024507-3142697795-56340-29473-S0 for 5secs
I0312 02:45:08.166669 29501 gc.cpp:84] Unscheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000' from gc
I0312 02:45:08.166915 29515 slave.cpp:1219] Launching task 0 for framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.169642 29515 slave.cpp:4149] Launching executor executor-2 of framework 20150312-024507-3142697795-56340-29473-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000/executors/executor-2/runs/360b3aaa-1287-4040-bd51-273f4db1c8cb'
I0312 02:45:08.172575 29515 exec.cpp:132] Version: 0.23.0
I0312 02:45:08.172814 29518 exec.cpp:182] Executor started at: executor(82)@67.195.81.187:56340 with pid 29473
I0312 02:45:08.172929 29515 slave.cpp:1365] Queuing task '0' for executor executor-2 of framework '20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.173043 29515 slave.cpp:565] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000/executors/executor-2/runs/360b3aaa-1287-4040-bd51-273f4db1c8cb'
I0312 02:45:08.173153 29515 slave.cpp:3099] Monitoring executor 'executor-2' of framework '20150312-024507-3142697795-56340-29473-0000' in container '360b3aaa-1287-4040-bd51-273f4db1c8cb'
I0312 02:45:08.173409 29515 slave.cpp:2117] Got registration for executor 'executor-2' of framework 20150312-024507-3142697795-56340-29473-0000 from executor(82)@67.195.81.187:56340
I0312 02:45:08.173718 29516 exec.cpp:206] Executor registered on slave 20150312-024507-3142697795-56340-29473-S0
I0312 02:45:08.174047 29515 slave.cpp:1517] Sending queued task '0' to executor 'executor-2' of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.176056 29516 exec.cpp:218] Executor::registered took 21260ns
I0312 02:45:08.176230 29516 exec.cpp:293] Executor asked to run task '0'
I0312 02:45:08.176337 29516 exec.cpp:302] Executor::launchTask took 83233ns
I0312 02:45:08.177909 29516 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.178062 29516 slave.cpp:2484] Handling status update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 from executor(82)@67.195.81.187:56340
I0312 02:45:08.178222 29503 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.178257 29503 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.178396 29503 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to the slave
I0312 02:45:08.178578 29501 slave.cpp:2721] Forwarding the update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to master@67.195.81.187:56340
I0312 02:45:08.178732 29501 slave.cpp:2648] Status update manager successfully handled status update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.178766 29501 slave.cpp:2654] Sending acknowledgement for status update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to executor(82)@67.195.81.187:56340
I0312 02:45:08.178786 29517 master.cpp:3299] Status update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 from slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:08.178813 29517 master.cpp:3338] Forwarding status update TASK_RUNNING (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.178872 29501 exec.cpp:339] Executor received status update acknowledgement ef71958e-7346-4fe8-b9ed-237d9090a024 for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.178916 29517 master.cpp:4617] Updating the latest state of task 0 of framework 20150312-024507-3142697795-56340-29473-0000 to TASK_RUNNING
I0312 02:45:08.179003 29503 sched.cpp:717] Scheduler::statusUpdate took 15702ns
I0312 02:45:08.179235 29512 master.cpp:2794] Forwarding status update acknowledgement ef71958e-7346-4fe8-b9ed-237d9090a024 for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 (default) at scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340 to slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:08.179507 29506 status_update_manager.cpp:389] Received status update acknowledgement (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.179672 29506 slave.cpp:2057] Status update manager successfully handled status update acknowledgement (UUID: ef71958e-7346-4fe8-b9ed-237d9090a024) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.199918 29473 sched.cpp:1589] Asked to stop the driver
I0312 02:45:08.200003 29473 master.cpp:752] Master terminating
I0312 02:45:08.200074 29508 sched.cpp:831] Stopping framework '20150312-024507-3142697795-56340-29473-0000'
I0312 02:45:08.200197 29512 hierarchical.hpp:486] Removed slave 20150312-024507-3142697795-56340-29473-S0
W0312 02:45:08.200180 29473 master.cpp:4668] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20150312-024507-3142697795-56340-29473-0000 on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org) in non-terminal state TASK_RUNNING
I0312 02:45:08.200662 29473 master.cpp:4711] Removing executor 'executor-2' with resources of framework 20150312-024507-3142697795-56340-29473-0000 on slave 20150312-024507-3142697795-56340-29473-S0 at slave(236)@67.195.81.187:56340 (pomona.apache.org)
I0312 02:45:08.201166 29501 hierarchical.hpp:354] Removed framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.201438 29516 slave.cpp:2882] master@67.195.81.187:56340 exited
W0312 02:45:08.201467 29516 slave.cpp:2885] Master disconnected! Waiting for a new master to be elected
I0312 02:45:08.202940 29473 process.cpp:2067] Dropped / Lost event for PID: master@67.195.81.187:56340
I0312 02:45:08.203034 29473 process.cpp:2067] Dropped / Lost event for PID: master@67.195.81.187:56340
I0312 02:45:08.205771 29506 slave.cpp:3157] Executor 'executor-2' of framework 20150312-024507-3142697795-56340-29473-0000 exited with status 0
I0312 02:45:08.208217 29506 slave.cpp:2484] Handling status update TASK_LOST (UUID: 1f4bffe6-6269-43fa-9ae1-7e35de038f57) for task 0 of framework 20150312-024507-3142697795-56340-29473-0000 from @0.0.0.0:0
I0312 02:45:08.208338 29506 slave.cpp:4457] Terminating task 0
I0312 02:45:08.208693 29506 process.cpp:2067] Dropped / Lost event for PID: master@67.195.81.187:56340
I0312 02:45:08.208734 29506 slave.cpp:494] Slave terminating
I0312 02:45:08.208817 29506 slave.cpp:1730] Asked to shut down framework 20150312-024507-3142697795-56340-29473-0000 by @0.0.0.0:0
I0312 02:45:08.208842 29506 slave.cpp:1755] Shutting down framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.208914 29506 slave.cpp:3266] Cleaning up executor 'executor-2' of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.209164 29503 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000/executors/executor-2/runs/360b3aaa-1287-4040-bd51-273f4db1c8cb' for gc 6.99999758086815days in the future
I0312 02:45:08.209209 29506 slave.cpp:3345] Cleaning up framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.209409 29513 status_update_manager.cpp:279] Closing status update streams for framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.331370 29513 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20150312-024507-3142697795-56340-29473-0000
I0312 02:45:08.211021 29473 process.cpp:2067] Dropped / Lost event for PID: slave(236)@67.195.81.187:56340
I0312 02:45:08.271522 29519 process.cpp:2067] Dropped / Lost event for PID: scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340
I0312 02:45:08.209434 29503 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Unschedule_63CcbP/slaves/20150312-024507-3142697795-56340-29473-S0/frameworks/20150312-024507-3142697795-56340-29473-0000/executors/executor-2' for gc 6.99999757937481days in the future
I0312 02:45:08.332257 29473 process.cpp:2067] Dropped / Lost event for PID: scheduler-3a2b5223-87f7-47c3-b981-e572bbbf97cb@67.195.81.187:56340
[ OK ] GarbageCollectorIntegrationTest.Unschedule (1028 ms)
[----------] 5 tests from GarbageCollectorIntegrationTest (3675 ms total)
[----------] 6 tests from DiskResourcesTest
[ RUN ] DiskResourcesTest.Equals
[ OK ] DiskResourcesTest.Equals (0 ms)
[ RUN ] DiskResourcesTest.Validation
[ OK ] DiskResourcesTest.Validation (0 ms)
[ RUN ] DiskResourcesTest.Subtraction
[ OK ] DiskResourcesTest.Subtraction (1 ms)
[ RUN ] DiskResourcesTest.Contains
[ OK ] DiskResourcesTest.Contains (0 ms)
[ RUN ] DiskResourcesTest.FilterPersistentVolumes
[ OK ] DiskResourcesTest.FilterPersistentVolumes (0 ms)
[ RUN ] DiskResourcesTest.Addition
[ OK ] DiskResourcesTest.Addition (0 ms)
[----------] 6 tests from DiskResourcesTest (1 ms total)
[----------] 4 tests from CRAMMD5Authentication/2, where TypeParam = mesos::internal::cram_md5::Authentication<mesos::internal::cram_md5::CRAMMD5Authenticatee, mesos::internal::tests::Module<mesos::Authenticator, (mesos::internal::tests::ModuleID)3> >
[ RUN ] CRAMMD5Authentication/2.success
Using temporary directory '/tmp/CRAMMD5Authentication_2_success_E5I88m'
I0312 02:45:08.335777 29506 authenticatee.hpp:139] Creating new client SASL connection
I0312 02:45:08.336549 29517 authenticator.hpp:170] Creating new server SASL connection
I0312 02:45:08.336740 29503 authenticatee.hpp:230] Received SASL authentication mechanisms: CRAM-MD5
I0312 02:45:08.336777 29503 authenticatee.hpp:256] Attempting to authenticate with mechanism 'CRAM-MD5'
I0312 02:45:08.336892 29508 authenticator.hpp:276] Received SASL authentication start
I0312 02:45:08.336946 29508 authenticator.hpp:398] Authentication requires more steps
I0312 02:45:08.337087 29514 authenticatee.hpp:276] Received SASL authentication step
I0312 02:45:08.337206 29514 authenticator.hpp:304] Received SASL authentication step
I0312 02:45:08.337235 29514 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0312 02:45:08.337249 29514 auxprop.cpp:171] Looking up auxiliary property '*userPassword'
I0312 02:45:08.337314 29514 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0312 02:45:08.337347 29514 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0312 02:45:08.337359 29514 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0312 02:45:08.337368 29514 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0312 02:45:08.337384 29514 authenticator.hpp:390] Authentication success
I0312 02:45:08.337466 29514 authenticatee.hpp:316] Authentication success
[ OK ] CRAMMD5Authentication/2.success (4 ms)
[ RUN ] CRAMMD5Authentication/2.AuthenticatorDestructionRace
Using temporary directory '/tmp/CRAMMD5Authentication_2_AuthenticatorDestructionRace_bUTNca'
I0312 02:45:08.339253 29513 authenticatee.hpp:139] Creating new client SASL connection
I0312 02:45:08.340051 29501 authenticator.hpp:170] Creating new server SASL connection
I0312 02:45:08.340252 29504 authenticatee.hpp:230] Received SASL authentication mechanisms: CRAM-MD5
I0312 02:45:08.340309 29504 authenticatee.hpp:256] Attempting to authenticate with mechanism 'CRAM-MD5'
I0312 02:45:08.340422 29518 authenticator.hpp:276] Received SASL authentication start
I0312 02:45:08.340476 29518 authenticator.hpp:398] Authentication requires more steps
[ OK ] CRAMMD5Authentication/2.AuthenticatorDestructionRace (4 ms)
[ RUN ] CRAMMD5Authentication/2.failed2
Using temporary directory '/tmp/CRAMMD5Authentication_2_failed2_SZ64gX'
I0312 02:45:08.342855 29504 authenticatee.hpp:139] Creating new client SASL connection
I0312 02:45:08.343593 29503 authenticator.hpp:170] Creating new server SASL connection
I0312 02:45:08.343739 29518 authenticatee.hpp:230] Received SASL authentication mechanisms: CRAM-MD5
I0312 02:45:08.343773 29518 authenticatee.hpp:256] Attempting to authenticate with mechanism 'CRAM-MD5'
I0312 02:45:08.343895 29513 authenticator.hpp:276] Received SASL authentication start
I0312 02:45:08.343940 29513 authenticator.hpp:398] Authentication requires more steps
I0312 02:45:08.344029 29503 authenticatee.hpp:276] Received SASL authentication step
I0312 02:45:08.344172 29505 authenticator.hpp:304] Received SASL authentication step
I0312 02:45:08.344205 29505 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0312 02:45:08.344220 29505 auxprop.cpp:171] Looking up auxiliary property '*userPassword'
I0312 02:45:08.344240 29505 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0312 02:45:08.344255 29505 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0312 02:45:08.344266 29505 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0312 02:45:08.344274 29505 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
W0312 02:45:08.344305 29505 authenticator.hpp:404] Authentication failure: user not found
[ OK ] CRAMMD5Authentication/2.failed2 (3 ms)
[ RUN ] CRAMMD5Authentication/2.failed1
Using temporary directory '/tmp/CRAMMD5Authentication_2_failed1_NsvUlK'
I0312 02:45:08.346458 29513 authenticatee.hpp:139] Creating new client SASL connection
I0312 02:45:08.347066 29514 authenticator.hpp:170] Creating new server SASL connection
I0312 02:45:08.347246 29509 authenticatee.hpp:230] Received SASL authentication mechanisms: CRAM-MD5
I0312 02:45:08.347275 29509 authenticatee.hpp:256] Attempting to authenticate with mechanism 'CRAM-MD5'
I0312 02:45:08.347436 29518 authenticator.hpp:276] Received SASL authentication start
I0312 02:45:08.347486 29518 authenticator.hpp:398] Authentication requires more steps
I0312 02:45:08.347636 29506 authenticatee.hpp:276] Received SASL authentication step
I0312 02:45:08.347744 29506 authenticator.hpp:304] Received SASL authentication step
I0312 02:45:08.347775 29506 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0312 02:45:08.347795 29506 auxprop.cpp:171] Looking up auxiliary property '*userPassword'
I0312 02:45:08.347827 29506 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0312 02:45:08.347852 29506 auxprop.cpp:99] Request to lookup properties for user: 'benh' realm: 'pomona.apache.org' server FQDN: 'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0312 02:45:08.527333 29506 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0312 02:45:08.527354 29506 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
W0312 02:45:08.527380 29506 authenticator.hpp:404] Authentication failure: authentication failure
[ OK ] CRAMMD5Authentication/2.failed1 (183 ms)
[----------] 4 tests from CRAMMD5Authentication/2 (194 ms total)
[----------] Global test environment tear-down
[==========] 548 tests from 85 test cases ran. (247680 ms total)
[ PASSED ] 547 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MasterTest.MasterFailoverLongLivedExecutor
1 FAILED TEST
YOU HAVE 8 DISABLED TESTS
make[3]: *** [check-local] Error 1
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #2531
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2531/changes>