See <https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=clang,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-6)&&(!ubuntu-eu2)/3035/changes>
Changes: [adam] Added authorization actions VIEW_CONTAINERS and SET_LOG_LEVEL. [adam] Enabled fine grained authorization for the getContainers API Call. [adam] Enabled authorization for the GET_FLAGS API Call. [adam] Enabled authorization in SET_LOG_LEVEL API call. [vinodkone] Fixed a check bug in LAUNCH_NESTED_CONTAINER_SESSION_CALL. ------------------------------------------ [...truncated 175666 lines...] I1214 06:38:28.458883 12687 master.cpp:1568] Recovering from registrar I1214 06:38:28.459067 12688 registrar.cpp:329] Recovering registrar I1214 06:38:28.461076 12676 registrar.cpp:362] Successfully fetched the registry (0B) in 1.9072ms I1214 06:38:28.461200 12676 registrar.cpp:461] Applied 1 operations in 32932ns; attempting to update the registry I1214 06:38:28.463778 12676 registrar.cpp:506] Successfully updated the registry in 2.51904ms I1214 06:38:28.463896 12676 registrar.cpp:392] Successfully recovered registrar I1214 06:38:28.464750 12690 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register I1214 06:38:28.464794 12676 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover I1214 06:38:28.470510 30028 cluster.cpp:446] Creating default 'local' authorizer I1214 06:38:28.472709 12691 slave.cpp:209] Mesos agent started on (670)@172.17.0.3:40081 I1214 06:38:28.473315 30028 scheduler.cpp:184] Version: 1.2.0 I1214 06:38:28.472754 12691 slave.cpp:210] 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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_dgcv59/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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_dgcv59/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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_dgcv59/http_credentials" --http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_dgcv59" --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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn" I1214 06:38:28.473358 12691 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_dgcv59/credential' I1214 06:38:28.473553 12691 slave.cpp:352] Agent using credential for: test-principal I1214 06:38:28.473577 12691 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_dgcv59/http_credentials' I1214 06:38:28.473853 12691 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I1214 06:38:28.474042 12691 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I1214 06:38:28.474552 12685 scheduler.cpp:470] New master detected at [email protected]:40081 I1214 06:38:28.475482 12691 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1214 06:38:28.475582 12691 slave.cpp:547] Agent attributes: [ ] I1214 06:38:28.475597 12691 slave.cpp:552] Agent hostname: 3d87d9b2091f I1214 06:38:28.477035 12685 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I1214 06:38:28.477443 12686 state.cpp:57] Recovering state from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn/meta' I1214 06:38:28.477777 12689 status_update_manager.cpp:203] Recovering status update manager I1214 06:38:28.478240 12687 slave.cpp:5420] Finished recovery I1214 06:38:28.479158 12687 slave.cpp:5594] Querying resource estimator for oversubscribable resources I1214 06:38:28.479538 12681 status_update_manager.cpp:177] Pausing sending status updates I1214 06:38:28.479537 12687 slave.cpp:924] New master detected at [email protected]:40081 I1214 06:38:28.479615 12687 slave.cpp:983] Authenticating with master [email protected]:40081 I1214 06:38:28.479739 12687 slave.cpp:994] Using default CRAM-MD5 authenticatee I1214 06:38:28.479984 12687 slave.cpp:956] Detecting new master I1214 06:38:28.480029 12689 authenticatee.cpp:121] Creating new client SASL connection I1214 06:38:28.480299 12686 scheduler.cpp:361] Connected with the master at http://172.17.0.3:40081/master/api/v1/scheduler I1214 06:38:28.480361 12687 slave.cpp:5608] Received oversubscribable resources {} from the resource estimator I1214 06:38:28.480427 12684 master.cpp:6748] Authenticating slave(670)@172.17.0.3:40081 I1214 06:38:28.480561 12676 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1272)@172.17.0.3:40081 I1214 06:38:28.480891 12682 authenticator.cpp:98] Creating new server SASL connection I1214 06:38:28.481443 12677 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I1214 06:38:28.481482 12677 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I1214 06:38:28.481603 12690 authenticator.cpp:204] Received SASL authentication start I1214 06:38:28.481678 12690 authenticator.cpp:326] Authentication requires more steps I1214 06:38:28.481791 12689 authenticatee.cpp:259] Received SASL authentication step I1214 06:38:28.481948 12686 authenticator.cpp:232] Received SASL authentication step I1214 06:38:28.481986 12686 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3d87d9b2091f' server FQDN: '3d87d9b2091f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1214 06:38:28.481998 12686 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I1214 06:38:28.482038 12686 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1214 06:38:28.482055 12686 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3d87d9b2091f' server FQDN: '3d87d9b2091f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1214 06:38:28.482065 12686 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1214 06:38:28.482072 12686 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1214 06:38:28.482091 12686 authenticator.cpp:318] Authentication success I1214 06:38:28.482178 12683 authenticatee.cpp:299] Authentication success I1214 06:38:28.482167 12678 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.3:40081/master/api/v1/scheduler I1214 06:38:28.482244 12679 master.cpp:6778] Successfully authenticated principal 'test-principal' at slave(670)@172.17.0.3:40081 I1214 06:38:28.482359 12691 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1272)@172.17.0.3:40081 I1214 06:38:28.482549 12677 slave.cpp:1078] Successfully authenticated with master [email protected]:40081 I1214 06:38:28.482816 12677 slave.cpp:1492] Will retry registration in 8.012949ms if necessary I1214 06:38:28.483008 12679 master.cpp:5161] Registering agent at slave(670)@172.17.0.3:40081 (3d87d9b2091f) with id f2b20fac-573f-4449-9e83-8929f454aa2b-S0 I1214 06:38:28.483394 12687 registrar.cpp:461] Applied 1 operations in 93192ns; attempting to update the registry I1214 06:38:28.483500 12690 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1214 06:38:28.485002 12679 http.cpp:404] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:44424 I1214 06:38:28.485394 12687 registrar.cpp:506] Successfully updated the registry in 1.910016ms I1214 06:38:28.486922 12679 master.cpp:2344] Received subscription request for HTTP framework 'default' I1214 06:38:28.486959 12679 master.cpp:2081] Authorizing framework principal 'test-principal' to receive offers for role '*' I1214 06:38:28.487879 12688 slave.cpp:4272] Received ping from slave-observer(619)@172.17.0.3:40081 I1214 06:38:28.487933 12679 master.cpp:5232] Registered agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1214 06:38:28.488070 12688 slave.cpp:1124] Registered with master [email protected]:40081; given agent ID f2b20fac-573f-4449-9e83-8929f454aa2b-S0 I1214 06:38:28.488104 12688 fetcher.cpp:90] Clearing fetcher cache I1214 06:38:28.488276 12680 status_update_manager.cpp:184] Resuming sending status updates I1214 06:38:28.488276 12679 master.cpp:2458] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I1214 06:38:28.488466 12676 hierarchical.cpp:490] Added agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 (3d87d9b2091f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I1214 06:38:28.488598 12688 slave.cpp:1147] Checkpointing SlaveInfo to '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn/meta/slaves/f2b20fac-573f-4449-9e83-8929f454aa2b-S0/slave.info' I1214 06:38:28.488651 12676 hierarchical.cpp:1689] No allocations performed I1214 06:38:28.488706 12676 hierarchical.cpp:1314] Performed allocation for agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 in 201863ns I1214 06:38:28.489085 12676 hierarchical.cpp:276] Added framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.489101 12688 slave.cpp:1184] Forwarding total oversubscribed resources {} I1214 06:38:28.489387 12683 master.hpp:2178] Sending heartbeat to f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.489491 12679 master.cpp:5633] Received update of agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) with total oversubscribed resources {} I1214 06:38:28.490510 12676 hierarchical.cpp:1784] No inverse offers to send out! I1214 06:38:28.490579 12676 hierarchical.cpp:1291] Performed allocation for 1 agents in 1.482228ms I1214 06:38:28.490622 12691 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.3:40081/master/api/v1/scheduler I1214 06:38:28.490860 12676 hierarchical.cpp:560] Agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 (3d87d9b2091f) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) I1214 06:38:28.491044 12676 hierarchical.cpp:1689] No allocations performed I1214 06:38:28.491093 12676 hierarchical.cpp:1784] No inverse offers to send out! I1214 06:38:28.491138 12678 master.cpp:6577] Sending 1 offers to framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (default) I1214 06:38:28.491152 12676 hierarchical.cpp:1314] Performed allocation for agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 in 223680ns I1214 06:38:28.491284 12691 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.3:40081/master/api/v1/scheduler I1214 06:38:28.494431 12682 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.3:40081/master/api/v1/scheduler I1214 06:38:28.497009 12689 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.3:40081/master/api/v1/scheduler I1214 06:38:28.498956 12681 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1214 06:38:28.500438 12688 http.cpp:404] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:44425 I1214 06:38:28.502589 12688 master.cpp:3588] Processing ACCEPT call for offers: [ f2b20fac-573f-4449-9e83-8929f454aa2b-O0 ] on agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) for framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (default) I1214 06:38:28.502701 12688 master.cpp:3175] Authorizing framework principal 'test-principal' to launch task 5494179e-996a-4415-9442-f3cf453055f3 W1214 06:38:28.505271 12684 validation.cpp:1001] Executor 'default' for task '5494179e-996a-4415-9442-f3cf453055f3' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W1214 06:38:28.505307 12684 validation.cpp:1013] Executor 'default' for task '5494179e-996a-4415-9442-f3cf453055f3' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I1214 06:38:28.505853 12684 master.cpp:8501] Adding task 5494179e-996a-4415-9442-f3cf453055f3 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 (3d87d9b2091f) I1214 06:38:28.506182 12684 master.cpp:4240] Launching task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (default) with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) I1214 06:38:28.506719 12676 slave.cpp:1556] Got assigned task '5494179e-996a-4415-9442-f3cf453055f3' for framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.507617 12676 slave.cpp:1718] Launching task '5494179e-996a-4415-9442-f3cf453055f3' for framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.508348 12676 paths.cpp:530] Trying to chown '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn/slaves/f2b20fac-573f-4449-9e83-8929f454aa2b-S0/frameworks/f2b20fac-573f-4449-9e83-8929f454aa2b-0000/executors/default/runs/fb2974e2-d423-454b-8e38-34a9571a03ed' to user 'mesos' I1214 06:38:28.516427 12676 slave.cpp:6347] Launching executor 'default' of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 with resources {} in work directory '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn/slaves/f2b20fac-573f-4449-9e83-8929f454aa2b-S0/frameworks/f2b20fac-573f-4449-9e83-8929f454aa2b-0000/executors/default/runs/fb2974e2-d423-454b-8e38-34a9571a03ed' I1214 06:38:28.517349 12676 slave.cpp:2040] Queued task '5494179e-996a-4415-9442-f3cf453055f3' for executor 'default' of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.517441 12676 slave.cpp:877] Successfully attached file '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn/slaves/f2b20fac-573f-4449-9e83-8929f454aa2b-S0/frameworks/f2b20fac-573f-4449-9e83-8929f454aa2b-0000/executors/default/runs/fb2974e2-d423-454b-8e38-34a9571a03ed' I1214 06:38:28.519944 12689 executor.cpp:191] Version: 1.2.0 I1214 06:38:28.524698 12686 executor.cpp:400] Connected with the agent I1214 06:38:28.525441 12691 executor.cpp:301] Sending SUBSCRIBE call to http://172.17.0.3:40081/slave(670)/api/v1/executor I1214 06:38:28.526435 12679 process.cpp:3679] Handling HTTP event for process 'slave(670)' with path: '/slave(670)/api/v1/executor' I1214 06:38:28.527865 12690 http.cpp:301] HTTP POST for /slave(670)/api/v1/executor from 172.17.0.3:44426 I1214 06:38:28.528105 12690 slave.cpp:3095] Received Subscribe request for HTTP executor 'default' of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.529609 12690 slave.cpp:2256] Sending queued task '5494179e-996a-4415-9442-f3cf453055f3' to executor 'default' of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (via HTTP) I1214 06:38:28.531538 12690 executor.cpp:715] Enqueuing event SUBSCRIBED received from http://172.17.0.3:40081/slave(670)/api/v1/executor I1214 06:38:28.532672 12686 executor.cpp:715] Enqueuing event LAUNCH received from http://172.17.0.3:40081/slave(670)/api/v1/executor I1214 06:38:28.535856 12680 executor.cpp:301] Sending UPDATE call to http://172.17.0.3:40081/slave(670)/api/v1/executor I1214 06:38:28.536955 12689 process.cpp:3679] Handling HTTP event for process 'slave(670)' with path: '/slave(670)/api/v1/executor' I1214 06:38:28.538317 12684 http.cpp:301] HTTP POST for /slave(670)/api/v1/executor from 172.17.0.3:44427 I1214 06:38:28.538493 12684 slave.cpp:3749] Handling status update TASK_RUNNING (UUID: 40fabdaf-4fa3-45ff-b5ac-ec7213f3f87e) for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.539492 12678 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 40fabdaf-4fa3-45ff-b5ac-ec7213f3f87e) for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.539556 12678 status_update_manager.cpp:500] Creating StatusUpdate stream for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.540151 12678 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 40fabdaf-4fa3-45ff-b5ac-ec7213f3f87e) for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 to the agent I1214 06:38:28.540487 12677 slave.cpp:4190] Forwarding the update TASK_RUNNING (UUID: 40fabdaf-4fa3-45ff-b5ac-ec7213f3f87e) for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 to [email protected]:40081 I1214 06:38:28.540727 12677 slave.cpp:4084] Status update manager successfully handled status update TASK_RUNNING (UUID: 40fabdaf-4fa3-45ff-b5ac-ec7213f3f87e) for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.540866 12679 master.cpp:5769] Status update TASK_RUNNING (UUID: 40fabdaf-4fa3-45ff-b5ac-ec7213f3f87e) for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 from agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) I1214 06:38:28.540932 12679 master.cpp:5831] Forwarding status update TASK_RUNNING (UUID: 40fabdaf-4fa3-45ff-b5ac-ec7213f3f87e) for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.541728 12679 master.cpp:7867] Updating the state of task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I1214 06:38:28.541729 12677 executor.cpp:715] Enqueuing event ACKNOWLEDGED received from http://172.17.0.3:40081/slave(670)/api/v1/executor I1214 06:38:28.543344 12682 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.3:40081/master/api/v1/scheduler I1214 06:38:28.544783 12684 scheduler.cpp:243] Sending TEARDOWN call to http://172.17.0.3:40081/master/api/v1/scheduler I1214 06:38:28.545656 12685 process.cpp:3679] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I1214 06:38:28.584169 12689 http.cpp:404] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:44425 I1214 06:38:28.584363 12689 master.cpp:7299] Removing framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (default) I1214 06:38:28.584542 12678 hierarchical.cpp:391] Deactivated framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.584547 12689 master.cpp:7867] Updating the state of task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED) I1214 06:38:28.584565 12679 slave.cpp:2584] Asked to shut down framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 by [email protected]:40081 I1214 06:38:28.584609 12679 slave.cpp:2609] Shutting down framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.584659 12679 slave.cpp:4999] Shutting down executor 'default' of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (via HTTP) I1214 06:38:28.585309 12689 master.cpp:7963] Removing task 5494179e-996a-4415-9442-f3cf453055f3 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 on agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) I1214 06:38:28.585594 12680 executor.cpp:715] Enqueuing event SHUTDOWN received from http://172.17.0.3:40081/slave(670)/api/v1/executor I1214 06:38:28.585723 12689 master.cpp:7992] Removing executor 'default' with resources {} of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 on agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) I1214 06:38:28.585744 12679 hierarchical.cpp:1023] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 from framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.587064 12686 hierarchical.cpp:342] Removed framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 E1214 06:38:28.587079 12682 scheduler.cpp:649] End-Of-File received from master. The master closed the event stream I1214 06:38:28.587638 12676 scheduler.cpp:444] Re-detecting master I1214 06:38:28.588328 12676 scheduler.cpp:395] Ignoring disconnection attempt from stale connection I1214 06:38:28.588449 12676 scheduler.cpp:470] New master detected at [email protected]:40081 I1214 06:38:28.588477 12676 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I1214 06:38:28.588779 12676 scheduler.cpp:395] Ignoring disconnection attempt from stale connection I1214 06:38:28.592885 12685 slave.cpp:4681] Executor 'default' of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 exited with status 0 I1214 06:38:28.593003 12685 slave.cpp:4785] Cleaning up executor 'default' of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (via HTTP) W1214 06:38:28.593245 12687 master.cpp:5887] Ignoring unknown exited executor 'default' of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 on agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) I1214 06:38:28.593461 12686 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn/slaves/f2b20fac-573f-4449-9e83-8929f454aa2b-S0/frameworks/f2b20fac-573f-4449-9e83-8929f454aa2b-0000/executors/default/runs/fb2974e2-d423-454b-8e38-34a9571a03ed' for gc 6.99999313434074days in the future I1214 06:38:28.593710 12685 slave.cpp:4873] Cleaning up framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.593713 12689 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn/slaves/f2b20fac-573f-4449-9e83-8929f454aa2b-S0/frameworks/f2b20fac-573f-4449-9e83-8929f454aa2b-0000/executors/default' for gc 6.99999313056593days in the future I1214 06:38:28.593811 12687 status_update_manager.cpp:285] Closing status update streams for framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.593864 12687 status_update_manager.cpp:531] Cleaning up status update stream for task 5494179e-996a-4415-9442-f3cf453055f3 of framework f2b20fac-573f-4449-9e83-8929f454aa2b-0000 I1214 06:38:28.593982 12677 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_FLG6pn/slaves/f2b20fac-573f-4449-9e83-8929f454aa2b-S0/frameworks/f2b20fac-573f-4449-9e83-8929f454aa2b-0000' for gc 6.99999312614518days in the future I1214 06:38:28.594252 12676 slave.cpp:796] Agent terminating I1214 06:38:28.594494 12684 master.cpp:1258] Agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) disconnected I1214 06:38:28.594521 12684 master.cpp:2977] Disconnecting agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) I1214 06:38:28.594575 12684 master.cpp:2996] Deactivating agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 at slave(670)@172.17.0.3:40081 (3d87d9b2091f) I1214 06:38:28.595412 12681 hierarchical.cpp:589] Agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 deactivated I1214 06:38:28.597491 12677 master.cpp:1097] Master terminating I1214 06:38:28.598247 12688 hierarchical.cpp:522] Removed agent f2b20fac-573f-4449-9e83-8929f454aa2b-S0 W1214 06:38:28.599027 30028 master.hpp:2531] Failed to close HTTP pipe for f2b20fac-573f-4449-9e83-8929f454aa2b-0000 (default) [ OK ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/3 (157 ms) *** Aborted at 1481697508 (unix time) try "date -d @1481697508" if you are using GNU date *** PC: @ 0x2afd2695ceee (unknown) I1214 06:38:28.607156 30028 process.cpp:1237] libprocess is initialized on 172.17.0.3:34371 with 16 worker threads [----------] 4 tests from ContentTypeAndSSLConfig/SchedulerSSLTest (2055 ms total) [----------] 9 tests from Endpoint/SlaveEndpointTest [ RUN ] Endpoint/SlaveEndpointTest.AuthorizedRequest/0 *** SIGSEGV (@0xa874088) received by PID 30028 (TID 0x2afd30798700) from PID 176636040; stack trace: *** @ 0x2afd3398a197 (unknown) @ 0x2afd3398e479 (unknown) @ 0x2afd266b7330 (unknown) @ 0x2afd2695ceee (unknown) @ 0x2afd25f41e30 (unknown) @ 0x2afd25f4248c (unknown) I1214 06:38:28.615176 30028 containerizer.cpp:220] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni W1214 06:38:28.615834 30028 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges, but is running as user mesos W1214 06:38:28.615996 30028 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges I1214 06:38:28.619796 12699 slave.cpp:209] Mesos agent started on (671)@172.17.0.3:34371 I1214 06:38:28.619835 12699 slave.cpp:210] 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/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_xXmFC6/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/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_xXmFC6/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/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_xXmFC6/http_credentials" --http_heartbeat_interval="30secs" --image_provisioner_backend="copy" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.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/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_xXmFC6" --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/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_hzo2zj" I1214 06:38:28.620331 12699 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_xXmFC6/credential' I1214 06:38:28.620471 12699 slave.cpp:352] Agent using credential for: test-principal I1214 06:38:28.620488 12699 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_xXmFC6/http_credentials' I1214 06:38:28.620689 12699 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I1214 06:38:28.620813 12699 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I1214 06:38:28.621798 12699 slave.cpp:539] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1214 06:38:28.621870 12699 slave.cpp:547] Agent attributes: [ ] I1214 06:38:28.621881 12699 slave.cpp:552] Agent hostname: 3d87d9b2091f I1214 06:38:28.623186 12698 state.cpp:57] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_0_hzo2zj/meta' I1214 06:38:28.623504 12707 status_update_manager.cpp:203] Recovering status update manager I1214 06:38:28.623682 12703 containerizer.cpp:594] Recovering containerizer @ 0x935dff process::UPID::UPID() I1214 06:38:28.624951 12706 provisioner.cpp:253] Provisioner recovery complete I1214 06:38:28.625286 12700 slave.cpp:5420] Finished recovery I1214 06:38:28.625777 12700 slave.cpp:5594] Querying resource estimator for oversubscribable resources I1214 06:38:28.626001 12697 slave.cpp:5608] Received oversubscribable resources {} from the resource estimator @ 0x2afd23ec6b4e process::DispatchEvent::DispatchEvent() @ 0x2afd23eacba3 process::internal::dispatch() @ 0x2afd23ec667e process::dispatch<>() @ 0x2afd23eabd84 process::ProcessBase::route() @ 0x2afd23dd12ab process::ProcessBase::route<>() @ 0x2afd23dcf073 process::Help::initialize() @ 0x2afd23dcf112 process::Help::initialize() @ 0x2afd23ea6e5c process::ProcessManager::resume() @ 0x2afd23eb49ac process::ProcessManager::init_threads()::$_2::operator()() @ 0x2afd23eb48b5 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_2vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE @ 0x2afd23eb4885 std::_Bind_simple<>::operator()() @ 0x2afd23eb485c std::thread::_Impl<>::_M_run() @ 0x2afd25f38a60 (unknown) @ 0x2afd266af184 start_thread @ 0x2afd269bf37d (unknown) make[4]: *** [check-local] Segmentation fault make[4]: Leaving directory `/mesos/mesos-1.2.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.2.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.2.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.2.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1481695004-8083 Untagged: mesos-1481695004-8083:latest Deleted: sha256:e15efd08bcc2aa04a9746d1a0815f8fc35e5236732429a32d6e387605791e8fc Deleted: sha256:6c895b28cbe71bc20e237b25b04248565a923226571a7a25acd9ef31e1a65044 Deleted: sha256:2809050fe079246371000113c9004d25508829b56c44b620ea8156f305a50535 Deleted: sha256:c4bbe84bc7f58dc0bea738ecb3c23ab78ed960a805581bdb713ae8ec9018627d Deleted: sha256:f184246bf1f4a325676160b4af887a702e78aba3f4d3a11048d4bbbd9502cf24 Deleted: sha256:d93ea212805cfc12f1bb81501ef722fd884a26199e6595ef94ae1368723e4624 Deleted: sha256:630f3960a7e3771c8751ccc944c998b12a45f1a2d54f58212f42bf3c41d54119 Deleted: sha256:b8ada6c8967003ed3e10becfb144b7f0f127a1a4831b617cbe9f7debdd07ff52 Deleted: sha256:412eb4344c27d867b0f97ecf25987637bf71a8ac56b363614bac7cee37c69421 Deleted: sha256:21baadc04f20adde9dd760e930873fe171e26adca75c29e11865b0c26c20b221 Deleted: sha256:3ff68f6326b3463e32071ea0e90f11212668bf7f01d49ea0ec1a0f2da7376662 Deleted: sha256:1e41d1ed195923d6ddaff6cf510c2613d327a5ca99983462c5d712c2f2b29e0f Deleted: sha256:9fda86e6da67a08a64201fa868910c61eca133cee5c7c10d63e9157e977f32d4 Deleted: sha256:b2bf5217f02b9672504857b869eca29593de1c4fc57f9323e16c0e7a38557151 Deleted: sha256:4385104f205a6406aab84cda5920fa4fb5042c6ef467fa51f57817d2c696c93b Deleted: sha256:4fa4f43209a7256d8cb9980b2b768f5edaf4bc223b867763f13fef6c2bdb1494 Deleted: sha256:3a540e1829b126ddd83179ce0e6ab6fd9e4f5a3984b9a5ddabc74342df6e1e3e Deleted: sha256:64124771786104dc6bb0c3d3b1f124e0cac52580e2af8fead72d410ddc9b91a7 Deleted: sha256:4b714898fbd1f369b9ce64a507e8cedcf4234f73bd2e3b735df0ce607bae89b9 Deleted: sha256:6ad2f8d939cc10c49da983f62a9252ef16a23aa5553f9bb2c534fe9ca12df6c1 Deleted: sha256:724b6838cb3a7f1e96a2de0dc5fc9651cd2c4e687fd5d78f00eb1b56627f8275 Deleted: sha256:60aea9e8f397ab50491530ee2d0cb3290f3e62d4bc8fa5bbbd75256cac0754ab Deleted: sha256:f7c5b866f5e492972be41e4c3755cf90d98f78b3ee04a9cc62bc7966d16b1ac2 Deleted: sha256:7fb7576d92d61070372875f0e9d6e622aab5b75a6a0385b055bba664133392d3 Build step 'Execute shell' marked build as failure
