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/04/05 19:31:31 UTC
Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1937
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,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 180416 lines...]
I0405 17:31:25.159062 31869 hierarchical.cpp:266] Added framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
I0405 17:31:25.159181 31869 hierarchical.cpp:1490] No resources available to allocate!
I0405 17:31:25.159219 31869 hierarchical.cpp:1585] No inverse offers to send out!
I0405 17:31:25.159296 31869 hierarchical.cpp:1141] Performed allocation for 0 agents in 191001ns
I0405 17:31:25.159404 31869 master.hpp:1719] Sending heartbeat to ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
I0405 17:31:25.160429 31871 scheduler.cpp:657] Enqueuing event SUBSCRIBED received from http://172.17.0.4:43972/master/api/v1/scheduler
I0405 17:31:25.162025 31870 scheduler.cpp:231] Sending REQUEST call to http://172.17.0.4:43972/master/api/v1/scheduler
I0405 17:31:25.162436 31870 scheduler.cpp:657] Enqueuing event HEARTBEAT received from http://172.17.0.4:43972/master/api/v1/scheduler
I0405 17:31:25.162942 31869 process.cpp:3165] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0405 17:31:25.163462 31866 http.cpp:313] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:37062
I0405 17:31:25.163760 31866 master.cpp:2785] Processing REQUEST call for framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
I0405 17:31:25.163913 31865 hierarchical.cpp:592] Received resource request from framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
I0405 17:31:25.166333 31856 master.cpp:1269] Framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default) disconnected
I0405 17:31:25.166366 31856 master.cpp:2642] Disconnecting framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
I0405 17:31:25.166388 31856 master.cpp:2666] Deactivating framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
I0405 17:31:25.166491 31856 master.cpp:1293] Giving framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default) 0ns to failover
I0405 17:31:25.166674 31856 hierarchical.cpp:378] Deactivated framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
I0405 17:31:25.166853 31856 master.cpp:5360] Framework failover timeout, removing framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
I0405 17:31:25.166874 31856 master.cpp:6093] Removing framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
I0405 17:31:25.167273 31859 hierarchical.cpp:329] Removed framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
I0405 17:31:25.176632 31837 master.cpp:1083] Master terminating
[ OK ] ContentType/SchedulerTest.Request/1 (653 ms)
[ RUN ] ContentType/SchedulerTest.SchedulerReconnect/0
I0405 17:31:25.186280 31837 cluster.cpp:139] Creating default 'local' authorizer
I0405 17:31:25.276929 31837 leveldb.cpp:174] Opened db in 90.295589ms
I0405 17:31:25.327116 31837 leveldb.cpp:181] Compacted db in 50.111162ms
I0405 17:31:25.327205 31837 leveldb.cpp:196] Created db iterator in 23723ns
I0405 17:31:25.327234 31837 leveldb.cpp:202] Seeked to beginning of db in 3199ns
I0405 17:31:25.327249 31837 leveldb.cpp:271] Iterated through 0 keys in the db in 285ns
I0405 17:31:25.327301 31837 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0405 17:31:25.327961 31863 recover.cpp:447] Starting replica recovery
I0405 17:31:25.328253 31863 recover.cpp:473] Replica is in EMPTY status
I0405 17:31:25.330695 31863 master.cpp:376] Master b6d48046-0782-4192-8394-aa41d565cc9d (4090d10eba90) started on 172.17.0.4:43972
I0405 17:31:25.330716 31863 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/WYHfmi/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/WYHfmi/master" --zk_session_timeout="10secs"
I0405 17:31:25.331143 31863 master.cpp:429] Master allowing unauthenticated frameworks to register
I0405 17:31:25.331156 31863 master.cpp:432] Master only allowing authenticated agents to register
I0405 17:31:25.331166 31863 credentials.hpp:37] Loading credentials for authentication from '/tmp/WYHfmi/credentials'
I0405 17:31:25.331599 31863 master.cpp:474] Using default 'crammd5' authenticator
I0405 17:31:25.331750 31863 master.cpp:545] Using default 'basic' HTTP authenticator
I0405 17:31:25.331877 31863 master.cpp:583] Authorization enabled
I0405 17:31:25.332294 31870 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16578)@172.17.0.4:43972
I0405 17:31:25.332476 31870 hierarchical.cpp:144] Initialized hierarchical allocator process
I0405 17:31:25.332540 31870 whitelist_watcher.cpp:77] No whitelist given
I0405 17:31:25.332882 31866 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0405 17:31:25.333523 31856 recover.cpp:564] Updating replica status to STARTING
I0405 17:31:25.335368 31856 master.cpp:1826] The newly elected leader is master@172.17.0.4:43972 with id b6d48046-0782-4192-8394-aa41d565cc9d
I0405 17:31:25.335402 31856 master.cpp:1839] Elected as the leading master!
I0405 17:31:25.335422 31856 master.cpp:1526] Recovering from registrar
I0405 17:31:25.335564 31869 registrar.cpp:331] Recovering registrar
I0405 17:31:25.369477 31870 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 35.558972ms
I0405 17:31:25.369582 31870 replica.cpp:320] Persisted replica status to STARTING
I0405 17:31:25.370028 31870 recover.cpp:473] Replica is in STARTING status
I0405 17:31:25.372201 31870 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16580)@172.17.0.4:43972
I0405 17:31:25.372637 31870 recover.cpp:193] Received a recover response from a replica in STARTING status
I0405 17:31:25.373510 31871 recover.cpp:564] Updating replica status to VOTING
I0405 17:31:25.402940 31861 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.239281ms
I0405 17:31:25.403045 31861 replica.cpp:320] Persisted replica status to VOTING
I0405 17:31:25.403293 31871 recover.cpp:578] Successfully joined the Paxos group
I0405 17:31:25.403545 31871 recover.cpp:462] Recover process terminated
I0405 17:31:25.404201 31871 log.cpp:659] Attempting to start the writer
I0405 17:31:25.405786 31857 replica.cpp:493] Replica received implicit promise request from (16581)@172.17.0.4:43972 with proposal 1
I0405 17:31:25.436377 31857 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.523397ms
I0405 17:31:25.436480 31857 replica.cpp:342] Persisted promised to 1
I0405 17:31:25.437525 31861 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0405 17:31:25.439023 31856 replica.cpp:388] Replica received explicit promise request from (16582)@172.17.0.4:43972 for position 0 with proposal 2
I0405 17:31:25.469802 31856 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 30.700643ms
I0405 17:31:25.469905 31856 replica.cpp:712] Persisted action at 0
I0405 17:31:25.471498 31866 replica.cpp:537] Replica received write request for position 0 from (16583)@172.17.0.4:43972
I0405 17:31:25.471596 31866 leveldb.cpp:436] Reading position from leveldb took 52027ns
I0405 17:31:25.503268 31866 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.586096ms
I0405 17:31:25.503377 31866 replica.cpp:712] Persisted action at 0
I0405 17:31:25.504559 31866 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0405 17:31:25.536703 31866 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.083286ms
I0405 17:31:25.536811 31866 replica.cpp:712] Persisted action at 0
I0405 17:31:25.536849 31866 replica.cpp:697] Replica learned NOP action at position 0
I0405 17:31:25.537925 31867 log.cpp:675] Writer started with ending position 0
I0405 17:31:25.539691 31867 leveldb.cpp:436] Reading position from leveldb took 88448ns
I0405 17:31:25.541448 31867 registrar.cpp:364] Successfully fetched the registry (0B) in 205.828096ms
I0405 17:31:25.541659 31867 registrar.cpp:463] Applied 1 operations in 51493ns; attempting to update the 'registry'
I0405 17:31:25.543038 31861 log.cpp:683] Attempting to append 170 bytes to the log
I0405 17:31:25.543267 31860 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0405 17:31:25.544409 31870 replica.cpp:537] Replica received write request for position 1 from (16584)@172.17.0.4:43972
I0405 17:31:25.578496 31870 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 34.006188ms
I0405 17:31:25.578606 31870 replica.cpp:712] Persisted action at 1
I0405 17:31:25.579566 31871 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0405 17:31:25.612403 31871 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.769506ms
I0405 17:31:25.612512 31871 replica.cpp:712] Persisted action at 1
I0405 17:31:25.612553 31871 replica.cpp:697] Replica learned APPEND action at position 1
I0405 17:31:25.614151 31865 registrar.cpp:508] Successfully updated the 'registry' in 72.396032ms
I0405 17:31:25.614435 31861 log.cpp:702] Attempting to truncate the log to 1
I0405 17:31:25.614575 31869 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0405 17:31:25.614382 31865 registrar.cpp:394] Successfully recovered registrar
I0405 17:31:25.615356 31865 master.cpp:1634] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register
I0405 17:31:25.615655 31866 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0405 17:31:25.615767 31862 replica.cpp:537] Replica received write request for position 2 from (16585)@172.17.0.4:43972
I0405 17:31:25.653707 31862 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 37.868106ms
I0405 17:31:25.653811 31862 replica.cpp:712] Persisted action at 2
I0405 17:31:25.654872 31862 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0405 17:31:25.684255 31862 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 29.315506ms
I0405 17:31:25.684437 31862 leveldb.cpp:399] Deleting ~1 keys from leveldb took 85678ns
I0405 17:31:25.684465 31862 replica.cpp:712] Persisted action at 2
I0405 17:31:25.684504 31862 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0405 17:31:25.687340 31837 scheduler.cpp:172] Version: 0.29.0
I0405 17:31:25.688011 31871 scheduler.cpp:466] New master detected at master@172.17.0.4:43972
I0405 17:31:25.690260 31868 scheduler.cpp:355] Connected with the master at http://172.17.0.4:43972/master/api/v1/scheduler
I0405 17:31:25.692463 31870 scheduler.cpp:437] Re-detecting master
I0405 17:31:25.692947 31870 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0405 17:31:25.693245 31870 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0405 17:31:25.693413 31870 scheduler.cpp:466] New master detected at master@172.17.0.4:43972
I0405 17:31:25.694856 31870 scheduler.cpp:355] Connected with the master at http://172.17.0.4:43972/master/api/v1/scheduler
I0405 17:31:25.697296 31867 scheduler.cpp:441] Lost leading master
I0405 17:31:25.697566 31867 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0405 17:31:25.697715 31867 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0405 17:31:25.698210 31866 scheduler.cpp:275] Ignoring reconnect request from scheduler since we are disconnected
I0405 17:31:25.719434 31837 master.cpp:1083] Master terminating
[ OK ] ContentType/SchedulerTest.SchedulerReconnect/0 (543 ms)
[ RUN ] ContentType/SchedulerTest.SchedulerReconnect/1
I0405 17:31:25.728667 31837 cluster.cpp:139] Creating default 'local' authorizer
I0405 17:31:25.810762 31837 leveldb.cpp:174] Opened db in 81.676765ms
I0405 17:31:25.835980 31837 leveldb.cpp:181] Compacted db in 25.146084ms
I0405 17:31:25.836066 31837 leveldb.cpp:196] Created db iterator in 22640ns
I0405 17:31:25.836082 31837 leveldb.cpp:202] Seeked to beginning of db in 3058ns
I0405 17:31:25.836093 31837 leveldb.cpp:271] Iterated through 0 keys in the db in 299ns
I0405 17:31:25.836143 31837 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0405 17:31:25.837239 31863 recover.cpp:447] Starting replica recovery
I0405 17:31:25.837699 31869 recover.cpp:473] Replica is in EMPTY status
I0405 17:31:25.838876 31864 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (16589)@172.17.0.4:43972
I0405 17:31:25.839337 31869 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0405 17:31:25.839902 31863 recover.cpp:564] Updating replica status to STARTING
I0405 17:31:25.841533 31863 master.cpp:376] Master 46673eeb-f2f9-4995-ac8c-9b699e4720a4 (4090d10eba90) started on 172.17.0.4:43972
I0405 17:31:25.841562 31863 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/C5J0Sb/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/C5J0Sb/master" --zk_session_timeout="10secs"
I0405 17:31:25.841986 31863 master.cpp:429] Master allowing unauthenticated frameworks to register
I0405 17:31:25.842000 31863 master.cpp:432] Master only allowing authenticated agents to register
I0405 17:31:25.842008 31863 credentials.hpp:37] Loading credentials for authentication from '/tmp/C5J0Sb/credentials'
I0405 17:31:25.842413 31863 master.cpp:474] Using default 'crammd5' authenticator
I0405 17:31:25.842572 31863 master.cpp:545] Using default 'basic' HTTP authenticator
I0405 17:31:25.842735 31863 master.cpp:583] Authorization enabled
I0405 17:31:25.842933 31857 whitelist_watcher.cpp:77] No whitelist given
I0405 17:31:25.842964 31870 hierarchical.cpp:144] Initialized hierarchical allocator process
I0405 17:31:25.845688 31858 master.cpp:1826] The newly elected leader is master@172.17.0.4:43972 with id 46673eeb-f2f9-4995-ac8c-9b699e4720a4
I0405 17:31:25.845752 31858 master.cpp:1839] Elected as the leading master!
I0405 17:31:25.845774 31858 master.cpp:1526] Recovering from registrar
I0405 17:31:25.845914 31857 registrar.cpp:331] Recovering registrar
I0405 17:31:25.866883 31864 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 26.844536ms
I0405 17:31:25.866989 31864 replica.cpp:320] Persisted replica status to STARTING
I0405 17:31:25.867362 31858 recover.cpp:473] Replica is in STARTING status
I0405 17:31:25.868968 31861 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (16591)@172.17.0.4:43972
I0405 17:31:25.869287 31866 recover.cpp:193] Received a recover response from a replica in STARTING status
I0405 17:31:25.870091 31871 recover.cpp:564] Updating replica status to VOTING
I0405 17:31:25.918589 31856 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 48.332193ms
I0405 17:31:25.918687 31856 replica.cpp:320] Persisted replica status to VOTING
I0405 17:31:25.918943 31862 recover.cpp:578] Successfully joined the Paxos group
I0405 17:31:25.919203 31862 recover.cpp:462] Recover process terminated
I0405 17:31:25.919878 31857 log.cpp:659] Attempting to start the writer
I0405 17:31:25.921718 31857 replica.cpp:493] Replica received implicit promise request from (16592)@172.17.0.4:43972 with proposal 1
I0405 17:31:25.971118 31857 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 49.321915ms
I0405 17:31:25.971240 31857 replica.cpp:342] Persisted promised to 1
I0405 17:31:25.972967 31857 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0405 17:31:25.975095 31857 replica.cpp:388] Replica received explicit promise request from (16593)@172.17.0.4:43972 for position 0 with proposal 2
I0405 17:31:26.029577 31857 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 54.287477ms
I0405 17:31:26.029683 31857 replica.cpp:712] Persisted action at 0
I0405 17:31:26.031633 31857 replica.cpp:537] Replica received write request for position 0 from (16594)@172.17.0.4:43972
I0405 17:31:26.031731 31857 leveldb.cpp:436] Reading position from leveldb took 52046ns
I0405 17:31:26.102416 31857 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 70.607066ms
I0405 17:31:26.102530 31857 replica.cpp:712] Persisted action at 0
I0405 17:31:26.103792 31864 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0405 17:31:26.155194 31864 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 51.329406ms
I0405 17:31:26.155318 31864 replica.cpp:712] Persisted action at 0
I0405 17:31:26.155357 31864 replica.cpp:697] Replica learned NOP action at position 0
I0405 17:31:26.156777 31864 log.cpp:675] Writer started with ending position 0
I0405 17:31:26.158592 31865 leveldb.cpp:436] Reading position from leveldb took 80206ns
I0405 17:31:26.159981 31858 registrar.cpp:364] Successfully fetched the registry (0B) in 314.010112ms
I0405 17:31:26.160147 31858 registrar.cpp:463] Applied 1 operations in 48136ns; attempting to update the 'registry'
I0405 17:31:26.161059 31858 log.cpp:683] Attempting to append 170 bytes to the log
I0405 17:31:26.161206 31864 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0405 17:31:26.162308 31866 replica.cpp:537] Replica received write request for position 1 from (16595)@172.17.0.4:43972
I0405 17:31:26.205615 31866 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 43.227618ms
I0405 17:31:26.205724 31866 replica.cpp:712] Persisted action at 1
I0405 17:31:26.207433 31866 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0405 17:31:26.244978 31866 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 37.466052ms
I0405 17:31:26.245093 31866 replica.cpp:712] Persisted action at 1
I0405 17:31:26.245132 31866 replica.cpp:697] Replica learned APPEND action at position 1
I0405 17:31:26.246959 31867 registrar.cpp:508] Successfully updated the 'registry' in 86.737152ms
I0405 17:31:26.247191 31867 registrar.cpp:394] Successfully recovered registrar
I0405 17:31:26.247280 31866 log.cpp:702] Attempting to truncate the log to 1
I0405 17:31:26.247496 31867 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0405 17:31:26.247740 31866 master.cpp:1634] Recovered 0 agents from the Registry (131B) ; allowing 10mins for agents to re-register
I0405 17:31:26.248195 31865 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0405 17:31:26.249133 31858 replica.cpp:537] Replica received write request for position 2 from (16596)@172.17.0.4:43972
I0405 17:31:26.270704 31858 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 21.488004ms
I0405 17:31:26.270812 31858 replica.cpp:712] Persisted action at 2
I0405 17:31:26.272128 31869 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0405 17:31:26.295784 31869 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.577124ms
I0405 17:31:26.295946 31869 leveldb.cpp:399] Deleting ~1 keys from leveldb took 77730ns
I0405 17:31:26.295974 31869 replica.cpp:712] Persisted action at 2
I0405 17:31:26.296012 31869 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0405 17:31:26.299934 31837 scheduler.cpp:172] Version: 0.29.0
I0405 17:31:26.300500 31867 scheduler.cpp:466] New master detected at master@172.17.0.4:43972
I0405 17:31:26.302775 31859 scheduler.cpp:355] Connected with the master at http://172.17.0.4:43972/master/api/v1/scheduler
I0405 17:31:26.305738 31865 scheduler.cpp:437] Re-detecting master
I0405 17:31:26.306303 31865 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0405 17:31:26.306362 31865 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0405 17:31:26.306473 31865 scheduler.cpp:466] New master detected at master@172.17.0.4:43972
I0405 17:31:26.308179 31865 scheduler.cpp:355] Connected with the master at http://172.17.0.4:43972/master/api/v1/scheduler
I0405 17:31:26.310058 31870 scheduler.cpp:441] Lost leading master
I0405 17:31:26.310580 31870 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0405 17:31:26.310618 31870 scheduler.cpp:388] Ignoring disconnection attempt from stale connection
I0405 17:31:26.310868 31859 scheduler.cpp:275] Ignoring reconnect request from scheduler since we are disconnected
I0405 17:31:26.332106 31837 master.cpp:1083] Master terminating
[ OK ] ContentType/SchedulerTest.SchedulerReconnect/1 (625 ms)
[----------] 28 tests from ContentType/SchedulerTest (21279 ms total)
[----------] Global test environment tear-down
[==========] 1058 tests from 132 test cases ran. (875305 ms total)
[ PASSED ] 1057 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] DiskResource/PersistentVolumeTest.AccessPersistentVolume/0, where GetParam() = 0
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]: Leaving directory `/mesos/mesos-0.29.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1459874188-7935
Untagged: mesos-1459874188-7935:latest
Deleted: 587bad44d4018f3e33d4faa8d5533d382257c3580c30eda9158956a688d111e9
Deleted: 11cd59d4e4b5b12bd61dc5fe3cb811671a5657c2836ec91ba76e08dc4c8c4496
Deleted: 738aa309775af0a727789a276c6090fad2879cf7ccfcd183438a8e46bb7f5bc4
Deleted: 7a62de0c236f973abad16509bd779af27cc5eb22159744c7d8b79397c495dac2
Deleted: 954595df6e1e60327ab072b56fc7317fd343806a3280f773f823acbcbc851c0b
Deleted: 999fd32567298d3056e91158eed2b99e8ac0c648c836718eaa8f472cffe0c6c6
Deleted: fd0da1c1837c5d3b582959a2047d1106174813f0ce14de67cf86c9561db6fbe4
Deleted: c37085c5a1fc1291f2502872c566739e4ff9273fc5525d955dc641ad6c986ad8
Deleted: 06ae36082a49533ef198ab59f36523dad09d183ea1f3723efc5ced1b89e37da9
Deleted: edfa413da51434ad9516dad4466feef216fa39a0468ca28343a614c2079a5386
Deleted: 446497054abc71afe6f4affee4d90dbaf8a0f3b9abeb414fbe8ca883cd25b263
Deleted: cb738127c1ceb0ea2c00277059af77157a55e842b86ce4510100d35827eb187a
Deleted: b3d8fa5da1a666a16d7bb84275de4573f72ecf4f80e18f8a8fc4cb2a68564daa
Deleted: 414d7e86a4915cc5cba9a2a3dd8f683eee7ac7591bc83ed9a45a6367a0139536
Deleted: 10df8a705374f1365915e43254c7cdaf6434ff41f70439c9153f2bcdd1d3a047
Deleted: 36e2ccea1d26a07d0fd601355996732ae283018ff1ef291b0b12e0103be8e2a3
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1938
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1938/changes>
Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1937
Posted by Greg Mann <gr...@mesosphere.io>.
This failure of PersistentVolumeTest.AccessPersistentVolume looks new; I
created a ticket here: https://issues.apache.org/jira/browse/MESOS-5128
On Tue, Apr 5, 2016 at 10:31 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,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 180416 lines...]
> I0405 17:31:25.159062 31869 hierarchical.cpp:266] Added framework
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
> I0405 17:31:25.159181 31869 hierarchical.cpp:1490] No resources available
> to allocate!
> I0405 17:31:25.159219 31869 hierarchical.cpp:1585] No inverse offers to
> send out!
> I0405 17:31:25.159296 31869 hierarchical.cpp:1141] Performed allocation
> for 0 agents in 191001ns
> I0405 17:31:25.159404 31869 master.hpp:1719] Sending heartbeat to
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
> I0405 17:31:25.160429 31871 scheduler.cpp:657] Enqueuing event SUBSCRIBED
> received from http://172.17.0.4:43972/master/api/v1/scheduler
> I0405 17:31:25.162025 31870 scheduler.cpp:231] Sending REQUEST call to
> http://172.17.0.4:43972/master/api/v1/scheduler
> I0405 17:31:25.162436 31870 scheduler.cpp:657] Enqueuing event HEARTBEAT
> received from http://172.17.0.4:43972/master/api/v1/scheduler
> I0405 17:31:25.162942 31869 process.cpp:3165] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0405 17:31:25.163462 31866 http.cpp:313] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.4:37062
> I0405 17:31:25.163760 31866 master.cpp:2785] Processing REQUEST call for
> framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
> I0405 17:31:25.163913 31865 hierarchical.cpp:592] Received resource
> request from framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
> I0405 17:31:25.166333 31856 master.cpp:1269] Framework
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default) disconnected
> I0405 17:31:25.166366 31856 master.cpp:2642] Disconnecting framework
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
> I0405 17:31:25.166388 31856 master.cpp:2666] Deactivating framework
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
> I0405 17:31:25.166491 31856 master.cpp:1293] Giving framework
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default) 0ns to failover
> I0405 17:31:25.166674 31856 hierarchical.cpp:378] Deactivated framework
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
> I0405 17:31:25.166853 31856 master.cpp:5360] Framework failover timeout,
> removing framework ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
> I0405 17:31:25.166874 31856 master.cpp:6093] Removing framework
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000 (default)
> I0405 17:31:25.167273 31859 hierarchical.cpp:329] Removed framework
> ef7b745a-3956-44cb-954b-afa5b2fa4484-0000
> I0405 17:31:25.176632 31837 master.cpp:1083] Master terminating
> [ OK ] ContentType/SchedulerTest.Request/1 (653 ms)
> [ RUN ] ContentType/SchedulerTest.SchedulerReconnect/0
> I0405 17:31:25.186280 31837 cluster.cpp:139] Creating default 'local'
> authorizer
> I0405 17:31:25.276929 31837 leveldb.cpp:174] Opened db in 90.295589ms
> I0405 17:31:25.327116 31837 leveldb.cpp:181] Compacted db in 50.111162ms
> I0405 17:31:25.327205 31837 leveldb.cpp:196] Created db iterator in 23723ns
> I0405 17:31:25.327234 31837 leveldb.cpp:202] Seeked to beginning of db in
> 3199ns
> I0405 17:31:25.327249 31837 leveldb.cpp:271] Iterated through 0 keys in
> the db in 285ns
> I0405 17:31:25.327301 31837 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0405 17:31:25.327961 31863 recover.cpp:447] Starting replica recovery
> I0405 17:31:25.328253 31863 recover.cpp:473] Replica is in EMPTY status
> I0405 17:31:25.330695 31863 master.cpp:376] Master
> b6d48046-0782-4192-8394-aa41d565cc9d (4090d10eba90) started on
> 172.17.0.4:43972
> I0405 17:31:25.330716 31863 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/WYHfmi/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/WYHfmi/master" --zk_session_timeout="10secs"
> I0405 17:31:25.331143 31863 master.cpp:429] Master allowing
> unauthenticated frameworks to register
> I0405 17:31:25.331156 31863 master.cpp:432] Master only allowing
> authenticated agents to register
> I0405 17:31:25.331166 31863 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/WYHfmi/credentials'
> I0405 17:31:25.331599 31863 master.cpp:474] Using default 'crammd5'
> authenticator
> I0405 17:31:25.331750 31863 master.cpp:545] Using default 'basic' HTTP
> authenticator
> I0405 17:31:25.331877 31863 master.cpp:583] Authorization enabled
> I0405 17:31:25.332294 31870 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (16578)@172.17.0.4:43972
> I0405 17:31:25.332476 31870 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0405 17:31:25.332540 31870 whitelist_watcher.cpp:77] No whitelist given
> I0405 17:31:25.332882 31866 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0405 17:31:25.333523 31856 recover.cpp:564] Updating replica status to
> STARTING
> I0405 17:31:25.335368 31856 master.cpp:1826] The newly elected leader is
> master@172.17.0.4:43972 with id b6d48046-0782-4192-8394-aa41d565cc9d
> I0405 17:31:25.335402 31856 master.cpp:1839] Elected as the leading master!
> I0405 17:31:25.335422 31856 master.cpp:1526] Recovering from registrar
> I0405 17:31:25.335564 31869 registrar.cpp:331] Recovering registrar
> I0405 17:31:25.369477 31870 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 35.558972ms
> I0405 17:31:25.369582 31870 replica.cpp:320] Persisted replica status to
> STARTING
> I0405 17:31:25.370028 31870 recover.cpp:473] Replica is in STARTING status
> I0405 17:31:25.372201 31870 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (16580)@172.17.0.4:43972
> I0405 17:31:25.372637 31870 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0405 17:31:25.373510 31871 recover.cpp:564] Updating replica status to
> VOTING
> I0405 17:31:25.402940 31861 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 29.239281ms
> I0405 17:31:25.403045 31861 replica.cpp:320] Persisted replica status to
> VOTING
> I0405 17:31:25.403293 31871 recover.cpp:578] Successfully joined the Paxos
> group
> I0405 17:31:25.403545 31871 recover.cpp:462] Recover process terminated
> I0405 17:31:25.404201 31871 log.cpp:659] Attempting to start the writer
> I0405 17:31:25.405786 31857 replica.cpp:493] Replica received implicit
> promise request from (16581)@172.17.0.4:43972 with proposal 1
> I0405 17:31:25.436377 31857 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.523397ms
> I0405 17:31:25.436480 31857 replica.cpp:342] Persisted promised to 1
> I0405 17:31:25.437525 31861 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0405 17:31:25.439023 31856 replica.cpp:388] Replica received explicit
> promise request from (16582)@172.17.0.4:43972 for position 0 with
> proposal 2
> I0405 17:31:25.469802 31856 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 30.700643ms
> I0405 17:31:25.469905 31856 replica.cpp:712] Persisted action at 0
> I0405 17:31:25.471498 31866 replica.cpp:537] Replica received write
> request for position 0 from (16583)@172.17.0.4:43972
> I0405 17:31:25.471596 31866 leveldb.cpp:436] Reading position from leveldb
> took 52027ns
> I0405 17:31:25.503268 31866 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 31.586096ms
> I0405 17:31:25.503377 31866 replica.cpp:712] Persisted action at 0
> I0405 17:31:25.504559 31866 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0405 17:31:25.536703 31866 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 32.083286ms
> I0405 17:31:25.536811 31866 replica.cpp:712] Persisted action at 0
> I0405 17:31:25.536849 31866 replica.cpp:697] Replica learned NOP action at
> position 0
> I0405 17:31:25.537925 31867 log.cpp:675] Writer started with ending
> position 0
> I0405 17:31:25.539691 31867 leveldb.cpp:436] Reading position from leveldb
> took 88448ns
> I0405 17:31:25.541448 31867 registrar.cpp:364] Successfully fetched the
> registry (0B) in 205.828096ms
> I0405 17:31:25.541659 31867 registrar.cpp:463] Applied 1 operations in
> 51493ns; attempting to update the 'registry'
> I0405 17:31:25.543038 31861 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0405 17:31:25.543267 31860 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0405 17:31:25.544409 31870 replica.cpp:537] Replica received write
> request for position 1 from (16584)@172.17.0.4:43972
> I0405 17:31:25.578496 31870 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 34.006188ms
> I0405 17:31:25.578606 31870 replica.cpp:712] Persisted action at 1
> I0405 17:31:25.579566 31871 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0405 17:31:25.612403 31871 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 32.769506ms
> I0405 17:31:25.612512 31871 replica.cpp:712] Persisted action at 1
> I0405 17:31:25.612553 31871 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0405 17:31:25.614151 31865 registrar.cpp:508] Successfully updated the
> 'registry' in 72.396032ms
> I0405 17:31:25.614435 31861 log.cpp:702] Attempting to truncate the log to
> 1
> I0405 17:31:25.614575 31869 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0405 17:31:25.614382 31865 registrar.cpp:394] Successfully recovered
> registrar
> I0405 17:31:25.615356 31865 master.cpp:1634] Recovered 0 agents from the
> Registry (131B) ; allowing 10mins for agents to re-register
> I0405 17:31:25.615655 31866 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0405 17:31:25.615767 31862 replica.cpp:537] Replica received write
> request for position 2 from (16585)@172.17.0.4:43972
> I0405 17:31:25.653707 31862 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 37.868106ms
> I0405 17:31:25.653811 31862 replica.cpp:712] Persisted action at 2
> I0405 17:31:25.654872 31862 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0405 17:31:25.684255 31862 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 29.315506ms
> I0405 17:31:25.684437 31862 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 85678ns
> I0405 17:31:25.684465 31862 replica.cpp:712] Persisted action at 2
> I0405 17:31:25.684504 31862 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0405 17:31:25.687340 31837 scheduler.cpp:172] Version: 0.29.0
> I0405 17:31:25.688011 31871 scheduler.cpp:466] New master detected at
> master@172.17.0.4:43972
> I0405 17:31:25.690260 31868 scheduler.cpp:355] Connected with the master
> at http://172.17.0.4:43972/master/api/v1/scheduler
> I0405 17:31:25.692463 31870 scheduler.cpp:437] Re-detecting master
> I0405 17:31:25.692947 31870 scheduler.cpp:388] Ignoring disconnection
> attempt from stale connection
> I0405 17:31:25.693245 31870 scheduler.cpp:388] Ignoring disconnection
> attempt from stale connection
> I0405 17:31:25.693413 31870 scheduler.cpp:466] New master detected at
> master@172.17.0.4:43972
> I0405 17:31:25.694856 31870 scheduler.cpp:355] Connected with the master
> at http://172.17.0.4:43972/master/api/v1/scheduler
> I0405 17:31:25.697296 31867 scheduler.cpp:441] Lost leading master
> I0405 17:31:25.697566 31867 scheduler.cpp:388] Ignoring disconnection
> attempt from stale connection
> I0405 17:31:25.697715 31867 scheduler.cpp:388] Ignoring disconnection
> attempt from stale connection
> I0405 17:31:25.698210 31866 scheduler.cpp:275] Ignoring reconnect request
> from scheduler since we are disconnected
> I0405 17:31:25.719434 31837 master.cpp:1083] Master terminating
> [ OK ] ContentType/SchedulerTest.SchedulerReconnect/0 (543 ms)
> [ RUN ] ContentType/SchedulerTest.SchedulerReconnect/1
> I0405 17:31:25.728667 31837 cluster.cpp:139] Creating default 'local'
> authorizer
> I0405 17:31:25.810762 31837 leveldb.cpp:174] Opened db in 81.676765ms
> I0405 17:31:25.835980 31837 leveldb.cpp:181] Compacted db in 25.146084ms
> I0405 17:31:25.836066 31837 leveldb.cpp:196] Created db iterator in 22640ns
> I0405 17:31:25.836082 31837 leveldb.cpp:202] Seeked to beginning of db in
> 3058ns
> I0405 17:31:25.836093 31837 leveldb.cpp:271] Iterated through 0 keys in
> the db in 299ns
> I0405 17:31:25.836143 31837 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0405 17:31:25.837239 31863 recover.cpp:447] Starting replica recovery
> I0405 17:31:25.837699 31869 recover.cpp:473] Replica is in EMPTY status
> I0405 17:31:25.838876 31864 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (16589)@172.17.0.4:43972
> I0405 17:31:25.839337 31869 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0405 17:31:25.839902 31863 recover.cpp:564] Updating replica status to
> STARTING
> I0405 17:31:25.841533 31863 master.cpp:376] Master
> 46673eeb-f2f9-4995-ac8c-9b699e4720a4 (4090d10eba90) started on
> 172.17.0.4:43972
> I0405 17:31:25.841562 31863 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/C5J0Sb/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/C5J0Sb/master" --zk_session_timeout="10secs"
> I0405 17:31:25.841986 31863 master.cpp:429] Master allowing
> unauthenticated frameworks to register
> I0405 17:31:25.842000 31863 master.cpp:432] Master only allowing
> authenticated agents to register
> I0405 17:31:25.842008 31863 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/C5J0Sb/credentials'
> I0405 17:31:25.842413 31863 master.cpp:474] Using default 'crammd5'
> authenticator
> I0405 17:31:25.842572 31863 master.cpp:545] Using default 'basic' HTTP
> authenticator
> I0405 17:31:25.842735 31863 master.cpp:583] Authorization enabled
> I0405 17:31:25.842933 31857 whitelist_watcher.cpp:77] No whitelist given
> I0405 17:31:25.842964 31870 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0405 17:31:25.845688 31858 master.cpp:1826] The newly elected leader is
> master@172.17.0.4:43972 with id 46673eeb-f2f9-4995-ac8c-9b699e4720a4
> I0405 17:31:25.845752 31858 master.cpp:1839] Elected as the leading master!
> I0405 17:31:25.845774 31858 master.cpp:1526] Recovering from registrar
> I0405 17:31:25.845914 31857 registrar.cpp:331] Recovering registrar
> I0405 17:31:25.866883 31864 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 26.844536ms
> I0405 17:31:25.866989 31864 replica.cpp:320] Persisted replica status to
> STARTING
> I0405 17:31:25.867362 31858 recover.cpp:473] Replica is in STARTING status
> I0405 17:31:25.868968 31861 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (16591)@172.17.0.4:43972
> I0405 17:31:25.869287 31866 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0405 17:31:25.870091 31871 recover.cpp:564] Updating replica status to
> VOTING
> I0405 17:31:25.918589 31856 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 48.332193ms
> I0405 17:31:25.918687 31856 replica.cpp:320] Persisted replica status to
> VOTING
> I0405 17:31:25.918943 31862 recover.cpp:578] Successfully joined the Paxos
> group
> I0405 17:31:25.919203 31862 recover.cpp:462] Recover process terminated
> I0405 17:31:25.919878 31857 log.cpp:659] Attempting to start the writer
> I0405 17:31:25.921718 31857 replica.cpp:493] Replica received implicit
> promise request from (16592)@172.17.0.4:43972 with proposal 1
> I0405 17:31:25.971118 31857 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 49.321915ms
> I0405 17:31:25.971240 31857 replica.cpp:342] Persisted promised to 1
> I0405 17:31:25.972967 31857 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0405 17:31:25.975095 31857 replica.cpp:388] Replica received explicit
> promise request from (16593)@172.17.0.4:43972 for position 0 with
> proposal 2
> I0405 17:31:26.029577 31857 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 54.287477ms
> I0405 17:31:26.029683 31857 replica.cpp:712] Persisted action at 0
> I0405 17:31:26.031633 31857 replica.cpp:537] Replica received write
> request for position 0 from (16594)@172.17.0.4:43972
> I0405 17:31:26.031731 31857 leveldb.cpp:436] Reading position from leveldb
> took 52046ns
> I0405 17:31:26.102416 31857 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 70.607066ms
> I0405 17:31:26.102530 31857 replica.cpp:712] Persisted action at 0
> I0405 17:31:26.103792 31864 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0405 17:31:26.155194 31864 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 51.329406ms
> I0405 17:31:26.155318 31864 replica.cpp:712] Persisted action at 0
> I0405 17:31:26.155357 31864 replica.cpp:697] Replica learned NOP action at
> position 0
> I0405 17:31:26.156777 31864 log.cpp:675] Writer started with ending
> position 0
> I0405 17:31:26.158592 31865 leveldb.cpp:436] Reading position from leveldb
> took 80206ns
> I0405 17:31:26.159981 31858 registrar.cpp:364] Successfully fetched the
> registry (0B) in 314.010112ms
> I0405 17:31:26.160147 31858 registrar.cpp:463] Applied 1 operations in
> 48136ns; attempting to update the 'registry'
> I0405 17:31:26.161059 31858 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0405 17:31:26.161206 31864 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0405 17:31:26.162308 31866 replica.cpp:537] Replica received write
> request for position 1 from (16595)@172.17.0.4:43972
> I0405 17:31:26.205615 31866 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 43.227618ms
> I0405 17:31:26.205724 31866 replica.cpp:712] Persisted action at 1
> I0405 17:31:26.207433 31866 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0405 17:31:26.244978 31866 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 37.466052ms
> I0405 17:31:26.245093 31866 replica.cpp:712] Persisted action at 1
> I0405 17:31:26.245132 31866 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0405 17:31:26.246959 31867 registrar.cpp:508] Successfully updated the
> 'registry' in 86.737152ms
> I0405 17:31:26.247191 31867 registrar.cpp:394] Successfully recovered
> registrar
> I0405 17:31:26.247280 31866 log.cpp:702] Attempting to truncate the log to
> 1
> I0405 17:31:26.247496 31867 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0405 17:31:26.247740 31866 master.cpp:1634] Recovered 0 agents from the
> Registry (131B) ; allowing 10mins for agents to re-register
> I0405 17:31:26.248195 31865 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0405 17:31:26.249133 31858 replica.cpp:537] Replica received write
> request for position 2 from (16596)@172.17.0.4:43972
> I0405 17:31:26.270704 31858 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 21.488004ms
> I0405 17:31:26.270812 31858 replica.cpp:712] Persisted action at 2
> I0405 17:31:26.272128 31869 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0405 17:31:26.295784 31869 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 23.577124ms
> I0405 17:31:26.295946 31869 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 77730ns
> I0405 17:31:26.295974 31869 replica.cpp:712] Persisted action at 2
> I0405 17:31:26.296012 31869 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0405 17:31:26.299934 31837 scheduler.cpp:172] Version: 0.29.0
> I0405 17:31:26.300500 31867 scheduler.cpp:466] New master detected at
> master@172.17.0.4:43972
> I0405 17:31:26.302775 31859 scheduler.cpp:355] Connected with the master
> at http://172.17.0.4:43972/master/api/v1/scheduler
> I0405 17:31:26.305738 31865 scheduler.cpp:437] Re-detecting master
> I0405 17:31:26.306303 31865 scheduler.cpp:388] Ignoring disconnection
> attempt from stale connection
> I0405 17:31:26.306362 31865 scheduler.cpp:388] Ignoring disconnection
> attempt from stale connection
> I0405 17:31:26.306473 31865 scheduler.cpp:466] New master detected at
> master@172.17.0.4:43972
> I0405 17:31:26.308179 31865 scheduler.cpp:355] Connected with the master
> at http://172.17.0.4:43972/master/api/v1/scheduler
> I0405 17:31:26.310058 31870 scheduler.cpp:441] Lost leading master
> I0405 17:31:26.310580 31870 scheduler.cpp:388] Ignoring disconnection
> attempt from stale connection
> I0405 17:31:26.310618 31870 scheduler.cpp:388] Ignoring disconnection
> attempt from stale connection
> I0405 17:31:26.310868 31859 scheduler.cpp:275] Ignoring reconnect request
> from scheduler since we are disconnected
> I0405 17:31:26.332106 31837 master.cpp:1083] Master terminating
> [ OK ] ContentType/SchedulerTest.SchedulerReconnect/1 (625 ms)
> [----------] 28 tests from ContentType/SchedulerTest (21279 ms total)
>
> [----------] Global test environment tear-down
> [==========] 1058 tests from 132 test cases ran. (875305 ms total)
> [ PASSED ] 1057 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] DiskResource/PersistentVolumeTest.AccessPersistentVolume/0,
> where GetParam() = 0
>
> 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]: Leaving directory `/mesos/mesos-0.29.0/_build'
> make[1]: *** [check-recursive] Error 1
> make: *** [distcheck] Error 1
> + docker rmi mesos-1459874188-7935
> Untagged: mesos-1459874188-7935:latest
> Deleted: 587bad44d4018f3e33d4faa8d5533d382257c3580c30eda9158956a688d111e9
> Deleted: 11cd59d4e4b5b12bd61dc5fe3cb811671a5657c2836ec91ba76e08dc4c8c4496
> Deleted: 738aa309775af0a727789a276c6090fad2879cf7ccfcd183438a8e46bb7f5bc4
> Deleted: 7a62de0c236f973abad16509bd779af27cc5eb22159744c7d8b79397c495dac2
> Deleted: 954595df6e1e60327ab072b56fc7317fd343806a3280f773f823acbcbc851c0b
> Deleted: 999fd32567298d3056e91158eed2b99e8ac0c648c836718eaa8f472cffe0c6c6
> Deleted: fd0da1c1837c5d3b582959a2047d1106174813f0ce14de67cf86c9561db6fbe4
> Deleted: c37085c5a1fc1291f2502872c566739e4ff9273fc5525d955dc641ad6c986ad8
> Deleted: 06ae36082a49533ef198ab59f36523dad09d183ea1f3723efc5ced1b89e37da9
> Deleted: edfa413da51434ad9516dad4466feef216fa39a0468ca28343a614c2079a5386
> Deleted: 446497054abc71afe6f4affee4d90dbaf8a0f3b9abeb414fbe8ca883cd25b263
> Deleted: cb738127c1ceb0ea2c00277059af77157a55e842b86ce4510100d35827eb187a
> Deleted: b3d8fa5da1a666a16d7bb84275de4573f72ecf4f80e18f8a8fc4cb2a68564daa
> Deleted: 414d7e86a4915cc5cba9a2a3dd8f683eee7ac7591bc83ed9a45a6367a0139536
> Deleted: 10df8a705374f1365915e43254c7cdaf6434ff41f70439c9153f2bcdd1d3a047
> Deleted: 36e2ccea1d26a07d0fd601355996732ae283018ff1ef291b0b12e0103be8e2a3
> Build step 'Execute shell' marked build as failure
>