See <https://builds.apache.org/job/Mesos/COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1937/changes>
Changes: [tnachen] Fxied minor cleanups to command executor. ------------------------------------------ [...truncated 177232 lines...] I0405 17:15:48.298254 28758 replica.cpp:712] Persisted action at 2 I0405 17:15:48.298980 28757 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0 I0405 17:15:48.299304 28757 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 301653ns I0405 17:15:48.299352 28757 leveldb.cpp:399] Deleting ~1 keys from leveldb took 35054ns I0405 17:15:48.299374 28757 replica.cpp:712] Persisted action at 2 I0405 17:15:48.299397 28757 replica.cpp:697] Replica learned TRUNCATE action at position 2 I0405 17:15:48.308316 28726 scheduler.cpp:172] Version: 0.29.0 I0405 17:15:48.309954 28751 scheduler.cpp:466] New master detected at [email protected]:53645 I0405 17:15:48.313500 28744 scheduler.cpp:355] Connected with the master at http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.317170 28753 scheduler.cpp:231] Sending SUBSCRIBE call to http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.318894 28753 process.cpp:3165] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0405 17:15:48.319479 28753 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:38391 I0405 17:15:48.320045 28753 master.cpp:2088] Received subscription request for HTTP framework 'default' I0405 17:15:48.320073 28753 master.cpp:1865] Authorizing framework principal 'test-principal' to receive offers for role '*' I0405 17:15:48.320729 28753 master.cpp:2179] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0405 17:15:48.321485 28752 hierarchical.cpp:266] Added framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 I0405 17:15:48.321599 28752 hierarchical.cpp:1490] No resources available to allocate! I0405 17:15:48.321642 28752 hierarchical.cpp:1585] No inverse offers to send out! I0405 17:15:48.321642 28746 master.hpp:1719] Sending heartbeat to 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 I0405 17:15:48.321715 28752 hierarchical.cpp:1141] Performed allocation for 0 agents in 212783ns I0405 17:15:48.322998 28759 scheduler.cpp:657] Enqueuing event SUBSCRIBED received from http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.323464 28759 scheduler.cpp:657] Enqueuing event HEARTBEAT received from http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.324918 28755 scheduler.cpp:231] Sending REQUEST call to http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.326136 28744 process.cpp:3165] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0405 17:15:48.326684 28744 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:38392 I0405 17:15:48.327028 28744 master.cpp:2785] Processing REQUEST call for framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 (default) I0405 17:15:48.327281 28744 hierarchical.cpp:592] Received resource request from framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 I0405 17:15:48.329917 28750 master.cpp:1269] Framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 (default) disconnected I0405 17:15:48.329955 28750 master.cpp:2642] Disconnecting framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 (default) I0405 17:15:48.329982 28750 master.cpp:2666] Deactivating framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 (default) I0405 17:15:48.330095 28750 master.cpp:1293] Giving framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 (default) 0ns to failover I0405 17:15:48.330301 28746 hierarchical.cpp:378] Deactivated framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 I0405 17:15:48.331545 28749 master.cpp:5360] Framework failover timeout, removing framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 (default) I0405 17:15:48.331576 28749 master.cpp:6093] Removing framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 (default) I0405 17:15:48.331945 28749 hierarchical.cpp:329] Removed framework 7db1e63b-2583-42f2-996d-01caa41d2ad5-0000 I0405 17:15:48.339758 28726 master.cpp:1083] Master terminating [ OK ] ContentType/SchedulerTest.Request/1 (79 ms) [ RUN ] ContentType/SchedulerTest.SchedulerReconnect/0 I0405 17:15:48.348482 28726 cluster.cpp:139] Creating default 'local' authorizer I0405 17:15:48.351285 28726 leveldb.cpp:174] Opened db in 2.582854ms I0405 17:15:48.353890 28726 leveldb.cpp:181] Compacted db in 2.576363ms I0405 17:15:48.353965 28726 leveldb.cpp:196] Created db iterator in 38209ns I0405 17:15:48.353983 28726 leveldb.cpp:202] Seeked to beginning of db in 9473ns I0405 17:15:48.353996 28726 leveldb.cpp:271] Iterated through 0 keys in the db in 6982ns I0405 17:15:48.354045 28726 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0405 17:15:48.354712 28753 recover.cpp:447] Starting replica recovery I0405 17:15:48.355057 28753 recover.cpp:473] Replica is in EMPTY status I0405 17:15:48.356070 28756 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16562)@172.17.0.3:53645 I0405 17:15:48.356348 28758 recover.cpp:193] Received a recover response from a replica in EMPTY status I0405 17:15:48.356729 28756 recover.cpp:564] Updating replica status to STARTING I0405 17:15:48.357430 28754 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 651887ns I0405 17:15:48.357460 28754 replica.cpp:320] Persisted replica status to STARTING I0405 17:15:48.357650 28754 recover.cpp:473] Replica is in STARTING status I0405 17:15:48.357939 28745 master.cpp:376] Master 8cb50ec9-c3e7-425c-b323-db605d11849c (89ccc70cbdb7) started on 172.17.0.3:53645 I0405 17:15:48.357975 28745 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/SsYO7m/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.29.0/_inst/share/mesos/webui" --work_dir="/tmp/SsYO7m/master" --zk_session_timeout="10secs" I0405 17:15:48.358371 28745 master.cpp:429] Master allowing unauthenticated frameworks to register I0405 17:15:48.358386 28745 master.cpp:432] Master only allowing authenticated agents to register I0405 17:15:48.358398 28745 credentials.hpp:37] Loading credentials for authentication from '/tmp/SsYO7m/credentials' I0405 17:15:48.358615 28758 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16563)@172.17.0.3:53645 I0405 17:15:48.358759 28745 master.cpp:474] Using default 'crammd5' authenticator I0405 17:15:48.358896 28749 recover.cpp:193] Received a recover response from a replica in STARTING status I0405 17:15:48.359247 28745 master.cpp:545] Using default 'basic' HTTP authenticator I0405 17:15:48.359432 28745 master.cpp:583] Authorization enabled I0405 17:15:48.359506 28756 recover.cpp:564] Updating replica status to VOTING I0405 17:15:48.359622 28744 hierarchical.cpp:144] Initialized hierarchical allocator process I0405 17:15:48.359947 28754 whitelist_watcher.cpp:77] No whitelist given I0405 17:15:48.360225 28753 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 433533ns I0405 17:15:48.360257 28753 replica.cpp:320] Persisted replica status to VOTING I0405 17:15:48.360376 28748 recover.cpp:578] Successfully joined the Paxos group I0405 17:15:48.360690 28748 recover.cpp:462] Recover process terminated I0405 17:15:48.362715 28745 master.cpp:1826] The newly elected leader is [email protected]:53645 with id 8cb50ec9-c3e7-425c-b323-db605d11849c I0405 17:15:48.362766 28745 master.cpp:1839] Elected as the leading master! I0405 17:15:48.362787 28745 master.cpp:1526] Recovering from registrar I0405 17:15:48.362982 28757 registrar.cpp:331] Recovering registrar I0405 17:15:48.363672 28754 log.cpp:659] Attempting to start the writer I0405 17:15:48.365152 28750 replica.cpp:493] Replica received implicit promise request from (16565)@172.17.0.3:53645 with proposal 1 I0405 17:15:48.365613 28750 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 433981ns I0405 17:15:48.365645 28750 replica.cpp:342] Persisted promised to 1 I0405 17:15:48.366267 28749 coordinator.cpp:238] Coordinator attempting to fill missing positions I0405 17:15:48.367558 28749 replica.cpp:388] Replica received explicit promise request from (16566)@172.17.0.3:53645 for position 0 with proposal 2 I0405 17:15:48.369098 28749 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 1.463732ms I0405 17:15:48.369153 28749 replica.cpp:712] Persisted action at 0 I0405 17:15:48.370574 28749 replica.cpp:537] Replica received write request for position 0 from (16567)@172.17.0.3:53645 I0405 17:15:48.370664 28749 leveldb.cpp:436] Reading position from leveldb took 61524ns I0405 17:15:48.371194 28749 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 486244ns I0405 17:15:48.371224 28749 replica.cpp:712] Persisted action at 0 I0405 17:15:48.372076 28749 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0 I0405 17:15:48.372443 28749 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 351554ns I0405 17:15:48.372544 28749 replica.cpp:712] Persisted action at 0 I0405 17:15:48.372664 28749 replica.cpp:697] Replica learned NOP action at position 0 I0405 17:15:48.373404 28750 log.cpp:675] Writer started with ending position 0 I0405 17:15:48.374722 28747 leveldb.cpp:436] Reading position from leveldb took 102568ns I0405 17:15:48.375839 28749 registrar.cpp:364] Successfully fetched the registry (0B) in 12.794112ms I0405 17:15:48.376030 28749 registrar.cpp:463] Applied 1 operations in 51945ns; attempting to update the 'registry' I0405 17:15:48.377323 28752 log.cpp:683] Attempting to append 170 bytes to the log I0405 17:15:48.377449 28757 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1 I0405 17:15:48.378406 28750 replica.cpp:537] Replica received write request for position 1 from (16568)@172.17.0.3:53645 I0405 17:15:48.379009 28750 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 567370ns I0405 17:15:48.379051 28750 replica.cpp:712] Persisted action at 1 I0405 17:15:48.379868 28756 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0 I0405 17:15:48.380218 28756 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 333356ns I0405 17:15:48.380318 28756 replica.cpp:712] Persisted action at 1 I0405 17:15:48.380455 28756 replica.cpp:697] Replica learned APPEND action at position 1 I0405 17:15:48.381716 28756 registrar.cpp:508] Successfully updated the 'registry' in 5.415936ms I0405 17:15:48.381973 28756 registrar.cpp:394] Successfully recovered registrar I0405 17:15:48.382418 28750 log.cpp:702] Attempting to truncate the log to 1 I0405 17:15:48.382796 28745 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover I0405 17:15:48.382572 28756 master.cpp:1634] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register I0405 17:15:48.383015 28745 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2 I0405 17:15:48.384066 28751 replica.cpp:537] Replica received write request for position 2 from (16569)@172.17.0.3:53645 I0405 17:15:48.384557 28751 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 456264ns I0405 17:15:48.384587 28751 replica.cpp:712] Persisted action at 2 I0405 17:15:48.385391 28746 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0 I0405 17:15:48.386060 28746 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 359731ns I0405 17:15:48.386123 28746 leveldb.cpp:399] Deleting ~1 keys from leveldb took 50325ns I0405 17:15:48.386152 28746 replica.cpp:712] Persisted action at 2 I0405 17:15:48.386183 28746 replica.cpp:697] Replica learned TRUNCATE action at position 2 I0405 17:15:48.395017 28726 scheduler.cpp:172] Version: 0.29.0 I0405 17:15:48.395544 28753 scheduler.cpp:466] New master detected at [email protected]:53645 I0405 17:15:48.398026 28751 scheduler.cpp:355] Connected with the master at http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.400408 28755 scheduler.cpp:437] Re-detecting master I0405 17:15:48.401870 28755 scheduler.cpp:388] Ignoring disconnection attempt from stale connection I0405 17:15:48.401918 28755 scheduler.cpp:388] Ignoring disconnection attempt from stale connection I0405 17:15:48.402946 28755 scheduler.cpp:466] New master detected at [email protected]:53645 I0405 17:15:48.404701 28754 scheduler.cpp:355] Connected with the master at http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.406466 28744 scheduler.cpp:441] Lost leading master I0405 17:15:48.406757 28744 scheduler.cpp:388] Ignoring disconnection attempt from stale connection I0405 17:15:48.406934 28744 scheduler.cpp:388] Ignoring disconnection attempt from stale connection I0405 17:15:48.407603 28744 scheduler.cpp:275] Ignoring reconnect request from scheduler since we are disconnected I0405 17:15:48.428740 28726 master.cpp:1083] Master terminating [ OK ] ContentType/SchedulerTest.SchedulerReconnect/0 (88 ms) [ RUN ] ContentType/SchedulerTest.SchedulerReconnect/1 I0405 17:15:48.436516 28726 cluster.cpp:139] Creating default 'local' authorizer I0405 17:15:48.439406 28726 leveldb.cpp:174] Opened db in 2.68283ms I0405 17:15:48.440418 28726 leveldb.cpp:181] Compacted db in 880254ns I0405 17:15:48.440472 28726 leveldb.cpp:196] Created db iterator in 31611ns I0405 17:15:48.440490 28726 leveldb.cpp:202] Seeked to beginning of db in 8288ns I0405 17:15:48.440501 28726 leveldb.cpp:271] Iterated through 0 keys in the db in 7065ns I0405 17:15:48.440551 28726 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned I0405 17:15:48.444113 28755 recover.cpp:447] Starting replica recovery I0405 17:15:48.444497 28759 recover.cpp:473] Replica is in EMPTY status I0405 17:15:48.446532 28749 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16573)@172.17.0.3:53645 I0405 17:15:48.446653 28748 master.cpp:376] Master 851eaa69-8457-44b9-a3a1-2e94b409c560 (89ccc70cbdb7) started on 172.17.0.3:53645 I0405 17:15:48.446940 28750 recover.cpp:193] Received a recover response from a replica in EMPTY status I0405 17:15:48.446841 28748 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/QOo1os/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.29.0/_inst/share/mesos/webui" --work_dir="/tmp/QOo1os/master" --zk_session_timeout="10secs" I0405 17:15:48.447803 28748 master.cpp:429] Master allowing unauthenticated frameworks to register I0405 17:15:48.447929 28748 master.cpp:432] Master only allowing authenticated agents to register I0405 17:15:48.448040 28748 credentials.hpp:37] Loading credentials for authentication from '/tmp/QOo1os/credentials' I0405 17:15:48.448808 28748 master.cpp:474] Using default 'crammd5' authenticator I0405 17:15:48.449065 28748 master.cpp:545] Using default 'basic' HTTP authenticator I0405 17:15:48.449302 28748 master.cpp:583] Authorization enabled I0405 17:15:48.447734 28744 recover.cpp:564] Updating replica status to STARTING I0405 17:15:48.450191 28747 hierarchical.cpp:144] Initialized hierarchical allocator process I0405 17:15:48.450276 28747 whitelist_watcher.cpp:77] No whitelist given I0405 17:15:48.450863 28758 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 998058ns I0405 17:15:48.450898 28758 replica.cpp:320] Persisted replica status to STARTING I0405 17:15:48.451253 28756 recover.cpp:473] Replica is in STARTING status I0405 17:15:48.452749 28751 master.cpp:1826] The newly elected leader is [email protected]:53645 with id 851eaa69-8457-44b9-a3a1-2e94b409c560 I0405 17:15:48.452811 28751 master.cpp:1839] Elected as the leading master! I0405 17:15:48.452841 28751 master.cpp:1526] Recovering from registrar I0405 17:15:48.452920 28757 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16575)@172.17.0.3:53645 I0405 17:15:48.452967 28755 registrar.cpp:331] Recovering registrar I0405 17:15:48.453902 28744 recover.cpp:193] Received a recover response from a replica in STARTING status I0405 17:15:48.454509 28752 recover.cpp:564] Updating replica status to VOTING I0405 17:15:48.455101 28746 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 463214ns I0405 17:15:48.455130 28746 replica.cpp:320] Persisted replica status to VOTING I0405 17:15:48.455255 28754 recover.cpp:578] Successfully joined the Paxos group I0405 17:15:48.455500 28754 recover.cpp:462] Recover process terminated I0405 17:15:48.456049 28754 log.cpp:659] Attempting to start the writer I0405 17:15:48.457250 28748 replica.cpp:493] Replica received implicit promise request from (16576)@172.17.0.3:53645 with proposal 1 I0405 17:15:48.457633 28748 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 367260ns I0405 17:15:48.457670 28748 replica.cpp:342] Persisted promised to 1 I0405 17:15:48.458474 28749 coordinator.cpp:238] Coordinator attempting to fill missing positions I0405 17:15:48.459717 28759 replica.cpp:388] Replica received explicit promise request from (16577)@172.17.0.3:53645 for position 0 with proposal 2 I0405 17:15:48.460181 28759 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 432178ns I0405 17:15:48.460211 28759 replica.cpp:712] Persisted action at 0 I0405 17:15:48.461484 28748 replica.cpp:537] Replica received write request for position 0 from (16578)@172.17.0.3:53645 I0405 17:15:48.461565 28748 leveldb.cpp:436] Reading position from leveldb took 48042ns I0405 17:15:48.461937 28748 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 336444ns I0405 17:15:48.461966 28748 replica.cpp:712] Persisted action at 0 I0405 17:15:48.462473 28752 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0 I0405 17:15:48.462926 28752 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 433130ns I0405 17:15:48.462954 28752 replica.cpp:712] Persisted action at 0 I0405 17:15:48.462980 28752 replica.cpp:697] Replica learned NOP action at position 0 I0405 17:15:48.463804 28755 log.cpp:675] Writer started with ending position 0 I0405 17:15:48.464975 28747 leveldb.cpp:436] Reading position from leveldb took 55738ns I0405 17:15:48.466035 28756 registrar.cpp:364] Successfully fetched the registry (0B) in 13.011968ms I0405 17:15:48.466202 28756 registrar.cpp:463] Applied 1 operations in 61797ns; attempting to update the 'registry' I0405 17:15:48.467432 28756 log.cpp:683] Attempting to append 170 bytes to the log I0405 17:15:48.467669 28754 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1 I0405 17:15:48.468916 28750 replica.cpp:537] Replica received write request for position 1 from (16579)@172.17.0.3:53645 I0405 17:15:48.469471 28750 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 519323ns I0405 17:15:48.469502 28750 replica.cpp:712] Persisted action at 1 I0405 17:15:48.470304 28747 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0 I0405 17:15:48.470700 28747 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 382292ns I0405 17:15:48.470736 28747 replica.cpp:712] Persisted action at 1 I0405 17:15:48.470764 28747 replica.cpp:697] Replica learned APPEND action at position 1 I0405 17:15:48.471889 28754 registrar.cpp:508] Successfully updated the 'registry' in 5.607936ms I0405 17:15:48.472046 28754 registrar.cpp:394] Successfully recovered registrar I0405 17:15:48.472173 28745 log.cpp:702] Attempting to truncate the log to 1 I0405 17:15:48.473158 28745 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2 I0405 17:15:48.474202 28750 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover I0405 17:15:48.474225 28748 master.cpp:1634] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register I0405 17:15:48.474305 28759 replica.cpp:537] Replica received write request for position 2 from (16580)@172.17.0.3:53645 I0405 17:15:48.474889 28759 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 561949ns I0405 17:15:48.474920 28759 replica.cpp:712] Persisted action at 2 I0405 17:15:48.475914 28751 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0 I0405 17:15:48.476258 28751 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 323707ns I0405 17:15:48.476320 28751 leveldb.cpp:399] Deleting ~1 keys from leveldb took 49440ns I0405 17:15:48.476351 28751 replica.cpp:712] Persisted action at 2 I0405 17:15:48.476382 28751 replica.cpp:697] Replica learned TRUNCATE action at position 2 I0405 17:15:48.486035 28726 scheduler.cpp:172] Version: 0.29.0 I0405 17:15:48.488183 28747 scheduler.cpp:466] New master detected at [email protected]:53645 I0405 17:15:48.491271 28758 scheduler.cpp:355] Connected with the master at http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.495173 28747 scheduler.cpp:437] Re-detecting master I0405 17:15:48.495429 28747 scheduler.cpp:388] Ignoring disconnection attempt from stale connection I0405 17:15:48.495473 28747 scheduler.cpp:388] Ignoring disconnection attempt from stale connection I0405 17:15:48.495676 28752 scheduler.cpp:466] New master detected at [email protected]:53645 I0405 17:15:48.499085 28752 scheduler.cpp:355] Connected with the master at http://172.17.0.3:53645/master/api/v1/scheduler I0405 17:15:48.501293 28745 scheduler.cpp:441] Lost leading master I0405 17:15:48.501512 28745 scheduler.cpp:388] Ignoring disconnection attempt from stale connection I0405 17:15:48.501549 28745 scheduler.cpp:388] Ignoring disconnection attempt from stale connection I0405 17:15:48.502943 28745 scheduler.cpp:275] Ignoring reconnect request from scheduler since we are disconnected I0405 17:15:48.525225 28726 master.cpp:1083] Master terminating [ OK ] ContentType/SchedulerTest.SchedulerReconnect/1 (108 ms) [----------] 28 tests from ContentType/SchedulerTest (5959 ms total) [----------] Global test environment tear-down [==========] 1058 tests from 132 test cases ran. (395188 ms total) [ PASSED ] 1057 tests. [ FAILED ] 1 test, listed below: [ FAILED ] HealthCheckTest.HealthStatusChange 1 FAILED TEST YOU HAVE 6 DISABLED TESTS make[4]: *** [check-local] Error 1 make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-0.29.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1459874189-13620 Error response from daemon: conflict: unable to delete 7a02b7eb13e6 (must be forced) - image is being used by stopped container 04b7e7f13cd1 Error: failed to remove images: [mesos-1459874189-13620] Build step 'Execute shell' marked build as failure
