You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/02/18 20:52:37 UTC
Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1684
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)/1684/>
------------------------------------------
[...truncated 164214 lines...]
I0218 19:52:37.031893 799 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_qXpzOV/slaves/10a6bd11-90ef-4c2b-bd00-2a15062eb28d-S0/frameworks/10a6bd11-90ef-4c2b-bd00-2a15062eb28d-0000' for gc 6.99999963853037days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (202 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0218 19:52:37.043962 777 leveldb.cpp:174] Opened db in 2.977776ms
I0218 19:52:37.044842 777 leveldb.cpp:181] Compacted db in 812029ns
I0218 19:52:37.044908 777 leveldb.cpp:196] Created db iterator in 26015ns
I0218 19:52:37.044935 777 leveldb.cpp:202] Seeked to beginning of db in 2267ns
I0218 19:52:37.044952 777 leveldb.cpp:271] Iterated through 0 keys in the db in 303ns
I0218 19:52:37.045011 777 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0218 19:52:37.045819 810 recover.cpp:447] Starting replica recovery
I0218 19:52:37.046145 807 recover.cpp:473] Replica is in EMPTY status
I0218 19:52:37.048825 811 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14712)@172.17.0.2:54908
I0218 19:52:37.049062 807 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0218 19:52:37.050375 805 recover.cpp:564] Updating replica status to STARTING
I0218 19:52:37.051323 801 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 775693ns
I0218 19:52:37.051353 801 replica.cpp:320] Persisted replica status to STARTING
I0218 19:52:37.051647 801 recover.cpp:473] Replica is in STARTING status
I0218 19:52:37.052798 808 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14713)@172.17.0.2:54908
I0218 19:52:37.053673 801 recover.cpp:193] Received a recover response from a replica in STARTING status
I0218 19:52:37.054111 800 recover.cpp:564] Updating replica status to VOTING
I0218 19:52:37.054646 807 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 410365ns
I0218 19:52:37.054673 807 replica.cpp:320] Persisted replica status to VOTING
I0218 19:52:37.054769 810 recover.cpp:578] Successfully joined the Paxos group
I0218 19:52:37.055001 810 recover.cpp:462] Recover process terminated
I0218 19:52:37.055313 796 master.cpp:376] Master 6d1f00f8-5834-4c8f-ad11-430d57ba28d9 (79756f9fbbea) started on 172.17.0.2:54908
I0218 19:52:37.055341 796 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/hRrKjS/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.28.0/_inst/share/mesos/webui" --work_dir="/tmp/hRrKjS/master" --zk_session_timeout="10secs"
I0218 19:52:37.055742 796 master.cpp:425] Master allowing unauthenticated frameworks to register
I0218 19:52:37.055758 796 master.cpp:428] Master only allowing authenticated slaves to register
I0218 19:52:37.055771 796 credentials.hpp:35] Loading credentials for authentication from '/tmp/hRrKjS/credentials'
I0218 19:52:37.056177 796 master.cpp:468] Using default 'crammd5' authenticator
I0218 19:52:37.056344 796 master.cpp:537] Using default 'basic' HTTP authenticator
I0218 19:52:37.056504 796 master.cpp:571] Authorization enabled
I0218 19:52:37.056712 807 whitelist_watcher.cpp:77] No whitelist given
I0218 19:52:37.056731 809 hierarchical.cpp:144] Initialized hierarchical allocator process
I0218 19:52:37.060174 800 master.cpp:1712] The newly elected leader is master@172.17.0.2:54908 with id 6d1f00f8-5834-4c8f-ad11-430d57ba28d9
I0218 19:52:37.060207 800 master.cpp:1725] Elected as the leading master!
I0218 19:52:37.060226 800 master.cpp:1470] Recovering from registrar
I0218 19:52:37.060343 807 registrar.cpp:307] Recovering registrar
I0218 19:52:37.061197 809 log.cpp:659] Attempting to start the writer
I0218 19:52:37.062604 805 replica.cpp:493] Replica received implicit promise request from (14715)@172.17.0.2:54908 with proposal 1
I0218 19:52:37.063136 805 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 486610ns
I0218 19:52:37.063163 805 replica.cpp:342] Persisted promised to 1
I0218 19:52:37.064955 810 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0218 19:52:37.066876 807 replica.cpp:388] Replica received explicit promise request from (14716)@172.17.0.2:54908 for position 0 with proposal 2
I0218 19:52:37.067526 807 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 603819ns
I0218 19:52:37.067556 807 replica.cpp:712] Persisted action at 0
I0218 19:52:37.069571 796 replica.cpp:537] Replica received write request for position 0 from (14717)@172.17.0.2:54908
I0218 19:52:37.069650 796 leveldb.cpp:436] Reading position from leveldb took 47404ns
I0218 19:52:37.070221 796 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 525549ns
I0218 19:52:37.070338 796 replica.cpp:712] Persisted action at 0
I0218 19:52:37.071142 800 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0218 19:52:37.071651 800 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 474835ns
I0218 19:52:37.071678 800 replica.cpp:712] Persisted action at 0
I0218 19:52:37.071697 800 replica.cpp:697] Replica learned NOP action at position 0
I0218 19:52:37.072551 800 log.cpp:675] Writer started with ending position 0
I0218 19:52:37.074100 800 leveldb.cpp:436] Reading position from leveldb took 29080ns
I0218 19:52:37.075575 800 registrar.cpp:340] Successfully fetched the registry (0B) in 15.183872ms
I0218 19:52:37.075731 800 registrar.cpp:439] Applied 1 operations in 35165ns; attempting to update the 'registry'
I0218 19:52:37.076985 802 log.cpp:683] Attempting to append 170 bytes to the log
I0218 19:52:37.077227 800 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0218 19:52:37.081328 809 replica.cpp:537] Replica received write request for position 1 from (14718)@172.17.0.2:54908
I0218 19:52:37.081972 809 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 567858ns
I0218 19:52:37.082003 809 replica.cpp:712] Persisted action at 1
I0218 19:52:37.082864 802 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0218 19:52:37.083412 802 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 514838ns
I0218 19:52:37.083444 802 replica.cpp:712] Persisted action at 1
I0218 19:52:37.083477 802 replica.cpp:697] Replica learned APPEND action at position 1
I0218 19:52:37.087505 809 log.cpp:702] Attempting to truncate the log to 1
I0218 19:52:37.087641 804 registrar.cpp:484] Successfully updated the 'registry' in 11.805184ms
I0218 19:52:37.087837 804 registrar.cpp:370] Successfully recovered registrar
I0218 19:52:37.087954 802 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0218 19:52:37.088842 802 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0218 19:52:37.088829 809 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0218 19:52:37.089324 811 replica.cpp:537] Replica received write request for position 2 from (14719)@172.17.0.2:54908
I0218 19:52:37.090692 811 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 1.303157ms
I0218 19:52:37.090749 811 replica.cpp:712] Persisted action at 2
I0218 19:52:37.091506 801 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0218 19:52:37.092172 801 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 634750ns
I0218 19:52:37.092231 801 leveldb.cpp:399] Deleting ~1 keys from leveldb took 33503ns
I0218 19:52:37.092257 801 replica.cpp:712] Persisted action at 2
I0218 19:52:37.092278 801 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0218 19:52:37.099485 777 scheduler.cpp:154] Version: 0.28.0
I0218 19:52:37.101133 807 scheduler.cpp:236] New master detected at master@172.17.0.2:54908
I0218 19:52:37.102888 803 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:54908
I0218 19:52:37.106009 801 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0218 19:52:37.106555 801 http.cpp:501] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44099
I0218 19:52:37.106793 801 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0218 19:52:37.106859 801 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0218 19:52:37.107512 801 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0218 19:52:37.108539 801 hierarchical.cpp:265] Added framework 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000
I0218 19:52:37.108768 808 master.hpp:1656] Sending heartbeat to 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000
I0218 19:52:37.110270 808 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:54908
I0218 19:52:37.111081 808 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:54908
I0218 19:52:37.111778 808 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0218 19:52:37.112155 801 hierarchical.cpp:1403] No resources available to allocate!
I0218 19:52:37.112268 801 hierarchical.cpp:1498] No inverse offers to send out!
I0218 19:52:37.112381 801 hierarchical.cpp:1096] Performed allocation for 0 slaves in 3.818583ms
I0218 19:52:37.112714 804 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:54908
I0218 19:52:37.114889 797 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0218 19:52:37.115468 797 http.cpp:501] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44100
I0218 19:52:37.115651 797 master.cpp:2719] Processing REQUEST call for framework 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000 (default)
I0218 19:52:37.115881 798 hierarchical.cpp:589] Received resource request from framework 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000
I0218 19:52:37.116422 777 master.cpp:1027] Master terminating
I0218 19:52:37.116627 802 hierarchical.cpp:326] Removed framework 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000
E0218 19:52:37.118911 811 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (87 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0218 19:52:37.129279 777 leveldb.cpp:174] Opened db in 2.491799ms
I0218 19:52:37.130517 777 leveldb.cpp:181] Compacted db in 1.115015ms
I0218 19:52:37.130568 777 leveldb.cpp:196] Created db iterator in 21446ns
I0218 19:52:37.130584 777 leveldb.cpp:202] Seeked to beginning of db in 2385ns
I0218 19:52:37.130595 777 leveldb.cpp:271] Iterated through 0 keys in the db in 263ns
I0218 19:52:37.130647 777 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0218 19:52:37.132804 802 recover.cpp:447] Starting replica recovery
I0218 19:52:37.134142 798 recover.cpp:473] Replica is in EMPTY status
I0218 19:52:37.134608 802 master.cpp:376] Master a0711345-ba85-49d1-b30a-4c7ca5dc3596 (79756f9fbbea) started on 172.17.0.2:54908
I0218 19:52:37.134639 802 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/qFITzR/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.28.0/_inst/share/mesos/webui" --work_dir="/tmp/qFITzR/master" --zk_session_timeout="10secs"
I0218 19:52:37.135002 802 master.cpp:425] Master allowing unauthenticated frameworks to register
I0218 19:52:37.135017 802 master.cpp:428] Master only allowing authenticated slaves to register
I0218 19:52:37.135028 802 credentials.hpp:35] Loading credentials for authentication from '/tmp/qFITzR/credentials'
I0218 19:52:37.135399 802 master.cpp:468] Using default 'crammd5' authenticator
I0218 19:52:37.135560 802 master.cpp:537] Using default 'basic' HTTP authenticator
I0218 19:52:37.135710 802 master.cpp:571] Authorization enabled
I0218 19:52:37.135881 798 whitelist_watcher.cpp:77] No whitelist given
I0218 19:52:37.136142 808 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14728)@172.17.0.2:54908
I0218 19:52:37.136168 800 hierarchical.cpp:144] Initialized hierarchical allocator process
I0218 19:52:37.136880 805 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0218 19:52:37.137462 797 recover.cpp:564] Updating replica status to STARTING
I0218 19:52:37.138481 797 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 793879ns
I0218 19:52:37.138509 797 replica.cpp:320] Persisted replica status to STARTING
I0218 19:52:37.138739 797 recover.cpp:473] Replica is in STARTING status
I0218 19:52:37.139892 797 master.cpp:1712] The newly elected leader is master@172.17.0.2:54908 with id a0711345-ba85-49d1-b30a-4c7ca5dc3596
I0218 19:52:37.139937 797 master.cpp:1725] Elected as the leading master!
I0218 19:52:37.139957 797 master.cpp:1470] Recovering from registrar
I0218 19:52:37.140303 808 registrar.cpp:307] Recovering registrar
I0218 19:52:37.141896 810 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14729)@172.17.0.2:54908
I0218 19:52:37.142233 809 recover.cpp:193] Received a recover response from a replica in STARTING status
I0218 19:52:37.142873 803 recover.cpp:564] Updating replica status to VOTING
I0218 19:52:37.143738 803 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 622957ns
I0218 19:52:37.143765 803 replica.cpp:320] Persisted replica status to VOTING
I0218 19:52:37.143853 809 recover.cpp:578] Successfully joined the Paxos group
I0218 19:52:37.144423 803 log.cpp:659] Attempting to start the writer
I0218 19:52:37.145686 809 recover.cpp:462] Recover process terminated
I0218 19:52:37.146031 809 replica.cpp:493] Replica received implicit promise request from (14730)@172.17.0.2:54908 with proposal 1
I0218 19:52:37.146541 809 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 462152ns
I0218 19:52:37.146605 809 replica.cpp:342] Persisted promised to 1
I0218 19:52:37.147454 809 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0218 19:52:37.148912 808 replica.cpp:388] Replica received explicit promise request from (14731)@172.17.0.2:54908 for position 0 with proposal 2
I0218 19:52:37.149471 808 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 493284ns
I0218 19:52:37.149504 808 replica.cpp:712] Persisted action at 0
I0218 19:52:37.150774 801 replica.cpp:537] Replica received write request for position 0 from (14732)@172.17.0.2:54908
I0218 19:52:37.150873 801 leveldb.cpp:436] Reading position from leveldb took 46281ns
I0218 19:52:37.151682 801 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 747348ns
I0218 19:52:37.151724 801 replica.cpp:712] Persisted action at 0
I0218 19:52:37.152480 797 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0218 19:52:37.152948 797 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 428749ns
I0218 19:52:37.152981 797 replica.cpp:712] Persisted action at 0
I0218 19:52:37.153007 797 replica.cpp:697] Replica learned NOP action at position 0
I0218 19:52:37.153748 806 log.cpp:675] Writer started with ending position 0
I0218 19:52:37.155051 809 leveldb.cpp:436] Reading position from leveldb took 65679ns
I0218 19:52:37.156543 798 registrar.cpp:340] Successfully fetched the registry (0B) in 16.162048ms
I0218 19:52:37.156714 798 registrar.cpp:439] Applied 1 operations in 44877ns; attempting to update the 'registry'
I0218 19:52:37.157639 808 log.cpp:683] Attempting to append 170 bytes to the log
I0218 19:52:37.157783 804 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0218 19:52:37.158860 797 replica.cpp:537] Replica received write request for position 1 from (14733)@172.17.0.2:54908
I0218 19:52:37.159682 797 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 756475ns
I0218 19:52:37.159725 797 replica.cpp:712] Persisted action at 1
I0218 19:52:37.160794 797 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0218 19:52:37.161684 797 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 839377ns
I0218 19:52:37.161726 797 replica.cpp:712] Persisted action at 1
I0218 19:52:37.161763 797 replica.cpp:697] Replica learned APPEND action at position 1
I0218 19:52:37.163756 799 registrar.cpp:484] Successfully updated the 'registry' in 6.956032ms
I0218 19:52:37.163991 799 registrar.cpp:370] Successfully recovered registrar
I0218 19:52:37.164504 799 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0218 19:52:37.164656 811 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0218 19:52:37.165379 797 log.cpp:702] Attempting to truncate the log to 1
I0218 19:52:37.165686 799 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0218 19:52:37.167279 799 replica.cpp:537] Replica received write request for position 2 from (14734)@172.17.0.2:54908
I0218 19:52:37.169704 799 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 2.341826ms
I0218 19:52:37.169786 799 replica.cpp:712] Persisted action at 2
I0218 19:52:37.170924 808 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0218 19:52:37.171485 808 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 511096ns
I0218 19:52:37.171550 808 leveldb.cpp:399] Deleting ~1 keys from leveldb took 32534ns
I0218 19:52:37.171577 808 replica.cpp:712] Persisted action at 2
I0218 19:52:37.171609 808 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0218 19:52:37.175920 777 scheduler.cpp:154] Version: 0.28.0
I0218 19:52:37.176810 799 scheduler.cpp:236] New master detected at master@172.17.0.2:54908
I0218 19:52:37.179808 796 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:54908
I0218 19:52:37.182498 804 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0218 19:52:37.183137 798 http.cpp:501] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44101
I0218 19:52:37.183640 798 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0218 19:52:37.183719 798 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0218 19:52:37.184077 804 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0218 19:52:37.184676 806 hierarchical.cpp:265] Added framework a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000
I0218 19:52:37.184850 806 hierarchical.cpp:1403] No resources available to allocate!
I0218 19:52:37.184957 806 hierarchical.cpp:1498] No inverse offers to send out!
I0218 19:52:37.185042 806 hierarchical.cpp:1096] Performed allocation for 0 slaves in 267263ns
I0218 19:52:37.185318 804 master.hpp:1656] Sending heartbeat to a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000
I0218 19:52:37.186214 804 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:54908
I0218 19:52:37.187055 804 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:54908
I0218 19:52:37.187518 804 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0218 19:52:37.188666 809 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:54908
I0218 19:52:37.191066 797 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0218 19:52:37.191671 809 http.cpp:501] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44102
I0218 19:52:37.192111 809 master.cpp:2719] Processing REQUEST call for framework a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000 (default)
I0218 19:52:37.192365 803 hierarchical.cpp:589] Received resource request from framework a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000
I0218 19:52:37.193048 805 master.cpp:1027] Master terminating
I0218 19:52:37.193361 807 hierarchical.cpp:326] Removed framework a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000
E0218 19:52:37.195322 802 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (79 ms)
[----------] 22 tests from ContentType/SchedulerTest (5594 ms total)
[----------] Global test environment tear-down
[==========] 974 tests from 126 test cases ran. (360845 ms total)
[ PASSED ] 973 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MasterQuotaTest.AvailableResourcesAfterRescinding
1 FAILED TEST
YOU HAVE 7 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1455823040-11311
Untagged: mesos-1455823040-11311:latest
Deleted: 3b1b0748281522bd7af63a4192bbb5c24864c11dbb696b5da85cb945dc933406
Deleted: dc1b49af9340ed1bb69a8d538cb41d6753b077f43d104111b6bf2f836b2975ba
Deleted: 418c3b054ddc0fda8c1609d2a1dabd90522a18806715b22192237182ce96cc76
Deleted: e8aaa004c2ab994c9ed6162387cd2af4b080dbe453e39104a7d7da818012a1bd
Deleted: 9cf6e77e1e9ac2d54616832e9ebcf9df24643403b58e1b4ca876656e31ff57fd
Deleted: c60e80696c212fe36220250783f5e0528c549a51ddfc14d180ad6cbdcfb6c6dc
Deleted: 83f2845e0da4f9798fe45348c9f4f197845d2b140869efa95912826fbd4ec60d
Deleted: 34e0b4dd418dd4d41e09f2a55fd8c30716e1a28fdd3f909d4ce1fc951fba9bb4
Deleted: 20c519a13d41799e6cfdcc10dd9911529a9c761608e63bcc5955639a35f0c632
Deleted: 286d92931d3e98ce33386b7709122a70036b3f7d51b00f963944fa9da76b9eb6
Deleted: 656bc6950001468f67d87b0b1c50a204643926e3ecefb2f4590ffec6086c00e2
Deleted: e1793817a15e4ed1fe95ada7f7e95dc82ee8eec3c82c163238a60f88aedad6dd
Deleted: 697ba0981484cdab3fada9e62ebd7e557a5f5f37aa6a7b8a8ca1804609f7db3e
Deleted: af76d741673693a8d81964a86e6bbe6c12c23cfc4cdde2edac5cf14ec7e6a4f3
Deleted: 38d171f87638c8c1b641b79cb37e9779b7e74e5991c28f9e00326b2f7c6f2364
Deleted: 0b9b6dc7b01b7457671593dbec9860ff37aee87b478afb060ef6a453b3a50af3
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1685
Posted by Apache Jenkins Server <je...@builds.apache.org>.
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)/1685/>
Re: Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1684
Posted by Alex Rukletsov <al...@mesosphere.com>.
Fix is on the way: https://reviews.apache.org/r/43746/
On Thu, Feb 18, 2016 at 8:52 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> 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)/1684/
> >
>
> ------------------------------------------
> [...truncated 164214 lines...]
> I0218 19:52:37.031893 799 gc.cpp:54] Scheduling
> '/tmp/ContentType_SchedulerTest_Message_1_qXpzOV/slaves/10a6bd11-90ef-4c2b-bd00-2a15062eb28d-S0/frameworks/10a6bd11-90ef-4c2b-bd00-2a15062eb28d-0000'
> for gc 6.99999963853037days in the future
> [ OK ] ContentType/SchedulerTest.Message/1 (202 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> I0218 19:52:37.043962 777 leveldb.cpp:174] Opened db in 2.977776ms
> I0218 19:52:37.044842 777 leveldb.cpp:181] Compacted db in 812029ns
> I0218 19:52:37.044908 777 leveldb.cpp:196] Created db iterator in 26015ns
> I0218 19:52:37.044935 777 leveldb.cpp:202] Seeked to beginning of db in
> 2267ns
> I0218 19:52:37.044952 777 leveldb.cpp:271] Iterated through 0 keys in
> the db in 303ns
> I0218 19:52:37.045011 777 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0218 19:52:37.045819 810 recover.cpp:447] Starting replica recovery
> I0218 19:52:37.046145 807 recover.cpp:473] Replica is in EMPTY status
> I0218 19:52:37.048825 811 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14712)@172.17.0.2:54908
> I0218 19:52:37.049062 807 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0218 19:52:37.050375 805 recover.cpp:564] Updating replica status to
> STARTING
> I0218 19:52:37.051323 801 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 775693ns
> I0218 19:52:37.051353 801 replica.cpp:320] Persisted replica status to
> STARTING
> I0218 19:52:37.051647 801 recover.cpp:473] Replica is in STARTING status
> I0218 19:52:37.052798 808 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14713)@172.17.0.2:54908
> I0218 19:52:37.053673 801 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0218 19:52:37.054111 800 recover.cpp:564] Updating replica status to
> VOTING
> I0218 19:52:37.054646 807 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 410365ns
> I0218 19:52:37.054673 807 replica.cpp:320] Persisted replica status to
> VOTING
> I0218 19:52:37.054769 810 recover.cpp:578] Successfully joined the Paxos
> group
> I0218 19:52:37.055001 810 recover.cpp:462] Recover process terminated
> I0218 19:52:37.055313 796 master.cpp:376] Master
> 6d1f00f8-5834-4c8f-ad11-430d57ba28d9 (79756f9fbbea) started on
> 172.17.0.2:54908
> I0218 19:52:37.055341 796 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/hRrKjS/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.28.0/_inst/share/mesos/webui"
> --work_dir="/tmp/hRrKjS/master" --zk_session_timeout="10secs"
> I0218 19:52:37.055742 796 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0218 19:52:37.055758 796 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0218 19:52:37.055771 796 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/hRrKjS/credentials'
> I0218 19:52:37.056177 796 master.cpp:468] Using default 'crammd5'
> authenticator
> I0218 19:52:37.056344 796 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0218 19:52:37.056504 796 master.cpp:571] Authorization enabled
> I0218 19:52:37.056712 807 whitelist_watcher.cpp:77] No whitelist given
> I0218 19:52:37.056731 809 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0218 19:52:37.060174 800 master.cpp:1712] The newly elected leader is
> master@172.17.0.2:54908 with id 6d1f00f8-5834-4c8f-ad11-430d57ba28d9
> I0218 19:52:37.060207 800 master.cpp:1725] Elected as the leading master!
> I0218 19:52:37.060226 800 master.cpp:1470] Recovering from registrar
> I0218 19:52:37.060343 807 registrar.cpp:307] Recovering registrar
> I0218 19:52:37.061197 809 log.cpp:659] Attempting to start the writer
> I0218 19:52:37.062604 805 replica.cpp:493] Replica received implicit
> promise request from (14715)@172.17.0.2:54908 with proposal 1
> I0218 19:52:37.063136 805 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 486610ns
> I0218 19:52:37.063163 805 replica.cpp:342] Persisted promised to 1
> I0218 19:52:37.064955 810 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0218 19:52:37.066876 807 replica.cpp:388] Replica received explicit
> promise request from (14716)@172.17.0.2:54908 for position 0 with
> proposal 2
> I0218 19:52:37.067526 807 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 603819ns
> I0218 19:52:37.067556 807 replica.cpp:712] Persisted action at 0
> I0218 19:52:37.069571 796 replica.cpp:537] Replica received write
> request for position 0 from (14717)@172.17.0.2:54908
> I0218 19:52:37.069650 796 leveldb.cpp:436] Reading position from leveldb
> took 47404ns
> I0218 19:52:37.070221 796 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 525549ns
> I0218 19:52:37.070338 796 replica.cpp:712] Persisted action at 0
> I0218 19:52:37.071142 800 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0218 19:52:37.071651 800 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 474835ns
> I0218 19:52:37.071678 800 replica.cpp:712] Persisted action at 0
> I0218 19:52:37.071697 800 replica.cpp:697] Replica learned NOP action at
> position 0
> I0218 19:52:37.072551 800 log.cpp:675] Writer started with ending
> position 0
> I0218 19:52:37.074100 800 leveldb.cpp:436] Reading position from leveldb
> took 29080ns
> I0218 19:52:37.075575 800 registrar.cpp:340] Successfully fetched the
> registry (0B) in 15.183872ms
> I0218 19:52:37.075731 800 registrar.cpp:439] Applied 1 operations in
> 35165ns; attempting to update the 'registry'
> I0218 19:52:37.076985 802 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0218 19:52:37.077227 800 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0218 19:52:37.081328 809 replica.cpp:537] Replica received write
> request for position 1 from (14718)@172.17.0.2:54908
> I0218 19:52:37.081972 809 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 567858ns
> I0218 19:52:37.082003 809 replica.cpp:712] Persisted action at 1
> I0218 19:52:37.082864 802 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0218 19:52:37.083412 802 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 514838ns
> I0218 19:52:37.083444 802 replica.cpp:712] Persisted action at 1
> I0218 19:52:37.083477 802 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0218 19:52:37.087505 809 log.cpp:702] Attempting to truncate the log to
> 1
> I0218 19:52:37.087641 804 registrar.cpp:484] Successfully updated the
> 'registry' in 11.805184ms
> I0218 19:52:37.087837 804 registrar.cpp:370] Successfully recovered
> registrar
> I0218 19:52:37.087954 802 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0218 19:52:37.088842 802 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0218 19:52:37.088829 809 master.cpp:1522] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0218 19:52:37.089324 811 replica.cpp:537] Replica received write
> request for position 2 from (14719)@172.17.0.2:54908
> I0218 19:52:37.090692 811 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 1.303157ms
> I0218 19:52:37.090749 811 replica.cpp:712] Persisted action at 2
> I0218 19:52:37.091506 801 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0218 19:52:37.092172 801 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 634750ns
> I0218 19:52:37.092231 801 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 33503ns
> I0218 19:52:37.092257 801 replica.cpp:712] Persisted action at 2
> I0218 19:52:37.092278 801 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0218 19:52:37.099485 777 scheduler.cpp:154] Version: 0.28.0
> I0218 19:52:37.101133 807 scheduler.cpp:236] New master detected at
> master@172.17.0.2:54908
> I0218 19:52:37.102888 803 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.2:54908
> I0218 19:52:37.106009 801 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0218 19:52:37.106555 801 http.cpp:501] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:44099
> I0218 19:52:37.106793 801 master.cpp:1974] Received subscription request
> for HTTP framework 'default'
> I0218 19:52:37.106859 801 master.cpp:1751] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0218 19:52:37.107512 801 master.cpp:2065] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0218 19:52:37.108539 801 hierarchical.cpp:265] Added framework
> 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000
> I0218 19:52:37.108768 808 master.hpp:1656] Sending heartbeat to
> 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000
> I0218 19:52:37.110270 808 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.2:54908
> I0218 19:52:37.111081 808 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.2:54908
> I0218 19:52:37.111778 808 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0218 19:52:37.112155 801 hierarchical.cpp:1403] No resources available
> to allocate!
> I0218 19:52:37.112268 801 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0218 19:52:37.112381 801 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 3.818583ms
> I0218 19:52:37.112714 804 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.2:54908
> I0218 19:52:37.114889 797 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0218 19:52:37.115468 797 http.cpp:501] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:44100
> I0218 19:52:37.115651 797 master.cpp:2719] Processing REQUEST call for
> framework 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000 (default)
> I0218 19:52:37.115881 798 hierarchical.cpp:589] Received resource
> request from framework 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000
> I0218 19:52:37.116422 777 master.cpp:1027] Master terminating
> I0218 19:52:37.116627 802 hierarchical.cpp:326] Removed framework
> 6d1f00f8-5834-4c8f-ad11-430d57ba28d9-0000
> E0218 19:52:37.118911 811 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/0 (87 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> I0218 19:52:37.129279 777 leveldb.cpp:174] Opened db in 2.491799ms
> I0218 19:52:37.130517 777 leveldb.cpp:181] Compacted db in 1.115015ms
> I0218 19:52:37.130568 777 leveldb.cpp:196] Created db iterator in 21446ns
> I0218 19:52:37.130584 777 leveldb.cpp:202] Seeked to beginning of db in
> 2385ns
> I0218 19:52:37.130595 777 leveldb.cpp:271] Iterated through 0 keys in
> the db in 263ns
> I0218 19:52:37.130647 777 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0218 19:52:37.132804 802 recover.cpp:447] Starting replica recovery
> I0218 19:52:37.134142 798 recover.cpp:473] Replica is in EMPTY status
> I0218 19:52:37.134608 802 master.cpp:376] Master
> a0711345-ba85-49d1-b30a-4c7ca5dc3596 (79756f9fbbea) started on
> 172.17.0.2:54908
> I0218 19:52:37.134639 802 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/qFITzR/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.28.0/_inst/share/mesos/webui"
> --work_dir="/tmp/qFITzR/master" --zk_session_timeout="10secs"
> I0218 19:52:37.135002 802 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0218 19:52:37.135017 802 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0218 19:52:37.135028 802 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/qFITzR/credentials'
> I0218 19:52:37.135399 802 master.cpp:468] Using default 'crammd5'
> authenticator
> I0218 19:52:37.135560 802 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0218 19:52:37.135710 802 master.cpp:571] Authorization enabled
> I0218 19:52:37.135881 798 whitelist_watcher.cpp:77] No whitelist given
> I0218 19:52:37.136142 808 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14728)@172.17.0.2:54908
> I0218 19:52:37.136168 800 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0218 19:52:37.136880 805 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0218 19:52:37.137462 797 recover.cpp:564] Updating replica status to
> STARTING
> I0218 19:52:37.138481 797 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 793879ns
> I0218 19:52:37.138509 797 replica.cpp:320] Persisted replica status to
> STARTING
> I0218 19:52:37.138739 797 recover.cpp:473] Replica is in STARTING status
> I0218 19:52:37.139892 797 master.cpp:1712] The newly elected leader is
> master@172.17.0.2:54908 with id a0711345-ba85-49d1-b30a-4c7ca5dc3596
> I0218 19:52:37.139937 797 master.cpp:1725] Elected as the leading master!
> I0218 19:52:37.139957 797 master.cpp:1470] Recovering from registrar
> I0218 19:52:37.140303 808 registrar.cpp:307] Recovering registrar
> I0218 19:52:37.141896 810 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14729)@172.17.0.2:54908
> I0218 19:52:37.142233 809 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0218 19:52:37.142873 803 recover.cpp:564] Updating replica status to
> VOTING
> I0218 19:52:37.143738 803 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 622957ns
> I0218 19:52:37.143765 803 replica.cpp:320] Persisted replica status to
> VOTING
> I0218 19:52:37.143853 809 recover.cpp:578] Successfully joined the Paxos
> group
> I0218 19:52:37.144423 803 log.cpp:659] Attempting to start the writer
> I0218 19:52:37.145686 809 recover.cpp:462] Recover process terminated
> I0218 19:52:37.146031 809 replica.cpp:493] Replica received implicit
> promise request from (14730)@172.17.0.2:54908 with proposal 1
> I0218 19:52:37.146541 809 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 462152ns
> I0218 19:52:37.146605 809 replica.cpp:342] Persisted promised to 1
> I0218 19:52:37.147454 809 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0218 19:52:37.148912 808 replica.cpp:388] Replica received explicit
> promise request from (14731)@172.17.0.2:54908 for position 0 with
> proposal 2
> I0218 19:52:37.149471 808 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 493284ns
> I0218 19:52:37.149504 808 replica.cpp:712] Persisted action at 0
> I0218 19:52:37.150774 801 replica.cpp:537] Replica received write
> request for position 0 from (14732)@172.17.0.2:54908
> I0218 19:52:37.150873 801 leveldb.cpp:436] Reading position from leveldb
> took 46281ns
> I0218 19:52:37.151682 801 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 747348ns
> I0218 19:52:37.151724 801 replica.cpp:712] Persisted action at 0
> I0218 19:52:37.152480 797 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0218 19:52:37.152948 797 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 428749ns
> I0218 19:52:37.152981 797 replica.cpp:712] Persisted action at 0
> I0218 19:52:37.153007 797 replica.cpp:697] Replica learned NOP action at
> position 0
> I0218 19:52:37.153748 806 log.cpp:675] Writer started with ending
> position 0
> I0218 19:52:37.155051 809 leveldb.cpp:436] Reading position from leveldb
> took 65679ns
> I0218 19:52:37.156543 798 registrar.cpp:340] Successfully fetched the
> registry (0B) in 16.162048ms
> I0218 19:52:37.156714 798 registrar.cpp:439] Applied 1 operations in
> 44877ns; attempting to update the 'registry'
> I0218 19:52:37.157639 808 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0218 19:52:37.157783 804 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0218 19:52:37.158860 797 replica.cpp:537] Replica received write
> request for position 1 from (14733)@172.17.0.2:54908
> I0218 19:52:37.159682 797 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 756475ns
> I0218 19:52:37.159725 797 replica.cpp:712] Persisted action at 1
> I0218 19:52:37.160794 797 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0218 19:52:37.161684 797 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 839377ns
> I0218 19:52:37.161726 797 replica.cpp:712] Persisted action at 1
> I0218 19:52:37.161763 797 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0218 19:52:37.163756 799 registrar.cpp:484] Successfully updated the
> 'registry' in 6.956032ms
> I0218 19:52:37.163991 799 registrar.cpp:370] Successfully recovered
> registrar
> I0218 19:52:37.164504 799 master.cpp:1522] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0218 19:52:37.164656 811 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0218 19:52:37.165379 797 log.cpp:702] Attempting to truncate the log to
> 1
> I0218 19:52:37.165686 799 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0218 19:52:37.167279 799 replica.cpp:537] Replica received write
> request for position 2 from (14734)@172.17.0.2:54908
> I0218 19:52:37.169704 799 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 2.341826ms
> I0218 19:52:37.169786 799 replica.cpp:712] Persisted action at 2
> I0218 19:52:37.170924 808 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0218 19:52:37.171485 808 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 511096ns
> I0218 19:52:37.171550 808 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 32534ns
> I0218 19:52:37.171577 808 replica.cpp:712] Persisted action at 2
> I0218 19:52:37.171609 808 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0218 19:52:37.175920 777 scheduler.cpp:154] Version: 0.28.0
> I0218 19:52:37.176810 799 scheduler.cpp:236] New master detected at
> master@172.17.0.2:54908
> I0218 19:52:37.179808 796 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.2:54908
> I0218 19:52:37.182498 804 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0218 19:52:37.183137 798 http.cpp:501] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:44101
> I0218 19:52:37.183640 798 master.cpp:1974] Received subscription request
> for HTTP framework 'default'
> I0218 19:52:37.183719 798 master.cpp:1751] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0218 19:52:37.184077 804 master.cpp:2065] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0218 19:52:37.184676 806 hierarchical.cpp:265] Added framework
> a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000
> I0218 19:52:37.184850 806 hierarchical.cpp:1403] No resources available
> to allocate!
> I0218 19:52:37.184957 806 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0218 19:52:37.185042 806 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 267263ns
> I0218 19:52:37.185318 804 master.hpp:1656] Sending heartbeat to
> a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000
> I0218 19:52:37.186214 804 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.2:54908
> I0218 19:52:37.187055 804 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.2:54908
> I0218 19:52:37.187518 804 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0218 19:52:37.188666 809 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.2:54908
> I0218 19:52:37.191066 797 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0218 19:52:37.191671 809 http.cpp:501] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.2:44102
> I0218 19:52:37.192111 809 master.cpp:2719] Processing REQUEST call for
> framework a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000 (default)
> I0218 19:52:37.192365 803 hierarchical.cpp:589] Received resource
> request from framework a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000
> I0218 19:52:37.193048 805 master.cpp:1027] Master terminating
> I0218 19:52:37.193361 807 hierarchical.cpp:326] Removed framework
> a0711345-ba85-49d1-b30a-4c7ca5dc3596-0000
> E0218 19:52:37.195322 802 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (79 ms)
> [----------] 22 tests from ContentType/SchedulerTest (5594 ms total)
>
> [----------] Global test environment tear-down
> [==========] 974 tests from 126 test cases ran. (360845 ms total)
> [ PASSED ] 973 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] MasterQuotaTest.AvailableResourcesAfterRescinding
>
> 1 FAILED TEST
> YOU HAVE 7 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1455823040-11311
> Untagged: mesos-1455823040-11311:latest
> Deleted: 3b1b0748281522bd7af63a4192bbb5c24864c11dbb696b5da85cb945dc933406
> Deleted: dc1b49af9340ed1bb69a8d538cb41d6753b077f43d104111b6bf2f836b2975ba
> Deleted: 418c3b054ddc0fda8c1609d2a1dabd90522a18806715b22192237182ce96cc76
> Deleted: e8aaa004c2ab994c9ed6162387cd2af4b080dbe453e39104a7d7da818012a1bd
> Deleted: 9cf6e77e1e9ac2d54616832e9ebcf9df24643403b58e1b4ca876656e31ff57fd
> Deleted: c60e80696c212fe36220250783f5e0528c549a51ddfc14d180ad6cbdcfb6c6dc
> Deleted: 83f2845e0da4f9798fe45348c9f4f197845d2b140869efa95912826fbd4ec60d
> Deleted: 34e0b4dd418dd4d41e09f2a55fd8c30716e1a28fdd3f909d4ce1fc951fba9bb4
> Deleted: 20c519a13d41799e6cfdcc10dd9911529a9c761608e63bcc5955639a35f0c632
> Deleted: 286d92931d3e98ce33386b7709122a70036b3f7d51b00f963944fa9da76b9eb6
> Deleted: 656bc6950001468f67d87b0b1c50a204643926e3ecefb2f4590ffec6086c00e2
> Deleted: e1793817a15e4ed1fe95ada7f7e95dc82ee8eec3c82c163238a60f88aedad6dd
> Deleted: 697ba0981484cdab3fada9e62ebd7e557a5f5f37aa6a7b8a8ca1804609f7db3e
> Deleted: af76d741673693a8d81964a86e6bbe6c12c23cfc4cdde2edac5cf14ec7e6a4f3
> Deleted: 38d171f87638c8c1b641b79cb37e9779b7e74e5991c28f9e00326b2f7c6f2364
> Deleted: 0b9b6dc7b01b7457671593dbec9860ff37aee87b478afb060ef6a453b3a50af3
> Build step 'Execute shell' marked build as failure
>