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 2014/08/05 03:27:13 UTC
Build failed in Jenkins: Mesos-Ubuntu-distcheck #248
See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/248/changes>
Changes:
[idownes] Fix stout/os to properly check errno from getpwnam_r.
[bmahler] Removed unused code from future.hpp.
[bmahler] Made WeakFuture<T>::get a const operation.
[bmahler] Master detector cleanups.
[bmahler] Handle discards on the master detection futures.
[bmahler] Added a ping timeout in the slave to trigger a re-detection.
[vinodkone] Fixed header paths for proto files during installation.
[benjamin.hindman] Added a 'Docker' abstraction.
[benjamin.hindman] Added a Docker containerizer.
[benjamin.hindman] Bug fixes and cleanups in Docker abstraction.
[benjamin.hindman] Integrated DockerContainerizer.
[benjamin.hindman] Add environment test for Docker.
[benjamin.hindman] Added pid() in docker/docker.cpp to get the pid of the container.
[benjamin.hindman] Added Docker::ps() "all" option.
[benjamin.hindman] Added rm() in docker.hpp/cpp to enable users remove containers.
[benjamin.hindman] Modify validate Docker to call 'info' instead.
[benjamin.hindman] Added Docker unit test, Docker flag and fix issues found.
[benjamin.hindman] Changed docker.kill() to docker.rm().
[benjamin.hindman] Added usage() for the docker containerizer.
[benjamin.hindman] Used 'flags.docker' in tests.
[benjamin.hindman] Exposed and used the Docker container name prefix.
[benjamin.hindman] Renamed variable to be more accurate.
[benjamin.hindman] Refactored Docker::Container::pid() to return an Option.
[benjamin.hindman] Made Docker::ps/rm default arguments match CLI defaults.
[benjamin.hindman] Be more mock friendly in MockDockerContainerizer.
[benjamin.hindman] Added license in docker/docker.cpp.
[benjamin.hindman] Added docker_tests to test the docker abstraction.
[benjamin.hindman] Added docker::killAndRm() to performs 'docker kill && docker rm (-f)'
[benjamin.hindman] Refactored docker::ps(), added 'prefix' option to inspect only interested containers.
[benjamin.hindman] Moved cpu/mem constants from cpushare.cpp/mem.cpp to cpushare.hpp/mem.hpp
[benjamin.hindman] Refactored docker::usage() and docker::run().
[benjamin.hindman] Added a DockerContainerizer::recover test.
[benjamin.hindman] Implemented DockerContainerizer::update.
[benjamin.hindman] Added an example Docker framework for testing.
[benjamin.hindman] Used unsigned constants for older compilers.
[benjamin.hindman] Used the new cgroups helpers when updating Docker container resources.
[benjamin.hindman] Moved WSTRINGIFY out of a namespace.
[benjamin.hindman] Modified Docker example framework to use busybox.
[benjamin.hindman] Improved failure handling of DockerContainerizer.
[benjamin.hindman] Refactored some Docker tests to be more generic.
[benjamin.hindman] Fix docker usage test
[benjamin.hindman] Added DockerContainerizer kill task test.
[benjamin.hindman] Checked for mounted 'cpu' cgroups subsystem in Docker::validate.
[benjamin.hindman] Await container termination in docker kill test
[benjamin.hindman] Update docker uri to expect 3 slashes in prefix
[benjamin.hindman] Implemented launching executors in DockerContainerizer.
[benjamin.hindman] Revised comments to reflect DockerContainerizer implementation.
[benjamin.hindman] Replaced the docker test executor with a smaller one.
[benjamin.hindman] Addressing Docker review comments
[benjamin.hindman] Fixed tests for new launch/wait containerizer semantics.
------------------------------------------
[...truncated 61365 lines...]
I0805 01:27:10.835929 6324 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 11.405259ms
I0805 01:27:10.835973 6324 replica.cpp:676] Persisted action at 0
I0805 01:27:10.836349 6324 replica.cpp:508] Replica received write request for position 0
I0805 01:27:10.836377 6324 leveldb.cpp:438] Reading position from leveldb took 14410ns
I0805 01:27:10.848009 6324 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 11.61214ms
I0805 01:27:10.848044 6324 replica.cpp:676] Persisted action at 0
I0805 01:27:10.848225 6324 replica.cpp:655] Replica received learned notice for position 0
I0805 01:27:10.861666 6324 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 13.41942ms
I0805 01:27:10.861701 6324 replica.cpp:676] Persisted action at 0
I0805 01:27:10.861709 6324 replica.cpp:661] Replica learned NOP action at position 0
I0805 01:27:10.861937 6324 log.cpp:672] Writer started with ending position 0
I0805 01:27:10.862207 6324 leveldb.cpp:438] Reading position from leveldb took 14367ns
I0805 01:27:10.864256 6324 registrar.cpp:346] Successfully fetched the registry (0B)
I0805 01:27:10.864279 6324 registrar.cpp:422] Attempting to update the 'registry'
I0805 01:27:10.866340 6324 log.cpp:680] Attempting to append 137 bytes to the log
I0805 01:27:10.866454 6324 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1
I0805 01:27:10.866773 6324 replica.cpp:508] Replica received write request for position 1
I0805 01:27:10.877653 6324 leveldb.cpp:343] Persisting action (156 bytes) to leveldb took 10.798292ms
I0805 01:27:10.877740 6324 replica.cpp:676] Persisted action at 1
I0805 01:27:10.877986 6324 replica.cpp:655] Replica received learned notice for position 1
I0805 01:27:10.893658 6324 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took 15.599903ms
I0805 01:27:10.893734 6324 replica.cpp:676] Persisted action at 1
I0805 01:27:10.893790 6324 replica.cpp:661] Replica learned APPEND action at position 1
I0805 01:27:10.894088 6324 registrar.cpp:479] Successfully updated 'registry'
I0805 01:27:10.894186 6324 registrar.cpp:372] Successfully recovered registrar
I0805 01:27:10.894284 6324 log.cpp:699] Attempting to truncate the log to 1
I0805 01:27:10.894404 6324 master.cpp:985] Recovered 0 slaves from the Registry (99B) ; allowing 10mins for slaves to re-register
I0805 01:27:10.894498 6324 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2
I0805 01:27:10.894772 6324 replica.cpp:508] Replica received write request for position 2
I0805 01:27:10.912338 6324 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 17.497633ms
I0805 01:27:10.912413 6324 replica.cpp:676] Persisted action at 2
I0805 01:27:10.912631 6324 replica.cpp:655] Replica received learned notice for position 2
I0805 01:27:10.925664 6324 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 12.96495ms
I0805 01:27:10.925755 6324 leveldb.cpp:401] Deleting ~1 keys from leveldb took 16607ns
I0805 01:27:10.925820 6324 replica.cpp:676] Persisted action at 2
I0805 01:27:10.925875 6324 replica.cpp:661] Replica learned TRUNCATE action at position 2
I0805 01:27:10.937911 6316 slave.cpp:167] Slave started on 200)@140.211.11.27:59014
I0805 01:27:10.937929 6316 credentials.hpp:84] Loading credential for authentication from '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/credential'
I0805 01:27:10.937999 6316 slave.cpp:265] Slave using credential for: test-principal
I0805 01:27:10.938087 6316 slave.cpp:278] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0805 01:27:10.938133 6316 slave.cpp:323] Slave hostname: hemera.apache.org
I0805 01:27:10.938140 6316 slave.cpp:324] Slave checkpoint: true
I0805 01:27:10.938624 6316 state.cpp:33] Recovering state from '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/meta'
I0805 01:27:10.938709 6316 status_update_manager.cpp:193] Recovering status update manager
I0805 01:27:10.938792 6316 slave.cpp:3188] Finished recovery
I0805 01:27:10.938982 6316 slave.cpp:604] New master detected at master@140.211.11.27:59014
I0805 01:27:10.939002 6316 slave.cpp:678] Authenticating with master master@140.211.11.27:59014
I0805 01:27:10.939038 6316 slave.cpp:651] Detecting new master
I0805 01:27:10.939071 6316 status_update_manager.cpp:167] New master detected at master@140.211.11.27:59014
I0805 01:27:10.939101 6316 authenticatee.hpp:128] Creating new client SASL connection
I0805 01:27:10.939216 6316 master.cpp:3503] Authenticating slave(200)@140.211.11.27:59014
I0805 01:27:10.939291 6316 authenticator.hpp:156] Creating new server SASL connection
I0805 01:27:10.939373 6316 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0805 01:27:10.939388 6316 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0805 01:27:10.939410 6316 authenticator.hpp:262] Received SASL authentication start
I0805 01:27:10.939443 6316 authenticator.hpp:384] Authentication requires more steps
I0805 01:27:10.939467 6316 authenticatee.hpp:265] Received SASL authentication step
I0805 01:27:10.939498 6316 authenticator.hpp:290] Received SASL authentication step
I0805 01:27:10.939512 6316 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0805 01:27:10.939518 6316 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0805 01:27:10.939528 6316 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0805 01:27:10.939538 6316 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0805 01:27:10.939543 6316 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0805 01:27:10.939549 6316 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0805 01:27:10.939560 6316 authenticator.hpp:376] Authentication success
I0805 01:27:10.939584 6316 authenticatee.hpp:305] Authentication success
I0805 01:27:10.939604 6316 master.cpp:3543] Successfully authenticated principal 'test-principal' at slave(200)@140.211.11.27:59014
I0805 01:27:10.939661 6316 slave.cpp:735] Successfully authenticated with master master@140.211.11.27:59014
I0805 01:27:10.939692 6316 slave.cpp:986] Will retry registration in 12.958595ms if necessary
I0805 01:27:10.939749 6316 master.cpp:2761] Registering slave at slave(200)@140.211.11.27:59014 (hemera.apache.org) with id 20140805-012710-453759884-59014-5132-0
I0805 01:27:10.939842 6316 registrar.cpp:422] Attempting to update the 'registry'
I0805 01:27:10.941982 6316 log.cpp:680] Attempting to append 330 bytes to the log
I0805 01:27:10.942023 6316 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3
I0805 01:27:10.942206 6316 replica.cpp:508] Replica received write request for position 3
I0805 01:27:10.949185 5132 sched.cpp:139] Version: 0.20.0
I0805 01:27:10.952805 6316 leveldb.cpp:343] Persisting action (349 bytes) to leveldb took 10.583006ms
I0805 01:27:10.952826 6316 replica.cpp:676] Persisted action at 3
I0805 01:27:10.952929 6316 slave.cpp:986] Will retry registration in 5.382729ms if necessary
I0805 01:27:10.953021 6316 master.cpp:2749] Ignoring register slave message from slave(200)@140.211.11.27:59014 (hemera.apache.org) as admission is already in progress
I0805 01:27:10.953116 6316 sched.cpp:235] New master detected at master@140.211.11.27:59014
I0805 01:27:10.953130 6316 sched.cpp:285] Authenticating with master master@140.211.11.27:59014
I0805 01:27:10.953243 6316 authenticatee.hpp:128] Creating new client SASL connection
I0805 01:27:10.953385 6316 master.cpp:3503] Authenticating scheduler-ff7d9985-fd1c-4d8b-8186-385f885401d3@140.211.11.27:59014
I0805 01:27:10.953454 6316 replica.cpp:655] Replica received learned notice for position 3
I0805 01:27:10.953570 6324 authenticator.hpp:156] Creating new server SASL connection
I0805 01:27:10.953656 6324 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0805 01:27:10.953721 6324 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0805 01:27:10.953811 6324 authenticator.hpp:262] Received SASL authentication start
I0805 01:27:10.953923 6324 authenticator.hpp:384] Authentication requires more steps
I0805 01:27:10.954015 6324 authenticatee.hpp:265] Received SASL authentication step
I0805 01:27:10.954102 6324 authenticator.hpp:290] Received SASL authentication step
I0805 01:27:10.954171 6324 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0805 01:27:10.954229 6324 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0805 01:27:10.954283 6324 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0805 01:27:10.954339 6324 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0805 01:27:10.954398 6324 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0805 01:27:10.954448 6324 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0805 01:27:10.954505 6324 authenticator.hpp:376] Authentication success
I0805 01:27:10.954578 6324 authenticatee.hpp:305] Authentication success
I0805 01:27:10.954656 6324 master.cpp:3543] Successfully authenticated principal 'test-principal' at scheduler-ff7d9985-fd1c-4d8b-8186-385f885401d3@140.211.11.27:59014
I0805 01:27:10.954764 6324 sched.cpp:359] Successfully authenticated with master master@140.211.11.27:59014
I0805 01:27:10.954829 6324 sched.cpp:478] Sending registration request to master@140.211.11.27:59014
I0805 01:27:10.954972 6324 master.cpp:1246] Received registration request from scheduler-ff7d9985-fd1c-4d8b-8186-385f885401d3@140.211.11.27:59014
I0805 01:27:10.955052 6324 master.cpp:1206] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0805 01:27:10.955202 6324 master.cpp:1305] Registering framework 20140805-012710-453759884-59014-5132-0000 at scheduler-ff7d9985-fd1c-4d8b-8186-385f885401d3@140.211.11.27:59014
I0805 01:27:10.955353 6324 sched.cpp:409] Framework registered with 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.955579 6324 sched.cpp:423] Scheduler::registered took 12129ns
I0805 01:27:10.955515 6346 hierarchical_allocator_process.hpp:331] Added framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.955694 6346 hierarchical_allocator_process.hpp:724] No resources available to allocate!
I0805 01:27:10.955747 6346 hierarchical_allocator_process.hpp:686] Performed allocation for 0 slaves in 54103ns
I0805 01:27:10.961752 6319 slave.cpp:986] Will retry registration in 23.528822ms if necessary
I0805 01:27:10.961807 6319 master.cpp:2749] Ignoring register slave message from slave(200)@140.211.11.27:59014 (hemera.apache.org) as admission is already in progress
I0805 01:27:10.969668 6316 leveldb.cpp:343] Persisting action (351 bytes) to leveldb took 16.197782ms
I0805 01:27:10.969688 6316 replica.cpp:676] Persisted action at 3
I0805 01:27:10.969696 6316 replica.cpp:661] Replica learned APPEND action at position 3
I0805 01:27:10.970165 6316 registrar.cpp:479] Successfully updated 'registry'
I0805 01:27:10.970243 6316 log.cpp:699] Attempting to truncate the log to 3
I0805 01:27:10.970295 6316 master.cpp:2801] Registered slave 20140805-012710-453759884-59014-5132-0 at slave(200)@140.211.11.27:59014 (hemera.apache.org)
I0805 01:27:10.970306 6316 master.cpp:3971] Adding slave 20140805-012710-453759884-59014-5132-0 at slave(200)@140.211.11.27:59014 (hemera.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0805 01:27:10.970403 6316 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4
I0805 01:27:10.970466 6316 slave.cpp:769] Registered with master master@140.211.11.27:59014; given slave ID 20140805-012710-453759884-59014-5132-0
I0805 01:27:10.970619 6316 slave.cpp:782] Checkpointing SlaveInfo to '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/meta/slaves/20140805-012710-453759884-59014-5132-0/slave.info'
I0805 01:27:10.970756 6316 hierarchical_allocator_process.hpp:444] Added slave 20140805-012710-453759884-59014-5132-0 (hemera.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I0805 01:27:10.970808 6316 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140805-012710-453759884-59014-5132-0 to framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.970911 6316 hierarchical_allocator_process.hpp:706] Performed allocation for slave 20140805-012710-453759884-59014-5132-0 in 123136ns
I0805 01:27:10.970959 6316 slave.cpp:2339] Received ping from slave-observer(169)@140.211.11.27:59014
I0805 01:27:10.971019 6316 master.hpp:816] Adding offer 20140805-012710-453759884-59014-5132-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140805-012710-453759884-59014-5132-0 (hemera.apache.org)
I0805 01:27:10.971060 6316 master.cpp:3450] Sending 1 offers to framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.971182 6316 sched.cpp:546] Scheduler::resourceOffers took 19792ns
I0805 01:27:10.971403 6316 replica.cpp:508] Replica received write request for position 4
I0805 01:27:10.971762 6341 master.hpp:826] Removing offer 20140805-012710-453759884-59014-5132-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140805-012710-453759884-59014-5132-0 (hemera.apache.org)
I0805 01:27:10.971818 6341 master.cpp:2124] Processing reply for offers: [ 20140805-012710-453759884-59014-5132-0 ] on slave 20140805-012710-453759884-59014-5132-0 at slave(200)@140.211.11.27:59014 (hemera.apache.org) for framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.971846 6341 master.cpp:2210] Authorizing framework principal 'test-principal' to launch task 1 as user 'jenkins'
I0805 01:27:10.972157 6341 master.hpp:788] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140805-012710-453759884-59014-5132-0 (hemera.apache.org)
I0805 01:27:10.972193 6341 master.cpp:2276] Launching task 1 of framework 20140805-012710-453759884-59014-5132-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140805-012710-453759884-59014-5132-0 at slave(200)@140.211.11.27:59014 (hemera.apache.org)
I0805 01:27:10.972329 6341 slave.cpp:1017] Got assigned task 1 for framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.972395 6341 slave.cpp:3463] Checkpointing FrameworkInfo to '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/meta/slaves/20140805-012710-453759884-59014-5132-0/frameworks/20140805-012710-453759884-59014-5132-0000/framework.info'
I0805 01:27:10.972517 6341 slave.cpp:3470] Checkpointing framework pid 'scheduler-ff7d9985-fd1c-4d8b-8186-385f885401d3@140.211.11.27:59014' to '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/meta/slaves/20140805-012710-453759884-59014-5132-0/frameworks/20140805-012710-453759884-59014-5132-0000/framework.pid'
I0805 01:27:10.972709 6341 slave.cpp:1127] Launching task 1 for framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.974869 6341 slave.cpp:3777] Checkpointing ExecutorInfo to '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/meta/slaves/20140805-012710-453759884-59014-5132-0/frameworks/20140805-012710-453759884-59014-5132-0000/executors/default/executor.info'
I0805 01:27:10.977088 6341 exec.cpp:131] Version: 0.20.0
I0805 01:27:10.977200 6341 slave.cpp:3892] Checkpointing TaskInfo to '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/meta/slaves/20140805-012710-453759884-59014-5132-0/frameworks/20140805-012710-453759884-59014-5132-0000/executors/default/runs/f0f637c1-bdd8-4c2c-821e-53a7b09e94d8/tasks/1/task.info'
I0805 01:27:10.977391 6323 exec.cpp:181] Executor started at: executor(63)@140.211.11.27:59014 with pid 5132
I0805 01:27:10.977454 6341 slave.cpp:1237] Queuing task '1' for executor default of framework '20140805-012710-453759884-59014-5132-0000
E0805 01:27:10.977515 6341 slave.cpp:2486] Container 'f0f637c1-bdd8-4c2c-821e-53a7b09e94d8' for executor 'default' of framework '20140805-012710-453759884-59014-5132-0000' failed to start: TaskInfo/ExecutorInfo not supported
I0805 01:27:10.977538 6341 slave.cpp:560] Successfully attached file '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/slaves/20140805-012710-453759884-59014-5132-0/frameworks/20140805-012710-453759884-59014-5132-0000/executors/default/runs/f0f637c1-bdd8-4c2c-821e-53a7b09e94d8'
I0805 01:27:10.977565 6341 slave.cpp:1748] Got registration for executor 'default' of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.977591 6341 slave.cpp:1833] Checkpointing executor pid 'executor(63)@140.211.11.27:59014' to '/tmp/StatusUpdateManagerTest_IgnoreDuplicateStatusUpdateAck_mVDVUV/meta/slaves/20140805-012710-453759884-59014-5132-0/frameworks/20140805-012710-453759884-59014-5132-0000/executors/default/runs/f0f637c1-bdd8-4c2c-821e-53a7b09e94d8/pids/libprocess.pid'
I0805 01:27:10.985060 6341 slave.cpp:1867] Flushing queued task 1 for executor 'default' of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.985139 6341 exec.cpp:205] Executor registered on slave 20140805-012710-453759884-59014-5132-0
I0805 01:27:10.987078 6341 exec.cpp:217] Executor::registered took 12813ns
I0805 01:27:10.987193 6341 exec.cpp:292] Executor asked to run task '1'
I0805 01:27:10.987285 6341 exec.cpp:301] Executor::launchTask took 23267ns
I0805 01:27:10.989233 6341 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.989389 6334 slave.cpp:2102] Handling status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 from executor(63)@140.211.11.27:59014
I0805 01:27:10.989488 6334 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.989500 6334 status_update_manager.cpp:499] Creating StatusUpdate stream for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.989656 6334 status_update_manager.hpp:342] Checkpointing UPDATE for status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:10.989735 6316 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 18.314574ms
I0805 01:27:10.989755 6316 replica.cpp:676] Persisted action at 4
I0805 01:27:10.989949 6316 replica.cpp:655] Replica received learned notice for position 4
I0805 01:27:11.011715 6316 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 21.74991ms
I0805 01:27:11.011766 6316 leveldb.cpp:401] Deleting ~2 keys from leveldb took 23322ns
I0805 01:27:11.011777 6316 replica.cpp:676] Persisted action at 4
I0805 01:27:11.011786 6316 replica.cpp:661] Replica learned TRUNCATE action at position 4
I0805 01:27:11.025672 6334 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 to master@140.211.11.27:59014
I0805 01:27:11.025837 6334 master.cpp:3130] Forwarding status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.025872 6334 master.cpp:3096] Status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 from slave 20140805-012710-453759884-59014-5132-0 at slave(200)@140.211.11.27:59014 (hemera.apache.org)
I0805 01:27:11.025903 6334 slave.cpp:2260] Status update manager successfully handled status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.025913 6334 slave.cpp:2266] Sending acknowledgement for status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 to executor(63)@140.211.11.27:59014
I0805 01:27:11.025982 6334 exec.cpp:338] Executor received status update acknowledgement 63a13ce9-3618-454b-92e9-5a136a779186 for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.037777 6334 master.cpp:121] No whitelist given. Advertising offers for all slaves
W0805 01:27:11.037813 6334 status_update_manager.cpp:477] Resending status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.037827 6334 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 to master@140.211.11.27:59014
I0805 01:27:11.037901 6334 master.cpp:3130] Forwarding status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.037940 6334 master.cpp:3096] Status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 from slave 20140805-012710-453759884-59014-5132-0 at slave(200)@140.211.11.27:59014 (hemera.apache.org)
I0805 01:27:11.038008 6334 sched.cpp:637] Scheduler::statusUpdate took 12709ns
I0805 01:27:11.038053 6334 master.cpp:2611] Forwarding status update acknowledgement 63a13ce9-3618-454b-92e9-5a136a779186 for task 1 of framework 20140805-012710-453759884-59014-5132-0000 to slave 20140805-012710-453759884-59014-5132-0 at slave(200)@140.211.11.27:59014 (hemera.apache.org)
I0805 01:27:11.038122 6334 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.038138 6334 status_update_manager.hpp:342] Checkpointing ACK for status update TASK_RUNNING (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.038326 6346 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 27648ns
I0805 01:27:11.063119 6334 slave.cpp:1688] Status update manager successfully handled status update acknowledgement (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.083701 6336 exec.cpp:524] Executor sending status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.083793 6336 slave.cpp:2102] Handling status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 from executor(63)@140.211.11.27:59014
I0805 01:27:11.083814 6336 slave.cpp:3825] Terminating task 1
I0805 01:27:11.083968 6336 status_update_manager.cpp:320] Received status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.083984 6336 status_update_manager.hpp:342] Checkpointing UPDATE for status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.111145 6336 status_update_manager.cpp:373] Forwarding status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 to master@140.211.11.27:59014
I0805 01:27:11.111274 6336 master.cpp:3130] Forwarding status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.111310 6336 master.cpp:3096] Status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 from slave 20140805-012710-453759884-59014-5132-0 at slave(200)@140.211.11.27:59014 (hemera.apache.org)
I0805 01:27:11.111367 6336 master.hpp:806] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140805-012710-453759884-59014-5132-0 (hemera.apache.org)
I0805 01:27:11.111469 6336 slave.cpp:2260] Status update manager successfully handled status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.111479 6336 slave.cpp:2266] Sending acknowledgement for status update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000 to executor(63)@140.211.11.27:59014
I0805 01:27:11.111592 6336 hierarchical_allocator_process.hpp:635] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20140805-012710-453759884-59014-5132-0 from framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.111634 6336 exec.cpp:338] Executor received status update acknowledgement b9482e58-bc93-457c-af75-c7c3ab6f87bd for task 1 of framework 20140805-012710-453759884-59014-5132-0000
I0805 01:27:11.115336 6335 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
W0805 01:27:11.115360 6335 status_update_manager.hpp:259] Duplicate status update acknowledgment (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for update TASK_FINISHED (UUID: b9482e58-bc93-457c-af75-c7c3ab6f87bd) for task 1 of framework 20140805-012710-453759884-59014-5132-0000
E0805 01:27:11.115404 6335 slave.cpp:1681] Failed to handle status update acknowledgement (UUID: 63a13ce9-3618-454b-92e9-5a136a779186) for task 1 of framework 20140805-012710-453759884-59014-5132-0000: Duplicate acknowledgement
I0805 01:27:11.125375 5132 master.cpp:624] Master terminating
I0805 01:27:11.125665 6335 sched.cpp:747] Stopping framework '20140805-012710-453759884-59014-5132-0000'
I0805 01:27:11.125718 6335 slave.cpp:2371] master@140.211.11.27:59014 exited
W0805 01:27:11.125728 6335 slave.cpp:2374] Master disconnected! Waiting for a new master to be elected
I0805 01:27:11.134173 5132 slave.cpp:483] Slave terminating
[ OK ] StatusUpdateManagerTest.IgnoreDuplicateStatusUpdateAck (435 ms)
[----------] 7 tests from StatusUpdateManagerTest (3356 ms total)
[----------] 2 tests from SorterTest
[ RUN ] SorterTest.WDRFSorter
[ OK ] SorterTest.WDRFSorter (0 ms)
[ RUN ] SorterTest.DRFSorter
[ OK ] SorterTest.DRFSorter (0 ms)
[----------] 2 tests from SorterTest (0 ms total)
[----------] 7 tests from InMemoryStateTest
[ RUN ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
[ OK ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch (22 ms)
[ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch
[ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch (30 ms)
[ RUN ] InMemoryStateTest.Names
[ OK ] InMemoryStateTest.Names (14 ms)
[ RUN ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
[ OK ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch (14 ms)
[ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge
[ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge (22 ms)
[ RUN ] InMemoryStateTest.FetchAndStoreAndFetch
[ OK ] InMemoryStateTest.FetchAndStoreAndFetch (7 ms)
[ RUN ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[ OK ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch (28 ms)
[----------] 7 tests from InMemoryStateTest (138 ms total)
[----------] Global test environment tear-down
[==========] 394 tests from 64 test cases ran. (420444 ms total)
[ PASSED ] 393 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ExamplesTest.PythonFramework
1 FAILED TEST
YOU HAVE 5 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build'
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Ubuntu-distcheck #249
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/249/changes>