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/755/changes>
Changes: [benjamin.mahler] Fixed a non-plural variable name in perf.hpp. ------------------------------------------ [...truncated 121858 lines...] I0901 01:34:13.515564 30509 hierarchical.hpp:428] Removed framework 20150901-013413-1996493228-35897-30488-0000 E0901 01:34:13.516106 30509 scheduler.cpp:435] End-Of-File received from master. The master closed the event stream I0901 01:34:13.516243 30515 slave.cpp:3143] master@172.17.0.119:35897 exited W0901 01:34:13.516481 30515 slave.cpp:3146] Master disconnected! Waiting for a new master to be elected I0901 01:34:13.519942 30508 slave.cpp:3399] Executor 'default' of framework 20150901-013413-1996493228-35897-30488-0000 exited with status 0 I0901 01:34:13.521723 30508 slave.cpp:2696] Handling status update TASK_LOST (UUID: 80a7ec7c-4558-4c42-8a68-9b282052b4a5) for task d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c of framework 20150901-013413-1996493228-35897-30488-0000 from @0.0.0.0:0 I0901 01:34:13.521848 30508 slave.cpp:5094] Terminating task d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c I0901 01:34:13.522336 30508 slave.cpp:564] Slave terminating I0901 01:34:13.522413 30508 slave.cpp:1959] Asked to shut down framework 20150901-013413-1996493228-35897-30488-0000 by @0.0.0.0:0 I0901 01:34:13.522534 30508 slave.cpp:1984] Shutting down framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.522689 30508 slave.cpp:3503] Cleaning up executor 'default' of framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.523030 30509 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000/executors/default/runs/fd926952-f7cf-40c8-be62-bc34289b89cf' for gc 6.99999394757037days in the future I0901 01:34:13.523133 30508 slave.cpp:3592] Cleaning up framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.523282 30509 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000/executors/default' for gc 6.99999394579852days in the future I0901 01:34:13.523396 30518 status_update_manager.cpp:284] Closing status update streams for framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.524374 30518 status_update_manager.cpp:530] Cleaning up status update stream for task d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c of framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.523474 30509 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000' for gc 6.99999394276741days in the future [ OK ] ContentType/SchedulerTest.Message/1 (125 ms) [ RUN ] ContentType/SchedulerTest.Request/0 Using temporary directory '/tmp/ContentType_SchedulerTest_Request_0_vR0XnP' I0901 01:34:13.531173 30488 leveldb.cpp:176] Opened db in 2.561692ms I0901 01:34:13.532003 30488 leveldb.cpp:183] Compacted db in 783277ns I0901 01:34:13.532119 30488 leveldb.cpp:198] Created db iterator in 22761ns I0901 01:34:13.532143 30488 leveldb.cpp:204] Seeked to beginning of db in 2055ns I0901 01:34:13.532160 30488 leveldb.cpp:273] Iterated through 0 keys in the db in 472ns I0901 01:34:13.532212 30488 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0901 01:34:13.532742 30511 recover.cpp:449] Starting replica recovery I0901 01:34:13.532968 30511 recover.cpp:475] Replica is in EMPTY status I0901 01:34:13.534502 30508 replica.cpp:641] Replica in EMPTY status received a broadcasted recover request I0901 01:34:13.535284 30508 recover.cpp:195] Received a recover response from a replica in EMPTY status I0901 01:34:13.537603 30516 recover.cpp:566] Updating replica status to STARTING I0901 01:34:13.538120 30518 master.cpp:378] Master 20150901-013413-1996493228-35897-30488 (998e845ced4a) started on 172.17.0.119:35897 I0901 01:34:13.538146 30518 master.cpp:380] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/credentials" --framework_sorter="drf" --help="false" --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.25.0/_inst/share/mesos/webui" --work_dir="/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/master" --zk_session_timeout="10secs" I0901 01:34:13.538532 30518 master.cpp:427] Master allowing unauthenticated frameworks to register I0901 01:34:13.538547 30518 master.cpp:430] Master only allowing authenticated slaves to register I0901 01:34:13.538558 30518 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/credentials' I0901 01:34:13.538666 30522 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 568647ns I0901 01:34:13.538703 30522 replica.cpp:323] Persisted replica status to STARTING I0901 01:34:13.538883 30518 master.cpp:469] Using default 'crammd5' authenticator I0901 01:34:13.538979 30510 recover.cpp:475] Replica is in STARTING status I0901 01:34:13.539052 30518 master.cpp:506] Authorization enabled I0901 01:34:13.540369 30508 replica.cpp:641] Replica in STARTING status received a broadcasted recover request I0901 01:34:13.540448 30510 hierarchical.hpp:346] Initialized hierarchical allocator process I0901 01:34:13.540478 30522 whitelist_watcher.cpp:79] No whitelist given I0901 01:34:13.541385 30508 recover.cpp:195] Received a recover response from a replica in STARTING status I0901 01:34:13.542026 30522 recover.cpp:566] Updating replica status to VOTING I0901 01:34:13.542232 30518 master.cpp:1559] The newly elected leader is master@172.17.0.119:35897 with id 20150901-013413-1996493228-35897-30488 I0901 01:34:13.542335 30518 master.cpp:1572] Elected as the leading master! I0901 01:34:13.542466 30518 master.cpp:1332] Recovering from registrar I0901 01:34:13.542826 30518 registrar.cpp:311] Recovering registrar I0901 01:34:13.543068 30508 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 365312ns I0901 01:34:13.543367 30508 replica.cpp:323] Persisted replica status to VOTING I0901 01:34:13.543473 30522 recover.cpp:580] Successfully joined the Paxos group I0901 01:34:13.543685 30522 recover.cpp:464] Recover process terminated I0901 01:34:13.544334 30516 log.cpp:661] Attempting to start the writer I0901 01:34:13.545722 30510 replica.cpp:477] Replica received implicit promise request with proposal 1 I0901 01:34:13.546131 30510 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 382715ns I0901 01:34:13.546164 30510 replica.cpp:345] Persisted promised to 1 I0901 01:34:13.547056 30510 coordinator.cpp:231] Coordinator attemping to fill missing position I0901 01:34:13.548393 30517 replica.cpp:378] Replica received explicit promise request for position 0 with proposal 2 I0901 01:34:13.549365 30517 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 508185ns I0901 01:34:13.549592 30517 replica.cpp:679] Persisted action at 0 I0901 01:34:13.553926 30522 replica.cpp:511] Replica received write request for position 0 I0901 01:34:13.554023 30522 leveldb.cpp:438] Reading position from leveldb took 34026ns I0901 01:34:13.554674 30522 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 523847ns I0901 01:34:13.554711 30522 replica.cpp:679] Persisted action at 0 I0901 01:34:13.555552 30522 replica.cpp:658] Replica received learned notice for position 0 I0901 01:34:13.555915 30522 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 333834ns I0901 01:34:13.555944 30522 replica.cpp:679] Persisted action at 0 I0901 01:34:13.555968 30522 replica.cpp:664] Replica learned NOP action at position 0 I0901 01:34:13.557031 30522 log.cpp:677] Writer started with ending position 0 I0901 01:34:13.558575 30522 leveldb.cpp:438] Reading position from leveldb took 35255ns I0901 01:34:13.562242 30522 registrar.cpp:344] Successfully fetched the registry (0B) in 19.28192ms I0901 01:34:13.562387 30522 registrar.cpp:443] Applied 1 operations in 33477ns; attempting to update the 'registry' I0901 01:34:13.565824 30510 log.cpp:685] Attempting to append 178 bytes to the log I0901 01:34:13.566048 30513 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 1 I0901 01:34:13.566949 30512 replica.cpp:511] Replica received write request for position 1 I0901 01:34:13.567483 30512 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 492374ns I0901 01:34:13.567523 30512 replica.cpp:679] Persisted action at 1 I0901 01:34:13.568199 30512 replica.cpp:658] Replica received learned notice for position 1 I0901 01:34:13.568665 30512 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 343360ns I0901 01:34:13.568699 30512 replica.cpp:679] Persisted action at 1 I0901 01:34:13.568729 30512 replica.cpp:664] Replica learned APPEND action at position 1 I0901 01:34:13.569790 30520 registrar.cpp:488] Successfully updated the 'registry' in 7.33184ms I0901 01:34:13.569968 30520 registrar.cpp:374] Successfully recovered registrar I0901 01:34:13.570150 30515 log.cpp:704] Attempting to truncate the log to 1 I0901 01:34:13.570272 30520 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2 I0901 01:34:13.570386 30507 master.cpp:1369] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register I0901 01:34:13.571435 30510 replica.cpp:511] Replica received write request for position 2 I0901 01:34:13.571858 30510 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 371692ns I0901 01:34:13.571985 30510 replica.cpp:679] Persisted action at 2 I0901 01:34:13.573128 30507 replica.cpp:658] Replica received learned notice for position 2 I0901 01:34:13.573748 30507 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 399399ns I0901 01:34:13.573860 30507 leveldb.cpp:401] Deleting ~1 keys from leveldb took 80758ns I0901 01:34:13.573890 30507 replica.cpp:679] Persisted action at 2 I0901 01:34:13.573920 30507 replica.cpp:664] Replica learned TRUNCATE action at position 2 I0901 01:34:13.581409 30488 scheduler.cpp:157] Version: 0.25.0 I0901 01:34:13.584280 30520 scheduler.cpp:240] New master detected at master@172.17.0.119:35897 I0901 01:34:13.586750 30522 scheduler.cpp:302] Sending SUBSCRIBE call to master@172.17.0.119:35897 I0901 01:34:13.588770 30510 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0901 01:34:13.588932 30510 http.cpp:333] HTTP POST for /master/api/v1/scheduler from 172.17.0.119:38028 I0901 01:34:13.589198 30510 master.cpp:1817] Received subscription request for HTTP framework 'default' I0901 01:34:13.589267 30510 master.cpp:1598] Authorizing framework principal 'test-principal' to receive offers for role '*' I0901 01:34:13.589565 30514 master.cpp:1909] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0901 01:34:13.590019 30513 hierarchical.hpp:391] Added framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.590059 30513 hierarchical.hpp:1010] No resources available to allocate! I0901 01:34:13.590093 30513 hierarchical.hpp:910] Performed allocation for 0 slaves in 30381ns I0901 01:34:13.590368 30507 master.hpp:1339] Sending heartbeat to 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.591143 30514 scheduler.cpp:461] Enqueuing event SUBSCRIBED received from master@172.17.0.119:35897 I0901 01:34:13.592531 30509 scheduler.cpp:302] Sending REQUEST call to master@172.17.0.119:35897 I0901 01:34:13.593093 30509 scheduler.cpp:461] Enqueuing event HEARTBEAT received from master@172.17.0.119:35897 I0901 01:34:13.593616 30515 scheduler_tests.cpp:113] Ignoring HEARTBEAT event I0901 01:34:13.598319 30517 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0901 01:34:13.598618 30517 http.cpp:333] HTTP POST for /master/api/v1/scheduler from 172.17.0.119:38029 I0901 01:34:13.598848 30517 master.cpp:2520] Processing REQUEST call for framework 20150901-013413-1996493228-35897-30488-0000 (default) I0901 01:34:13.599093 30508 hierarchical.hpp:668] Received resource request from framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.599490 30515 master.cpp:884] Master terminating I0901 01:34:13.600097 30512 hierarchical.hpp:428] Removed framework 20150901-013413-1996493228-35897-30488-0000 E0901 01:34:13.602247 30520 scheduler.cpp:435] End-Of-File received from master. The master closed the event stream [ OK ] ContentType/SchedulerTest.Request/0 (80 ms) [ RUN ] ContentType/SchedulerTest.Request/1 Using temporary directory '/tmp/ContentType_SchedulerTest_Request_1_ccrAwf' I0901 01:34:13.612321 30488 leveldb.cpp:176] Opened db in 2.732436ms I0901 01:34:13.613260 30488 leveldb.cpp:183] Compacted db in 757637ns I0901 01:34:13.613317 30488 leveldb.cpp:198] Created db iterator in 22964ns I0901 01:34:13.613451 30488 leveldb.cpp:204] Seeked to beginning of db in 2606ns I0901 01:34:13.613534 30488 leveldb.cpp:273] Iterated through 0 keys in the db in 548ns I0901 01:34:13.613595 30488 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0901 01:34:13.614243 30514 recover.cpp:449] Starting replica recovery I0901 01:34:13.614574 30514 recover.cpp:475] Replica is in EMPTY status I0901 01:34:13.615953 30515 replica.cpp:641] Replica in EMPTY status received a broadcasted recover request I0901 01:34:13.616627 30514 recover.cpp:195] Received a recover response from a replica in EMPTY status I0901 01:34:13.617187 30512 master.cpp:378] Master 20150901-013413-1996493228-35897-30488 (998e845ced4a) started on 172.17.0.119:35897 I0901 01:34:13.617529 30519 recover.cpp:566] Updating replica status to STARTING I0901 01:34:13.617300 30512 master.cpp:380] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/credentials" --framework_sorter="drf" --help="false" --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.25.0/_inst/share/mesos/webui" --work_dir="/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/master" --zk_session_timeout="10secs" I0901 01:34:13.617902 30512 master.cpp:427] Master allowing unauthenticated frameworks to register I0901 01:34:13.617919 30512 master.cpp:430] Master only allowing authenticated slaves to register I0901 01:34:13.617931 30512 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/credentials' I0901 01:34:13.618306 30512 master.cpp:469] Using default 'crammd5' authenticator I0901 01:34:13.618398 30514 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 600368ns I0901 01:34:13.618505 30512 master.cpp:506] Authorization enabled I0901 01:34:13.619098 30519 hierarchical.hpp:346] Initialized hierarchical allocator process I0901 01:34:13.619163 30519 whitelist_watcher.cpp:79] No whitelist given I0901 01:34:13.618569 30514 replica.cpp:323] Persisted replica status to STARTING I0901 01:34:13.620694 30521 master.cpp:1559] The newly elected leader is master@172.17.0.119:35897 with id 20150901-013413-1996493228-35897-30488 I0901 01:34:13.620810 30521 master.cpp:1572] Elected as the leading master! I0901 01:34:13.620961 30521 master.cpp:1332] Recovering from registrar I0901 01:34:13.621230 30519 registrar.cpp:311] Recovering registrar I0901 01:34:13.621961 30514 recover.cpp:475] Replica is in STARTING status I0901 01:34:13.622889 30517 replica.cpp:641] Replica in STARTING status received a broadcasted recover request I0901 01:34:13.623294 30515 recover.cpp:195] Received a recover response from a replica in STARTING status I0901 01:34:13.623807 30512 recover.cpp:566] Updating replica status to VOTING I0901 01:34:13.624295 30515 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 327184ns I0901 01:34:13.624326 30515 replica.cpp:323] Persisted replica status to VOTING I0901 01:34:13.624436 30514 recover.cpp:580] Successfully joined the Paxos group I0901 01:34:13.624774 30514 recover.cpp:464] Recover process terminated I0901 01:34:13.625172 30508 log.cpp:661] Attempting to start the writer I0901 01:34:13.626436 30517 replica.cpp:477] Replica received implicit promise request with proposal 1 I0901 01:34:13.626927 30517 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 298893ns I0901 01:34:13.626951 30517 replica.cpp:345] Persisted promised to 1 I0901 01:34:13.627614 30507 coordinator.cpp:231] Coordinator attemping to fill missing position I0901 01:34:13.628764 30518 replica.cpp:378] Replica received explicit promise request for position 0 with proposal 2 I0901 01:34:13.629114 30518 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 303373ns I0901 01:34:13.629140 30518 replica.cpp:679] Persisted action at 0 I0901 01:34:13.630182 30517 replica.cpp:511] Replica received write request for position 0 I0901 01:34:13.630272 30517 leveldb.cpp:438] Reading position from leveldb took 58804ns I0901 01:34:13.630722 30517 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 280506ns I0901 01:34:13.630748 30517 replica.cpp:679] Persisted action at 0 I0901 01:34:13.632084 30516 replica.cpp:658] Replica received learned notice for position 0 I0901 01:34:13.632535 30516 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 395677ns I0901 01:34:13.633059 30516 replica.cpp:679] Persisted action at 0 I0901 01:34:13.633344 30516 replica.cpp:664] Replica learned NOP action at position 0 I0901 01:34:13.634232 30521 log.cpp:677] Writer started with ending position 0 I0901 01:34:13.635602 30521 leveldb.cpp:438] Reading position from leveldb took 36365ns I0901 01:34:13.639372 30521 registrar.cpp:344] Successfully fetched the registry (0B) in 17.931008ms I0901 01:34:13.640036 30521 registrar.cpp:443] Applied 1 operations in 62767ns; attempting to update the 'registry' I0901 01:34:13.642554 30521 log.cpp:685] Attempting to append 178 bytes to the log I0901 01:34:13.642717 30508 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 1 I0901 01:34:13.643582 30514 replica.cpp:511] Replica received write request for position 1 I0901 01:34:13.644204 30514 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 470599ns I0901 01:34:13.644233 30514 replica.cpp:679] Persisted action at 1 I0901 01:34:13.645012 30512 replica.cpp:658] Replica received learned notice for position 1 I0901 01:34:13.645385 30512 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 347723ns I0901 01:34:13.645421 30512 replica.cpp:679] Persisted action at 1 I0901 01:34:13.645450 30512 replica.cpp:664] Replica learned APPEND action at position 1 I0901 01:34:13.646477 30507 registrar.cpp:488] Successfully updated the 'registry' in 6.305024ms I0901 01:34:13.646771 30512 log.cpp:704] Attempting to truncate the log to 1 I0901 01:34:13.646960 30512 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2 I0901 01:34:13.646975 30507 registrar.cpp:374] Successfully recovered registrar I0901 01:34:13.647505 30521 master.cpp:1369] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register I0901 01:34:13.648180 30518 replica.cpp:511] Replica received write request for position 2 I0901 01:34:13.648666 30518 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 326369ns I0901 01:34:13.648694 30518 replica.cpp:679] Persisted action at 2 I0901 01:34:13.649610 30518 replica.cpp:658] Replica received learned notice for position 2 I0901 01:34:13.649953 30518 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 318537ns I0901 01:34:13.650037 30518 leveldb.cpp:401] Deleting ~1 keys from leveldb took 57361ns I0901 01:34:13.650075 30518 replica.cpp:679] Persisted action at 2 I0901 01:34:13.650104 30518 replica.cpp:664] Replica learned TRUNCATE action at position 2 I0901 01:34:13.658303 30488 scheduler.cpp:157] Version: 0.25.0 I0901 01:34:13.660554 30519 scheduler.cpp:240] New master detected at master@172.17.0.119:35897 I0901 01:34:13.662181 30518 scheduler.cpp:302] Sending SUBSCRIBE call to master@172.17.0.119:35897 I0901 01:34:13.664407 30514 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0901 01:34:13.664616 30514 http.cpp:333] HTTP POST for /master/api/v1/scheduler from 172.17.0.119:38030 I0901 01:34:13.664958 30514 master.cpp:1817] Received subscription request for HTTP framework 'default' I0901 01:34:13.665046 30514 master.cpp:1598] Authorizing framework principal 'test-principal' to receive offers for role '*' I0901 01:34:13.665314 30514 master.cpp:1909] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0901 01:34:13.665791 30508 hierarchical.hpp:391] Added framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.665828 30508 hierarchical.hpp:1010] No resources available to allocate! I0901 01:34:13.665844 30508 hierarchical.hpp:910] Performed allocation for 0 slaves in 29650ns I0901 01:34:13.665873 30508 master.hpp:1339] Sending heartbeat to 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.666849 30521 scheduler.cpp:461] Enqueuing event SUBSCRIBED received from master@172.17.0.119:35897 I0901 01:34:13.667315 30521 scheduler.cpp:461] Enqueuing event HEARTBEAT received from master@172.17.0.119:35897 I0901 01:34:13.667949 30508 scheduler_tests.cpp:113] Ignoring HEARTBEAT event I0901 01:34:13.668045 30518 scheduler.cpp:302] Sending REQUEST call to master@172.17.0.119:35897 I0901 01:34:13.669878 30522 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0901 01:34:13.670075 30522 http.cpp:333] HTTP POST for /master/api/v1/scheduler from 172.17.0.119:38031 I0901 01:34:13.670334 30522 master.cpp:2520] Processing REQUEST call for framework 20150901-013413-1996493228-35897-30488-0000 (default) I0901 01:34:13.670500 30512 hierarchical.hpp:668] Received resource request from framework 20150901-013413-1996493228-35897-30488-0000 I0901 01:34:13.670824 30508 master.cpp:884] Master terminating I0901 01:34:13.671052 30522 hierarchical.hpp:428] Removed framework 20150901-013413-1996493228-35897-30488-0000 E0901 01:34:13.673291 30515 scheduler.cpp:435] End-Of-File received from master. The master closed the event stream [ OK ] ContentType/SchedulerTest.Request/1 (69 ms) [----------] 20 tests from ContentType/SchedulerTest (4160 ms total) [----------] Global test environment tear-down [==========] 750 tests from 102 test cases ran. (315444 ms total) [ PASSED ] 749 tests. [ FAILED ] 1 test, listed below: [ FAILED ] FetcherCacheHttpTest.HttpMixed 1 FAILED TEST YOU HAVE 8 DISABLED TESTS make[4]: *** [check-local] Error 1 make[4]: Leaving directory `/mesos/mesos-0.25.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-0.25.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-0.25.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-0.25.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1441069876-15440 Untagged: mesos-1441069876-15440:latest Deleted: fb82ea47314ebcb7532036a117c5bf2fb3f387c351c3388b589c8eef849fbbcb Deleted: 3a6b23d38f2ec006a11631ca3f654512fd974d75a9643e96dd34f9c3f7340fb8 Deleted: bad7754fb1b9adb33da8efe6647760dd1db582b7f0c0caa902ac8bb3fac84b32 Deleted: 9bb423c03745fd22630cfa4c2fed2c0088bbb0ad09bd6a4403a6c7464e32b597 Build step 'Execute shell' marked build as failure