Thanks for fixing! On Tue, Mar 29, 2016 at 11:36 AM, Joseph Wu <[email protected]> wrote:
> 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 > >> > > > > >
