See
<https://builds.apache.org/job/Mesos-Reviewbot/18714/display/redirect?page=changes>
Changes:
[zhq527725] Replaced raw string with normal string for
`DefaultContainerDNSCniTest`.
------------------------------------------
[...truncated 13.07 MB...]
I0803 15:26:36.288707 5127 slave.cpp:5832] Current disk usage 94.87%. Max
allowed age: 0ns
I0803 15:26:36.288925 5125 hierarchical.cpp:1468] Performed allocation for 0
agents in 301510ns
I0803 15:26:36.289310 5129 slave.cpp:6403] Received oversubscribable resources
{} from the resource estimator
I0803 15:26:36.289965 5128 master.cpp:5995] Ignoring re-register agent message
from agent 7f8992c7-deb7-46b5-a10c-27f2062e3a06-S0 at
slave(234)@172.17.0.3:45885 (dab9864a824c) as re-registration is already in
progress
I0803 15:26:36.304438 5105 slave.cpp:843] Agent terminating
I0803 15:26:36.311868 5124 master.cpp:1160] Master terminating
[ OK ] MasterTest.RecoveredSlaveReregisterThenUnreachableRace (204 ms)
[ RUN ] MasterTest.RecoveredFramework
I0803 15:26:36.321956 5105 cluster.cpp:162] Creating default 'local' authorizer
I0803 15:26:36.326387 5128 master.cpp:442] Master
f1cdc1f2-c2c5-4404-9821-3e31ee142dab (dab9864a824c) started on 172.17.0.3:45885
I0803 15:26:36.326431 5128 master.cpp:444] 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/4bo7ky/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="/mesos/mesos-1.4.0/_inst/share/mesos/webui"
--work_dir="/tmp/4bo7ky/master" --zk_session_timeout="10secs"
I0803 15:26:36.327144 5128 master.cpp:494] Master only allowing authenticated
frameworks to register
I0803 15:26:36.327167 5128 master.cpp:508] Master only allowing authenticated
agents to register
I0803 15:26:36.327181 5128 master.cpp:521] Master only allowing authenticated
HTTP frameworks to register
I0803 15:26:36.327198 5128 credentials.hpp:37] Loading credentials for
authentication from '/tmp/4bo7ky/credentials'
I0803 15:26:36.327949 5128 master.cpp:566] Using default 'crammd5'
authenticator
I0803 15:26:36.328373 5128 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0803 15:26:36.328724 5128 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0803 15:26:36.329035 5128 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0803 15:26:36.329321 5128 master.cpp:646] Authorization enabled
I0803 15:26:36.329577 5126 hierarchical.cpp:171] Initialized hierarchical
allocator process
I0803 15:26:36.329725 5127 whitelist_watcher.cpp:77] No whitelist given
I0803 15:26:36.333365 5127 master.cpp:2163] Elected as the leading master!
I0803 15:26:36.333403 5127 master.cpp:1702] Recovering from registrar
I0803 15:26:36.333919 5124 registrar.cpp:347] Recovering registrar
I0803 15:26:36.335085 5127 registrar.cpp:391] Successfully fetched the
registry (0B) in 1.095936ms
I0803 15:26:36.335242 5127 registrar.cpp:495] Applied 1 operations in 35790ns;
attempting to update the registry
I0803 15:26:36.336105 5127 registrar.cpp:552] Successfully updated the
registry in 791808ns
I0803 15:26:36.336257 5127 registrar.cpp:424] Successfully recovered registrar
I0803 15:26:36.336881 5130 master.cpp:1801] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0803 15:26:36.337275 5130 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
I0803 15:26:36.346001 5105 process.cpp:3233] Attempting to spawn already
spawned process [email protected]:45885
I0803 15:26:36.346524 5105 cluster.cpp:448] Creating default 'local' authorizer
I0803 15:26:36.349308 5125 slave.cpp:250] Mesos agent started on
(235)@172.17.0.3:45885
I0803 15:26:36.349350 5125 slave.cpp:251] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/MasterTest_RecoveredFramework_ktdHOr/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/MasterTest_RecoveredFramework_ktdHOr/credential"
--default_role="*" --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/MasterTest_RecoveredFramework_ktdHOr/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/MasterTest_RecoveredFramework_ktdHOr/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/MasterTest_RecoveredFramework_ktdHOr/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/mesos-1.4.0/_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" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ns"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/MasterTest_RecoveredFramework_ktdHOr"
--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/MasterTest_RecoveredFramework_VEvT4Z"
I0803 15:26:36.350236 5125 credentials.hpp:86] Loading credential for
authentication from '/tmp/MasterTest_RecoveredFramework_ktdHOr/credential'
I0803 15:26:36.350257 5105 process.cpp:3233] Attempting to spawn already
spawned process [email protected]:45885
I0803 15:26:36.350795 5125 slave.cpp:283] Agent using credential for:
test-principal
I0803 15:26:36.350829 5125 credentials.hpp:37] Loading credentials for
authentication from '/tmp/MasterTest_RecoveredFramework_ktdHOr/http_credentials'
I0803 15:26:36.351400 5125 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0803 15:26:36.351658 5105 sched.cpp:232] Version: 1.4.0
I0803 15:26:36.352246 5125 http.cpp:1026] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0803 15:26:36.352572 5129 sched.cpp:336] New master detected at
[email protected]:45885
I0803 15:26:36.352957 5129 sched.cpp:407] Authenticating with master
[email protected]:45885
I0803 15:26:36.352989 5129 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0803 15:26:36.353720 5127 authenticatee.cpp:121] Creating new client SASL
connection
I0803 15:26:36.354259 5129 master.cpp:7837] Authenticating
[email protected]:45885
I0803 15:26:36.354636 5124 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(493)@172.17.0.3:45885
I0803 15:26:36.355420 5124 authenticator.cpp:98] Creating new server SASL
connection
I0803 15:26:36.356060 5126 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0803 15:26:36.356098 5126 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0803 15:26:36.356218 5126 authenticator.cpp:204] Received SASL authentication
start
I0803 15:26:36.356278 5126 authenticator.cpp:326] Authentication requires more
steps
I0803 15:26:36.356390 5126 authenticatee.cpp:259] Received SASL authentication
step
I0803 15:26:36.356493 5126 authenticator.cpp:232] Received SASL authentication
step
I0803 15:26:36.356526 5126 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'dab9864a824c' server FQDN: 'dab9864a824c'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0803 15:26:36.356564 5126 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0803 15:26:36.356616 5126 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0803 15:26:36.356650 5126 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'dab9864a824c' server FQDN: 'dab9864a824c'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0803 15:26:36.356670 5126 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0803 15:26:36.356689 5126 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0803 15:26:36.356717 5126 authenticator.cpp:318] Authentication success
I0803 15:26:36.357113 5124 authenticatee.cpp:299] Authentication success
I0803 15:26:36.357132 5126 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(493)@172.17.0.3:45885
I0803 15:26:36.357542 5129 master.cpp:7867] Successfully authenticated
principal 'test-principal' at
[email protected]:45885
I0803 15:26:36.356294 5125 slave.cpp:565] Agent resources:
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0803 15:26:36.357713 5125 slave.cpp:573] Agent attributes: [ ]
I0803 15:26:36.357743 5125 slave.cpp:582] Agent hostname: dab9864a824c
I0803 15:26:36.357802 5127 sched.cpp:513] Successfully authenticated with
master [email protected]:45885
I0803 15:26:36.357851 5127 sched.cpp:836] Sending SUBSCRIBE call to
[email protected]:45885
I0803 15:26:36.358191 5127 sched.cpp:869] Will retry registration in
1.212121775secs if necessary
I0803 15:26:36.358631 5131 master.cpp:2894] Received SUBSCRIBE call for
framework 'default' at
[email protected]:45885
I0803 15:26:36.358752 5131 master.cpp:2228] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0803 15:26:36.359685 5131 master.cpp:2974] Subscribing framework default with
checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0803 15:26:36.360769 5131 sched.cpp:759] Framework registered with
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.360898 5126 hierarchical.cpp:303] Added framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.360842 5131 sched.cpp:773] Scheduler::registered took 30477ns
I0803 15:26:36.361151 5126 hierarchical.cpp:1925] No allocations performed
I0803 15:26:36.361213 5126 hierarchical.cpp:2015] No inverse offers to send
out!
I0803 15:26:36.361256 5126 hierarchical.cpp:1468] Performed allocation for 0
agents in 160785ns
I0803 15:26:36.361644 5128 status_update_manager.cpp:177] Pausing sending
status updates
I0803 15:26:36.363951 5127 state.cpp:64] Recovering state from
'/tmp/MasterTest_RecoveredFramework_VEvT4Z/meta'
I0803 15:26:36.364562 5127 status_update_manager.cpp:203] Recovering status
update manager
I0803 15:26:36.365183 5126 slave.cpp:6207] Finished recovery
I0803 15:26:36.365757 5126 slave.cpp:6389] Querying resource estimator for
oversubscribable resources
I0803 15:26:36.366327 5130 status_update_manager.cpp:177] Pausing sending
status updates
I0803 15:26:36.366348 5128 slave.cpp:971] New master detected at
[email protected]:45885
I0803 15:26:36.366752 5128 slave.cpp:1006] Detecting new master
I0803 15:26:36.366982 5128 slave.cpp:6403] Received oversubscribable resources
{} from the resource estimator
I0803 15:26:36.368125 5131 slave.cpp:1033] Authenticating with master
[email protected]:45885
I0803 15:26:36.368244 5131 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0803 15:26:36.368902 5131 authenticatee.cpp:121] Creating new client SASL
connection
I0803 15:26:36.369550 5126 master.cpp:7837] Authenticating
slave(235)@172.17.0.3:45885
I0803 15:26:36.369942 5124 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(494)@172.17.0.3:45885
I0803 15:26:36.370749 5124 authenticator.cpp:98] Creating new server SASL
connection
I0803 15:26:36.371484 5124 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0803 15:26:36.371526 5124 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0803 15:26:36.371971 5127 authenticator.cpp:204] Received SASL authentication
start
I0803 15:26:36.372045 5127 authenticator.cpp:326] Authentication requires more
steps
I0803 15:26:36.372174 5127 authenticatee.cpp:259] Received SASL authentication
step
I0803 15:26:36.372282 5127 authenticator.cpp:232] Received SASL authentication
step
I0803 15:26:36.372314 5127 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'dab9864a824c' server FQDN: 'dab9864a824c'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0803 15:26:36.372336 5127 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0803 15:26:36.372398 5127 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0803 15:26:36.372432 5127 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'dab9864a824c' server FQDN: 'dab9864a824c'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0803 15:26:36.372452 5127 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0803 15:26:36.372470 5127 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0803 15:26:36.372498 5127 authenticator.cpp:318] Authentication success
I0803 15:26:36.372767 5127 authenticatee.cpp:299] Authentication success
I0803 15:26:36.373008 5125 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(494)@172.17.0.3:45885
I0803 15:26:36.373209 5127 slave.cpp:1128] Successfully authenticated with
master [email protected]:45885
I0803 15:26:36.373389 5126 master.cpp:7867] Successfully authenticated
principal 'test-principal' at slave(235)@172.17.0.3:45885
I0803 15:26:36.373719 5127 slave.cpp:1572] Will retry registration in 12ns if
necessary
I0803 15:26:36.374207 5129 master.cpp:5712] Received register agent message
from slave(235)@172.17.0.3:45885 (dab9864a824c)
I0803 15:26:36.374665 5129 master.cpp:3803] Authorizing agent with principal
'test-principal'
I0803 15:26:36.375425 5129 slave.cpp:1572] Will retry registration in 12ns if
necessary
I0803 15:26:36.375907 5131 master.cpp:5706] Ignoring register agent message
from slave(235)@172.17.0.3:45885 (dab9864a824c) as registration is already in
progress
I0803 15:26:36.376034 5131 master.cpp:5772] Authorized registration of agent
at slave(235)@172.17.0.3:45885 (dab9864a824c)
I0803 15:26:36.376374 5131 master.cpp:5865] Registering agent at
slave(235)@172.17.0.3:45885 (dab9864a824c) with id
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0
I0803 15:26:36.377161 5126 registrar.cpp:495] Applied 1 operations in 90829ns;
attempting to update the registry
I0803 15:26:36.377974 5130 master.cpp:5706] Ignoring register agent message
from slave(235)@172.17.0.3:45885 (dab9864a824c) as registration is already in
progress
I0803 15:26:36.378053 5126 registrar.cpp:552] Successfully updated the
registry in 801280ns
I0803 15:26:36.378167 5125 slave.cpp:1572] Will retry registration in 55ns if
necessary
I0803 15:26:36.378590 5131 master.cpp:5912] Admitted agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at slave(235)@172.17.0.3:45885
(dab9864a824c)
I0803 15:26:36.380218 5128 hierarchical.cpp:593] Added agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 (dab9864a824c) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
I0803 15:26:36.382318 5128 hierarchical.cpp:2015] No inverse offers to send
out!
I0803 15:26:36.382490 5128 hierarchical.cpp:1468] Performed allocation for 1
agents in 2.010358ms
I0803 15:26:36.382992 5131 master.cpp:5943] Registered agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at slave(235)@172.17.0.3:45885
(dab9864a824c) with
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0803 15:26:36.384522 5131 master.cpp:7667] Sending 1 offers to framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 (default) at
[email protected]:45885
I0803 15:26:36.385215 5125 slave.cpp:4883] Received ping from
slave-observer(229)@172.17.0.3:45885
I0803 15:26:36.385638 5125 slave.cpp:1174] Registered with master
[email protected]:45885; given agent ID f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0
I0803 15:26:36.386237 5125 slave.cpp:1194] Checkpointing SlaveInfo to
'/tmp/MasterTest_RecoveredFramework_VEvT4Z/meta/slaves/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0/slave.info'
I0803 15:26:36.386380 5126 status_update_manager.cpp:184] Resuming sending
status updates
I0803 15:26:36.387020 5125 slave.cpp:1232] Forwarding total oversubscribed
resources {}
I0803 15:26:36.387470 5125 master.cpp:6688] Received update of agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at slave(235)@172.17.0.3:45885
(dab9864a824c) with total oversubscribed resources {}
I0803 15:26:36.388105 5124 sched.cpp:933] Scheduler::resourceOffers took
2.434958ms
I0803 15:26:36.388139 5128 hierarchical.cpp:660] Agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 (dab9864a824c) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0803 15:26:36.390372 5131 master.cpp:4153] Processing ACCEPT call for offers:
[ f1cdc1f2-c2c5-4404-9821-3e31ee142dab-O0 ] on agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at slave(235)@172.17.0.3:45885
(dab9864a824c) for framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
(default) at [email protected]:45885
I0803 15:26:36.390512 5131 master.cpp:3530] Authorizing framework principal
'test-principal' to launch task 0
W0803 15:26:36.393491 5131 validation.cpp:1294] Executor 'default' for task
'0' uses less CPUs (None) than the minimum required (0.01). Please update your
executor, as this will be mandatory in future releases.
W0803 15:26:36.393555 5131 validation.cpp:1306] Executor 'default' for task
'0' uses less memory (None) than the minimum required (32MB). Please update
your executor, as this will be mandatory in future releases.
I0803 15:26:36.394352 5131 master.cpp:9723] Adding task 0 with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":64.0},"type":"SCALAR"}]
on agent f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at
slave(235)@172.17.0.3:45885 (dab9864a824c)
I0803 15:26:36.395157 5131 master.cpp:4816] Launching task 0 of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 (default) at
[email protected]:45885 with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":64.0},"type":"SCALAR"}]
on agent f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at
slave(235)@172.17.0.3:45885 (dab9864a824c)
I0803 15:26:36.396236 5124 slave.cpp:1697] Got assigned task '0' for framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.397425 5124 slave.cpp:1978] Authorizing task '0' for framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.397819 5124 slave.cpp:6706] Authorizing framework principal
'test-principal' to launch task 0
I0803 15:26:36.399188 5130 slave.cpp:2165] Launching task '0' for framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.399919 5131 hierarchical.cpp:887] Updated allocation of
framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 on agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 from cpus(allocated: *):2;
mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] to cpus(allocated: *):2; mem(allocated: *):1024;
disk(allocated: *):1024; ports(allocated: *):[31000-32000]
I0803 15:26:36.400616 5130 paths.cpp:578] Trying to chown
'/tmp/MasterTest_RecoveredFramework_VEvT4Z/slaves/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0/frameworks/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000/executors/default/runs/3a01ab9d-85b6-4be7-896f-4da46d9d67be'
to user 'mesos'
I0803 15:26:36.401213 5130 slave.cpp:7162] Launching executor 'default' of
framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 with resources [] in work
directory
'/tmp/MasterTest_RecoveredFramework_VEvT4Z/slaves/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0/frameworks/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000/executors/default/runs/3a01ab9d-85b6-4be7-896f-4da46d9d67be'
I0803 15:26:36.401792 5131 hierarchical.cpp:1152] Recovered cpus(allocated:
*):1; mem(allocated: *):960; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000],
allocated: cpus(allocated: *):1; mem(allocated: *):64) on agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 from framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.401901 5131 hierarchical.cpp:1189] Framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 filtered agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 for 5secs
I0803 15:26:36.402431 5130 slave.cpp:2852] Launching container
3a01ab9d-85b6-4be7-896f-4da46d9d67be for executor 'default' of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.403251 5130 slave.cpp:2394] Queued task '0' for executor
'default' of framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.403671 5130 slave.cpp:924] Successfully attached file
'/tmp/MasterTest_RecoveredFramework_VEvT4Z/slaves/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0/frameworks/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000/executors/default/runs/3a01ab9d-85b6-4be7-896f-4da46d9d67be'
I0803 15:26:36.403784 5130 slave.cpp:924] Successfully attached file
'/tmp/MasterTest_RecoveredFramework_VEvT4Z/slaves/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0/frameworks/f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000/executors/default/runs/3a01ab9d-85b6-4be7-896f-4da46d9d67be'
I0803 15:26:36.407076 5128 process.cpp:3233] Attempting to spawn already
spawned process [email protected]:45885
I0803 15:26:36.407591 5128 exec.cpp:162] Version: 1.4.0
I0803 15:26:36.408428 5129 exec.cpp:212] Executor started at:
executor(83)@172.17.0.3:45885 with pid 5105
I0803 15:26:36.409027 5128 slave.cpp:3877] Got registration for executor
'default' of framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 from
executor(83)@172.17.0.3:45885
I0803 15:26:36.409967 5124 exec.cpp:237] Executor registered on agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0
I0803 15:26:36.410037 5124 exec.cpp:249] Executor::registered took 28091ns
I0803 15:26:36.410691 5126 slave.cpp:2599] Sending queued task '0' to executor
'default' of framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 at
executor(83)@172.17.0.3:45885
I0803 15:26:36.411231 5126 exec.cpp:331] Executor asked to run task '0'
I0803 15:26:36.411330 5126 exec.cpp:340] Executor::launchTask took 69064ns
I0803 15:26:36.411484 5126 exec.cpp:571] Executor sending status update
TASK_RUNNING (UUID: 52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of
framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.412021 5125 slave.cpp:4341] Handling status update TASK_RUNNING
(UUID: 52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 from executor(83)@172.17.0.3:45885
I0803 15:26:36.413193 5129 status_update_manager.cpp:323] Received status
update TASK_RUNNING (UUID: 52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of
framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.413275 5129 status_update_manager.cpp:500] Creating
StatusUpdate stream for task 0 of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.414366 5129 status_update_manager.cpp:377] Forwarding update
TASK_RUNNING (UUID: 52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of
framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 to the agent
I0803 15:26:36.415086 5128 slave.cpp:4793] Forwarding the update TASK_RUNNING
(UUID: 52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 to [email protected]:45885
I0803 15:26:36.415555 5128 slave.cpp:4687] Status update manager successfully
handled status update TASK_RUNNING (UUID: 52245be6-5f99-4211-9dcf-10bc04d98037)
for task 0 of framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.415630 5128 slave.cpp:4703] Sending acknowledgement for status
update TASK_RUNNING (UUID: 52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of
framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 to
executor(83)@172.17.0.3:45885
I0803 15:26:36.416040 5128 exec.cpp:388] Executor received status update
acknowledgement 52245be6-5f99-4211-9dcf-10bc04d98037 for task 0 of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.416074 5127 master.cpp:6846] Status update TASK_RUNNING (UUID:
52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 from agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at slave(235)@172.17.0.3:45885
(dab9864a824c)
I0803 15:26:36.416162 5127 master.cpp:6908] Forwarding status update
TASK_RUNNING (UUID: 52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of
framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.416419 5127 master.cpp:8933] Updating the state of task 0 of
framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 (latest state:
TASK_RUNNING, status update state: TASK_RUNNING)
I0803 15:26:36.416854 5127 sched.cpp:1041] Scheduler::statusUpdate took
136735ns
I0803 15:26:36.417382 5127 master.cpp:5477] Processing ACKNOWLEDGE call
52245be6-5f99-4211-9dcf-10bc04d98037 for task 0 of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 (default) at
[email protected]:45885 on agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0
I0803 15:26:36.417799 5127 status_update_manager.cpp:395] Received status
update acknowledgement (UUID: 52245be6-5f99-4211-9dcf-10bc04d98037) for task 0
of framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.418203 5130 slave.cpp:3605] Status update manager successfully
handled status update acknowledgement (UUID:
52245be6-5f99-4211-9dcf-10bc04d98037) for task 0 of framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
I0803 15:26:36.421093 5130 process.cpp:3938] Handling HTTP event for process
'master' with path: '/master/state'
I0803 15:26:36.423900 5131 http.cpp:1166] HTTP GET for /master/state from
172.17.0.3:44574
I0803 15:26:36.434751 5105 master.cpp:1160] Master terminating
W0803 15:26:36.434900 5105 master.cpp:9013] Removing task 0 with resources
[{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":64.0},"type":"SCALAR"}]
of framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 on agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at slave(235)@172.17.0.3:45885
(dab9864a824c) in non-removable state TASK_RUNNING
I0803 15:26:36.435852 5105 master.cpp:9056] Removing executor 'default' with
resources [] of framework f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000 on agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0 at slave(235)@172.17.0.3:45885
(dab9864a824c)
I0803 15:26:36.435901 5126 hierarchical.cpp:626] Removed agent
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-S0
I0803 15:26:36.444712 5124 slave.cpp:4925] Got exited event for
[email protected]:45885
W0803 15:26:36.444773 5124 slave.cpp:4930] Master disconnected! Waiting for a
new master to be elected
I0803 15:26:36.445163 5130 hierarchical.cpp:355] Removed framework
f1cdc1f2-c2c5-4404-9821-3e31ee142dab-0000
F0803 15:26:36.451879 5105 mesos.cpp:136] CHECK_SOME(os::write(fd.get(),
stringify(JSON::protobuf(credentials)))): No space left on device Failed to
write credentials to '/tmp/4bo7ky/credentials'
*** Check failure stack trace: ***
@ 0x2b4acbec40e8 google::LogMessage::Fail()
@ 0x2b4acbec4034 google::LogMessage::SendToLog()
@ 0x2b4acbec3a36 google::LogMessage::Flush()
@ 0x2b4acbec694a google::LogMessageFatal::~LogMessageFatal()
@ 0x5617143f933a _CheckFatal::~_CheckFatal()
@ 0x561714f5227f mesos::internal::tests::MesosTest::CreateMasterFlags()
@ 0x561714f53bd1 mesos::internal::tests::MesosTest::StartMaster()
@ 0x561714e0dc42
mesos::internal::tests::MasterTest_RecoveredFramework_Test::TestBody()
@ 0x5617159a3b1e
testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x56171599e19c
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x56171597e455 testing::Test::Run()
@ 0x56171597ec6e testing::TestInfo::Run()
@ 0x56171597f2b4 testing::TestCase::Run()
@ 0x561715985a98 testing::internal::UnitTestImpl::RunAllTests()
@ 0x5617159a4743
testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x56171599ecdc
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x561715984834 testing::UnitTest::Run()
@ 0x561714c5ec38 RUN_ALL_TESTS()
@ 0x561714c5e769 main
@ 0x2b4ace5d1f45 (unknown)
@ 0x5617143f7709 (unknown)
make[4]: *** [check-local] Aborted (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1501768382-26084
Untagged: mesos-1501768382-26084:latest
Deleted: sha256:24f6f9f2972e2fd092b5d7a4d72446bcd4eac3bd785385217728b6b246092b10
Deleted: sha256:e410b1f9a0af807ccf2e5ff6ca079df22ff7195287d441758b26682fccd29536
Deleted: sha256:a5dc071abf31ae590cb84ae182654b4a856073fc0474dbad2de228fd95d17305
Deleted: sha256:5201a57df2bd6ba81f325e9c8dbb95f45f20aa8bada1c645c876924130d99b84
Deleted: sha256:5e2c979e42e340c377fec2f7bdad52eea689e8c3ca4ca9221d86b0d1a1767ea2
Deleted: sha256:44d91a88e1df985a3004c8704d2ef2ed314949a04dd457d4d25eaaa8fbe06acd
Deleted: sha256:853b75e08beed64dca5ca5f8461d334c438593440f0d1b2a027f10f909cd939e
Deleted: sha256:96e49e3737437f53df75af89aad2209e96e42fe41308645153e5d64186591c5d
Deleted: sha256:9b5cd114f94518c08c7aa60e88c2a49871993416db93611ed6089eefcc10846d
Deleted: sha256:15061035b668d18eb0f9ac5d49729ddf4e8bfedde9ba5726a81519f5e170afe0
Deleted: sha256:e367255fe3dce679c29149c9620c6e18c7b0d88df229428bb48c3d41ae90ce0b
Deleted: sha256:855bf3be0e7eb9b0cc3e367d41a57f82d5a78f66b45da155472a1f9abc3facae
Deleted: sha256:b3709b53bcc0bd75b4f33f859e18bf77037214cdc30681d490f8680bb48572d6
Deleted: sha256:6122beb05638c011202708ee5367c0e95dcba2c19c65dd3ba18da5cec4d2357a
Deleted: sha256:85593874447df1699ed210141270a679f901e5905b92ec1195c8a895da3edafb
Deleted: sha256:fa9ba168953e4d082ce7e719c1217401a1f3e59092df855c4abe2647aa9e5a64
Deleted: sha256:ab7b3ee28d0f6282c94b3e41cbbcccd0c70d1143af1cd4622a1e6803af6104ed
Deleted: sha256:f53eeafe1c443bd98c5a640a33a3052cd2606680ceea82b9c23bcdaf1eab10ae
Deleted: sha256:a0326cc0c7fdd8a01060e7f8fb0682401eb9bf8329792b2e53c982be85a8eca9
Deleted: sha256:8ee2eaefe0f609d61d2505b0cd29c23d17b68dc064bb8e475d79a9411190a090
Deleted: sha256:50e3bc688b25304432e366b77a2f694e5f03cc1a052741ce023b25f48bbb0151
Deleted: sha256:f95d0282be6e67b719bb596607362293aa51bfd19a25ae74691255983a228aed
Deleted: sha256:bad03c61b8dde1d4ff3e83500e0a5587886ee4eb4309558e98221230d3f1aade
Deleted: sha256:8e04f5c30bfd4cc909523a3c51f1c29fb4d2f4cbc99516a83a16a28ad0a65c8a
Build step 'Execute shell' marked build as failure
