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/03/12 06:08:35 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1691
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1691/changes>
Changes:
[benjamin.hindman] Adds await on a tuple of futures.
------------------------------------------
[...truncated 26115 lines...]
I0312 05:08:33.612222 10006 master.cpp:2382] Authenticating framework at scheduler(135)@67.195.138.60:56863
I0312 05:08:33.612306 10004 authenticator.hpp:148] Creating new server SASL connection
I0312 05:08:33.612416 10005 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0312 05:08:33.612434 10005 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0312 05:08:33.612467 10005 authenticator.hpp:254] Received SASL authentication start
I0312 05:08:33.612520 10005 authenticator.hpp:342] Authentication requires more steps
I0312 05:08:33.612550 10005 authenticatee.hpp:265] Received SASL authentication step
I0312 05:08:33.612591 10005 authenticator.hpp:282] Received SASL authentication step
I0312 05:08:33.612608 10005 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
I0312 05:08:33.612617 10005 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0312 05:08:33.612632 10005 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0312 05:08:33.612643 10005 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
I0312 05:08:33.612650 10005 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0312 05:08:33.612656 10005 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0312 05:08:33.612669 10005 authenticator.hpp:334] Authentication success
I0312 05:08:33.612707 10004 authenticatee.hpp:305] Authentication success
I0312 05:08:33.612720 10005 master.cpp:2422] Successfully authenticated framework at scheduler(135)@67.195.138.60:56863
I0312 05:08:33.612800 10004 sched.cpp:341] Successfully authenticated with master master@67.195.138.60:56863
I0312 05:08:33.612814 10004 sched.cpp:460] Sending registration request to master@67.195.138.60:56863
I0312 05:08:33.612887 10006 master.cpp:919] Re-registering framework 2014-03-12-05:08:33-1015726915-56863-9979-0000 at scheduler(135)@67.195.138.60:56863
I0312 05:08:33.612987 10004 sched.cpp:391] Framework registered with 2014-03-12-05:08:33-1015726915-56863-9979-0000
I0312 05:08:33.613010 10004 sched.cpp:405] Scheduler::registered took 10521ns
I0312 05:08:33.613047 10004 hierarchical_allocator_process.hpp:332] Added framework 2014-03-12-05:08:33-1015726915-56863-9979-0000
I0312 05:08:33.613056 10004 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0312 05:08:33.613062 10004 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 7004ns
I0312 05:08:34.461799 10006 master.cpp:1980] Attempting to re-register slave 2014-03-12-05:08:33-1015726915-56863-9979-0 at slave(142)@67.195.138.60:56863 (janus.apache.org)
I0312 05:08:34.461853 10006 master.cpp:2869] Adding slave 2014-03-12-05:08:33-1015726915-56863-9979-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]
I0312 05:08:34.462031 10006 master.hpp:428] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 2014-03-12-05:08:33-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.462172 10006 slave.cpp:498] Re-registered with master master@67.195.138.60:56863
I0312 05:08:34.462193 10006 slave.cpp:1238] Updating framework 2014-03-12-05:08:33-1015726915-56863-9979-0000 pid to scheduler(135)@67.195.138.60:56863
I0312 05:08:34.462424 10006 hierarchical_allocator_process.hpp:445] Added slave 2014-03-12-05:08:33-1015726915-56863-9979-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] available)
I0312 05:08:34.462489 10006 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 2014-03-12-05:08:33-1015726915-56863-9979-0 to framework 2014-03-12-05:08:33-1015726915-56863-9979-0000
I0312 05:08:34.462609 10006 hierarchical_allocator_process.hpp:708] Performed allocation for slave 2014-03-12-05:08:33-1015726915-56863-9979-0 in 150521ns
I0312 05:08:34.462692 9999 master.hpp:456] Adding offer 2014-03-12-05:08:33-1015726915-56863-9979-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 2014-03-12-05:08:33-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.462755 9999 master.cpp:2337] Sending 1 offers to framework 2014-03-12-05:08:33-1015726915-56863-9979-0000
I0312 05:08:34.466513 9999 sched.cpp:528] Scheduler::resourceOffers took 3.594509ms
I0312 05:08:34.466629 9999 sched.cpp:730] Stopping framework '2014-03-12-05:08:33-1015726915-56863-9979-0000'
I0312 05:08:34.466655 10001 master.cpp:530] Master terminating
I0312 05:08:34.466713 10001 master.hpp:446] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 2014-03-12-05:08:33-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.466802 10001 master.hpp:466] Removing offer 2014-03-12-05:08:33-1015726915-56863-9979-0 with resources cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] on slave 2014-03-12-05:08:33-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.470553 10001 slave.cpp:1881] master@67.195.138.60:56863 exited
W0312 05:08:34.470568 10001 slave.cpp:1884] Master disconnected! Waiting for a new master to be elected
I0312 05:08:34.470655 10001 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):1; mem(*):500) on slave 2014-03-12-05:08:33-1015726915-56863-9979-0 from framework 2014-03-12-05:08:33-1015726915-56863-9979-0000
I0312 05:08:34.474689 9979 contender.cpp:182] Now cancelling the membership: 1
I0312 05:08:34.474776 10005 group.cpp:616] Trying to remove '/znode/info_0000000001' in ZooKeeper
I0312 05:08:34.518817 10004 hierarchical_allocator_process.hpp:752] Offering cpus(*):1; mem(*):500 on slave 2014-03-12-05:08:33-1015726915-56863-9979-0 to framework 2014-03-12-05:08:33-1015726915-56863-9979-0000
I0312 05:08:34.518960 10004 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 230885ns
2014-03-12 05:08:34,600:9979(0x2b595ef237c0):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x144b4b129680005 to [127.0.0.1:49218]
I0312 05:08:34.600425 10004 detector.cpp:122] The current leader (id=1) is lost
I0312 05:08:34.600455 10004 detector.cpp:134] Detected a new leader: None
2014-03-12 05:08:34,600:9979(0x2b595ef237c0):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x144b4b129680004 to [127.0.0.1:49218]
I0312 05:08:34.601076 10001 detector.cpp:122] The current leader (id=1) is lost
I0312 05:08:34.601100 9979 slave.cpp:295] Slave terminating
I0312 05:08:34.601109 10001 detector.cpp:134] Detected a new leader: None
I0312 05:08:34.601153 9979 slave.cpp:1068] Asked to shut down framework 2014-03-12-05:08:33-1015726915-56863-9979-0000 by @0.0.0.0:0
I0312 05:08:34.601219 9979 slave.cpp:1093] Shutting down framework 2014-03-12-05:08:33-1015726915-56863-9979-0000
I0312 05:08:34.601245 9979 slave.cpp:2369] Shutting down executor 'default' of framework 2014-03-12-05:08:33-1015726915-56863-9979-0000
I0312 05:08:34.601276 10001 exec.cpp:378] Executor asked to shutdown
I0312 05:08:34.601307 10001 exec.cpp:393] Executor::shutdown took 19341ns
2014-03-12 05:08:34,601:9979(0x2b595ef237c0):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x144b4b129680002 to [127.0.0.1:49218]
2014-03-12 05:08:34,602:9979(0x2b595ef237c0):ZOO_INFO@zookeeper_close@2505: Closing zookeeper sessionId=0x144b4b129680003 to [127.0.0.1:49218]
[ OK ] AllocatorZooKeeperTest/0.FrameworkReregistersFirst (1345 ms)
[ RUN ] AllocatorZooKeeperTest/0.SlaveReregistersFirst
2014-03-12 05:08:34,603:9979(0x2b595f52a700):ZOO_INFO@log_env@712: Client environment:zookeeper.version=zookeeper C client 3.4.5
2014-03-12 05:08:34,603:9979(0x2b595fd2e700):ZOO_INFO@log_env@712: Client environment:zookeeper.version=zookeeper C client 3.4.5
2014-03-12 05:08:34,603:9979(0x2b595fd2e700):ZOO_INFO@log_env@716: Client environment:host.name=janus
2014-03-12 05:08:34,603:9979(0x2b595fd2e700):ZOO_INFO@log_env@723: Client environment:os.name=Linux
2014-03-12 05:08:34,603:9979(0x2b595fd2e700):ZOO_INFO@log_env@724: Client environment:os.arch=3.2.0-57-generic
2014-03-12 05:08:34,603:9979(0x2b595fd2e700):ZOO_INFO@log_env@725: Client environment:os.version=#87-Ubuntu SMP Tue Nov 12 21:35:10 UTC 2013
2014-03-12 05:08:34,603:9979(0x2b595f52a700):ZOO_INFO@log_env@716: Client environment:host.name=janus
2014-03-12 05:08:34,603:9979(0x2b595f52a700):ZOO_INFO@log_env@723: Client environment:os.name=Linux
2014-03-12 05:08:34,603:9979(0x2b595f52a700):ZOO_INFO@log_env@724: Client environment:os.arch=3.2.0-57-generic
2014-03-12 05:08:34,603:9979(0x2b595f52a700):ZOO_INFO@log_env@725: Client environment:os.version=#87-Ubuntu SMP Tue Nov 12 21:35:10 UTC 2013
I0312 05:08:34.603503 9979 master.cpp:254] Master ID: 2014-03-12-05:08:34-1015726915-56863-9979 Hostname: janus.apache.org
2014-03-12 05:08:34,603:9979(0x2b595fd2e700):ZOO_INFO@log_env@733: Client environment:user.name=(null)
2014-03-12 05:08:34,603:9979(0x2b595f52a700):ZOO_INFO@log_env@733: Client environment:user.name=(null)
I0312 05:08:34.603863 10003 master.cpp:264] Master started on 67.195.138.60:56863
I0312 05:08:34.603916 10003 master.cpp:267] Master only allowing authenticated frameworks to register!
2014-03-12 05:08:34,603:9979(0x2b595fd2e700):ZOO_INFO@log_env@741: Client environment:user.home=/home/jenkins
2014-03-12 05:08:34,604:9979(0x2b595fd2e700):ZOO_INFO@log_env@753: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src>
2014-03-12 05:08:34,604:9979(0x2b595fd2e700):ZOO_INFO@zookeeper_init@786: Initiating client connection, host=127.0.0.1:49218 sessionTimeout=10000 watcher=0x2b595c67f6b0 sessionId=0 sessionPasswd=<null> context=0x2b59740196e0 flags=0
2014-03-12 05:08:34,604:9979(0x2b595f52a700):ZOO_INFO@log_env@741: Client environment:user.home=/home/jenkins
2014-03-12 05:08:34,604:9979(0x2b595f52a700):ZOO_INFO@log_env@753: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src>
2014-03-12 05:08:34,604:9979(0x2b595f52a700):ZOO_INFO@zookeeper_init@786: Initiating client connection, host=127.0.0.1:49218 sessionTimeout=10000 watcher=0x2b595c67f6b0 sessionId=0 sessionPasswd=<null> context=0x2b597c016670 flags=0
2014-03-12 05:08:34,604:9979(0x2b5aecf59700):ZOO_INFO@check_events@1703: initiated connection to server [127.0.0.1:49218]
2014-03-12 05:08:34,604:9979(0x2b5aedba7700):ZOO_INFO@check_events@1703: initiated connection to server [127.0.0.1:49218]
I0312 05:08:34.604591 10004 hierarchical_allocator_process.hpp:302] Initializing hierarchical allocator process with master : master@67.195.138.60:56863
I0312 05:08:34.604667 10002 master.cpp:98] No whitelist given. Advertising offers for all slaves
I0312 05:08:34.605010 10006 contender.cpp:127] Joining the ZK group
2014-03-12 05:08:34,653:9979(0x2b5aecf59700):ZOO_INFO@check_events@1750: session establishment complete on server [127.0.0.1:49218], sessionId=0x144b4b129680006, negotiated timeout=10000
I0312 05:08:34.653378 10005 group.cpp:310] Group process ((2264)@67.195.138.60:56863) connected to ZooKeeper
I0312 05:08:34.653395 10005 group.cpp:778] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0)
I0312 05:08:34.653405 10005 group.cpp:382] Trying to create path '/znode' in ZooKeeper
2014-03-12 05:08:34,653:9979(0x2b5aedba7700):ZOO_INFO@check_events@1750: session establishment complete on server [127.0.0.1:49218], sessionId=0x144b4b129680007, negotiated timeout=10000
I0312 05:08:34.653887 9999 group.cpp:310] Group process ((2262)@67.195.138.60:56863) connected to ZooKeeper
I0312 05:08:34.653904 9999 group.cpp:778] Syncing group operations: queue size (joins, cancels, datas) = (1, 0, 0)
I0312 05:08:34.653913 9999 group.cpp:382] Trying to create path '/znode' in ZooKeeper
I0312 05:08:34.666240 10005 contender.cpp:243] New candidate (id='2') has entered the contest for leadership
I0312 05:08:34.666828 10003 detector.cpp:134] Detected a new leader: (id='2')
I0312 05:08:34.666916 10003 group.cpp:655] Trying to get '/znode/info_0000000002' in ZooKeeper
I0312 05:08:34.667685 10006 detector.cpp:377] A new leading master (UPID=master@67.195.138.60:56863) is detected
I0312 05:08:34.667743 10003 master.cpp:773] The newly elected leader is master@67.195.138.60:56863 with id 2014-03-12-05:08:34-1015726915-56863-9979
I0312 05:08:34.667763 10003 master.cpp:783] Elected as the leading master!
2014-03-12 05:08:34,668:9979(0x2b595f128700):ZOO_INFO@log_env@712: Client environment:zookeeper.version=zookeeper C client 3.4.5
I0312 05:08:34.668591 10005 slave.cpp:120] Slave started on 143)@67.195.138.60:56863
2014-03-12 05:08:34,668:9979(0x2b595f128700):ZOO_INFO@log_env@716: Client environment:host.name=janus
2014-03-12 05:08:34,669:9979(0x2b595f128700):ZOO_INFO@log_env@723: Client environment:os.name=Linux
2014-03-12 05:08:34,669:9979(0x2b595f128700):ZOO_INFO@log_env@724: Client environment:os.arch=3.2.0-57-generic
2014-03-12 05:08:34,669:9979(0x2b595f128700):ZOO_INFO@log_env@725: Client environment:os.version=#87-Ubuntu SMP Tue Nov 12 21:35:10 UTC 2013
I0312 05:08:34.668987 9979 sched.cpp:121] Version: 0.19.0
2014-03-12 05:08:34,668:9979(0x2b595fb2d700):ZOO_INFO@log_env@712: Client environment:zookeeper.version=zookeeper C client 3.4.5
2014-03-12 05:08:34,669:9979(0x2b595fb2d700):ZOO_INFO@log_env@716: Client environment:host.name=janus
2014-03-12 05:08:34,669:9979(0x2b595fb2d700):ZOO_INFO@log_env@723: Client environment:os.name=Linux
2014-03-12 05:08:34,669:9979(0x2b595fb2d700):ZOO_INFO@log_env@724: Client environment:os.arch=3.2.0-57-generic
2014-03-12 05:08:34,669:9979(0x2b595fb2d700):ZOO_INFO@log_env@725: Client environment:os.version=#87-Ubuntu SMP Tue Nov 12 21:35:10 UTC 2013
I0312 05:08:34.668978 10005 slave.cpp:130] Slave resources: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]
2014-03-12 05:08:34,669:9979(0x2b595f128700):ZOO_INFO@log_env@733: Client environment:user.name=(null)
2014-03-12 05:08:34,669:9979(0x2b595fb2d700):ZOO_INFO@log_env@733: Client environment:user.name=(null)
I0312 05:08:34.669440 10005 slave.cpp:158] Slave hostname: janus.apache.org
I0312 05:08:34.669450 10005 slave.cpp:159] Slave checkpoint: false
2014-03-12 05:08:34,669:9979(0x2b595fb2d700):ZOO_INFO@log_env@741: Client environment:user.home=/home/jenkins
2014-03-12 05:08:34,669:9979(0x2b595f128700):ZOO_INFO@log_env@741: Client environment:user.home=/home/jenkins
2014-03-12 05:08:34,669:9979(0x2b595fb2d700):ZOO_INFO@log_env@753: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src>
2014-03-12 05:08:34,669:9979(0x2b595fb2d700):ZOO_INFO@zookeeper_init@786: Initiating client connection, host=127.0.0.1:49218 sessionTimeout=10000 watcher=0x2b595c67f6b0 sessionId=0 sessionPasswd=<null> context=0x2b5980011510 flags=0
2014-03-12 05:08:34,669:9979(0x2b595f128700):ZOO_INFO@log_env@753: Client environment:user.dir=<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src>
2014-03-12 05:08:34,669:9979(0x2b595f128700):ZOO_INFO@zookeeper_init@786: Initiating client connection, host=127.0.0.1:49218 sessionTimeout=10000 watcher=0x2b595c67f6b0 sessionId=0 sessionPasswd=<null> context=0x2b5978004350 flags=0
2014-03-12 05:08:34,669:9979(0x2b5aedda8700):ZOO_INFO@check_events@1703: initiated connection to server [127.0.0.1:49218]
2014-03-12 05:08:34,669:9979(0x2b5aed15a700):ZOO_INFO@check_events@1703: initiated connection to server [127.0.0.1:49218]
I0312 05:08:34.669826 9999 state.cpp:33] Recovering state from '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_uIS0xT/meta'
I0312 05:08:34.670723 10005 status_update_manager.cpp:193] Recovering status update manager
I0312 05:08:34.670898 10005 slave.cpp:2707] Finished recovery
2014-03-12 05:08:34,699:9979(0x2b5aedda8700):ZOO_INFO@check_events@1750: session establishment complete on server [127.0.0.1:49218], sessionId=0x144b4b129680008, negotiated timeout=10000
I0312 05:08:34.700760 10005 group.cpp:310] Group process ((2274)@67.195.138.60:56863) connected to ZooKeeper
I0312 05:08:34.700781 10005 group.cpp:778] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0)
I0312 05:08:34.700790 10005 group.cpp:382] Trying to create path '/znode' in ZooKeeper
2014-03-12 05:08:34,733:9979(0x2b5aed15a700):ZOO_INFO@check_events@1750: session establishment complete on server [127.0.0.1:49218], sessionId=0x144b4b129680009, negotiated timeout=10000
I0312 05:08:34.734194 9999 group.cpp:310] Group process ((2268)@67.195.138.60:56863) connected to ZooKeeper
I0312 05:08:34.734215 9999 group.cpp:778] Syncing group operations: queue size (joins, cancels, datas) = (0, 0, 0)
I0312 05:08:34.734225 9999 group.cpp:382] Trying to create path '/znode' in ZooKeeper
I0312 05:08:34.734604 10003 detector.cpp:134] Detected a new leader: (id='2')
I0312 05:08:34.734733 10001 group.cpp:655] Trying to get '/znode/info_0000000002' in ZooKeeper
I0312 05:08:34.735414 10003 detector.cpp:134] Detected a new leader: (id='2')
I0312 05:08:34.735573 9999 group.cpp:655] Trying to get '/znode/info_0000000002' in ZooKeeper
I0312 05:08:34.735676 10001 detector.cpp:377] A new leading master (UPID=master@67.195.138.60:56863) is detected
I0312 05:08:34.735790 10005 sched.cpp:217] New master detected at master@67.195.138.60:56863
I0312 05:08:34.735816 10005 sched.cpp:267] Authenticating with master master@67.195.138.60:56863
I0312 05:08:34.735894 10001 authenticatee.hpp:128] Creating new client SASL connection
I0312 05:08:34.736050 10001 master.cpp:2382] Authenticating framework at scheduler(136)@67.195.138.60:56863
I0312 05:08:34.736204 9999 authenticator.hpp:148] Creating new server SASL connection
I0312 05:08:34.736232 10001 detector.cpp:377] A new leading master (UPID=master@67.195.138.60:56863) is detected
I0312 05:08:34.736305 10004 slave.cpp:405] New master detected at master@67.195.138.60:56863
I0312 05:08:34.736351 10001 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0312 05:08:34.736359 9999 status_update_manager.cpp:167] New master detected at master@67.195.138.60:56863
I0312 05:08:34.736364 10004 slave.cpp:430] Detecting new master
I0312 05:08:34.736439 10003 master.cpp:1885] Attempting to register slave on janus.apache.org at slave(143)@67.195.138.60:56863
I0312 05:08:34.736446 10001 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0312 05:08:34.736649 10003 master.cpp:2869] Adding slave 2014-03-12-05:08:34-1015726915-56863-9979-0 at janus.apache.org with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]
I0312 05:08:34.736757 10001 authenticator.hpp:254] Received SASL authentication start
I0312 05:08:34.736917 10003 slave.cpp:448] Registered with master master@67.195.138.60:56863; given slave ID 2014-03-12-05:08:34-1015726915-56863-9979-0
I0312 05:08:34.737016 10001 authenticator.hpp:342] Authentication requires more steps
I0312 05:08:34.737030 10004 hierarchical_allocator_process.hpp:445] Added slave 2014-03-12-05:08:34-1015726915-56863-9979-0 (janus.apache.org) with cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] available)
I0312 05:08:34.737210 10004 hierarchical_allocator_process.hpp:708] Performed allocation for slave 2014-03-12-05:08:34-1015726915-56863-9979-0 in 7623ns
I0312 05:08:34.737184 10001 authenticatee.hpp:265] Received SASL authentication step
I0312 05:08:34.737267 10001 authenticator.hpp:282] Received SASL authentication step
I0312 05:08:34.737357 10001 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
I0312 05:08:34.737368 10001 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0312 05:08:34.737385 10001 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0312 05:08:34.737396 10001 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
I0312 05:08:34.737403 10001 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0312 05:08:34.737409 10001 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0312 05:08:34.737422 10001 authenticator.hpp:334] Authentication success
I0312 05:08:34.737453 10001 authenticatee.hpp:305] Authentication success
I0312 05:08:34.737473 10003 master.cpp:2422] Successfully authenticated framework at scheduler(136)@67.195.138.60:56863
I0312 05:08:34.737586 10001 sched.cpp:341] Successfully authenticated with master master@67.195.138.60:56863
I0312 05:08:34.737722 10001 sched.cpp:460] Sending registration request to master@67.195.138.60:56863
I0312 05:08:34.737835 10002 master.cpp:831] Received registration request from scheduler(136)@67.195.138.60:56863
I0312 05:08:34.737864 10002 master.cpp:849] Registering framework 2014-03-12-05:08:34-1015726915-56863-9979-0000 at scheduler(136)@67.195.138.60:56863
I0312 05:08:34.737931 10001 sched.cpp:391] Framework registered with 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.737993 10001 sched.cpp:405] Scheduler::registered took 11420ns
I0312 05:08:34.737951 10002 hierarchical_allocator_process.hpp:332] Added framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.738066 10002 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 2014-03-12-05:08:34-1015726915-56863-9979-0 to framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.738208 10002 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 169548ns
I0312 05:08:34.738293 10005 master.hpp:456] Adding offer 2014-03-12-05:08:34-1015726915-56863-9979-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 2014-03-12-05:08:34-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.738363 10005 master.cpp:2337] Sending 1 offers to framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.738693 10002 sched.cpp:528] Scheduler::resourceOffers took 183657ns
I0312 05:08:34.738903 10005 master.hpp:466] Removing offer 2014-03-12-05:08:34-1015726915-56863-9979-0 with resources cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000] on slave 2014-03-12-05:08:34-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.738965 10005 master.cpp:1611] Processing reply for offers: [ 2014-03-12-05:08:34-1015726915-56863-9979-0 ] on slave 2014-03-12-05:08:34-1015726915-56863-9979-0 (janus.apache.org) for framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.739049 10005 master.hpp:428] Adding task 0 with resources cpus(*):1; mem(*):500 on slave 2014-03-12-05:08:34-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.739080 10005 master.cpp:2506] Launching task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000 with resources cpus(*):1; mem(*):500 on slave 2014-03-12-05:08:34-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.739176 10002 slave.cpp:666] Got assigned task 0 for framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.739250 10006 hierarchical_allocator_process.hpp:547] Framework 2014-03-12-05:08:34-1015726915-56863-9979-0000 left cpus(*):1; mem(*):524; disk(*):23038; ports(*):[31000-32000] unused on slave 2014-03-12-05:08:34-1015726915-56863-9979-0
I0312 05:08:34.739361 10002 slave.cpp:775] Launching task 0 for framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.739382 10006 hierarchical_allocator_process.hpp:590] Framework 2014-03-12-05:08:34-1015726915-56863-9979-0000 filtered slave 2014-03-12-05:08:34-1015726915-56863-9979-0 for 5secs
#
# A fatal error has been detected by the Java Runtime Environment:
#
# SIGSEGV (0xb) at pc=0x00002b595dd08f5c, pid=9979, tid=47663632295680
#
# JRE version: 6.0_20-b02
# Java VM: Java HotSpot(TM) 64-Bit Server VM (16.3-b01 mixed mode linux-amd64 )
# Problematic frame:
# C [libc.so.6+0x3af5c] getenv+0x9c
#
# An error report file with more information is saved as:
# <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src/hs_err_pid9979.log>
I0312 05:08:34.743176 10002 exec.cpp:131] Version: 0.19.0
I0312 05:08:34.743237 9999 exec.cpp:181] Executor started at: executor(47)@67.195.138.60:56863 with pid 9979
I0312 05:08:34.743278 10002 slave.cpp:885] Queuing task '0' for executor default of framework '2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.743319 10002 slave.cpp:366] Successfully attached file '/tmp/AllocatorZooKeeperTest_0_SlaveReregistersFirst_uIS0xT/slaves/2014-03-12-05:08:34-1015726915-56863-9979-0/frameworks/2014-03-12-05:08:34-1015726915-56863-9979-0000/executors/default/runs/8c69ee56-f9a5-4e12-8a41-c90b90e825b6'
I0312 05:08:34.743345 10002 slave.cpp:2026] Monitoring executor 'default' of framework '2014-03-12-05:08:34-1015726915-56863-9979-0000' in container '8c69ee56-f9a5-4e12-8a41-c90b90e825b6'
I0312 05:08:34.743408 10002 slave.cpp:1357] Got registration for executor 'default' of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.743541 10002 slave.cpp:1475] Flushing queued task 0 for executor 'default' of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.743579 10004 exec.cpp:205] Executor registered on slave 2014-03-12-05:08:34-1015726915-56863-9979-0
I0312 05:08:34.745177 10004 exec.cpp:217] Executor::registered took 12725ns
I0312 05:08:34.745236 10004 exec.cpp:292] Executor asked to run task '0'
I0312 05:08:34.745273 10004 exec.cpp:301] Executor::launchTask took 24028ns
I0312 05:08:34.746829 10004 exec.cpp:524] Executor sending status update TASK_RUNNING (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.746913 10004 slave.cpp:1684] Handling status update TASK_RUNNING (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000 from executor(47)@67.195.138.60:56863
I0312 05:08:34.747005 10006 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.747025 10006 status_update_manager.cpp:499] Creating StatusUpdate stream for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.747100 10006 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000 to master@67.195.138.60:56863
I0312 05:08:34.747202 10005 slave.cpp:1800] Status update manager successfully handled status update TASK_RUNNING (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.747218 10005 slave.cpp:1806] Sending acknowledgement for status update TASK_RUNNING (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000 to executor(47)@67.195.138.60:56863
I0312 05:08:34.747278 10002 exec.cpp:338] Executor received status update acknowledgement ee73a6c1-80b1-479e-bfe1-933f5bb72ea8 for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.747303 10004 master.cpp:2069] Status update TASK_RUNNING (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000 from slave(143)@67.195.138.60:56863
I0312 05:08:34.747365 10005 sched.cpp:619] Scheduler::statusUpdate took 21968ns
I0312 05:08:34.747547 9979 master.cpp:530] Master terminating
I0312 05:08:34.747546 10002 status_update_manager.cpp:398] Received status update acknowledgement (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.747618 9979 master.hpp:446] Removing task 0 with resources cpus(*):1; mem(*):500 on slave 2014-03-12-05:08:34-1015726915-56863-9979-0 (janus.apache.org)
I0312 05:08:34.747658 10005 slave.cpp:1297] Status update manager successfully handled status update acknowledgement (UUID: ee73a6c1-80b1-479e-bfe1-933f5bb72ea8) for task 0 of framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.747756 10005 hierarchical_allocator_process.hpp:637] Recovered cpus(*):1; mem(*):500 (total allocatable: cpus(*):2; mem(*):1024; disk(*):23038; ports(*):[31000-32000]) on slave 2014-03-12-05:08:34-1015726915-56863-9979-0 from framework 2014-03-12-05:08:34-1015726915-56863-9979-0000
I0312 05:08:34.747879 10006 slave.cpp:1881] master@67.195.138.60:56863 exited
W0312 05:08:34.747897 10006 slave.cpp:1884] Master disconnected! Waiting for a new master to be elected
#
# If you would like to submit a bug report, please visit:
# http://java.sun.com/webapps/bugreport/crash.jsp
#
make[3]: *** [check-local] Aborted
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
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME #1692
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/1692/>