See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4461/display/redirect?page=changes>
Changes: [alexr] Fixed "opeartor" typo in "master.cpp". ------------------------------------------ [...truncated 8.03 MB...] 3: I1115 10:31:07.039255 19971 slave.cpp:883] Agent terminating 3: I1115 10:31:07.046605 19948 master.cpp:1151] Master terminating 3: [ OK ] SlaveTest.ReadonlyHTTPEndpointsNoAuthentication (83 ms) 3: [ RUN ] SlaveTest.HTTPExecutorBadAuthentication 3: I1115 10:31:07.053879 19948 cluster.cpp:162] Creating default 'local' authorizer 3: I1115 10:31:07.058357 19957 master.cpp:448] Master 9553a2d0-9d07-4ff8-84b1-6345da513028 (894218ea17d1) started on 172.17.0.2:35204 3: I1115 10:31:07.058382 19957 master.cpp:450] 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/NgeR8F/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="/usr/local/share/mesos/webui" --work_dir="/tmp/NgeR8F/master" --zk_session_timeout="10secs" 3: I1115 10:31:07.058707 19957 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1115 10:31:07.058724 19957 master.cpp:505] Master only allowing authenticated agents to register 3: I1115 10:31:07.058734 19957 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1115 10:31:07.058746 19957 credentials.hpp:37] Loading credentials for authentication from '/tmp/NgeR8F/credentials' 3: I1115 10:31:07.059092 19957 master.cpp:555] Using default 'crammd5' authenticator 3: I1115 10:31:07.059267 19957 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1115 10:31:07.059453 19957 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1115 10:31:07.059618 19957 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1115 10:31:07.059751 19957 master.cpp:634] Authorization enabled 3: I1115 10:31:07.059917 19960 hierarchical.cpp:171] Initialized hierarchical allocator process 3: I1115 10:31:07.059962 19956 whitelist_watcher.cpp:77] No whitelist given 3: I1115 10:31:07.063838 19949 master.cpp:2213] Elected as the leading master! 3: I1115 10:31:07.063880 19949 master.cpp:1693] Recovering from registrar 3: I1115 10:31:07.064071 19959 registrar.cpp:347] Recovering registrar 3: I1115 10:31:07.064957 19959 registrar.cpp:391] Successfully fetched the registry (0B) in 817920ns 3: I1115 10:31:07.065129 19959 registrar.cpp:495] Applied 1 operations in 45499ns; attempting to update the registry 3: I1115 10:31:07.066031 19959 registrar.cpp:552] Successfully updated the registry in 818944ns 3: I1115 10:31:07.066215 19959 registrar.cpp:424] Successfully recovered registrar 3: I1115 10:31:07.066788 19968 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1115 10:31:07.066823 19953 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1115 10:31:07.074517 19948 process.cpp:3178] Attempted to spawn already running process files@172.17.0.2:35204 3: I1115 10:31:07.075906 19950 slave.cpp:262] Mesos agent started on @172.17.0.2:35204 3: I1115 10:31:07.075940 19950 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/SlaveTest_HTTPExecutorBadAuthentication_fwnElK/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveTest_HTTPExecutorBadAuthentication_fwnElK/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/SlaveTest_HTTPExecutorBadAuthentication_fwnElK/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_secret_key="/tmp/SlaveTest_HTTPExecutorBadAuthentication_fwnElK/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveTest_HTTPExecutorBadAuthentication_fwnElK/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/SlaveTest_HTTPExecutorBadAuthentication_fwnElK/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveTest_HTTPExecutorBadAuthentication_fwnElK" --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/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd" --zk_session_timeout="10secs" 3: I1115 10:31:07.076540 19950 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_HTTPExecutorBadAuthentication_fwnElK/credential' 3: I1115 10:31:07.076769 19950 slave.cpp:295] Agent using credential for: test-principal 3: I1115 10:31:07.076798 19950 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_HTTPExecutorBadAuthentication_fwnElK/http_credentials' 3: I1115 10:31:07.076859 19948 scheduler.cpp:190] Version: 1.5.0 3: I1115 10:31:07.077219 19950 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1115 10:31:07.077229 19969 scheduler.cpp:313] Using default 'basic' HTTP authenticatee 3: I1115 10:31:07.077436 19950 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1115 10:31:07.077749 19950 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1115 10:31:07.077824 19965 scheduler.cpp:496] New master detected at master@172.17.0.2:35204 3: I1115 10:31:07.077865 19965 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master 3: I1115 10:31:07.077910 19950 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1115 10:31:07.078166 19950 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1115 10:31:07.078311 19950 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1115 10:31:07.080351 19969 scheduler.cpp:387] Connected with the master at http://172.17.0.2:35204/master/api/v1/scheduler 3: I1115 10:31:07.080315 19950 slave.cpp:593] 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"}] 3: I1115 10:31:07.080718 19950 slave.cpp:601] Agent attributes: [ ] 3: I1115 10:31:07.080734 19950 slave.cpp:610] Agent hostname: 894218ea17d1 3: I1115 10:31:07.080889 19969 status_update_manager.cpp:177] Pausing sending status updates 3: I1115 10:31:07.081748 19956 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:35204/master/api/v1/scheduler 3: I1115 10:31:07.082970 19963 state.cpp:64] Recovering state from '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/meta' 3: I1115 10:31:07.083292 19962 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1115 10:31:07.083355 19955 status_update_manager.cpp:203] Recovering status update manager 3: I1115 10:31:07.083822 19964 slave.cpp:6441] Finished recovery 3: I1115 10:31:07.084727 19956 slave.cpp:1007] New master detected at master@172.17.0.2:35204 3: I1115 10:31:07.084746 19949 status_update_manager.cpp:177] Pausing sending status updates 3: I1115 10:31:07.084823 19956 slave.cpp:1042] Detecting new master 3: I1115 10:31:07.085224 19954 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:59640 3: I1115 10:31:07.085633 19954 master.cpp:2613] Received subscription request for HTTP framework 'default' 3: I1115 10:31:07.085796 19954 master.cpp:2278] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1115 10:31:07.086643 19951 master.cpp:2748] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1115 10:31:07.087838 19963 hierarchical.cpp:303] Added framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.088152 19963 hierarchical.cpp:1450] Performed allocation for 0 agents in 114541ns 3: I1115 10:31:07.088927 19964 scheduler.cpp:741] Enqueuing event SUBSCRIBED received from http://172.17.0.2:35204/master/api/v1/scheduler 3: I1115 10:31:07.089653 19964 scheduler.cpp:741] Enqueuing event HEARTBEAT received from http://172.17.0.2:35204/master/api/v1/scheduler 3: I1115 10:31:07.091236 19959 slave.cpp:1069] Authenticating with master master@172.17.0.2:35204 3: I1115 10:31:07.091346 19959 slave.cpp:1078] Using default CRAM-MD5 authenticatee 3: I1115 10:31:07.091727 19966 authenticatee.cpp:121] Creating new client SASL connection 3: I1115 10:31:07.092166 19968 master.cpp:8285] Authenticating (196)@172.17.0.2:35204 3: I1115 10:31:07.092325 19951 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(385)@172.17.0.2:35204 3: I1115 10:31:07.092697 19962 authenticator.cpp:98] Creating new server SASL connection 3: I1115 10:31:07.093035 19955 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1115 10:31:07.093076 19955 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1115 10:31:07.093271 19955 authenticator.cpp:204] Received SASL authentication start 3: I1115 10:31:07.093407 19955 authenticator.cpp:326] Authentication requires more steps 3: I1115 10:31:07.093613 19955 authenticatee.cpp:259] Received SASL authentication step 3: I1115 10:31:07.093840 19960 authenticator.cpp:232] Received SASL authentication step 3: I1115 10:31:07.093886 19960 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '894218ea17d1' server FQDN: '894218ea17d1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1115 10:31:07.093906 19960 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1115 10:31:07.093956 19960 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1115 10:31:07.093988 19960 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '894218ea17d1' server FQDN: '894218ea17d1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1115 10:31:07.094002 19960 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1115 10:31:07.094009 19960 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1115 10:31:07.094032 19960 authenticator.cpp:318] Authentication success 3: I1115 10:31:07.094147 19961 authenticatee.cpp:299] Authentication success 3: I1115 10:31:07.094271 19957 master.cpp:8315] Successfully authenticated principal 'test-principal' at (196)@172.17.0.2:35204 3: I1115 10:31:07.094342 19961 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(385)@172.17.0.2:35204 3: I1115 10:31:07.094655 19970 slave.cpp:1161] Successfully authenticated with master master@172.17.0.2:35204 3: I1115 10:31:07.095059 19970 slave.cpp:1682] Will retry registration in 8.589541ms if necessary 3: I1115 10:31:07.095338 19950 master.cpp:6032] Received register agent message from (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.095521 19950 master.cpp:3870] Authorizing agent with principal 'test-principal' 3: I1115 10:31:07.096098 19964 master.cpp:6092] Authorized registration of agent at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.096282 19964 master.cpp:6185] Registering agent at (196)@172.17.0.2:35204 (894218ea17d1) with id 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 3: I1115 10:31:07.096942 19952 registrar.cpp:495] Applied 1 operations in 97975ns; attempting to update the registry 3: I1115 10:31:07.097877 19952 registrar.cpp:552] Successfully updated the registry in 848128ns 3: I1115 10:31:07.098181 19966 master.cpp:6232] Admitted agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.099256 19951 slave.cpp:1207] Registered with master master@172.17.0.2:35204; given agent ID 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 3: I1115 10:31:07.099417 19963 status_update_manager.cpp:184] Resuming sending status updates 3: I1115 10:31:07.099108 19966 master.cpp:6263] Registered agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 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"}] 3: I1115 10:31:07.099647 19962 hierarchical.cpp:593] Added agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 (894218ea17d1) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1115 10:31:07.099824 19951 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/meta/slaves/9553a2d0-9d07-4ff8-84b1-6345da513028-S0/slave.info' 3: I1115 10:31:07.101614 19962 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.712919ms 3: I1115 10:31:07.102381 19955 master.cpp:8115] Sending 1 offers to framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) 3: I1115 10:31:07.105150 19958 scheduler.cpp:741] Enqueuing event OFFERS received from http://172.17.0.2:35204/master/api/v1/scheduler 3: I1115 10:31:07.107836 19969 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:35204/master/api/v1/scheduler 3: I1115 10:31:07.110430 19966 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' 3: I1115 10:31:07.112555 19953 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:59638 3: I1115 10:31:07.114104 19953 master.cpp:10024] Removing offer 9553a2d0-9d07-4ff8-84b1-6345da513028-O0 3: I1115 10:31:07.114394 19953 master.cpp:4228] Processing ACCEPT call for offers: [ 9553a2d0-9d07-4ff8-84b1-6345da513028-O0 ] on agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) for framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) 3: I1115 10:31:07.114599 19953 master.cpp:3597] Authorizing framework principal 'test-principal' to launch task 9d2a31cc-01fa-4553-b859-a655f5f1823d 3: I1115 10:31:07.119261 19953 master.cpp:10770] Adding task 9d2a31cc-01fa-4553-b859-a655f5f1823d with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] on agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.119863 19953 master.cpp:5176] Launching task group { 9d2a31cc-01fa-4553-b859-a655f5f1823d } of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) with resources cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 on agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.120609 19950 slave.cpp:1803] Got assigned task group containing tasks [ 9d2a31cc-01fa-4553-b859-a655f5f1823d ] for framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.121424 19964 hierarchical.cpp:1125] Recovered cpus(allocated: *):1.8; mem(allocated: *):960; disk(allocated: *):960; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.2; mem(allocated: *):64; disk(allocated: *):64) on agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 from framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.121526 19964 hierarchical.cpp:1171] Framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 filtered agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 for 5secs 3: I1115 10:31:07.121825 19950 slave.cpp:2071] Authorizing task group containing tasks [ 9d2a31cc-01fa-4553-b859-a655f5f1823d ] for framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.122728 19950 slave.cpp:2239] Launching task group containing tasks [ 9d2a31cc-01fa-4553-b859-a655f5f1823d ] for framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.123538 19950 paths.cpp:621] Trying to chown '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/slaves/9553a2d0-9d07-4ff8-84b1-6345da513028-S0/frameworks/9553a2d0-9d07-4ff8-84b1-6345da513028-0000/executors/default/runs/2adc9a68-9ad1-4f69-a1d4-da6c38f6c1c3' to user 'mesos' 3: I1115 10:31:07.123894 19950 slave.cpp:7736] Launching executor 'default' of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/slaves/9553a2d0-9d07-4ff8-84b1-6345da513028-S0/frameworks/9553a2d0-9d07-4ff8-84b1-6345da513028-0000/executors/default/runs/2adc9a68-9ad1-4f69-a1d4-da6c38f6c1c3' 3: I1115 10:31:07.124892 19950 slave.cpp:2467] Queued task group containing tasks [ 9d2a31cc-01fa-4553-b859-a655f5f1823d ] for executor 'default' of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.125020 19950 slave.cpp:958] Successfully attached '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/slaves/9553a2d0-9d07-4ff8-84b1-6345da513028-S0/frameworks/9553a2d0-9d07-4ff8-84b1-6345da513028-0000/executors/default/runs/2adc9a68-9ad1-4f69-a1d4-da6c38f6c1c3' to virtual path '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/slaves/9553a2d0-9d07-4ff8-84b1-6345da513028-S0/frameworks/9553a2d0-9d07-4ff8-84b1-6345da513028-0000/executors/default/runs/latest' 3: I1115 10:31:07.125074 19950 slave.cpp:958] Successfully attached '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/slaves/9553a2d0-9d07-4ff8-84b1-6345da513028-S0/frameworks/9553a2d0-9d07-4ff8-84b1-6345da513028-0000/executors/default/runs/2adc9a68-9ad1-4f69-a1d4-da6c38f6c1c3' to virtual path '/frameworks/9553a2d0-9d07-4ff8-84b1-6345da513028-0000/executors/default/runs/latest' 3: I1115 10:31:07.125114 19950 slave.cpp:958] Successfully attached '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/slaves/9553a2d0-9d07-4ff8-84b1-6345da513028-S0/frameworks/9553a2d0-9d07-4ff8-84b1-6345da513028-0000/executors/default/runs/2adc9a68-9ad1-4f69-a1d4-da6c38f6c1c3' to virtual path '/tmp/SlaveTest_HTTPExecutorBadAuthentication_Ne7efd/slaves/9553a2d0-9d07-4ff8-84b1-6345da513028-S0/frameworks/9553a2d0-9d07-4ff8-84b1-6345da513028-0000/executors/default/runs/2adc9a68-9ad1-4f69-a1d4-da6c38f6c1c3' 3: I1115 10:31:07.126132 19951 slave.cpp:2930] Launching container 2adc9a68-9ad1-4f69-a1d4-da6c38f6c1c3 for executor 'default' of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.129679 19960 executor.cpp:192] Version: 1.5.0 3: W1115 10:31:07.129756 19960 process.cpp:3178] Attempted to spawn already running process version@172.17.0.2:35204 3: I1115 10:31:07.132436 19954 executor.cpp:410] Connected with the agent 3: I1115 10:31:07.133538 19965 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.2:35204/(196)/api/v1/executor 3: I1115 10:31:07.134575 19962 process.cpp:3925] Handling HTTP event for process '(196)' with path: '/(196)/api/v1/executor' 3: I1115 10:31:07.138792 19954 executor.cpp:723] Enqueuing locally injected event ERROR 3: I1115 10:31:07.141506 19962 process.cpp:3925] Handling HTTP event for process '(196)' with path: '/(196)/api/v1' 3: I1115 10:31:07.145025 19948 slave.cpp:883] Agent terminating 3: I1115 10:31:07.145094 19948 slave.cpp:3267] Asked to shut down framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 by @0.0.0.0:0 3: I1115 10:31:07.145125 19948 slave.cpp:3292] Shutting down framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.145174 19948 slave.cpp:5874] Shutting down executor 'default' of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: W1115 10:31:07.145193 19948 slave.hpp:781] Unable to send event to executor 'default' of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000: unknown connection type 3: I1115 10:31:07.145483 19950 master.cpp:1309] Agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) disconnected 3: I1115 10:31:07.145511 19950 master.cpp:3368] Disconnecting agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.145581 19950 master.cpp:3387] Deactivating agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.145718 19963 hierarchical.cpp:690] Agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 deactivated 3: I1115 10:31:07.145758 19950 master.cpp:1333] Removing framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) from disconnected agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) because the framework is not checkpointing 3: I1115 10:31:07.145792 19950 master.cpp:8973] Removing framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) from agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.145933 19950 master.cpp:9502] Updating the state of task 9d2a31cc-01fa-4553-b859-a655f5f1823d of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (latest state: TASK_LOST, status update state: TASK_LOST) 3: I1115 10:31:07.146570 19950 master.cpp:9596] Removing task 9d2a31cc-01fa-4553-b859-a655f5f1823d with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 on agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.147004 19950 master.cpp:7304] Sending status update TASK_LOST for task 9d2a31cc-01fa-4553-b859-a655f5f1823d of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 'Agent 894218ea17d1 disconnected' 3: W1115 10:31:07.147251 19950 master.hpp:2606] Unable to send event to framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default): connection closed 3: I1115 10:31:07.147233 19967 hierarchical.cpp:1125] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32) on agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 from framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.147406 19950 master.cpp:9625] Removing executor 'default' with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":32.0},"type":"SCALAR"}] of framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 on agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 at (196)@172.17.0.2:35204 (894218ea17d1) 3: I1115 10:31:07.148244 19950 master.cpp:1423] Framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) disconnected 3: I1115 10:31:07.148267 19950 master.cpp:3331] Deactivating framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) 3: I1115 10:31:07.148317 19950 master.cpp:3308] Disconnecting framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) 3: I1115 10:31:07.148347 19950 master.cpp:1438] Giving framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) 0ns to failover 3: I1115 10:31:07.148613 19952 master.cpp:7947] Framework failover timeout, removing framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) 3: I1115 10:31:07.148640 19952 master.cpp:8804] Removing framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 (default) 3: I1115 10:31:07.148766 19954 hierarchical.cpp:1125] Recovered cpus(allocated: *):0.1; mem(allocated: *):32; disk(allocated: *):32 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 from framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.148939 19954 hierarchical.cpp:412] Deactivated framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.149292 19954 hierarchical.cpp:355] Removed framework 9553a2d0-9d07-4ff8-84b1-6345da513028-0000 3: I1115 10:31:07.153365 19948 master.cpp:1151] Master terminating 3: I1115 10:31:07.154178 19958 hierarchical.cpp:626] Removed agent 9553a2d0-9d07-4ff8-84b1-6345da513028-S0 3: [ OK ] SlaveTest.HTTPExecutorBadAuthentication (106 ms) 3: [ RUN ] SlaveTest.StatisticsEndpointNoExecutor 3: I1115 10:31:07.160974 19948 cluster.cpp:162] Creating default 'local' authorizer 3: I1115 10:31:07.164083 19965 master.cpp:448] Master 3303125b-d87a-4ba6-9e93-3b8d18aa6990 (894218ea17d1) started on 172.17.0.2:35204 3: I1115 10:31:07.164113 19965 master.cpp:450] 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/MhpeXl/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="/usr/local/share/mesos/webui" --work_dir="/tmp/MhpeXl/master" --zk_session_timeout="10secs" 3: I1115 10:31:07.164610 19965 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1115 10:31:07.164629 19965 master.cpp:505] Master only allowing authenticated agents to register 3: I1115 10:31:07.164638 19965 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1115 10:31:07.164646 19965 credentials.hpp:37] Loading credentials for authentication from '/tmp/MhpeXl/credentials' 3: I1115 10:31:07.164984 19965 master.cpp:555] Using default 'crammd5' authenticator 3: I1115 10:31:07.165148 19965 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1115 10:31:07.165340 19965 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1115 10:31:07.165515 19965 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1115 10:31:07.165650 19965 master.cpp:634] Authorization enabled 3: I1115 10:31:07.165833 19953 hierarchical.cpp:171] Initialized hierarchical allocator process 3: I1115 10:31:07.165845 19963 whitelist_watcher.cpp:77] No whitelist given 3: I1115 10:31:07.168864 19964 master.cpp:2213] Elected as the leading master! 3: I1115 10:31:07.168893 19964 master.cpp:1693] Recovering from registrar 3: I1115 10:31:07.169052 19950 registrar.cpp:347] Recovering registrar 3: I1115 10:31:07.169662 19950 registrar.cpp:391] Successfully fetched the registry (0B) in 570112ns 3: I1115 10:31:07.169777 19950 registrar.cpp:495] Applied 1 operations in 31516ns; attempting to update the registry 3: I1115 10:31:07.170303 19950 registrar.cpp:552] Successfully updated the registry in 474880ns 3: I1115 10:31:07.170418 19950 registrar.cpp:424] Successfully recovered registrar 3: I1115 10:31:07.170768 19956 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1115 10:31:07.170786 19962 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1115 10:31:07.175281 19948 process.cpp:3178] Attempted to spawn already running process files@172.17.0.2:35204 3: I1115 10:31:07.176218 19948 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1115 10:31:07.176677 19948 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges 3: W1115 10:31:07.176795 19948 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges 3: I1115 10:31:07.176826 19948 provisioner.cpp:255] Using default backend 'copy' 3: I1115 10:31:07.178750 19948 cluster.cpp:448] Creating default 'local' authorizer 3: I1115 10:31:07.180860 19956 slave.cpp:262] Mesos agent started on (174)@172.17.0.2:35204 3: I1115 10:31:07.180881 19956 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq/store/appc" --authenticate_http_executors="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq/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/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq/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_secret_key="/tmp/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq/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/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq" --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/SlaveTest_StatisticsEndpointNoExecutor_04l6hT" --zk_session_timeout="10secs" 3: I1115 10:31:07.181495 19956 credentials.hpp:86] Loading credential for authentication from '/tmp/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq/credential' 3: I1115 10:31:07.181691 19956 slave.cpp:295] Agent using credential for: test-principal 3: I1115 10:31:07.181713 19956 credentials.hpp:37] Loading credentials for authentication from '/tmp/SlaveTest_StatisticsEndpointNoExecutor_kqcZCq/http_credentials' 3: I1115 10:31:07.182016 19956 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor' 3: I1115 10:31:07.182138 19956 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor' 3: I1115 10:31:07.182339 19956 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1115 10:31:07.182551 19956 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1115 10:31:07.182806 19956 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1115 10:31:07.182925 19956 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1115 10:31:07.184547 19956 slave.cpp:593] 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"}] 3: I1115 10:31:07.184789 19956 slave.cpp:601] Agent attributes: [ ] 3: I1115 10:31:07.184800 19956 slave.cpp:610] Agent hostname: 894218ea17d1 3: I1115 10:31:07.184926 19968 status_update_manager.cpp:177] Pausing sending status updates 3: I1115 10:31:07.186601 19962 state.cpp:64] Recovering state from '/tmp/SlaveTest_StatisticsEndpointNoExecutor_04l6hT/meta' 3: I1115 10:31:07.186626 19956 process.cpp:3925] Handling HTTP event for process 'slave(174)' with path: '/slave(174)//monitor/statistics' 3: I1115 10:31:07.187237 19961 status_update_manager.cpp:203] Recovering status update manager 3: I1115 10:31:07.187561 19964 containerizer.cpp:662] Recovering containerizer 3: I1115 10:31:07.188532 19949 http.cpp:1185] HTTP GET for /slave(174)//monitor/statistics from 172.17.0.2:59648 3: I1115 10:31:07.188639 19949 http.cpp:976] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint 3: I1115 10:31:07.188922 19962 provisioner.cpp:416] Provisioner recovery complete 3: I1115 10:31:07.190637 19963 slave.cpp:6441] Finished recovery 3: I1115 10:31:07.191566 19954 status_update_manager.cpp:177] Pausing sending status updates 3: I1115 10:31:07.191586 19963 slave.cpp:1007] New master detected at master@172.17.0.2:35204 3: I1115 10:31:07.191655 19963 slave.cpp:1042] Detecting new master 3: I1115 10:31:07.193069 19972 slave.cpp:883] Agent terminating 3: I1115 10:31:07.199347 19948 master.cpp:1151] Master terminating 3: [ OK ] SlaveTest.StatisticsEndpointNoExecutor (45 ms) 3: [ RUN ] SlaveTest.StatisticsEndpointMissingStatistics 3: I1115 10:31:07.206436 19948 cluster.cpp:162] Creating default 'local' authorizer 3: I1115 10:31:07.209108 19962 master.cpp:448] Master 976a71db-1c74-4d15-8422-2af460bead02 (894218ea17d1) started on 172.17.0.2:35204 3: I1115 10:31:07.209130 19962 master.cpp:450] 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/chRFpv/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="/usr/local/share/mesos/webui" --work_dir="/tmp/chRFpv/master" --zk_session_timeout="10secs" 3: I1115 10:31:07.209434 19962 master.cpp:499] Master only allowing authenticated frameworks to register 3: I1115 10:31:07.209440 19962 master.cpp:505] Master only allowing authenticated agents to register 3: I1115 10:31:07.209444 19962 master.cpp:511] Master only allowing authenticated HTTP frameworks to register 3: I1115 10:31:07.209450 19962 credentials.hpp:37] Loading credentials for authentication from '/tmp/chRFpv/credentials' 3: I1115 10:31:07.209736 19962 master.cpp:555] Using default 'crammd5' authenticator 3: I1115 10:31:07.209884 19962 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1115 10:31:07.210038 19962 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1115 10:31:07.210181 19962 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1115 10:31:07.210315 19962 master.cpp:634] Authorization enabled 3: I1115 10:31:07.210459 19963 hierarchical.cpp:171] Initialized hierarchical allocator process 3: I1115 10:31:07.210484 19952 whitelist_watcher.cpp:77] No whitelist given 3: I1115 10:31:07.213109 19972 master.cpp:2213] Elected as the leading master! 3: I1115 10:31:07.213132 19972 master.cpp:1693] Recovering from registrar 3: I1115 10:31:07.213253 19959 registrar.cpp:347] Recovering registrar 3: I1115 10:31:07.213829 19959 registrar.cpp:391] Successfully fetched the registry (0B) in 535808ns 3: I1115 10:31:07.213940 19959 registrar.cpp:495] Applied 1 operations in 30504ns; attempting to update the registry 3: I1115 10:31:07.214478 19959 registrar.cpp:552] Successfully updated the registry in 485120ns 3: I1115 10:31:07.214594 19959 registrar.cpp:424] Successfully recovered registrar 3: I1115 10:31:07.214941 19949 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1115 10:31:07.214967 19955 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1115 10:31:07.219774 19948 process.cpp:3178] Attempted to spawn already running process files@172.17.0.2:35204 3: I1115 10:31:07.220563 19966 slave.cpp:262] Mesos agent started on @172.17.0.2:35204 3: W1115 10:31:07.220901 19948 process.cpp:3178] Attempted to spawn already running process version@172.17.0.2:35204 write /dev/stdout: resource temporarily unavailable + docker rmi mesos-1510739246-1078 Error response from daemon: conflict: unable to remove repository reference "mesos-1510739246-1078" (must force) - container 894218ea17d1 is using its referenced image 435e8b8efb10 Build step 'Execute shell' marked build as failure
