mesos-builds mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4573
Date Mon, 11 Dec 2017 17:52:14 GMT
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4573/display/redirect?page=changes>

Changes:

[gregorywmann] Fixed a comment in the master.

[gregorywmann] Edited some comments in the master for style and grammar.

------------------------------------------
[...truncated 6.43 MB...]
3: W1211 17:51:54.727504 15997 master.cpp:2345] Dropping TEARDOWN call from framework a955dd67-201f-4e69-a0d0-2eb0dffbbd1c-0000
at scheduler-bcf182a2-9090-4f1e-9ec4-231109bd12d2@172.17.0.4:58832: Call is not from registered
framework
3: I1211 17:51:54.728214 15995 master.cpp:1152] Master terminating
3: I1211 17:51:54.728696 16003 hierarchical.cpp:344] Removed framework a955dd67-201f-4e69-a0d0-2eb0dffbbd1c-0000
3: [       OK ] RateLimitingTest.SchedulerFailover (103 ms)
3: [ RUN      ] RateLimitingTest.CapacityReached
3: I1211 17:51:54.735558 15995 cluster.cpp:172] Creating default 'local' authorizer
3: I1211 17:51:54.739301 16008 master.cpp:456] Master 5f271f27-d22e-439c-a50a-17cd0979d2ca
(e25451cbd691) started on 172.17.0.4:58832
3: I1211 17:51:54.739343 16008 master.cpp:458] Flags at startup: --acls="" --agent_ping_timeout="15secs"
--agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF"
--authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/VWmZxn/credentials" --filter_gpu_resources="true"
--framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic"
--http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true"
--logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50"
--max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050"
--quiet="false" --rate_limits="limits {
3:   qps: 1
3:   principal: "test-principal"
3:   capacity: 2
3: }
3: " --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins"
--registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400"
--registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf"
--version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/VWmZxn/master"
--zk_session_timeout="10secs"
3: I1211 17:51:54.739778 16008 master.cpp:507] Master only allowing authenticated frameworks
to register
3: I1211 17:51:54.739794 16008 master.cpp:513] Master only allowing authenticated agents to
register
3: I1211 17:51:54.739804 16008 master.cpp:519] Master only allowing authenticated HTTP frameworks
to register
3: I1211 17:51:54.739814 16008 credentials.hpp:37] Loading credentials for authentication
from '/tmp/VWmZxn/credentials'
3: I1211 17:51:54.740146 16008 master.cpp:563] Using default 'crammd5' authenticator
3: I1211 17:51:54.740310 16008 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readonly'
3: I1211 17:51:54.740490 16008 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readwrite'
3: I1211 17:51:54.740666 16008 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-scheduler'
3: I1211 17:51:54.740828 16008 master.cpp:642] Authorization enabled
3: I1211 17:51:54.741003 16008 master.cpp:692] Framework rate limiting enabled
3: I1211 17:51:54.741206 16010 whitelist_watcher.cpp:77] No whitelist given
3: I1211 17:51:54.741226 16000 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1211 17:51:54.744117 16006 master.cpp:2214] Elected as the leading master!
3: I1211 17:51:54.744137 16006 master.cpp:1694] Recovering from registrar
3: I1211 17:51:54.744242 16011 registrar.cpp:347] Recovering registrar
3: I1211 17:51:54.744964 16011 registrar.cpp:391] Successfully fetched the registry (0B) in
692992ns
3: I1211 17:51:54.745091 16011 registrar.cpp:495] Applied 1 operations in 46434ns; attempting
to update the registry
3: I1211 17:51:54.745687 16011 registrar.cpp:552] Successfully updated the registry in 538880ns
3: I1211 17:51:54.745826 16011 registrar.cpp:424] Successfully recovered registrar
3: I1211 17:51:54.746304 16000 master.cpp:1807] Recovered 0 agents from the registry (135B);
allowing 10mins for agents to re-register
3: I1211 17:51:54.746368 16003 hierarchical.cpp:213] Skipping recovery of hierarchical allocator:
nothing to recover
3: W1211 17:51:54.747031 15995 process.cpp:2756] Attempted to spawn already running process
version@172.17.0.4:58832
3: I1211 17:51:54.747949 15995 sched.cpp:232] Version: 1.5.0
3: I1211 17:51:54.748503 16005 sched.cpp:336] New master detected at master@172.17.0.4:58832
3: I1211 17:51:54.748582 16005 sched.cpp:396] Authenticating with master master@172.17.0.4:58832
3: I1211 17:51:54.748594 16005 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1211 17:51:54.748844 16009 authenticatee.cpp:121] Creating new client SASL connection
3: I1211 17:51:54.749202 16001 master.cpp:8877] Authenticating scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832
3: I1211 17:51:54.749367 16008 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(288)@172.17.0.4:58832
3: I1211 17:51:54.749681 16004 authenticator.cpp:98] Creating new server SASL connection
3: I1211 17:51:54.749963 16011 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1211 17:51:54.749990 16011 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1211 17:51:54.750108 16002 authenticator.cpp:204] Received SASL authentication start
3: I1211 17:51:54.750175 16002 authenticator.cpp:326] Authentication requires more steps
3: I1211 17:51:54.750349 16002 authenticatee.cpp:259] Received SASL authentication step
3: I1211 17:51:54.750541 16000 authenticator.cpp:232] Received SASL authentication step
3: I1211 17:51:54.750576 16000 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'e25451cbd691' server FQDN: 'e25451cbd691' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1211 17:51:54.750589 16000 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1211 17:51:54.750629 16000 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1211 17:51:54.750653 16000 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'e25451cbd691' server FQDN: 'e25451cbd691' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1211 17:51:54.750666 16000 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1211 17:51:54.750677 16000 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1211 17:51:54.750699 16000 authenticator.cpp:318] Authentication success
3: I1211 17:51:54.750828 16003 authenticatee.cpp:299] Authentication success
3: I1211 17:51:54.750988 15997 master.cpp:8907] Successfully authenticated principal 'test-principal'
at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832
3: I1211 17:51:54.751044 16007 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(288)@172.17.0.4:58832
3: I1211 17:51:54.751251 16003 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:58832
3: I1211 17:51:54.751272 16003 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:58832
3: I1211 17:51:54.751404 16003 sched.cpp:857] Will retry registration in 18.366916ms if necessary
3: I1211 17:51:54.751834 16009 master.cpp:2962] Received SUBSCRIBE call for framework 'default'
at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832
3: I1211 17:51:54.751862 16009 master.cpp:2279] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1211 17:51:54.752538 16008 master.cpp:3042] Subscribing framework default with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1211 17:51:54.752728 16008 master.cpp:9098] Adding framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
(default) at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832 with roles {
 } suppressed
3: I1211 17:51:54.753301 16006 sched.cpp:751] Framework registered with 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
3: I1211 17:51:54.753355 16006 sched.cpp:765] Scheduler::registered took 28755ns
3: I1211 17:51:54.753407 16004 hierarchical.cpp:297] Added framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
3: I1211 17:51:54.753628 16004 hierarchical.cpp:1513] Performed allocation for 0 agents in
89012ns
3: I1211 17:51:54.754324 16005 master.cpp:2962] Received SUBSCRIBE call for framework 'default'
at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832
3: I1211 17:51:54.754353 16005 master.cpp:2279] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1211 17:51:54.754910 15997 master.cpp:3042] Subscribing framework default with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1211 17:51:54.754952 15997 master.cpp:3052] Framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
(default) at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832 already subscribed,
resending acknowledgement
3: I1211 17:51:54.755218 15998 sched.cpp:738] Ignoring framework registered message because
the driver is already connected!
3: I1211 17:51:54.757599 16003 process.cpp:3503] Handling HTTP event for process 'metrics'
with path: '/metrics/snapshot'
3: I1211 17:51:54.758781 16011 http.cpp:851] Authorizing principal 'ANY' to GET the endpoint
'/metrics/snapshot'
3: W1211 17:51:54.777987 16008 master.cpp:1656] Dropping message mesos.scheduler.Call from
scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832(test-principal): capacity(2)
exceeded
3: I1211 17:51:54.778164 16008 sched.cpp:1175] Got error 'Message mesos.scheduler.Call dropped:
capacity(2) exceeded'
3: I1211 17:51:54.778213 16008 sched.cpp:2043] Asked to abort the driver
3: I1211 17:51:54.778288 16008 sched.cpp:1186] Scheduler::error took 43526ns
3: I1211 17:51:54.778353 16008 sched.cpp:1221] Aborting framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
3: W1211 17:51:54.778632 16008 master.cpp:1656] Dropping message mesos.internal.DeactivateFrameworkMessage
from scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832(test-principal): capacity(2)
exceeded
3: I1211 17:51:54.778795 16008 sched.cpp:1171] Ignoring error message because the driver is
not running!
3: I1211 17:51:54.778879 15995 sched.cpp:2009] Asked to stop the driver
3: I1211 17:51:54.781661 16011 process.cpp:3503] Handling HTTP event for process 'metrics'
with path: '/metrics/snapshot'
3: I1211 17:51:54.782874 15996 http.cpp:851] Authorizing principal 'ANY' to GET the endpoint
'/metrics/snapshot'
3: I1211 17:51:54.803390 16001 hierarchical.cpp:1513] Performed allocation for 0 agents in
103079ns
3: I1211 17:51:54.803524 16011 master.cpp:2962] Received SUBSCRIBE call for framework 'default'
at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832
3: I1211 17:51:54.803555 16011 master.cpp:2279] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1211 17:51:54.804180 16005 master.cpp:3042] Subscribing framework default with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1211 17:51:54.804216 16005 master.cpp:3052] Framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
(default) at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832 already subscribed,
resending acknowledgement
3: I1211 17:51:54.806154 16008 hierarchical.cpp:1513] Performed allocation for 0 agents in
134339ns
3: I1211 17:51:54.806385 16004 master.cpp:2962] Received SUBSCRIBE call for framework 'default'
at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832
3: I1211 17:51:54.806411 16004 master.cpp:2279] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1211 17:51:54.806975 16002 master.cpp:3042] Subscribing framework default with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1211 17:51:54.807009 16002 master.cpp:3052] Framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
(default) at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832 already subscribed,
resending acknowledgement
3: I1211 17:51:54.808806 16009 hierarchical.cpp:1513] Performed allocation for 0 agents in
78848ns
3: I1211 17:51:54.808866 16010 master.cpp:1424] Framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
(default) at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832 disconnected
3: I1211 17:51:54.808903 16010 master.cpp:3332] Deactivating framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
(default) at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832
3: I1211 17:51:54.808969 16010 master.cpp:3309] Disconnecting framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
(default) at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832
3: I1211 17:51:54.809007 16010 master.cpp:1439] Giving framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
(default) at scheduler-b661965e-2228-4b4c-8e68-173e12f989b0@172.17.0.4:58832 10secs to failover
3: I1211 17:51:54.809108 16009 hierarchical.cpp:405] Deactivated framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
3: I1211 17:51:54.811197 16011 process.cpp:3503] Handling HTTP event for process 'metrics'
with path: '/metrics/snapshot'
3: I1211 17:51:54.812304 16000 http.cpp:851] Authorizing principal 'ANY' to GET the endpoint
'/metrics/snapshot'
3: I1211 17:51:54.832934 15995 master.cpp:1152] Master terminating
3: I1211 17:51:54.833683 15996 hierarchical.cpp:344] Removed framework 5f271f27-d22e-439c-a50a-17cd0979d2ca-0000
3: [       OK ] RateLimitingTest.CapacityReached (105 ms)
3: [----------] 6 tests from RateLimitingTest (633 ms total)
3: 
3: [----------] 5 tests from ResourceOffersTest
3: [ RUN      ] ResourceOffersTest.ResourceOfferWithMultipleSlaves
3: I1211 17:51:54.841336 15995 cluster.cpp:172] Creating default 'local' authorizer
3: I1211 17:51:54.845211 15999 master.cpp:456] Master d9513a1e-8a2c-45b1-8554-f34b88f4424f
(e25451cbd691) started on 172.17.0.4:58832
3: I1211 17:51:54.845260 15999 master.cpp:458] Flags at startup: --acls="" --agent_ping_timeout="15secs"
--agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF"
--authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5"
--authorizers="local" --credentials="/tmp/6VhpjL/credentials" --filter_gpu_resources="true"
--framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic"
--http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true"
--logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50"
--max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050"
--quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins"
--registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400"
--registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf"
--version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/6VhpjL/master"
--zk_session_timeout="10secs"
3: I1211 17:51:54.845849 15999 master.cpp:507] Master only allowing authenticated frameworks
to register
3: I1211 17:51:54.845865 15999 master.cpp:513] Master only allowing authenticated agents to
register
3: I1211 17:51:54.845875 15999 master.cpp:519] Master only allowing authenticated HTTP frameworks
to register
3: I1211 17:51:54.845885 15999 credentials.hpp:37] Loading credentials for authentication
from '/tmp/6VhpjL/credentials'
3: I1211 17:51:54.846376 15999 master.cpp:563] Using default 'crammd5' authenticator
3: I1211 17:51:54.846581 15999 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readonly'
3: I1211 17:51:54.846801 15999 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readwrite'
3: I1211 17:51:54.846979 15999 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-scheduler'
3: I1211 17:51:54.847144 15999 master.cpp:642] Authorization enabled
3: I1211 17:51:54.847427 16007 whitelist_watcher.cpp:77] No whitelist given
3: I1211 17:51:54.847568 16006 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1211 17:51:54.850745 16011 master.cpp:2214] Elected as the leading master!
3: I1211 17:51:54.850770 16011 master.cpp:1694] Recovering from registrar
3: I1211 17:51:54.850999 15998 registrar.cpp:347] Recovering registrar
3: I1211 17:51:54.851562 15998 registrar.cpp:391] Successfully fetched the registry (0B) in
518144ns
3: I1211 17:51:54.851691 15998 registrar.cpp:495] Applied 1 operations in 46584ns; attempting
to update the registry
3: I1211 17:51:54.852285 16006 registrar.cpp:552] Successfully updated the registry in 541952ns
3: I1211 17:51:54.852428 16006 registrar.cpp:424] Successfully recovered registrar
3: I1211 17:51:54.852929 16000 master.cpp:1807] Recovered 0 agents from the registry (135B);
allowing 10mins for agents to re-register
3: I1211 17:51:54.852954 16004 hierarchical.cpp:213] Skipping recovery of hierarchical allocator:
nothing to recover
3: W1211 17:51:54.858748 15995 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:58832
3: I1211 17:51:54.859946 15995 containerizer.cpp:304] Using isolation { environment_secret,
posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1211 17:51:54.860560 15995 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend
requires root privileges
3: W1211 17:51:54.860690 15995 backend.cpp:76] Failed to create 'bind' backend: BindBackend
requires root privileges
3: I1211 17:51:54.860728 15995 provisioner.cpp:299] Using default backend 'copy'
3: I1211 17:51:54.863059 15995 cluster.cpp:460] Creating default 'local' authorizer
3: I1211 17:51:54.865875 15997 slave.cpp:258] Mesos agent started on (127)@172.17.0.4:58832
3: I1211 17:51:54.865917 15997 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_cziJVw/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_cziJVw/credential"
--default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins"
--docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"
--docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns"
--docker_store_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_cziJVw/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false"
--executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_cziJVw/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1"
--hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_cziJVw/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms" --resources="cpus:2;mem:1024" --revocable_cpu_low_priority="true"
--runtime_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_cziJVw" --sandbox_directory="/mnt/mesos/sandbox"
--strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system"
--version="false" --work_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_XPFy78"
--zk_session_timeout="10secs"
3: I1211 17:51:54.866593 15997 credentials.hpp:86] Loading credential for authentication from
'/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_cziJVw/credential'
3: I1211 17:51:54.866762 15997 slave.cpp:291] Agent using credential for: test-principal
3: I1211 17:51:54.866801 15997 credentials.hpp:37] Loading credentials for authentication
from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_cziJVw/http_credentials'
3: I1211 17:51:54.867063 15997 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1211 17:51:54.867219 15997 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1211 17:51:54.868732 15997 slave.cpp:590] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":3701220.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1211 17:51:54.869074 15997 slave.cpp:598] Agent attributes: [  ]
3: I1211 17:51:54.869088 15997 slave.cpp:607] Agent hostname: e25451cbd691
3: I1211 17:51:54.869264 16000 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1211 17:51:54.871264 16011 state.cpp:66] Recovering state from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_XPFy78/meta'
3: W1211 17:51:54.871490 15995 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:58832
3: I1211 17:51:54.871587 15998 task_status_update_manager.cpp:207] Recovering task status
update manager
3: I1211 17:51:54.871764 16006 containerizer.cpp:674] Recovering containerizer
3: I1211 17:51:54.872241 15995 containerizer.cpp:304] Using isolation { environment_secret,
posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1211 17:51:54.872736 15995 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend
requires root privileges
3: W1211 17:51:54.872826 15995 backend.cpp:76] Failed to create 'bind' backend: BindBackend
requires root privileges
3: I1211 17:51:54.872850 15995 provisioner.cpp:299] Using default backend 'copy'
3: I1211 17:51:54.873184 16007 provisioner.cpp:495] Provisioner recovery complete
3: I1211 17:51:54.873461 16000 slave.cpp:6727] Finished recovery
3: I1211 17:51:54.874255 16011 slave.cpp:1033] New master detected at master@172.17.0.4:58832
3: I1211 17:51:54.874269 15999 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1211 17:51:54.874348 16011 slave.cpp:1088] Detecting new master
3: I1211 17:51:54.874732 15995 cluster.cpp:460] Creating default 'local' authorizer
3: I1211 17:51:54.877439 16008 slave.cpp:258] Mesos agent started on (128)@172.17.0.4:58832
3: I1211 17:51:54.877476 16008 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_epqaCi/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_epqaCi/credential"
--default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins"
--docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"
--docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns"
--docker_store_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_epqaCi/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false"
--executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_epqaCi/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1"
--hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_epqaCi/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms" --resources="cpus:2;mem:1024" --revocable_cpu_low_priority="true"
--runtime_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_epqaCi" --sandbox_directory="/mnt/mesos/sandbox"
--strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system"
--version="false" --work_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_5DhWLU"
--zk_session_timeout="10secs"
3: I1211 17:51:54.877998 16008 credentials.hpp:86] Loading credential for authentication from
'/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_epqaCi/credential'
3: I1211 17:51:54.878207 16008 slave.cpp:291] Agent using credential for: test-principal
3: I1211 17:51:54.878231 16008 credentials.hpp:37] Loading credentials for authentication
from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_epqaCi/http_credentials'
3: I1211 17:51:54.878490 16008 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1211 17:51:54.878655 16008 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1211 17:51:54.879647 16007 slave.cpp:1115] Authenticating with master master@172.17.0.4:58832
3: I1211 17:51:54.879717 16007 slave.cpp:1124] Using default CRAM-MD5 authenticatee
3: I1211 17:51:54.880017 15996 authenticatee.cpp:121] Creating new client SASL connection
3: I1211 17:51:54.880313 16000 master.cpp:8877] Authenticating slave(127)@172.17.0.4:58832
3: I1211 17:51:54.880100 16008 slave.cpp:590] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":3701220.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1211 17:51:54.880395 16008 slave.cpp:598] Agent attributes: [  ]
3: I1211 17:51:54.880408 16008 slave.cpp:607] Agent hostname: e25451cbd691
3: I1211 17:51:54.880439 15998 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(289)@172.17.0.4:58832
3: I1211 17:51:54.880548 16006 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1211 17:51:54.880672 15997 authenticator.cpp:98] Creating new server SASL connection
3: I1211 17:51:54.880931 16011 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1211 17:51:54.880956 16011 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1211 17:51:54.881058 16003 authenticator.cpp:204] Received SASL authentication start
3: I1211 17:51:54.881108 16003 authenticator.cpp:326] Authentication requires more steps
3: I1211 17:51:54.881206 16005 authenticatee.cpp:259] Received SASL authentication step
3: I1211 17:51:54.881325 16010 authenticator.cpp:232] Received SASL authentication step
3: I1211 17:51:54.881357 16010 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'e25451cbd691' server FQDN: 'e25451cbd691' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1211 17:51:54.881374 16010 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1211 17:51:54.881414 16010 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1211 17:51:54.881444 16010 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: 'e25451cbd691' server FQDN: 'e25451cbd691' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1211 17:51:54.881458 16010 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1211 17:51:54.881466 16010 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1211 17:51:54.881484 16010 authenticator.cpp:318] Authentication success
3: I1211 17:51:54.881566 16004 authenticatee.cpp:299] Authentication success
3: I1211 17:51:54.881690 16009 master.cpp:8907] Successfully authenticated principal 'test-principal'
at slave(127)@172.17.0.4:58832
3: I1211 17:51:54.881816 16007 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(289)@172.17.0.4:58832
3: W1211 17:51:54.881928 15995 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:58832
3: I1211 17:51:54.881958 15999 slave.cpp:1207] Successfully authenticated with master master@172.17.0.4:58832
3: I1211 17:51:54.882257 15999 slave.cpp:1686] Will retry registration in 8.929726ms if necessary
3: I1211 17:51:54.882515 15998 master.cpp:6090] Received register agent message from slave(127)@172.17.0.4:58832
(e25451cbd691)
3: I1211 17:51:54.882552 16003 state.cpp:66] Recovering state from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_5DhWLU/meta'
3: I1211 17:51:54.882586 15998 master.cpp:3871] Authorizing agent with principal 'test-principal'
3: I1211 17:51:54.882858 15995 containerizer.cpp:304] Using isolation { environment_secret,
posix/cpu, posix/mem, filesystem/posix, network/cni }
3: I1211 17:51:54.882900 16001 task_status_update_manager.cpp:207] Recovering task status
update manager
3: I1211 17:51:54.883105 16000 master.cpp:6166] Authorized registration of agent at slave(127)@172.17.0.4:58832
(e25451cbd691)
3: I1211 17:51:54.883117 16009 containerizer.cpp:674] Recovering containerizer
3: I1211 17:51:54.883199 16000 master.cpp:6259] Registering agent at slave(127)@172.17.0.4:58832
(e25451cbd691) with id d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0
3: W1211 17:51:54.883288 15995 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend
requires root privileges
3: W1211 17:51:54.883378 15995 backend.cpp:76] Failed to create 'bind' backend: BindBackend
requires root privileges
3: I1211 17:51:54.883402 15995 provisioner.cpp:299] Using default backend 'copy'
3: I1211 17:51:54.883591 16006 registrar.cpp:495] Applied 1 operations in 103971ns; attempting
to update the registry
3: I1211 17:51:54.884205 16007 registrar.cpp:552] Successfully updated the registry in 542976ns
3: I1211 17:51:54.884454 16005 master.cpp:6308] Admitted agent d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0
at slave(127)@172.17.0.4:58832 (e25451cbd691)
3: I1211 17:51:54.884783 16010 provisioner.cpp:495] Provisioner recovery complete
3: I1211 17:51:54.885149 15995 cluster.cpp:460] Creating default 'local' authorizer
3: I1211 17:51:54.885113 16005 master.cpp:6344] Registered agent d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0
at slave(127)@172.17.0.4:58832 (e25451cbd691) with cpus:2; mem:1024; disk:3701220; ports:[31000-32000]
3: I1211 17:51:54.885305 15998 slave.cpp:1253] Registered with master master@172.17.0.4:58832;
given agent ID d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0
3: I1211 17:51:54.885310 15999 slave.cpp:6727] Finished recovery
3: I1211 17:51:54.885457 16002 task_status_update_manager.cpp:188] Resuming sending task status
updates
3: I1211 17:51:54.885524 16010 hierarchical.cpp:572] Added agent d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0
(e25451cbd691) with cpus:2; mem:1024; disk:3701220; ports:[31000-32000] (allocated: {})
3: I1211 17:51:54.885604 15998 slave.cpp:1273] Checkpointing SlaveInfo to '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_XPFy78/meta/slaves/d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0/slave.info'
3: I1211 17:51:54.885805 16010 hierarchical.cpp:1513] Performed allocation for 1 agents in
136022ns
3: I1211 17:51:54.886049 15998 slave.cpp:1330] Forwarding agent update {"offer_operations":{},"resource_categories":{"oversubscribed":true},"resource_version_uuid":"SzKah4qcQzqBSWxTpsCejA==","slave_id":{"value":"d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0"}}
3: I1211 17:51:54.886234 16005 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1211 17:51:54.886270 16007 slave.cpp:1033] New master detected at master@172.17.0.4:58832
3: I1211 17:51:54.886361 16007 slave.cpp:1088] Detecting new master
3: I1211 17:51:54.886425 16008 master.cpp:7227] Received update of agent d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0
at slave(127)@172.17.0.4:58832 (e25451cbd691) with total oversubscribed resources {}
3: I1211 17:51:54.886765 16008 master.cpp:7325] Ignoring update on agent d9513a1e-8a2c-45b1-8554-f34b88f4424f-S0
at slave(127)@172.17.0.4:58832 (e25451cbd691) as it reports no changes
3: I1211 17:51:54.887223 16002 slave.cpp:258] Mesos agent started on (129)@172.17.0.4:58832
3: I1211 17:51:54.887259 16002 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_bv4gds/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_bv4gds/credential"
--default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins"
--docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"
--docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns"
--docker_store_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_bv4gds/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false"
--executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_bv4gds/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1"
--hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_bv4gds/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms" --resources="cpus:2;mem:1024" --revocable_cpu_low_priority="true"
--runtime_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_bv4gds" --sandbox_directory="/mnt/mesos/sandbox"
--strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system"
--version="false" --work_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_Sk8el4"
--zk_session_timeout="10secs"
3: I1211 17:51:54.887836 16002 credentials.hpp:86] Loading credential for authentication from
'/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_bv4gds/credential'
3: I1211 17:51:54.888003 16002 slave.cpp:291] Agent using credential for: test-principal
3: I1211 17:51:54.888032 16002 credentials.hpp:37] Loading credentials for authentication
from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_bv4gds/http_credentials'
3: I1211 17:51:54.888339 16002 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1211 17:51:54.888564 16002 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1211 17:51:54.889974 16002 slave.cpp:590] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":3701220.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1211 17:51:54.890251 16002 slave.cpp:598] Agent attributes: [  ]
3: I1211 17:51:54.890262 16002 slave.cpp:607] Agent hostname: e25451cbd691
3: I1211 17:51:54.890393 16011 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: W1211 17:51:54.891376 15995 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:58832
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1513012918-22764
Process leaked file descriptors. See https://jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors
for more information
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user gregorywmann@gmail.com
Not sending mail to unregistered user yujie.jay@gmail.com


Mime
View raw message