See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2368/changes>
Changes:
[yujie.jay] Replaced PKG_CHECK_MODULES with AC_CHECKs in configure.ac.
[yujie.jay] Replaced PKG_CHECK_MODULES with AC_CHECKs in libprocess
configure.ac.
------------------------------------------
[...truncated 23561 lines...]
I0827 20:36:23.616559 18037 group.cpp:787] Syncing group operations: queue size
(joins, cancels, datas) = (0, 0, 0)
I0827 20:36:23.616574 18037 group.cpp:385] Trying to create path
'/znode/log_replicas' in ZooKeeper
I0827 20:36:23.616781 18034 group.cpp:313] Group process
(group(4)@67.195.81.186:49273) connected to ZooKeeper
I0827 20:36:23.616821 18034 group.cpp:787] Syncing group operations: queue size
(joins, cancels, datas) = (0, 0, 0)
I0827 20:36:23.616833 18034 group.cpp:385] Trying to create path '/znode' in
ZooKeeper
2014-08-27 20:36:23,617:18008(0x2aca63df6700):ZOO_INFO@check_events@1750:
session establishment complete on server [127.0.0.1:56082],
sessionId=0x148192eff150003, negotiated timeout=10000
I0827 20:36:23.617689 18031 group.cpp:313] Group process
(group(2)@67.195.81.186:49273) connected to ZooKeeper
I0827 20:36:23.617713 18031 group.cpp:787] Syncing group operations: queue size
(joins, cancels, datas) = (1, 0, 0)
I0827 20:36:23.617725 18031 group.cpp:385] Trying to create path
'/znode/log_replicas' in ZooKeeper
I0827 20:36:23.638625 18029 contender.cpp:247] New candidate (id='0') has
entered the contest for leadership
I0827 20:36:23.641109 18034 group.cpp:714] Found non-sequence node
'log_replicas' at '/znode' in ZooKeeper
I0827 20:36:23.641260 18035 detector.cpp:138] Detected a new leader: (id='0')
I0827 20:36:23.641393 18026 group.cpp:714] Found non-sequence node
'log_replicas' at '/znode' in ZooKeeper
I0827 20:36:23.641561 18022 group.cpp:658] Trying to get
'/znode/info_0000000000' in ZooKeeper
I0827 20:36:23.645103 18035 detector.cpp:426] A new leading master
(UPID=master@67.195.81.186:49273) is detected
I0827 20:36:23.645179 18024 master.cpp:1205] The newly elected leader is
master@67.195.81.186:49273 with id 20140827-203623-3125920579-49273-18008
I0827 20:36:23.645206 18024 master.cpp:1218] Elected as the leading master!
I0827 20:36:23.645217 18024 master.cpp:1036] Recovering from registrar
I0827 20:36:23.645447 18030 registrar.cpp:313] Recovering registrar
I0827 20:36:23.645555 18029 network.hpp:423] ZooKeeper group memberships changed
I0827 20:36:23.645757 18030 group.cpp:658] Trying to get
'/znode/log_replicas/0000000000' in ZooKeeper
I0827 20:36:23.645807 18036 log.cpp:656] Attempting to start the writer
I0827 20:36:23.646709 18027 replica.cpp:474] Replica received implicit promise
request with proposal 1
I0827 20:36:23.647060 18028 network.hpp:461] ZooKeeper group PIDs: {
log-replica(134)@67.195.81.186:49273 }
I0827 20:36:23.647233 18027 leveldb.cpp:306] Persisting metadata (8 bytes) to
leveldb took 499696ns
I0827 20:36:23.647254 18027 replica.cpp:342] Persisted promised to 1
I0827 20:36:23.647662 18026 coordinator.cpp:230] Coordinator attemping to fill
missing position
I0827 20:36:23.648377 18027 replica.cpp:375] Replica received explicit promise
request for position 0 with proposal 2
I0827 20:36:23.648510 18027 leveldb.cpp:343] Persisting action (8 bytes) to
leveldb took 108878ns
I0827 20:36:23.648530 18027 replica.cpp:676] Persisted action at 0
I0827 20:36:23.649034 18033 replica.cpp:508] Replica received write request for
position 0
I0827 20:36:23.649071 18033 leveldb.cpp:438] Reading position from leveldb took
16241ns
I0827 20:36:23.649427 18033 leveldb.cpp:343] Persisting action (14 bytes) to
leveldb took 334420ns
I0827 20:36:23.649446 18033 replica.cpp:676] Persisted action at 0
I0827 20:36:23.649771 18036 replica.cpp:655] Replica received learned notice
for position 0
I0827 20:36:23.649911 18036 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 117325ns
I0827 20:36:23.649940 18036 replica.cpp:676] Persisted action at 0
I0827 20:36:23.649955 18036 replica.cpp:661] Replica learned NOP action at
position 0
I0827 20:36:23.650184 18036 log.cpp:672] Writer started with ending position 0
I0827 20:36:23.650632 18029 leveldb.cpp:438] Reading position from leveldb took
11101ns
I0827 20:36:23.652402 18022 registrar.cpp:346] Successfully fetched the
registry (0B)
I0827 20:36:23.652446 18022 registrar.cpp:422] Attempting to update the
'registry'
I0827 20:36:23.655201 18036 log.cpp:680] Attempting to append 140 bytes to the
log
I0827 20:36:23.655402 18026 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 1
I0827 20:36:23.655912 18033 replica.cpp:508] Replica received write request for
position 1
I0827 20:36:23.656443 18033 leveldb.cpp:343] Persisting action (159 bytes) to
leveldb took 492030ns
I0827 20:36:23.656501 18033 replica.cpp:676] Persisted action at 1
I0827 20:36:23.657109 18026 replica.cpp:655] Replica received learned notice
for position 1
I0827 20:36:23.657611 18026 leveldb.cpp:343] Persisting action (161 bytes) to
leveldb took 439685ns
I0827 20:36:23.657670 18026 replica.cpp:676] Persisted action at 1
I0827 20:36:23.657682 18026 replica.cpp:661] Replica learned APPEND action at
position 1
I0827 20:36:23.657999 18029 registrar.cpp:479] Successfully updated 'registry'
I0827 20:36:23.658115 18037 log.cpp:699] Attempting to truncate the log to 1
I0827 20:36:23.658145 18029 registrar.cpp:372] Successfully recovered registrar
I0827 20:36:23.658180 18030 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 2
I0827 20:36:23.658332 18034 master.cpp:1063] Recovered 0 slaves from the
Registry (102B) ; allowing 10mins for slaves to re-register
I0827 20:36:23.658800 18029 replica.cpp:508] Replica received write request for
position 2
I0827 20:36:23.658982 18029 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 116369ns
I0827 20:36:23.659036 18029 replica.cpp:676] Persisted action at 2
I0827 20:36:23.659327 18031 replica.cpp:655] Replica received learned notice
for position 2
I0827 20:36:23.659459 18031 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 111713ns
I0827 20:36:23.659488 18031 leveldb.cpp:401] Deleting ~1 keys from leveldb took
12530ns
I0827 20:36:23.659498 18031 replica.cpp:676] Persisted action at 2
I0827 20:36:23.659507 18031 replica.cpp:661] Replica learned TRUNCATE action at
position 2
2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@712: Client
environment:zookeeper.version=zookeeper C client 3.4.5
2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@716: Client
environment:host.name=penates.apache.org
2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@723: Client
environment:os.name=Linux
2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@724: Client
environment:os.arch=3.13.0-24-generic
2014-08-27 20:36:23,669:18008(0x2ac6afb19700):ZOO_INFO@log_env@725: Client
environment:os.version=#47-Ubuntu SMP Fri May 2 23:30:00 UTC 2014
2014-08-27 20:36:23,670:18008(0x2ac6afb19700):ZOO_INFO@log_env@733: Client
environment:user.name=(null)
2014-08-27 20:36:23,670:18008(0x2ac6afb19700):ZOO_INFO@log_env@741: Client
environment:user.home=/home/jenkins
2014-08-27 20:36:23,670:18008(0x2ac6afb19700):ZOO_INFO@log_env@753: Client
environment:user.dir=/tmp/RegistrarZooKeeperTest_TaskRunning_WhHZri
2014-08-27 20:36:23,670:18008(0x2ac6afb19700):ZOO_INFO@zookeeper_init@786:
Initiating client connection, host=127.0.0.1:56082 sessionTimeout=10000
watcher=0x2ac6abf9f920 sessionId=0 sessionPasswd=<null> context=0x2ac6f4040ec0
flags=0
2014-08-27 20:36:23,670:18008(0x2aca643f9700):ZOO_INFO@check_events@1703:
initiated connection to server [127.0.0.1:56082]
I0827 20:36:23.670694 18028 slave.cpp:167] Slave started on
83)@67.195.81.186:49273
I0827 20:36:23.670725 18028 credentials.hpp:84] Loading credential for
authentication from '/tmp/RegistrarZooKeeperTest_TaskRunning_6FF4jK/credential'
I0827 20:36:23.670846 18028 slave.cpp:274] Slave using credential for:
test-principal
I0827 20:36:23.671012 18028 slave.cpp:287] Slave resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0827 20:36:23.671129 18028 slave.cpp:315] Slave hostname: penates.apache.org
I0827 20:36:23.671150 18028 slave.cpp:316] Slave checkpoint: false
I0827 20:36:23.671577 18032 state.cpp:33] Recovering state from
'/tmp/RegistrarZooKeeperTest_TaskRunning_6FF4jK/meta'
I0827 20:36:23.671757 18023 status_update_manager.cpp:193] Recovering status
update manager
I0827 20:36:23.671907 18030 slave.cpp:3204] Finished recovery
2014-08-27 20:36:23,672:18008(0x2aca643f9700):ZOO_INFO@check_events@1750:
session establishment complete on server [127.0.0.1:56082],
sessionId=0x148192eff150004, negotiated timeout=10000
I0827 20:36:23.672554 18030 group.cpp:313] Group process
(group(5)@67.195.81.186:49273) connected to ZooKeeper
I0827 20:36:23.672585 18030 group.cpp:787] Syncing group operations: queue size
(joins, cancels, datas) = (0, 0, 0)
I0827 20:36:23.672598 18030 group.cpp:385] Trying to create path '/znode' in
ZooKeeper
I0827 20:36:23.674561 18030 group.cpp:714] Found non-sequence node
'log_replicas' at '/znode' in ZooKeeper
I0827 20:36:23.674681 18022 detector.cpp:138] Detected a new leader: (id='0')
I0827 20:36:23.674929 18022 group.cpp:658] Trying to get
'/znode/info_0000000000' in ZooKeeper
I0827 20:36:23.675407 18008 sched.cpp:137] Version: 0.21.0
I0827 20:36:23.675626 18025 sched.cpp:233] New master detected at
master@67.195.81.186:49273
I0827 20:36:23.675662 18025 sched.cpp:283] Authenticating with master
master@67.195.81.186:49273
I0827 20:36:23.675747 18035 authenticatee.hpp:128] Creating new client SASL
connection
I0827 20:36:23.675856 18035 master.cpp:3637] Authenticating
scheduler-e18a07c9-73ef-49ba-9f93-879dfb44027b@67.195.81.186:49273
I0827 20:36:23.675967 18026 detector.cpp:426] A new leading master
(UPID=master@67.195.81.186:49273) is detected
I0827 20:36:23.675981 18023 authenticator.hpp:156] Creating new server SASL
connection
I0827 20:36:23.676049 18026 slave.cpp:598] New master detected at
master@67.195.81.186:49273
I0827 20:36:23.676064 18023 authenticatee.hpp:219] Received SASL authentication
mechanisms: CRAM-MD5
I0827 20:36:23.676090 18023 authenticatee.hpp:245] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0827 20:36:23.676128 18026 slave.cpp:672] Authenticating with master
master@67.195.81.186:49273
I0827 20:36:23.676197 18024 status_update_manager.cpp:167] New master detected
at master@67.195.81.186:49273
I0827 20:36:23.676213 18035 authenticator.hpp:262] Received SASL authentication
start
I0827 20:36:23.676285 18035 authenticator.hpp:384] Authentication requires more
steps
I0827 20:36:23.676373 18023 authenticatee.hpp:265] Received SASL authentication
step
I0827 20:36:23.676400 18026 slave.cpp:645] Detecting new master
I0827 20:36:23.676448 18023 authenticator.hpp:290] Received SASL authentication
step
I0827 20:36:23.676446 18028 authenticatee.hpp:128] Creating new client SASL
connection
I0827 20:36:23.676486 18023 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'penates.apache.org' server FQDN:
'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0827 20:36:23.676504 18023 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0827 20:36:23.676527 18023 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0827 20:36:23.676548 18023 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'penates.apache.org' server FQDN:
'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0827 20:36:23.676565 18023 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0827 20:36:23.676579 18023 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0827 20:36:23.676584 18033 master.cpp:3637] Authenticating
slave(83)@67.195.81.186:49273
I0827 20:36:23.676606 18023 authenticator.hpp:376] Authentication success
I0827 20:36:23.676667 18036 authenticatee.hpp:305] Authentication success
I0827 20:36:23.676969 18025 authenticator.hpp:156] Creating new server SASL
connection
I0827 20:36:23.677011 18033 master.cpp:3677] Successfully authenticated
principal 'test-principal' at
scheduler-e18a07c9-73ef-49ba-9f93-879dfb44027b@67.195.81.186:49273
I0827 20:36:23.677021 18027 sched.cpp:357] Successfully authenticated with
master master@67.195.81.186:49273
I0827 20:36:23.677048 18027 sched.cpp:476] Sending registration request to
master@67.195.81.186:49273
I0827 20:36:23.677075 18022 authenticatee.hpp:219] Received SASL authentication
mechanisms: CRAM-MD5
I0827 20:36:23.677106 18022 authenticatee.hpp:245] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0827 20:36:23.677136 18033 master.cpp:1324] Received registration request from
scheduler-e18a07c9-73ef-49ba-9f93-879dfb44027b@67.195.81.186:49273
I0827 20:36:23.677150 18022 authenticator.hpp:262] Received SASL authentication
start
I0827 20:36:23.677168 18033 master.cpp:1284] Authorizing framework principal
'test-principal' to receive offers for role '*'
I0827 20:36:23.677192 18022 authenticator.hpp:384] Authentication requires more
steps
I0827 20:36:23.677285 18022 authenticatee.hpp:265] Received SASL authentication
step
I0827 20:36:23.677350 18032 authenticator.hpp:290] Received SASL authentication
step
I0827 20:36:23.677347 18023 master.cpp:1383] Registering framework
20140827-203623-3125920579-49273-18008-0000 at
scheduler-e18a07c9-73ef-49ba-9f93-879dfb44027b@67.195.81.186:49273
I0827 20:36:23.677382 18032 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'penates.apache.org' server FQDN:
'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0827 20:36:23.677402 18032 auxprop.cpp:153] Looking up auxiliary property
'*userPassword'
I0827 20:36:23.677420 18032 auxprop.cpp:153] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0827 20:36:23.677435 18032 auxprop.cpp:81] Request to lookup properties for
user: 'test-principal' realm: 'penates.apache.org' server FQDN:
'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0827 20:36:23.677445 18032 auxprop.cpp:103] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0827 20:36:23.677448 18022 sched.cpp:407] Framework registered with
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.677454 18032 auxprop.cpp:103] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0827 20:36:23.677481 18032 authenticator.hpp:376] Authentication success
I0827 20:36:23.677490 18022 sched.cpp:421] Scheduler::registered took 21164ns
I0827 20:36:23.677500 18034 hierarchical_allocator_process.hpp:329] Added
framework 20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.677525 18034 hierarchical_allocator_process.hpp:691] No
resources available to allocate!
I0827 20:36:23.677538 18034 hierarchical_allocator_process.hpp:653] Performed
allocation for 0 slaves in 16302ns
I0827 20:36:23.677553 18036 authenticatee.hpp:305] Authentication success
I0827 20:36:23.677574 18030 master.cpp:3677] Successfully authenticated
principal 'test-principal' at slave(83)@67.195.81.186:49273
I0827 20:36:23.677685 18036 slave.cpp:729] Successfully authenticated with
master master@67.195.81.186:49273
I0827 20:36:23.677745 18036 slave.cpp:980] Will retry registration in
19.175231ms if necessary
I0827 20:36:23.677798 18032 master.cpp:2836] Registering slave at
slave(83)@67.195.81.186:49273 (penates.apache.org) with id
20140827-203623-3125920579-49273-18008-0
I0827 20:36:23.677937 18028 registrar.cpp:422] Attempting to update the
'registry'
I0827 20:36:23.679662 18030 log.cpp:680] Attempting to append 337 bytes to the
log
I0827 20:36:23.679740 18022 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 3
I0827 20:36:23.680212 18026 replica.cpp:508] Replica received write request for
position 3
I0827 20:36:23.680418 18026 leveldb.cpp:343] Persisting action (356 bytes) to
leveldb took 174539ns
I0827 20:36:23.680444 18026 replica.cpp:676] Persisted action at 3
I0827 20:36:23.680752 18032 replica.cpp:655] Replica received learned notice
for position 3
I0827 20:36:23.681164 18032 leveldb.cpp:343] Persisting action (358 bytes) to
leveldb took 385849ns
I0827 20:36:23.681187 18032 replica.cpp:676] Persisted action at 3
I0827 20:36:23.681200 18032 replica.cpp:661] Replica learned APPEND action at
position 3
I0827 20:36:23.681562 18037 registrar.cpp:479] Successfully updated 'registry'
I0827 20:36:23.681694 18033 log.cpp:699] Attempting to truncate the log to 3
I0827 20:36:23.681710 18022 master.cpp:2876] Registered slave
20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273
(penates.apache.org)
I0827 20:36:23.681732 18022 master.cpp:4110] Adding slave
20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273
(penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0827 20:36:23.681771 18031 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 4
I0827 20:36:23.681834 18025 slave.cpp:763] Registered with master
master@67.195.81.186:49273; given slave ID
20140827-203623-3125920579-49273-18008-0
I0827 20:36:23.681970 18029 slave.cpp:2333] Received ping from
slave-observer(68)@67.195.81.186:49273
I0827 20:36:23.682057 18030 hierarchical_allocator_process.hpp:442] Added slave
20140827-203623-3125920579-49273-18008-0 (penates.apache.org) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] available)
I0827 20:36:23.682168 18030 hierarchical_allocator_process.hpp:728] Offering
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
20140827-203623-3125920579-49273-18008-0 to framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.682314 18030 hierarchical_allocator_process.hpp:673] Performed
allocation for slave 20140827-203623-3125920579-49273-18008-0 in 190586ns
I0827 20:36:23.682317 18033 replica.cpp:508] Replica received write request for
position 4
I0827 20:36:23.682433 18022 master.hpp:857] Adding offer
20140827-203623-3125920579-49273-18008-0 with resources cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] on slave
20140827-203623-3125920579-49273-18008-0 (penates.apache.org)
I0827 20:36:23.682464 18033 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 113256ns
I0827 20:36:23.682483 18033 replica.cpp:676] Persisted action at 4
I0827 20:36:23.682507 18022 master.cpp:3584] Sending 1 offers to framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.682688 18025 sched.cpp:544] Scheduler::resourceOffers took
38346ns
I0827 20:36:23.682838 18037 replica.cpp:655] Replica received learned notice
for position 4
I0827 20:36:23.682996 18037 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 129181ns
I0827 20:36:23.683121 18037 leveldb.cpp:401] Deleting ~2 keys from leveldb took
61473ns
I0827 20:36:23.683146 18037 replica.cpp:676] Persisted action at 4
I0827 20:36:23.683166 18037 replica.cpp:661] Replica learned TRUNCATE action at
position 4
I0827 20:36:23.685097 18034 master.hpp:867] Removing offer
20140827-203623-3125920579-49273-18008-0 with resources cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] on slave
20140827-203623-3125920579-49273-18008-0 (penates.apache.org)
I0827 20:36:23.685485 18034 master.cpp:2194] Processing reply for offers: [
20140827-203623-3125920579-49273-18008-0 ] on slave
20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273
(penates.apache.org) for framework 20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.685600 18034 master.cpp:2277] Authorizing framework principal
'test-principal' to launch task 9a5937dd-1dbc-4120-becc-f635cc6656f6 as user
'jenkins'
I0827 20:36:23.686669 18034 master.hpp:829] Adding task
9a5937dd-1dbc-4120-becc-f635cc6656f6 with resources cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] on slave
20140827-203623-3125920579-49273-18008-0 (penates.apache.org)
I0827 20:36:23.686758 18034 master.cpp:2343] Launching task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000 with resources cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273
(penates.apache.org)
I0827 20:36:23.687325 18029 slave.cpp:1011] Got assigned task
9a5937dd-1dbc-4120-becc-f635cc6656f6 for framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.687593 18029 slave.cpp:1121] Launching task
9a5937dd-1dbc-4120-becc-f635cc6656f6 for framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.691444 18029 exec.cpp:132] Version: 0.21.0
I0827 20:36:23.691726 18030 exec.cpp:182] Executor started at:
executor(17)@67.195.81.186:49273 with pid 18008
I0827 20:36:23.691815 18029 slave.cpp:1231] Queuing task
'9a5937dd-1dbc-4120-becc-f635cc6656f6' for executor default of framework
'20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.691869 18029 slave.cpp:552] Successfully attached file
'/tmp/RegistrarZooKeeperTest_TaskRunning_6FF4jK/slaves/20140827-203623-3125920579-49273-18008-0/frameworks/20140827-203623-3125920579-49273-18008-0000/executors/default/runs/2846316d-065d-478c-8bb1-5ad649bc0629'
I0827 20:36:23.691915 18029 slave.cpp:2544] Monitoring executor 'default' of
framework '20140827-203623-3125920579-49273-18008-0000' in container
'2846316d-065d-478c-8bb1-5ad649bc0629'
I0827 20:36:23.692044 18029 slave.cpp:1742] Got registration for executor
'default' of framework 20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.692399 18029 slave.cpp:1861] Flushing queued task
9a5937dd-1dbc-4120-becc-f635cc6656f6 for executor 'default' of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.692492 18036 exec.cpp:206] Executor registered on slave
20140827-203623-3125920579-49273-18008-0
I0827 20:36:23.694097 18036 exec.cpp:218] Executor::registered took 22140ns
I0827 20:36:23.694246 18036 exec.cpp:293] Executor asked to run task
'9a5937dd-1dbc-4120-becc-f635cc6656f6'
I0827 20:36:23.694299 18036 exec.cpp:302] Executor::launchTask took 33286ns
I0827 20:36:23.695788 18036 exec.cpp:525] Executor sending status update
TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.695935 18036 slave.cpp:2096] Handling status update TASK_RUNNING
(UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000 from
executor(17)@67.195.81.186:49273
I0827 20:36:23.696074 18030 status_update_manager.cpp:320] Received status
update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.696099 18030 status_update_manager.cpp:499] Creating
StatusUpdate stream for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.696372 18030 status_update_manager.cpp:373] Forwarding status
update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000 to master@67.195.81.186:49273
I0827 20:36:23.696583 18030 slave.cpp:2254] Status update manager successfully
handled status update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc)
for task 9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.696609 18036 master.cpp:3205] Forwarding status update
TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.696650 18030 slave.cpp:2260] Sending acknowledgement for status
update TASK_RUNNING (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000 to executor(17)@67.195.81.186:49273
I0827 20:36:23.696755 18036 master.cpp:3171] Status update TASK_RUNNING (UUID:
a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000 from slave
20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273
(penates.apache.org)
I0827 20:36:23.697017 18033 exec.cpp:339] Executor received status update
acknowledgement a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.697070 18024 sched.cpp:635] Scheduler::statusUpdate took 63398ns
I0827 20:36:23.697154 18024 master.cpp:2686] Forwarding status update
acknowledgement a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000 to slave
20140827-203623-3125920579-49273-18008-0 at slave(83)@67.195.81.186:49273
(penates.apache.org)
I0827 20:36:23.697516 18028 status_update_manager.cpp:398] Received status
update acknowledgement (UUID: a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.697532 18008 master.cpp:643] Master terminating
I0827 20:36:23.697633 18032 sched.cpp:745] Stopping framework
'20140827-203623-3125920579-49273-18008-0000'
I0827 20:36:23.697635 18028 slave.cpp:1682] Status update manager successfully
handled status update acknowledgement (UUID:
a2e6d8fe-5d38-4cf8-b6e9-b513bd9eaebc) for task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.697685 18008 master.hpp:847] Removing task
9a5937dd-1dbc-4120-becc-f635cc6656f6 with resources cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] on slave
20140827-203623-3125920579-49273-18008-0 (penates.apache.org)
W0827 20:36:23.697896 18008 master.cpp:4403] Removing task
9a5937dd-1dbc-4120-becc-f635cc6656f6 of framework
20140827-203623-3125920579-49273-18008-0000 and slave
20140827-203623-3125920579-49273-18008-0 in non-terminal state TASK_RUNNING
I0827 20:36:23.698776 18036 slave.cpp:2365] master@67.195.81.186:49273 exited
W0827 20:36:23.698802 18036 slave.cpp:2368] Master disconnected! Waiting for a
new master to be elected
I0827 20:36:23.698945 18035 hierarchical_allocator_process.hpp:563] Recovered
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total
allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on
slave 20140827-203623-3125920579-49273-18008-0 from framework
20140827-203623-3125920579-49273-18008-0000
I0827 20:36:23.699013 18035 hierarchical_allocator_process.hpp:594] Framework
20140827-203623-3125920579-49273-18008-0000 filtered slave
20140827-203623-3125920579-49273-18008-0 for 5secs
2014-08-27 20:36:23,702:18008(0x2ac6b031d700):ZOO_INFO@zookeeper_close@2505:
Closing zookeeper sessionId=0x148192eff150003 to [127.0.0.1:56082]
2014-08-27 20:36:23,703:18008(0x2ac6afd1a700):ZOO_INFO@zookeeper_close@2505:
Closing zookeeper sessionId=0x148192eff150001 to [127.0.0.1:56082]
I0827 20:36:23.705186 18008 contender.cpp:186] Now cancelling the membership: 0
I0827 20:36:23.705526 18027 group.cpp:619] Trying to remove
'/znode/info_0000000000' in ZooKeeper
W0827 20:36:23.705526 20319 glog.hpp:59] RAW: Received signal SIGPIPE;
escalating to SIGABRT
*** Aborted at 1409171783 (unix time) try "date -d @1409171783" if you are
using GNU date ***
PC: @ 0x2ac6add1f20b raise
*** SIGABRT (@0x95c00004658) received by PID 18008 (TID 0x2ac6c34f1700) from
PID 18008; stack trace: ***
@ 0x2ac6add1f340 (unknown)
@ 0x2ac6add1f20b raise
@ 0x2ac6abd06287 internal::handler()
@ 0x2ac6b28b885a call_chained_handler()
@ 0x2ac6b28b5deb os::Linux::chained_handler()
@ 0x2ac6b28b99fe JVM_handle_linux_signal
@ 0x2ac6b28b5cfe signalHandler()
@ 0x2ac6add1f340 (unknown)
@ 0x2ac6add1e35d __libc_write
@ 0x2ac6c30eb4b0 Java_sun_nio_ch_FileDispatcher_write0
@ 0x2ac6b8010c48 (unknown)
make[3]: *** [check-local] Aborted
make[3]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
