You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/08/15 06:34:48 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4108
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4108/display/redirect?page=changes>
Changes:
[songzihao1990] Added container PID namespace control protobuf field in LinuxInfo.
[songzihao1990] Introduced `--disallow_sharing_agent_pid_namespace` agent flag.
[songzihao1990] Added pid ns sharing based on agent flag and protobuf message field.
[songzihao1990] Fixed a bug in the test `NamespacesIsolatorTest`.
[songzihao1990] Added a test `NamespacesIsolatorTest.ROOT_SharePidNamespace`.
[songzihao1990] Added test `NamespacesIsolatorTest.ROOT_SharePidNamespaceWhenDisallow`.
[songzihao1990] Added the test `ROOT_CGROUPS_LaunchNestedSharePidNamespace`.
[songzihao1990] Added a test `DefaultExecutorTest.ROOT_MultiTaskgroupSharePidNamespace`.
------------------------------------------
[...truncated 30.39 MB...]
I0815 06:34:42.012986 5209 hierarchical.cpp:690] Agent 4b3d55cb-32d0-4d8a-bf56-090ec16890d8-S0 deactivated
I0815 06:34:42.024571 5183 master.cpp:1160] Master terminating
I0815 06:34:42.025786 5205 hierarchical.cpp:626] Removed agent 4b3d55cb-32d0-4d8a-bf56-090ec16890d8-S0
[ OK ] ContentType/SchedulerTest.TaskRunning/0 (172 ms)
[ RUN ] ContentType/SchedulerTest.TaskRunning/1
I0815 06:34:42.032881 5183 cluster.cpp:162] Creating default 'local' authorizer
I0815 06:34:42.036079 5209 master.cpp:442] Master 4f87b483-3285-4e19-9994-12521a03da08 (05b3fdb5a50f) started on 172.17.0.4:44097
I0815 06:34:42.036111 5209 master.cpp:444] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/hAsHQb/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.4.0/_inst/share/mesos/webui" --work_dir="/tmp/hAsHQb/master" --zk_session_timeout="10secs"
I0815 06:34:42.036653 5209 master.cpp:494] Master only allowing authenticated frameworks to register
I0815 06:34:42.036680 5209 master.cpp:508] Master only allowing authenticated agents to register
I0815 06:34:42.036687 5209 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0815 06:34:42.036695 5209 credentials.hpp:37] Loading credentials for authentication from '/tmp/hAsHQb/credentials'
I0815 06:34:42.037053 5209 master.cpp:566] Using default 'crammd5' authenticator
I0815 06:34:42.037302 5209 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0815 06:34:42.037534 5209 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0815 06:34:42.037802 5209 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0815 06:34:42.038164 5209 master.cpp:646] Authorization enabled
I0815 06:34:42.038401 5206 whitelist_watcher.cpp:77] No whitelist given
I0815 06:34:42.038552 5205 hierarchical.cpp:171] Initialized hierarchical allocator process
I0815 06:34:42.041193 5203 master.cpp:2163] Elected as the leading master!
I0815 06:34:42.041316 5203 master.cpp:1702] Recovering from registrar
I0815 06:34:42.041693 5204 registrar.cpp:347] Recovering registrar
I0815 06:34:42.042318 5204 registrar.cpp:391] Successfully fetched the registry (0B) in 576768ns
I0815 06:34:42.042552 5204 registrar.cpp:495] Applied 1 operations in 26885ns; attempting to update the registry
I0815 06:34:42.043190 5204 registrar.cpp:552] Successfully updated the registry in 572928ns
I0815 06:34:42.043387 5204 registrar.cpp:424] Successfully recovered registrar
I0815 06:34:42.043962 5203 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0815 06:34:42.044131 5205 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0815 06:34:42.048645 5183 process.cpp:3228] Attempting to spawn already spawned process files@172.17.0.4:44097
I0815 06:34:42.048909 5183 cluster.cpp:448] Creating default 'local' authorizer
I0815 06:34:42.051453 5206 slave.cpp:250] Mesos agent started on (776)@172.17.0.4:44097
I0815 06:34:42.051482 5206 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/ContentType_SchedulerTest_TaskRunning_1_e9deQs/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/ContentType_SchedulerTest_TaskRunning_1_e9deQs/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/ContentType_SchedulerTest_TaskRunning_1_e9deQs/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_SchedulerTest_TaskRunning_1_e9deQs/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/ContentType_SchedulerTest_TaskRunning_1_e9deQs/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ContentType_SchedulerTest_TaskRunning_1_e9deQs" --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/ContentType_SchedulerTest_TaskRunning_1_swnslP"
I0815 06:34:42.051975 5206 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_SchedulerTest_TaskRunning_1_e9deQs/credential'
I0815 06:34:42.052109 5206 slave.cpp:283] Agent using credential for: test-principal
I0815 06:34:42.052275 5206 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_TaskRunning_1_e9deQs/http_credentials'
I0815 06:34:42.052672 5206 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 06:34:42.052984 5206 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 06:34:42.054596 5206 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 06:34:42.054785 5206 slave.cpp:573] Agent attributes: [ ]
I0815 06:34:42.054997 5206 slave.cpp:582] Agent hostname: 05b3fdb5a50f
I0815 06:34:42.055260 5204 status_update_manager.cpp:177] Pausing sending status updates
I0815 06:34:42.055661 5183 scheduler.cpp:184] Version: 1.4.0
I0815 06:34:42.056919 5202 scheduler.cpp:470] New master detected at master@172.17.0.4:44097
I0815 06:34:42.057049 5202 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0815 06:34:42.057690 5207 state.cpp:64] Recovering state from '/tmp/ContentType_SchedulerTest_TaskRunning_1_swnslP/meta'
I0815 06:34:42.058075 5207 status_update_manager.cpp:203] Recovering status update manager
I0815 06:34:42.058449 5202 slave.cpp:6210] Finished recovery
I0815 06:34:42.059957 5202 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 06:34:42.060372 5202 slave.cpp:971] New master detected at master@172.17.0.4:44097
I0815 06:34:42.060421 5208 status_update_manager.cpp:177] Pausing sending status updates
I0815 06:34:42.060676 5202 slave.cpp:1006] Detecting new master
I0815 06:34:42.060925 5202 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 06:34:42.061980 5203 scheduler.cpp:361] Connected with the master at http://172.17.0.4:44097/master/api/v1/scheduler
I0815 06:34:42.063442 5204 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.4:44097/master/api/v1/scheduler
I0815 06:34:42.072101 5208 process.cpp:3933] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0815 06:34:42.072139 5203 slave.cpp:1033] Authenticating with master master@172.17.0.4:44097
I0815 06:34:42.072563 5203 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0815 06:34:42.072940 5204 authenticatee.cpp:121] Creating new client SASL connection
I0815 06:34:42.073539 5208 master.cpp:7837] Authenticating slave(776)@172.17.0.4:44097
I0815 06:34:42.074066 5203 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1450)@172.17.0.4:44097
I0815 06:34:42.074507 5203 authenticator.cpp:98] Creating new server SASL connection
I0815 06:34:42.074753 5204 http.cpp:1166] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:56864
I0815 06:34:42.075083 5208 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0815 06:34:42.075119 5208 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0815 06:34:42.075269 5204 master.cpp:2545] Received subscription request for HTTP framework 'default'
I0815 06:34:42.075439 5204 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0815 06:34:42.075670 5208 authenticator.cpp:204] Received SASL authentication start
I0815 06:34:42.075911 5208 authenticator.cpp:326] Authentication requires more steps
I0815 06:34:42.076153 5208 authenticatee.cpp:259] Received SASL authentication step
I0815 06:34:42.076437 5208 master.cpp:2681] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0815 06:34:42.076577 5203 authenticator.cpp:232] Received SASL authentication step
I0815 06:34:42.077669 5208 master.hpp:2217] Sending heartbeat to 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.077873 5203 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '05b3fdb5a50f' server FQDN: '05b3fdb5a50f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0815 06:34:42.077991 5203 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0815 06:34:42.078073 5203 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0815 06:34:42.078202 5203 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '05b3fdb5a50f' server FQDN: '05b3fdb5a50f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0815 06:34:42.078258 5203 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0815 06:34:42.078351 5203 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0815 06:34:42.078410 5203 authenticator.cpp:318] Authentication success
I0815 06:34:42.077726 5205 hierarchical.cpp:303] Added framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.078896 5203 master.cpp:7867] Successfully authenticated principal 'test-principal' at slave(776)@172.17.0.4:44097
I0815 06:34:42.078788 5208 authenticatee.cpp:299] Authentication success
I0815 06:34:42.079022 5204 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1450)@172.17.0.4:44097
I0815 06:34:42.079557 5205 hierarchical.cpp:1925] No allocations performed
I0815 06:34:42.079624 5205 hierarchical.cpp:2015] No inverse offers to send out!
I0815 06:34:42.079921 5205 hierarchical.cpp:1468] Performed allocation for 0 agents in 594481ns
I0815 06:34:42.079985 5208 slave.cpp:1128] Successfully authenticated with master master@172.17.0.4:44097
I0815 06:34:42.080591 5208 slave.cpp:1572] Will retry registration in 2.802407ms if necessary
I0815 06:34:42.081024 5208 master.cpp:5712] Received register agent message from slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.081143 5203 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.4:44097/master/api/v1/scheduler
I0815 06:34:42.081544 5208 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0815 06:34:42.082135 5203 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.4:44097/master/api/v1/scheduler
I0815 06:34:42.082182 5204 master.cpp:5772] Authorized registration of agent at slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.082356 5204 master.cpp:5865] Registering agent at slave(776)@172.17.0.4:44097 (05b3fdb5a50f) with id 4f87b483-3285-4e19-9994-12521a03da08-S0
I0815 06:34:42.083022 5206 registrar.cpp:495] Applied 1 operations in 48353ns; attempting to update the registry
I0815 06:34:42.083798 5205 registrar.cpp:552] Successfully updated the registry in 713984ns
I0815 06:34:42.084158 5204 master.cpp:5912] Admitted agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.084823 5206 slave.cpp:1572] Will retry registration in 31.990313ms if necessary
I0815 06:34:42.085145 5206 slave.cpp:4887] Received ping from slave-observer(706)@172.17.0.4:44097
I0815 06:34:42.085294 5206 slave.cpp:1174] Registered with master master@172.17.0.4:44097; given agent ID 4f87b483-3285-4e19-9994-12521a03da08-S0
I0815 06:34:42.085054 5204 master.cpp:5943] Registered agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 06:34:42.085703 5204 master.cpp:5712] Received register agent message from slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.085891 5204 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0815 06:34:42.085750 5202 hierarchical.cpp:593] Added agent 4f87b483-3285-4e19-9994-12521a03da08-S0 (05b3fdb5a50f) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0815 06:34:42.086241 5208 status_update_manager.cpp:184] Resuming sending status updates
I0815 06:34:42.086444 5204 master.cpp:5772] Authorized registration of agent at slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.086627 5204 master.cpp:5843] Agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f) already registered, resending acknowledgement
I0815 06:34:42.086704 5206 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_TaskRunning_1_swnslP/meta/slaves/4f87b483-3285-4e19-9994-12521a03da08-S0/slave.info'
I0815 06:34:42.087250 5206 slave.cpp:1232] Forwarding total oversubscribed resources {}
W0815 06:34:42.087510 5206 slave.cpp:1218] Already registered with master master@172.17.0.4:44097
I0815 06:34:42.087564 5206 slave.cpp:1232] Forwarding total oversubscribed resources {}
I0815 06:34:42.087525 5205 master.cpp:6688] Received update of agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f) with total oversubscribed resources {}
I0815 06:34:42.087923 5205 master.cpp:6688] Received update of agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f) with total oversubscribed resources {}
I0815 06:34:42.088737 5202 hierarchical.cpp:2015] No inverse offers to send out!
I0815 06:34:42.088843 5202 hierarchical.cpp:1468] Performed allocation for 1 agents in 2.330504ms
I0815 06:34:42.089305 5202 hierarchical.cpp:660] Agent 4f87b483-3285-4e19-9994-12521a03da08-S0 (05b3fdb5a50f) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0815 06:34:42.089344 5209 master.cpp:7667] Sending 1 offers to framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default)
I0815 06:34:42.089779 5202 hierarchical.cpp:660] Agent 4f87b483-3285-4e19-9994-12521a03da08-S0 (05b3fdb5a50f) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0815 06:34:42.092579 5206 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.4:44097/master/api/v1/scheduler
I0815 06:34:42.097944 5206 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.4:44097/master/api/v1/scheduler
I0815 06:34:42.101495 5208 process.cpp:3933] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0815 06:34:42.105844 5209 http.cpp:1166] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:56862
I0815 06:34:42.107729 5209 master.cpp:9164] Removing offer 4f87b483-3285-4e19-9994-12521a03da08-O0
I0815 06:34:42.107976 5209 master.cpp:4153] Processing ACCEPT call for offers: [ 4f87b483-3285-4e19-9994-12521a03da08-O0 ] on agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f) for framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default)
I0815 06:34:42.108126 5209 master.cpp:3530] Authorizing framework principal 'test-principal' to launch task 1
W0815 06:34:42.110977 5209 validation.cpp:1294] Executor 'default' 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.
W0815 06:34:42.111022 5209 validation.cpp:1306] Executor 'default' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0815 06:34:42.111598 5209 master.cpp:9724] Adding task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.112167 5209 master.cpp:4816] Launching task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default) with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.112848 5207 slave.cpp:1701] Got assigned task '1' for framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.113530 5207 slave.cpp:1982] Authorizing task '1' for framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.113687 5207 slave.cpp:6709] Authorizing framework principal 'test-principal' to launch task 1
I0815 06:34:42.114738 5207 slave.cpp:2169] Launching task '1' for framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.115448 5207 paths.cpp:578] Trying to chown '/tmp/ContentType_SchedulerTest_TaskRunning_1_swnslP/slaves/4f87b483-3285-4e19-9994-12521a03da08-S0/frameworks/4f87b483-3285-4e19-9994-12521a03da08-0000/executors/default/runs/f56b25d7-fbd9-48f6-aa53-56c0095318da' to user 'mesos'
I0815 06:34:42.115788 5207 slave.cpp:7165] Launching executor 'default' of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 with resources [] in work directory '/tmp/ContentType_SchedulerTest_TaskRunning_1_swnslP/slaves/4f87b483-3285-4e19-9994-12521a03da08-S0/frameworks/4f87b483-3285-4e19-9994-12521a03da08-0000/executors/default/runs/f56b25d7-fbd9-48f6-aa53-56c0095318da'
I0815 06:34:42.116251 5207 slave.cpp:2856] Launching container f56b25d7-fbd9-48f6-aa53-56c0095318da for executor 'default' of framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.116601 5207 slave.cpp:2398] Queued task '1' for executor 'default' of framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.116765 5207 slave.cpp:924] Successfully attached file '/tmp/ContentType_SchedulerTest_TaskRunning_1_swnslP/slaves/4f87b483-3285-4e19-9994-12521a03da08-S0/frameworks/4f87b483-3285-4e19-9994-12521a03da08-0000/executors/default/runs/f56b25d7-fbd9-48f6-aa53-56c0095318da'
I0815 06:34:42.118075 5209 hierarchical.cpp:887] Updated allocation of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 on agent 4f87b483-3285-4e19-9994-12521a03da08-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]
I0815 06:34:42.119191 5208 executor.cpp:192] Version: 1.4.0
I0815 06:34:42.119274 5208 process.cpp:3228] Attempting to spawn already spawned process version@172.17.0.4:44097
I0815 06:34:42.119882 5203 slave.cpp:924] Successfully attached file '/tmp/ContentType_SchedulerTest_TaskRunning_1_swnslP/slaves/4f87b483-3285-4e19-9994-12521a03da08-S0/frameworks/4f87b483-3285-4e19-9994-12521a03da08-0000/executors/default/runs/f56b25d7-fbd9-48f6-aa53-56c0095318da'
I0815 06:34:42.122488 5207 executor.cpp:410] Connected with the agent
I0815 06:34:42.123183 5209 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.4:44097/slave(776)/api/v1/executor
I0815 06:34:42.124613 5206 process.cpp:3933] Handling HTTP event for process 'slave(776)' with path: '/slave(776)/api/v1/executor'
I0815 06:34:42.126245 5205 http.cpp:1166] HTTP POST for /slave(776)/api/v1/executor from 172.17.0.4:56868
I0815 06:34:42.126683 5205 slave.cpp:3673] Received Subscribe request for HTTP executor 'default' of framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.128262 5205 slave.cpp:2603] Sending queued task '1' to executor 'default' of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (via HTTP)
I0815 06:34:42.130656 5207 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.4:44097/slave(776)/api/v1/executor
I0815 06:34:42.132145 5206 executor.cpp:725] Enqueuing event LAUNCH received from http://172.17.0.4:44097/slave(776)/api/v1/executor
I0815 06:34:42.133252 5204 executor.cpp:307] Sending UPDATE call to http://172.17.0.4:44097/slave(776)/api/v1/executor
I0815 06:34:42.134738 5206 process.cpp:3933] Handling HTTP event for process 'slave(776)' with path: '/slave(776)/api/v1/executor'
I0815 06:34:42.136085 5208 http.cpp:1166] HTTP POST for /slave(776)/api/v1/executor from 172.17.0.4:56870
I0815 06:34:42.136518 5208 slave.cpp:4345] Handling status update TASK_RUNNING (UUID: 5ee1b34b-7c8f-4177-b4df-4b50d2b22f5b) for task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.137495 5204 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 5ee1b34b-7c8f-4177-b4df-4b50d2b22f5b) for task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.137550 5204 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.138084 5204 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 5ee1b34b-7c8f-4177-b4df-4b50d2b22f5b) for task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 to the agent
I0815 06:34:42.138507 5204 slave.cpp:4797] Forwarding the update TASK_RUNNING (UUID: 5ee1b34b-7c8f-4177-b4df-4b50d2b22f5b) for task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 to master@172.17.0.4:44097
I0815 06:34:42.138805 5204 slave.cpp:4691] Status update manager successfully handled status update TASK_RUNNING (UUID: 5ee1b34b-7c8f-4177-b4df-4b50d2b22f5b) for task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.139402 5202 master.cpp:6846] Status update TASK_RUNNING (UUID: 5ee1b34b-7c8f-4177-b4df-4b50d2b22f5b) for task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 from agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.140403 5204 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:44097/slave(776)/api/v1/executor
I0815 06:34:42.140414 5202 master.cpp:6908] Forwarding status update TASK_RUNNING (UUID: 5ee1b34b-7c8f-4177-b4df-4b50d2b22f5b) for task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.141974 5202 master.cpp:8933] Updating the state of task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0815 06:34:42.143311 5204 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.4:44097/master/api/v1/scheduler
I0815 06:34:42.146234 5206 master.cpp:1432] Framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default) disconnected
I0815 06:34:42.146261 5206 master.cpp:3264] Deactivating framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default)
I0815 06:34:42.146411 5206 master.cpp:3241] Disconnecting framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default)
I0815 06:34:42.146466 5207 hierarchical.cpp:412] Deactivated framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.146744 5206 master.cpp:1447] Giving framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default) 0ns to failover
I0815 06:34:42.148349 5204 master.cpp:7499] Framework failover timeout, removing framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default)
I0815 06:34:42.148380 5204 master.cpp:8360] Removing framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (default)
I0815 06:34:42.148613 5204 master.cpp:8933] Updating the state of task 1 of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0815 06:34:42.148828 5208 slave.cpp:3159] Asked to shut down framework 4f87b483-3285-4e19-9994-12521a03da08-0000 by master@172.17.0.4:44097
I0815 06:34:42.149045 5208 slave.cpp:3184] Shutting down framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.149305 5208 slave.cpp:5651] Shutting down executor 'default' of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 (via HTTP)
I0815 06:34:42.150300 5207 hierarchical.cpp:1152] 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 4f87b483-3285-4e19-9994-12521a03da08-S0 from framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.149372 5204 master.cpp:9027] Removing task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 on agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.150874 5204 master.cpp:9056] Removing executor 'default' with resources [] of framework 4f87b483-3285-4e19-9994-12521a03da08-0000 on agent 4f87b483-3285-4e19-9994-12521a03da08-S0 at slave(776)@172.17.0.4:44097 (05b3fdb5a50f)
I0815 06:34:42.151415 5207 hierarchical.cpp:355] Removed framework 4f87b483-3285-4e19-9994-12521a03da08-0000
I0815 06:34:42.152688 5206 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.4:44097/slave(776)/api/v1/executor
*** Aborted at 1502778882 (unix time) try "date -d @1502778882" if you are using GNU date ***
PC: @ 0x55984b6d78ac testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
*** SIGSEGV (@0x0) received by PID 5183 (TID 0x2aed17901700) from PID 0; stack trace: ***
@ 0x2aed1a8cc417 (unknown)
@ 0x2aed1a8d06f9 (unknown)
@ 0x2aed0eb93330 (unknown)
@ 0x55984b6d78ac testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
@ 0x55984a57cf2d mesos::internal::tests::executor::MockHTTPExecutor<>::shutdown()
@ 0x55984a5751cf mesos::internal::tests::executor::MockHTTPExecutor<>::event()
@ 0x55984a56e8c2 mesos::internal::tests::executor::TestMesos<>::events()
@ 0x55984a593ff5 std::_Mem_fn<>::operator()<>()
@ 0x55984a587647 _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS1_2v18executor5MesosENS7_5EventEEEFvSt5queueIS9_St5dequeIS9_SaIS9_EEEEEPSA_St12_PlaceholderILi1EEEE6__callIvIRKSF_EILm0ELm1EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE
@ 0x55984a57d32b _ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS1_2v18executor5MesosENS7_5EventEEEFvSt5queueIS9_St5dequeIS9_SaIS9_EEEEEPSA_St12_PlaceholderILi1EEEEclIJRKSF_EvEET0_DpOT_
@ 0x55984a575595 std::_Function_handler<>::_M_invoke()
@ 0x2aed0ae44167 std::function<>::operator()()
@ 0x2aed0ae3c946 process::AsyncExecutorProcess::execute<>()
@ 0x2aed0ae3caa1 _ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS8_SaIS8_EEEEESC_SI_RSC_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSO_FSL_T1_T2_EOT3_OT4_ENKUlRSG_SJ_PNS_11ProcessBaseEE_clES10_SJ_S12_
@ 0x2aed0ae54af4 _ZNSt5_BindIFZN7process8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS9_SaIS9_EEEEESD_SJ_RSD_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSP_FSM_T1_T2_EOT3_OT4_EUlRSH_SK_PNS0_11ProcessBaseEE_SH_SD_St12_PlaceholderILi1EEEE6__callIvIOS13_EILm0ELm1ELm2EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE
@ 0x2aed0ae5160f _ZNSt5_BindIFZN7process8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS9_SaIS9_EEEEESD_SJ_RSD_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSP_FSM_T1_T2_EOT3_OT4_EUlRSH_SK_PNS0_11ProcessBaseEE_SH_SD_St12_PlaceholderILi1EEEEclIJS13_EvEET0_DpOT_
@ 0x2aed0ae4b742 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeISD_SaISD_EEEEESH_SN_RSH_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMST_FSQ_T1_T2_EOT3_OT4_EUlRSL_SO_S2_E_SL_SH_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_
@ 0x2aed0c501dc3 std::function<>::operator()()
@ 0x2aed0c4e2495 process::ProcessBase::visit()
@ 0x2aed0c4ee7c4 process::DispatchEvent::visit()
@ 0x55984a127e36 process::ProcessBase::serve()
@ 0x2aed0c4dfc85 process::ProcessManager::resume()
@ 0x2aed0c4dc060 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2aed0c4ed7ce _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2aed0c4ed725 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2aed0c4ed6be _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2aed0e414a60 (unknown)
@ 0x2aed0eb8b184 start_thread
@ 0x2aed0ee9effd (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1502773233-24743
Untagged: mesos-1502773233-24743:latest
Deleted: sha256:69fe7d1ab1e6e67862df97f394f0f6e0484295a21065b5de7df9b4aa280b0d0b
Deleted: sha256:97836e9864a396474683583c213b67489b77dc9865c1abc0adda89f6f66f5f49
Deleted: sha256:47f539092fa2ae127ef0a8b6cea1aa8a95b49e269ae3b97f87a2481510988c8d
Deleted: sha256:d373293f77dc1ed31ad06a9b181ae18d80d7674b06745633367f6420ab4d0490
Deleted: sha256:07b9b877ddd19de11a8e5a4ba440bbd8735de73b74d08a1b2aca61f151bec21a
Deleted: sha256:8660b111dfd5483d75ccb7a86b2dc4be521882b2dc84c45753096721105a016c
Deleted: sha256:70ffdca595bbfbd3bd95b4eba13f6f832f37c9be2cd6d21a43ca7269df32ea3b
Deleted: sha256:410cd3e6182828f78604756a7a4f976e04d168ac294b83bb83f7f0ec09f2aef8
Deleted: sha256:19b1dfffb8ba627b8cfd2499edc9fc672c07d9c9dd08aec508a586094105cf3b
Deleted: sha256:d5d8e4eca01cfe401abf32f39e84c0dc41d86665a494adebedd4f32c0d7693c1
Deleted: sha256:1e6675c833f94f8562f79609aa16109c6e85465ba7c0cee23a51e62946aaaa1f
Deleted: sha256:3bbea5ab960dbbd29bf5d8d3f707001692364409c962dac13988d34bc097d61b
Deleted: sha256:f51a03094e11e7254d6e481990eb18ef47800988285f83a1318c276b7414d3f5
Deleted: sha256:31387c2d8d29119ba157136e8ce2e5b038e8669ab1b9db454848afdb13b95844
Deleted: sha256:84d9b58fa937e1629b5acf147852c66ba59f06c9a130ce49608b065e23edd54d
Deleted: sha256:733aeffe7729c26966fe6d26b5e5fe174eb1d56cc2dea7126a01bd20a1ab5b31
Deleted: sha256:807b2b92295615f7805913426c44ed1a86f053d76e28efe69bd2af1248a6336e
Deleted: sha256:a6733d3b0789af9c09b196459fb63b8bfc8bb9986197e3ea4f769b19ef5198a5
Deleted: sha256:1776fd6ce7796995fb4a1173895c27c195105b4d07c5310808e5e4c6b3c5dd9b
Deleted: sha256:cb14e1fd8cb21868d0c3d01e169641b0155f3f46d207dd9bade85009561c319e
Deleted: sha256:98538bfe1245dd3e24e631132a4df3ed9fd9c9d268e6c8962aaf8b88d85c26e9
Deleted: sha256:084e07e305da972e884e37a4252a33122a427dd326956e43633441bb57893118
Deleted: sha256:ede38d5662a8e8919caeaa7ddc36b7f17fa0fedee2dc971623dbc887a93e0ea4
Deleted: sha256:f9d7e160a5575be3374b94c308124554b1a5fda280e761b4d6ad1fe766bd4e34
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1990@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4111
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4111/display/redirect?page=changes>
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4110
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4110/display/redirect?page=changes>
Changes:
[alexr] Added entry for 1.5.0 to CHANGELOG.
------------------------------------------
[...truncated 31.01 MB...]
I0815 15:18:33.523758 5194 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_JncIWZ/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_2_JncIWZ/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_JncIWZ/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_JncIWZ/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/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_JncIWZ/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_JncIWZ" --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_2_lXxaB5"
I0815 15:18:33.524344 5194 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_JncIWZ/credential'
I0815 15:18:33.524770 5194 slave.cpp:283] Agent using credential for: test-principal
I0815 15:18:33.524811 5194 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_JncIWZ/http_credentials'
I0815 15:18:33.525229 5194 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 15:18:33.525516 5194 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 15:18:33.527873 5194 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:18:33.528240 5194 slave.cpp:573] Agent attributes: [ ]
I0815 15:18:33.528276 5194 slave.cpp:582] Agent hostname: c7b576f3f487
I0815 15:18:33.528509 5197 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:18:33.532889 5197 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_lXxaB5/meta'
I0815 15:18:33.533565 5192 status_update_manager.cpp:203] Recovering status update manager
I0815 15:18:33.533960 5190 containerizer.cpp:598] Recovering containerizer
I0815 15:18:33.536037 5191 provisioner.cpp:416] Provisioner recovery complete
I0815 15:18:33.536695 5193 slave.cpp:6210] Finished recovery
I0815 15:18:33.537289 5193 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 15:18:33.537667 5191 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 15:18:33.541442 5197 process.cpp:3933] Handling HTTP event for process 'slave(799)' with path: '/slave(799)/containers'
I0815 15:18:33.543931 5195 http.cpp:1166] HTTP GET for /slave(799)/containers from 172.17.0.4:47586
I0815 15:18:33.544095 5195 http.cpp:957] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0815 15:18:33.549998 5190 slave.cpp:843] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (76 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0815 15:18:33.596791 5171 containerizer.cpp:246] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0815 15:18:33.597476 5171 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0815 15:18:33.597713 5171 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0815 15:18:33.597868 5171 provisioner.cpp:255] Using default backend 'copy'
I0815 15:18:33.604182 5195 slave.cpp:250] Mesos agent started on (800)@172.17.0.4:46144
I0815 15:18:33.604225 5195 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rQq02D/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_UnauthorizedRequest_0_rQq02D/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rQq02D/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rQq02D/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rQq02D/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rQq02D" --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_UnauthorizedRequest_0_VtfRtJ"
I0815 15:18:33.604684 5195 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rQq02D/credential'
I0815 15:18:33.604962 5195 slave.cpp:283] Agent using credential for: test-principal
I0815 15:18:33.605016 5195 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_rQq02D/http_credentials'
I0815 15:18:33.605309 5195 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 15:18:33.605623 5195 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 15:18:33.607403 5195 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:18:33.607645 5195 slave.cpp:573] Agent attributes: [ ]
I0815 15:18:33.607662 5195 slave.cpp:582] Agent hostname: c7b576f3f487
I0815 15:18:33.607892 5194 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:18:33.609530 5196 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_VtfRtJ/meta'
I0815 15:18:33.610050 5194 status_update_manager.cpp:203] Recovering status update manager
I0815 15:18:33.610368 5195 containerizer.cpp:598] Recovering containerizer
I0815 15:18:33.612421 5193 provisioner.cpp:416] Provisioner recovery complete
I0815 15:18:33.613400 5192 slave.cpp:6210] Finished recovery
I0815 15:18:33.614214 5192 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 15:18:33.614694 5192 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 15:18:33.619405 5191 process.cpp:3933] Handling HTTP event for process 'slave(800)' with path: '/slave(800)/monitor/statistics'
I0815 15:18:33.621083 5194 http.cpp:1166] HTTP GET for /slave(800)/monitor/statistics from 172.17.0.4:47590
I0815 15:18:33.621270 5194 http.cpp:957] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0815 15:18:33.624552 5197 slave.cpp:843] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (79 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0815 15:18:33.678452 5171 containerizer.cpp:246] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0815 15:18:33.679144 5171 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0815 15:18:33.679384 5171 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0815 15:18:33.679523 5171 provisioner.cpp:255] Using default backend 'copy'
I0815 15:18:33.683990 5192 slave.cpp:250] Mesos agent started on (801)@172.17.0.4:46144
I0815 15:18:33.684026 5192 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_f73m2i/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_UnauthorizedRequest_1_f73m2i/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_f73m2i/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_f73m2i/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_f73m2i/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_f73m2i" --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_UnauthorizedRequest_1_hErmfo"
I0815 15:18:33.684450 5192 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_f73m2i/credential'
I0815 15:18:33.684828 5192 slave.cpp:283] Agent using credential for: test-principal
I0815 15:18:33.684867 5192 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_f73m2i/http_credentials'
I0815 15:18:33.685219 5192 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 15:18:33.685494 5192 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 15:18:33.687216 5192 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:18:33.687425 5192 slave.cpp:573] Agent attributes: [ ]
I0815 15:18:33.687451 5192 slave.cpp:582] Agent hostname: c7b576f3f487
I0815 15:18:33.687799 5191 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:18:33.690021 5190 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_hErmfo/meta'
I0815 15:18:33.698942 5196 status_update_manager.cpp:203] Recovering status update manager
I0815 15:18:33.702164 5193 containerizer.cpp:598] Recovering containerizer
I0815 15:18:33.703984 5197 provisioner.cpp:416] Provisioner recovery complete
I0815 15:18:33.704444 5196 slave.cpp:6210] Finished recovery
I0815 15:18:33.706303 5196 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 15:18:33.706732 5193 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 15:18:33.710912 5193 process.cpp:3933] Handling HTTP event for process 'slave(801)' with path: '/slave(801)/monitor/statistics.json'
I0815 15:18:33.712775 5192 http.cpp:1166] HTTP GET for /slave(801)/monitor/statistics.json from 172.17.0.4:47594
I0815 15:18:33.712867 5192 http.cpp:957] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0815 15:18:33.715942 5194 slave.cpp:843] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (68 ms)
[ RUN ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0815 15:18:33.743647 5171 containerizer.cpp:246] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0815 15:18:33.744259 5171 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0815 15:18:33.744508 5171 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0815 15:18:33.744619 5171 provisioner.cpp:255] Using default backend 'copy'
I0815 15:18:33.749352 5193 slave.cpp:250] Mesos agent started on (802)@172.17.0.4:46144
I0815 15:18:33.749384 5193 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_O8xRRY/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_UnauthorizedRequest_2_O8xRRY/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_O8xRRY/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_O8xRRY/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/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_O8xRRY/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_O8xRRY" --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_UnauthorizedRequest_2_Bz3TS3"
I0815 15:18:33.749836 5193 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_O8xRRY/credential'
I0815 15:18:33.750046 5193 slave.cpp:283] Agent using credential for: test-principal
I0815 15:18:33.750079 5193 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_O8xRRY/http_credentials'
I0815 15:18:33.750347 5193 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 15:18:33.750643 5193 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 15:18:33.752593 5193 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:18:33.752858 5193 slave.cpp:573] Agent attributes: [ ]
I0815 15:18:33.752874 5193 slave.cpp:582] Agent hostname: c7b576f3f487
I0815 15:18:33.753096 5194 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:18:33.755332 5194 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_Bz3TS3/meta'
I0815 15:18:33.755854 5191 status_update_manager.cpp:203] Recovering status update manager
I0815 15:18:33.756227 5196 containerizer.cpp:598] Recovering containerizer
I0815 15:18:33.758193 5196 provisioner.cpp:416] Provisioner recovery complete
I0815 15:18:33.758690 5190 slave.cpp:6210] Finished recovery
I0815 15:18:33.759905 5190 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 15:18:33.760253 5197 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 15:18:33.764360 5194 process.cpp:3933] Handling HTTP event for process 'slave(802)' with path: '/slave(802)/containers'
I0815 15:18:33.765861 5197 http.cpp:1166] HTTP GET for /slave(802)/containers from 172.17.0.4:47596
I0815 15:18:33.766011 5197 http.cpp:957] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0815 15:18:33.778790 5197 slave.cpp:843] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (66 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0815 15:18:33.809864 5171 containerizer.cpp:246] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0815 15:18:33.810497 5171 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0815 15:18:33.810768 5171 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0815 15:18:33.810861 5171 provisioner.cpp:255] Using default backend 'copy'
I0815 15:18:33.812923 5171 cluster.cpp:448] Creating default 'local' authorizer
I0815 15:18:33.815520 5195 slave.cpp:250] Mesos agent started on (803)@172.17.0.4:46144
I0815 15:18:33.815548 5195 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_7crSpF/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_NoAuthorizer_0_7crSpF/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_7crSpF/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_7crSpF/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/Endpoint_SlaveEndpointTest_NoAuthorizer_0_7crSpF/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_7crSpF" --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_NoAuthorizer_0_6fJdfK"
I0815 15:18:33.816025 5195 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_7crSpF/credential'
I0815 15:18:33.816354 5195 slave.cpp:283] Agent using credential for: test-principal
I0815 15:18:33.816387 5195 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_7crSpF/http_credentials'
I0815 15:18:33.817098 5195 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 15:18:33.817345 5195 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 15:18:33.818871 5195 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:18:33.819406 5195 slave.cpp:573] Agent attributes: [ ]
I0815 15:18:33.819427 5195 slave.cpp:582] Agent hostname: c7b576f3f487
I0815 15:18:33.819609 5196 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:18:33.821358 5190 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_6fJdfK/meta'
I0815 15:18:33.829704 5191 status_update_manager.cpp:203] Recovering status update manager
I0815 15:18:33.830116 5194 containerizer.cpp:598] Recovering containerizer
I0815 15:18:33.832758 5197 provisioner.cpp:416] Provisioner recovery complete
I0815 15:18:33.833364 5192 slave.cpp:6210] Finished recovery
I0815 15:18:33.834166 5192 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 15:18:33.834520 5196 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 15:18:33.837715 5190 process.cpp:3933] Handling HTTP event for process 'slave(803)' with path: '/slave(803)/monitor/statistics'
I0815 15:18:33.839165 5193 http.cpp:1166] HTTP GET for /slave(803)/monitor/statistics from 172.17.0.4:47600
I0815 15:18:33.839288 5193 http.cpp:957] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0815 15:18:33.854862 5195 slave.cpp:843] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (83 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0815 15:18:33.894804 5171 containerizer.cpp:246] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0815 15:18:33.895409 5171 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0815 15:18:33.895603 5171 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0815 15:18:33.895738 5171 provisioner.cpp:255] Using default backend 'copy'
I0815 15:18:33.897703 5171 cluster.cpp:448] Creating default 'local' authorizer
I0815 15:18:33.899770 5196 slave.cpp:250] Mesos agent started on (804)@172.17.0.4:46144
I0815 15:18:33.899822 5196 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L1ykTm/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_NoAuthorizer_1_L1ykTm/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L1ykTm/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L1ykTm/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/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L1ykTm/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L1ykTm" --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_NoAuthorizer_1_kdZStr"
I0815 15:18:33.900244 5196 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L1ykTm/credential'
I0815 15:18:33.900461 5196 slave.cpp:283] Agent using credential for: test-principal
I0815 15:18:33.900539 5196 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_L1ykTm/http_credentials'
I0815 15:18:33.900856 5196 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 15:18:33.901098 5196 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 15:18:33.902683 5196 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:18:33.902936 5196 slave.cpp:573] Agent attributes: [ ]
I0815 15:18:33.903102 5196 slave.cpp:582] Agent hostname: c7b576f3f487
I0815 15:18:33.903334 5193 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:18:33.905160 5190 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_kdZStr/meta'
I0815 15:18:33.905516 5193 status_update_manager.cpp:203] Recovering status update manager
I0815 15:18:33.905767 5191 containerizer.cpp:598] Recovering containerizer
I0815 15:18:33.907690 5193 provisioner.cpp:416] Provisioner recovery complete
I0815 15:18:33.908277 5197 slave.cpp:6210] Finished recovery
I0815 15:18:33.909124 5197 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 15:18:33.909461 5193 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 15:18:33.912600 5193 process.cpp:3933] Handling HTTP event for process 'slave(804)' with path: '/slave(804)/monitor/statistics.json'
I0815 15:18:33.914152 5192 http.cpp:1166] HTTP GET for /slave(804)/monitor/statistics.json from 172.17.0.4:47604
I0815 15:18:33.914238 5192 http.cpp:957] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0815 15:18:33.918715 5171 slave.cpp:843] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (79 ms)
[ RUN ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0815 15:18:33.977859 5171 containerizer.cpp:246] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0815 15:18:33.978512 5171 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0815 15:18:33.978725 5171 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0815 15:18:33.978878 5171 provisioner.cpp:255] Using default backend 'copy'
I0815 15:18:33.981210 5171 cluster.cpp:448] Creating default 'local' authorizer
I0815 15:18:33.983889 5192 slave.cpp:250] Mesos agent started on (805)@172.17.0.4:46144
I0815 15:18:33.983924 5192 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_8m4Hg5/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_NoAuthorizer_2_8m4Hg5/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_8m4Hg5/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_8m4Hg5/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/Endpoint_SlaveEndpointTest_NoAuthorizer_2_8m4Hg5/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_8m4Hg5" --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_NoAuthorizer_2_KK3SC9"
I0815 15:18:33.985426 5192 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_8m4Hg5/credential'
I0815 15:18:33.985684 5192 slave.cpp:283] Agent using credential for: test-principal
I0815 15:18:33.985718 5192 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_8m4Hg5/http_credentials'
I0815 15:18:33.986068 5192 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0815 15:18:33.986320 5192 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0815 15:18:33.988004 5192 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 15:18:33.988207 5192 slave.cpp:573] Agent attributes: [ ]
I0815 15:18:33.988276 5192 slave.cpp:582] Agent hostname: c7b576f3f487
I0815 15:18:33.988548 5197 status_update_manager.cpp:177] Pausing sending status updates
I0815 15:18:33.990245 5193 state.cpp:64] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_KK3SC9/meta'
I0815 15:18:33.990813 5192 status_update_manager.cpp:203] Recovering status update manager
I0815 15:18:33.991613 5190 containerizer.cpp:598] Recovering containerizer
I0815 15:18:33.993283 5192 provisioner.cpp:416] Provisioner recovery complete
I0815 15:18:33.993646 5197 slave.cpp:6210] Finished recovery
I0815 15:18:33.994280 5197 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 15:18:33.994659 5192 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 15:18:33.998023 5197 process.cpp:3933] Handling HTTP event for process 'slave(805)' with path: '/slave(805)/containers'
I0815 15:18:34.000006 5194 http.cpp:1166] HTTP GET for /slave(805)/containers from 172.17.0.4:47606
I0815 15:18:34.000108 5194 http.cpp:957] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0815 15:18:34.014245 5171 slave.cpp:843] Agent terminating
[ OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (88 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (691 ms total)
[----------] 2 tests from ContainerizerType/DefaultContainerDNSFlagTest
[ RUN ] ContainerizerType/DefaultContainerDNSFlagTest.ValidateFlag/0
[ OK ] ContainerizerType/DefaultContainerDNSFlagTest.ValidateFlag/0 (12 ms)
[ RUN ] ContainerizerType/DefaultContainerDNSFlagTest.ValidateFlag/1
[ OK ] ContainerizerType/DefaultContainerDNSFlagTest.ValidateFlag/1 (10 ms)
[----------] 2 tests from ContainerizerType/DefaultContainerDNSFlagTest (22 ms total)
[----------] Global test environment tear-down
[==========] 1655 tests from 182 test cases ran. (612570 ms total)
[ PASSED ] 1654 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] DefaultExecutorCheckTest.CommandCheckSharesWorkDirWithTask
1 FAILED TEST
YOU HAVE 20 DISABLED TESTS
I0815 15:18:34.132408 5198 process.cpp:1072] Failed to accept socket: future discarded
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1502805354-7837
Untagged: mesos-1502805354-7837:latest
Deleted: sha256:79648d11325be673bbe856b48d1510eae9d7b08d3a3d738dd97bcf5a7cc1bd65
Deleted: sha256:c59d8ebbaffb48c2e09c19abfba400c6beef55aca5636967f837997130299ef1
Deleted: sha256:df51845f920730dca1413d8089b4584091c114727426b826834ecb293ef3d654
Deleted: sha256:068164025795632da1500a9b828a60bc68f79595684daca58735bf13e183f449
Deleted: sha256:b7438534c78ecac89aac2950d6b9b8b477f49f3b00c0413f1f7050eef83e5d9b
Deleted: sha256:48f2c83a9fd1ebff2ea53a88e3721951f9f1e8d29bbf163024af5ad285f7755c
Deleted: sha256:061c15e582f57abd7e13b44e039b2eb45551e6b765cd202a25bd204f013d7581
Deleted: sha256:2c12e222770dcd898a3180dd82be7f8df0415ad59c46a2d2260e4e90a8f1fb78
Deleted: sha256:4de6542ae866f0c08941f4d1a8e0205d238b9d3c2a2bbe7659d27762f1b14369
Deleted: sha256:240048133814f0c88ecc03b152173e3d1400b3dd12731616083ea91dcd005307
Deleted: sha256:2af61714505ad8f35dbe89c786ef5cba09d267711bb8c2a724a26406b5e7c83e
Deleted: sha256:427b2b5c331613e1fc6f19a6c025a4eea83d82ef6bffd6b521cebcb8814b6cfc
Deleted: sha256:f272c34a97efc9eb1e3a6eee283567f55db59f9b12958b9067e20b661fdfa29f
Deleted: sha256:1f377e96fdebf1ec6d58c3c43dbf63b09d6d14bdd901bde132db5992bb5383c4
Deleted: sha256:1ba8c60eb984fbc8222ff41ca215c411af726cb47e86f129672fc2013e5442f4
Deleted: sha256:d4fe961cbc9b5a304915d3e254988ed87eb7ccf839f2cf0bf9fb3ca0aaf69c84
Deleted: sha256:e2004f94aece73bb29e3b5eb33ad87d983efb974970d32483b3b093339e8a900
Deleted: sha256:113e0562c837de36cb1e4aaa956bf9374de14f8fdc8c9f10c1a1e1e7b5e65393
Deleted: sha256:e7efbdb3be78d700f886c0dab568f2e09c9af20755ed3ec60cd20e9cee3fd8ca
Deleted: sha256:f00195f6bf450e623ceabfce72323dba7ba80b624eae78132d390e82cddcade9
Deleted: sha256:9148a76c8e6ca7a58dd27cf50cc2784a5a9e5d59f8c3befcf90a99463fc70303
Deleted: sha256:06bda394de7b0eb1b4989a023e0e5676ff90ea7504d18ffa313e81e3ec4a66d1
Deleted: sha256:a86c6b22d92854dd1089bafa7bc60093eca0720215b4a9c2bc99dadaa6a753a4
Deleted: sha256:7fb0b810bb5c1132a5fa263c33cafe2685dbb766541ff55251ca6d1120b061cd
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1990@gmail.com
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4109
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4109/display/redirect>
------------------------------------------
[...truncated 11.30 MB...]
I0815 07:55:32.973080 5190 sched.cpp:407] Authenticating with master master@172.17.0.4:35197
I0815 07:55:32.973196 5190 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0815 07:55:32.973776 5195 authenticatee.cpp:121] Creating new client SASL connection
I0815 07:55:32.974151 5191 master.cpp:7837] Authenticating scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197
I0815 07:55:32.974478 5191 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(378)@172.17.0.4:35197
I0815 07:55:32.974762 5190 authenticator.cpp:98] Creating new server SASL connection
I0815 07:55:32.975086 5189 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0815 07:55:32.975118 5189 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0815 07:55:32.975363 5189 authenticator.cpp:204] Received SASL authentication start
I0815 07:55:32.975425 5189 authenticator.cpp:326] Authentication requires more steps
I0815 07:55:32.975663 5189 authenticatee.cpp:259] Received SASL authentication step
I0815 07:55:32.975852 5189 authenticator.cpp:232] Received SASL authentication step
I0815 07:55:32.975934 5189 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5c5fca379317' server FQDN: '5c5fca379317' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0815 07:55:32.975950 5189 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0815 07:55:32.975985 5189 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0815 07:55:32.976092 5189 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5c5fca379317' server FQDN: '5c5fca379317' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0815 07:55:32.976117 5189 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0815 07:55:32.976125 5189 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0815 07:55:32.976255 5189 authenticator.cpp:318] Authentication success
I0815 07:55:32.976383 5196 authenticatee.cpp:299] Authentication success
I0815 07:55:32.976542 5190 master.cpp:7867] Successfully authenticated principal 'test-principal' at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197
I0815 07:55:32.976707 5190 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(378)@172.17.0.4:35197
I0815 07:55:32.976961 5189 sched.cpp:513] Successfully authenticated with master master@172.17.0.4:35197
I0815 07:55:32.976989 5189 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.4:35197
I0815 07:55:32.977275 5189 sched.cpp:869] Will retry registration in 273.070525ms if necessary
I0815 07:55:32.977591 5191 master.cpp:2894] Received SUBSCRIBE call for framework 'default' at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197
I0815 07:55:32.977787 5191 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ role }'
I0815 07:55:32.978899 5191 master.cpp:2974] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0815 07:55:32.979650 5190 sched.cpp:759] Framework registered with a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:32.979733 5190 sched.cpp:773] Scheduler::registered took 50016ns
I0815 07:55:32.980118 5194 hierarchical.cpp:303] Added framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:32.980376 5194 hierarchical.cpp:1925] No allocations performed
I0815 07:55:32.980499 5194 hierarchical.cpp:2015] No inverse offers to send out!
I0815 07:55:32.980550 5194 hierarchical.cpp:1468] Performed allocation for 0 agents in 212844ns
I0815 07:55:32.980960 5192 slave.cpp:565] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 07:55:32.981204 5192 slave.cpp:573] Agent attributes: [ ]
I0815 07:55:32.981331 5192 slave.cpp:582] Agent hostname: 5c5fca379317
I0815 07:55:32.982075 5194 status_update_manager.cpp:177] Pausing sending status updates
I0815 07:55:32.983449 5190 state.cpp:64] Recovering state from '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/meta'
I0815 07:55:32.983856 5194 status_update_manager.cpp:203] Recovering status update manager
I0815 07:55:32.984375 5190 slave.cpp:6210] Finished recovery
I0815 07:55:32.985105 5190 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0815 07:55:32.985620 5194 status_update_manager.cpp:177] Pausing sending status updates
I0815 07:55:32.985620 5190 slave.cpp:971] New master detected at master@172.17.0.4:35197
I0815 07:55:32.985782 5190 slave.cpp:1006] Detecting new master
I0815 07:55:32.986768 5190 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0815 07:55:32.988195 5196 slave.cpp:1033] Authenticating with master master@172.17.0.4:35197
I0815 07:55:32.988291 5196 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0815 07:55:32.988759 5196 authenticatee.cpp:121] Creating new client SASL connection
I0815 07:55:32.989117 5189 master.cpp:7837] Authenticating slave(169)@172.17.0.4:35197
I0815 07:55:32.989362 5191 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(379)@172.17.0.4:35197
I0815 07:55:32.989787 5191 authenticator.cpp:98] Creating new server SASL connection
I0815 07:55:32.990619 5194 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0815 07:55:32.990653 5194 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0815 07:55:32.990820 5195 authenticator.cpp:204] Received SASL authentication start
I0815 07:55:32.990869 5195 authenticator.cpp:326] Authentication requires more steps
I0815 07:55:32.991039 5194 authenticatee.cpp:259] Received SASL authentication step
I0815 07:55:32.991222 5194 authenticator.cpp:232] Received SASL authentication step
I0815 07:55:32.991255 5194 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5c5fca379317' server FQDN: '5c5fca379317' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0815 07:55:32.991271 5194 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0815 07:55:32.991294 5194 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0815 07:55:32.991313 5194 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5c5fca379317' server FQDN: '5c5fca379317' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0815 07:55:32.991435 5194 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0815 07:55:32.991463 5194 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0815 07:55:32.991478 5194 authenticator.cpp:318] Authentication success
I0815 07:55:32.991588 5196 authenticatee.cpp:299] Authentication success
I0815 07:55:32.991852 5189 master.cpp:7867] Successfully authenticated principal 'test-principal' at slave(169)@172.17.0.4:35197
I0815 07:55:32.992049 5191 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(379)@172.17.0.4:35197
I0815 07:55:32.992233 5189 slave.cpp:1128] Successfully authenticated with master master@172.17.0.4:35197
*** Aborted at 1502783732 (unix time) try "date -d @1502783732" if you are using GNU date ***
I0815 07:55:32.992697 5189 slave.cpp:1572] Will retry registration in 1.007958ms if necessary
I0815 07:55:32.992945 5195 master.cpp:5712] Received register agent message from slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:32.993175 5195 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0815 07:55:32.993657 5190 master.cpp:5772] Authorized registration of agent at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:32.994000 5190 master.cpp:5865] Registering agent at slave(169)@172.17.0.4:35197 (5c5fca379317) with id a68c7f20-4d53-48b2-becc-ce045060b2f6-S0
I0815 07:55:32.994426 5195 registrar.cpp:495] Applied 1 operations in 47319ns; attempting to update the registry
I0815 07:55:32.995069 5191 slave.cpp:1572] Will retry registration in 38.86716ms if necessary
I0815 07:55:32.995394 5195 registrar.cpp:552] Successfully updated the registry in 905984ns
I0815 07:55:32.995407 5190 master.cpp:5706] Ignoring register agent message from slave(169)@172.17.0.4:35197 (5c5fca379317) as registration is already in progress
I0815 07:55:32.995683 5191 master.cpp:5912] Admitted agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:32.996481 5190 slave.cpp:1174] Registered with master master@172.17.0.4:35197; given agent ID a68c7f20-4d53-48b2-becc-ce045060b2f6-S0
I0815 07:55:32.996373 5191 master.cpp:5943] Registered agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0815 07:55:32.996786 5191 status_update_manager.cpp:184] Resuming sending status updates
I0815 07:55:32.996937 5190 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/meta/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/slave.info'
I0815 07:55:32.996942 5194 hierarchical.cpp:593] Added agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 (5c5fca379317) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0815 07:55:32.997534 5190 slave.cpp:1232] Forwarding total oversubscribed resources {}
I0815 07:55:32.997882 5190 slave.cpp:4887] Received ping from slave-observer(165)@172.17.0.4:35197
I0815 07:55:32.998109 5195 master.cpp:6688] Received update of agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317) with total oversubscribed resources {}
I0815 07:55:32.998746 5194 hierarchical.cpp:2015] No inverse offers to send out!
I0815 07:55:32.998877 5194 hierarchical.cpp:1468] Performed allocation for 1 agents in 1.655876ms
I0815 07:55:32.999104 5194 hierarchical.cpp:660] Agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 (5c5fca379317) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0815 07:55:32.999583 5193 master.cpp:7667] Sending 1 offers to framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (default) at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197
I0815 07:55:33.000182 5189 sched.cpp:933] Scheduler::resourceOffers took 104105ns
I0815 07:55:33.002066 5195 master.cpp:9164] Removing offer a68c7f20-4d53-48b2-becc-ce045060b2f6-O0
I0815 07:55:33.002619 5195 master.cpp:4153] Processing ACCEPT call for offers: [ a68c7f20-4d53-48b2-becc-ce045060b2f6-O0 ] on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317) for framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (default) at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197
I0815 07:55:33.002825 5195 master.cpp:3530] Authorizing framework principal 'test-principal' to launch task 1
W0815 07:55:33.005771 5193 validation.cpp:1294] Executor 'test-executor' 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.
W0815 07:55:33.005836 5193 validation.cpp:1306] Executor 'test-executor' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0815 07:55:33.006482 5193 master.cpp:9724] Adding task 1 with resources [{"allocation_info":{"role":"role"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:33.007170 5193 master.cpp:4816] Launching task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (default) at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197 with resources [{"allocation_info":{"role":"role"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:33.007838 5191 slave.cpp:1701] Got assigned task '1' for framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.008581 5191 slave.cpp:1982] Authorizing task '1' for framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.008760 5191 slave.cpp:6709] Authorizing framework principal 'test-principal' to launch task 1
I0815 07:55:33.009630 5193 slave.cpp:2169] Launching task '1' for framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.010619 5193 paths.cpp:578] Trying to chown '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/frameworks/a68c7f20-4d53-48b2-becc-ce045060b2f6-0000/executors/test-executor/runs/756a1c72-7cee-480b-bfd0-6aea3c4c016f' to user 'bar'
W0815 07:55:33.010740 5193 paths.cpp:586] Failed to chown executor directory '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/frameworks/a68c7f20-4d53-48b2-becc-ce045060b2f6-0000/executors/test-executor/runs/756a1c72-7cee-480b-bfd0-6aea3c4c016f'. This may be due to attempting to run the executor as a nonexistent user on the agent; see the description for the `--switch_user` flag for more information: Failed to get user information for 'bar': Success
I0815 07:55:33.011001 5193 slave.cpp:7165] Launching executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 with resources [] in work directory '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/frameworks/a68c7f20-4d53-48b2-becc-ce045060b2f6-0000/executors/test-executor/runs/756a1c72-7cee-480b-bfd0-6aea3c4c016f'
I0815 07:55:33.011469 5194 hierarchical.cpp:887] Updated allocation of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 from cpus(allocated: role):2; mem(allocated: role):1024; disk(allocated: role):1024; ports(allocated: role):[31000-32000] to cpus(allocated: role):2; mem(allocated: role):1024; disk(allocated: role):1024; ports(allocated: role):[31000-32000]
I0815 07:55:33.011972 5193 slave.cpp:2856] Launching container 756a1c72-7cee-480b-bfd0-6aea3c4c016f for executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.012410 5193 slave.cpp:2398] Queued task '1' for executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.014511 5192 process.cpp:3228] Attempting to spawn already spawned process version@172.17.0.4:35197
I0815 07:55:33.015023 5192 exec.cpp:162] Version: 1.4.0
I0815 07:55:33.015421 5195 exec.cpp:212] Executor started at: executor(60)@172.17.0.4:35197 with pid 5170
I0815 07:55:33.015738 5193 slave.cpp:924] Successfully attached file '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/frameworks/a68c7f20-4d53-48b2-becc-ce045060b2f6-0000/executors/test-executor/runs/756a1c72-7cee-480b-bfd0-6aea3c4c016f'
I0815 07:55:33.015815 5193 slave.cpp:924] Successfully attached file '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/frameworks/a68c7f20-4d53-48b2-becc-ce045060b2f6-0000/executors/test-executor/runs/756a1c72-7cee-480b-bfd0-6aea3c4c016f'
I0815 07:55:33.026986 5193 slave.cpp:3881] Got registration for executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 from executor(60)@172.17.0.4:35197
I0815 07:55:33.027526 5194 exec.cpp:237] Executor registered on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0
I0815 07:55:33.027673 5194 exec.cpp:249] Executor::registered took 103982ns
I0815 07:55:33.028447 5193 slave.cpp:2603] Sending queued task '1' to executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 at executor(60)@172.17.0.4:35197
I0815 07:55:33.028939 5193 exec.cpp:331] Executor asked to run task '1'
I0815 07:55:33.029139 5193 exec.cpp:340] Executor::launchTask took 146594ns
I0815 07:55:33.029426 5193 exec.cpp:571] Executor sending status update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.030135 5195 slave.cpp:4345] Handling status update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 from executor(60)@172.17.0.4:35197
I0815 07:55:33.030896 5191 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.030974 5191 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.031548 5191 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 to the agent
I0815 07:55:33.031934 5189 slave.cpp:4797] Forwarding the update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 to master@172.17.0.4:35197
I0815 07:55:33.032200 5189 slave.cpp:4691] Status update manager successfully handled status update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.032332 5189 slave.cpp:4707] Sending acknowledgement for status update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 to executor(60)@172.17.0.4:35197
I0815 07:55:33.032433 5190 master.cpp:6846] Status update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 from agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:33.032501 5190 master.cpp:6908] Forwarding status update TASK_RUNNING (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.032667 5189 exec.cpp:388] Executor received status update acknowledgement 94160e36-b941-49c0-aba8-a1d35babcb16 for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.032796 5190 master.cpp:8933] Updating the state of task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0815 07:55:33.033138 5189 sched.cpp:1041] Scheduler::statusUpdate took 85822ns
I0815 07:55:33.033607 5193 master.cpp:5477] Processing ACKNOWLEDGE call 94160e36-b941-49c0-aba8-a1d35babcb16 for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (default) at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197 on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0
I0815 07:55:33.034080 5192 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.034569 5192 slave.cpp:3609] Status update manager successfully handled status update acknowledgement (UUID: 94160e36-b941-49c0-aba8-a1d35babcb16) for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.036504 5191 process.cpp:3933] Handling HTTP event for process 'master' with path: '/master/frameworks'
I0815 07:55:33.038031 5194 http.cpp:1166] HTTP GET for /master/frameworks from 172.17.0.4:60012
PC: @ 0x2b614f1a2db6 process::EventQueue::Consumer::empty()
*** SIGSEGV (@0x8) received by PID 5170 (TID 0x2b615abb4700) from PID 8; stack trace: ***
I0815 07:55:33.046377 5190 process.cpp:3933] Handling HTTP event for process 'master' with path: '/master/frameworks'
I0815 07:55:33.047654 5189 http.cpp:1166] HTTP GET for /master/frameworks from 172.17.0.4:60014
@ 0x2b6181596417 (unknown)
@ 0x2b618159a6f9 (unknown)
@ 0x2b6151843330 (unknown)
I0815 07:55:33.051333 5170 sched.cpp:2021] Asked to stop the driver
I0815 07:55:33.051524 5191 sched.cpp:1203] Stopping framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.052070 5195 master.cpp:8348] Processing TEARDOWN call for framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (default) at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197
I0815 07:55:33.052105 5195 master.cpp:8360] Removing framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (default) at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197
I0815 07:55:33.052227 5195 master.cpp:3264] Deactivating framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (default) at scheduler-920ff871-d6a6-4696-822d-1b7c81a7b4a5@172.17.0.4:35197
I0815 07:55:33.052397 5190 hierarchical.cpp:412] Deactivated framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.052604 5190 slave.cpp:3159] Asked to shut down framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 by master@172.17.0.4:35197
I0815 07:55:33.052616 5195 master.cpp:8933] Updating the state of task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0815 07:55:33.052646 5190 slave.cpp:3184] Shutting down framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.052753 5190 slave.cpp:5651] Shutting down executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 at executor(60)@172.17.0.4:35197
I0815 07:55:33.052989 5190 exec.cpp:435] Executor asked to shutdown
I0815 07:55:33.053145 5190 exec.cpp:450] Executor::shutdown took 131017ns
I0815 07:55:33.053468 5190 slave.cpp:4929] Got exited event for executor(60)@172.17.0.4:35197
I0815 07:55:33.054229 5193 hierarchical.cpp:1152] Recovered cpus(allocated: role):2; mem(allocated: role):1024; disk(allocated: role):1024; ports(allocated: role):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 from framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.053738 5195 master.cpp:9027] Removing task 1 with resources [{"allocation_info":{"role":"role"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"role"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:33.055032 5195 master.cpp:9056] Removing executor 'test-executor' with resources [] of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:33.055578 5191 slave.cpp:5329] Executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 exited with status 0
I0815 07:55:33.055797 5195 hierarchical.cpp:355] Removed framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.056136 5191 slave.cpp:5429] Cleaning up executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 at executor(60)@172.17.0.4:35197
W0815 07:55:33.056241 5190 master.cpp:6963] Ignoring unknown exited executor 'test-executor' of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000 on agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:33.056617 5190 gc.cpp:59] Scheduling '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/frameworks/a68c7f20-4d53-48b2-becc-ce045060b2f6-0000/executors/test-executor/runs/756a1c72-7cee-480b-bfd0-6aea3c4c016f' for gc 6.99999934612444days in the future
I0815 07:55:33.056965 5191 slave.cpp:5525] Cleaning up framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.057350 5189 status_update_manager.cpp:285] Closing status update streams for framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.057123 5192 gc.cpp:59] Scheduling '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/frameworks/a68c7f20-4d53-48b2-becc-ce045060b2f6-0000/executors/test-executor' for gc 6.99999934255704days in the future
I0815 07:55:33.057478 5189 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework a68c7f20-4d53-48b2-becc-ce045060b2f6-0000
I0815 07:55:33.057823 5189 gc.cpp:59] Scheduling '/tmp/MasterAuthorizerTest_1_FilterFrameworksEndpoint_aCaz6X/slaves/a68c7f20-4d53-48b2-becc-ce045060b2f6-S0/frameworks/a68c7f20-4d53-48b2-becc-ce045060b2f6-0000' for gc 6.99999933162074days in the future
I0815 07:55:33.057881 5191 slave.cpp:843] Agent terminating
I0815 07:55:33.058578 5190 master.cpp:1318] Agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317) disconnected
I0815 07:55:33.058610 5190 master.cpp:3301] Disconnecting agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:33.058665 5190 master.cpp:3320] Deactivating agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 at slave(169)@172.17.0.4:35197 (5c5fca379317)
I0815 07:55:33.058929 5190 hierarchical.cpp:690] Agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0 deactivated
I0815 07:55:33.067242 5170 master.cpp:1160] Master terminating
I0815 07:55:33.067905 5194 hierarchical.cpp:626] Removed agent a68c7f20-4d53-48b2-becc-ce045060b2f6-S0
[ OK ] MasterAuthorizerTest/1.FilterFrameworksEndpoint (136 ms)
[ RUN ] MasterAuthorizerTest/1.FilterTasksEndpoint
I0815 07:55:33.093044 5195 master.cpp:442] Master cc67b8f3-abba-4beb-a327-00380c5b7353 (5c5fca379317) started on 172.17.0.4:35197
I0815 07:55:33.093089 5195 master.cpp:444] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/P1TJ3y/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.4.0/_inst/share/mesos/webui" --work_dir="/tmp/P1TJ3y/master" --zk_session_timeout="10secs"
I0815 07:55:33.093576 5195 master.cpp:494] Master only allowing authenticated frameworks to register
I0815 07:55:33.093597 5195 master.cpp:508] Master only allowing authenticated agents to register
I0815 07:55:33.093603 5195 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0815 07:55:33.093610 5195 credentials.hpp:37] Loading credentials for authentication from '/tmp/P1TJ3y/credentials'
I0815 07:55:33.093955 5195 master.cpp:566] Using default 'crammd5' authenticator
I0815 07:55:33.094262 5195 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0815 07:55:33.094547 5195 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
@ 0x2b614f1a2db6 process::EventQueue::Consumer::empty()
I0815 07:55:33.095029 5195 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0815 07:55:33.095281 5195 master.cpp:646] Authorization enabled
I0815 07:55:33.095712 5193 hierarchical.cpp:171] Initialized hierarchical allocator process
I0815 07:55:33.095965 5193 whitelist_watcher.cpp:77] No whitelist given
I0815 07:55:33.099761 5193 master.cpp:2163] Elected as the leading master!
I0815 07:55:33.099827 5193 master.cpp:1702] Recovering from registrar
I0815 07:55:33.100266 5189 registrar.cpp:347] Recovering registrar
I0815 07:55:33.100927 5189 registrar.cpp:391] Successfully fetched the registry (0B) in 616960ns
I0815 07:55:33.101140 5189 registrar.cpp:495] Applied 1 operations in 30684ns; attempting to update the registry
I0815 07:55:33.101969 5193 registrar.cpp:552] Successfully updated the registry in 766976ns
I0815 07:55:33.102502 5193 registrar.cpp:424] Successfully recovered registrar
I0815 07:55:33.103050 5193 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0815 07:55:33.103305 5194 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
@ 0x2b614f18fa64 process::ProcessManager::resume()
@ 0x2b614f18c060 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b614f19d7ce _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b614f19d725 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b614f19d6be _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2b61510c4a60 (unknown)
@ 0x2b615183b184 start_thread
@ 0x2b6151b4effd (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1502779268-32366
Untagged: mesos-1502779268-32366:latest
Deleted: sha256:09000982c253c962f6ec74f691894af636c3d7faf759e48ea4d978459c95faea
Deleted: sha256:d33f45c02965075b52579a9639a885c539586eeb257173ed02c82109044cab49
Deleted: sha256:e2056c049f91b76f31e6386579bd4b825dc86b446bede33dd9614a2935301268
Deleted: sha256:9bd0047301e33d891608237f155e023399cfd3b32aecb9e36222884f7ce500af
Deleted: sha256:d5e7a1562370f36cb9c2f33d4395e1f8c2852fc74d5e0bea724d01404405cbfa
Deleted: sha256:45d0f136ae41e9f1e3fb519ea96a22221894dd2d8209f40d09482dd40f498eec
Deleted: sha256:74f64705d8399d9b2ceb4bee4a784e5349e699e48c1c4643d19b0e3a7c216b61
Deleted: sha256:a1bdd61d59ecdb8da709906c844143eba4c2b2f4f610ee997c9e350e8be24f68
Deleted: sha256:05e3b08ba33c7f13121ef396c62ae90d38bab9ae1e673a851d1663279d1ad755
Deleted: sha256:f0651a9a2e505d86cd0e28529d19a3be88c3fd3b4d3a20bf4a11a2958e49641b
Deleted: sha256:d409374546ae88df243618731b026f4b13cf1fe91b1360213428c5656fc9e9af
Deleted: sha256:dfd4b2b580437453813a47683373f2b507368e0fde6ee8fb8dbb8b0da5ded715
Deleted: sha256:8a76f46903b6a5f1ba79c09f5eacade76f5d5c1899e7d9da2d38ff7d5ce0eca2
Deleted: sha256:5aa71112fa52b7820e695f2de477ea5af9d124dbe3930d81fb6b37b67ee4ea21
Deleted: sha256:a9066591cc546e17c4dfe2f6e0ae9a36d228aaaed80beeb1cb53cb91b3485ded
Deleted: sha256:83996a4044587050a1a5213407be14ceea4ad9e222d008905da1d837dc2760d6
Deleted: sha256:64f2177772c7d0e654548d0ff786412a048de7a7d1c625714cd1cc769472e300
Deleted: sha256:e3e24df8f607e2697e5f68f59b0ed0b64d83066e52e260792fad20ce8045dff4
Deleted: sha256:c9b9242ff2b050c26112d15f70aaf6a7f07e447b7ddc4fe199277cef4fa72d9b
Deleted: sha256:1bb64d29b08ca1fad9c0949736fc273cb7d12c22d76652adc39b647530c069d0
Deleted: sha256:af40714be944c01ea0c7a0288ba707e90b69d5134e64e911605c868139db06c3
Deleted: sha256:e0664edf20f63c0f0068c0f2e25a53ac3f68b8adb11d61ffe4bd2c807211feaf
Deleted: sha256:70b3ba729643439a55bea71a9baabbe0eeccd146dc5db04be5c5da427ac37990
Deleted: sha256:ffdb3402ec1eb7149975d1a102bbf87c5eb7a525420df675152d417331a7a5ac
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1990@gmail.com