Mailing-List: contact builds-help@mesos.apache.org; run by ezmlm
Precedence: bulk
Reply-To: builds@mesos.apache.org
Date: Mon, 23 Nov 2015 21:29:26 +0000 (UTC)
From: Apache Jenkins Server  <jenkins@builds.apache.org>
To: builds@mesos.apache.org, joris.van.remoortere@gmail.com,
 tnachen@apache.org
Message-ID: <1407105779.6330.1448314166688.JavaMail.jenkins@crius>
Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_Mesos_=C2=BB_gcc,-?=
 =?UTF-8?Q?-verbose,centos:7,docker||Hadoop_#1266?=
MIME-Version: 1.0
Content-Type: text/plain; charset=UTF-8
Content-Transfer-Encoding: 7bit

See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1266/changes>

Changes:

[joris.van.remoortere] Libprocess: Removed some undocumented, commented-out code.

[joris.van.remoortere] Documented and simplified libprocess initialization.

[joris.van.remoortere] Libprocess `Once`: removed unused includes.

[joris.van.remoortere] Libprocess Reinitialization: Implemented Clock::finalize.

[tnachen] Fixed registry client getBlob invocation to use defer.

------------------------------------------
[...truncated 141940 lines...]
I1123 21:29:20.543298 31326 slave.cpp:3655] Cleaning up executor 'default' of framework f1db2631-9a39-441a-b182-d049f32973d6-0000 at executor(132)@172.17.9.96:49700
I1123 21:29:20.543642 31326 slave.cpp:3743] Cleaning up framework f1db2631-9a39-441a-b182-d049f32973d6-0000
I1123 21:29:20.543854 31326 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_CXP0xm/slaves/f1db2631-9a39-441a-b182-d049f32973d6-S0/frameworks/f1db2631-9a39-441a-b182-d049f32973d6-0000/executors/default/runs/14bb9190-ae1a-4dd3-a874-785298bcd3f8' for gc 6.99999371019852days in the future
I1123 21:29:20.544030 31325 status_update_manager.cpp:282] Closing status update streams for framework f1db2631-9a39-441a-b182-d049f32973d6-0000
I1123 21:29:20.544049 31326 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_CXP0xm/slaves/f1db2631-9a39-441a-b182-d049f32973d6-S0/frameworks/f1db2631-9a39-441a-b182-d049f32973d6-0000/executors/default' for gc 6.99999370843852days in the future
I1123 21:29:20.544088 31325 status_update_manager.cpp:528] Cleaning up status update stream for task 19246088-8879-4b5e-ae22-ed266aba5be2 of framework f1db2631-9a39-441a-b182-d049f32973d6-0000
I1123 21:29:20.544128 31326 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_CXP0xm/slaves/f1db2631-9a39-441a-b182-d049f32973d6-S0/frameworks/f1db2631-9a39-441a-b182-d049f32973d6-0000' for gc 6.99999370673778days in the future
[       OK ] ContentType/SchedulerTest.Message/1 (170 ms)
[ RUN      ] ContentType/SchedulerTest.Request/0
I1123 21:29:20.565492 31306 leveldb.cpp:174] Opened db in 2.508495ms
I1123 21:29:20.566257 31306 leveldb.cpp:181] Compacted db in 709760ns
I1123 21:29:20.566308 31306 leveldb.cpp:196] Created db iterator in 24517ns
I1123 21:29:20.566325 31306 leveldb.cpp:202] Seeked to beginning of db in 1716ns
I1123 21:29:20.566336 31306 leveldb.cpp:271] Iterated through 0 keys in the db in 271ns
I1123 21:29:20.566392 31306 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1123 21:29:20.567229 31339 recover.cpp:447] Starting replica recovery
I1123 21:29:20.567529 31339 recover.cpp:473] Replica is in EMPTY status
I1123 21:29:20.568984 31326 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (10706)@172.17.9.96:49700
I1123 21:29:20.569634 31339 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1123 21:29:20.570233 31339 recover.cpp:564] Updating replica status to STARTING
I1123 21:29:20.571117 31339 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 626624ns
I1123 21:29:20.571173 31339 replica.cpp:321] Persisted replica status to STARTING
I1123 21:29:20.572089 31339 recover.cpp:473] Replica is in STARTING status
I1123 21:29:20.574038 31327 master.cpp:365] Master aa7a12c4-060b-46fb-a02a-6091b35d54ee (c1f4fc9eec05) started on 172.17.9.96:49700
I1123 21:29:20.574069 31327 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/0Ua3Z8/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/0Ua3Z8/master" --zk_session_timeout="10secs"
I1123 21:29:20.574399 31327 master.cpp:414] Master allowing unauthenticated frameworks to register
I1123 21:29:20.574414 31327 master.cpp:417] Master only allowing authenticated slaves to register
I1123 21:29:20.574424 31327 credentials.hpp:35] Loading credentials for authentication from '/tmp/0Ua3Z8/credentials'
I1123 21:29:20.574740 31327 master.cpp:456] Using default 'crammd5' authenticator
I1123 21:29:20.574872 31327 master.cpp:493] Authorization enabled
I1123 21:29:20.576459 31325 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (10708)@172.17.9.96:49700
I1123 21:29:20.576638 31325 hierarchical.cpp:162] Initialized hierarchical allocator process
I1123 21:29:20.576711 31325 whitelist_watcher.cpp:77] No whitelist given
I1123 21:29:20.577522 31340 recover.cpp:193] Received a recover response from a replica in STARTING status
I1123 21:29:20.578083 31327 master.cpp:1625] The newly elected leader is master@172.17.9.96:49700 with id aa7a12c4-060b-46fb-a02a-6091b35d54ee
I1123 21:29:20.578114 31327 master.cpp:1638] Elected as the leading master!
I1123 21:29:20.578133 31327 master.cpp:1383] Recovering from registrar
I1123 21:29:20.578259 31332 recover.cpp:564] Updating replica status to VOTING
I1123 21:29:20.578743 31327 registrar.cpp:307] Recovering registrar
I1123 21:29:20.580976 31332 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 1.296356ms
I1123 21:29:20.581023 31332 replica.cpp:321] Persisted replica status to VOTING
I1123 21:29:20.584261 31327 recover.cpp:578] Successfully joined the Paxos group
I1123 21:29:20.584453 31327 recover.cpp:462] Recover process terminated
I1123 21:29:20.585034 31327 log.cpp:659] Attempting to start the writer
I1123 21:29:20.586565 31327 replica.cpp:494] Replica received implicit promise request from (10709)@172.17.9.96:49700 with proposal 1
I1123 21:29:20.587976 31327 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 1.368523ms
I1123 21:29:20.588023 31327 replica.cpp:343] Persisted promised to 1
I1123 21:29:20.589776 31339 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1123 21:29:20.591692 31334 replica.cpp:389] Replica received explicit promise request from (10710)@172.17.9.96:49700 for position 0 with proposal 2
I1123 21:29:20.592972 31334 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 1.224376ms
I1123 21:29:20.593017 31334 replica.cpp:713] Persisted action at 0
I1123 21:29:20.594323 31333 replica.cpp:538] Replica received write request for position 0 from (10711)@172.17.9.96:49700
I1123 21:29:20.594389 31333 leveldb.cpp:436] Reading position from leveldb took 31078ns
I1123 21:29:20.594962 31333 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 527437ns
I1123 21:29:20.594993 31333 replica.cpp:713] Persisted action at 0
I1123 21:29:20.595567 31329 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1123 21:29:20.596092 31329 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 493771ns
I1123 21:29:20.596122 31329 replica.cpp:713] Persisted action at 0
I1123 21:29:20.596144 31329 replica.cpp:698] Replica learned NOP action at position 0
I1123 21:29:20.596987 31333 log.cpp:675] Writer started with ending position 0
I1123 21:29:20.598415 31325 leveldb.cpp:436] Reading position from leveldb took 38467ns
I1123 21:29:20.599685 31325 registrar.cpp:340] Successfully fetched the registry (0B) in 20896us
I1123 21:29:20.599812 31325 registrar.cpp:439] Applied 1 operations in 32002ns; attempting to update the 'registry'
I1123 21:29:20.601030 31326 log.cpp:683] Attempting to append 174 bytes to the log
I1123 21:29:20.601256 31326 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1123 21:29:20.602083 31326 replica.cpp:538] Replica received write request for position 1 from (10712)@172.17.9.96:49700
I1123 21:29:20.602975 31326 leveldb.cpp:341] Persisting action (193 bytes) to leveldb took 846778ns
I1123 21:29:20.603009 31326 replica.cpp:713] Persisted action at 1
I1123 21:29:20.603847 31329 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1123 21:29:20.604221 31329 leveldb.cpp:341] Persisting action (195 bytes) to leveldb took 339608ns
I1123 21:29:20.604249 31329 replica.cpp:713] Persisted action at 1
I1123 21:29:20.604269 31329 replica.cpp:698] Replica learned APPEND action at position 1
I1123 21:29:20.606164 31340 registrar.cpp:484] Successfully updated the 'registry' in 6.271232ms
I1123 21:29:20.606323 31340 registrar.cpp:370] Successfully recovered registrar
I1123 21:29:20.606813 31340 master.cpp:1435] Recovered 0 slaves from the Registry (135B) ; allowing 10mins for slaves to re-register
I1123 21:29:20.606983 31333 hierarchical.cpp:174] Allocator recovery is not supported yet
I1123 21:29:20.607530 31337 log.cpp:702] Attempting to truncate the log to 1
I1123 21:29:20.607728 31337 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1123 21:29:20.608737 31340 replica.cpp:538] Replica received write request for position 2 from (10713)@172.17.9.96:49700
I1123 21:29:20.609416 31340 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 633259ns
I1123 21:29:20.609539 31340 replica.cpp:713] Persisted action at 2
I1123 21:29:20.610728 31340 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1123 21:29:20.611316 31340 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 365669ns
I1123 21:29:20.611433 31340 leveldb.cpp:399] Deleting ~1 keys from leveldb took 31343ns
I1123 21:29:20.611557 31340 replica.cpp:713] Persisted action at 2
I1123 21:29:20.611657 31340 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1123 21:29:20.620196 31306 scheduler.cpp:154] Version: 0.26.0
I1123 21:29:20.621261 31331 scheduler.cpp:236] New master detected at master@172.17.9.96:49700
I1123 21:29:20.622671 31326 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.9.96:49700
I1123 21:29:20.626178 31332 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1123 21:29:20.626262 31332 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.9.96:58935
I1123 21:29:20.626499 31332 master.cpp:1887] Received subscription request for HTTP framework 'default'
I1123 21:29:20.626576 31332 master.cpp:1664] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1123 21:29:20.627393 31332 master.cpp:1979] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I1123 21:29:20.628093 31329 hierarchical.cpp:220] Added framework aa7a12c4-060b-46fb-a02a-6091b35d54ee-0000
I1123 21:29:20.628152 31329 hierarchical.cpp:1066] No resources available to allocate!
I1123 21:29:20.628191 31329 hierarchical.cpp:1159] No inverse offers to send out!
I1123 21:29:20.628216 31329 hierarchical.cpp:961] Performed allocation for 0 slaves in 95841ns
I1123 21:29:20.628490 31325 master.hpp:1514] Sending heartbeat to aa7a12c4-060b-46fb-a02a-6091b35d54ee-0000
I1123 21:29:20.630259 31329 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.9.96:49700
I1123 21:29:20.631062 31329 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.9.96:49700
I1123 21:29:20.631664 31329 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1123 21:29:20.633150 31329 scheduler.cpp:298] Sending REQUEST call to master@172.17.9.96:49700
I1123 21:29:20.636144 31329 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1123 21:29:20.636220 31329 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.9.96:58936
I1123 21:29:20.636348 31329 master.cpp:2634] Processing REQUEST call for framework aa7a12c4-060b-46fb-a02a-6091b35d54ee-0000 (default)
I1123 21:29:20.636612 31329 hierarchical.cpp:494] Received resource request from framework aa7a12c4-060b-46fb-a02a-6091b35d54ee-0000
I1123 21:29:20.637560 31306 master.cpp:926] Master terminating
I1123 21:29:20.637949 31333 hierarchical.cpp:260] Removed framework aa7a12c4-060b-46fb-a02a-6091b35d54ee-0000
E1123 21:29:20.639835 31330 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[       OK ] ContentType/SchedulerTest.Request/0 (85 ms)
[ RUN      ] ContentType/SchedulerTest.Request/1
I1123 21:29:20.650871 31306 leveldb.cpp:174] Opened db in 2.476731ms
I1123 21:29:20.652164 31306 leveldb.cpp:181] Compacted db in 1.237295ms
I1123 21:29:20.652220 31306 leveldb.cpp:196] Created db iterator in 21847ns
I1123 21:29:20.652240 31306 leveldb.cpp:202] Seeked to beginning of db in 2432ns
I1123 21:29:20.652251 31306 leveldb.cpp:271] Iterated through 0 keys in the db in 299ns
I1123 21:29:20.652295 31306 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1123 21:29:20.653470 31338 recover.cpp:447] Starting replica recovery
I1123 21:29:20.655596 31338 recover.cpp:473] Replica is in EMPTY status
I1123 21:29:20.655869 31338 master.cpp:365] Master 3a98fcfc-4555-4cba-b2e9-bd10faae2212 (c1f4fc9eec05) started on 172.17.9.96:49700
I1123 21:29:20.657637 31325 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (10718)@172.17.9.96:49700
I1123 21:29:20.658033 31325 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1123 21:29:20.658701 31325 recover.cpp:564] Updating replica status to STARTING
I1123 21:29:20.655890 31338 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/LUaexp/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/LUaexp/master" --zk_session_timeout="10secs"
I1123 21:29:20.660291 31338 master.cpp:414] Master allowing unauthenticated frameworks to register
I1123 21:29:20.660305 31338 master.cpp:417] Master only allowing authenticated slaves to register
I1123 21:29:20.660315 31338 credentials.hpp:35] Loading credentials for authentication from '/tmp/LUaexp/credentials'
I1123 21:29:20.660632 31338 master.cpp:456] Using default 'crammd5' authenticator
I1123 21:29:20.660769 31338 master.cpp:493] Authorization enabled
I1123 21:29:20.661484 31340 hierarchical.cpp:162] Initialized hierarchical allocator process
I1123 21:29:20.661751 31326 whitelist_watcher.cpp:77] No whitelist given
I1123 21:29:20.661945 31325 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 928124ns
I1123 21:29:20.661972 31325 replica.cpp:321] Persisted replica status to STARTING
I1123 21:29:20.662271 31331 recover.cpp:473] Replica is in STARTING status
I1123 21:29:20.664000 31332 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (10719)@172.17.9.96:49700
I1123 21:29:20.678273 31338 master.cpp:1625] The newly elected leader is master@172.17.9.96:49700 with id 3a98fcfc-4555-4cba-b2e9-bd10faae2212
I1123 21:29:20.678333 31338 master.cpp:1638] Elected as the leading master!
I1123 21:29:20.678351 31338 master.cpp:1383] Recovering from registrar
I1123 21:29:20.678598 31338 registrar.cpp:307] Recovering registrar
I1123 21:29:20.679441 31336 recover.cpp:193] Received a recover response from a replica in STARTING status
I1123 21:29:20.679982 31337 recover.cpp:564] Updating replica status to VOTING
I1123 21:29:20.680973 31336 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 863395ns
I1123 21:29:20.681005 31336 replica.cpp:321] Persisted replica status to VOTING
I1123 21:29:20.681121 31337 recover.cpp:578] Successfully joined the Paxos group
I1123 21:29:20.681299 31337 recover.cpp:462] Recover process terminated
I1123 21:29:20.681767 31337 log.cpp:659] Attempting to start the writer
I1123 21:29:20.683095 31337 replica.cpp:494] Replica received implicit promise request from (10720)@172.17.9.96:49700 with proposal 1
I1123 21:29:20.683512 31337 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 378395ns
I1123 21:29:20.683538 31337 replica.cpp:343] Persisted promised to 1
I1123 21:29:20.684229 31337 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1123 21:29:20.685642 31331 replica.cpp:389] Replica received explicit promise request from (10721)@172.17.9.96:49700 for position 0 with proposal 2
I1123 21:29:20.686024 31331 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 338164ns
I1123 21:29:20.686050 31331 replica.cpp:713] Persisted action at 0
I1123 21:29:20.692164 31334 replica.cpp:538] Replica received write request for position 0 from (10722)@172.17.9.96:49700
I1123 21:29:20.692256 31334 leveldb.cpp:436] Reading position from leveldb took 39122ns
I1123 21:29:20.692968 31334 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 663430ns
I1123 21:29:20.693001 31334 replica.cpp:713] Persisted action at 0
I1123 21:29:20.693655 31334 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1123 21:29:20.694964 31334 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.276412ms
I1123 21:29:20.695008 31334 replica.cpp:713] Persisted action at 0
I1123 21:29:20.695036 31334 replica.cpp:698] Replica learned NOP action at position 0
I1123 21:29:20.696013 31334 log.cpp:675] Writer started with ending position 0
I1123 21:29:20.697530 31334 leveldb.cpp:436] Reading position from leveldb took 39723ns
I1123 21:29:20.698854 31334 registrar.cpp:340] Successfully fetched the registry (0B) in 20.208128ms
I1123 21:29:20.699013 31334 registrar.cpp:439] Applied 1 operations in 38898ns; attempting to update the 'registry'
I1123 21:29:20.700368 31334 log.cpp:683] Attempting to append 174 bytes to the log
I1123 21:29:20.700615 31334 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1123 21:29:20.702911 31325 replica.cpp:538] Replica received write request for position 1 from (10723)@172.17.9.96:49700
I1123 21:29:20.703974 31325 leveldb.cpp:341] Persisting action (193 bytes) to leveldb took 978188ns
I1123 21:29:20.704006 31325 replica.cpp:713] Persisted action at 1
I1123 21:29:20.705026 31329 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1123 21:29:20.705454 31329 leveldb.cpp:341] Persisting action (195 bytes) to leveldb took 396252ns
I1123 21:29:20.705482 31329 replica.cpp:713] Persisted action at 1
I1123 21:29:20.705502 31329 replica.cpp:698] Replica learned APPEND action at position 1
I1123 21:29:20.706724 31329 registrar.cpp:484] Successfully updated the 'registry' in 7.646976ms
I1123 21:29:20.706873 31329 registrar.cpp:370] Successfully recovered registrar
I1123 21:29:20.707082 31336 log.cpp:702] Attempting to truncate the log to 1
I1123 21:29:20.707263 31329 master.cpp:1435] Recovered 0 slaves from the Registry (135B) ; allowing 10mins for slaves to re-register
I1123 21:29:20.707352 31329 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1123 21:29:20.707375 31336 hierarchical.cpp:174] Allocator recovery is not supported yet
I1123 21:29:20.708336 31339 replica.cpp:538] Replica received write request for position 2 from (10724)@172.17.9.96:49700
I1123 21:29:20.708731 31339 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 351718ns
I1123 21:29:20.708758 31339 replica.cpp:713] Persisted action at 2
I1123 21:29:20.709697 31339 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1123 21:29:20.710072 31339 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 342433ns
I1123 21:29:20.710132 31339 leveldb.cpp:399] Deleting ~1 keys from leveldb took 33056ns
I1123 21:29:20.710155 31339 replica.cpp:713] Persisted action at 2
I1123 21:29:20.710178 31339 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1123 21:29:20.719564 31306 scheduler.cpp:154] Version: 0.26.0
I1123 21:29:20.720574 31336 scheduler.cpp:236] New master detected at master@172.17.9.96:49700
I1123 21:29:20.722352 31330 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.9.96:49700
I1123 21:29:20.725179 31328 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1123 21:29:20.725261 31328 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.9.96:58937
I1123 21:29:20.725700 31328 master.cpp:1887] Received subscription request for HTTP framework 'default'
I1123 21:29:20.725780 31328 master.cpp:1664] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1123 21:29:20.726228 31328 master.cpp:1979] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I1123 21:29:20.727136 31327 hierarchical.cpp:220] Added framework 3a98fcfc-4555-4cba-b2e9-bd10faae2212-0000
I1123 21:29:20.727195 31327 hierarchical.cpp:1066] No resources available to allocate!
I1123 21:29:20.727232 31327 hierarchical.cpp:1159] No inverse offers to send out!
I1123 21:29:20.727257 31327 hierarchical.cpp:961] Performed allocation for 0 slaves in 95392ns
I1123 21:29:20.727286 31327 master.hpp:1514] Sending heartbeat to 3a98fcfc-4555-4cba-b2e9-bd10faae2212-0000
I1123 21:29:20.730841 31333 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.9.96:49700
I1123 21:29:20.731624 31333 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.9.96:49700
I1123 21:29:20.733237 31333 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1123 21:29:20.733868 31329 scheduler.cpp:298] Sending REQUEST call to master@172.17.9.96:49700
I1123 21:29:20.736582 31330 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1123 21:29:20.736749 31330 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.9.96:58938
I1123 21:29:20.737110 31330 master.cpp:2634] Processing REQUEST call for framework 3a98fcfc-4555-4cba-b2e9-bd10faae2212-0000 (default)
I1123 21:29:20.737452 31327 hierarchical.cpp:494] Received resource request from framework 3a98fcfc-4555-4cba-b2e9-bd10faae2212-0000
I1123 21:29:20.738484 31306 master.cpp:926] Master terminating
I1123 21:29:20.738679 31330 hierarchical.cpp:260] Removed framework 3a98fcfc-4555-4cba-b2e9-bd10faae2212-0000
E1123 21:29:20.742779 31336 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[       OK ] ContentType/SchedulerTest.Request/1 (102 ms)
[----------] 22 tests from ContentType/SchedulerTest (5215 ms total)

[----------] Global test environment tear-down
[==========] 840 tests from 114 test cases ran. (385991 ms total)
[  PASSED  ] 839 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] SlaveTest.HTTPSchedulerSlaveRestart

 1 FAILED TEST
  YOU HAVE 7 DISABLED TESTS

make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.26.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1448311999-23301
Untagged: mesos-1448311999-23301:latest
Deleted: 1c5f38c5ffae5ef9732cf480352ec1634ec5085efb174b7cd81e5a8d822f637d
Deleted: 5100566921fc89362a7d757309505deed8fb28d3b38858d5e5032f01cde7f054
Deleted: 45980e5dea18bfb1e96d4b4bdabe8e16a81512b4782ee6aa2e2160f6b15b18f6
Deleted: 4db1f27db5f929480ebeea32e2f7f4679edbfacb9e800db30574528ef185a488
Deleted: 70a2a35e278601d1f42026d039a1e70de593e93f7c2ff67a91cfde513df2eeb2
Deleted: cf595a19b70594b90513f8b955df43260dce150450bb1ceddc14a257cad5a067
Deleted: 55beb0322e954ea895a954f1043dc370728aec52e708ee4c6efb3ff8c1ef0d4f
Deleted: d26c881e0940251e6e3ae955d377a612144e299c994f20f5952a05a6b8a142db
Deleted: 8a7fd3b0aca686bf96a4123c9a27c1b952ec8c9e27c51f89b68c109f87d42697
Deleted: f3a4ac362088d8c3666c9fd4e57133a84a40c70d7d40227899c721c5d175f06e
Deleted: f2ade38e9dd3c1147d5e95d949950ca0f6ee6ed20fc08992965c343744ee3221
Deleted: d4db78f36c5b52361da752374c8718ed5af768f04a554c4a3d0858cda8af429b
Build step 'Execute shell' marked build as failure