See <https://builds.apache.org/job/Mesos-Reviewbot/17326/display/redirect?page=changes>
Changes: [vinodkone] Implemented the 'Principal' type in libprocess. [vinodkone] Updated libprocess handlers to use the 'Principal' type. [vinodkone] Updated common Mesos code to use the 'Principal' type. [vinodkone] Updated master validation code to use the 'Principal' type. [vinodkone] Fixed a bug in master and agent handler authorization logic. [vinodkone] Removed unnecessary 'using' statement in master HTTP code. [vinodkone] Updated 'Files' handlers to use the 'Principal' type. [vinodkone] Updated agent handlers to use the 'Principal' type. [vinodkone] Updated master handlers to use the 'Principal' type. [vinodkone] Updated libprocess tests to use the 'Principal' type. [vinodkone] Added a new libprocess test for invalid principals. [vinodkone] Updated Mesos tests to use the 'Principal' type. [vinodkone] Added default parameter value to master validation function. ------------------------------------------ [...truncated 10.48 MB...] I0306 22:48:43.603993 30437 sched.cpp:1041] Scheduler::statusUpdate took 121931ns I0306 22:48:43.604454 30438 master.cpp:5148] Processing ACKNOWLEDGE call b6b534a9-150c-4a48-964f-aaebd0a727f8 for task 1 of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 (default) at [email protected]:45774 on agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 I0306 22:48:43.604970 30431 exec.cpp:396] Executor received framework message I0306 22:48:43.605041 30430 status_update_manager.cpp:395] Received status update acknowledgement (UUID: b6b534a9-150c-4a48-964f-aaebd0a727f8) for task 1 of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.605134 30431 exec.cpp:405] Executor::frameworkMessage took 82790ns I0306 22:48:43.605465 30435 slave.cpp:3106] Status update manager successfully handled status update acknowledgement (UUID: b6b534a9-150c-4a48-964f-aaebd0a727f8) for task 1 of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.605994 30439 slave.cpp:4325] Sending message for framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 to [email protected]:45774 I0306 22:48:43.606416 30439 sched.cpp:1177] Scheduler::frameworkMessage took 82556ns I0306 22:48:43.606909 30410 sched.cpp:2021] Asked to stop the driver I0306 22:48:43.607060 30442 sched.cpp:1203] Stopping framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.607252 30430 master.cpp:7761] Processing TEARDOWN call for framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 (default) at [email protected]:45774 I0306 22:48:43.607303 30430 master.cpp:7773] Removing framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 (default) at [email protected]:45774 I0306 22:48:43.607326 30430 master.cpp:3145] Deactivating framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 (default) at [email protected]:45774 I0306 22:48:43.607508 30444 hierarchical.cpp:415] Deactivated framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.607609 30430 master.cpp:8359] Updating the state of task 1 of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I0306 22:48:43.607630 30429 slave.cpp:2664] Asked to shut down framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 by [email protected]:45774 I0306 22:48:43.607703 30429 slave.cpp:2689] Shutting down framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.607765 30429 slave.cpp:5084] Shutting down executor 'default' of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 at executor(74)@172.17.0.2:45774 I0306 22:48:43.607882 30437 exec.cpp:415] Executor asked to shutdown I0306 22:48:43.607982 30437 exec.cpp:430] Executor::shutdown took 26346ns I0306 22:48:43.608155 30437 slave.cpp:4389] Got exited event for executor(74)@172.17.0.2:45774 I0306 22:48:43.608618 30430 master.cpp:8453] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 on agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 at slave(179)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.609087 30430 master.cpp:8482] Removing executor 'default' with resources {} of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 on agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 at slave(179)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.609177 30432 hierarchical.cpp:1092] Recovered cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 from framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.609885 30438 hierarchical.cpp:362] Removed framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.610633 30433 slave.cpp:4770] Executor 'default' of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 exited with status 0 I0306 22:48:43.610944 30433 slave.cpp:4870] Cleaning up executor 'default' of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 at executor(74)@172.17.0.2:45774 W0306 22:48:43.611085 30434 master.cpp:6334] Ignoring unknown exited executor 'default' of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 on agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 at slave(179)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.611405 30440 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_7RoV9J/slaves/484f7b4d-554d-4e24-9cfb-e945cb429843-S0/frameworks/484f7b4d-554d-4e24-9cfb-e945cb429843-0000/executors/default/runs/721883d7-ee77-4e12-b36c-d8b2660c04dc' for gc 6.99999292454815days in the future I0306 22:48:43.611532 30433 slave.cpp:4958] Cleaning up framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.611555 30440 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_7RoV9J/slaves/484f7b4d-554d-4e24-9cfb-e945cb429843-S0/frameworks/484f7b4d-554d-4e24-9cfb-e945cb429843-0000/executors/default' for gc 6.99999292279111days in the future I0306 22:48:43.611623 30432 status_update_manager.cpp:285] Closing status update streams for framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.611675 30432 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 484f7b4d-554d-4e24-9cfb-e945cb429843-0000 I0306 22:48:43.611743 30433 slave.cpp:808] Agent terminating I0306 22:48:43.611943 30434 master.cpp:1268] Agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 at slave(179)@172.17.0.2:45774 (360ede780c06) disconnected I0306 22:48:43.612004 30434 master.cpp:3182] Disconnecting agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 at slave(179)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.612083 30434 master.cpp:3201] Deactivating agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 at slave(179)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.611708 30440 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_7RoV9J/slaves/484f7b4d-554d-4e24-9cfb-e945cb429843-S0/frameworks/484f7b4d-554d-4e24-9cfb-e945cb429843-0000' for gc 6.99999292075259days in the future I0306 22:48:43.612213 30432 hierarchical.cpp:646] Agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 deactivated I0306 22:48:43.615649 30431 master.cpp:1107] Master terminating I0306 22:48:43.616638 30430 hierarchical.cpp:551] Removed agent 484f7b4d-554d-4e24-9cfb-e945cb429843-S0 [ OK ] MasterTest.FrameworkMessage (93 ms) [ RUN ] MasterTest.MultipleExecutors I0306 22:48:43.626153 30410 cluster.cpp:160] Creating default 'local' authorizer I0306 22:48:43.629781 30429 master.cpp:387] Master a5935f2f-14fa-4d64-81df-9027336d2faa (360ede780c06) started on 172.17.0.2:45774 I0306 22:48:43.629824 30429 master.cpp:389] 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/NaOAs5/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" --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.3.0/_inst/share/mesos/webui" --work_dir="/tmp/NaOAs5/master" --zk_session_timeout="10secs" I0306 22:48:43.630481 30429 master.cpp:439] Master only allowing authenticated frameworks to register I0306 22:48:43.630508 30429 master.cpp:453] Master only allowing authenticated agents to register I0306 22:48:43.630527 30429 master.cpp:466] Master only allowing authenticated HTTP frameworks to register I0306 22:48:43.630545 30429 credentials.hpp:37] Loading credentials for authentication from '/tmp/NaOAs5/credentials' I0306 22:48:43.631011 30429 master.cpp:511] Using default 'crammd5' authenticator I0306 22:48:43.631201 30429 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly' I0306 22:48:43.631409 30429 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' I0306 22:48:43.632019 30429 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' I0306 22:48:43.632185 30429 master.cpp:591] Authorization enabled I0306 22:48:43.632535 30435 hierarchical.cpp:161] Initialized hierarchical allocator process I0306 22:48:43.632706 30436 whitelist_watcher.cpp:77] No whitelist given I0306 22:48:43.635762 30441 master.cpp:2131] Elected as the leading master! I0306 22:48:43.635805 30441 master.cpp:1655] Recovering from registrar I0306 22:48:43.635977 30442 registrar.cpp:331] Recovering registrar I0306 22:48:43.637078 30437 registrar.cpp:364] Successfully fetched the registry (0B) in 981248ns I0306 22:48:43.637291 30437 registrar.cpp:463] Applied 1 operations in 29729ns; attempting to update the registry I0306 22:48:43.638586 30437 registrar.cpp:508] Successfully updated the registry in 1.218048ms I0306 22:48:43.638782 30437 registrar.cpp:394] Successfully recovered registrar I0306 22:48:43.639458 30435 master.cpp:1769] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I0306 22:48:43.639508 30444 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover I0306 22:48:43.645192 30410 cluster.cpp:446] Creating default 'local' authorizer I0306 22:48:43.647667 30410 sched.cpp:232] Version: 1.3.0 I0306 22:48:43.647853 30436 slave.cpp:215] Mesos agent started on (180)@172.17.0.2:45774 I0306 22:48:43.647898 30436 slave.cpp:216] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/mesos/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_MultipleExecutors_LeUArL/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_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_LeUArL/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/MasterTest_MultipleExecutors_LeUArL/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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/MasterTest_MultipleExecutors_LeUArL" --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_MultipleExecutors_LbZ4rq" I0306 22:48:43.648629 30436 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_LeUArL/credential' I0306 22:48:43.648814 30436 slave.cpp:358] Agent using credential for: test-principal I0306 22:48:43.648843 30436 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_LeUArL/http_credentials' I0306 22:48:43.649163 30436 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0306 22:48:43.649319 30436 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0306 22:48:43.651401 30432 sched.cpp:336] New master detected at [email protected]:45774 I0306 22:48:43.651532 30432 sched.cpp:407] Authenticating with master [email protected]:45774 I0306 22:48:43.651566 30432 sched.cpp:414] Using default CRAM-MD5 authenticatee I0306 22:48:43.651729 30436 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0306 22:48:43.651888 30436 slave.cpp:553] Agent attributes: [ ] I0306 22:48:43.651914 30436 slave.cpp:558] Agent hostname: 360ede780c06 I0306 22:48:43.652021 30432 authenticatee.cpp:121] Creating new client SASL connection I0306 22:48:43.652406 30432 status_update_manager.cpp:177] Pausing sending status updates I0306 22:48:43.652531 30432 master.cpp:7219] Authenticating [email protected]:45774 I0306 22:48:43.652766 30432 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(413)@172.17.0.2:45774 I0306 22:48:43.653584 30434 authenticator.cpp:98] Creating new server SASL connection I0306 22:48:43.653810 30429 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0306 22:48:43.653848 30429 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0306 22:48:43.654000 30433 authenticator.cpp:204] Received SASL authentication start I0306 22:48:43.654089 30433 authenticator.cpp:326] Authentication requires more steps I0306 22:48:43.654119 30432 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_LbZ4rq/meta' I0306 22:48:43.654191 30433 authenticatee.cpp:259] Received SASL authentication step I0306 22:48:43.654357 30436 authenticator.cpp:232] Received SASL authentication step I0306 22:48:43.654393 30436 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '360ede780c06' server FQDN: '360ede780c06' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0306 22:48:43.654510 30443 status_update_manager.cpp:203] Recovering status update manager I0306 22:48:43.654570 30436 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0306 22:48:43.654650 30436 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0306 22:48:43.654855 30436 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '360ede780c06' server FQDN: '360ede780c06' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0306 22:48:43.654899 30436 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0306 22:48:43.654955 30436 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0306 22:48:43.655004 30436 authenticator.cpp:318] Authentication success I0306 22:48:43.655010 30443 slave.cpp:5572] Finished recovery I0306 22:48:43.655597 30443 slave.cpp:5754] Querying resource estimator for oversubscribable resources I0306 22:48:43.655887 30432 authenticatee.cpp:299] Authentication success I0306 22:48:43.655931 30435 status_update_manager.cpp:177] Pausing sending status updates I0306 22:48:43.655951 30430 slave.cpp:936] New master detected at [email protected]:45774 I0306 22:48:43.656003 30439 master.cpp:7249] Successfully authenticated principal 'test-principal' at [email protected]:45774 I0306 22:48:43.656062 30437 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(413)@172.17.0.2:45774 I0306 22:48:43.656113 30430 slave.cpp:971] Detecting new master I0306 22:48:43.656263 30435 sched.cpp:513] Successfully authenticated with master [email protected]:45774 I0306 22:48:43.656421 30435 sched.cpp:836] Sending SUBSCRIBE call to [email protected]:45774 I0306 22:48:43.656532 30435 sched.cpp:869] Will retry registration in 530.288878ms if necessary I0306 22:48:43.656740 30444 master.cpp:2799] Received SUBSCRIBE call for framework 'default' at [email protected]:45774 I0306 22:48:43.656826 30444 master.cpp:2167] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0306 22:48:43.656874 30430 slave.cpp:5768] Received oversubscribable resources {} from the resource estimator I0306 22:48:43.657241 30430 master.cpp:2876] Subscribing framework default with checkpointing disabled and capabilities [ ] I0306 22:48:43.657755 30429 sched.cpp:759] Framework registered with a5935f2f-14fa-4d64-81df-9027336d2faa-0000 I0306 22:48:43.657798 30429 sched.cpp:773] Scheduler::registered took 17415ns I0306 22:48:43.657866 30433 hierarchical.cpp:286] Added framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 I0306 22:48:43.658017 30433 hierarchical.cpp:1838] No allocations performed I0306 22:48:43.658064 30433 hierarchical.cpp:1928] No inverse offers to send out! I0306 22:48:43.658143 30433 hierarchical.cpp:1422] Performed allocation for 0 agents in 172670ns I0306 22:48:43.660444 30431 slave.cpp:998] Authenticating with master [email protected]:45774 I0306 22:48:43.660544 30431 slave.cpp:1009] Using default CRAM-MD5 authenticatee I0306 22:48:43.660831 30434 authenticatee.cpp:121] Creating new client SASL connection I0306 22:48:43.661056 30429 master.cpp:7219] Authenticating slave(180)@172.17.0.2:45774 I0306 22:48:43.661172 30437 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(414)@172.17.0.2:45774 I0306 22:48:43.661386 30429 authenticator.cpp:98] Creating new server SASL connection I0306 22:48:43.661587 30441 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0306 22:48:43.661618 30441 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0306 22:48:43.661765 30442 authenticator.cpp:204] Received SASL authentication start I0306 22:48:43.661810 30442 authenticator.cpp:326] Authentication requires more steps I0306 22:48:43.661896 30442 authenticatee.cpp:259] Received SASL authentication step I0306 22:48:43.662021 30438 authenticator.cpp:232] Received SASL authentication step I0306 22:48:43.662048 30438 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '360ede780c06' server FQDN: '360ede780c06' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0306 22:48:43.662062 30438 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0306 22:48:43.662087 30438 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0306 22:48:43.662106 30438 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '360ede780c06' server FQDN: '360ede780c06' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0306 22:48:43.662117 30438 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0306 22:48:43.662127 30438 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0306 22:48:43.662139 30438 authenticator.cpp:318] Authentication success I0306 22:48:43.662221 30444 authenticatee.cpp:299] Authentication success I0306 22:48:43.662250 30433 master.cpp:7249] Successfully authenticated principal 'test-principal' at slave(180)@172.17.0.2:45774 I0306 22:48:43.662292 30438 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(414)@172.17.0.2:45774 I0306 22:48:43.662566 30444 slave.cpp:1093] Successfully authenticated with master [email protected]:45774 I0306 22:48:43.662755 30444 slave.cpp:1521] Will retry registration in 2.460185ms if necessary I0306 22:48:43.662966 30429 master.cpp:5434] Registering agent at slave(180)@172.17.0.2:45774 (360ede780c06) with id a5935f2f-14fa-4d64-81df-9027336d2faa-S0 I0306 22:48:43.663378 30431 registrar.cpp:463] Applied 1 operations in 54452ns; attempting to update the registry I0306 22:48:43.664880 30431 registrar.cpp:508] Successfully updated the registry in 1.435904ms I0306 22:48:43.665624 30444 slave.cpp:4347] Received ping from slave-observer(177)@172.17.0.2:45774 I0306 22:48:43.665889 30432 slave.cpp:1139] Registered with master [email protected]:45774; given agent ID a5935f2f-14fa-4d64-81df-9027336d2faa-S0 I0306 22:48:43.665915 30432 fetcher.cpp:94] Clearing fetcher cache I0306 22:48:43.665819 30430 master.cpp:5508] Registered agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 at slave(180)@172.17.0.2:45774 (360ede780c06) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0306 22:48:43.666102 30444 hierarchical.cpp:518] Added agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 (360ede780c06) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0306 22:48:43.666323 30435 status_update_manager.cpp:184] Resuming sending status updates I0306 22:48:43.666601 30432 slave.cpp:1167] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_LbZ4rq/meta/slaves/a5935f2f-14fa-4d64-81df-9027336d2faa-S0/slave.info' I0306 22:48:43.666950 30432 slave.cpp:1205] Forwarding total oversubscribed resources {} I0306 22:48:43.667130 30437 master.cpp:6065] Received update of agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 at slave(180)@172.17.0.2:45774 (360ede780c06) with total oversubscribed resources {} I0306 22:48:43.667341 30444 hierarchical.cpp:1928] No inverse offers to send out! I0306 22:48:43.667392 30444 hierarchical.cpp:1422] Performed allocation for 1 agents in 1.173808ms I0306 22:48:43.667835 30432 master.cpp:7048] Sending 1 offers to framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 (default) at [email protected]:45774 I0306 22:48:43.668334 30434 sched.cpp:933] Scheduler::resourceOffers took 102627ns I0306 22:48:43.671037 30432 master.cpp:3836] Processing ACCEPT call for offers: [ a5935f2f-14fa-4d64-81df-9027336d2faa-O0 ] on agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 at slave(180)@172.17.0.2:45774 (360ede780c06) for framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 (default) at [email protected]:45774 I0306 22:48:43.671146 30432 master.cpp:3412] Authorizing framework principal 'test-principal' to launch task 1 I0306 22:48:43.671468 30432 master.cpp:3412] Authorizing framework principal 'test-principal' to launch task 2 W0306 22:48:43.673771 30432 validation.cpp:1113] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0306 22:48:43.673815 30432 validation.cpp:1125] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0306 22:48:43.674305 30432 master.cpp:9111] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 at slave(180)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.674535 30432 master.cpp:4486] Launching task 1 of framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 (default) at [email protected]:45774 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 at slave(180)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.675084 30430 slave.cpp:1631] Got assigned task '1' for framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 I0306 22:48:43.675855 30430 slave.cpp:1791] Launching task '1' for framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 W0306 22:48:43.676111 30432 validation.cpp:1113] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0306 22:48:43.676151 30432 validation.cpp:1125] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0306 22:48:43.676620 30432 master.cpp:9111] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 at slave(180)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.676820 30430 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_LbZ4rq/slaves/a5935f2f-14fa-4d64-81df-9027336d2faa-S0/frameworks/a5935f2f-14fa-4d64-81df-9027336d2faa-0000/executors/executor-1/runs/e54e815e-ef1a-4911-9bf5-ba310c669afc' to user 'mesos' I0306 22:48:43.676816 30432 master.cpp:4486] Launching task 2 of framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 (default) at [email protected]:45774 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 at slave(180)@172.17.0.2:45774 (360ede780c06) I0306 22:48:43.677170 30430 slave.cpp:6499] Launching executor 'executor-1' of framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_LbZ4rq/slaves/a5935f2f-14fa-4d64-81df-9027336d2faa-S0/frameworks/a5935f2f-14fa-4d64-81df-9027336d2faa-0000/executors/executor-1/runs/e54e815e-ef1a-4911-9bf5-ba310c669afc' I0306 22:48:43.677986 30430 slave.cpp:2124] Queued task '1' for executor 'executor-1' of framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 I0306 22:48:43.678297 30430 slave.cpp:1631] Got assigned task '2' for framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 I0306 22:48:43.678876 30430 slave.cpp:889] Successfully attached file '/tmp/MasterTest_MultipleExecutors_LbZ4rq/slaves/a5935f2f-14fa-4d64-81df-9027336d2faa-S0/frameworks/a5935f2f-14fa-4d64-81df-9027336d2faa-0000/executors/executor-1/runs/e54e815e-ef1a-4911-9bf5-ba310c669afc' I0306 22:48:43.679002 30430 slave.cpp:1791] Launching task '2' for framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 I0306 22:48:43.679585 30430 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_LbZ4rq/slaves/a5935f2f-14fa-4d64-81df-9027336d2faa-S0/frameworks/a5935f2f-14fa-4d64-81df-9027336d2faa-0000/executors/executor-2/runs/b26a2379-4332-4a37-8224-549fe8de8736' to user 'mesos' I0306 22:48:43.679857 30430 slave.cpp:6499] Launching executor 'executor-2' of framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_LbZ4rq/slaves/a5935f2f-14fa-4d64-81df-9027336d2faa-S0/frameworks/a5935f2f-14fa-4d64-81df-9027336d2faa-0000/executors/executor-2/runs/b26a2379-4332-4a37-8224-549fe8de8736' *** Aborted at 1488840523 (unix time) try "date -d @1488840523" if you are using GNU date *** PC: @ 0x2ac821b9a82d (unknown) I0306 22:48:43.680539 30442 exec.cpp:162] Version: 1.3.0 *** SIGSEGV (@0xf0) received by PID 30410 (TID 0x2ac82a00b700) from PID 240; stack trace: *** I0306 22:48:43.680945 30432 exec.cpp:212] Executor started at: executor(75)@172.17.0.2:45774 with pid 30410 I0306 22:48:43.681140 30435 hierarchical.cpp:835] Updated allocation of framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 on agent a5935f2f-14fa-4d64-81df-9027336d2faa-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] I0306 22:48:43.681851 30435 hierarchical.cpp:1092] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 from framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 I0306 22:48:43.681916 30435 hierarchical.cpp:1129] Framework a5935f2f-14fa-4d64-81df-9027336d2faa-0000 filtered agent a5935f2f-14fa-4d64-81df-9027336d2faa-S0 for 5secs @ 0x2ac8331c62d7 (unknown) @ 0x2ac8331ca5b9 (unknown) @ 0x2ac821951330 (unknown) @ 0x2ac821b9a82d (unknown) @ 0x2ac8177846b0 os::getenv() @ 0x2ac81e8a001b mesos::internal::slave::executorEnvironment() @ 0x2ac81e89a7de mesos::internal::slave::Framework::launchExecutor() @ 0x2ac81e87475b mesos::internal::slave::Slave::_run() @ 0x2ac81e8ce8b7 _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureIbEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSF_INS1_13TaskGroupInfoEES6_S9_SC_SH_SL_EEvRKNS_3PIDIT_EEMSP_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES16_ @ 0x2ac81e904453 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureIbEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSJ_INS5_13TaskGroupInfoEESA_SD_SG_SL_SP_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_ @ 0x2ac81f663e5d std::function<>::operator()() @ 0x2ac81f649c4f process::ProcessBase::visit() @ 0x2ac81f654588 process::DispatchEvent::visit() @ 0x2ac8177589f6 process::ProcessBase::serve() @ 0x2ac81f645f3c process::ProcessManager::resume() @ 0x2ac81f642780 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv @ 0x2ac81f653cc2 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE @ 0x2ac81f653c19 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv @ 0x2ac81f653bb2 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2ac8211d2a60 (unknown) @ 0x2ac821949184 start_thread @ 0x2ac821c5937d (unknown) make[4]: *** [check-local] Segmentation fault make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.3.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1488837601-17017 Untagged: mesos-1488837601-17017:latest Deleted: sha256:0ea0095c9fdaf547567ba580bb9e2296e0837bff521792482377bcf63d940312 Deleted: sha256:ab71b70773e856e30ee88f949762c09c0189d70232205b2274c71e6802e39719 Deleted: sha256:da1517abb42d076a50ce5d1e5c339ca07862a299b56487e39da5d7e863b26626 Deleted: sha256:f73115233f85838eefafb5f306f89257120acf380233dc01819e295e9b3b46ee Deleted: sha256:aa2f86d445a73290065eaa0141bcbcaecd13f8d2a08e2b3cb65c90e5f6bc7516 Deleted: sha256:513fd431a3a9432d4434488069482787400e9382c47ba81a4d40f5476e18c004 Deleted: sha256:142c1f0ca5da8804db4bec6170d5789e1c79a58e39a0881ccec286f820b934eb Deleted: sha256:42b1e3f1f7ecbf40602ed59d286e3f7ff3bdbc8455412d48f5cabe40f2123ab3 Deleted: sha256:df2a33682dee9b17654e382769c9dd36bbbf9b521847a0a479d847a0be305090 Deleted: sha256:f4d913b249ce27ec53218d15b379a5b6932f881d5a0f678039af4842e00fbe80 Deleted: sha256:c2d5ad2a3ec1423da7e3054132be56852663c744722b040e6732789cda853f01 Deleted: sha256:c73ba2737c9fc0f642d8a69b308f31152e58b5bb3a96eb6c0f966e83d360e7d9 Deleted: sha256:6154f6e7b1938d2536b1019256e9616a567cf86d9ed18c8f851cc9642e4c8f98 Deleted: sha256:4565eb4ed92d11db3b06ae81839b008f527e5cb7334ccf5ab64b8565ff2580a7 Deleted: sha256:1569ee3454234690ecd1e6937d2dc2b102b0eccb4b07b96099fc4a8af95a2120 Deleted: sha256:d1a09dfb9d167bd2f610f2071cb7c2ca61d634ffe28ea288dfdb526e1ab4a3a0 Deleted: sha256:cd3b1adc7fee98d40e06c10bf2ac35fb034a5a19f76e82a93e827b687d8fa689 Deleted: sha256:37c15c831769dea8bea6be33899958157bafafa5da545651834624d9a350d8ec Deleted: sha256:f4866a92f04e83ddbfc7829202bc68d06d068feffc108c247ca76a81a58fde9c Deleted: sha256:9e9105151ed744992bb846361b861b834a7328ee6f4c1892b7cf819119d654cc Deleted: sha256:511134d7f268f3417c9ed5287cdad98303b52282ff23761d23150f79896e487a Deleted: sha256:7726cec3e197dc04fd49b2d535c654a3d0078719d6089014009e819ff58c6a5e Deleted: sha256:240384cb31f5f958983bb474b7ead6ce918674d7bd0ee6c8ae646cb966edcb83 Deleted: sha256:2bc1e8f5f9de1a2e070ec9d3602b7572fa12f488ca635226a70738dfaee2d833 Build step 'Execute shell' marked build as failure
