See
<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2533/changes>
Changes:
[idownes] Updated docs/configuration.md.
------------------------------------------
[...truncated 23794 lines...]
I1106 21:50:13.799895 10758 leveldb.cpp:343] Persisting action (14 bytes) to
leveldb took 629259ns
I1106 21:50:13.799923 10758 replica.cpp:676] Persisted action at 0
I1106 21:50:13.800532 10763 replica.cpp:655] Replica received learned notice
for position 0
I1106 21:50:13.800899 10763 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 344023ns
I1106 21:50:13.800920 10763 replica.cpp:676] Persisted action at 0
I1106 21:50:13.800935 10763 replica.cpp:661] Replica learned NOP action at
position 0
I1106 21:50:13.801533 10762 log.cpp:672] Writer started with ending position 0
I1106 21:50:13.803004 10757 leveldb.cpp:438] Reading position from leveldb took
33502ns
I1106 21:50:13.806342 10755 registrar.cpp:346] Successfully fetched the
registry (0B) in 13.821184ms
I1106 21:50:13.806473 10755 registrar.cpp:445] Applied 1 operations in 25831ns;
attempting to update the 'registry'
I1106 21:50:13.808749 10767 log.cpp:680] Attempting to append 139 bytes to the
log
I1106 21:50:13.808902 10764 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 1
I1106 21:50:13.809695 10757 replica.cpp:508] Replica received write request for
position 1
I1106 21:50:13.810334 10757 leveldb.cpp:343] Persisting action (158 bytes) to
leveldb took 604652ns
I1106 21:50:13.810364 10757 replica.cpp:676] Persisted action at 1
I1106 21:50:13.810999 10754 replica.cpp:655] Replica received learned notice
for position 1
I1106 21:50:13.811554 10754 leveldb.cpp:343] Persisting action (160 bytes) to
leveldb took 519147ns
I1106 21:50:13.811584 10754 replica.cpp:676] Persisted action at 1
I1106 21:50:13.811607 10754 replica.cpp:661] Replica learned APPEND action at
position 1
I1106 21:50:13.812592 10755 registrar.cpp:490] Successfully updated the
'registry' in 6.061056ms
I1106 21:50:13.812728 10755 registrar.cpp:376] Successfully recovered registrar
I1106 21:50:13.812847 10759 log.cpp:699] Attempting to truncate the log to 1
I1106 21:50:13.812947 10764 master.cpp:1116] Recovered 0 slaves from the
Registry (101B) ; allowing 10mins for slaves to re-register
I1106 21:50:13.812988 10766 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 2
I1106 21:50:13.814779 10760 replica.cpp:508] Replica received write request for
position 2
I1106 21:50:13.815408 10760 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 597533ns
I1106 21:50:13.815438 10760 replica.cpp:676] Persisted action at 2
I1106 21:50:13.816007 10757 replica.cpp:655] Replica received learned notice
for position 2
I1106 21:50:13.816519 10757 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 479005ns
I1106 21:50:13.816588 10757 leveldb.cpp:401] Deleting ~1 keys from leveldb took
41354ns
I1106 21:50:13.816611 10757 replica.cpp:676] Persisted action at 2
I1106 21:50:13.816632 10757 replica.cpp:661] Replica learned TRUNCATE action at
position 2
I1106 21:50:13.827955 10756 slave.cpp:169] Slave started on
51)@67.195.81.187:42265
I1106 21:50:13.827997 10756 credentials.hpp:84] Loading credential for
authentication from
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_mdHXjr/credential'
I1106 21:50:13.828145 10756 slave.cpp:276] Slave using credential for:
test-principal
I1106 21:50:13.828390 10756 slave.cpp:289] Slave resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I1106 21:50:13.828479 10756 slave.cpp:318] Slave hostname: pomona.apache.org
I1106 21:50:13.828490 10756 slave.cpp:319] Slave checkpoint: false
W1106 21:50:13.828497 10756 slave.cpp:321] Disabling checkpointing is
deprecated and the --checkpoint flag will be removed in a future release.
Please avoid using this flag
I1106 21:50:13.829340 10761 state.cpp:33] Recovering state from
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_mdHXjr/meta'
I1106 21:50:13.829656 10763 status_update_manager.cpp:197] Recovering status
update manager
I1106 21:50:13.829936 10763 slave.cpp:3466] Finished recovery
I1106 21:50:13.830659 10755 slave.cpp:602] New master detected at
master@67.195.81.187:42265
I1106 21:50:13.830688 10766 status_update_manager.cpp:171] Pausing sending
status updates
I1106 21:50:13.830749 10755 slave.cpp:665] Authenticating with master
master@67.195.81.187:42265
I1106 21:50:13.830904 10755 slave.cpp:638] Detecting new master
I1106 21:50:13.831002 10764 authenticatee.hpp:133] Creating new client SASL
connection
I1106 21:50:13.831218 10760 master.cpp:3896] Authenticating
slave(51)@67.195.81.187:42265
I1106 21:50:13.831256 10760 master.cpp:3907] Using default CRAM-MD5
authenticator
I1106 21:50:13.831481 10763 authenticator.hpp:169] Creating new server SASL
connection
I1106 21:50:13.831625 10762 authenticatee.hpp:224] Received SASL authentication
mechanisms: CRAM-MD5
I1106 21:50:13.831666 10762 authenticatee.hpp:250] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1106 21:50:13.831845 10767 authenticator.hpp:275] Received SASL authentication
start
I1106 21:50:13.831892 10767 authenticator.hpp:397] Authentication requires more
steps
I1106 21:50:13.831956 10767 authenticatee.hpp:270] Received SASL authentication
step
I1106 21:50:13.832058 10767 authenticator.hpp:303] Received SASL authentication
step
I1106 21:50:13.832082 10767 auxprop.cpp:99] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1106 21:50:13.832095 10767 auxprop.cpp:171] Looking up auxiliary property
'*userPassword'
I1106 21:50:13.832171 10767 auxprop.cpp:171] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1106 21:50:13.832197 10767 auxprop.cpp:99] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1106 21:50:13.832206 10767 auxprop.cpp:121] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1106 21:50:13.832211 10767 auxprop.cpp:121] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1106 21:50:13.832224 10767 authenticator.hpp:389] Authentication success
I1106 21:50:13.832356 10755 authenticatee.hpp:310] Authentication success
I1106 21:50:13.832412 10756 master.cpp:3957] Successfully authenticated
principal 'test-principal' at slave(51)@67.195.81.187:42265
I1106 21:50:13.832648 10755 slave.cpp:722] Successfully authenticated with
master master@67.195.81.187:42265
I1106 21:50:13.832798 10755 slave.cpp:1052] Will retry registration in
4.334745ms if necessary
I1106 21:50:13.833009 10756 master.cpp:3063] Registering slave at
slave(51)@67.195.81.187:42265 (pomona.apache.org) with id
20141106-215013-3142697795-42265-10739-S0
I1106 21:50:13.833498 10754 registrar.cpp:445] Applied 1 operations in 72886ns;
attempting to update the 'registry'
I1106 21:50:13.836539 10763 log.cpp:680] Attempting to append 316 bytes to the
log
I1106 21:50:13.836688 10759 coordinator.cpp:340] Coordinator attempting to
write APPEND action at position 3
I1106 21:50:13.837621 10765 replica.cpp:508] Replica received write request for
position 3
I1106 21:50:13.838235 10765 leveldb.cpp:343] Persisting action (335 bytes) to
leveldb took 580209ns
I1106 21:50:13.838243 10753 slave.cpp:1052] Will retry registration in
34.28488ms if necessary
I1106 21:50:13.838259 10765 replica.cpp:676] Persisted action at 3
I1106 21:50:13.838379 10766 master.cpp:3051] Ignoring register slave message
from slave(51)@67.195.81.187:42265 (pomona.apache.org) as admission is already
in progress
I1106 21:50:13.839057 10755 replica.cpp:655] Replica received learned notice
for position 3
I1106 21:50:13.839463 10755 leveldb.cpp:343] Persisting action (337 bytes) to
leveldb took 359634ns
I1106 21:50:13.839483 10755 replica.cpp:676] Persisted action at 3
I1106 21:50:13.839498 10755 replica.cpp:661] Replica learned APPEND action at
position 3
I1106 21:50:13.840714 10765 registrar.cpp:490] Successfully updated the
'registry' in 7.154176ms
I1106 21:50:13.841016 10768 log.cpp:699] Attempting to truncate the log to 3
I1106 21:50:13.841130 10763 coordinator.cpp:340] Coordinator attempting to
write TRUNCATE action at position 4
I1106 21:50:13.841276 10766 master.cpp:3117] Registered slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I1106 21:50:13.841367 10759 slave.cpp:2538] Received ping from
slave-observer(52)@67.195.81.187:42265
I1106 21:50:13.841577 10755 hierarchical_allocator_process.hpp:442] Added slave
20141106-215013-3142697795-42265-10739-S0 (pomona.apache.org) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] available)
I1106 21:50:13.841961 10764 replica.cpp:508] Replica received write request for
position 4
I1106 21:50:13.900509 10755 hierarchical_allocator_process.hpp:679] Performed
allocation for slave 20141106-215013-3142697795-42265-10739-S0 in 104213ns
I1106 21:50:13.900746 10759 slave.cpp:756] Registered with master
master@67.195.81.187:42265; given slave ID
20141106-215013-3142697795-42265-10739-S0
I1106 21:50:13.900835 10754 status_update_manager.cpp:178] Resuming sending
status updates
I1106 21:50:13.901160 10764 leveldb.cpp:343] Persisting action (16 bytes) to
leveldb took 742687ns
I1106 21:50:13.901190 10764 replica.cpp:676] Persisted action at 4
I1106 21:50:13.901860 10753 replica.cpp:655] Replica received learned notice
for position 4
I1106 21:50:13.902582 10753 leveldb.cpp:343] Persisting action (18 bytes) to
leveldb took 692123ns
I1106 21:50:13.902664 10753 leveldb.cpp:401] Deleting ~2 keys from leveldb took
51569ns
I1106 21:50:13.902688 10753 replica.cpp:676] Persisted action at 4
I1106 21:50:13.902711 10753 replica.cpp:661] Replica learned TRUNCATE action at
position 4
I1106 21:50:13.906543 10739 sched.cpp:137] Version: 0.22.0
I1106 21:50:13.907243 10754 sched.cpp:233] New master detected at
master@67.195.81.187:42265
I1106 21:50:13.907296 10754 sched.cpp:283] Authenticating with master
master@67.195.81.187:42265
I1106 21:50:13.907470 10762 authenticatee.hpp:133] Creating new client SASL
connection
I1106 21:50:13.907733 10756 master.cpp:3896] Authenticating
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:13.907794 10756 master.cpp:3907] Using default CRAM-MD5
authenticator
I1106 21:50:13.908069 10764 authenticator.hpp:169] Creating new server SASL
connection
I1106 21:50:13.908233 10758 authenticatee.hpp:224] Received SASL authentication
mechanisms: CRAM-MD5
I1106 21:50:13.908267 10758 authenticatee.hpp:250] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1106 21:50:13.908519 10762 authenticator.hpp:275] Received SASL authentication
start
I1106 21:50:13.908638 10762 authenticator.hpp:397] Authentication requires more
steps
I1106 21:50:13.908733 10762 authenticatee.hpp:270] Received SASL authentication
step
I1106 21:50:13.908982 10754 authenticator.hpp:303] Received SASL authentication
step
I1106 21:50:13.909025 10754 auxprop.cpp:99] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1106 21:50:13.909041 10754 auxprop.cpp:171] Looking up auxiliary property
'*userPassword'
I1106 21:50:13.909081 10754 auxprop.cpp:171] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1106 21:50:13.909106 10754 auxprop.cpp:99] Request to lookup properties for
user: 'test-principal' realm: 'pomona.apache.org' server FQDN:
'pomona.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1106 21:50:13.909118 10754 auxprop.cpp:121] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1106 21:50:13.909127 10754 auxprop.cpp:121] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1106 21:50:13.909147 10754 authenticator.hpp:389] Authentication success
I1106 21:50:13.909334 10762 authenticatee.hpp:310] Authentication success
I1106 21:50:13.909369 10753 master.cpp:3957] Successfully authenticated
principal 'test-principal' at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:13.909657 10762 sched.cpp:357] Successfully authenticated with
master master@67.195.81.187:42265
I1106 21:50:13.909684 10762 sched.cpp:476] Sending registration request to
master@67.195.81.187:42265
I1106 21:50:13.909880 10762 master.cpp:1378] Received registration request for
framework 'default' at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:13.909965 10762 master.cpp:1337] Authorizing framework principal
'test-principal' to receive offers for role '*'
I1106 21:50:13.910452 10762 master.cpp:1442] Registering framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:13.910780 10759 hierarchical_allocator_process.hpp:329] Added
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:13.910938 10767 sched.cpp:407] Framework registered with
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:13.910969 10759 hierarchical_allocator_process.hpp:734] Offering
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave
20141106-215013-3142697795-42265-10739-S0 to framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:13.911002 10767 sched.cpp:421] Scheduler::registered took 44961ns
I1106 21:50:13.911329 10759 hierarchical_allocator_process.hpp:659] Performed
allocation for 1 slaves in 518563ns
I1106 21:50:13.911627 10761 master.cpp:3838] Sending 1 offers to framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:13.912448 10761 sched.cpp:544] Scheduler::resourceOffers took
497007ns
I1106 21:50:13.913274 10763 master.cpp:2339] Processing reply for offers: [
20141106-215013-3142697795-42265-10739-O0 ] on slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org) for framework 20141106-215013-3142697795-42265-10739-0000
(default) at scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
W1106 21:50:13.913410 10763 master.cpp:1985] Executor default for task 0 uses
less CPUs (None) than the minimum required (0.01). Please update your executor,
as this will be mandatory in future releases.
W1106 21:50:13.913439 10763 master.cpp:1996] Executor default for task 0 uses
less memory (None) than the minimum required (32MB). Please update your
executor, as this will be mandatory in future releases.
I1106 21:50:13.913521 10763 master.cpp:2435] Authorizing framework principal
'test-principal' to launch task 0 as user 'jenkins'
I1106 21:50:13.914578 10761 master.hpp:877] Adding task 0 with resources
cpus(*):2; mem(*):1024 on slave 20141106-215013-3142697795-42265-10739-S0
(pomona.apache.org)
I1106 21:50:13.914644 10761 master.cpp:2498] Launching task 0 of framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265 with
resources cpus(*):2; mem(*):1024 on slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org)
I1106 21:50:13.914954 10755 slave.cpp:1083] Got assigned task 0 for framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:13.915149 10768 hierarchical_allocator_process.hpp:563] Recovered
disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024;
ports(*):[31000-32000]) on slave 20141106-215013-3142697795-42265-10739-S0 from
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:13.915195 10768 hierarchical_allocator_process.hpp:599] Framework
20141106-215013-3142697795-42265-10739-0000 filtered slave
20141106-215013-3142697795-42265-10739-S0 for 5secs
I1106 21:50:13.915515 10755 slave.cpp:1193] Launching task 0 for framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:13.917570 10755 slave.cpp:3913] Launching executor default of
framework 20141106-215013-3142697795-42265-10739-0000 in work directory
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_mdHXjr/slaves/20141106-215013-3142697795-42265-10739-S0/frameworks/20141106-215013-3142697795-42265-10739-0000/executors/default/runs/10da9a4c-904c-4fad-b22f-83a7fa9217fa'
I1106 21:50:14.120666 10755 exec.cpp:132] Version: 0.22.0
I1106 21:50:14.121011 10758 exec.cpp:182] Executor started at:
executor(14)@67.195.81.187:42265 with pid 10739
I1106 21:50:14.121172 10755 slave.cpp:1316] Queuing task '0' for executor
default of framework '20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.121331 10755 slave.cpp:555] Successfully attached file
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_mdHXjr/slaves/20141106-215013-3142697795-42265-10739-S0/frameworks/20141106-215013-3142697795-42265-10739-0000/executors/default/runs/10da9a4c-904c-4fad-b22f-83a7fa9217fa'
I1106 21:50:14.121477 10755 slave.cpp:2840] Monitoring executor 'default' of
framework '20141106-215013-3142697795-42265-10739-0000' in container
'10da9a4c-904c-4fad-b22f-83a7fa9217fa'
I1106 21:50:14.121742 10755 slave.cpp:1860] Got registration for executor
'default' of framework 20141106-215013-3142697795-42265-10739-0000 from
executor(14)@67.195.81.187:42265
I1106 21:50:14.122126 10755 slave.cpp:1979] Flushing queued task 0 for executor
'default' of framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.122347 10766 exec.cpp:206] Executor registered on slave
20141106-215013-3142697795-42265-10739-S0
I1106 21:50:14.124495 10766 exec.cpp:218] Executor::registered took 25989ns
I1106 21:50:14.124685 10766 exec.cpp:293] Executor asked to run task '0'
I1106 21:50:14.124763 10766 exec.cpp:302] Executor::launchTask took 56581ns
I1106 21:50:14.126777 10766 exec.cpp:525] Executor sending status update
TASK_RUNNING (UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.126983 10766 slave.cpp:2215] Handling status update TASK_RUNNING
(UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 from
executor(14)@67.195.81.187:42265
I1106 21:50:14.127236 10759 status_update_manager.cpp:317] Received status
update TASK_RUNNING (UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.127270 10759 status_update_manager.cpp:494] Creating
StatusUpdate stream for task 0 of framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.127538 10759 status_update_manager.cpp:371] Forwarding update
TASK_RUNNING (UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of
framework 20141106-215013-3142697795-42265-10739-0000 to the slave
I1106 21:50:14.127790 10764 slave.cpp:2458] Forwarding the update TASK_RUNNING
(UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 to master@67.195.81.187:42265
I1106 21:50:14.127976 10764 slave.cpp:2385] Status update manager successfully
handled status update TASK_RUNNING (UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80)
for task 0 of framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.128015 10764 slave.cpp:2391] Sending acknowledgement for status
update TASK_RUNNING (UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of
framework 20141106-215013-3142697795-42265-10739-0000 to
executor(14)@67.195.81.187:42265
I1106 21:50:14.128082 10766 master.cpp:3441] Forwarding status update
TASK_RUNNING (UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.128233 10762 exec.cpp:339] Executor received status update
acknowledgement 5126b7c9-12d2-4a80-a9d8-999aa3b31c80 for task 0 of framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.128233 10766 master.cpp:3413] Status update TASK_RUNNING (UUID:
5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 from slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org)
I1106 21:50:14.128295 10766 master.cpp:4691] Updating the latest state of task
0 of framework 20141106-215013-3142697795-42265-10739-0000 to TASK_RUNNING
I1106 21:50:14.128437 10763 sched.cpp:635] Scheduler::statusUpdate took 62931ns
I1106 21:50:14.128746 10757 master.cpp:2913] Forwarding status update
acknowledgement 5126b7c9-12d2-4a80-a9d8-999aa3b31c80 for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265 to slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org)
I1106 21:50:14.129066 10754 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: 5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0
of framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.129191 10764 slave.cpp:2898] Executor 'default' of framework
20141106-215013-3142697795-42265-10739-0000 exited with status 0
I1106 21:50:14.130794 10764 slave.cpp:2215] Handling status update TASK_LOST
(UUID: bb600811-060b-49bb-9622-85332fad3356) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 from @0.0.0.0:0
I1106 21:50:14.130856 10764 slave.cpp:4221] Terminating task 0
I1106 21:50:14.131203 10764 slave.cpp:1800] Status update manager successfully
handled status update acknowledgement (UUID:
5126b7c9-12d2-4a80-a9d8-999aa3b31c80) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.131315 10767 master.cpp:3493] Executor default of framework
20141106-215013-3142697795-42265-10739-0000 on slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org) exited with status 0
I1106 21:50:14.131410 10767 master.cpp:4779] Removing executor 'default' with
resources of framework 20141106-215013-3142697795-42265-10739-0000 on slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org)
I1106 21:50:14.131677 10759 status_update_manager.cpp:317] Received status
update TASK_LOST (UUID: bb600811-060b-49bb-9622-85332fad3356) for task 0 of
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.131803 10759 status_update_manager.cpp:371] Forwarding update
TASK_LOST (UUID: bb600811-060b-49bb-9622-85332fad3356) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 to the slave
I1106 21:50:14.132072 10765 slave.cpp:2458] Forwarding the update TASK_LOST
(UUID: bb600811-060b-49bb-9622-85332fad3356) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 to master@67.195.81.187:42265
I1106 21:50:14.132231 10765 slave.cpp:2385] Status update manager successfully
handled status update TASK_LOST (UUID: bb600811-060b-49bb-9622-85332fad3356)
for task 0 of framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.132354 10768 master.cpp:3441] Forwarding status update TASK_LOST
(UUID: bb600811-060b-49bb-9622-85332fad3356) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.132535 10768 master.cpp:3413] Status update TASK_LOST (UUID:
bb600811-060b-49bb-9622-85332fad3356) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 from slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org)
I1106 21:50:14.132598 10768 master.cpp:4691] Updating the latest state of task
0 of framework 20141106-215013-3142697795-42265-10739-0000 to TASK_LOST
I1106 21:50:14.132658 10758 sched.cpp:635] Scheduler::statusUpdate took 21619ns
I1106 21:50:14.132899 10757 hierarchical_allocator_process.hpp:563] Recovered
cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024;
ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave
20141106-215013-3142697795-42265-10739-S0 from framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:14.132951 10768 master.cpp:4750] Removing task 0 with resources
cpus(*):2; mem(*):1024 of framework 20141106-215013-3142697795-42265-10739-0000
on slave 20141106-215013-3142697795-42265-10739-S0 at
slave(51)@67.195.81.187:42265 (pomona.apache.org)
I1106 21:50:16.413043 10768 master.cpp:2913] Forwarding status update
acknowledgement bb600811-060b-49bb-9622-85332fad3356 for task 0 of framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265 to slave
20141106-215013-3142697795-42265-10739-S0 at slave(51)@67.195.81.187:42265
(pomona.apache.org)
I1106 21:50:16.413677 10755 status_update_manager.cpp:389] Received status
update acknowledgement (UUID: bb600811-060b-49bb-9622-85332fad3356) for task 0
of framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.413802 10755 status_update_manager.cpp:525] Cleaning up status
update stream for task 0 of framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.414089 10755 slave.cpp:1800] Status update manager successfully
handled status update acknowledgement (UUID:
bb600811-060b-49bb-9622-85332fad3356) for task 0 of framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.414172 10755 slave.cpp:4260] Completing task 0
I1106 21:50:16.414216 10755 slave.cpp:3007] Cleaning up executor 'default' of
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.414535 10762 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_mdHXjr/slaves/20141106-215013-3142697795-42265-10739-S0/frameworks/20141106-215013-3142697795-42265-10739-0000/executors/default/runs/10da9a4c-904c-4fad-b22f-83a7fa9217fa'
for gc 1.00000309387048weeks in the future
I1106 21:50:16.414616 10755 slave.cpp:3084] Cleaning up framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.414772 10761 status_update_manager.cpp:279] Closing status
update streams for framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.414799 10762 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_mdHXjr/slaves/20141106-215013-3142697795-42265-10739-S0/frameworks/20141106-215013-3142697795-42265-10739-0000/executors/default'
for gc 1.00000309387048weeks in the future
I1106 21:50:16.414923 10762 gc.cpp:56] Scheduling
'/tmp/GarbageCollectorIntegrationTest_DiskUsage_mdHXjr/slaves/20141106-215013-3142697795-42265-10739-S0/frameworks/20141106-215013-3142697795-42265-10739-0000'
for gc 1.00000309387048weeks in the future
I1106 21:50:16.427589 10754 slave.cpp:3321] Current usage 90.00%. Max allowed
age: 0ns
I1106 21:50:16.427918 10760 hierarchical_allocator_process.hpp:734] Offering
disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave
20141106-215013-3142697795-42265-10739-S0 to framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.428326 10760 hierarchical_allocator_process.hpp:659] Performed
allocation for 1 slaves in 683048ns
I1106 21:50:16.428653 10756 master.cpp:3838] Sending 1 offers to framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:16.429054 10758 sched.cpp:544] Scheduler::resourceOffers took
24389ns
../../src/tests/gc_tests.cpp:675: Failure
Value of: os::exists(executorDir)
Actual: true
Expected: false
I1106 21:50:16.448060 10756 master.cpp:784] Framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265 disconnected
I1106 21:50:16.448087 10756 master.cpp:1747] Disconnecting framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:16.448122 10756 master.cpp:1763] Deactivating framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:16.448385 10762 hierarchical_allocator_process.hpp:405] Deactivated
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.448642 10756 master.cpp:806] Giving framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265 0ns to
failover
I1106 21:50:16.448665 10762 hierarchical_allocator_process.hpp:563] Recovered
disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 (total
allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on
slave 20141106-215013-3142697795-42265-10739-S0 from framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.448982 10764 master.cpp:3708] Framework failover timeout,
removing framework 20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:16.449010 10764 master.cpp:4269] Removing framework
20141106-215013-3142697795-42265-10739-0000 (default) at
scheduler-ab9b9bc3-84ca-43a7-8285-df9369bcd243@67.195.81.187:42265
I1106 21:50:16.449188 10755 slave.cpp:1533] Asked to shut down framework
20141106-215013-3142697795-42265-10739-0000 by master@67.195.81.187:42265
W1106 21:50:16.449218 10755 slave.cpp:1548] Cannot shut down unknown framework
20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.449532 10764 master.cpp:693] Master terminating
I1106 21:50:16.449652 10768 hierarchical_allocator_process.hpp:360] Removed
framework 20141106-215013-3142697795-42265-10739-0000
I1106 21:50:16.449995 10764 slave.cpp:2623] master@67.195.81.187:42265 exited
W1106 21:50:16.450018 10764 slave.cpp:2626] Master disconnected! Waiting for a
new master to be elected
*** Aborted at 1415310616 (unix time) try "date -d @1415310616" if you are
using GNU date ***
PC: @ 0x902276 mesos::internal::tests::Cluster::Slaves::shutdown()
*** SIGSEGV (@0x48) received by PID 10739 (TID 0x2af61367b080) from PID 72;
stack trace: ***
@ 0x2af618d37340 (unknown)
@ 0x902276 mesos::internal::tests::Cluster::Slaves::shutdown()
@ 0xdfc8d4 mesos::internal::tests::MesosTest::ShutdownSlaves()
@ 0xdfc898 mesos::internal::tests::MesosTest::Shutdown()
@ 0xdfa09f mesos::internal::tests::MesosTest::TearDown()
@ 0x1093796
testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x108e868
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x1076bc2 testing::Test::Run()
@ 0x107729c testing::TestInfo::Run()
@ 0x1077824 testing::TestCase::Run()
@ 0x107c4ee testing::internal::UnitTestImpl::RunAllTests()
@ 0x109462c
testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x108f59b
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x107b3fa testing::UnitTest::Run()
@ 0xd32273 main
@ 0x2af618f66ec5 (unknown)
@ 0x8daa59 (unknown)
make[3]: *** [check-local] Segmentation fault
make[3]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/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-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory
`<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results
