Fixed in: https://issues.apache.org/jira/browse/MESOS-3577
On Sat, Oct 3, 2015 at 1:31 PM, Apache Jenkins Server < jenk...@builds.apache.org> wrote: > See < > https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=centos%3A7,label_exp=docker%7C%7CHadoop/890/changes > > > > Changes: > > [joris.van.remoortere] Generate Java V1 Protobufs. > > ------------------------------------------ > [...truncated 134684 lines...] > I1003 20:31:26.935798 31568 slave.cpp:3544] Cleaning up executor 'default' > of framework 224019a0-d443-4ff2-96b4-10a11c17aa3d-0000 > I1003 20:31:26.936024 31571 gc.cpp:56] Scheduling > '/tmp/ContentType_SchedulerTest_Message_1_kDA90t/slaves/224019a0-d443-4ff2-96b4-10a11c17aa3d-S0/frameworks/224019a0-d443-4ff2-96b4-10a11c17aa3d-0000/executors/default/runs/b75fab2e-8565-40d0-9032-859fed21d2e3' > for gc 6.99998916746667days in the future > I1003 20:31:26.936125 31568 slave.cpp:3633] Cleaning up framework > 224019a0-d443-4ff2-96b4-10a11c17aa3d-0000 > I1003 20:31:26.936184 31571 gc.cpp:56] Scheduling > '/tmp/ContentType_SchedulerTest_Message_1_kDA90t/slaves/224019a0-d443-4ff2-96b4-10a11c17aa3d-S0/frameworks/224019a0-d443-4ff2-96b4-10a11c17aa3d-0000/executors/default' > for gc 6.99998916576296days in the future > I1003 20:31:26.936226 31577 status_update_manager.cpp:284] Closing status > update streams for framework 224019a0-d443-4ff2-96b4-10a11c17aa3d-0000 > I1003 20:31:26.936396 31571 gc.cpp:56] Scheduling > '/tmp/ContentType_SchedulerTest_Message_1_kDA90t/slaves/224019a0-d443-4ff2-96b4-10a11c17aa3d-S0/frameworks/224019a0-d443-4ff2-96b4-10a11c17aa3d-0000' > for gc 6.99998916395852days in the future > I1003 20:31:26.936451 31577 status_update_manager.cpp:530] Cleaning up > status update stream for task b3ee7750-63ce-4b9a-917e-04b317621a75 of > framework 224019a0-d443-4ff2-96b4-10a11c17aa3d-0000 > [ OK ] ContentType/SchedulerTest.Message/1 (105 ms) > [ RUN ] ContentType/SchedulerTest.Request/0 > Using temporary directory '/tmp/ContentType_SchedulerTest_Request_0_9KBzRb' > I1003 20:31:26.943343 31549 leveldb.cpp:176] Opened db in 2.643266ms > I1003 20:31:26.944468 31549 leveldb.cpp:183] Compacted db in 1.08934ms > I1003 20:31:26.944531 31549 leveldb.cpp:198] Created db iterator in 21933ns > I1003 20:31:26.944548 31549 leveldb.cpp:204] Seeked to beginning of db in > 1962ns > I1003 20:31:26.944557 31549 leveldb.cpp:273] Iterated through 0 keys in > the db in 391ns > I1003 20:31:26.944602 31549 replica.cpp:744] Replica recovered with log > positions 0 -> 0 with 1 holes and 0 unlearned > I1003 20:31:26.945060 31571 recover.cpp:449] Starting replica recovery > I1003 20:31:26.945502 31571 recover.cpp:475] Replica is in EMPTY status > I1003 20:31:26.946650 31571 replica.cpp:641] Replica in EMPTY status > received a broadcasted recover request > I1003 20:31:26.947477 31577 recover.cpp:195] Received a recover response > from a replica in EMPTY status > I1003 20:31:26.947746 31581 master.cpp:376] Master > 94d2511e-eee6-4866-8e56-974c3005eaf6 (9efc27440ed0) started on > 172.17.5.73:38504 > I1003 20:31:26.947770 31581 master.cpp:378] Flags at startup: --acls="" > --allocation_interval="1secs" --allocator="HierarchicalDRF" > --authenticate="false" --authenticate_slaves="true" > --authenticators="crammd5" --authorizers="local" > --credentials="/tmp/ContentType_SchedulerTest_Request_0_9KBzRb/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/ContentType_SchedulerTest_Request_0_9KBzRb/master" > --zk_session_timeout="10secs" > I1003 20:31:26.948117 31581 master.cpp:425] Master allowing > unauthenticated frameworks to register > I1003 20:31:26.948132 31581 master.cpp:428] Master only allowing > authenticated slaves to register > I1003 20:31:26.948143 31581 credentials.hpp:37] Loading credentials for > authentication from > '/tmp/ContentType_SchedulerTest_Request_0_9KBzRb/credentials' > I1003 20:31:26.948341 31576 recover.cpp:566] Updating replica status to > STARTING > I1003 20:31:26.948417 31581 master.cpp:467] Using default 'crammd5' > authenticator > I1003 20:31:26.948561 31581 master.cpp:504] Authorization enabled > I1003 20:31:26.948895 31573 whitelist_watcher.cpp:79] No whitelist given > I1003 20:31:26.949038 31570 hierarchical.hpp:468] Initialized hierarchical > allocator process > I1003 20:31:26.949053 31577 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 577328ns > I1003 20:31:26.949076 31577 replica.cpp:323] Persisted replica status to > STARTING > I1003 20:31:26.949398 31578 recover.cpp:475] Replica is in STARTING status > I1003 20:31:26.950956 31579 master.cpp:1603] The newly elected leader is > master@172.17.5.73:38504 with id 94d2511e-eee6-4866-8e56-974c3005eaf6 > I1003 20:31:26.950990 31579 master.cpp:1616] Elected as the leading master! > I1003 20:31:26.951012 31579 master.cpp:1376] Recovering from registrar > I1003 20:31:26.951195 31570 registrar.cpp:309] Recovering registrar > I1003 20:31:26.951963 31568 replica.cpp:641] Replica in STARTING status > received a broadcasted recover request > I1003 20:31:26.952205 31583 recover.cpp:195] Received a recover response > from a replica in STARTING status > I1003 20:31:26.952705 31572 recover.cpp:566] Updating replica status to > VOTING > I1003 20:31:26.953330 31581 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 456356ns > I1003 20:31:26.953361 31581 replica.cpp:323] Persisted replica status to > VOTING > I1003 20:31:26.953470 31573 recover.cpp:580] Successfully joined the Paxos > group > I1003 20:31:26.953698 31573 recover.cpp:464] Recover process terminated > I1003 20:31:26.954145 31569 log.cpp:661] Attempting to start the writer > I1003 20:31:26.955271 31573 replica.cpp:477] Replica received implicit > promise request with proposal 1 > I1003 20:31:26.955692 31573 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 395700ns > I1003 20:31:26.955714 31573 replica.cpp:345] Persisted promised to 1 > I1003 20:31:26.956269 31582 coordinator.cpp:231] Coordinator attemping to > fill missing position > I1003 20:31:26.957309 31582 replica.cpp:378] Replica received explicit > promise request for position 0 with proposal 2 > I1003 20:31:26.957700 31582 leveldb.cpp:343] Persisting action (8 bytes) > to leveldb took 356918ns > I1003 20:31:26.957722 31582 replica.cpp:679] Persisted action at 0 > I1003 20:31:26.958642 31571 replica.cpp:511] Replica received write > request for position 0 > I1003 20:31:26.958700 31571 leveldb.cpp:438] Reading position from leveldb > took 29252ns > I1003 20:31:26.959053 31571 leveldb.cpp:343] Persisting action (14 bytes) > to leveldb took 301004ns > I1003 20:31:26.959079 31571 replica.cpp:679] Persisted action at 0 > I1003 20:31:26.959656 31579 replica.cpp:658] Replica received learned > notice for position 0 > I1003 20:31:26.959997 31579 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 316014ns > I1003 20:31:26.960021 31579 replica.cpp:679] Persisted action at 0 > I1003 20:31:26.960036 31579 replica.cpp:664] Replica learned NOP action at > position 0 > I1003 20:31:26.960554 31575 log.cpp:677] Writer started with ending > position 0 > I1003 20:31:26.961552 31570 leveldb.cpp:438] Reading position from leveldb > took 28736ns > I1003 20:31:26.962498 31570 registrar.cpp:342] Successfully fetched the > registry (0B) in 11.25504ms > I1003 20:31:26.962633 31570 registrar.cpp:441] Applied 1 operations in > 32862ns; attempting to update the 'registry' > I1003 20:31:26.963507 31579 log.cpp:685] Attempting to append 174 bytes to > the log > I1003 20:31:26.963649 31571 coordinator.cpp:341] Coordinator attempting to > write APPEND action at position 1 > I1003 20:31:26.964371 31583 replica.cpp:511] Replica received write > request for position 1 > I1003 20:31:26.964804 31583 leveldb.cpp:343] Persisting action (193 bytes) > to leveldb took 397869ns > I1003 20:31:26.964846 31583 replica.cpp:679] Persisted action at 1 > I1003 20:31:26.965461 31571 replica.cpp:658] Replica received learned > notice for position 1 > I1003 20:31:26.965787 31571 leveldb.cpp:343] Persisting action (195 bytes) > to leveldb took 298959ns > I1003 20:31:26.965828 31571 replica.cpp:679] Persisted action at 1 > I1003 20:31:26.965859 31571 replica.cpp:664] Replica learned APPEND action > at position 1 > I1003 20:31:26.966734 31568 registrar.cpp:486] Successfully updated the > 'registry' in 4.032768ms > I1003 20:31:26.966929 31568 registrar.cpp:372] Successfully recovered > registrar > I1003 20:31:26.967002 31583 log.cpp:704] Attempting to truncate the log to > 1 > I1003 20:31:26.967124 31576 coordinator.cpp:341] Coordinator attempting to > write TRUNCATE action at position 2 > I1003 20:31:26.967452 31580 master.cpp:1413] Recovered 0 slaves from the > Registry (135B) ; allowing 10mins for slaves to re-register > I1003 20:31:26.967943 31571 replica.cpp:511] Replica received write > request for position 2 > I1003 20:31:26.968336 31571 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 359063ns > I1003 20:31:26.968361 31571 replica.cpp:679] Persisted action at 2 > I1003 20:31:26.968940 31580 replica.cpp:658] Replica received learned > notice for position 2 > I1003 20:31:26.969307 31580 leveldb.cpp:343] Persisting action (18 bytes) > to leveldb took 343640ns > I1003 20:31:26.969357 31580 leveldb.cpp:401] Deleting ~1 keys from leveldb > took 25444ns > I1003 20:31:26.969375 31580 replica.cpp:679] Persisted action at 2 > I1003 20:31:26.969393 31580 replica.cpp:664] Replica learned TRUNCATE > action at position 2 > I1003 20:31:26.978515 31549 scheduler.cpp:157] Version: 0.26.0 > I1003 20:31:26.979357 31575 scheduler.cpp:240] New master detected at > master@172.17.5.73:38504 > I1003 20:31:26.980631 31569 scheduler.cpp:302] Sending SUBSCRIBE call to > master@172.17.5.73:38504 > I1003 20:31:26.982694 31578 process.cpp:3075] Handling HTTP event for > process 'master' with path: '/master/api/v1/scheduler' > I1003 20:31:26.982976 31578 http.cpp:336] HTTP POST for > /master/api/v1/scheduler from 172.17.5.73:54886 > I1003 20:31:26.983217 31578 master.cpp:1865] Received subscription request > for HTTP framework 'default' > I1003 20:31:26.983309 31578 master.cpp:1642] Authorizing framework > principal 'test-principal' to receive offers for role '*' > I1003 20:31:26.983615 31578 master.cpp:1957] Subscribing framework > 'default' with checkpointing disabled and capabilities [ ] > I1003 20:31:26.984068 31570 hierarchical.hpp:515] Added framework > 94d2511e-eee6-4866-8e56-974c3005eaf6-0000 > I1003 20:31:26.984110 31570 hierarchical.hpp:1328] No resources available > to allocate! > I1003 20:31:26.984138 31570 hierarchical.hpp:1423] No inverse offers to > send out! > I1003 20:31:26.984158 31570 hierarchical.hpp:1223] Performed allocation > for 0 slaves in 57736ns > I1003 20:31:26.984163 31582 master.hpp:1431] Sending heartbeat to > 94d2511e-eee6-4866-8e56-974c3005eaf6-0000 > I1003 20:31:26.984869 31569 scheduler.cpp:461] Enqueuing event SUBSCRIBED > received from master@172.17.5.73:38504 > I1003 20:31:26.985405 31569 scheduler.cpp:461] Enqueuing event HEARTBEAT > received from master@172.17.5.73:38504 > I1003 20:31:26.986198 31575 master_maintenance_tests.cpp:179] Ignoring > HEARTBEAT event > I1003 20:31:26.986294 31569 scheduler.cpp:302] Sending REQUEST call to > master@172.17.5.73:38504 > I1003 20:31:26.987635 31573 process.cpp:3075] Handling HTTP event for > process 'master' with path: '/master/api/v1/scheduler' > I1003 20:31:26.987895 31573 http.cpp:336] HTTP POST for > /master/api/v1/scheduler from 172.17.5.73:54887 > I1003 20:31:26.988034 31573 master.cpp:2618] Processing REQUEST call for > framework 94d2511e-eee6-4866-8e56-974c3005eaf6-0000 (default) > I1003 20:31:26.988258 31582 hierarchical.hpp:801] Received resource > request from framework 94d2511e-eee6-4866-8e56-974c3005eaf6-0000 > I1003 20:31:26.988548 31569 master.cpp:919] Master terminating > I1003 20:31:26.988723 31574 hierarchical.hpp:552] Removed framework > 94d2511e-eee6-4866-8e56-974c3005eaf6-0000 > E1003 20:31:26.991010 31577 scheduler.cpp:435] End-Of-File received from > master. The master closed the event stream > [ OK ] ContentType/SchedulerTest.Request/0 (55 ms) > [ RUN ] ContentType/SchedulerTest.Request/1 > Using temporary directory '/tmp/ContentType_SchedulerTest_Request_1_KDKVnL' > I1003 20:31:26.998771 31549 leveldb.cpp:176] Opened db in 2.755486ms > I1003 20:31:26.999613 31549 leveldb.cpp:183] Compacted db in 789760ns > I1003 20:31:26.999689 31549 leveldb.cpp:198] Created db iterator in 24926ns > I1003 20:31:26.999709 31549 leveldb.cpp:204] Seeked to beginning of db in > 2125ns > I1003 20:31:26.999724 31549 leveldb.cpp:273] Iterated through 0 keys in > the db in 606ns > I1003 20:31:26.999784 31549 replica.cpp:744] Replica recovered with log > positions 0 -> 0 with 1 holes and 0 unlearned > I1003 20:31:27.000324 31572 recover.cpp:449] Starting replica recovery > I1003 20:31:27.000587 31572 recover.cpp:475] Replica is in EMPTY status > I1003 20:31:27.001752 31581 replica.cpp:641] Replica in EMPTY status > received a broadcasted recover request > I1003 20:31:27.002233 31582 recover.cpp:195] Received a recover response > from a replica in EMPTY status > I1003 20:31:27.002789 31570 recover.cpp:566] Updating replica status to > STARTING > I1003 20:31:27.003468 31577 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 442342ns > I1003 20:31:27.003497 31577 replica.cpp:323] Persisted replica status to > STARTING > I1003 20:31:27.003772 31579 recover.cpp:475] Replica is in STARTING status > I1003 20:31:27.003780 31575 master.cpp:376] Master > d1560281-8d57-4d6c-9906-fa2c3dcb3331 (9efc27440ed0) started on > 172.17.5.73:38504 > I1003 20:31:27.003840 31575 master.cpp:378] Flags at startup: --acls="" > --allocation_interval="1secs" --allocator="HierarchicalDRF" > --authenticate="false" --authenticate_slaves="true" > --authenticators="crammd5" --authorizers="local" > --credentials="/tmp/ContentType_SchedulerTest_Request_1_KDKVnL/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/ContentType_SchedulerTest_Request_1_KDKVnL/master" > --zk_session_timeout="10secs" > I1003 20:31:27.004225 31575 master.cpp:425] Master allowing > unauthenticated frameworks to register > I1003 20:31:27.004237 31575 master.cpp:428] Master only allowing > authenticated slaves to register > I1003 20:31:27.004245 31575 credentials.hpp:37] Loading credentials for > authentication from > '/tmp/ContentType_SchedulerTest_Request_1_KDKVnL/credentials' > I1003 20:31:27.004546 31575 master.cpp:467] Using default 'crammd5' > authenticator > I1003 20:31:27.004685 31575 master.cpp:504] Authorization enabled > I1003 20:31:27.005069 31570 replica.cpp:641] Replica in STARTING status > received a broadcasted recover request > I1003 20:31:27.005086 31576 hierarchical.hpp:468] Initialized hierarchical > allocator process > I1003 20:31:27.005187 31576 whitelist_watcher.cpp:79] No whitelist given > I1003 20:31:27.005318 31581 recover.cpp:195] Received a recover response > from a replica in STARTING status > I1003 20:31:27.005837 31571 recover.cpp:566] Updating replica status to > VOTING > I1003 20:31:27.006467 31573 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 306422ns > I1003 20:31:27.006592 31573 replica.cpp:323] Persisted replica status to > VOTING > I1003 20:31:27.006808 31575 recover.cpp:580] Successfully joined the Paxos > group > I1003 20:31:27.006954 31573 master.cpp:1603] The newly elected leader is > master@172.17.5.73:38504 with id d1560281-8d57-4d6c-9906-fa2c3dcb3331 > I1003 20:31:27.006985 31573 master.cpp:1616] Elected as the leading master! > I1003 20:31:27.007009 31573 master.cpp:1376] Recovering from registrar > I1003 20:31:27.007161 31580 registrar.cpp:309] Recovering registrar > I1003 20:31:27.007259 31575 recover.cpp:464] Recover process terminated > I1003 20:31:27.007966 31574 log.cpp:661] Attempting to start the writer > I1003 20:31:27.009629 31578 replica.cpp:477] Replica received implicit > promise request with proposal 1 > I1003 20:31:27.010030 31578 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 366028ns > I1003 20:31:27.010061 31578 replica.cpp:345] Persisted promised to 1 > I1003 20:31:27.010684 31574 coordinator.cpp:231] Coordinator attemping to > fill missing position > I1003 20:31:27.012042 31573 replica.cpp:378] Replica received explicit > promise request for position 0 with proposal 2 > I1003 20:31:27.012424 31573 leveldb.cpp:343] Persisting action (8 bytes) > to leveldb took 337796ns > I1003 20:31:27.012456 31573 replica.cpp:679] Persisted action at 0 > I1003 20:31:27.013864 31576 replica.cpp:511] Replica received write > request for position 0 > I1003 20:31:27.013926 31576 leveldb.cpp:438] Reading position from leveldb > took 29384ns > I1003 20:31:27.014309 31576 leveldb.cpp:343] Persisting action (14 bytes) > to leveldb took 334847ns > I1003 20:31:27.014339 31576 replica.cpp:679] Persisted action at 0 > I1003 20:31:27.015094 31575 replica.cpp:658] Replica received learned > notice for position 0 > I1003 20:31:27.015486 31575 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 358999ns > I1003 20:31:27.015524 31575 replica.cpp:679] Persisted action at 0 > I1003 20:31:27.015555 31575 replica.cpp:664] Replica learned NOP action at > position 0 > I1003 20:31:27.016301 31575 log.cpp:677] Writer started with ending > position 0 > I1003 20:31:27.017655 31571 leveldb.cpp:438] Reading position from leveldb > took 58018ns > I1003 20:31:27.018712 31573 registrar.cpp:342] Successfully fetched the > registry (0B) in 11.49184ms > I1003 20:31:27.018856 31573 registrar.cpp:441] Applied 1 operations in > 44211ns; attempting to update the 'registry' > I1003 20:31:27.019628 31578 log.cpp:685] Attempting to append 174 bytes to > the log > I1003 20:31:27.019750 31576 coordinator.cpp:341] Coordinator attempting to > write APPEND action at position 1 > I1003 20:31:27.020622 31572 replica.cpp:511] Replica received write > request for position 1 > I1003 20:31:27.021129 31572 leveldb.cpp:343] Persisting action (193 bytes) > to leveldb took 467625ns > I1003 20:31:27.021154 31572 replica.cpp:679] Persisted action at 1 > I1003 20:31:27.022037 31575 replica.cpp:658] Replica received learned > notice for position 1 > I1003 20:31:27.022439 31575 leveldb.cpp:343] Persisting action (195 bytes) > to leveldb took 373930ns > I1003 20:31:27.022462 31575 replica.cpp:679] Persisted action at 1 > I1003 20:31:27.022487 31575 replica.cpp:664] Replica learned APPEND action > at position 1 > I1003 20:31:27.023706 31572 registrar.cpp:486] Successfully updated the > 'registry' in 4.787968ms > I1003 20:31:27.023870 31572 registrar.cpp:372] Successfully recovered > registrar > I1003 20:31:27.024009 31576 log.cpp:704] Attempting to truncate the log to > 1 > I1003 20:31:27.024216 31569 coordinator.cpp:341] Coordinator attempting to > write TRUNCATE action at position 2 > I1003 20:31:27.024286 31573 master.cpp:1413] Recovered 0 slaves from the > Registry (135B) ; allowing 10mins for slaves to re-register > I1003 20:31:27.025131 31581 replica.cpp:511] Replica received write > request for position 2 > I1003 20:31:27.025573 31581 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 347232ns > I1003 20:31:27.025600 31581 replica.cpp:679] Persisted action at 2 > I1003 20:31:27.026346 31571 replica.cpp:658] Replica received learned > notice for position 2 > I1003 20:31:27.026702 31571 leveldb.cpp:343] Persisting action (18 bytes) > to leveldb took 322477ns > I1003 20:31:27.026772 31571 leveldb.cpp:401] Deleting ~1 keys from leveldb > took 35916ns > I1003 20:31:27.026798 31571 replica.cpp:679] Persisted action at 2 > I1003 20:31:27.026839 31571 replica.cpp:664] Replica learned TRUNCATE > action at position 2 > I1003 20:31:27.035171 31549 scheduler.cpp:157] Version: 0.26.0 > I1003 20:31:27.035781 31579 scheduler.cpp:240] New master detected at > master@172.17.5.73:38504 > I1003 20:31:27.036762 31580 scheduler.cpp:302] Sending SUBSCRIBE call to > master@172.17.5.73:38504 > I1003 20:31:27.038741 31570 process.cpp:3075] Handling HTTP event for > process 'master' with path: '/master/api/v1/scheduler' > I1003 20:31:27.038975 31570 http.cpp:336] HTTP POST for > /master/api/v1/scheduler from 172.17.5.73:54888 > I1003 20:31:27.039371 31570 master.cpp:1865] Received subscription request > for HTTP framework 'default' > I1003 20:31:27.039441 31570 master.cpp:1642] Authorizing framework > principal 'test-principal' to receive offers for role '*' > I1003 20:31:27.039698 31570 master.cpp:1957] Subscribing framework > 'default' with checkpointing disabled and capabilities [ ] > I1003 20:31:27.040057 31571 hierarchical.hpp:515] Added framework > d1560281-8d57-4d6c-9906-fa2c3dcb3331-0000 > I1003 20:31:27.040117 31571 hierarchical.hpp:1328] No resources available > to allocate! > I1003 20:31:27.040144 31571 hierarchical.hpp:1423] No inverse offers to > send out! > I1003 20:31:27.040302 31571 hierarchical.hpp:1223] Performed allocation > for 0 slaves in 216466ns > I1003 20:31:27.040141 31570 master.hpp:1431] Sending heartbeat to > d1560281-8d57-4d6c-9906-fa2c3dcb3331-0000 > I1003 20:31:27.041565 31574 scheduler.cpp:461] Enqueuing event SUBSCRIBED > received from master@172.17.5.73:38504 > I1003 20:31:27.042208 31574 scheduler.cpp:461] Enqueuing event HEARTBEAT > received from master@172.17.5.73:38504 > I1003 20:31:27.042997 31570 master_maintenance_tests.cpp:179] Ignoring > HEARTBEAT event > I1003 20:31:27.043011 31574 scheduler.cpp:302] Sending REQUEST call to > master@172.17.5.73:38504 > I1003 20:31:27.044297 31581 process.cpp:3075] Handling HTTP event for > process 'master' with path: '/master/api/v1/scheduler' > I1003 20:31:27.044492 31581 http.cpp:336] HTTP POST for > /master/api/v1/scheduler from 172.17.5.73:54889 > I1003 20:31:27.044718 31581 master.cpp:2618] Processing REQUEST call for > framework d1560281-8d57-4d6c-9906-fa2c3dcb3331-0000 (default) > I1003 20:31:27.044900 31580 hierarchical.hpp:801] Received resource > request from framework d1560281-8d57-4d6c-9906-fa2c3dcb3331-0000 > I1003 20:31:27.045220 31583 master.cpp:919] Master terminating > I1003 20:31:27.045383 31576 hierarchical.hpp:552] Removed framework > d1560281-8d57-4d6c-9906-fa2c3dcb3331-0000 > E1003 20:31:27.046392 31574 scheduler.cpp:435] End-Of-File received from > master. The master closed the event stream > [ OK ] ContentType/SchedulerTest.Request/1 (56 ms) > [----------] 22 tests from ContentType/SchedulerTest (4027 ms total) > > [----------] Global test environment tear-down > [==========] 811 tests from 111 test cases ran. (340981 ms total) > [ PASSED ] 810 tests. > [ FAILED ] 1 test, listed below: > [ FAILED ] OversubscriptionTest.UpdateAllocatorOnSchedulerFailover > > 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-1443902511-9349 > Untagged: mesos-1443902511-9349:latest > Deleted: 2cc8005a35b8fff4e1c19e0cae1e509d860399ab3a3cd95aa5c22ead08a18f20 > Deleted: 1c6faf02555604c4ab58f5bbc81bb29cbb068e5cab20ebfc8849f8b7a65f9c29 > Deleted: 0951418475e87072d1d80a92e5c037e336cce8be66a4c602c941d01b879ed17e > Deleted: 954d53d392bb0700c87d276b7f53e576bb7f2d272722a3f73bae69bf5cfb15d1 > Deleted: 3cc0c1f2a89c9b8caf93e3bd0e9686a01ef334c877e1f6de12a2ab073d0c6a93 > Deleted: 90a2c6b9a281441cc10fb214c55264e1edadf4c3a1cbcdc08c2c8831bb3102c1 > Deleted: 4e6953edbd113c221b410888e53c17edd3b16ed242561f52150df07b80425e29 > Deleted: 3b4152e576feb0503c78d12dd0be3124e50abf1451c073a2df8e42c2a7820ddd > Deleted: 3538d07677e5e788cb054b6b62ebdc3625c18ee9b45518fc9e3f9e33d0269daa > Deleted: cb00c34ea0e0956c77ace4f9e121e1a809018ce51b68a90f3b19ad2f63f729a9 > Deleted: b8016cea7a995429125f9adfa587cacdf7162ce7b54aee4e4970f9cbfda9940d > Deleted: eec2c59e96363c175481b53e101c159974c3c113ad26e59a7278f9b2509ccbdf > Build step 'Execute shell' marked build as failure >