You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/02/06 02:37:28 UTC
Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1632
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=centos%3A7,label_exp=docker%7C%7CHadoop/1632/changes>
Changes:
[yujie.jay] Added helper functions in cgroup for supporting net_cls subsystem.
------------------------------------------
[...truncated 167635 lines...]
[ OK ] ContentType/SchedulerTest.Message/1 (616 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0206 01:37:28.679303 2824 leveldb.cpp:174] Opened db in 76.966471ms
I0206 01:37:28.704545 2824 leveldb.cpp:181] Compacted db in 25.081696ms
I0206 01:37:28.704617 2824 leveldb.cpp:196] Created db iterator in 30299ns
I0206 01:37:28.704635 2824 leveldb.cpp:202] Seeked to beginning of db in 1620ns
I0206 01:37:28.704643 2824 leveldb.cpp:271] Iterated through 0 keys in the db in 268ns
I0206 01:37:28.704689 2824 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0206 01:37:28.705224 2856 recover.cpp:447] Starting replica recovery
I0206 01:37:28.705482 2856 recover.cpp:473] Replica is in EMPTY status
I0206 01:37:28.706450 2855 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14378)@172.17.0.8:37843
I0206 01:37:28.706908 2852 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0206 01:37:28.707272 2855 recover.cpp:564] Updating replica status to STARTING
I0206 01:37:28.707988 2848 master.cpp:376] Master f8669fe3-e905-45e0-8063-a4968d66a97e (74ef606c4063) started on 172.17.0.8:37843
I0206 01:37:28.708010 2848 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/z2SLxg/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/z2SLxg/master" --zk_session_timeout="10secs"
I0206 01:37:28.708268 2848 master.cpp:425] Master allowing unauthenticated frameworks to register
I0206 01:37:28.708278 2848 master.cpp:428] Master only allowing authenticated slaves to register
I0206 01:37:28.708284 2848 credentials.hpp:35] Loading credentials for authentication from '/tmp/z2SLxg/credentials'
I0206 01:37:28.708549 2848 master.cpp:468] Using default 'crammd5' authenticator
I0206 01:37:28.708688 2848 master.cpp:537] Using default 'basic' HTTP authenticator
I0206 01:37:28.708824 2848 master.cpp:571] Authorization enabled
I0206 01:37:28.708986 2856 hierarchical.cpp:144] Initialized hierarchical allocator process
I0206 01:37:28.709018 2846 whitelist_watcher.cpp:77] No whitelist given
I0206 01:37:28.710572 2851 master.cpp:1712] The newly elected leader is master@172.17.0.8:37843 with id f8669fe3-e905-45e0-8063-a4968d66a97e
I0206 01:37:28.710618 2851 master.cpp:1725] Elected as the leading master!
I0206 01:37:28.710708 2851 master.cpp:1470] Recovering from registrar
I0206 01:37:28.710876 2843 registrar.cpp:307] Recovering registrar
I0206 01:37:28.729671 2853 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.184621ms
I0206 01:37:28.729707 2853 replica.cpp:320] Persisted replica status to STARTING
I0206 01:37:28.729889 2852 recover.cpp:473] Replica is in STARTING status
I0206 01:37:28.730726 2858 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14380)@172.17.0.8:37843
I0206 01:37:28.731076 2857 recover.cpp:193] Received a recover response from a replica in STARTING status
I0206 01:37:28.731470 2852 recover.cpp:564] Updating replica status to VOTING
I0206 01:37:28.761700 2844 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.01581ms
I0206 01:37:28.761744 2844 replica.cpp:320] Persisted replica status to VOTING
I0206 01:37:28.761885 2848 recover.cpp:578] Successfully joined the Paxos group
I0206 01:37:28.762101 2848 recover.cpp:462] Recover process terminated
I0206 01:37:28.762521 2857 log.cpp:659] Attempting to start the writer
I0206 01:37:28.763659 2855 replica.cpp:493] Replica received implicit promise request from (14381)@172.17.0.8:37843 with proposal 1
I0206 01:37:28.795686 2855 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.930403ms
I0206 01:37:28.795774 2855 replica.cpp:342] Persisted promised to 1
I0206 01:37:28.798104 2857 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0206 01:37:28.799767 2845 replica.cpp:388] Replica received explicit promise request from (14382)@172.17.0.8:37843 for position 0 with proposal 2
I0206 01:37:28.828594 2845 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 28.759079ms
I0206 01:37:28.828680 2845 replica.cpp:712] Persisted action at 0
I0206 01:37:28.829860 2846 replica.cpp:537] Replica received write request for position 0 from (14383)@172.17.0.8:37843
I0206 01:37:28.829978 2846 leveldb.cpp:436] Reading position from leveldb took 39903ns
I0206 01:37:28.862035 2846 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.989561ms
I0206 01:37:28.862118 2846 replica.cpp:712] Persisted action at 0
I0206 01:37:28.863024 2847 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0206 01:37:28.895505 2847 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.258897ms
I0206 01:37:28.895747 2847 replica.cpp:712] Persisted action at 0
I0206 01:37:28.895907 2847 replica.cpp:697] Replica learned NOP action at position 0
I0206 01:37:28.896898 2847 log.cpp:675] Writer started with ending position 0
I0206 01:37:28.898190 2855 leveldb.cpp:436] Reading position from leveldb took 45954ns
I0206 01:37:28.899428 2855 registrar.cpp:340] Successfully fetched the registry (0B) in 188.497152ms
I0206 01:37:28.899546 2855 registrar.cpp:439] Applied 1 operations in 35031ns; attempting to update the 'registry'
I0206 01:37:28.900215 2852 log.cpp:683] Attempting to append 170 bytes to the log
I0206 01:37:28.900336 2854 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0206 01:37:28.901142 2858 replica.cpp:537] Replica received write request for position 1 from (14384)@172.17.0.8:37843
I0206 01:37:28.948690 2858 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 47.491423ms
I0206 01:37:28.948776 2858 replica.cpp:712] Persisted action at 1
I0206 01:37:28.950230 2845 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0206 01:37:28.979105 2845 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 28.765891ms
I0206 01:37:28.979189 2845 replica.cpp:712] Persisted action at 1
I0206 01:37:28.979225 2845 replica.cpp:697] Replica learned APPEND action at position 1
I0206 01:37:28.981251 2845 registrar.cpp:484] Successfully updated the 'registry' in 81.641984ms
I0206 01:37:28.981386 2845 registrar.cpp:370] Successfully recovered registrar
I0206 01:37:28.981757 2855 log.cpp:702] Attempting to truncate the log to 1
I0206 01:37:28.982208 2851 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0206 01:37:28.982374 2853 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0206 01:37:28.983206 2858 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0206 01:37:28.984444 2853 replica.cpp:537] Replica received write request for position 2 from (14385)@172.17.0.8:37843
I0206 01:37:29.012693 2853 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 28.184253ms
I0206 01:37:29.012780 2853 replica.cpp:712] Persisted action at 2
I0206 01:37:29.014279 2854 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0206 01:37:29.046149 2854 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 31.824309ms
I0206 01:37:29.046288 2854 leveldb.cpp:399] Deleting ~1 keys from leveldb took 66669ns
I0206 01:37:29.046309 2854 replica.cpp:712] Persisted action at 2
I0206 01:37:29.046340 2854 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0206 01:37:29.055670 2824 scheduler.cpp:154] Version: 0.28.0
I0206 01:37:29.056491 2854 scheduler.cpp:236] New master detected at master@172.17.0.8:37843
I0206 01:37:29.057818 2851 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.8:37843
I0206 01:37:29.059360 2855 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 01:37:29.059873 2858 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.8:47819
I0206 01:37:29.060060 2858 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0206 01:37:29.060124 2858 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0206 01:37:29.060379 2858 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0206 01:37:29.060849 2858 master.hpp:1658] Sending heartbeat to f8669fe3-e905-45e0-8063-a4968d66a97e-0000
I0206 01:37:29.060854 2855 hierarchical.cpp:265] Added framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000
I0206 01:37:29.061645 2853 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.8:37843
I0206 01:37:29.061714 2855 hierarchical.cpp:1403] No resources available to allocate!
I0206 01:37:29.061748 2855 hierarchical.cpp:1498] No inverse offers to send out!
I0206 01:37:29.061771 2855 hierarchical.cpp:1096] Performed allocation for 0 slaves in 117038ns
I0206 01:37:29.062062 2853 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.8:37843
I0206 01:37:29.062855 2853 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.8:37843
I0206 01:37:29.062923 2850 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0206 01:37:29.064299 2843 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 01:37:29.064771 2855 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.8:47820
I0206 01:37:29.064883 2855 master.cpp:2719] Processing REQUEST call for framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000 (default)
I0206 01:37:29.065019 2844 hierarchical.cpp:589] Received resource request from framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000
I0206 01:37:29.065457 2845 master.cpp:1027] Master terminating
I0206 01:37:29.065826 2850 hierarchical.cpp:326] Removed framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000
E0206 01:37:29.066864 2844 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (472 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0206 01:37:29.180583 2824 leveldb.cpp:174] Opened db in 105.938866ms
I0206 01:37:29.223002 2824 leveldb.cpp:181] Compacted db in 42.322554ms
I0206 01:37:29.223089 2824 leveldb.cpp:196] Created db iterator in 21712ns
I0206 01:37:29.223108 2824 leveldb.cpp:202] Seeked to beginning of db in 2666ns
I0206 01:37:29.223119 2824 leveldb.cpp:271] Iterated through 0 keys in the db in 322ns
I0206 01:37:29.223170 2824 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0206 01:37:29.223954 2847 recover.cpp:447] Starting replica recovery
I0206 01:37:29.224406 2847 recover.cpp:473] Replica is in EMPTY status
I0206 01:37:29.226563 2843 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14393)@172.17.0.8:37843
I0206 01:37:29.226886 2849 master.cpp:376] Master 675a84d4-4d67-4255-b221-c63e4669bf79 (74ef606c4063) started on 172.17.0.8:37843
I0206 01:37:29.226912 2849 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/K1hwPx/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/K1hwPx/master" --zk_session_timeout="10secs"
I0206 01:37:29.227296 2849 master.cpp:425] Master allowing unauthenticated frameworks to register
I0206 01:37:29.227314 2849 master.cpp:428] Master only allowing authenticated slaves to register
I0206 01:37:29.227325 2849 credentials.hpp:35] Loading credentials for authentication from '/tmp/K1hwPx/credentials'
I0206 01:37:29.227718 2849 master.cpp:468] Using default 'crammd5' authenticator
I0206 01:37:29.227891 2849 master.cpp:537] Using default 'basic' HTTP authenticator
I0206 01:37:29.228045 2849 master.cpp:571] Authorization enabled
I0206 01:37:29.229780 2850 hierarchical.cpp:144] Initialized hierarchical allocator process
I0206 01:37:29.229867 2850 whitelist_watcher.cpp:77] No whitelist given
I0206 01:37:29.231914 2852 master.cpp:1712] The newly elected leader is master@172.17.0.8:37843 with id 675a84d4-4d67-4255-b221-c63e4669bf79
I0206 01:37:29.231953 2852 master.cpp:1725] Elected as the leading master!
I0206 01:37:29.231976 2852 master.cpp:1470] Recovering from registrar
I0206 01:37:29.232695 2857 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0206 01:37:29.234669 2857 recover.cpp:564] Updating replica status to STARTING
I0206 01:37:29.235661 2852 registrar.cpp:307] Recovering registrar
I0206 01:37:29.256692 2843 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.762851ms
I0206 01:37:29.256778 2843 replica.cpp:320] Persisted replica status to STARTING
I0206 01:37:29.257149 2843 recover.cpp:473] Replica is in STARTING status
I0206 01:37:29.258880 2854 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14395)@172.17.0.8:37843
I0206 01:37:29.259963 2854 recover.cpp:193] Received a recover response from a replica in STARTING status
I0206 01:37:29.260452 2851 recover.cpp:564] Updating replica status to VOTING
I0206 01:37:29.281335 2852 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 20.649922ms
I0206 01:37:29.281419 2852 replica.cpp:320] Persisted replica status to VOTING
I0206 01:37:29.281673 2847 recover.cpp:578] Successfully joined the Paxos group
I0206 01:37:29.281895 2847 recover.cpp:462] Recover process terminated
I0206 01:37:29.282418 2851 log.cpp:659] Attempting to start the writer
I0206 01:37:29.283726 2858 replica.cpp:493] Replica received implicit promise request from (14396)@172.17.0.8:37843 with proposal 1
I0206 01:37:29.306686 2858 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.909015ms
I0206 01:37:29.306771 2858 replica.cpp:342] Persisted promised to 1
I0206 01:37:29.308105 2843 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0206 01:37:29.310048 2848 replica.cpp:388] Replica received explicit promise request from (14397)@172.17.0.8:37843 for position 0 with proposal 2
I0206 01:37:29.331552 2848 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 21.429846ms
I0206 01:37:29.331781 2848 replica.cpp:712] Persisted action at 0
I0206 01:37:29.334182 2848 replica.cpp:537] Replica received write request for position 0 from (14398)@172.17.0.8:37843
I0206 01:37:29.334568 2848 leveldb.cpp:436] Reading position from leveldb took 42222ns
I0206 01:37:29.358628 2848 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.773959ms
I0206 01:37:29.358721 2848 replica.cpp:712] Persisted action at 0
I0206 01:37:29.359482 2848 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0206 01:37:29.392504 2848 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.969204ms
I0206 01:37:29.392735 2848 replica.cpp:712] Persisted action at 0
I0206 01:37:29.392900 2848 replica.cpp:697] Replica learned NOP action at position 0
I0206 01:37:29.394258 2853 log.cpp:675] Writer started with ending position 0
I0206 01:37:29.395711 2853 leveldb.cpp:436] Reading position from leveldb took 77483ns
I0206 01:37:29.397044 2849 registrar.cpp:340] Successfully fetched the registry (0B) in 161.316864ms
I0206 01:37:29.397209 2849 registrar.cpp:439] Applied 1 operations in 43170ns; attempting to update the 'registry'
I0206 01:37:29.398488 2851 log.cpp:683] Attempting to append 170 bytes to the log
I0206 01:37:29.398684 2846 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0206 01:37:29.400101 2846 replica.cpp:537] Replica received write request for position 1 from (14399)@172.17.0.8:37843
I0206 01:37:29.442999 2846 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 42.830963ms
I0206 01:37:29.443084 2846 replica.cpp:712] Persisted action at 1
I0206 01:37:29.444593 2857 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0206 01:37:29.491114 2857 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 46.373782ms
I0206 01:37:29.491322 2857 replica.cpp:712] Persisted action at 1
I0206 01:37:29.491787 2857 replica.cpp:697] Replica learned APPEND action at position 1
I0206 01:37:29.493533 2847 registrar.cpp:484] Successfully updated the 'registry' in 96.251904ms
I0206 01:37:29.493690 2847 registrar.cpp:370] Successfully recovered registrar
I0206 01:37:29.493767 2852 log.cpp:702] Attempting to truncate the log to 1
I0206 01:37:29.493958 2847 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0206 01:37:29.494138 2852 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0206 01:37:29.494397 2850 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0206 01:37:29.496155 2850 replica.cpp:537] Replica received write request for position 2 from (14400)@172.17.0.8:37843
I0206 01:37:29.531819 2850 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 35.583922ms
I0206 01:37:29.531920 2850 replica.cpp:712] Persisted action at 2
I0206 01:37:29.533265 2846 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0206 01:37:29.556874 2846 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.561286ms
I0206 01:37:29.556985 2846 leveldb.cpp:399] Deleting ~1 keys from leveldb took 51812ns
I0206 01:37:29.557010 2846 replica.cpp:712] Persisted action at 2
I0206 01:37:29.557040 2846 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0206 01:37:29.567399 2824 scheduler.cpp:154] Version: 0.28.0
I0206 01:37:29.570505 2844 scheduler.cpp:236] New master detected at master@172.17.0.8:37843
I0206 01:37:29.573139 2856 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.8:37843
I0206 01:37:29.577590 2852 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 01:37:29.578420 2852 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.8:47821
I0206 01:37:29.579581 2852 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0206 01:37:29.579772 2852 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0206 01:37:29.580469 2852 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0206 01:37:29.583930 2854 hierarchical.cpp:265] Added framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000
I0206 01:37:29.584069 2854 hierarchical.cpp:1403] No resources available to allocate!
I0206 01:37:29.584120 2854 hierarchical.cpp:1498] No inverse offers to send out!
I0206 01:37:29.584152 2854 hierarchical.cpp:1096] Performed allocation for 0 slaves in 165356ns
I0206 01:37:29.584631 2854 master.hpp:1658] Sending heartbeat to 675a84d4-4d67-4255-b221-c63e4669bf79-0000
I0206 01:37:29.586541 2855 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.8:37843
I0206 01:37:29.588764 2855 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.8:37843
I0206 01:37:29.593571 2850 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.8:37843
I0206 01:37:29.594358 2850 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0206 01:37:29.596145 2850 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 01:37:29.596845 2850 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.8:47822
I0206 01:37:29.597326 2850 master.cpp:2719] Processing REQUEST call for framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000 (default)
I0206 01:37:29.597625 2850 hierarchical.cpp:589] Received resource request from framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000
I0206 01:37:29.599362 2824 master.cpp:1027] Master terminating
I0206 01:37:29.599967 2849 hierarchical.cpp:326] Removed framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000
E0206 01:37:29.601402 2856 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (574 ms)
[----------] 22 tests from ContentType/SchedulerTest (15447 ms total)
[----------] Global test environment tear-down
2016-02-06 01:37:30,165:2824(0x7f25627fc700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:35224] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
[==========] 975 tests from 128 test cases ran. (759070 ms total)
[ PASSED ] 974 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ContainerLoggerTest.DefaultToSandbox
1 FAILED TEST
YOU HAVE 9 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1454720011-2393
Untagged: mesos-1454720011-2393:latest
Deleted: e42c99fcaa72eb17cc1611376204fd545950000d1790a9268348eecfbe30fde8
Deleted: 185e0e3627ee9ba9e7f301d825100654545b1372782cd9d8f8e13a6d5a268194
Deleted: 1cfb55e4d4cc6825a688ab1754693915bc6a298f02a78709e1802cb846f14d9b
Deleted: 9c1de076fef7cc3437e97beb23056d01b7ccc2e760d45794fd4663740a9b6336
Deleted: fdc9e659e296cb00bbe5a898b7d579a4f22dc92511bb663a369d7c27b7ed9609
Deleted: 128bc0580c75f17df712dc6444eb8fe4b4ca7a2c4e8eb26cb3a8f283c267c68b
Deleted: f82740bbdbbfc84ccbd5ab8f8bea8a6d836107d5abe6f8bd5aee9ebc38d1f51c
Deleted: fa86e52e13d0b16978d9d4054daa7c93d69f6d2011038e6d3bde292e986ad49a
Deleted: 00fecc2a5e8492452e7fc63e4c0a4a17a4a3b3460cd810c2e8e07e76d420cf8e
Deleted: 02681e291122d4d87e6e37d61a92e9bfdc9cf8fa00f7aa5e40fb2e10ec10ba94
Deleted: 181b660e5c6ab86421c2fd12552119d635981cfd09b25b22d9e5540346b4127c
Deleted: c0afc00571a7808ede99b86b6be19c862ca541fc62bf9903a352b12849efed42
Deleted: d69ed4589d9496dc861c5d922e0fe8a09f2ddafefa36576d5a80d4a2002062ed
Deleted: 28508a2d7ecb6046efd260b46698d0100a7d7fe059ff208f91dc6db22a633f73
Deleted: bd17c85774c72a7e2d71b965cefca5569bef2c360923f5ed2eacca24e95baac4
Deleted: 8dc0ccb1195d7dfd955154b09a7368e0886ac2c9667b8e6cd020dccfb698592c
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,centos:7,docker||Hadoop #1633
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=centos%3A7,label_exp=docker%7C%7CHadoop/1633/changes>
Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1632
Posted by Greg Mann <gr...@mesosphere.io>.
Looks like we didn't have a ticket for this one, so I created one here:
https://issues.apache.org/jira/browse/MESOS-4615
On Fri, Feb 5, 2016 at 5:37 PM, 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=centos%3A7,label_exp=docker%7C%7CHadoop/1632/changes
> >
>
> Changes:
>
> [yujie.jay] Added helper functions in cgroup for supporting net_cls
> subsystem.
>
> ------------------------------------------
> [...truncated 167635 lines...]
> [ OK ] ContentType/SchedulerTest.Message/1 (616 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> I0206 01:37:28.679303 2824 leveldb.cpp:174] Opened db in 76.966471ms
> I0206 01:37:28.704545 2824 leveldb.cpp:181] Compacted db in 25.081696ms
> I0206 01:37:28.704617 2824 leveldb.cpp:196] Created db iterator in 30299ns
> I0206 01:37:28.704635 2824 leveldb.cpp:202] Seeked to beginning of db in
> 1620ns
> I0206 01:37:28.704643 2824 leveldb.cpp:271] Iterated through 0 keys in
> the db in 268ns
> I0206 01:37:28.704689 2824 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0206 01:37:28.705224 2856 recover.cpp:447] Starting replica recovery
> I0206 01:37:28.705482 2856 recover.cpp:473] Replica is in EMPTY status
> I0206 01:37:28.706450 2855 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14378)@172.17.0.8:37843
> I0206 01:37:28.706908 2852 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0206 01:37:28.707272 2855 recover.cpp:564] Updating replica status to
> STARTING
> I0206 01:37:28.707988 2848 master.cpp:376] Master
> f8669fe3-e905-45e0-8063-a4968d66a97e (74ef606c4063) started on
> 172.17.0.8:37843
> I0206 01:37:28.708010 2848 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/z2SLxg/credentials"
> --framework_sorter="drf" --help="false" --hostname_lookup="true"
> --http_authenticators="basic" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
> --max_slave_ping_timeouts="5" --quiet="false"
> --recovery_slave_removal_limit="100%" --registry="replicated_log"
> --registry_fetch_timeout="1mins" --registry_store_timeout="100secs"
> --registry_strict="true" --root_submissions="true"
> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui"
> --work_dir="/tmp/z2SLxg/master" --zk_session_timeout="10secs"
> I0206 01:37:28.708268 2848 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0206 01:37:28.708278 2848 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0206 01:37:28.708284 2848 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/z2SLxg/credentials'
> I0206 01:37:28.708549 2848 master.cpp:468] Using default 'crammd5'
> authenticator
> I0206 01:37:28.708688 2848 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0206 01:37:28.708824 2848 master.cpp:571] Authorization enabled
> I0206 01:37:28.708986 2856 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0206 01:37:28.709018 2846 whitelist_watcher.cpp:77] No whitelist given
> I0206 01:37:28.710572 2851 master.cpp:1712] The newly elected leader is
> master@172.17.0.8:37843 with id f8669fe3-e905-45e0-8063-a4968d66a97e
> I0206 01:37:28.710618 2851 master.cpp:1725] Elected as the leading master!
> I0206 01:37:28.710708 2851 master.cpp:1470] Recovering from registrar
> I0206 01:37:28.710876 2843 registrar.cpp:307] Recovering registrar
> I0206 01:37:28.729671 2853 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 22.184621ms
> I0206 01:37:28.729707 2853 replica.cpp:320] Persisted replica status to
> STARTING
> I0206 01:37:28.729889 2852 recover.cpp:473] Replica is in STARTING status
> I0206 01:37:28.730726 2858 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14380)@172.17.0.8:37843
> I0206 01:37:28.731076 2857 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0206 01:37:28.731470 2852 recover.cpp:564] Updating replica status to
> VOTING
> I0206 01:37:28.761700 2844 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.01581ms
> I0206 01:37:28.761744 2844 replica.cpp:320] Persisted replica status to
> VOTING
> I0206 01:37:28.761885 2848 recover.cpp:578] Successfully joined the Paxos
> group
> I0206 01:37:28.762101 2848 recover.cpp:462] Recover process terminated
> I0206 01:37:28.762521 2857 log.cpp:659] Attempting to start the writer
> I0206 01:37:28.763659 2855 replica.cpp:493] Replica received implicit
> promise request from (14381)@172.17.0.8:37843 with proposal 1
> I0206 01:37:28.795686 2855 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 31.930403ms
> I0206 01:37:28.795774 2855 replica.cpp:342] Persisted promised to 1
> I0206 01:37:28.798104 2857 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0206 01:37:28.799767 2845 replica.cpp:388] Replica received explicit
> promise request from (14382)@172.17.0.8:37843 for position 0 with
> proposal 2
> I0206 01:37:28.828594 2845 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 28.759079ms
> I0206 01:37:28.828680 2845 replica.cpp:712] Persisted action at 0
> I0206 01:37:28.829860 2846 replica.cpp:537] Replica received write
> request for position 0 from (14383)@172.17.0.8:37843
> I0206 01:37:28.829978 2846 leveldb.cpp:436] Reading position from leveldb
> took 39903ns
> I0206 01:37:28.862035 2846 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 31.989561ms
> I0206 01:37:28.862118 2846 replica.cpp:712] Persisted action at 0
> I0206 01:37:28.863024 2847 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0206 01:37:28.895505 2847 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 32.258897ms
> I0206 01:37:28.895747 2847 replica.cpp:712] Persisted action at 0
> I0206 01:37:28.895907 2847 replica.cpp:697] Replica learned NOP action at
> position 0
> I0206 01:37:28.896898 2847 log.cpp:675] Writer started with ending
> position 0
> I0206 01:37:28.898190 2855 leveldb.cpp:436] Reading position from leveldb
> took 45954ns
> I0206 01:37:28.899428 2855 registrar.cpp:340] Successfully fetched the
> registry (0B) in 188.497152ms
> I0206 01:37:28.899546 2855 registrar.cpp:439] Applied 1 operations in
> 35031ns; attempting to update the 'registry'
> I0206 01:37:28.900215 2852 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0206 01:37:28.900336 2854 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0206 01:37:28.901142 2858 replica.cpp:537] Replica received write
> request for position 1 from (14384)@172.17.0.8:37843
> I0206 01:37:28.948690 2858 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 47.491423ms
> I0206 01:37:28.948776 2858 replica.cpp:712] Persisted action at 1
> I0206 01:37:28.950230 2845 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0206 01:37:28.979105 2845 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 28.765891ms
> I0206 01:37:28.979189 2845 replica.cpp:712] Persisted action at 1
> I0206 01:37:28.979225 2845 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0206 01:37:28.981251 2845 registrar.cpp:484] Successfully updated the
> 'registry' in 81.641984ms
> I0206 01:37:28.981386 2845 registrar.cpp:370] Successfully recovered
> registrar
> I0206 01:37:28.981757 2855 log.cpp:702] Attempting to truncate the log to
> 1
> I0206 01:37:28.982208 2851 master.cpp:1522] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0206 01:37:28.982374 2853 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0206 01:37:28.983206 2858 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0206 01:37:28.984444 2853 replica.cpp:537] Replica received write
> request for position 2 from (14385)@172.17.0.8:37843
> I0206 01:37:29.012693 2853 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 28.184253ms
> I0206 01:37:29.012780 2853 replica.cpp:712] Persisted action at 2
> I0206 01:37:29.014279 2854 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0206 01:37:29.046149 2854 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 31.824309ms
> I0206 01:37:29.046288 2854 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 66669ns
> I0206 01:37:29.046309 2854 replica.cpp:712] Persisted action at 2
> I0206 01:37:29.046340 2854 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0206 01:37:29.055670 2824 scheduler.cpp:154] Version: 0.28.0
> I0206 01:37:29.056491 2854 scheduler.cpp:236] New master detected at
> master@172.17.0.8:37843
> I0206 01:37:29.057818 2851 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.8:37843
> I0206 01:37:29.059360 2855 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0206 01:37:29.059873 2858 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.8:47819
> I0206 01:37:29.060060 2858 master.cpp:1974] Received subscription request
> for HTTP framework 'default'
> I0206 01:37:29.060124 2858 master.cpp:1751] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0206 01:37:29.060379 2858 master.cpp:2065] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0206 01:37:29.060849 2858 master.hpp:1658] Sending heartbeat to
> f8669fe3-e905-45e0-8063-a4968d66a97e-0000
> I0206 01:37:29.060854 2855 hierarchical.cpp:265] Added framework
> f8669fe3-e905-45e0-8063-a4968d66a97e-0000
> I0206 01:37:29.061645 2853 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.8:37843
> I0206 01:37:29.061714 2855 hierarchical.cpp:1403] No resources available
> to allocate!
> I0206 01:37:29.061748 2855 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0206 01:37:29.061771 2855 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 117038ns
> I0206 01:37:29.062062 2853 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.8:37843
> I0206 01:37:29.062855 2853 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.8:37843
> I0206 01:37:29.062923 2850 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0206 01:37:29.064299 2843 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0206 01:37:29.064771 2855 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.8:47820
> I0206 01:37:29.064883 2855 master.cpp:2719] Processing REQUEST call for
> framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000 (default)
> I0206 01:37:29.065019 2844 hierarchical.cpp:589] Received resource
> request from framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000
> I0206 01:37:29.065457 2845 master.cpp:1027] Master terminating
> I0206 01:37:29.065826 2850 hierarchical.cpp:326] Removed framework
> f8669fe3-e905-45e0-8063-a4968d66a97e-0000
> E0206 01:37:29.066864 2844 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/0 (472 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> I0206 01:37:29.180583 2824 leveldb.cpp:174] Opened db in 105.938866ms
> I0206 01:37:29.223002 2824 leveldb.cpp:181] Compacted db in 42.322554ms
> I0206 01:37:29.223089 2824 leveldb.cpp:196] Created db iterator in 21712ns
> I0206 01:37:29.223108 2824 leveldb.cpp:202] Seeked to beginning of db in
> 2666ns
> I0206 01:37:29.223119 2824 leveldb.cpp:271] Iterated through 0 keys in
> the db in 322ns
> I0206 01:37:29.223170 2824 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0206 01:37:29.223954 2847 recover.cpp:447] Starting replica recovery
> I0206 01:37:29.224406 2847 recover.cpp:473] Replica is in EMPTY status
> I0206 01:37:29.226563 2843 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14393)@172.17.0.8:37843
> I0206 01:37:29.226886 2849 master.cpp:376] Master
> 675a84d4-4d67-4255-b221-c63e4669bf79 (74ef606c4063) started on
> 172.17.0.8:37843
> I0206 01:37:29.226912 2849 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/K1hwPx/credentials"
> --framework_sorter="drf" --help="false" --hostname_lookup="true"
> --http_authenticators="basic" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
> --max_slave_ping_timeouts="5" --quiet="false"
> --recovery_slave_removal_limit="100%" --registry="replicated_log"
> --registry_fetch_timeout="1mins" --registry_store_timeout="100secs"
> --registry_strict="true" --root_submissions="true"
> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui"
> --work_dir="/tmp/K1hwPx/master" --zk_session_timeout="10secs"
> I0206 01:37:29.227296 2849 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0206 01:37:29.227314 2849 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0206 01:37:29.227325 2849 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/K1hwPx/credentials'
> I0206 01:37:29.227718 2849 master.cpp:468] Using default 'crammd5'
> authenticator
> I0206 01:37:29.227891 2849 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0206 01:37:29.228045 2849 master.cpp:571] Authorization enabled
> I0206 01:37:29.229780 2850 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0206 01:37:29.229867 2850 whitelist_watcher.cpp:77] No whitelist given
> I0206 01:37:29.231914 2852 master.cpp:1712] The newly elected leader is
> master@172.17.0.8:37843 with id 675a84d4-4d67-4255-b221-c63e4669bf79
> I0206 01:37:29.231953 2852 master.cpp:1725] Elected as the leading master!
> I0206 01:37:29.231976 2852 master.cpp:1470] Recovering from registrar
> I0206 01:37:29.232695 2857 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0206 01:37:29.234669 2857 recover.cpp:564] Updating replica status to
> STARTING
> I0206 01:37:29.235661 2852 registrar.cpp:307] Recovering registrar
> I0206 01:37:29.256692 2843 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 21.762851ms
> I0206 01:37:29.256778 2843 replica.cpp:320] Persisted replica status to
> STARTING
> I0206 01:37:29.257149 2843 recover.cpp:473] Replica is in STARTING status
> I0206 01:37:29.258880 2854 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14395)@172.17.0.8:37843
> I0206 01:37:29.259963 2854 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0206 01:37:29.260452 2851 recover.cpp:564] Updating replica status to
> VOTING
> I0206 01:37:29.281335 2852 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 20.649922ms
> I0206 01:37:29.281419 2852 replica.cpp:320] Persisted replica status to
> VOTING
> I0206 01:37:29.281673 2847 recover.cpp:578] Successfully joined the Paxos
> group
> I0206 01:37:29.281895 2847 recover.cpp:462] Recover process terminated
> I0206 01:37:29.282418 2851 log.cpp:659] Attempting to start the writer
> I0206 01:37:29.283726 2858 replica.cpp:493] Replica received implicit
> promise request from (14396)@172.17.0.8:37843 with proposal 1
> I0206 01:37:29.306686 2858 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 22.909015ms
> I0206 01:37:29.306771 2858 replica.cpp:342] Persisted promised to 1
> I0206 01:37:29.308105 2843 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0206 01:37:29.310048 2848 replica.cpp:388] Replica received explicit
> promise request from (14397)@172.17.0.8:37843 for position 0 with
> proposal 2
> I0206 01:37:29.331552 2848 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 21.429846ms
> I0206 01:37:29.331781 2848 replica.cpp:712] Persisted action at 0
> I0206 01:37:29.334182 2848 replica.cpp:537] Replica received write
> request for position 0 from (14398)@172.17.0.8:37843
> I0206 01:37:29.334568 2848 leveldb.cpp:436] Reading position from leveldb
> took 42222ns
> I0206 01:37:29.358628 2848 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 23.773959ms
> I0206 01:37:29.358721 2848 replica.cpp:712] Persisted action at 0
> I0206 01:37:29.359482 2848 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0206 01:37:29.392504 2848 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 32.969204ms
> I0206 01:37:29.392735 2848 replica.cpp:712] Persisted action at 0
> I0206 01:37:29.392900 2848 replica.cpp:697] Replica learned NOP action at
> position 0
> I0206 01:37:29.394258 2853 log.cpp:675] Writer started with ending
> position 0
> I0206 01:37:29.395711 2853 leveldb.cpp:436] Reading position from leveldb
> took 77483ns
> I0206 01:37:29.397044 2849 registrar.cpp:340] Successfully fetched the
> registry (0B) in 161.316864ms
> I0206 01:37:29.397209 2849 registrar.cpp:439] Applied 1 operations in
> 43170ns; attempting to update the 'registry'
> I0206 01:37:29.398488 2851 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0206 01:37:29.398684 2846 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0206 01:37:29.400101 2846 replica.cpp:537] Replica received write
> request for position 1 from (14399)@172.17.0.8:37843
> I0206 01:37:29.442999 2846 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 42.830963ms
> I0206 01:37:29.443084 2846 replica.cpp:712] Persisted action at 1
> I0206 01:37:29.444593 2857 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0206 01:37:29.491114 2857 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 46.373782ms
> I0206 01:37:29.491322 2857 replica.cpp:712] Persisted action at 1
> I0206 01:37:29.491787 2857 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0206 01:37:29.493533 2847 registrar.cpp:484] Successfully updated the
> 'registry' in 96.251904ms
> I0206 01:37:29.493690 2847 registrar.cpp:370] Successfully recovered
> registrar
> I0206 01:37:29.493767 2852 log.cpp:702] Attempting to truncate the log to
> 1
> I0206 01:37:29.493958 2847 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0206 01:37:29.494138 2852 master.cpp:1522] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0206 01:37:29.494397 2850 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0206 01:37:29.496155 2850 replica.cpp:537] Replica received write
> request for position 2 from (14400)@172.17.0.8:37843
> I0206 01:37:29.531819 2850 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 35.583922ms
> I0206 01:37:29.531920 2850 replica.cpp:712] Persisted action at 2
> I0206 01:37:29.533265 2846 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0206 01:37:29.556874 2846 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 23.561286ms
> I0206 01:37:29.556985 2846 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 51812ns
> I0206 01:37:29.557010 2846 replica.cpp:712] Persisted action at 2
> I0206 01:37:29.557040 2846 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0206 01:37:29.567399 2824 scheduler.cpp:154] Version: 0.28.0
> I0206 01:37:29.570505 2844 scheduler.cpp:236] New master detected at
> master@172.17.0.8:37843
> I0206 01:37:29.573139 2856 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.8:37843
> I0206 01:37:29.577590 2852 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0206 01:37:29.578420 2852 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.8:47821
> I0206 01:37:29.579581 2852 master.cpp:1974] Received subscription request
> for HTTP framework 'default'
> I0206 01:37:29.579772 2852 master.cpp:1751] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0206 01:37:29.580469 2852 master.cpp:2065] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0206 01:37:29.583930 2854 hierarchical.cpp:265] Added framework
> 675a84d4-4d67-4255-b221-c63e4669bf79-0000
> I0206 01:37:29.584069 2854 hierarchical.cpp:1403] No resources available
> to allocate!
> I0206 01:37:29.584120 2854 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0206 01:37:29.584152 2854 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 165356ns
> I0206 01:37:29.584631 2854 master.hpp:1658] Sending heartbeat to
> 675a84d4-4d67-4255-b221-c63e4669bf79-0000
> I0206 01:37:29.586541 2855 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.8:37843
> I0206 01:37:29.588764 2855 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.8:37843
> I0206 01:37:29.593571 2850 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.8:37843
> I0206 01:37:29.594358 2850 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0206 01:37:29.596145 2850 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0206 01:37:29.596845 2850 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.8:47822
> I0206 01:37:29.597326 2850 master.cpp:2719] Processing REQUEST call for
> framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000 (default)
> I0206 01:37:29.597625 2850 hierarchical.cpp:589] Received resource
> request from framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000
> I0206 01:37:29.599362 2824 master.cpp:1027] Master terminating
> I0206 01:37:29.599967 2849 hierarchical.cpp:326] Removed framework
> 675a84d4-4d67-4255-b221-c63e4669bf79-0000
> E0206 01:37:29.601402 2856 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (574 ms)
> [----------] 22 tests from ContentType/SchedulerTest (15447 ms total)
>
> [----------] Global test environment tear-down
> 2016-02-06
> 01:37:30,165:2824(0x7f25627fc700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:35224] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> [==========] 975 tests from 128 test cases ran. (759070 ms total)
> [ PASSED ] 974 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] ContainerLoggerTest.DefaultToSandbox
>
> 1 FAILED TEST
> YOU HAVE 9 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1454720011-2393
> Untagged: mesos-1454720011-2393:latest
> Deleted: e42c99fcaa72eb17cc1611376204fd545950000d1790a9268348eecfbe30fde8
> Deleted: 185e0e3627ee9ba9e7f301d825100654545b1372782cd9d8f8e13a6d5a268194
> Deleted: 1cfb55e4d4cc6825a688ab1754693915bc6a298f02a78709e1802cb846f14d9b
> Deleted: 9c1de076fef7cc3437e97beb23056d01b7ccc2e760d45794fd4663740a9b6336
> Deleted: fdc9e659e296cb00bbe5a898b7d579a4f22dc92511bb663a369d7c27b7ed9609
> Deleted: 128bc0580c75f17df712dc6444eb8fe4b4ca7a2c4e8eb26cb3a8f283c267c68b
> Deleted: f82740bbdbbfc84ccbd5ab8f8bea8a6d836107d5abe6f8bd5aee9ebc38d1f51c
> Deleted: fa86e52e13d0b16978d9d4054daa7c93d69f6d2011038e6d3bde292e986ad49a
> Deleted: 00fecc2a5e8492452e7fc63e4c0a4a17a4a3b3460cd810c2e8e07e76d420cf8e
> Deleted: 02681e291122d4d87e6e37d61a92e9bfdc9cf8fa00f7aa5e40fb2e10ec10ba94
> Deleted: 181b660e5c6ab86421c2fd12552119d635981cfd09b25b22d9e5540346b4127c
> Deleted: c0afc00571a7808ede99b86b6be19c862ca541fc62bf9903a352b12849efed42
> Deleted: d69ed4589d9496dc861c5d922e0fe8a09f2ddafefa36576d5a80d4a2002062ed
> Deleted: 28508a2d7ecb6046efd260b46698d0100a7d7fe059ff208f91dc6db22a633f73
> Deleted: bd17c85774c72a7e2d71b965cefca5569bef2c360923f5ed2eacca24e95baac4
> Deleted: 8dc0ccb1195d7dfd955154b09a7368e0886ac2c9667b8e6cd020dccfb698592c
> Build step 'Execute shell' marked build as failure
>