See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3549/display/redirect?page=changes>
Changes:
[anand] Added MESOS-7400 to 1.2.1 CHANGELOG.
[vinodkone] Changed 'Principal.claims' to a hashmap.
[vinodkone] Allowed the local authorizer to accept subjects with no value.
[vinodkone] Added a ContainerID to 'ObjectApprover::Object'.
[vinodkone] Added implicit executor authorization to the agent operator API.
[vinodkone] Added implicit authorization to the agent executor API.
[vinodkone] Added a new agent authorization test which runs a task group.
[vinodkone] Added tests for failed executor authorization.
[vinodkone] Enabled authorization in default executor check tests.
------------------------------------------
[...truncated 17.96 MB...]
I0421 21:54:15.346823 27777 http.cpp:1115] HTTP POST for /(558)/api/v1/executor
from 172.17.0.2:45392
I0421 21:54:15.347412 27777 executor.cpp:723] Enqueuing locally injected event
ERROR
I0421 21:54:15.347877 27772 slave.cpp:790] Agent terminating
I0421 21:54:15.347908 27772 slave.cpp:3057] Asked to shut down framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 by @0.0.0.0:0
I0421 21:54:15.347923 27772 slave.cpp:3082] Shutting down framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000
I0421 21:54:15.347937 27772 slave.cpp:5475] Shutting down executor 'default' of
framework 5da98cb1-0b60-453d-bba9-829d03993ab9-0000
W0421 21:54:15.347949 27772 slave.hpp:986] Unable to send event to executor
'default' of framework 5da98cb1-0b60-453d-bba9-829d03993ab9-0000: unknown
connection type
I0421 21:54:15.348124 27772 master.cpp:1315] Agent
5da98cb1-0b60-453d-bba9-829d03993ab9-S0 at (558)@172.17.0.2:44744
(87e23b2fa107) disconnected
I0421 21:54:15.348150 27772 master.cpp:3199] Disconnecting agent
5da98cb1-0b60-453d-bba9-829d03993ab9-S0 at (558)@172.17.0.2:44744 (87e23b2fa107)
I0421 21:54:15.348171 27772 master.cpp:3218] Deactivating agent
5da98cb1-0b60-453d-bba9-829d03993ab9-S0 at (558)@172.17.0.2:44744 (87e23b2fa107)
I0421 21:54:15.348204 27772 master.cpp:1342] Removing framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (default) from disconnected agent
5da98cb1-0b60-453d-bba9-829d03993ab9-S0 at (558)@172.17.0.2:44744
(87e23b2fa107) because the framework is not checkpointing
I0421 21:54:15.348219 27772 master.cpp:7946] Removing framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (default) from agent
5da98cb1-0b60-453d-bba9-829d03993ab9-S0 at (558)@172.17.0.2:44744 (87e23b2fa107)
I0421 21:54:15.348258 27772 master.cpp:8350] Updating the state of task
06c18e33-ec58-4e11-a99d-42df80f9988c of framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (latest state: TASK_LOST, status
update state: TASK_LOST)
I0421 21:54:15.348408 27772 master.cpp:8444] Removing task
06c18e33-ec58-4e11-a99d-42df80f9988c with resources cpus(*)(allocated: *):0.1;
mem(*)(allocated: *):32; disk(*)(allocated: *):32 of framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 on agent
5da98cb1-0b60-453d-bba9-829d03993ab9-S0 at (558)@172.17.0.2:44744 (87e23b2fa107)
I0421 21:54:15.348497 27772 master.cpp:6314] Sending status update TASK_LOST
for task 06c18e33-ec58-4e11-a99d-42df80f9988c of framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 'Agent 87e23b2fa107 disconnected'
I0421 21:54:15.348636 27772 master.cpp:8473] Removing executor 'default' with
resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32;
disk(*)(allocated: *):32 of framework 5da98cb1-0b60-453d-bba9-829d03993ab9-0000
on agent 5da98cb1-0b60-453d-bba9-829d03993ab9-S0 at (558)@172.17.0.2:44744
(87e23b2fa107)
I0421 21:54:15.349552 27777 master.cpp:1432] Framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (default) disconnected
I0421 21:54:15.349663 27777 master.cpp:3162] Deactivating framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (default)
I0421 21:54:15.349794 27777 master.cpp:3139] Disconnecting framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (default)
I0421 21:54:15.349895 27777 master.cpp:1447] Giving framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (default) 0ns to failover
I0421 21:54:15.350164 27777 master.cpp:6928] Framework failover timeout,
removing framework 5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (default)
I0421 21:54:15.350283 27777 master.cpp:7782] Removing framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000 (default)
I0421 21:54:15.350401 27772 hierarchical.cpp:655] Agent
5da98cb1-0b60-453d-bba9-829d03993ab9-S0 deactivated
I0421 21:54:15.350599 27772 hierarchical.cpp:1116] Recovered cpus(*)(allocated:
*):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 (total: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated:
cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32)
on agent 5da98cb1-0b60-453d-bba9-829d03993ab9-S0 from framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000
I0421 21:54:15.350718 27772 hierarchical.cpp:1116] Recovered cpus(*)(allocated:
*):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 (total: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent
5da98cb1-0b60-453d-bba9-829d03993ab9-S0 from framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000
I0421 21:54:15.350754 27772 hierarchical.cpp:376] Deactivated framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000
I0421 21:54:15.350797 27772 hierarchical.cpp:323] Removed framework
5da98cb1-0b60-453d-bba9-829d03993ab9-0000
I0421 21:54:15.356803 27768 master.cpp:1157] Master terminating
[ OK ] ExecutorAuthorizationTest.FailedSubscribe (86 ms)
[ RUN ] ExecutorAuthorizationTest.FailedApiCalls
I0421 21:54:15.365419 27768 cluster.cpp:162] Creating default 'local' authorizer
I0421 21:54:15.366902 27780 master.cpp:438] Master
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112 (87e23b2fa107) started on 172.17.0.2:44744
I0421 21:54:15.366924 27780 master.cpp:440] 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/mbQI5t/credentials"
--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/mbQI5t/master"
--zk_session_timeout="10secs"
I0421 21:54:15.367079 27780 master.cpp:490] Master only allowing authenticated
frameworks to register
I0421 21:54:15.367086 27780 master.cpp:504] Master only allowing authenticated
agents to register
I0421 21:54:15.367092 27780 master.cpp:517] Master only allowing authenticated
HTTP frameworks to register
I0421 21:54:15.367099 27780 credentials.hpp:37] Loading credentials for
authentication from '/tmp/mbQI5t/credentials'
I0421 21:54:15.367252 27780 master.cpp:562] Using default 'crammd5'
authenticator
I0421 21:54:15.367305 27780 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0421 21:54:15.367341 27780 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0421 21:54:15.367363 27780 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0421 21:54:15.367398 27780 master.cpp:642] Authorization enabled
I0421 21:54:15.367532 27784 whitelist_watcher.cpp:77] No whitelist given
I0421 21:54:15.367561 27769 hierarchical.cpp:159] Initialized hierarchical
allocator process
I0421 21:54:15.369426 27776 master.cpp:2163] Elected as the leading master!
I0421 21:54:15.369448 27776 master.cpp:1702] Recovering from registrar
I0421 21:54:15.369513 27776 registrar.cpp:345] Recovering registrar
I0421 21:54:15.369751 27776 registrar.cpp:389] Successfully fetched the
registry (0B) in 219904ns
I0421 21:54:15.369791 27776 registrar.cpp:493] Applied 1 operations in 6185ns;
attempting to update the registry
I0421 21:54:15.369988 27776 registrar.cpp:550] Successfully updated the
registry in 178944ns
I0421 21:54:15.370028 27776 registrar.cpp:422] Successfully recovered registrar
I0421 21:54:15.370142 27776 master.cpp:1801] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0421 21:54:15.370201 27776 hierarchical.cpp:186] Skipping recovery of
hierarchical allocator: nothing to recover
I0421 21:54:15.374058 27768 cluster.cpp:448] Creating default 'local' authorizer
I0421 21:54:15.375326 27768 scheduler.cpp:184] Version: 1.3.0
I0421 21:54:15.375458 27775 slave.cpp:225] Mesos agent started on
(494)@172.17.0.2:44744
I0421 21:54:15.375771 27783 scheduler.cpp:470] New master detected at
[email protected]:44744
I0421 21:54:15.375798 27783 scheduler.cpp:479] Waiting for 0ns before
initiating a re-(connection) attempt with the master
I0421 21:54:15.375488 27775 slave.cpp:226] Flags at startup:
--acls="permissive: true
" --appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/mesos/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/ExecutorAuthorizationTest_FailedApiCalls_cEaNQY/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/mesos/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_secret_key="/tmp/ExecutorAuthorizationTest_FailedApiCalls_cEaNQY/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ExecutorAuthorizationTest_FailedApiCalls_cEaNQY/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/ExecutorAuthorizationTest_FailedApiCalls_cEaNQY/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" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/ExecutorAuthorizationTest_FailedApiCalls_cEaNQY"
--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/ExecutorAuthorizationTest_FailedApiCalls_MEefte"
I0421 21:54:15.376170 27775 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ExecutorAuthorizationTest_FailedApiCalls_cEaNQY/credential'
I0421 21:54:15.376693 27775 slave.cpp:258] Agent using credential for:
test-principal
I0421 21:54:15.376709 27775 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ExecutorAuthorizationTest_FailedApiCalls_cEaNQY/http_credentials'
I0421 21:54:15.376936 27783 scheduler.cpp:361] Connected with the master at
http://172.17.0.2:44744/master/api/v1/scheduler
I0421 21:54:15.379628 27783 scheduler.cpp:243] Sending SUBSCRIBE call to
http://172.17.0.2:44744/master/api/v1/scheduler
I0421 21:54:15.381819 27783 process.cpp:3722] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0421 21:54:15.382200 27783 http.cpp:1115] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:45395
I0421 21:54:15.382292 27783 master.cpp:2515] Received subscription request for
HTTP framework 'default'
I0421 21:54:15.382323 27783 master.cpp:2199] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0421 21:54:15.382542 27783 master.cpp:2630] Subscribing framework 'default'
with checkpointing disabled and capabilities [ ]
I0421 21:54:15.382803 27783 hierarchical.cpp:271] Added framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.382846 27783 hierarchical.cpp:1862] No allocations performed
I0421 21:54:15.382858 27783 hierarchical.cpp:1952] No inverse offers to send
out!
I0421 21:54:15.382869 27783 hierarchical.cpp:1446] Performed allocation for 0
agents in 30257ns
I0421 21:54:15.382951 27783 master.hpp:2167] Sending heartbeat to
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.386775 27769 scheduler.cpp:676] Enqueuing event SUBSCRIBED
received from http://172.17.0.2:44744/master/api/v1/scheduler
I0421 21:54:15.387316 27784 scheduler.cpp:676] Enqueuing event HEARTBEAT
received from http://172.17.0.2:44744/master/api/v1/scheduler
I0421 21:54:15.396191 27775 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
I0421 21:54:15.396284 27775 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
I0421 21:54:15.396386 27775 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0421 21:54:15.396415 27775 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
I0421 21:54:15.396499 27775 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0421 21:54:15.399919 27775 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0421 21:54:15.400563 27775 slave.cpp:525] Agent resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0421 21:54:15.400619 27775 slave.cpp:533] Agent attributes: [ ]
I0421 21:54:15.400629 27775 slave.cpp:538] Agent hostname: 87e23b2fa107
I0421 21:54:15.400961 27769 status_update_manager.cpp:177] Pausing sending
status updates
I0421 21:54:15.401115 27772 state.cpp:62] Recovering state from
'/tmp/ExecutorAuthorizationTest_FailedApiCalls_MEefte/meta'
I0421 21:54:15.401360 27771 status_update_manager.cpp:203] Recovering status
update manager
I0421 21:54:15.401571 27771 slave.cpp:5963] Finished recovery
I0421 21:54:15.402024 27771 slave.cpp:6145] Querying resource estimator for
oversubscribable resources
I0421 21:54:15.402138 27771 slave.cpp:918] New master detected at
[email protected]:44744
I0421 21:54:15.402176 27771 slave.cpp:953] Detecting new master
I0421 21:54:15.402202 27771 slave.cpp:6159] Received oversubscribable resources
{} from the resource estimator
I0421 21:54:15.402225 27771 status_update_manager.cpp:177] Pausing sending
status updates
I0421 21:54:15.408596 27771 slave.cpp:980] Authenticating with master
[email protected]:44744
I0421 21:54:15.408653 27771 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0421 21:54:15.408771 27771 authenticatee.cpp:121] Creating new client SASL
connection
I0421 21:54:15.408996 27771 master.cpp:7257] Authenticating
slave(494)@172.17.0.2:44744
I0421 21:54:15.409122 27780 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1007)@172.17.0.2:44744
I0421 21:54:15.409212 27780 authenticator.cpp:98] Creating new server SASL
connection
I0421 21:54:15.409346 27780 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0421 21:54:15.409363 27780 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0421 21:54:15.409400 27780 authenticator.cpp:204] Received SASL authentication
start
I0421 21:54:15.409447 27780 authenticator.cpp:326] Authentication requires more
steps
I0421 21:54:15.409481 27780 authenticatee.cpp:259] Received SASL authentication
step
I0421 21:54:15.409523 27780 authenticator.cpp:232] Received SASL authentication
step
I0421 21:54:15.409543 27780 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '87e23b2fa107' server FQDN: '87e23b2fa107'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0421 21:54:15.409553 27780 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0421 21:54:15.409566 27780 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0421 21:54:15.409579 27780 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '87e23b2fa107' server FQDN: '87e23b2fa107'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0421 21:54:15.409587 27780 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0421 21:54:15.409595 27780 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0421 21:54:15.409610 27780 authenticator.cpp:318] Authentication success
I0421 21:54:15.409662 27780 authenticatee.cpp:299] Authentication success
I0421 21:54:15.409696 27780 master.cpp:7287] Successfully authenticated
principal 'test-principal' at slave(494)@172.17.0.2:44744
I0421 21:54:15.409726 27780 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1007)@172.17.0.2:44744
I0421 21:54:15.409811 27780 slave.cpp:1075] Successfully authenticated with
master [email protected]:44744
I0421 21:54:15.409886 27780 slave.cpp:1503] Will retry registration in
18.15501ms if necessary
I0421 21:54:15.410040 27780 master.cpp:5447] Registering agent at
slave(494)@172.17.0.2:44744 (87e23b2fa107) with id
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0
I0421 21:54:15.410202 27780 registrar.cpp:493] Applied 1 operations in 21366ns;
attempting to update the registry
I0421 21:54:15.410429 27780 registrar.cpp:550] Successfully updated the
registry in 203008ns
I0421 21:54:15.410745 27780 master.cpp:5521] Registered agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 at slave(494)@172.17.0.2:44744
(87e23b2fa107) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0421 21:54:15.410810 27773 slave.cpp:1121] Registered with master
[email protected]:44744; given agent ID 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0
I0421 21:54:15.410826 27773 fetcher.cpp:94] Clearing fetcher cache
I0421 21:54:15.410835 27782 hierarchical.cpp:527] Added agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 (87e23b2fa107) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0421 21:54:15.411020 27780 status_update_manager.cpp:184] Resuming sending
status updates
I0421 21:54:15.411201 27782 hierarchical.cpp:1952] No inverse offers to send
out!
I0421 21:54:15.411223 27782 hierarchical.cpp:1446] Performed allocation for 1
agents in 327547ns
I0421 21:54:15.411442 27770 master.cpp:7087] Sending 1 offers to framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default)
I0421 21:54:15.412281 27784 scheduler.cpp:676] Enqueuing event OFFERS received
from http://172.17.0.2:44744/master/api/v1/scheduler
I0421 21:54:15.413208 27784 scheduler.cpp:243] Sending ACCEPT call to
http://172.17.0.2:44744/master/api/v1/scheduler
I0421 21:54:15.414034 27784 process.cpp:3722] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0421 21:54:15.414434 27784 http.cpp:1115] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:45394
I0421 21:54:15.414769 27784 master.cpp:3853] Processing ACCEPT call for offers:
[ 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-O0 ] on agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 at slave(494)@172.17.0.2:44744
(87e23b2fa107) for framework 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default)
I0421 21:54:15.414831 27784 master.cpp:3429] Authorizing framework principal
'test-principal' to launch task b3ee5920-bede-426e-868a-65bd2c8f4748
I0421 21:54:15.416249 27784 master.cpp:9102] Adding task
b3ee5920-bede-426e-868a-65bd2c8f4748 with resources cpus(*)(allocated: *):0.1;
mem(*)(allocated: *):32; disk(*)(allocated: *):32 on agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 at slave(494)@172.17.0.2:44744
(87e23b2fa107)
I0421 21:54:15.416342 27784 master.cpp:4708] Launching task group {
b3ee5920-bede-426e-868a-65bd2c8f4748 } of framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default) with resources
cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 on
agent 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 at slave(494)@172.17.0.2:44744
(87e23b2fa107)
I0421 21:54:15.416612 27770 hierarchical.cpp:1116] Recovered cpus(*)(allocated:
*):1.8; mem(*)(allocated: *):960; disk(*)(allocated: *):960;
ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):0.2;
mem(*)(allocated: *):64; disk(*)(allocated: *):64) on agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 from framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.416647 27770 hierarchical.cpp:1153] Framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 filtered agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 for 5secs
I0421 21:54:15.447882 27773 slave.cpp:1149] Checkpointing SlaveInfo to
'/tmp/ExecutorAuthorizationTest_FailedApiCalls_MEefte/meta/slaves/0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0/slave.info'
I0421 21:54:15.448282 27773 slave.cpp:1187] Forwarding total oversubscribed
resources {}
I0421 21:54:15.448421 27781 master.cpp:6106] Received update of agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 at slave(494)@172.17.0.2:44744
(87e23b2fa107) with total oversubscribed resources {}
I0421 21:54:15.448475 27773 slave.cpp:4745] Received ping from
slave-observer(483)@172.17.0.2:44744
I0421 21:54:15.448657 27773 slave.cpp:1613] Got assigned task group containing
tasks [ b3ee5920-bede-426e-868a-65bd2c8f4748 ] for framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.449112 27773 slave.cpp:1894] Authorizing task group containing
tasks [ b3ee5920-bede-426e-868a-65bd2c8f4748 ] for framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.449143 27773 slave.cpp:6582] Authorizing framework principal
'test-principal' to launch task b3ee5920-bede-426e-868a-65bd2c8f4748
I0421 21:54:15.449643 27773 slave.cpp:2081] Launching task group containing
tasks [ b3ee5920-bede-426e-868a-65bd2c8f4748 ] for framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.450328 27773 paths.cpp:556] Trying to chown
'/tmp/ExecutorAuthorizationTest_FailedApiCalls_MEefte/slaves/0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0/frameworks/0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000/executors/default/runs/4df3e01c-e1c9-4d15-9bf1-b8e8bc2e23cb'
to user 'mesos'
I0421 21:54:15.450572 27773 slave.cpp:6926] Launching executor 'default' of
framework 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 with resources
cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 in
work directory
'/tmp/ExecutorAuthorizationTest_FailedApiCalls_MEefte/slaves/0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0/frameworks/0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000/executors/default/runs/4df3e01c-e1c9-4d15-9bf1-b8e8bc2e23cb'
I0421 21:54:15.450969 27773 slave.cpp:2310] Queued task group containing tasks
[ b3ee5920-bede-426e-868a-65bd2c8f4748 ] for executor 'default' of framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.451076 27773 slave.cpp:871] Successfully attached file
'/tmp/ExecutorAuthorizationTest_FailedApiCalls_MEefte/slaves/0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0/frameworks/0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000/executors/default/runs/4df3e01c-e1c9-4d15-9bf1-b8e8bc2e23cb'
I0421 21:54:15.452584 27778 executor.cpp:192] Version: 1.3.0
I0421 21:54:15.453783 27780 executor.cpp:410] Connected with the agent
I0421 21:54:15.454519 27784 executor.cpp:307] Sending SUBSCRIBE call to
http://172.17.0.2:44744/slave(494)/api/v1/executor
I0421 21:54:15.455075 27784 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1/executor'
I0421 21:54:15.455900 27784 http.cpp:1115] HTTP POST for
/slave(494)/api/v1/executor from 172.17.0.2:45404
I0421 21:54:15.455991 27784 slave.cpp:3568] Received Subscribe request for HTTP
executor 'default' of framework 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.457371 27784 slave.cpp:2552] Sending queued task group task
group containing tasks [ b3ee5920-bede-426e-868a-65bd2c8f4748 ] to executor
'default' of framework 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (via HTTP)
I0421 21:54:15.460391 27773 executor.cpp:725] Enqueuing event SUBSCRIBED
received from http://172.17.0.2:44744/slave(494)/api/v1/executor
I0421 21:54:15.460721 27784 executor.cpp:725] Enqueuing event LAUNCH_GROUP
received from http://172.17.0.2:44744/slave(494)/api/v1/executor
I0421 21:54:15.462455 27778 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1'
I0421 21:54:15.463165 27778 http.cpp:1115] HTTP POST for /slave(494)/api/v1
from 172.17.0.2:45412
I0421 21:54:15.463305 27778 http.cpp:527] Processing call
LAUNCH_NESTED_CONTAINER
I0421 21:54:15.464862 27783 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1'
I0421 21:54:15.465517 27783 http.cpp:1115] HTTP POST for /slave(494)/api/v1
from 172.17.0.2:45413
I0421 21:54:15.465677 27783 http.cpp:527] Processing call
LAUNCH_NESTED_CONTAINER_SESSION
I0421 21:54:15.469816 27778 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1'
I0421 21:54:15.470537 27778 http.cpp:1115] HTTP POST for /slave(494)/api/v1
from 172.17.0.2:45414
I0421 21:54:15.470684 27778 http.cpp:527] Processing call WAIT_NESTED_CONTAINER
I0421 21:54:15.472357 27774 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1'
I0421 21:54:15.473042 27769 http.cpp:1115] HTTP POST for /slave(494)/api/v1
from 172.17.0.2:45415
I0421 21:54:15.473152 27769 http.cpp:527] Processing call KILL_NESTED_CONTAINER
I0421 21:54:15.479714 27776 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1'
I0421 21:54:15.480401 27776 http.cpp:1115] HTTP POST for /slave(494)/api/v1
from 172.17.0.2:45416
I0421 21:54:15.480551 27776 http.cpp:527] Processing call
REMOVE_NESTED_CONTAINER
I0421 21:54:15.482995 27773 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1'
I0421 21:54:15.485586 27772 http.cpp:1115] HTTP POST for /slave(494)/api/v1
from 172.17.0.2:45417
I0421 21:54:15.485766 27772 http.cpp:527] Processing call
ATTACH_CONTAINER_OUTPUT
I0421 21:54:15.488070 27772 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1/executor'
I0421 21:54:15.488983 27778 http.cpp:1115] HTTP POST for
/slave(494)/api/v1/executor from 172.17.0.2:45418
I0421 21:54:15.490478 27783 process.cpp:3722] Handling HTTP event for process
'slave(494)' with path: '/slave(494)/api/v1/executor'
I0421 21:54:15.491421 27777 http.cpp:1115] HTTP POST for
/slave(494)/api/v1/executor from 172.17.0.2:45420
I0421 21:54:15.496040 27776 master.cpp:1432] Framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default) disconnected
I0421 21:54:15.496074 27776 master.cpp:3162] Deactivating framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default)
I0421 21:54:15.496101 27776 master.cpp:3139] Disconnecting framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default)
I0421 21:54:15.496115 27776 master.cpp:1447] Giving framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default) 0ns to failover
I0421 21:54:15.496140 27769 hierarchical.cpp:376] Deactivated framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.496260 27772 master.cpp:6928] Framework failover timeout,
removing framework 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default)
I0421 21:54:15.496276 27772 master.cpp:7782] Removing framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (default)
I0421 21:54:15.496346 27772 master.cpp:8350] Updating the state of task
b3ee5920-bede-426e-868a-65bd2c8f4748 of framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
I0421 21:54:15.496363 27771 slave.cpp:3057] Asked to shut down framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 by [email protected]:44744
I0421 21:54:15.496399 27771 slave.cpp:3082] Shutting down framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.496414 27771 slave.cpp:5475] Shutting down executor 'default' of
framework 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 (via HTTP)
I0421 21:54:15.496529 27772 master.cpp:8444] Removing task
b3ee5920-bede-426e-868a-65bd2c8f4748 with resources cpus(*)(allocated: *):0.1;
mem(*)(allocated: *):32; disk(*)(allocated: *):32 of framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000 on agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 at slave(494)@172.17.0.2:44744
(87e23b2fa107)
I0421 21:54:15.496662 27772 master.cpp:8473] Removing executor 'default' with
resources cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32;
disk(*)(allocated: *):32 of framework 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
on agent 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 at slave(494)@172.17.0.2:44744
(87e23b2fa107)
I0421 21:54:15.496682 27771 hierarchical.cpp:1116] Recovered cpus(*)(allocated:
*):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 (total: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated:
cpus(*)(allocated: *):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32)
on agent 0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 from framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.496904 27771 hierarchical.cpp:1116] Recovered cpus(*)(allocated:
*):0.1; mem(*)(allocated: *):32; disk(*)(allocated: *):32 (total: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-S0 from framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.497036 27771 hierarchical.cpp:323] Removed framework
0d3a29a3-ffe9-4e6c-9c43-bf81cff61112-0000
I0421 21:54:15.497769 27772 executor.cpp:725] Enqueuing event SHUTDOWN received
from http://172.17.0.2:44744/slave(494)/api/v1/executor
*** Aborted at 1492811655 (unix time) try "date -d @1492811655" if you are
using GNU date ***
PC: @ 0x1cacdc3
testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
*** SIGSEGV (@0x358) received by PID 27768 (TID 0x2ac9d5ce8700) from PID 856;
stack trace: ***
@ 0x2ac9ccad3330 (unknown)
@ 0x1cacdc3
testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
@ 0xdd77d1
mesos::internal::tests::executor::MockHTTPExecutor<>::event()
@ 0xdfb9d7 mesos::internal::tests::executor::TestMesos<>::events()
@ 0xde784d std::_Function_handler<>::_M_invoke()
@ 0x2ac9c9ea9805 process::AsyncExecutorProcess::execute<>()
@ 0x2ac9c9eb33e5
_ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeISC_SaISC_EEEEESG_SK_SG_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSR_FSO_T1_T2_ET3_T4_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x2ac9caea5011 process::ProcessManager::resume()
@ 0x2ac9caeade97
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2ac9ccd92a60 (unknown)
@ 0x2ac9ccacb184 start_thread
@ 0x2ac9cd5febed (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1492806359-24321
Untagged: mesos-1492806359-24321:latest
Deleted: sha256:5de95137678705e08c63e77cd277541de1ed111f6b794bea768cc299cc5878f7
Deleted: sha256:b4c0a8bd5926d63145e8a54f8270907af12f5d6853467b7bde0eb2a73335c357
Deleted: sha256:faaac0c9b72fdf813743b3ab14777ea128f77460823c124bb72934d4702aa69b
Deleted: sha256:02b51b272f10a3f2afdf787db451ad9aaa07fed5781fd04f262c7f8ddca99b29
Deleted: sha256:cd32636ce15faf9b3a5395eb0bfc0169c586c88c6130098956764f08d3ac0b31
Deleted: sha256:0366bbf791d220e60a8a53da0c09d7dceb01d2a3e1a71c1d77c5eb91ba531314
Deleted: sha256:43fd34bc8839d1dd12bb2860b2baa64990458c137c4eb25e82743859a1d7345d
Deleted: sha256:57f83795b5ca40ce1254b753bfe38e1c252b4296286c9e37c0f90789331d34ba
Deleted: sha256:8ebbc70c1e11102cda2f054fcc40f2a1f09636ebe484f2f77ac698f8b8edfe6c
Deleted: sha256:8af2fc446a116c02f336e5433afd4e41efaf650ffeb463add5c490a41fe4016e
Deleted: sha256:57426bfb9f0b4b97e214c8aa38dfdbbb5ac2000c630a38b35fce57c54b7b1f86
Deleted: sha256:2bfa7d803f27f0c2a1974d2f58368d1c97b0aad85aa2d811580714d85cf6a883
Deleted: sha256:f92e000eacc3e75105f8d2823993c748d824534adbd6c01879f1b14b67b75451
Deleted: sha256:0f74663a1c780f2534dbe21d9c83ab38419be269ed096a7f27cb276dc976bbff
Deleted: sha256:4d2c700b015d4bfda058c2db881c5d50d0986ca42aade2f2a74849b5cefddcfc
Deleted: sha256:1c7db7aef2d40815fdbefe14aa2896e0d2116284a23b186214637c7c3f684304
Deleted: sha256:071cd9588cc481f0c904b48b1ce7db5df372c616ccd656a7c19699767a9e3348
Deleted: sha256:213aa550421d299f0591e719e07ef81248cb1068ec7218ac0b89adc3526d559b
Deleted: sha256:7447a2faddab1ee56df58ca3ac9d52a890e3d9fbaeeacd2731c1ffa4572f1fb0
Deleted: sha256:520f1b4d0081e8db9821001cf54b1dcab4ca2f9b629e8039b09e1c19ee45c0c9
Deleted: sha256:175c917b6afc932f8e44940eb9fe86d5ce5f25b9fc63187e5f52c515fd638acf
Deleted: sha256:7cbf46c849558ef6ec32c385c49fa73ae2248b2e4790ccf5d57b09ec2aa6fb78
Deleted: sha256:7886f87f8b3cc90c823e6609c2c612817eaa44739460a1213deb6e4bd893707f
Deleted: sha256:c1e27cc89f1b66273c8b3468f51fba77e83e17c76bce1310702b42bf2832f93b
Build step 'Execute shell' marked build as failure
