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 --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4578
Date Mon, 11 Dec 2017 23:36:01 GMT
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4578/display/redirect?page=changes>

Changes:

[yujie.jay] Refactored agent to keep track of local resource providers.

[yujie.jay] Passed the message directly to the Master::registerSlave handler.

[yujie.jay] Sent resource version uuid only for agent default resources.

------------------------------------------
[...truncated 6.64 MB...]
3: I1211 23:35:43.422955 18412 sched.cpp:1191] Stopping framework e8cb9765-fb35-421e-94ed-361b18762959-0000
3: I1211 23:35:43.423377 18409 sched.cpp:2009] Asked to stop the driver
3: I1211 23:35:43.423527 18423 sched.cpp:1191] Stopping framework e8cb9765-fb35-421e-94ed-361b18762959-0000
3: W1211 23:35:43.423879 18426 master.cpp:2340] Dropping TEARDOWN call from framework e8cb9765-fb35-421e-94ed-361b18762959-0000
at scheduler-c35e7df5-efaf-4331-8000-b54bc7f3c422@172.17.0.4:34442: Call is not from registered
framework
3: I1211 23:35:43.424685 18422 master.cpp:1147] Master terminating
3: I1211 23:35:43.425217 18414 hierarchical.cpp:344] Removed framework e8cb9765-fb35-421e-94ed-361b18762959-0000
3: [       OK ] RateLimitingTest.SchedulerFailover (104 ms)
3: [ RUN      ] RateLimitingTest.CapacityReached
3: I1211 23:35:43.433645 18409 cluster.cpp:172] Creating default 'local' authorizer
3: I1211 23:35:43.439088 18433 master.cpp:456] Master 61e217c9-4fc5-40c1-8367-6fddee749c0e
(763730d7e147) started on 172.17.0.4:34442
3: I1211 23:35:43.439127 18433 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/2yW64K/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/2yW64K/master"
--zk_session_timeout="10secs"
3: I1211 23:35:43.439550 18433 master.cpp:507] Master only allowing authenticated frameworks
to register
3: I1211 23:35:43.439558 18433 master.cpp:513] Master only allowing authenticated agents to
register
3: I1211 23:35:43.439563 18433 master.cpp:519] Master only allowing authenticated HTTP frameworks
to register
3: I1211 23:35:43.439568 18433 credentials.hpp:37] Loading credentials for authentication
from '/tmp/2yW64K/credentials'
3: I1211 23:35:43.439841 18433 master.cpp:563] Using default 'crammd5' authenticator
3: I1211 23:35:43.439990 18433 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readonly'
3: I1211 23:35:43.440146 18433 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readwrite'
3: I1211 23:35:43.440263 18433 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-scheduler'
3: I1211 23:35:43.440390 18433 master.cpp:642] Authorization enabled
3: I1211 23:35:43.440516 18433 master.cpp:692] Framework rate limiting enabled
3: I1211 23:35:43.440742 18417 whitelist_watcher.cpp:77] No whitelist given
3: I1211 23:35:43.440783 18420 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1211 23:35:43.443789 18430 master.cpp:2209] Elected as the leading master!
3: I1211 23:35:43.443812 18430 master.cpp:1689] Recovering from registrar
3: I1211 23:35:43.444083 18414 registrar.cpp:347] Recovering registrar
3: I1211 23:35:43.444953 18414 registrar.cpp:391] Successfully fetched the registry (0B) in
814080ns
3: I1211 23:35:43.445116 18414 registrar.cpp:495] Applied 1 operations in 59633ns; attempting
to update the registry
3: I1211 23:35:43.445926 18414 registrar.cpp:552] Successfully updated the registry in 726016ns
3: I1211 23:35:43.446117 18414 registrar.cpp:424] Successfully recovered registrar
3: I1211 23:35:43.446727 18421 master.cpp:1802] Recovered 0 agents from the registry (135B);
allowing 10mins for agents to re-register
3: I1211 23:35:43.446791 18432 hierarchical.cpp:213] Skipping recovery of hierarchical allocator:
nothing to recover
3: W1211 23:35:43.447836 18409 process.cpp:2756] Attempted to spawn already running process
version@172.17.0.4:34442
3: I1211 23:35:43.449293 18409 sched.cpp:232] Version: 1.5.0
3: I1211 23:35:43.450166 18425 sched.cpp:336] New master detected at master@172.17.0.4:34442
3: I1211 23:35:43.450338 18425 sched.cpp:396] Authenticating with master master@172.17.0.4:34442
3: I1211 23:35:43.450373 18425 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1211 23:35:43.450659 18427 authenticatee.cpp:121] Creating new client SASL connection
3: I1211 23:35:43.451057 18430 master.cpp:8888] Authenticating scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442
3: I1211 23:35:43.451331 18418 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(291)@172.17.0.4:34442
3: I1211 23:35:43.451738 18431 authenticator.cpp:98] Creating new server SASL connection
3: I1211 23:35:43.452018 18419 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1211 23:35:43.452044 18419 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1211 23:35:43.452164 18419 authenticator.cpp:204] Received SASL authentication start
3: I1211 23:35:43.452214 18419 authenticator.cpp:326] Authentication requires more steps
3: I1211 23:35:43.452338 18415 authenticatee.cpp:259] Received SASL authentication step
3: I1211 23:35:43.452512 18415 authenticator.cpp:232] Received SASL authentication step
3: I1211 23:35:43.452549 18415 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '763730d7e147' server FQDN: '763730d7e147' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1211 23:35:43.452560 18415 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1211 23:35:43.452587 18415 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1211 23:35:43.452618 18415 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '763730d7e147' server FQDN: '763730d7e147' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1211 23:35:43.452628 18415 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1211 23:35:43.452635 18415 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1211 23:35:43.452646 18415 authenticator.cpp:318] Authentication success
3: I1211 23:35:43.452854 18428 authenticatee.cpp:299] Authentication success
3: I1211 23:35:43.452913 18415 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(291)@172.17.0.4:34442
3: I1211 23:35:43.452921 18423 master.cpp:8918] Successfully authenticated principal 'test-principal'
at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442
3: I1211 23:35:43.453248 18428 sched.cpp:502] Successfully authenticated with master master@172.17.0.4:34442
3: I1211 23:35:43.453266 18428 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.4:34442
3: I1211 23:35:43.453382 18428 sched.cpp:857] Will retry registration in 781.375837ms if necessary
3: I1211 23:35:43.453703 18421 master.cpp:2957] Received SUBSCRIBE call for framework 'default'
at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442
3: I1211 23:35:43.453727 18421 master.cpp:2274] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1211 23:35:43.454249 18422 master.cpp:3037] Subscribing framework default with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1211 23:35:43.454401 18422 master.cpp:9109] Adding framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
(default) at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442 with roles {
 } suppressed
3: I1211 23:35:43.454864 18422 sched.cpp:751] Framework registered with 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
3: I1211 23:35:43.454902 18422 sched.cpp:765] Scheduler::registered took 22871ns
3: I1211 23:35:43.455127 18426 hierarchical.cpp:297] Added framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
3: I1211 23:35:43.455392 18426 hierarchical.cpp:1513] Performed allocation for 0 agents in
87518ns
3: I1211 23:35:43.456073 18430 master.cpp:2957] Received SUBSCRIBE call for framework 'default'
at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442
3: I1211 23:35:43.456100 18430 master.cpp:2274] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1211 23:35:43.456740 18411 master.cpp:3037] Subscribing framework default with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1211 23:35:43.456768 18411 master.cpp:3047] Framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
(default) at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442 already subscribed,
resending acknowledgement
3: I1211 23:35:43.456964 18419 sched.cpp:738] Ignoring framework registered message because
the driver is already connected!
3: I1211 23:35:43.458912 18413 process.cpp:3503] Handling HTTP event for process 'metrics'
with path: '/metrics/snapshot'
3: I1211 23:35:43.460242 18410 http.cpp:851] Authorizing principal 'ANY' to GET the endpoint
'/metrics/snapshot'
3: W1211 23:35:43.479773 18430 master.cpp:1651] Dropping message mesos.scheduler.Call from
scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442(test-principal): capacity(2)
exceeded
3: I1211 23:35:43.479917 18431 sched.cpp:1175] Got error 'Message mesos.scheduler.Call dropped:
capacity(2) exceeded'
3: I1211 23:35:43.479936 18431 sched.cpp:2043] Asked to abort the driver
3: I1211 23:35:43.479995 18431 sched.cpp:1186] Scheduler::error took 29638ns
3: I1211 23:35:43.480041 18431 sched.cpp:1221] Aborting framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
3: W1211 23:35:43.480211 18431 master.cpp:1651] Dropping message mesos.internal.DeactivateFrameworkMessage
from scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442(test-principal): capacity(2)
exceeded
3: I1211 23:35:43.480377 18429 sched.cpp:1171] Ignoring error message because the driver is
not running!
3: I1211 23:35:43.480468 18409 sched.cpp:2009] Asked to stop the driver
3: I1211 23:35:43.483913 18424 process.cpp:3503] Handling HTTP event for process 'metrics'
with path: '/metrics/snapshot'
3: I1211 23:35:43.485294 18412 http.cpp:851] Authorizing principal 'ANY' to GET the endpoint
'/metrics/snapshot'
3: I1211 23:35:43.503861 18414 hierarchical.cpp:1513] Performed allocation for 0 agents in
117492ns
3: I1211 23:35:43.503880 18423 master.cpp:2957] Received SUBSCRIBE call for framework 'default'
at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442
3: I1211 23:35:43.503901 18423 master.cpp:2274] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1211 23:35:43.504508 18432 master.cpp:3037] Subscribing framework default with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1211 23:35:43.504568 18432 master.cpp:3047] Framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
(default) at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442 already subscribed,
resending acknowledgement
3: I1211 23:35:43.505479 18424 hierarchical.cpp:1513] Performed allocation for 0 agents in
92706ns
3: I1211 23:35:43.505533 18415 master.cpp:2957] Received SUBSCRIBE call for framework 'default'
at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442
3: I1211 23:35:43.505560 18415 master.cpp:2274] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1211 23:35:43.506156 18425 master.cpp:3037] Subscribing framework default with checkpointing
disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1211 23:35:43.506196 18425 master.cpp:3047] Framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
(default) at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442 already subscribed,
resending acknowledgement
3: I1211 23:35:43.506880 18427 hierarchical.cpp:1513] Performed allocation for 0 agents in
82776ns
3: I1211 23:35:43.507066 18426 master.cpp:1419] Framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
(default) at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442 disconnected
3: I1211 23:35:43.507112 18426 master.cpp:3327] Deactivating framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
(default) at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442
3: I1211 23:35:43.507242 18426 master.cpp:3304] Disconnecting framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
(default) at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442
3: I1211 23:35:43.507308 18426 master.cpp:1434] Giving framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
(default) at scheduler-0660acd4-2744-4431-83c5-ed3afce8ecc5@172.17.0.4:34442 10secs to failover
3: I1211 23:35:43.507346 18422 hierarchical.cpp:405] Deactivated framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
3: I1211 23:35:43.509382 18430 process.cpp:3503] Handling HTTP event for process 'metrics'
with path: '/metrics/snapshot'
3: I1211 23:35:43.510375 18419 http.cpp:851] Authorizing principal 'ANY' to GET the endpoint
'/metrics/snapshot'
3: I1211 23:35:43.529866 18409 master.cpp:1147] Master terminating
3: I1211 23:35:43.530374 18420 hierarchical.cpp:344] Removed framework 61e217c9-4fc5-40c1-8367-6fddee749c0e-0000
3: [       OK ] RateLimitingTest.CapacityReached (105 ms)
3: [----------] 6 tests from RateLimitingTest (656 ms total)
3: 
3: [----------] 5 tests from ResourceOffersTest
3: [ RUN      ] ResourceOffersTest.ResourceOfferWithMultipleSlaves
3: I1211 23:35:43.538221 18409 cluster.cpp:172] Creating default 'local' authorizer
3: I1211 23:35:43.541316 18431 master.cpp:456] Master 21122ac1-0f4e-49aa-ad19-1cb288fa6221
(763730d7e147) started on 172.17.0.4:34442
3: I1211 23:35:43.541374 18431 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/xu1DMb/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/xu1DMb/master"
--zk_session_timeout="10secs"
3: I1211 23:35:43.541836 18431 master.cpp:507] Master only allowing authenticated frameworks
to register
3: I1211 23:35:43.541847 18431 master.cpp:513] Master only allowing authenticated agents to
register
3: I1211 23:35:43.541862 18431 master.cpp:519] Master only allowing authenticated HTTP frameworks
to register
3: I1211 23:35:43.541870 18431 credentials.hpp:37] Loading credentials for authentication
from '/tmp/xu1DMb/credentials'
3: I1211 23:35:43.542217 18431 master.cpp:563] Using default 'crammd5' authenticator
3: I1211 23:35:43.542421 18431 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readonly'
3: I1211 23:35:43.542650 18431 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readwrite'
3: I1211 23:35:43.542809 18431 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-scheduler'
3: I1211 23:35:43.542987 18431 master.cpp:642] Authorization enabled
3: I1211 23:35:43.543259 18422 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1211 23:35:43.543336 18410 whitelist_watcher.cpp:77] No whitelist given
3: I1211 23:35:43.546910 18420 master.cpp:2209] Elected as the leading master!
3: I1211 23:35:43.546931 18420 master.cpp:1689] Recovering from registrar
3: I1211 23:35:43.547061 18432 registrar.cpp:347] Recovering registrar
3: I1211 23:35:43.547739 18432 registrar.cpp:391] Successfully fetched the registry (0B) in
643072ns
3: I1211 23:35:43.547854 18432 registrar.cpp:495] Applied 1 operations in 37320ns; attempting
to update the registry
3: I1211 23:35:43.548642 18419 registrar.cpp:552] Successfully updated the registry in 677888ns
3: I1211 23:35:43.548792 18419 registrar.cpp:424] Successfully recovered registrar
3: I1211 23:35:43.549253 18413 master.cpp:1802] Recovered 0 agents from the registry (135B);
allowing 10mins for agents to re-register
3: I1211 23:35:43.549394 18417 hierarchical.cpp:213] Skipping recovery of hierarchical allocator:
nothing to recover
3: W1211 23:35:43.555641 18409 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:34442
3: I1211 23:35:43.556766 18409 containerizer.cpp:304] Using isolation { environment_secret,
posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1211 23:35:43.557358 18409 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend
requires root privileges
3: W1211 23:35:43.557497 18409 backend.cpp:76] Failed to create 'bind' backend: BindBackend
requires root privileges
3: I1211 23:35:43.557545 18409 provisioner.cpp:299] Using default backend 'copy'
3: I1211 23:35:43.559947 18409 cluster.cpp:460] Creating default 'local' authorizer
3: I1211 23:35:43.562743 18412 slave.cpp:258] Mesos agent started on (128)@172.17.0.4:34442
3: I1211 23:35:43.562801 18412 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_N9qHg3/store/appc"
--authenticate_http_executors="true" --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_N9qHg3/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_N9qHg3/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_N9qHg3/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_N9qHg3/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--jwt_secret_key="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_N9qHg3/jwt_secret_key"
--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_N9qHg3" --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_Cd5EwC"
--zk_session_timeout="10secs"
3: I1211 23:35:43.563510 18412 credentials.hpp:86] Loading credential for authentication from
'/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_N9qHg3/credential'
3: I1211 23:35:43.563704 18412 slave.cpp:291] Agent using credential for: test-principal
3: I1211 23:35:43.563721 18412 credentials.hpp:37] Loading credentials for authentication
from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_N9qHg3/http_credentials'
3: I1211 23:35:43.564020 18412 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-executor'
3: I1211 23:35:43.564177 18412 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-executor'
3: I1211 23:35:43.564448 18412 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1211 23:35:43.564564 18412 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readonly'
3: I1211 23:35:43.564738 18412 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1211 23:35:43.564829 18412 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readwrite'
3: I1211 23:35:43.566314 18412 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":367489.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1211 23:35:43.566584 18412 slave.cpp:598] Agent attributes: [  ]
3: I1211 23:35:43.566601 18412 slave.cpp:607] Agent hostname: 763730d7e147
3: I1211 23:35:43.566761 18411 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: W1211 23:35:43.568006 18409 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:34442
3: I1211 23:35:43.568706 18424 state.cpp:66] Recovering state from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_Cd5EwC/meta'
3: I1211 23:35:43.569066 18409 containerizer.cpp:304] Using isolation { environment_secret,
posix/cpu, posix/mem, filesystem/posix, network/cni }
3: I1211 23:35:43.569138 18424 task_status_update_manager.cpp:207] Recovering task status
update manager
3: I1211 23:35:43.569430 18431 containerizer.cpp:674] Recovering containerizer
3: W1211 23:35:43.569566 18409 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend
requires root privileges
3: W1211 23:35:43.569677 18409 backend.cpp:76] Failed to create 'bind' backend: BindBackend
requires root privileges
3: I1211 23:35:43.569707 18409 provisioner.cpp:299] Using default backend 'copy'
3: I1211 23:35:43.571220 18417 provisioner.cpp:495] Provisioner recovery complete
3: I1211 23:35:43.571691 18424 slave.cpp:6727] Finished recovery
3: I1211 23:35:43.571933 18409 cluster.cpp:460] Creating default 'local' authorizer
3: I1211 23:35:43.572598 18429 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1211 23:35:43.572631 18431 slave.cpp:1033] New master detected at master@172.17.0.4:34442
3: I1211 23:35:43.572710 18431 slave.cpp:1088] Detecting new master
3: I1211 23:35:43.574546 18416 slave.cpp:258] Mesos agent started on (129)@172.17.0.4:34442
3: I1211 23:35:43.574584 18416 slave.cpp:259] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_r5LsOU/store/appc"
--authenticate_http_executors="true" --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_r5LsOU/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_r5LsOU/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_r5LsOU/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_r5LsOU/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem"
--jwt_secret_key="/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_r5LsOU/jwt_secret_key"
--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_r5LsOU" --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_k0yz2t"
--zk_session_timeout="10secs"
3: I1211 23:35:43.575014 18416 credentials.hpp:86] Loading credential for authentication from
'/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_r5LsOU/credential'
3: I1211 23:35:43.575214 18416 slave.cpp:291] Agent using credential for: test-principal
3: I1211 23:35:43.575253 18416 credentials.hpp:37] Loading credentials for authentication
from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_r5LsOU/http_credentials'
3: I1211 23:35:43.575536 18416 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-executor'
3: I1211 23:35:43.575669 18416 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-executor'
3: I1211 23:35:43.575935 18416 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1211 23:35:43.575949 18422 slave.cpp:1115] Authenticating with master master@172.17.0.4:34442
3: I1211 23:35:43.576061 18416 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readonly'
3: I1211 23:35:43.576063 18422 slave.cpp:1124] Using default CRAM-MD5 authenticatee
3: I1211 23:35:43.576386 18425 authenticatee.cpp:121] Creating new client SASL connection
3: I1211 23:35:43.576443 18416 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1211 23:35:43.576551 18416 http.cpp:1066] Creating default 'jwt' HTTP authenticator for
realm 'mesos-agent-readwrite'
3: I1211 23:35:43.576753 18430 master.cpp:8888] Authenticating slave(128)@172.17.0.4:34442
3: I1211 23:35:43.576926 18413 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(292)@172.17.0.4:34442
3: I1211 23:35:43.577205 18427 authenticator.cpp:98] Creating new server SASL connection
3: I1211 23:35:43.577451 18425 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1211 23:35:43.577486 18425 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1211 23:35:43.577595 18425 authenticator.cpp:204] Received SASL authentication start
3: I1211 23:35:43.577653 18425 authenticator.cpp:326] Authentication requires more steps
3: I1211 23:35:43.577800 18429 authenticatee.cpp:259] Received SASL authentication step
3: I1211 23:35:43.577967 18432 authenticator.cpp:232] Received SASL authentication step
3: I1211 23:35:43.578001 18432 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '763730d7e147' server FQDN: '763730d7e147' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1211 23:35:43.578016 18432 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1211 23:35:43.578060 18432 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1211 23:35:43.578083 18432 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '763730d7e147' server FQDN: '763730d7e147' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1211 23:35:43.578091 18432 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1211 23:35:43.578097 18432 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1211 23:35:43.578119 18432 authenticator.cpp:318] Authentication success
3: I1211 23:35:43.578199 18414 authenticatee.cpp:299] Authentication success
3: I1211 23:35:43.578315 18419 master.cpp:8918] Successfully authenticated principal 'test-principal'
at slave(128)@172.17.0.4:34442
3: I1211 23:35:43.578336 18412 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(292)@172.17.0.4:34442
3: I1211 23:35:43.578136 18416 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":367489.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1211 23:35:43.578372 18416 slave.cpp:598] Agent attributes: [  ]
3: I1211 23:35:43.578400 18416 slave.cpp:607] Agent hostname: 763730d7e147
3: I1211 23:35:43.578531 18410 slave.cpp:1207] Successfully authenticated with master master@172.17.0.4:34442
3: I1211 23:35:43.578557 18430 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1211 23:35:43.578786 18410 slave.cpp:1684] Will retry registration in 6.024016ms if necessary
3: I1211 23:35:43.578999 18415 master.cpp:6077] Received register agent message from slave(128)@172.17.0.4:34442
(763730d7e147)
3: I1211 23:35:43.579038 18415 master.cpp:3866] Authorizing agent with principal 'test-principal'
3: I1211 23:35:43.579507 18422 master.cpp:6147] Authorized registration of agent at slave(128)@172.17.0.4:34442
(763730d7e147)
3: I1211 23:35:43.579602 18422 master.cpp:6240] Registering agent at slave(128)@172.17.0.4:34442
(763730d7e147) with id 21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0
3: W1211 23:35:43.579756 18409 process.cpp:2756] Attempted to spawn already running process
files@172.17.0.4:34442
3: I1211 23:35:43.580091 18413 registrar.cpp:495] Applied 1 operations in 106154ns; attempting
to update the registry
3: I1211 23:35:43.580489 18431 state.cpp:66] Recovering state from '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_k0yz2t/meta'
3: I1211 23:35:43.580713 18413 registrar.cpp:552] Successfully updated the registry in 560128ns
3: I1211 23:35:43.580754 18409 containerizer.cpp:304] Using isolation { environment_secret,
posix/cpu, posix/mem, filesystem/posix, network/cni }
3: I1211 23:35:43.580826 18432 task_status_update_manager.cpp:207] Recovering task status
update manager
3: I1211 23:35:43.580849 18414 master.cpp:6288] Admitted agent 21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0
at slave(128)@172.17.0.4:34442 (763730d7e147)
3: I1211 23:35:43.581019 18419 containerizer.cpp:674] Recovering containerizer
3: W1211 23:35:43.581281 18409 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend
requires root privileges
3: W1211 23:35:43.581393 18409 backend.cpp:76] Failed to create 'bind' backend: BindBackend
requires root privileges
3: I1211 23:35:43.581426 18409 provisioner.cpp:299] Using default backend 'copy'
3: I1211 23:35:43.581475 18414 master.cpp:6337] Registered agent 21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0
at slave(128)@172.17.0.4:34442 (763730d7e147) with cpus:2; mem:1024; disk:367489; ports:[31000-32000]
3: I1211 23:35:43.581712 18415 slave.cpp:1253] Registered with master master@172.17.0.4:34442;
given agent ID 21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0
3: I1211 23:35:43.581864 18429 task_status_update_manager.cpp:188] Resuming sending task status
updates
3: I1211 23:35:43.582024 18430 hierarchical.cpp:572] Added agent 21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0
(763730d7e147) with cpus:2; mem:1024; disk:367489; ports:[31000-32000] (allocated: {})
3: I1211 23:35:43.582156 18415 slave.cpp:1273] Checkpointing SlaveInfo to '/tmp/ResourceOffersTest_ResourceOfferWithMultipleSlaves_Cd5EwC/meta/slaves/21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0/slave.info'
3: I1211 23:35:43.582314 18430 hierarchical.cpp:1513] Performed allocation for 1 agents in
145977ns
3: I1211 23:35:43.582644 18410 provisioner.cpp:495] Provisioner recovery complete
3: I1211 23:35:43.582597 18415 slave.cpp:1330] Forwarding agent update {"offer_operations":{},"resource_categories":{"oversubscribed":true},"resource_version_uuid":"mtuAq4qtR\/CY09xmOkYQVg==","slave_id":{"value":"21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0"}}
3: I1211 23:35:43.582844 18428 master.cpp:7238] Received update of agent 21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0
at slave(128)@172.17.0.4:34442 (763730d7e147) with total oversubscribed resources {}
3: I1211 23:35:43.583011 18422 slave.cpp:6727] Finished recovery
3: I1211 23:35:43.583076 18428 master.cpp:7336] Ignoring update on agent 21122ac1-0f4e-49aa-ad19-1cb288fa6221-S0
at slave(128)@172.17.0.4:34442 (763730d7e147) as it reports no changes
3: I1211 23:35:43.583418 18409 cluster.cpp:460] Creating default 'local' authorizer
3: I1211 23:35:43.583933 18429 slave.cpp:1033] New master detected at master@172.17.0.4:34442
3: I1211 23:35:43.583943 18411 task_status_update_manager.cpp:181] Pausing sending task status
updates
3: I1211 23:35:43.583987 18429 slave.cpp:1088] Detecting new master
3: I1211 23:35:43.585883 18412 slave.cpp:258] Mesos agent started on (130)@172.17.0.4:34442
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1513033598-21819
Error response from daemon: conflict: unable to remove repository reference "mesos-1513033598-21819"
(must force) - container 763730d7e147 is using its referenced image 0af9e88c5967
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