Tracked here: https://issues.apache.org/jira/browse/MESOS-4961
On Tue, Mar 29, 2016 at 11:22 AM, Benjamin Mahler <[email protected]> wrote: > +joseph > > On Mon, Mar 28, 2016 at 11:32 PM, Apache Jenkins Server < > [email protected]> wrote: > >> See < >> https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1888/changes >> > >> >> Changes: >> >> [yujie.jay] Adapted port_mapping isolator with missing subprocess >> parameter. >> >> [yujie.jay] Fixed typo in subprocess doxygen comments. >> >> ------------------------------------------ >> [...truncated 178901 lines...] >> I0329 06:24:26.085312 32305 master.cpp:378] Flags at startup: --acls="" >> --allocation_interval="1secs" --allocator="HierarchicalDRF" >> --authenticate="false" --authenticate_http="true" >> --authenticate_slaves="true" --authenticators="crammd5" >> --authorizers="local" --credentials="/tmp/h70a8A/credentials" >> --framework_sorter="drf" --help="false" --hostname_lookup="true" >> --http_authenticators="basic" --initialize_driver_logging="true" >> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" >> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" >> --max_slave_ping_timeouts="5" --quiet="false" >> --recovery_slave_removal_limit="100%" --registry="replicated_log" >> --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" >> --registry_strict="true" --root_submissions="true" >> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" >> --user_sorter="drf" --version="false" >> --webui_dir="/mesos/mesos-0.29.0/_inst/share/mesos/webui" >> --work_dir="/tmp/h70a8A/master" --zk_session_timeout="10secs" >> I0329 06:24:26.085846 32305 master.cpp:429] Master allowing >> unauthenticated frameworks to register >> I0329 06:24:26.085932 32305 master.cpp:432] Master only allowing >> authenticated slaves to register >> I0329 06:24:26.086014 32305 credentials.hpp:37] Loading credentials for >> authentication from '/tmp/h70a8A/credentials' >> I0329 06:24:26.089220 32305 master.cpp:474] Using default 'crammd5' >> authenticator >> I0329 06:24:26.089391 32305 master.cpp:545] Using default 'basic' HTTP >> authenticator >> I0329 06:24:26.089530 32305 master.cpp:583] Authorization enabled >> I0329 06:24:26.092001 32305 hierarchical.cpp:144] Initialized >> hierarchical allocator process >> I0329 06:24:26.092093 32305 whitelist_watcher.cpp:77] No whitelist given >> I0329 06:24:26.093214 32310 master.cpp:1826] The newly elected leader is >> [email protected]:44577 with id c0df3402-8c18-4794-aea1-d0fc2093455d >> I0329 06:24:26.093361 32310 master.cpp:1839] Elected as the leading >> master! >> I0329 06:24:26.093479 32310 master.cpp:1526] Recovering from registrar >> I0329 06:24:26.093793 32302 registrar.cpp:307] Recovering registrar >> I0329 06:24:26.097321 32299 leveldb.cpp:304] Persisting metadata (8 >> bytes) to leveldb took 28.543546ms >> I0329 06:24:26.097383 32299 replica.cpp:320] Persisted replica status to >> STARTING >> I0329 06:24:26.097652 32299 recover.cpp:473] Replica is in STARTING status >> I0329 06:24:26.099536 32300 replica.cpp:673] Replica in STARTING status >> received a broadcasted recover request from (16412)@172.17.0.2:44577 >> I0329 06:24:26.100143 32300 recover.cpp:193] Received a recover response >> from a replica in STARTING status >> I0329 06:24:26.100862 32300 recover.cpp:564] Updating replica status to >> VOTING >> I0329 06:24:26.131098 32300 leveldb.cpp:304] Persisting metadata (8 >> bytes) to leveldb took 30.047383ms >> I0329 06:24:26.131202 32300 replica.cpp:320] Persisted replica status to >> VOTING >> I0329 06:24:26.131471 32300 recover.cpp:578] Successfully joined the >> Paxos group >> I0329 06:24:26.131674 32300 recover.cpp:462] Recover process terminated >> I0329 06:24:26.132522 32300 log.cpp:659] Attempting to start the writer >> I0329 06:24:26.134305 32300 replica.cpp:493] Replica received implicit >> promise request from (16413)@172.17.0.2:44577 with proposal 1 >> I0329 06:24:26.164098 32300 leveldb.cpp:304] Persisting metadata (8 >> bytes) to leveldb took 29.777838ms >> I0329 06:24:26.164202 32300 replica.cpp:342] Persisted promised to 1 >> I0329 06:24:26.174093 32307 coordinator.cpp:238] Coordinator attempting >> to fill missing positions >> I0329 06:24:26.177204 32307 replica.cpp:388] Replica received explicit >> promise request from (16414)@172.17.0.2:44577 for position 0 with >> proposal 2 >> I0329 06:24:26.202256 32307 leveldb.cpp:341] Persisting action (8 bytes) >> to leveldb took 24.828611ms >> I0329 06:24:26.202365 32307 replica.cpp:712] Persisted action at 0 >> I0329 06:24:26.204294 32307 replica.cpp:537] Replica received write >> request for position 0 from (16415)@172.17.0.2:44577 >> I0329 06:24:26.204396 32307 leveldb.cpp:436] Reading position from >> leveldb took 63731ns >> I0329 06:24:26.227409 32307 leveldb.cpp:341] Persisting action (14 bytes) >> to leveldb took 22.997586ms >> I0329 06:24:26.227517 32307 replica.cpp:712] Persisted action at 0 >> I0329 06:24:26.233665 32299 replica.cpp:691] Replica received learned >> notice for position 0 from @0.0.0.0:0 >> I0329 06:24:26.252598 32299 leveldb.cpp:341] Persisting action (16 bytes) >> to leveldb took 18.804081ms >> I0329 06:24:26.252712 32299 replica.cpp:712] Persisted action at 0 >> I0329 06:24:26.252753 32299 replica.cpp:697] Replica learned NOP action >> at position 0 >> I0329 06:24:26.254039 32299 log.cpp:675] Writer started with ending >> position 0 >> I0329 06:24:26.255581 32299 leveldb.cpp:436] Reading position from >> leveldb took 100584ns >> I0329 06:24:26.263558 32306 registrar.cpp:340] Successfully fetched the >> registry (0B) in 169.595904ms >> I0329 06:24:26.263902 32306 registrar.cpp:439] Applied 1 operations in >> 60146ns; attempting to update the 'registry' >> I0329 06:24:26.266656 32304 log.cpp:683] Attempting to append 170 bytes >> to the log >> I0329 06:24:26.266984 32304 coordinator.cpp:348] Coordinator attempting >> to write APPEND action at position 1 >> I0329 06:24:26.268291 32304 replica.cpp:537] Replica received write >> request for position 1 from (16416)@172.17.0.2:44577 >> I0329 06:24:26.294421 32304 leveldb.cpp:341] Persisting action (189 >> bytes) to leveldb took 26.109178ms >> I0329 06:24:26.294524 32304 replica.cpp:712] Persisted action at 1 >> I0329 06:24:26.296066 32304 replica.cpp:691] Replica received learned >> notice for position 1 from @0.0.0.0:0 >> I0329 06:24:26.319604 32304 leveldb.cpp:341] Persisting action (191 >> bytes) to leveldb took 23.539377ms >> I0329 06:24:26.319721 32304 replica.cpp:712] Persisted action at 1 >> I0329 06:24:26.319761 32304 replica.cpp:697] Replica learned APPEND >> action at position 1 >> I0329 06:24:26.322026 32304 registrar.cpp:484] Successfully updated the >> 'registry' in 58.029056ms >> I0329 06:24:26.322201 32304 registrar.cpp:370] Successfully recovered >> registrar >> I0329 06:24:26.322477 32304 log.cpp:702] Attempting to truncate the log >> to 1 >> I0329 06:24:26.323202 32309 master.cpp:1634] Recovered 0 slaves from the >> Registry (131B) ; allowing 10mins for slaves to re-register >> I0329 06:24:26.323429 32309 hierarchical.cpp:171] Skipping recovery of >> hierarchical allocator: nothing to recover >> I0329 06:24:26.323717 32296 coordinator.cpp:348] Coordinator attempting >> to write TRUNCATE action at position 2 >> I0329 06:24:26.325094 32296 replica.cpp:537] Replica received write >> request for position 2 from (16417)@172.17.0.2:44577 >> I0329 06:24:26.344977 32296 leveldb.cpp:341] Persisting action (16 bytes) >> to leveldb took 19.869767ms >> I0329 06:24:26.345098 32296 replica.cpp:712] Persisted action at 2 >> I0329 06:24:26.346740 32296 replica.cpp:691] Replica received learned >> notice for position 2 from @0.0.0.0:0 >> I0329 06:24:26.370178 32296 leveldb.cpp:341] Persisting action (18 bytes) >> to leveldb took 23.433093ms >> I0329 06:24:26.370374 32296 leveldb.cpp:399] Deleting ~1 keys from >> leveldb took 101580ns >> I0329 06:24:26.370405 32296 replica.cpp:712] Persisted action at 2 >> I0329 06:24:26.370445 32296 replica.cpp:697] Replica learned TRUNCATE >> action at position 2 >> I0329 06:24:26.375056 32278 scheduler.cpp:172] Version: 0.29.0 >> I0329 06:24:26.376142 32296 scheduler.cpp:438] New master detected at >> [email protected]:44577 >> I0329 06:24:26.378262 32304 scheduler.cpp:338] Connected with the master >> at http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.379989 32298 scheduler.cpp:231] Sending SUBSCRIBE call to >> http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.381155 32304 process.cpp:3136] Handling HTTP event for process >> 'master' with path: '/master/api/v1/scheduler' >> I0329 06:24:26.381928 32297 http.cpp:313] HTTP POST for >> /master/api/v1/scheduler from 172.17.0.2:60727 >> I0329 06:24:26.382292 32297 master.cpp:2088] Received subscription >> request for HTTP framework 'default' >> I0329 06:24:26.382323 32297 master.cpp:1865] Authorizing framework >> principal 'test-principal' to receive offers for role '*' >> I0329 06:24:26.383196 32297 master.cpp:2179] Subscribing framework >> 'default' with checkpointing disabled and capabilities [ ] >> I0329 06:24:26.384454 32297 hierarchical.cpp:266] Added framework >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 >> I0329 06:24:26.384557 32297 hierarchical.cpp:1490] No resources available >> to allocate! >> I0329 06:24:26.384598 32297 hierarchical.cpp:1585] No inverse offers to >> send out! >> I0329 06:24:26.384667 32297 hierarchical.cpp:1141] Performed allocation >> for 0 slaves in 195737ns >> I0329 06:24:26.384935 32297 master.hpp:1715] Sending heartbeat to >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 >> I0329 06:24:26.385965 32306 scheduler.cpp:629] Enqueuing event SUBSCRIBED >> received from http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.386927 32306 scheduler.cpp:629] Enqueuing event HEARTBEAT received >> from http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.388162 32301 scheduler.cpp:231] Sending REQUEST call to >> http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.389529 32301 process.cpp:3136] Handling HTTP event for process >> 'master' with path: '/master/api/v1/scheduler' >> I0329 06:24:26.390229 32300 http.cpp:313] HTTP POST for >> /master/api/v1/scheduler from 172.17.0.2:60728 >> I0329 06:24:26.390472 32300 master.cpp:2785] Processing REQUEST call for >> framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) >> I0329 06:24:26.390704 32303 hierarchical.cpp:592] Received resource >> request from framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 >> I0329 06:24:26.394057 32305 master.cpp:1269] Framework >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) disconnected >> I0329 06:24:26.394111 32305 master.cpp:2642] Disconnecting framework >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) >> I0329 06:24:26.394139 32305 master.cpp:2666] Deactivating framework >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) >> I0329 06:24:26.394261 32305 master.cpp:1293] Giving framework >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) 0ns to failover >> I0329 06:24:26.394523 32305 hierarchical.cpp:378] Deactivated framework >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 >> I0329 06:24:26.395911 32305 master.cpp:5360] Framework failover timeout, >> removing framework c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) >> I0329 06:24:26.395953 32305 master.cpp:6093] Removing framework >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 (default) >> I0329 06:24:26.396348 32305 hierarchical.cpp:329] Removed framework >> c0df3402-8c18-4794-aea1-d0fc2093455d-0000 >> I0329 06:24:26.404429 32278 master.cpp:1083] Master terminating >> [ OK ] ContentType/SchedulerTest.Request/0 (666 ms) >> [ RUN ] ContentType/SchedulerTest.Request/1 >> I0329 06:24:26.430703 32278 cluster.cpp:139] Creating default 'local' >> authorizer >> I0329 06:24:26.515738 32278 leveldb.cpp:174] Opened db in 84.58043ms >> I0329 06:24:26.541720 32278 leveldb.cpp:181] Compacted db in 25.941366ms >> I0329 06:24:26.541827 32278 leveldb.cpp:196] Created db iterator in >> 35061ns >> I0329 06:24:26.541844 32278 leveldb.cpp:202] Seeked to beginning of db in >> 9132ns >> I0329 06:24:26.541856 32278 leveldb.cpp:271] Iterated through 0 keys in >> the db in 6113ns >> I0329 06:24:26.541901 32278 replica.cpp:779] Replica recovered with log >> positions 0 -> 0 with 1 holes and 0 unlearned >> I0329 06:24:26.542896 32309 recover.cpp:447] Starting replica recovery >> I0329 06:24:26.543231 32309 recover.cpp:473] Replica is in EMPTY status >> I0329 06:24:26.544759 32302 replica.cpp:673] Replica in EMPTY status >> received a broadcasted recover request from (16425)@172.17.0.2:44577 >> I0329 06:24:26.545496 32309 recover.cpp:193] Received a recover response >> from a replica in EMPTY status >> I0329 06:24:26.546010 32309 recover.cpp:564] Updating replica status to >> STARTING >> I0329 06:24:26.550267 32300 master.cpp:376] Master >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c (58e403dcc250) started on >> 172.17.0.2:44577 >> I0329 06:24:26.550334 32300 master.cpp:378] Flags at startup: --acls="" >> --allocation_interval="1secs" --allocator="HierarchicalDRF" >> --authenticate="false" --authenticate_http="true" >> --authenticate_slaves="true" --authenticators="crammd5" >> --authorizers="local" --credentials="/tmp/rgiyDU/credentials" >> --framework_sorter="drf" --help="false" --hostname_lookup="true" >> --http_authenticators="basic" --initialize_driver_logging="true" >> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" >> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" >> --max_slave_ping_timeouts="5" --quiet="false" >> --recovery_slave_removal_limit="100%" --registry="replicated_log" >> --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" >> --registry_strict="true" --root_submissions="true" >> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" >> --user_sorter="drf" --version="false" >> --webui_dir="/mesos/mesos-0.29.0/_inst/share/mesos/webui" >> --work_dir="/tmp/rgiyDU/master" --zk_session_timeout="10secs" >> I0329 06:24:26.550730 32300 master.cpp:429] Master allowing >> unauthenticated frameworks to register >> I0329 06:24:26.550742 32300 master.cpp:432] Master only allowing >> authenticated slaves to register >> I0329 06:24:26.550750 32300 credentials.hpp:37] Loading credentials for >> authentication from '/tmp/rgiyDU/credentials' >> I0329 06:24:26.551120 32300 master.cpp:474] Using default 'crammd5' >> authenticator >> I0329 06:24:26.551282 32300 master.cpp:545] Using default 'basic' HTTP >> authenticator >> I0329 06:24:26.551408 32300 master.cpp:583] Authorization enabled >> I0329 06:24:26.553966 32301 whitelist_watcher.cpp:77] No whitelist given >> I0329 06:24:26.554941 32308 master.cpp:1826] The newly elected leader is >> [email protected]:44577 with id e055dc0c-92ec-4a53-9dad-b00b45fc8a0c >> I0329 06:24:26.555119 32308 master.cpp:1839] Elected as the leading >> master! >> I0329 06:24:26.555203 32308 master.cpp:1526] Recovering from registrar >> I0329 06:24:26.555528 32308 registrar.cpp:307] Recovering registrar >> I0329 06:24:26.556252 32300 hierarchical.cpp:144] Initialized >> hierarchical allocator process >> I0329 06:24:26.583319 32309 leveldb.cpp:304] Persisting metadata (8 >> bytes) to leveldb took 37.070461ms >> I0329 06:24:26.583428 32309 replica.cpp:320] Persisted replica status to >> STARTING >> I0329 06:24:26.583833 32300 recover.cpp:473] Replica is in STARTING status >> I0329 06:24:26.585232 32307 replica.cpp:673] Replica in STARTING status >> received a broadcasted recover request from (16427)@172.17.0.2:44577 >> I0329 06:24:26.585587 32299 recover.cpp:193] Received a recover response >> from a replica in STARTING status >> I0329 06:24:26.586372 32307 recover.cpp:564] Updating replica status to >> VOTING >> I0329 06:24:26.625116 32298 leveldb.cpp:304] Persisting metadata (8 >> bytes) to leveldb took 38.529877ms >> I0329 06:24:26.625231 32298 replica.cpp:320] Persisted replica status to >> VOTING >> I0329 06:24:26.625517 32303 recover.cpp:578] Successfully joined the >> Paxos group >> I0329 06:24:26.625844 32303 recover.cpp:462] Recover process terminated >> I0329 06:24:26.626432 32311 log.cpp:659] Attempting to start the writer >> I0329 06:24:26.627914 32309 replica.cpp:493] Replica received implicit >> promise request from (16428)@172.17.0.2:44577 with proposal 1 >> I0329 06:24:26.658563 32309 leveldb.cpp:304] Persisting metadata (8 >> bytes) to leveldb took 30.632068ms >> I0329 06:24:26.658671 32309 replica.cpp:342] Persisted promised to 1 >> I0329 06:24:26.659965 32299 coordinator.cpp:238] Coordinator attempting >> to fill missing positions >> I0329 06:24:26.661671 32307 replica.cpp:388] Replica received explicit >> promise request from (16429)@172.17.0.2:44577 for position 0 with >> proposal 2 >> I0329 06:24:26.700374 32307 leveldb.cpp:341] Persisting action (8 bytes) >> to leveldb took 38.66346ms >> I0329 06:24:26.700486 32307 replica.cpp:712] Persisted action at 0 >> I0329 06:24:26.702354 32301 replica.cpp:537] Replica received write >> request for position 0 from (16430)@172.17.0.2:44577 >> I0329 06:24:26.702471 32301 leveldb.cpp:436] Reading position from >> leveldb took 60629ns >> I0329 06:24:26.733788 32301 leveldb.cpp:341] Persisting action (14 bytes) >> to leveldb took 31.29248ms >> I0329 06:24:26.733875 32301 replica.cpp:712] Persisted action at 0 >> I0329 06:24:26.734787 32307 replica.cpp:691] Replica received learned >> notice for position 0 from @0.0.0.0:0 >> I0329 06:24:26.766201 32307 leveldb.cpp:341] Persisting action (16 bytes) >> to leveldb took 31.401125ms >> I0329 06:24:26.766286 32307 replica.cpp:712] Persisted action at 0 >> I0329 06:24:26.766320 32307 replica.cpp:697] Replica learned NOP action >> at position 0 >> I0329 06:24:26.767251 32301 log.cpp:675] Writer started with ending >> position 0 >> I0329 06:24:26.768996 32298 leveldb.cpp:436] Reading position from >> leveldb took 111416ns >> I0329 06:24:26.770465 32298 registrar.cpp:340] Successfully fetched the >> registry (0B) in 214.683136ms >> I0329 06:24:26.770622 32298 registrar.cpp:439] Applied 1 operations in >> 54730ns; attempting to update the 'registry' >> I0329 06:24:26.772197 32298 log.cpp:683] Attempting to append 170 bytes >> to the log >> I0329 06:24:26.772478 32299 coordinator.cpp:348] Coordinator attempting >> to write APPEND action at position 1 >> I0329 06:24:26.773361 32302 replica.cpp:537] Replica received write >> request for position 1 from (16431)@172.17.0.2:44577 >> I0329 06:24:26.812733 32302 leveldb.cpp:341] Persisting action (189 >> bytes) to leveldb took 39.346476ms >> I0329 06:24:26.812857 32302 replica.cpp:712] Persisted action at 1 >> I0329 06:24:26.813958 32299 replica.cpp:691] Replica received learned >> notice for position 1 from @0.0.0.0:0 >> I0329 06:24:26.837826 32299 leveldb.cpp:341] Persisting action (191 >> bytes) to leveldb took 23.866792ms >> I0329 06:24:26.837930 32299 replica.cpp:712] Persisted action at 1 >> I0329 06:24:26.837967 32299 replica.cpp:697] Replica learned APPEND >> action at position 1 >> I0329 06:24:26.839619 32311 registrar.cpp:484] Successfully updated the >> 'registry' in 68.916992ms >> I0329 06:24:26.839851 32311 registrar.cpp:370] Successfully recovered >> registrar >> I0329 06:24:26.839921 32300 log.cpp:702] Attempting to truncate the log >> to 1 >> I0329 06:24:26.840009 32299 coordinator.cpp:348] Coordinator attempting >> to write TRUNCATE action at position 2 >> I0329 06:24:26.840361 32300 master.cpp:1634] Recovered 0 slaves from the >> Registry (131B) ; allowing 10mins for slaves to re-register >> I0329 06:24:26.840445 32305 hierarchical.cpp:171] Skipping recovery of >> hierarchical allocator: nothing to recover >> I0329 06:24:26.841119 32306 replica.cpp:537] Replica received write >> request for position 2 from (16432)@172.17.0.2:44577 >> I0329 06:24:26.863555 32306 leveldb.cpp:341] Persisting action (16 bytes) >> to leveldb took 22.418343ms >> I0329 06:24:26.863641 32306 replica.cpp:712] Persisted action at 2 >> I0329 06:24:26.865054 32306 replica.cpp:691] Replica received learned >> notice for position 2 from @0.0.0.0:0 >> I0329 06:24:26.883360 32306 leveldb.cpp:341] Persisting action (18 bytes) >> to leveldb took 18.305162ms >> I0329 06:24:26.883512 32306 leveldb.cpp:399] Deleting ~1 keys from >> leveldb took 83579ns >> I0329 06:24:26.883538 32306 replica.cpp:712] Persisted action at 2 >> I0329 06:24:26.883570 32306 replica.cpp:697] Replica learned TRUNCATE >> action at position 2 >> I0329 06:24:26.891973 32278 scheduler.cpp:172] Version: 0.29.0 >> I0329 06:24:26.892935 32302 scheduler.cpp:438] New master detected at >> [email protected]:44577 >> I0329 06:24:26.894947 32298 scheduler.cpp:338] Connected with the master >> at http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.896307 32309 scheduler.cpp:231] Sending SUBSCRIBE call to >> http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.897629 32309 process.cpp:3136] Handling HTTP event for process >> 'master' with path: '/master/api/v1/scheduler' >> I0329 06:24:26.898205 32309 http.cpp:313] HTTP POST for >> /master/api/v1/scheduler from 172.17.0.2:60732 >> I0329 06:24:26.898597 32309 master.cpp:2088] Received subscription >> request for HTTP framework 'default' >> I0329 06:24:26.898622 32309 master.cpp:1865] Authorizing framework >> principal 'test-principal' to receive offers for role '*' >> I0329 06:24:26.899363 32309 master.cpp:2179] Subscribing framework >> 'default' with checkpointing disabled and capabilities [ ] >> I0329 06:24:26.900554 32309 hierarchical.cpp:266] Added framework >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 >> I0329 06:24:26.900652 32309 hierarchical.cpp:1490] No resources available >> to allocate! >> I0329 06:24:26.900890 32303 master.hpp:1715] Sending heartbeat to >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 >> I0329 06:24:26.902096 32304 scheduler.cpp:629] Enqueuing event SUBSCRIBED >> received from http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.902498 32304 scheduler.cpp:629] Enqueuing event HEARTBEAT received >> from http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.902828 32309 hierarchical.cpp:1585] No inverse offers to send out! >> I0329 06:24:26.902901 32309 hierarchical.cpp:1141] Performed allocation >> for 0 slaves in 2.330913ms >> I0329 06:24:26.903455 32308 scheduler.cpp:231] Sending REQUEST call to >> http://172.17.0.2:44577/master/api/v1/scheduler >> I0329 <http://172.17.0.2:44577/master/api/v1/schedulerI0329> >> 06:24:26.904709 32302 process.cpp:3136] Handling HTTP event for process >> 'master' with path: '/master/api/v1/scheduler' >> I0329 06:24:26.905304 32302 http.cpp:313] HTTP POST for >> /master/api/v1/scheduler from 172.17.0.2:60733 >> I0329 06:24:26.905647 32302 master.cpp:2785] Processing REQUEST call for >> framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) >> I0329 06:24:26.905892 32302 hierarchical.cpp:592] Received resource >> request from framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 >> I0329 06:24:26.908712 32304 master.cpp:1269] Framework >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) disconnected >> I0329 06:24:26.908759 32304 master.cpp:2642] Disconnecting framework >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) >> I0329 06:24:26.908783 32304 master.cpp:2666] Deactivating framework >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) >> I0329 06:24:26.908882 32304 master.cpp:1293] Giving framework >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) 0ns to failover >> I0329 06:24:26.909106 32304 hierarchical.cpp:378] Deactivated framework >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 >> I0329 06:24:26.910423 32304 master.cpp:5360] Framework failover timeout, >> removing framework e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) >> I0329 06:24:26.910450 32304 master.cpp:6093] Removing framework >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 (default) >> I0329 06:24:26.910758 32304 hierarchical.cpp:329] Removed framework >> e055dc0c-92ec-4a53-9dad-b00b45fc8a0c-0000 >> I0329 06:24:26.919173 32278 master.cpp:1083] Master terminating >> [ OK ] ContentType/SchedulerTest.Request/1 (499 ms) >> [----------] 26 tests from ContentType/SchedulerTest (19532 ms total) >> >> [----------] Global test environment tear-down >> E0329 06:24:27.207061 32278 environment.cpp:778] Failed to remove >> '/tmp/LogZooKeeperTest_WriteRead_isx6ON': No such file or directory >> E0329 06:24:27.207288 32278 environment.cpp:778] Failed to remove >> '/tmp/LogZooKeeperTest_LostZooKeeper_UHzb62': No such file or directory >> [==========] 1052 tests from 132 test cases ran. (788517 ms total) >> [ PASSED ] 1051 tests. >> [ FAILED ] 1 test, listed below: >> [ FAILED ] ContainerLoggerTest.LOGROTATE_RotateInSandbox >> >> 1 FAILED TEST >> YOU HAVE 6 DISABLED TESTS >> >> make[4]: *** [check-local] Error 1 >> make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src' >> make[3]: *** [check-am] Error 2 >> make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src' >> make[2]: *** [check] Error 2 >> make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src' >> make[1]: Leaving directory `/mesos/mesos-0.29.0/_build' >> make[1]: *** [check-recursive] Error 1 >> make: *** [distcheck] Error 1 >> + docker rmi mesos-1459230127-13906 >> Untagged: mesos-1459230127-13906:latest >> Deleted: cacb8f6afe4d7fdbe1ac7bf1ad0338181413f3a81b5b1c5cf56bf2667f810aad >> Deleted: f48e36119876751f898378fa38c0e9cc8f01f3566284450b1e917b855f4520c5 >> Deleted: 90526e7a88d474bb45f1edb44c7cc3eea0151ecca4be9ec0d3765d1888fa8315 >> Deleted: 76e7df84fa713c1a9786f5f1f4c4dda9be08c32e292c151e01049bb1cbcd448c >> Deleted: cac845a110e827f30f8bcde5084d1a8b2b252fd7e3593d123763a75d1d9f7af8 >> Deleted: 2304e9792ef33b93a77f035160b9de70501c135bddfe18a6f16023f51faeb60a >> Deleted: 6eb7b0792e8661dd7e8d1d95eb8549bda73ce4da64e2274240ebf39d73ee0ffb >> Deleted: cb90fa476408199deb8e11824723b16bc05ca545548b773aa30a49f2295f8f77 >> Deleted: f7d306edca1ab89b22abc8e15bd4bac995e67183c4620cf654c15721598f9c4d >> Deleted: 76558ca548aa164ffe882d74281b3d03f6be8cc76b5b71b7b6cee7d1e95f9677 >> Deleted: a236590f9a5e81a39c2acf4e88ba0dc7706b44b6093ddb6a37d5809c6e27f3e7 >> Deleted: 6507f80835107a1b45ec2612528378e6ea33acc3ab14e635cd9bb4e6cf217073 >> Deleted: 8fbd0d2550c193ccb5107a9a13540cd1646e18d01286449d09cb9c7184d77186 >> Deleted: bc9b5531d8a17a788ca2cec0731fdc7ef3f3e7d3a0e6c8c10e5e20dbc38a60d5 >> Deleted: b005ae3936a4887eeee6a9ce4d5a41ba3e61b8ea4267ed01e09a3cbdca026684 >> Deleted: 80c1f8ce2529191ea73a5ab2339172c0c801f7e56bd326b240015eae5c680502 >> Build step 'Execute shell' marked build as failure >> > >
