See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1850/changes>
Changes: [bmahler] Added a metric for the number of times the allocation algorithm has run. ------------------------------------------ [...truncated 175886 lines...] I0323 03:54:28.028702 1472 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/bJCb4N/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/bJCb4N/master" --zk_session_timeout="10secs" I0323 03:54:28.029009 1472 master.cpp:425] Master allowing unauthenticated frameworks to register I0323 03:54:28.029019 1472 master.cpp:428] Master only allowing authenticated slaves to register I0323 03:54:28.029026 1472 credentials.hpp:35] Loading credentials for authentication from '/tmp/bJCb4N/credentials' I0323 03:54:28.029335 1472 master.cpp:468] Using default 'crammd5' authenticator I0323 03:54:28.029537 1472 master.cpp:537] Using default 'basic' HTTP authenticator I0323 03:54:28.029696 1472 master.cpp:571] Authorization enabled I0323 03:54:28.029873 1468 whitelist_watcher.cpp:77] No whitelist given I0323 03:54:28.029894 1464 hierarchical.cpp:144] Initialized hierarchical allocator process I0323 03:54:28.031880 1465 master.cpp:1806] The newly elected leader is [email protected]:34431 with id 5813c249-c5b8-4d55-b2e6-0202fb514dcc I0323 03:54:28.031919 1465 master.cpp:1819] Elected as the leading master! I0323 03:54:28.031937 1465 master.cpp:1508] Recovering from registrar I0323 03:54:28.032155 1466 registrar.cpp:307] Recovering registrar I0323 03:54:28.079540 1474 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 51.89349ms I0323 03:54:28.079623 1474 replica.cpp:320] Persisted replica status to STARTING I0323 03:54:28.080013 1468 recover.cpp:473] Replica is in STARTING status I0323 03:54:28.081800 1463 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15709)@172.17.0.2:34431 I0323 03:54:28.082201 1463 recover.cpp:193] Received a recover response from a replica in STARTING status I0323 03:54:28.082926 1463 recover.cpp:564] Updating replica status to VOTING I0323 03:54:28.113013 1472 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.852669ms I0323 03:54:28.113101 1472 replica.cpp:320] Persisted replica status to VOTING I0323 03:54:28.113382 1463 recover.cpp:578] Successfully joined the Paxos group I0323 03:54:28.114133 1477 log.cpp:659] Attempting to start the writer I0323 03:54:28.114612 1463 recover.cpp:462] Recover process terminated I0323 03:54:28.115800 1463 replica.cpp:493] Replica received implicit promise request from (15710)@172.17.0.2:34431 with proposal 1 I0323 03:54:28.146488 1463 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.628087ms I0323 03:54:28.146577 1463 replica.cpp:342] Persisted promised to 1 I0323 03:54:28.147550 1469 coordinator.cpp:238] Coordinator attempting to fill missing positions I0323 03:54:28.148949 1473 replica.cpp:388] Replica received explicit promise request from (15711)@172.17.0.2:34431 for position 0 with proposal 2 I0323 03:54:28.179949 1473 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 30.91677ms I0323 03:54:28.180040 1473 replica.cpp:712] Persisted action at 0 I0323 03:54:28.181911 1473 replica.cpp:537] Replica received write request for position 0 from (15712)@172.17.0.2:34431 I0323 03:54:28.181990 1473 leveldb.cpp:436] Reading position from leveldb took 41811ns I0323 03:54:28.213547 1473 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.489936ms I0323 03:54:28.213634 1473 replica.cpp:712] Persisted action at 0 I0323 03:54:28.214356 1477 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0 I0323 03:54:28.246942 1477 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.531913ms I0323 03:54:28.247035 1477 replica.cpp:712] Persisted action at 0 I0323 03:54:28.247081 1477 replica.cpp:697] Replica learned NOP action at position 0 I0323 03:54:28.247975 1466 log.cpp:675] Writer started with ending position 0 I0323 03:54:28.249302 1474 leveldb.cpp:436] Reading position from leveldb took 58272ns I0323 03:54:28.250500 1471 registrar.cpp:340] Successfully fetched the registry (0B) in 218.212864ms I0323 03:54:28.250648 1471 registrar.cpp:439] Applied 1 operations in 40197ns; attempting to update the 'registry' I0323 03:54:28.251516 1472 log.cpp:683] Attempting to append 170 bytes to the log I0323 03:54:28.251667 1465 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1 I0323 03:54:28.252585 1471 replica.cpp:537] Replica received write request for position 1 from (15713)@172.17.0.2:34431 I0323 03:54:28.288868 1471 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 36.206026ms I0323 03:54:28.288951 1471 replica.cpp:712] Persisted action at 1 I0323 03:54:28.289917 1468 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0 I0323 03:54:28.322298 1468 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.325574ms I0323 03:54:28.322384 1468 replica.cpp:712] Persisted action at 1 I0323 03:54:28.322451 1468 replica.cpp:697] Replica learned APPEND action at position 1 I0323 03:54:28.323838 1475 registrar.cpp:484] Successfully updated the 'registry' in 73.047296ms I0323 03:54:28.323992 1475 registrar.cpp:370] Successfully recovered registrar I0323 03:54:28.324137 1463 log.cpp:702] Attempting to truncate the log to 1 I0323 03:54:28.324297 1467 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2 I0323 03:54:28.324688 1474 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register I0323 03:54:28.325017 1464 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover I0323 03:54:28.326653 1466 replica.cpp:537] Replica received write request for position 2 from (15714)@172.17.0.2:34431 I0323 03:54:28.364063 1466 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 36.97425ms I0323 03:54:28.364148 1466 replica.cpp:712] Persisted action at 2 I0323 03:54:28.365064 1465 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0 I0323 03:54:28.397572 1465 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.457099ms I0323 03:54:28.397738 1465 leveldb.cpp:399] Deleting ~1 keys from leveldb took 82680ns I0323 03:54:28.397775 1465 replica.cpp:712] Persisted action at 2 I0323 03:54:28.397822 1465 replica.cpp:697] Replica learned TRUNCATE action at position 2 I0323 03:54:28.409557 1443 scheduler.cpp:172] Version: 0.29.0 I0323 03:54:28.411671 1471 scheduler.cpp:437] New master detected at [email protected]:34431 I0323 03:54:28.413910 1474 scheduler.cpp:337] Connected with the master at http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.415660 1476 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.417182 1466 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0323 03:54:28.417999 1466 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39316 I0323 03:54:28.418259 1466 master.cpp:2068] Received subscription request for HTTP framework 'default' I0323 03:54:28.418335 1466 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*' I0323 03:54:28.418632 1466 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0323 03:54:28.419095 1471 hierarchical.cpp:265] Added framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 I0323 03:54:28.419191 1471 hierarchical.cpp:1459] No resources available to allocate! I0323 03:54:28.419227 1471 hierarchical.cpp:1554] No inverse offers to send out! I0323 03:54:28.419237 1466 master.hpp:1715] Sending heartbeat to 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 I0323 03:54:28.419255 1471 hierarchical.cpp:1134] Performed allocation for 0 slaves in 136605ns I0323 03:54:28.420646 1467 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.421116 1467 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.422122 1469 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.423046 1475 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0323 03:54:28.423552 1475 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39317 I0323 03:54:28.423679 1475 master.cpp:2765] Processing REQUEST call for framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 (default) I0323 03:54:28.423910 1475 hierarchical.cpp:589] Received resource request from framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 I0323 03:54:28.426100 1467 master.cpp:1251] Framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 (default) disconnected I0323 03:54:28.426203 1467 master.cpp:2622] Disconnecting framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 (default) I0323 03:54:28.426334 1467 master.cpp:2646] Deactivating framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 (default) I0323 03:54:28.426553 1467 master.cpp:1275] Giving framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 (default) 0ns to failover I0323 03:54:28.426575 1462 hierarchical.cpp:375] Deactivated framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 I0323 03:54:28.428480 1464 master.cpp:5340] Framework failover timeout, removing framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 (default) I0323 03:54:28.428509 1464 master.cpp:6073] Removing framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 (default) I0323 03:54:28.428796 1469 hierarchical.cpp:326] Removed framework 5813c249-c5b8-4d55-b2e6-0202fb514dcc-0000 I0323 03:54:28.437450 1443 master.cpp:1065] Master terminating [ OK ] ContentType/SchedulerTest.Request/0 (566 ms) [ RUN ] ContentType/SchedulerTest.Request/1 I0323 03:54:28.447052 1443 cluster.cpp:139] Creating default 'local' authorizer I0323 03:54:28.550240 1443 leveldb.cpp:174] Opened db in 102.853341ms I0323 03:54:28.584123 1443 leveldb.cpp:181] Compacted db in 33.795574ms I0323 03:54:28.584218 1443 leveldb.cpp:196] Created db iterator in 24783ns I0323 03:54:28.584238 1443 leveldb.cpp:202] Seeked to beginning of db in 3240ns I0323 03:54:28.584245 1443 leveldb.cpp:271] Iterated through 0 keys in the db in 331ns I0323 03:54:28.584296 1443 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0323 03:54:28.585186 1465 recover.cpp:447] Starting replica recovery I0323 03:54:28.585659 1469 recover.cpp:473] Replica is in EMPTY status I0323 03:54:28.587882 1467 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (15722)@172.17.0.2:34431 I0323 03:54:28.588222 1473 recover.cpp:193] Received a recover response from a replica in EMPTY status I0323 03:54:28.588754 1469 recover.cpp:564] Updating replica status to STARTING I0323 03:54:28.590667 1473 master.cpp:376] Master 321139c6-3f50-4b53-9349-c7d23461727d (4e06b6d9b9c4) started on 172.17.0.2:34431 I0323 03:54:28.590770 1473 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/VulAK8/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/VulAK8/master" --zk_session_timeout="10secs" I0323 03:54:28.591202 1473 master.cpp:425] Master allowing unauthenticated frameworks to register I0323 03:54:28.591218 1473 master.cpp:428] Master only allowing authenticated slaves to register I0323 03:54:28.591230 1473 credentials.hpp:35] Loading credentials for authentication from '/tmp/VulAK8/credentials' I0323 03:54:28.591677 1473 master.cpp:468] Using default 'crammd5' authenticator I0323 03:54:28.591850 1473 master.cpp:537] Using default 'basic' HTTP authenticator I0323 03:54:28.592046 1473 master.cpp:571] Authorization enabled I0323 03:54:28.592288 1469 whitelist_watcher.cpp:77] No whitelist given I0323 03:54:28.592344 1462 hierarchical.cpp:144] Initialized hierarchical allocator process I0323 03:54:28.594584 1472 master.cpp:1806] The newly elected leader is [email protected]:34431 with id 321139c6-3f50-4b53-9349-c7d23461727d I0323 03:54:28.594622 1472 master.cpp:1819] Elected as the leading master! I0323 03:54:28.594646 1472 master.cpp:1508] Recovering from registrar I0323 03:54:28.594797 1467 registrar.cpp:307] Recovering registrar I0323 03:54:28.617350 1465 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 28.469222ms I0323 03:54:28.617434 1465 replica.cpp:320] Persisted replica status to STARTING I0323 03:54:28.617768 1470 recover.cpp:473] Replica is in STARTING status I0323 03:54:28.619026 1474 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15724)@172.17.0.2:34431 I0323 03:54:28.619571 1464 recover.cpp:193] Received a recover response from a replica in STARTING status I0323 03:54:28.619993 1465 recover.cpp:564] Updating replica status to VOTING I0323 03:54:28.639631 1467 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 19.369069ms I0323 03:54:28.639710 1467 replica.cpp:320] Persisted replica status to VOTING I0323 03:54:28.639977 1467 recover.cpp:578] Successfully joined the Paxos group I0323 03:54:28.640190 1467 recover.cpp:462] Recover process terminated I0323 03:54:28.640877 1473 log.cpp:659] Attempting to start the writer I0323 03:54:28.642091 1473 replica.cpp:493] Replica received implicit promise request from (15725)@172.17.0.2:34431 with proposal 1 I0323 03:54:28.664767 1473 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.616634ms I0323 03:54:28.664849 1473 replica.cpp:342] Persisted promised to 1 I0323 03:54:28.665927 1474 coordinator.cpp:238] Coordinator attempting to fill missing positions I0323 03:54:28.667166 1477 replica.cpp:388] Replica received explicit promise request from (15726)@172.17.0.2:34431 for position 0 with proposal 2 I0323 03:54:28.698815 1477 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 31.584268ms I0323 03:54:28.698896 1477 replica.cpp:712] Persisted action at 0 I0323 03:54:28.700314 1477 replica.cpp:537] Replica received write request for position 0 from (15727)@172.17.0.2:34431 I0323 03:54:28.700398 1477 leveldb.cpp:436] Reading position from leveldb took 45083ns I0323 03:54:28.732301 1477 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.795153ms I0323 03:54:28.732384 1477 replica.cpp:712] Persisted action at 0 I0323 03:54:28.733283 1474 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0 I0323 03:54:28.765691 1474 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.349169ms I0323 03:54:28.765781 1474 replica.cpp:712] Persisted action at 0 I0323 03:54:28.765820 1474 replica.cpp:697] Replica learned NOP action at position 0 I0323 03:54:28.766697 1464 log.cpp:675] Writer started with ending position 0 I0323 03:54:28.768144 1462 leveldb.cpp:436] Reading position from leveldb took 50938ns I0323 03:54:28.769526 1474 registrar.cpp:340] Successfully fetched the registry (0B) in 174.578944ms I0323 03:54:28.769660 1474 registrar.cpp:439] Applied 1 operations in 39675ns; attempting to update the 'registry' I0323 03:54:28.770776 1468 log.cpp:683] Attempting to append 170 bytes to the log I0323 03:54:28.770927 1477 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1 I0323 03:54:28.771889 1462 replica.cpp:537] Replica received write request for position 1 from (15728)@172.17.0.2:34431 I0323 03:54:28.799226 1462 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 27.273526ms I0323 03:54:28.799302 1462 replica.cpp:712] Persisted action at 1 I0323 03:54:28.800496 1469 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0 I0323 03:54:28.832972 1469 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.415542ms I0323 03:54:28.833058 1469 replica.cpp:712] Persisted action at 1 I0323 03:54:28.833097 1469 replica.cpp:697] Replica learned APPEND action at position 1 I0323 03:54:28.834684 1474 registrar.cpp:484] Successfully updated the 'registry' in 64.943872ms I0323 03:54:28.834839 1474 registrar.cpp:370] Successfully recovered registrar I0323 03:54:28.835286 1474 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register I0323 03:54:28.835499 1474 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover I0323 03:54:28.836038 1464 log.cpp:702] Attempting to truncate the log to 1 I0323 03:54:28.836194 1476 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2 I0323 03:54:28.837522 1468 replica.cpp:537] Replica received write request for position 2 from (15729)@172.17.0.2:34431 I0323 03:54:28.874869 1468 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 37.257904ms I0323 03:54:28.874953 1468 replica.cpp:712] Persisted action at 2 I0323 03:54:28.876006 1468 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0 I0323 03:54:28.908694 1468 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.641642ms I0323 03:54:28.909015 1468 leveldb.cpp:399] Deleting ~1 keys from leveldb took 88282ns I0323 03:54:28.909072 1468 replica.cpp:712] Persisted action at 2 I0323 03:54:28.909111 1468 replica.cpp:697] Replica learned TRUNCATE action at position 2 I0323 03:54:28.919801 1443 scheduler.cpp:172] Version: 0.29.0 I0323 03:54:28.920755 1463 scheduler.cpp:437] New master detected at [email protected]:34431 I0323 03:54:28.922523 1471 scheduler.cpp:337] Connected with the master at http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.924165 1476 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.925182 1463 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0323 03:54:28.925791 1474 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39318 I0323 03:54:28.926286 1474 master.cpp:2068] Received subscription request for HTTP framework 'default' I0323 03:54:28.926360 1474 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*' I0323 03:54:28.926666 1474 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0323 03:54:28.927170 1477 hierarchical.cpp:265] Added framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 I0323 03:54:28.927258 1468 master.hpp:1715] Sending heartbeat to 321139c6-3f50-4b53-9349-c7d23461727d-0000 I0323 03:54:28.927273 1477 hierarchical.cpp:1459] No resources available to allocate! I0323 03:54:28.927309 1477 hierarchical.cpp:1554] No inverse offers to send out! I0323 03:54:28.927335 1477 hierarchical.cpp:1134] Performed allocation for 0 slaves in 133989ns I0323 03:54:28.928127 1463 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.928689 1463 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.929576 1474 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.2:34431/master/api/v1/scheduler I0323 03:54:28.930436 1470 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0323 03:54:28.930884 1465 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:39319 I0323 03:54:28.931181 1465 master.cpp:2765] Processing REQUEST call for framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 (default) I0323 03:54:28.931342 1474 hierarchical.cpp:589] Received resource request from framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 I0323 03:54:28.933037 1468 master.cpp:1251] Framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 (default) disconnected I0323 03:54:28.933068 1468 master.cpp:2622] Disconnecting framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 (default) I0323 03:54:28.933091 1468 master.cpp:2646] Deactivating framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 (default) I0323 03:54:28.933217 1468 master.cpp:1275] Giving framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 (default) 0ns to failover I0323 03:54:28.933264 1476 hierarchical.cpp:375] Deactivated framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 I0323 03:54:28.934586 1464 master.cpp:5340] Framework failover timeout, removing framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 (default) I0323 03:54:28.934610 1464 master.cpp:6073] Removing framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 (default) I0323 03:54:28.934865 1467 hierarchical.cpp:326] Removed framework 321139c6-3f50-4b53-9349-c7d23461727d-0000 I0323 03:54:28.943661 1463 master.cpp:1065] Master terminating [ OK ] ContentType/SchedulerTest.Request/1 (505 ms) [----------] 26 tests from ContentType/SchedulerTest (18363 ms total) [----------] Global test environment tear-down E0323 03:54:29.071810 1443 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_WriteRead_BA34YM': No such file or directory E0323 03:54:29.072006 1443 environment.cpp:778] Failed to remove '/tmp/LogZooKeeperTest_LostZooKeeper_j8jd1w': No such file or directory [==========] 1035 tests from 131 test cases ran. (845412 ms total) [ PASSED ] 1034 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]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-0.29.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1458702287-25633 Untagged: mesos-1458702287-25633:latest Deleted: f3708089b58cfff4f5548162af283c8a1183630155f4b8fd5ac78b065d4752ea Deleted: 126b2a32571a60c606071cff44c00b27e75a5a648faf8476848a9a666d8956f5 Deleted: ea93d1a912da16441fcd1939b4d4e8470fb5011656c72530028e3190fe7aabb2 Deleted: 9867cb5b27f9925ca96517a6a1a8ca01908383522f86a8871e7b999ab0178c09 Deleted: 5cf05e60650eff8149d95d69b0164ada0d886f88df1c08b697ec738762229fb5 Deleted: 91665b76e7eea3ad4115a01590232a6a35f11dc733dcfc1ea43220fdf84f01c8 Deleted: a4b1b79eb5a8c1b451b62989389229024973e84fb2651d396cf99f3b571dfe23 Deleted: 601959f6777a1ab32e4178ac109157fe36f136b43bd7a111d9f76935fde2d0a4 Deleted: 76c4e868c384442df78e9c213621ee68d15e315cb3d9316b28902dab45317855 Deleted: 57f41f46a95f9882ce6da03c233399eb287ea1fc58e4c54c574bc0f15d8ccb66 Deleted: b1339c1b7dce7e6b3e589a9a66e7476f0b266dec9fa87075146c31c2acbd27b3 Deleted: 6fb5bf309aeeb9d75881fb90bd9bfe4307689f4745059191a077eb7e7a4969b4 Deleted: dd407a156f82c5d2b03292df01f005931238b0ea56ba62867d8dac3482a499e3 Deleted: da6bb2c93b29b882219aae0739abd8b42cdd3443b0a6cbbaa36cf300d0d542c7 Deleted: fce13d17247e71b7197dca71703000527db1d0dfd9c7350b36510d7d380fa695 Deleted: ed410a236a329b85f89f439a6fc7618c9971ac71a0fb1088c99eaf27e06728ea Build step 'Execute shell' marked build as failure
