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 2015/11/10 12:10:39 UTC
Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1209
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1209/changes>
Changes:
[bernd] Added a list of protocols supported by the Mesos fetcher to its
------------------------------------------
[...truncated 140909 lines...]
I1110 11:10:49.337502 28131 slave.cpp:3657] Cleaning up executor 'default' of framework 00224ed3-1285-4bdf-a2b2-64c956b4c1f7-0000 at executor(132)@172.17.6.114:57108
I1110 11:10:49.338004 28137 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_WmWggc/slaves/00224ed3-1285-4bdf-a2b2-64c956b4c1f7-S0/frameworks/00224ed3-1285-4bdf-a2b2-64c956b4c1f7-0000/executors/default/runs/c65b0e14-9fc0-4c49-a8c2-cb3b52adfacc' for gc 6.99999609054519days in the future
I1110 11:10:49.338116 28131 slave.cpp:3745] Cleaning up framework 00224ed3-1285-4bdf-a2b2-64c956b4c1f7-0000
I1110 11:10:49.338198 28137 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_WmWggc/slaves/00224ed3-1285-4bdf-a2b2-64c956b4c1f7-S0/frameworks/00224ed3-1285-4bdf-a2b2-64c956b4c1f7-0000/executors/default' for gc 6.99999608790518days in the future
I1110 11:10:49.338706 28137 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_WmWggc/slaves/00224ed3-1285-4bdf-a2b2-64c956b4c1f7-S0/frameworks/00224ed3-1285-4bdf-a2b2-64c956b4c1f7-0000' for gc 6.99999608384296days in the future
I1110 11:10:49.338901 28128 status_update_manager.cpp:284] Closing status update streams for framework 00224ed3-1285-4bdf-a2b2-64c956b4c1f7-0000
I1110 11:10:49.339074 28128 status_update_manager.cpp:530] Cleaning up status update stream for task beaa4a9f-07b2-4259-a1f8-5b85bd7c168f of framework 00224ed3-1285-4bdf-a2b2-64c956b4c1f7-0000
[ OK ] ContentType/SchedulerTest.Message/1 (139 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1110 11:10:49.350327 28108 leveldb.cpp:176] Opened db in 4.288526ms
I1110 11:10:49.352051 28108 leveldb.cpp:183] Compacted db in 1.670019ms
I1110 11:10:49.352123 28108 leveldb.cpp:198] Created db iterator in 35225ns
I1110 11:10:49.352144 28108 leveldb.cpp:204] Seeked to beginning of db in 8472ns
I1110 11:10:49.352155 28108 leveldb.cpp:273] Iterated through 0 keys in the db in 6125ns
I1110 11:10:49.352207 28108 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1110 11:10:49.353693 28126 recover.cpp:449] Starting replica recovery
I1110 11:10:49.354365 28126 recover.cpp:475] Replica is in EMPTY status
I1110 11:10:49.355646 28141 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10524)@172.17.6.114:57108
I1110 11:10:49.356071 28139 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1110 11:10:49.357976 28127 master.cpp:367] Master f73b591b-e146-46e4-93af-7f9f0c3f0294 (8ce800f70d85) started on 172.17.6.114:57108
I1110 11:10:49.358050 28127 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/sg3OT9/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/sg3OT9/master" --zk_session_timeout="10secs"
I1110 11:10:49.358438 28127 master.cpp:416] Master allowing unauthenticated frameworks to register
I1110 11:10:49.358450 28127 master.cpp:419] Master only allowing authenticated slaves to register
I1110 11:10:49.358459 28127 credentials.hpp:37] Loading credentials for authentication from '/tmp/sg3OT9/credentials'
I1110 11:10:49.358482 28139 recover.cpp:566] Updating replica status to STARTING
I1110 11:10:49.358813 28127 master.cpp:458] Using default 'crammd5' authenticator
I1110 11:10:49.358989 28127 master.cpp:495] Authorization enabled
I1110 11:10:49.359310 28136 hierarchical.cpp:140] Initialized hierarchical allocator process
I1110 11:10:49.359458 28136 whitelist_watcher.cpp:79] No whitelist given
I1110 11:10:49.361707 28139 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 2.933942ms
I1110 11:10:49.361850 28139 replica.cpp:323] Persisted replica status to STARTING
I1110 11:10:49.362211 28126 recover.cpp:475] Replica is in STARTING status
I1110 11:10:49.361922 28137 master.cpp:1606] The newly elected leader is master@172.17.6.114:57108 with id f73b591b-e146-46e4-93af-7f9f0c3f0294
I1110 11:10:49.363230 28137 master.cpp:1619] Elected as the leading master!
I1110 11:10:49.363246 28137 master.cpp:1379] Recovering from registrar
I1110 11:10:49.363508 28137 registrar.cpp:309] Recovering registrar
I1110 11:10:49.363970 28137 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10525)@172.17.6.114:57108
I1110 11:10:49.364423 28139 recover.cpp:195] Received a recover response from a replica in STARTING status
I1110 11:10:49.365111 28139 recover.cpp:566] Updating replica status to VOTING
I1110 11:10:49.365687 28135 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 423930ns
I1110 11:10:49.365718 28135 replica.cpp:323] Persisted replica status to VOTING
I1110 11:10:49.365860 28139 recover.cpp:580] Successfully joined the Paxos group
I1110 11:10:49.366063 28139 recover.cpp:464] Recover process terminated
I1110 11:10:49.366788 28126 log.cpp:661] Attempting to start the writer
I1110 11:10:49.368474 28140 replica.cpp:496] Replica received implicit promise request from (10526)@172.17.6.114:57108 with proposal 1
I1110 11:10:49.369132 28140 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 608186ns
I1110 11:10:49.369161 28140 replica.cpp:345] Persisted promised to 1
I1110 11:10:49.370100 28140 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1110 11:10:49.371657 28131 replica.cpp:391] Replica received explicit promise request from (10527)@172.17.6.114:57108 for position 0 with proposal 2
I1110 11:10:49.372129 28131 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 417228ns
I1110 11:10:49.372160 28131 replica.cpp:715] Persisted action at 0
I1110 11:10:49.373469 28131 replica.cpp:540] Replica received write request for position 0 from (10528)@172.17.6.114:57108
I1110 11:10:49.373534 28131 leveldb.cpp:438] Reading position from leveldb took 44435ns
I1110 11:10:49.373857 28131 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 285997ns
I1110 11:10:49.373888 28131 replica.cpp:715] Persisted action at 0
I1110 11:10:49.374558 28136 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1110 11:10:49.375033 28136 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 457368ns
I1110 11:10:49.375059 28136 replica.cpp:715] Persisted action at 0
I1110 11:10:49.375077 28136 replica.cpp:700] Replica learned NOP action at position 0
I1110 11:10:49.375883 28136 log.cpp:677] Writer started with ending position 0
I1110 11:10:49.377354 28128 leveldb.cpp:438] Reading position from leveldb took 55225ns
I1110 11:10:49.378649 28128 registrar.cpp:342] Successfully fetched the registry (0B) in 15.106048ms
I1110 11:10:49.379053 28128 registrar.cpp:441] Applied 1 operations in 45690ns; attempting to update the 'registry'
I1110 11:10:49.380267 28128 log.cpp:685] Attempting to append 176 bytes to the log
I1110 11:10:49.380543 28128 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1110 11:10:49.381507 28136 replica.cpp:540] Replica received write request for position 1 from (10529)@172.17.6.114:57108
I1110 11:10:49.381963 28136 leveldb.cpp:343] Persisting action (195 bytes) to leveldb took 369437ns
I1110 11:10:49.381991 28136 replica.cpp:715] Persisted action at 1
I1110 11:10:49.382742 28127 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1110 11:10:49.383196 28127 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 412127ns
I1110 11:10:49.383229 28127 replica.cpp:715] Persisted action at 1
I1110 11:10:49.383257 28127 replica.cpp:700] Replica learned APPEND action at position 1
I1110 11:10:49.384256 28138 registrar.cpp:486] Successfully updated the 'registry' in 5.055232ms
I1110 11:10:49.384378 28138 registrar.cpp:372] Successfully recovered registrar
I1110 11:10:49.384462 28132 log.cpp:704] Attempting to truncate the log to 1
I1110 11:10:49.384580 28127 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1110 11:10:49.384735 28138 master.cpp:1416] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1110 11:10:49.386509 28138 replica.cpp:540] Replica received write request for position 2 from (10530)@172.17.6.114:57108
I1110 11:10:49.386912 28138 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 375416ns
I1110 11:10:49.386947 28138 replica.cpp:715] Persisted action at 2
I1110 11:10:49.387457 28140 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1110 11:10:49.387773 28140 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 299514ns
I1110 11:10:49.387822 28140 leveldb.cpp:401] Deleting ~1 keys from leveldb took 34908ns
I1110 11:10:49.387838 28140 replica.cpp:715] Persisted action at 2
I1110 11:10:49.387856 28140 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1110 11:10:49.395949 28108 scheduler.cpp:156] Version: 0.26.0
I1110 11:10:49.397022 28141 scheduler.cpp:238] New master detected at master@172.17.6.114:57108
I1110 11:10:49.398387 28129 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.6.114:57108
I1110 11:10:49.401041 28131 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1110 11:10:49.401309 28131 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.114:53020
I1110 11:10:49.401562 28131 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1110 11:10:49.401592 28131 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1110 11:10:49.402596 28131 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1110 11:10:49.403270 28131 hierarchical.cpp:185] Added framework f73b591b-e146-46e4-93af-7f9f0c3f0294-0000
I1110 11:10:49.403395 28131 hierarchical.cpp:971] No resources available to allocate!
I1110 11:10:49.403514 28131 hierarchical.cpp:1064] No inverse offers to send out!
I1110 11:10:49.403628 28131 hierarchical.cpp:870] Performed allocation for 0 slaves in 283417ns
I1110 11:10:49.403724 28131 master.hpp:1439] Sending heartbeat to f73b591b-e146-46e4-93af-7f9f0c3f0294-0000
I1110 11:10:49.404579 28133 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.6.114:57108
I1110 11:10:49.406483 28141 scheduler.cpp:300] Sending REQUEST call to master@172.17.6.114:57108
I1110 11:10:49.407258 28141 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.6.114:57108
I1110 11:10:49.407701 28141 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1110 11:10:49.408519 28141 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1110 11:10:49.408851 28141 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.114:53021
I1110 11:10:49.409139 28141 master.cpp:2615] Processing REQUEST call for framework f73b591b-e146-46e4-93af-7f9f0c3f0294-0000 (default)
I1110 11:10:49.409498 28141 hierarchical.cpp:449] Received resource request from framework f73b591b-e146-46e4-93af-7f9f0c3f0294-0000
I1110 11:10:49.410195 28108 master.cpp:922] Master terminating
I1110 11:10:49.412056 28131 hierarchical.cpp:220] Removed framework f73b591b-e146-46e4-93af-7f9f0c3f0294-0000
E1110 11:10:49.415083 28132 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (75 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1110 11:10:49.423451 28108 leveldb.cpp:176] Opened db in 2.066826ms
I1110 11:10:49.424149 28108 leveldb.cpp:183] Compacted db in 655017ns
I1110 11:10:49.424201 28108 leveldb.cpp:198] Created db iterator in 35241ns
I1110 11:10:49.424226 28108 leveldb.cpp:204] Seeked to beginning of db in 12032ns
I1110 11:10:49.424243 28108 leveldb.cpp:273] Iterated through 0 keys in the db in 9821ns
I1110 11:10:49.424304 28108 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1110 11:10:49.425070 28127 recover.cpp:449] Starting replica recovery
I1110 11:10:49.427052 28129 recover.cpp:475] Replica is in EMPTY status
I1110 11:10:49.428896 28127 master.cpp:367] Master 6eb0347f-3930-41b9-b099-844f7df9a562 (8ce800f70d85) started on 172.17.6.114:57108
I1110 11:10:49.429975 28127 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/JnOjMO/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/JnOjMO/master" --zk_session_timeout="10secs"
I1110 11:10:49.430325 28127 master.cpp:416] Master allowing unauthenticated frameworks to register
I1110 11:10:49.430341 28127 master.cpp:419] Master only allowing authenticated slaves to register
I1110 11:10:49.430352 28127 credentials.hpp:37] Loading credentials for authentication from '/tmp/JnOjMO/credentials'
I1110 11:10:49.430685 28127 master.cpp:458] Using default 'crammd5' authenticator
I1110 11:10:49.430832 28127 master.cpp:495] Authorization enabled
I1110 11:10:49.431133 28140 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10535)@172.17.6.114:57108
I1110 11:10:49.431679 28137 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1110 11:10:49.431946 28126 hierarchical.cpp:140] Initialized hierarchical allocator process
I1110 11:10:49.432865 28141 recover.cpp:566] Updating replica status to STARTING
I1110 11:10:49.434476 28127 master.cpp:1606] The newly elected leader is master@172.17.6.114:57108 with id 6eb0347f-3930-41b9-b099-844f7df9a562
I1110 11:10:49.434520 28127 master.cpp:1619] Elected as the leading master!
I1110 11:10:49.434540 28127 master.cpp:1379] Recovering from registrar
I1110 11:10:49.435071 28127 registrar.cpp:309] Recovering registrar
I1110 11:10:49.432186 28133 whitelist_watcher.cpp:79] No whitelist given
I1110 11:10:49.436274 28140 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 1.111514ms
I1110 11:10:49.436314 28140 replica.cpp:323] Persisted replica status to STARTING
I1110 11:10:49.436570 28140 recover.cpp:475] Replica is in STARTING status
I1110 11:10:49.437657 28140 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10536)@172.17.6.114:57108
I1110 11:10:49.438251 28129 recover.cpp:195] Received a recover response from a replica in STARTING status
I1110 11:10:49.438733 28140 recover.cpp:566] Updating replica status to VOTING
I1110 11:10:49.439483 28129 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 377637ns
I1110 11:10:49.439523 28129 replica.cpp:323] Persisted replica status to VOTING
I1110 11:10:49.439645 28129 recover.cpp:580] Successfully joined the Paxos group
I1110 11:10:49.439901 28129 recover.cpp:464] Recover process terminated
I1110 11:10:49.440557 28129 log.cpp:661] Attempting to start the writer
I1110 11:10:49.442067 28134 replica.cpp:496] Replica received implicit promise request from (10537)@172.17.6.114:57108 with proposal 1
I1110 11:10:49.442507 28134 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 413007ns
I1110 11:10:49.442534 28134 replica.cpp:345] Persisted promised to 1
I1110 11:10:49.443476 28134 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1110 11:10:49.444900 28134 replica.cpp:391] Replica received explicit promise request from (10538)@172.17.6.114:57108 for position 0 with proposal 2
I1110 11:10:49.445374 28134 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 429607ns
I1110 11:10:49.445413 28134 replica.cpp:715] Persisted action at 0
I1110 11:10:49.446442 28126 replica.cpp:540] Replica received write request for position 0 from (10539)@172.17.6.114:57108
I1110 11:10:49.446578 28126 leveldb.cpp:438] Reading position from leveldb took 51909ns
I1110 11:10:49.447062 28126 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 327192ns
I1110 11:10:49.447088 28126 replica.cpp:715] Persisted action at 0
I1110 11:10:49.447675 28126 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1110 11:10:49.448009 28126 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 318670ns
I1110 11:10:49.448333 28126 replica.cpp:715] Persisted action at 0
I1110 11:10:49.448362 28126 replica.cpp:700] Replica learned NOP action at position 0
I1110 11:10:49.449117 28140 log.cpp:677] Writer started with ending position 0
I1110 11:10:49.450307 28126 leveldb.cpp:438] Reading position from leveldb took 63476ns
I1110 11:10:49.451318 28137 registrar.cpp:342] Successfully fetched the registry (0B) in 16.198912ms
I1110 11:10:49.451707 28137 registrar.cpp:441] Applied 1 operations in 85126ns; attempting to update the 'registry'
I1110 11:10:49.452484 28137 log.cpp:685] Attempting to append 176 bytes to the log
I1110 11:10:49.452658 28126 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1110 11:10:49.453645 28126 replica.cpp:540] Replica received write request for position 1 from (10540)@172.17.6.114:57108
I1110 11:10:49.454140 28126 leveldb.cpp:343] Persisting action (195 bytes) to leveldb took 459177ns
I1110 11:10:49.454169 28126 replica.cpp:715] Persisted action at 1
I1110 11:10:49.455129 28135 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1110 11:10:49.455591 28135 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 294118ns
I1110 11:10:49.455617 28135 replica.cpp:715] Persisted action at 1
I1110 11:10:49.455644 28135 replica.cpp:700] Replica learned APPEND action at position 1
I1110 11:10:49.456770 28135 registrar.cpp:486] Successfully updated the 'registry' in 4.987904ms
I1110 11:10:49.457146 28135 registrar.cpp:372] Successfully recovered registrar
I1110 11:10:49.457217 28141 log.cpp:704] Attempting to truncate the log to 1
I1110 11:10:49.457756 28137 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1110 11:10:49.458271 28126 master.cpp:1416] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1110 11:10:49.458977 28126 replica.cpp:540] Replica received write request for position 2 from (10541)@172.17.6.114:57108
I1110 11:10:49.459465 28126 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 448835ns
I1110 11:10:49.459628 28126 replica.cpp:715] Persisted action at 2
I1110 11:10:49.460415 28126 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1110 11:10:49.460785 28126 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 353766ns
I1110 11:10:49.460846 28126 leveldb.cpp:401] Deleting ~1 keys from leveldb took 49439ns
I1110 11:10:49.460980 28126 replica.cpp:715] Persisted action at 2
I1110 11:10:49.461016 28126 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1110 11:10:49.469491 28108 scheduler.cpp:156] Version: 0.26.0
I1110 11:10:49.470408 28134 scheduler.cpp:238] New master detected at master@172.17.6.114:57108
I1110 11:10:49.471585 28138 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.6.114:57108
I1110 11:10:49.473649 28133 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1110 11:10:49.474123 28133 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.114:53022
I1110 11:10:49.474762 28133 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1110 11:10:49.474798 28133 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1110 11:10:49.475257 28132 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1110 11:10:49.476171 28136 master.hpp:1439] Sending heartbeat to 6eb0347f-3930-41b9-b099-844f7df9a562-0000
I1110 11:10:49.476187 28132 hierarchical.cpp:185] Added framework 6eb0347f-3930-41b9-b099-844f7df9a562-0000
I1110 11:10:49.476248 28132 hierarchical.cpp:971] No resources available to allocate!
I1110 11:10:49.476289 28132 hierarchical.cpp:1064] No inverse offers to send out!
I1110 11:10:49.476317 28132 hierarchical.cpp:870] Performed allocation for 0 slaves in 117539ns
I1110 11:10:49.478117 28136 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.6.114:57108
I1110 11:10:49.479027 28136 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.6.114:57108
I1110 11:10:49.479604 28136 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1110 11:10:49.480417 28139 scheduler.cpp:300] Sending REQUEST call to master@172.17.6.114:57108
I1110 11:10:49.482357 28126 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1110 11:10:49.482578 28126 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.114:53023
I1110 11:10:49.482913 28126 master.cpp:2615] Processing REQUEST call for framework 6eb0347f-3930-41b9-b099-844f7df9a562-0000 (default)
I1110 11:10:49.483608 28126 hierarchical.cpp:449] Received resource request from framework 6eb0347f-3930-41b9-b099-844f7df9a562-0000
I1110 11:10:49.484073 28140 master.cpp:922] Master terminating
I1110 11:10:49.484474 28139 hierarchical.cpp:220] Removed framework 6eb0347f-3930-41b9-b099-844f7df9a562-0000
E1110 11:10:49.487090 28139 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
2015-11-10 11:10:49,488:28108(0x2b5cf3804700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:59641] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
[ OK ] ContentType/SchedulerTest.Request/1 (73 ms)
[----------] 22 tests from ContentType/SchedulerTest (4903 ms total)
[----------] Global test environment tear-down
[==========] 827 tests from 112 test cases ran. (343214 ms total)
[ PASSED ] 826 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveRecoveryTest/0.KillTask, where TypeParam = mesos::internal::slave::MesosContainerizer
1 FAILED TEST
YOU HAVE 7 DISABLED TESTS
make[4]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[4]: *** [check-local] Error 1
make[3]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[1]: Leaving directory `/mesos/mesos-0.26.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1447151984-19928
Untagged: mesos-1447151984-19928:latest
Deleted: 330fa60ff8c7297ea764f39effabd4039065f767fc5ff6852f08f0de3c261683
Deleted: 4c118ce617747344611d7e834f83a2f552ddcc6f66775c143964ad26f2dba702
Deleted: e096141e7e2564231c2fdb45c95daa8fb66c6a60ba97fdd4ff168aa57d0a64ed
Deleted: 3c4946b6c3f93558b39c02ba78d59eb42b9ecb206b0d16d11e069871d10bb203
Deleted: 39709b655dac3b52f5dcc73aba4227dc8db821c8e868116fab2c915c1f96d360
Deleted: d21d4fba8b061197c24b6905253457e227bcfce2cd20ed875bb6b8df9b0de385
Deleted: c19c5b031f5b3fccb190983b32f10a44c75641d00418547723806fd40c952707
Deleted: 37f08466cd879dca6c14bc47da4f01fd20c9cb3967a33811910668c7dcfb6e19
Deleted: 23d410eab50d8486b480a1cb5569ddc04cbd496f4e51457ec99eb94fe0289e15
Deleted: 41c06f40d23262003049606e2127e76dba9a1dbd596e257344a87121ba42926c
Deleted: 8d02b297a6d93c4582f07e0226736f88ae69be6325d106c118a18521a6d21e68
Deleted: cdc83f46cb6ae14b2267765b22846df4546e6a240e99d4c63745dd9bc535a2bb
Deleted: 6f397e876af43aa4c8a21867a1eea3e86f59a7efa1b655ca7b1bd2decd1146c9
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1211
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1211/changes>
Build failed in Jenkins: Mesos » clang,--verbose,ubuntu:14.04,docker||Hadoop #1210
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,OS=ubuntu%3A14.04,label_exp=docker%7C%7CHadoop/1210/>
------------------------------------------
[...truncated 140734 lines...]
I1110 11:41:49.041090 28125 slave.cpp:2034] Shutting down framework 8a9302a0-f0aa-45fd-b428-271e3152d32c-0000
I1110 11:41:49.041154 28125 slave.cpp:3657] Cleaning up executor 'default' of framework 8a9302a0-f0aa-45fd-b428-271e3152d32c-0000 at executor(132)@172.17.6.121:37832
I1110 11:41:49.041669 28124 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_y77HBh/slaves/8a9302a0-f0aa-45fd-b428-271e3152d32c-S0/frameworks/8a9302a0-f0aa-45fd-b428-271e3152d32c-0000/executors/default/runs/0c007a05-e10f-41e5-8331-09b3f2ec4276' for gc 6.99999951996444days in the future
I1110 11:41:49.041705 28125 slave.cpp:3745] Cleaning up framework 8a9302a0-f0aa-45fd-b428-271e3152d32c-0000
I1110 11:41:49.041900 28124 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_y77HBh/slaves/8a9302a0-f0aa-45fd-b428-271e3152d32c-S0/frameworks/8a9302a0-f0aa-45fd-b428-271e3152d32c-0000/executors/default' for gc 6.99999951791704days in the future
I1110 11:41:49.042413 28119 status_update_manager.cpp:284] Closing status update streams for framework 8a9302a0-f0aa-45fd-b428-271e3152d32c-0000
I1110 11:41:49.042479 28119 status_update_manager.cpp:530] Cleaning up status update stream for task 01669b49-d54e-4e4d-864b-4b418bd988ef of framework 8a9302a0-f0aa-45fd-b428-271e3152d32c-0000
I1110 11:41:49.042906 28123 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_y77HBh/slaves/8a9302a0-f0aa-45fd-b428-271e3152d32c-S0/frameworks/8a9302a0-f0aa-45fd-b428-271e3152d32c-0000' for gc 6.99999950622815days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (151 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1110 11:41:49.054337 28101 leveldb.cpp:176] Opened db in 3.683465ms
I1110 11:41:49.056949 28101 leveldb.cpp:183] Compacted db in 2.571056ms
I1110 11:41:49.057018 28101 leveldb.cpp:198] Created db iterator in 34358ns
I1110 11:41:49.057041 28101 leveldb.cpp:204] Seeked to beginning of db in 10048ns
I1110 11:41:49.057054 28101 leveldb.cpp:273] Iterated through 0 keys in the db in 7333ns
I1110 11:41:49.057107 28101 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1110 11:41:49.057781 28126 recover.cpp:449] Starting replica recovery
I1110 11:41:49.058069 28126 recover.cpp:475] Replica is in EMPTY status
I1110 11:41:49.059139 28126 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10535)@172.17.6.121:37832
I1110 11:41:49.059558 28131 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1110 11:41:49.060215 28125 recover.cpp:566] Updating replica status to STARTING
I1110 11:41:49.061317 28119 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 577558ns
I1110 11:41:49.061349 28119 replica.cpp:323] Persisted replica status to STARTING
I1110 11:41:49.061527 28127 recover.cpp:475] Replica is in STARTING status
I1110 11:41:49.062693 28134 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10536)@172.17.6.121:37832
I1110 11:41:49.062964 28122 recover.cpp:195] Received a recover response from a replica in STARTING status
I1110 11:41:49.063511 28122 recover.cpp:566] Updating replica status to VOTING
I1110 11:41:49.064404 28124 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 809867ns
I1110 11:41:49.064441 28124 replica.cpp:323] Persisted replica status to VOTING
I1110 11:41:49.064540 28125 recover.cpp:580] Successfully joined the Paxos group
I1110 11:41:49.064718 28126 recover.cpp:464] Recover process terminated
I1110 11:41:49.065155 28121 master.cpp:367] Master 633d2eec-3822-4255-9049-0f82de3c6334 (f5a17423f185) started on 172.17.6.121:37832
I1110 11:41:49.065207 28121 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/XdZYlZ/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/XdZYlZ/master" --zk_session_timeout="10secs"
I1110 11:41:49.065557 28121 master.cpp:416] Master allowing unauthenticated frameworks to register
I1110 11:41:49.065568 28121 master.cpp:419] Master only allowing authenticated slaves to register
I1110 11:41:49.065577 28121 credentials.hpp:37] Loading credentials for authentication from '/tmp/XdZYlZ/credentials'
I1110 11:41:49.065904 28121 master.cpp:458] Using default 'crammd5' authenticator
I1110 11:41:49.066082 28121 master.cpp:495] Authorization enabled
I1110 11:41:49.067994 28128 whitelist_watcher.cpp:79] No whitelist given
I1110 11:41:49.068219 28132 hierarchical.cpp:140] Initialized hierarchical allocator process
I1110 11:41:49.068591 28128 master.cpp:1606] The newly elected leader is master@172.17.6.121:37832 with id 633d2eec-3822-4255-9049-0f82de3c6334
I1110 11:41:49.068629 28128 master.cpp:1619] Elected as the leading master!
I1110 11:41:49.068642 28128 master.cpp:1379] Recovering from registrar
I1110 11:41:49.068907 28128 registrar.cpp:309] Recovering registrar
I1110 11:41:49.070243 28129 log.cpp:661] Attempting to start the writer
I1110 11:41:49.071674 28134 replica.cpp:496] Replica received implicit promise request from (10537)@172.17.6.121:37832 with proposal 1
I1110 11:41:49.072906 28134 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 1.168117ms
I1110 11:41:49.072959 28134 replica.cpp:345] Persisted promised to 1
I1110 11:41:49.073618 28126 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1110 11:41:49.075206 28133 replica.cpp:391] Replica received explicit promise request from (10538)@172.17.6.121:37832 for position 0 with proposal 2
I1110 11:41:49.075692 28133 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 431613ns
I1110 11:41:49.075726 28133 replica.cpp:715] Persisted action at 0
I1110 11:41:49.077083 28122 replica.cpp:540] Replica received write request for position 0 from (10539)@172.17.6.121:37832
I1110 11:41:49.077190 28122 leveldb.cpp:438] Reading position from leveldb took 65123ns
I1110 11:41:49.077595 28122 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 361627ns
I1110 11:41:49.077622 28122 replica.cpp:715] Persisted action at 0
I1110 11:41:49.079267 28120 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1110 11:41:49.079958 28120 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 674128ns
I1110 11:41:49.079987 28120 replica.cpp:715] Persisted action at 0
I1110 11:41:49.080008 28120 replica.cpp:700] Replica learned NOP action at position 0
I1110 11:41:49.082329 28126 log.cpp:677] Writer started with ending position 0
I1110 11:41:49.083901 28123 leveldb.cpp:438] Reading position from leveldb took 85728ns
I1110 11:41:49.085217 28131 registrar.cpp:342] Successfully fetched the registry (0B) in 16128us
I1110 11:41:49.085371 28131 registrar.cpp:441] Applied 1 operations in 50995ns; attempting to update the 'registry'
I1110 11:41:49.086328 28128 log.cpp:685] Attempting to append 176 bytes to the log
I1110 11:41:49.086458 28134 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1110 11:41:49.087239 28119 replica.cpp:540] Replica received write request for position 1 from (10540)@172.17.6.121:37832
I1110 11:41:49.087772 28119 leveldb.cpp:343] Persisting action (195 bytes) to leveldb took 484299ns
I1110 11:41:49.087802 28119 replica.cpp:715] Persisted action at 1
I1110 11:41:49.088655 28127 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1110 11:41:49.089076 28127 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 395416ns
I1110 11:41:49.089107 28127 replica.cpp:715] Persisted action at 1
I1110 11:41:49.089134 28127 replica.cpp:700] Replica learned APPEND action at position 1
I1110 11:41:49.093060 28125 registrar.cpp:486] Successfully updated the 'registry' in 7.607808ms
I1110 11:41:49.093245 28125 registrar.cpp:372] Successfully recovered registrar
I1110 11:41:49.093466 28127 log.cpp:704] Attempting to truncate the log to 1
I1110 11:41:49.093639 28125 master.cpp:1416] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1110 11:41:49.093755 28127 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1110 11:41:49.097689 28130 replica.cpp:540] Replica received write request for position 2 from (10541)@172.17.6.121:37832
I1110 11:41:49.098345 28130 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 535806ns
I1110 11:41:49.098376 28130 replica.cpp:715] Persisted action at 2
I1110 11:41:49.099741 28130 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1110 11:41:49.100288 28130 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 462020ns
I1110 11:41:49.100350 28130 leveldb.cpp:401] Deleting ~1 keys from leveldb took 46858ns
I1110 11:41:49.100370 28130 replica.cpp:715] Persisted action at 2
I1110 11:41:49.100394 28130 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1110 11:41:49.104898 28101 scheduler.cpp:156] Version: 0.26.0
I1110 11:41:49.106380 28131 scheduler.cpp:238] New master detected at master@172.17.6.121:37832
I1110 11:41:49.108170 28132 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.6.121:37832
I1110 11:41:49.110203 28133 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1110 11:41:49.110386 28133 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.121:36638
I1110 11:41:49.110628 28133 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1110 11:41:49.110664 28133 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1110 11:41:49.111193 28121 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1110 11:41:49.111578 28133 hierarchical.cpp:185] Added framework 633d2eec-3822-4255-9049-0f82de3c6334-0000
I1110 11:41:49.111640 28133 hierarchical.cpp:971] No resources available to allocate!
I1110 11:41:49.111683 28133 hierarchical.cpp:1064] No inverse offers to send out!
I1110 11:41:49.111716 28133 hierarchical.cpp:870] Performed allocation for 0 slaves in 125486ns
I1110 11:41:49.111740 28121 master.hpp:1439] Sending heartbeat to 633d2eec-3822-4255-9049-0f82de3c6334-0000
I1110 11:41:49.113788 28121 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.6.121:37832
I1110 11:41:49.114220 28121 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.6.121:37832
I1110 11:41:49.114852 28121 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1110 11:41:49.115569 28123 scheduler.cpp:300] Sending REQUEST call to master@172.17.6.121:37832
I1110 11:41:49.118011 28121 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1110 11:41:49.118229 28121 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.121:36639
I1110 11:41:49.118396 28121 master.cpp:2615] Processing REQUEST call for framework 633d2eec-3822-4255-9049-0f82de3c6334-0000 (default)
I1110 11:41:49.118657 28121 hierarchical.cpp:449] Received resource request from framework 633d2eec-3822-4255-9049-0f82de3c6334-0000
I1110 11:41:49.119967 28120 master.cpp:922] Master terminating
I1110 11:41:49.120702 28130 hierarchical.cpp:220] Removed framework 633d2eec-3822-4255-9049-0f82de3c6334-0000
E1110 11:41:49.129988 28119 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (94 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1110 11:41:49.147680 28101 leveldb.cpp:176] Opened db in 2.912441ms
I1110 11:41:49.148845 28101 leveldb.cpp:183] Compacted db in 1.112552ms
I1110 11:41:49.148908 28101 leveldb.cpp:198] Created db iterator in 34810ns
I1110 11:41:49.148941 28101 leveldb.cpp:204] Seeked to beginning of db in 11868ns
I1110 11:41:49.148957 28101 leveldb.cpp:273] Iterated through 0 keys in the db in 6813ns
I1110 11:41:49.149010 28101 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1110 11:41:49.152236 28122 recover.cpp:449] Starting replica recovery
I1110 11:41:49.152992 28129 master.cpp:367] Master 8b8c0a36-78e4-43fa-a0ff-3491da32f4c7 (f5a17423f185) started on 172.17.6.121:37832
I1110 11:41:49.153048 28129 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/227Uje/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/227Uje/master" --zk_session_timeout="10secs"
I1110 11:41:49.153398 28129 master.cpp:416] Master allowing unauthenticated frameworks to register
I1110 11:41:49.153410 28129 master.cpp:419] Master only allowing authenticated slaves to register
I1110 11:41:49.153419 28129 credentials.hpp:37] Loading credentials for authentication from '/tmp/227Uje/credentials'
I1110 11:41:49.153707 28129 master.cpp:458] Using default 'crammd5' authenticator
I1110 11:41:49.153878 28129 master.cpp:495] Authorization enabled
I1110 11:41:49.153992 28120 recover.cpp:475] Replica is in EMPTY status
I1110 11:41:49.154211 28130 hierarchical.cpp:140] Initialized hierarchical allocator process
I1110 11:41:49.154979 28126 whitelist_watcher.cpp:79] No whitelist given
I1110 11:41:49.155107 28134 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10546)@172.17.6.121:37832
I1110 11:41:49.156316 28124 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1110 11:41:49.156766 28129 master.cpp:1606] The newly elected leader is master@172.17.6.121:37832 with id 8b8c0a36-78e4-43fa-a0ff-3491da32f4c7
I1110 11:41:49.156796 28129 master.cpp:1619] Elected as the leading master!
I1110 11:41:49.156810 28129 master.cpp:1379] Recovering from registrar
I1110 11:41:49.158377 28125 recover.cpp:566] Updating replica status to STARTING
I1110 11:41:49.159482 28127 registrar.cpp:309] Recovering registrar
I1110 11:41:49.160079 28125 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 1.447634ms
I1110 11:41:49.160128 28125 replica.cpp:323] Persisted replica status to STARTING
I1110 11:41:49.160353 28125 recover.cpp:475] Replica is in STARTING status
I1110 11:41:49.161978 28134 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10547)@172.17.6.121:37832
I1110 11:41:49.162529 28132 recover.cpp:195] Received a recover response from a replica in STARTING status
I1110 11:41:49.163317 28121 recover.cpp:566] Updating replica status to VOTING
I1110 11:41:49.163975 28121 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 480274ns
I1110 11:41:49.164008 28121 replica.cpp:323] Persisted replica status to VOTING
I1110 11:41:49.164131 28121 recover.cpp:580] Successfully joined the Paxos group
I1110 11:41:49.164466 28121 recover.cpp:464] Recover process terminated
I1110 11:41:49.165017 28127 log.cpp:661] Attempting to start the writer
I1110 11:41:49.166688 28119 replica.cpp:496] Replica received implicit promise request from (10548)@172.17.6.121:37832 with proposal 1
I1110 11:41:49.167976 28119 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 1.255116ms
I1110 11:41:49.168025 28119 replica.cpp:345] Persisted promised to 1
I1110 11:41:49.169128 28119 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1110 11:41:49.171005 28122 replica.cpp:391] Replica received explicit promise request from (10549)@172.17.6.121:37832 for position 0 with proposal 2
I1110 11:41:49.172446 28122 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 1.380198ms
I1110 11:41:49.172493 28122 replica.cpp:715] Persisted action at 0
I1110 11:41:49.174226 28120 replica.cpp:540] Replica received write request for position 0 from (10550)@172.17.6.121:37832
I1110 11:41:49.174324 28120 leveldb.cpp:438] Reading position from leveldb took 58314ns
I1110 11:41:49.174757 28120 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 394433ns
I1110 11:41:49.174785 28120 replica.cpp:715] Persisted action at 0
I1110 11:41:49.175426 28130 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1110 11:41:49.175961 28130 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 516041ns
I1110 11:41:49.175988 28130 replica.cpp:715] Persisted action at 0
I1110 11:41:49.176010 28130 replica.cpp:700] Replica learned NOP action at position 0
I1110 11:41:49.176899 28130 log.cpp:677] Writer started with ending position 0
I1110 11:41:49.178375 28130 leveldb.cpp:438] Reading position from leveldb took 51865ns
I1110 11:41:49.179842 28125 registrar.cpp:342] Successfully fetched the registry (0B) in 20.306944ms
I1110 11:41:49.180019 28125 registrar.cpp:441] Applied 1 operations in 51059ns; attempting to update the 'registry'
I1110 11:41:49.180774 28132 log.cpp:685] Attempting to append 176 bytes to the log
I1110 11:41:49.180871 28134 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1110 11:41:49.182095 28131 replica.cpp:540] Replica received write request for position 1 from (10551)@172.17.6.121:37832
I1110 11:41:49.182617 28131 leveldb.cpp:343] Persisting action (195 bytes) to leveldb took 492112ns
I1110 11:41:49.182648 28131 replica.cpp:715] Persisted action at 1
I1110 11:41:49.183753 28131 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1110 11:41:49.184139 28131 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 369333ns
I1110 11:41:49.184167 28131 replica.cpp:715] Persisted action at 1
I1110 11:41:49.184188 28131 replica.cpp:700] Replica learned APPEND action at position 1
I1110 11:41:49.185791 28134 registrar.cpp:486] Successfully updated the 'registry' in 5.650176ms
I1110 11:41:49.186085 28134 registrar.cpp:372] Successfully recovered registrar
I1110 11:41:49.186117 28131 log.cpp:704] Attempting to truncate the log to 1
I1110 11:41:49.186296 28131 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1110 11:41:49.186607 28134 master.cpp:1416] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1110 11:41:49.187544 28132 replica.cpp:540] Replica received write request for position 2 from (10552)@172.17.6.121:37832
I1110 11:41:49.188097 28132 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 522843ns
I1110 11:41:49.188125 28132 replica.cpp:715] Persisted action at 2
I1110 11:41:49.189084 28133 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1110 11:41:49.190158 28133 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 1.030072ms
I1110 11:41:49.190243 28133 leveldb.cpp:401] Deleting ~1 keys from leveldb took 52072ns
I1110 11:41:49.190268 28133 replica.cpp:715] Persisted action at 2
I1110 11:41:49.190299 28133 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1110 11:41:49.198163 28101 scheduler.cpp:156] Version: 0.26.0
I1110 11:41:49.199472 28125 scheduler.cpp:238] New master detected at master@172.17.6.121:37832
I1110 11:41:49.200744 28120 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.6.121:37832
I1110 11:41:49.204002 28126 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1110 11:41:49.204224 28126 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.121:36640
I1110 11:41:49.204674 28126 master.cpp:1868] Received subscription request for HTTP framework 'default'
I1110 11:41:49.204706 28126 master.cpp:1645] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1110 11:41:49.205023 28126 master.cpp:1960] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1110 11:41:49.205411 28124 hierarchical.cpp:185] Added framework 8b8c0a36-78e4-43fa-a0ff-3491da32f4c7-0000
I1110 11:41:49.205474 28124 hierarchical.cpp:971] No resources available to allocate!
I1110 11:41:49.205514 28124 hierarchical.cpp:1064] No inverse offers to send out!
I1110 11:41:49.205546 28124 hierarchical.cpp:870] Performed allocation for 0 slaves in 120385ns
I1110 11:41:49.205595 28130 master.hpp:1439] Sending heartbeat to 8b8c0a36-78e4-43fa-a0ff-3491da32f4c7-0000
I1110 11:41:49.206444 28122 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.6.121:37832
I1110 11:41:49.206832 28122 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.6.121:37832
I1110 11:41:49.208055 28134 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1110 11:41:49.208190 28123 scheduler.cpp:300] Sending REQUEST call to master@172.17.6.121:37832
I1110 11:41:49.210788 28125 process.cpp:3075] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1110 11:41:49.211024 28125 http.cpp:334] HTTP POST for /master/api/v1/scheduler from 172.17.6.121:36641
I1110 11:41:49.211290 28125 master.cpp:2615] Processing REQUEST call for framework 8b8c0a36-78e4-43fa-a0ff-3491da32f4c7-0000 (default)
I1110 11:41:49.211833 28125 hierarchical.cpp:449] Received resource request from framework 8b8c0a36-78e4-43fa-a0ff-3491da32f4c7-0000
I1110 11:41:49.212450 28101 master.cpp:922] Master terminating
I1110 11:41:49.214035 28125 hierarchical.cpp:220] Removed framework 8b8c0a36-78e4-43fa-a0ff-3491da32f4c7-0000
E1110 11:41:49.214942 28127 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (77 ms)
[----------] 22 tests from ContentType/SchedulerTest (4928 ms total)
[----------] Global test environment tear-down
[==========] 827 tests from 112 test cases ran. (342084 ms total)
[ PASSED ] 826 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] FetcherCacheTest.FallbackFromEviction
1 FAILED TEST
YOU HAVE 7 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[3]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[2]: *** [check] Error 2
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.26.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1447153871-5706
Untagged: mesos-1447153871-5706:latest
Deleted: 559a341861497436ce183e7db18493ac6d55edd850d1c04737fcfbc663784594
Deleted: eb057b2e5f74997c032a952d710ddab070bd1faebb690f2085f6d5bb2557cff9
Deleted: 100c5df4df1f5ab70ab500b7cf4f37f1c6300a045d109efa4c7ccd9a83691031
Deleted: 7bef5f8c768b54cc595da6a7d997f6adb9f9e632a9c816ef51907732bf9cc70d
Deleted: a01127dd7f51352a86d700012ce35a125fadef40bc75d448b24818bcc0df56c1
Deleted: bdb2eb133341996f883b844a8bb184adc4175ac178a8736d0937c9a084c37d31
Deleted: 667656b73d0440fd04b5ffd4fa499fbc83289890b608d59d045da54df465d113
Deleted: 531721f540fbe8521551fd48068c5889f26fb7034ba0d5e21b31be63bff6ada7
Deleted: ee2bf8ae2b681fa8216dff5e79d6c49108487dbdb2f3aa45f2946c4bdddd314f
Deleted: 9d04a809880719acec2a8c1a3a0f64f107cdbef46e4487b378da2c8acda82ae2
Deleted: d38b7da19ed9b83fa806cb6f1de035159a2a9f40ebf58fefd0b118c3ebe70a7c
Deleted: c309f9c8ba16bbfac4da4403e1c87f0c4339a1fbfa409f105adeea7332c2f674
Deleted: f13ffb4b8f22d28fbdc1368a6a2b78f933eb542d99868f8b39211f7eaacd9d24
Build step 'Execute shell' marked build as failure