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/05/23 20:54:26 UTC

Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3713

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3713/display/redirect?page=changes>

Changes:

[neil.conway] Avoid validation of agent re-registration message if possible.

[neil.conway] Added MESOS-7538 to 1.3.0 CHANGELOG.

------------------------------------------
[...truncated 10.94 MB...]
I0523 20:54:20.346149 31861 sched.cpp:1203] Stopping framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000
I0523 20:54:20.346374 31848 master.cpp:7988] Processing TEARDOWN call for framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 (default) at scheduler-3d631f1a-be60-4b00-a444-81161ac2b766@172.17.0.3:35270
I0523 20:54:20.346420 31848 master.cpp:8000] Removing framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 (default) at scheduler-3d631f1a-be60-4b00-a444-81161ac2b766@172.17.0.3:35270
I0523 20:54:20.346452 31848 master.cpp:3160] Deactivating framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 (default) at scheduler-3d631f1a-be60-4b00-a444-81161ac2b766@172.17.0.3:35270
I0523 20:54:20.346609 31860 hierarchical.cpp:374] Deactivated framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000
I0523 20:54:20.346729 31860 slave.cpp:3057] Asked to shut down framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 by master@172.17.0.3:35270
I0523 20:54:20.346750 31848 master.cpp:8568] Updating the state of task 1 of framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0523 20:54:20.346771 31860 slave.cpp:3082] Shutting down framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000
I0523 20:54:20.346814 31860 slave.cpp:5482] Shutting down executor 'default' of framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 at executor(74)@172.17.0.3:35270
I0523 20:54:20.347437 31850 exec.cpp:415] Executor asked to shutdown
I0523 20:54:20.347487 31850 exec.cpp:430] Executor::shutdown took 22553ns
I0523 20:54:20.347856 31846 slave.cpp:4787] Got exited event for executor(74)@172.17.0.3:35270
I0523 20:54:20.347786 31848 master.cpp:8662] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 on agent fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0 at slave(194)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.348073 31853 hierarchical.cpp:1114] 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 fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0 from framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000
I0523 20:54:20.348191 31864 slave.cpp:5168] Executor 'default' of framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 exited with status 0
I0523 20:54:20.348266 31848 master.cpp:8691] Removing executor 'default' with resources {} of framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 on agent fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0 at slave(194)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.348278 31864 slave.cpp:5268] Cleaning up executor 'default' of framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 at executor(74)@172.17.0.3:35270
I0523 20:54:20.348520 31856 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_ELDHg5/slaves/fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0/frameworks/fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000/executors/default/runs/4ca24ea3-ca39-40a0-9043-289383cd59fc' for gc 6.99999596717333days in the future
I0523 20:54:20.348670 31864 slave.cpp:5356] Cleaning up framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000
I0523 20:54:20.348882 31847 status_update_manager.cpp:285] Closing status update streams for framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000
I0523 20:54:20.348903 31856 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_ELDHg5/slaves/fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0/frameworks/fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000/executors/default' for gc 6.99999596514963days in the future
I0523 20:54:20.348925 31847 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000
I0523 20:54:20.348930 31864 slave.cpp:790] Agent terminating
I0523 20:54:20.349035 31856 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_ELDHg5/slaves/fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0/frameworks/fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000' for gc 6.99999596296296days in the future
W0523 20:54:20.349144 31848 master.cpp:6592] Ignoring unknown exited executor 'default' of framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000 on agent fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0 at slave(194)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.349424 31856 master.cpp:1313] Agent fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0 at slave(194)@172.17.0.3:35270 (b5e103ba73c5) disconnected
I0523 20:54:20.349453 31856 master.cpp:3197] Disconnecting agent fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0 at slave(194)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.349511 31856 master.cpp:3216] Deactivating agent fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0 at slave(194)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.349655 31849 hierarchical.cpp:325] Removed framework fb6f9842-96e6-4b21-a221-93ea4546ad3a-0000
I0523 20:54:20.349728 31849 hierarchical.cpp:653] Agent fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0 deactivated
I0523 20:54:20.352468 31867 master.cpp:1155] Master terminating
I0523 20:54:20.353132 31859 hierarchical.cpp:558] Removed agent fb6f9842-96e6-4b21-a221-93ea4546ad3a-S0
[       OK ] MasterTest.FrameworkMessage (72 ms)
[ RUN      ] MasterTest.MultipleExecutors
I0523 20:54:20.359177 31826 cluster.cpp:162] Creating default 'local' authorizer
I0523 20:54:20.361822 31846 master.cpp:436] Master 6f8d9311-4c29-4b47-aaec-4ba60ff594e5 (b5e103ba73c5) started on 172.17.0.3:35270
I0523 20:54:20.361852 31846 master.cpp:438] 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/qu8jB8/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.3.0/_inst/share/mesos/webui" --work_dir="/tmp/qu8jB8/master" --zk_session_timeout="10secs"
I0523 20:54:20.362135 31846 master.cpp:488] Master only allowing authenticated frameworks to register
I0523 20:54:20.362149 31846 master.cpp:502] Master only allowing authenticated agents to register
I0523 20:54:20.362159 31846 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0523 20:54:20.362167 31846 credentials.hpp:37] Loading credentials for authentication from '/tmp/qu8jB8/credentials'
I0523 20:54:20.362440 31846 master.cpp:560] Using default 'crammd5' authenticator
I0523 20:54:20.362596 31846 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0523 20:54:20.362826 31846 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0523 20:54:20.363003 31846 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0523 20:54:20.363155 31846 master.cpp:640] Authorization enabled
I0523 20:54:20.363291 31853 hierarchical.cpp:158] Initialized hierarchical allocator process
I0523 20:54:20.363327 31855 whitelist_watcher.cpp:77] No whitelist given
I0523 20:54:20.365866 31865 master.cpp:2161] Elected as the leading master!
I0523 20:54:20.365896 31865 master.cpp:1700] Recovering from registrar
I0523 20:54:20.366021 31848 registrar.cpp:345] Recovering registrar
I0523 20:54:20.366780 31848 registrar.cpp:389] Successfully fetched the registry (0B) in 719872ns
I0523 20:54:20.366890 31848 registrar.cpp:493] Applied 1 operations in 22782ns; attempting to update the registry
I0523 20:54:20.367483 31848 registrar.cpp:550] Successfully updated the registry in 530176ns
I0523 20:54:20.367599 31848 registrar.cpp:422] Successfully recovered registrar
I0523 20:54:20.368173 31868 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0523 20:54:20.368175 31845 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0523 20:54:20.372581 31826 cluster.cpp:448] Creating default 'local' authorizer
I0523 20:54:20.374433 31848 slave.cpp:225] Mesos agent started on (195)@172.17.0.3:35270
I0523 20:54:20.374466 31848 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterTest_MultipleExecutors_0mfXuF/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_0mfXuF/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/MasterTest_MultipleExecutors_0mfXuF/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --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/MasterTest_MultipleExecutors_0mfXuF" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/MasterTest_MultipleExecutors_MFg82o"
I0523 20:54:20.374941 31848 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_0mfXuF/credential'
I0523 20:54:20.375170 31848 slave.cpp:258] Agent using credential for: test-principal
I0523 20:54:20.375198 31848 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_0mfXuF/http_credentials'
I0523 20:54:20.375478 31848 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 20:54:20.375660 31848 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 20:54:20.376178 31826 sched.cpp:232] Version: 1.3.0
I0523 20:54:20.376771 31849 sched.cpp:336] New master detected at master@172.17.0.3:35270
I0523 20:54:20.376873 31849 sched.cpp:407] Authenticating with master master@172.17.0.3:35270
I0523 20:54:20.376893 31849 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0523 20:54:20.377135 31866 authenticatee.cpp:121] Creating new client SASL connection
I0523 20:54:20.377395 31862 master.cpp:7475] Authenticating scheduler-62a98d3a-4792-46cc-aa0b-e257ddbc9ccf@172.17.0.3:35270
I0523 20:54:20.377532 31867 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(425)@172.17.0.3:35270
I0523 20:54:20.377511 31848 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 20:54:20.377609 31848 slave.cpp:533] Agent attributes: [  ]
I0523 20:54:20.377624 31848 slave.cpp:538] Agent hostname: b5e103ba73c5
I0523 20:54:20.377753 31858 authenticator.cpp:98] Creating new server SASL connection
I0523 20:54:20.377990 31856 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0523 20:54:20.378018 31856 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0523 20:54:20.378129 31860 authenticator.cpp:204] Received SASL authentication start
I0523 20:54:20.378203 31860 authenticator.cpp:326] Authentication requires more steps
I0523 20:54:20.378321 31855 authenticatee.cpp:259] Received SASL authentication step
I0523 20:54:20.378492 31868 authenticator.cpp:232] Received SASL authentication step
I0523 20:54:20.378528 31868 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b5e103ba73c5' server FQDN: 'b5e103ba73c5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0523 20:54:20.378545 31868 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0523 20:54:20.378582 31868 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0523 20:54:20.378605 31868 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b5e103ba73c5' server FQDN: 'b5e103ba73c5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0523 20:54:20.378623 31868 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0523 20:54:20.378641 31868 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0523 20:54:20.378664 31868 authenticator.cpp:318] Authentication success
I0523 20:54:20.378689 31853 status_update_manager.cpp:177] Pausing sending status updates
I0523 20:54:20.378782 31863 authenticatee.cpp:299] Authentication success
I0523 20:54:20.378829 31853 master.cpp:7505] Successfully authenticated principal 'test-principal' at scheduler-62a98d3a-4792-46cc-aa0b-e257ddbc9ccf@172.17.0.3:35270
I0523 20:54:20.378883 31847 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(425)@172.17.0.3:35270
I0523 20:54:20.379137 31864 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:35270
I0523 20:54:20.379173 31864 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:35270
I0523 20:54:20.379302 31864 sched.cpp:869] Will retry registration in 1.50520812secs if necessary
I0523 20:54:20.379436 31865 master.cpp:2813] Received SUBSCRIBE call for framework 'default' at scheduler-62a98d3a-4792-46cc-aa0b-e257ddbc9ccf@172.17.0.3:35270
I0523 20:54:20.379510 31865 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0523 20:54:20.379914 31855 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_MFg82o/meta'
I0523 20:54:20.379951 31859 master.cpp:2890] Subscribing framework default with checkpointing disabled and capabilities [  ]
I0523 20:54:20.380343 31852 status_update_manager.cpp:203] Recovering status update manager
I0523 20:54:20.380595 31853 sched.cpp:759] Framework registered with 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.380651 31853 sched.cpp:773] Scheduler::registered took 28364ns
I0523 20:54:20.380815 31847 slave.cpp:5970] Finished recovery
I0523 20:54:20.381016 31863 hierarchical.cpp:273] Added framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.381168 31863 hierarchical.cpp:1850] No allocations performed
I0523 20:54:20.381212 31863 hierarchical.cpp:1940] No inverse offers to send out!
I0523 20:54:20.381268 31863 hierarchical.cpp:1434] Performed allocation for 0 agents in 147503ns
I0523 20:54:20.381276 31847 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0523 20:54:20.381539 31847 slave.cpp:918] New master detected at master@172.17.0.3:35270
I0523 20:54:20.381544 31862 status_update_manager.cpp:177] Pausing sending status updates
I0523 20:54:20.381639 31847 slave.cpp:953] Detecting new master
I0523 20:54:20.381791 31847 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0523 20:54:20.386914 31846 slave.cpp:980] Authenticating with master master@172.17.0.3:35270
I0523 20:54:20.387030 31846 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0523 20:54:20.387306 31859 authenticatee.cpp:121] Creating new client SASL connection
I0523 20:54:20.387547 31856 master.cpp:7475] Authenticating slave(195)@172.17.0.3:35270
I0523 20:54:20.387662 31854 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(426)@172.17.0.3:35270
I0523 20:54:20.387861 31850 authenticator.cpp:98] Creating new server SASL connection
I0523 20:54:20.388062 31866 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0523 20:54:20.388090 31866 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0523 20:54:20.388206 31861 authenticator.cpp:204] Received SASL authentication start
I0523 20:54:20.388269 31861 authenticator.cpp:326] Authentication requires more steps
I0523 20:54:20.388392 31868 authenticatee.cpp:259] Received SASL authentication step
I0523 20:54:20.388522 31856 authenticator.cpp:232] Received SASL authentication step
I0523 20:54:20.388556 31856 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b5e103ba73c5' server FQDN: 'b5e103ba73c5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0523 20:54:20.388572 31856 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0523 20:54:20.388598 31856 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0523 20:54:20.388630 31856 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'b5e103ba73c5' server FQDN: 'b5e103ba73c5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0523 20:54:20.388649 31856 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0523 20:54:20.388665 31856 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0523 20:54:20.388689 31856 authenticator.cpp:318] Authentication success
I0523 20:54:20.388780 31860 authenticatee.cpp:299] Authentication success
I0523 20:54:20.388818 31855 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(195)@172.17.0.3:35270
I0523 20:54:20.388875 31867 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(426)@172.17.0.3:35270
I0523 20:54:20.389386 31862 slave.cpp:1075] Successfully authenticated with master master@172.17.0.3:35270
I0523 20:54:20.389612 31862 slave.cpp:1503] Will retry registration in 15.074893ms if necessary
I0523 20:54:20.389799 31863 master.cpp:5429] Received register agent message from slave(195)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.389951 31863 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0523 20:54:20.390512 31847 master.cpp:5564] Registering agent at slave(195)@172.17.0.3:35270 (b5e103ba73c5) with id 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0
I0523 20:54:20.390941 31868 registrar.cpp:493] Applied 1 operations in 60958ns; attempting to update the registry
I0523 20:54:20.391561 31868 registrar.cpp:550] Successfully updated the registry in 560896ns
I0523 20:54:20.392230 31868 slave.cpp:4745] Received ping from slave-observer(190)@172.17.0.3:35270
I0523 20:54:20.392452 31861 slave.cpp:1121] Registered with master master@172.17.0.3:35270; given agent ID 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0
I0523 20:54:20.392383 31857 master.cpp:5639] Registered agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 at slave(195)@172.17.0.3:35270 (b5e103ba73c5) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 20:54:20.392490 31861 fetcher.cpp:94] Clearing fetcher cache
I0523 20:54:20.392745 31860 status_update_manager.cpp:184] Resuming sending status updates
I0523 20:54:20.392890 31864 hierarchical.cpp:525] Added agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 (b5e103ba73c5) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0523 20:54:20.393021 31861 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_MFg82o/meta/slaves/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0/slave.info'
I0523 20:54:20.393519 31861 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0523 20:54:20.393743 31863 master.cpp:6324] Received update of agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 at slave(195)@172.17.0.3:35270 (b5e103ba73c5) with total oversubscribed resources {}
I0523 20:54:20.394282 31864 hierarchical.cpp:1940] No inverse offers to send out!
I0523 20:54:20.394340 31864 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.317962ms
I0523 20:54:20.394809 31855 master.cpp:7305] Sending 1 offers to framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000 (default) at scheduler-62a98d3a-4792-46cc-aa0b-e257ddbc9ccf@172.17.0.3:35270
I0523 20:54:20.395294 31850 sched.cpp:933] Scheduler::resourceOffers took 129334ns
I0523 20:54:20.397603 31856 master.cpp:3875] Processing ACCEPT call for offers: [ 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-O0 ] on agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 at slave(195)@172.17.0.3:35270 (b5e103ba73c5) for framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000 (default) at scheduler-62a98d3a-4792-46cc-aa0b-e257ddbc9ccf@172.17.0.3:35270
I0523 20:54:20.397735 31856 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 1
I0523 20:54:20.398026 31856 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 2
W0523 20:54:20.400199 31865 validation.cpp:1284] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0523 20:54:20.400244 31865 validation.cpp:1296] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0523 20:54:20.400643 31865 master.cpp:9320] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 at slave(195)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.400840 31865 master.cpp:4531] Launching task 1 of framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000 (default) at scheduler-62a98d3a-4792-46cc-aa0b-e257ddbc9ccf@172.17.0.3:35270 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 at slave(195)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.401346 31862 slave.cpp:1613] Got assigned task '1' for framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
W0523 20:54:20.401973 31865 validation.cpp:1284] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0523 20:54:20.402005 31865 validation.cpp:1296] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0523 20:54:20.402089 31862 slave.cpp:1894] Authorizing task '1' for framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.402204 31862 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 1
I0523 20:54:20.402362 31865 master.cpp:9320] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 at slave(195)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.402606 31865 master.cpp:4531] Launching task 2 of framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000 (default) at scheduler-62a98d3a-4792-46cc-aa0b-e257ddbc9ccf@172.17.0.3:35270 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 at slave(195)@172.17.0.3:35270 (b5e103ba73c5)
I0523 20:54:20.403190 31862 slave.cpp:1613] Got assigned task '2' for framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.403780 31862 slave.cpp:2081] Launching task '1' for framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.404430 31862 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_MFg82o/slaves/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0/frameworks/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000/executors/executor-1/runs/845a59d5-db3c-4880-8e84-e645c972fb4d' to user 'mesos'
I0523 20:54:20.404811 31862 slave.cpp:6933] Launching executor 'executor-1' of framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_MFg82o/slaves/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0/frameworks/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000/executors/executor-1/runs/845a59d5-db3c-4880-8e84-e645c972fb4d'
I0523 20:54:20.405577 31862 slave.cpp:2310] Queued task '1' for executor 'executor-1' of framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.405591 31863 hierarchical.cpp:850] Updated allocation of framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000 on agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-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]
I0523 20:54:20.405735 31862 slave.cpp:1894] Authorizing task '2' for framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.405805 31862 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 2
I0523 20:54:20.406323 31862 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_MFg82o/slaves/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0/frameworks/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000/executors/executor-1/runs/845a59d5-db3c-4880-8e84-e645c972fb4d'
I0523 20:54:20.406306 31863 hierarchical.cpp:1114] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 from framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.406384 31863 hierarchical.cpp:1151] Framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000 filtered agent 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0 for 5secs
I0523 20:54:20.406960 31855 slave.cpp:2081] Launching task '2' for framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000
I0523 20:54:20.407455 31855 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_MFg82o/slaves/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0/frameworks/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000/executors/executor-2/runs/9d42a76a-5d52-4209-9afa-6ec4580eaa27' to user 'mesos'
I0523 20:54:20.407793 31855 slave.cpp:6933] Launching executor 'executor-2' of framework 6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_MFg82o/slaves/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-S0/frameworks/6f8d9311-4c29-4b47-aaec-4ba60ff594e5-0000/executors/executor-2/runs/9d42a76a-5d52-4209-9afa-6ec4580eaa27'
*** Aborted at 1495572860 (unix time) try "date -d @1495572860" if you are using GNU date ***
I0523 20:54:20.408478 31866 exec.cpp:162] Version: 1.3.0
I0523 20:54:20.408815 31856 exec.cpp:212] Executor started at: executor(75)@172.17.0.3:35270 with pid 31826
PC: @     0x7f683accd0cd __GI_getenv
*** SIGSEGV (@0x100) received by PID 31826 (TID 0x7f682ec93700) from PID 256; stack trace: ***
    @     0x7f6825bbdfc5 (unknown)
    @     0x7f6825bc32c1 (unknown)
    @     0x7f6825bb70a8 (unknown)
    @     0x7f683bc92370 (unknown)
    @     0x7f683accd0cd __GI_getenv
    @     0x55ae474045d9 os::getenv()
    @     0x7f6840e2f5e0 mesos::internal::slave::executorEnvironment()
    @     0x7f6840e064b2 mesos::internal::slave::Slave::launchExecutor()
    @     0x7f6840e01d86 mesos::internal::slave::Slave::__run()
    @     0x7f6840e63a8e _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureISt4listIbSaIbEEEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSI_INS1_13TaskGroupInfoEES9_SC_SF_SK_SO_EEvRKNS_3PIDIT_EEMSS_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES19_
    @     0x7f6840ea08f5 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureISt4listIbSaIbEEEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSM_INS5_13TaskGroupInfoEESD_SG_SJ_SO_SS_EEvRKNS0_3PIDIT_EEMSW_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
    @     0x7f6841cc8337 std::function<>::operator()()
    @     0x7f6841cac24b process::ProcessBase::visit()
    @     0x7f6841cb760a process::DispatchEvent::visit()
    @     0x55ae473d5320 process::ProcessBase::serve()
    @     0x7f6841ca82bd process::ProcessManager::resume()
    @     0x7f6841ca4a68 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
    @     0x7f6841cb6c72 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
    @     0x7f6841cb6bb6 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
    @     0x7f6841cb6b40 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
    @     0x7f683b623230 (unknown)
    @     0x7f683bc8adc5 start_thread
    @     0x7f683ad8c73d __clone
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1495570836-3624
Untagged: mesos-1495570836-3624:latest
Deleted: sha256:a900efe5aefe97e88bc658899897ba36a033567e4803b07dc77c71264be5abfa
Deleted: sha256:601935aed41a42f9f083d2cb156020d64866a56e85b1005db39cbf23cae578e4
Deleted: sha256:bc340b60e5220c1c687736188b31d2f1628bb7df76b55ec5f1d89b3c38d9f454
Deleted: sha256:7e1179cf04688a419ebd8771ad1122595380bd9fd251afd3875d634980a96529
Deleted: sha256:ab59d9b0d4509b88adae1de2c57abb52bc7c50ad3a9165e876032bb5e83c3db8
Deleted: sha256:93a0fb499a5c8043aaa9085c1ad1094eea31f3de774dfea603626712a1d34711
Deleted: sha256:b396c9178d78c910f79170d97206cd5787c3c654be6bcfd31974d55d0bc7e19b
Deleted: sha256:66b5fc0c807a907f3c19b6e54fd9ed09bcf0647f7ef87cea961b61c40e53f477
Deleted: sha256:9b46ed2d3f0aaf93cca1e35088b1868a0cb6de2f94e021ec3f2000eed487304b
Deleted: sha256:4b78f5561b2e9e484667e1785eebbaf984ca51a1df5209c210a63ed80a062f0b
Deleted: sha256:1a6f6f4f116f3b95515edb699466a138ddd775fc461b752d83c5f7d700ef1086
Deleted: sha256:3f86c9348434db4797d0300ef932bf9b676af231d7b282194d0683d0e7868d1e
Deleted: sha256:496b1cb2dcdaad099991c088fa315b2caa8cecd0462e2120b6c45ba7e393d3d0
Deleted: sha256:edf595f1d3c0a7864bb9f121e90971ceb3ce48bd15e2397d5fc529a676d41be8
Deleted: sha256:f7a28f686a32d086f723feac70e6490e40d3966d9a5d022faebf52450b7b7fd3
Deleted: sha256:684ddb8d4ded76f6ae32adf61abeddadf4c106add374187f1ebf5118b7172bb7
Deleted: sha256:c29f2b7c3368928fb7625aeecdcfb9fe3d177c979d20292cc9e7bd01f1cfa6f5
Deleted: sha256:f2955e10efe520cd84e23ca40e7cb9b797cf0403f945f10686095e84459a1da3
Deleted: sha256:5cc39149b78f173ccdeb07fe2740aeccabeccb44e62df39a93f8aab5e272e09e
Deleted: sha256:0be74629b7dce2ba35b8c94cecc8270bd018c78800e7c2f59d87e228586548ae
Deleted: sha256:9af0cf4a12b0c546bfb6bbc139ba6715086f5845c517a6fbb66802f16187a6f5
Deleted: sha256:97a5efececf0f87a95f7da5951179832e5d903e479316712e3f6d8dbc98474af
Deleted: sha256:1b4e44a958a96fad30ca8370653bc43f093668a12555711e8a0fc731f2d60c2d
Deleted: sha256:bf5378094fed30e44ac27446e146edf1587661873a8c3e993f65c54750e37037
Deleted: sha256:67bb3fcdeb1be432ab777887f4ac8bb5a4578b8cff8a0ac00d0d0ee90233a339
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com

Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3715

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=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3715/display/redirect?page=changes>


Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3714

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=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3714/display/redirect?page=changes>

Changes:

[neil.conway] Added MESOS-7538 to 1.3.0 CHANGELOG.

------------------------------------------
[...truncated 27.17 MB...]
W0523 23:17:37.846760 32146 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0523 23:17:37.846807 32146 provisioner.cpp:249] Using default backend 'copy'
I0523 23:17:37.851354 32187 slave.cpp:225] Mesos agent started on (744)@172.17.0.2:43089
I0523 23:17:37.851377 32187 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_cfeV7A/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_cfeV7A/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_cfeV7A/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_cfeV7A" --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_uz5qN3"
I0523 23:17:37.851828 32187 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_cfeV7A/credential'
I0523 23:17:37.852025 32187 slave.cpp:258] Agent using credential for: test-principal
I0523 23:17:37.852047 32187 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_cfeV7A/http_credentials'
I0523 23:17:37.852315 32187 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 23:17:37.852499 32187 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 23:17:37.854043 32187 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 23:17:37.854126 32187 slave.cpp:537] Agent attributes: [  ]
I0523 23:17:37.854141 32187 slave.cpp:542] Agent hostname: 78b7e28fbdbf
I0523 23:17:37.854300 32182 status_update_manager.cpp:177] Pausing sending status updates
I0523 23:17:37.856119 32185 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_AuthorizedRequest_2_uz5qN3/meta'
I0523 23:17:37.856547 32175 status_update_manager.cpp:203] Recovering status update manager
I0523 23:17:37.856747 32167 containerizer.cpp:627] Recovering containerizer
I0523 23:17:37.858505 32186 provisioner.cpp:410] Provisioner recovery complete
I0523 23:17:37.858968 32166 slave.cpp:5974] Finished recovery
I0523 23:17:37.859515 32166 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 23:17:37.859834 32174 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 23:17:37.862901 32181 process.cpp:3730] Handling HTTP event for process 'slave(744)' with path: '/slave(744)/containers'
I0523 23:17:37.864388 32166 http.cpp:1115] HTTP GET for /slave(744)/containers from 172.17.0.2:53518
I0523 23:17:37.864467 32166 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0523 23:17:37.868981 32182 slave.cpp:794] Agent terminating
[       OK ] Endpoint/SlaveEndpointTest.AuthorizedRequest/2 (34 ms)
[ RUN      ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0
I0523 23:17:37.880285 32146 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0523 23:17:37.880903 32146 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0523 23:17:37.881029 32146 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0523 23:17:37.881078 32146 provisioner.cpp:249] Using default backend 'copy'
I0523 23:17:37.884696 32169 slave.cpp:225] Mesos agent started on (745)@172.17.0.2:43089
I0523 23:17:37.884717 32169 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_C9WfJK/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_C9WfJK/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_C9WfJK/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_C9WfJK" --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_4wPDjd"
I0523 23:17:37.885120 32169 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_C9WfJK/credential'
I0523 23:17:37.885296 32169 slave.cpp:258] Agent using credential for: test-principal
I0523 23:17:37.885318 32169 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_C9WfJK/http_credentials'
I0523 23:17:37.885562 32169 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 23:17:37.885738 32169 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 23:17:37.887367 32169 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 23:17:37.887460 32169 slave.cpp:537] Agent attributes: [  ]
I0523 23:17:37.887475 32169 slave.cpp:542] Agent hostname: 78b7e28fbdbf
I0523 23:17:37.887619 32174 status_update_manager.cpp:177] Pausing sending status updates
I0523 23:17:37.889251 32180 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_0_4wPDjd/meta'
I0523 23:17:37.889544 32175 status_update_manager.cpp:203] Recovering status update manager
I0523 23:17:37.889945 32170 containerizer.cpp:627] Recovering containerizer
I0523 23:17:37.891682 32181 provisioner.cpp:410] Provisioner recovery complete
I0523 23:17:37.892096 32185 slave.cpp:5974] Finished recovery
I0523 23:17:37.892654 32185 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 23:17:37.893049 32188 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 23:17:37.895736 32177 process.cpp:3730] Handling HTTP event for process 'slave(745)' with path: '/slave(745)/monitor/statistics'
I0523 23:17:37.896968 32165 http.cpp:1115] HTTP GET for /slave(745)/monitor/statistics from 172.17.0.2:53520
I0523 23:17:37.897032 32165 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0523 23:17:37.899420 32186 slave.cpp:794] Agent terminating
[       OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/0 (30 ms)
[ RUN      ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1
I0523 23:17:37.911002 32146 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0523 23:17:37.911550 32146 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0523 23:17:37.911664 32146 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0523 23:17:37.911700 32146 provisioner.cpp:249] Using default backend 'copy'
I0523 23:17:37.915648 32185 slave.cpp:225] Mesos agent started on (746)@172.17.0.2:43089
I0523 23:17:37.915688 32185 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_m85fDU/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_m85fDU/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_m85fDU/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_m85fDU" --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_6wr48m"
I0523 23:17:37.916121 32185 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_m85fDU/credential'
I0523 23:17:37.916360 32185 slave.cpp:258] Agent using credential for: test-principal
I0523 23:17:37.916383 32185 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_m85fDU/http_credentials'
I0523 23:17:37.916657 32185 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 23:17:37.916846 32185 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 23:17:37.918290 32185 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 23:17:37.918373 32185 slave.cpp:537] Agent attributes: [  ]
I0523 23:17:37.918387 32185 slave.cpp:542] Agent hostname: 78b7e28fbdbf
I0523 23:17:37.918519 32188 status_update_manager.cpp:177] Pausing sending status updates
I0523 23:17:37.920156 32179 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_1_6wr48m/meta'
I0523 23:17:37.920706 32178 status_update_manager.cpp:203] Recovering status update manager
I0523 23:17:37.920925 32184 containerizer.cpp:627] Recovering containerizer
I0523 23:17:37.922760 32178 provisioner.cpp:410] Provisioner recovery complete
I0523 23:17:37.923358 32167 slave.cpp:5974] Finished recovery
I0523 23:17:37.924015 32167 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 23:17:37.924335 32179 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 23:17:37.927322 32175 process.cpp:3730] Handling HTTP event for process 'slave(746)' with path: '/slave(746)/monitor/statistics.json'
I0523 23:17:37.929175 32171 http.cpp:1115] HTTP GET for /slave(746)/monitor/statistics.json from 172.17.0.2:53522
I0523 23:17:37.929260 32171 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0523 23:17:37.931710 32170 slave.cpp:794] Agent terminating
[       OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/1 (31 ms)
[ RUN      ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2
I0523 23:17:37.943611 32146 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0523 23:17:37.944178 32146 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0523 23:17:37.944288 32146 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0523 23:17:37.944326 32146 provisioner.cpp:249] Using default backend 'copy'
I0523 23:17:37.948020 32165 slave.cpp:225] Mesos agent started on (747)@172.17.0.2:43089
I0523 23:17:37.948045 32165 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_wujnQ4/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_wujnQ4/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_wujnQ4/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_wujnQ4" --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_UIgmhx"
I0523 23:17:37.948525 32165 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_wujnQ4/credential'
I0523 23:17:37.948715 32165 slave.cpp:258] Agent using credential for: test-principal
I0523 23:17:37.948750 32165 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_wujnQ4/http_credentials'
I0523 23:17:37.949028 32165 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 23:17:37.949224 32165 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 23:17:37.950754 32165 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 23:17:37.950836 32165 slave.cpp:537] Agent attributes: [  ]
I0523 23:17:37.950850 32165 slave.cpp:542] Agent hostname: 78b7e28fbdbf
I0523 23:17:37.951002 32176 status_update_manager.cpp:177] Pausing sending status updates
I0523 23:17:37.952556 32168 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_UnauthorizedRequest_2_UIgmhx/meta'
I0523 23:17:37.953003 32176 status_update_manager.cpp:203] Recovering status update manager
I0523 23:17:37.953336 32169 containerizer.cpp:627] Recovering containerizer
I0523 23:17:37.955123 32167 provisioner.cpp:410] Provisioner recovery complete
I0523 23:17:37.955513 32165 slave.cpp:5974] Finished recovery
I0523 23:17:37.956006 32165 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 23:17:37.956334 32165 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 23:17:37.958560 32174 process.cpp:3730] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/containers'
I0523 23:17:37.959796 32175 http.cpp:1115] HTTP GET for /slave(747)/containers from 172.17.0.2:53524
I0523 23:17:37.959880 32175 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0523 23:17:37.963106 32186 slave.cpp:794] Agent terminating
[       OK ] Endpoint/SlaveEndpointTest.UnauthorizedRequest/2 (30 ms)
[ RUN      ] Endpoint/SlaveEndpointTest.NoAuthorizer/0
I0523 23:17:37.973860 32146 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0523 23:17:37.974380 32146 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0523 23:17:37.974483 32146 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0523 23:17:37.974521 32146 provisioner.cpp:249] Using default backend 'copy'
I0523 23:17:37.976816 32146 cluster.cpp:448] Creating default 'local' authorizer
I0523 23:17:37.978818 32167 slave.cpp:225] Mesos agent started on (748)@172.17.0.2:43089
I0523 23:17:37.978842 32167 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_GpyRlf/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_GpyRlf/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_GpyRlf/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_GpyRlf" --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_Mf5kIH"
I0523 23:17:37.979269 32167 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_GpyRlf/credential'
I0523 23:17:37.979434 32167 slave.cpp:258] Agent using credential for: test-principal
I0523 23:17:37.979459 32167 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_GpyRlf/http_credentials'
I0523 23:17:37.979712 32167 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 23:17:37.979868 32167 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 23:17:37.981251 32167 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 23:17:37.981330 32167 slave.cpp:537] Agent attributes: [  ]
I0523 23:17:37.981343 32167 slave.cpp:542] Agent hostname: 78b7e28fbdbf
I0523 23:17:37.981478 32188 status_update_manager.cpp:177] Pausing sending status updates
I0523 23:17:37.982964 32166 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_0_Mf5kIH/meta'
I0523 23:17:37.983394 32171 status_update_manager.cpp:203] Recovering status update manager
I0523 23:17:37.983731 32183 containerizer.cpp:627] Recovering containerizer
I0523 23:17:37.985146 32184 provisioner.cpp:410] Provisioner recovery complete
I0523 23:17:37.985548 32169 slave.cpp:5974] Finished recovery
I0523 23:17:37.985978 32169 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 23:17:37.986362 32166 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 23:17:37.988600 32171 process.cpp:3730] Handling HTTP event for process 'slave(748)' with path: '/slave(748)/monitor/statistics'
I0523 23:17:37.989872 32180 http.cpp:1115] HTTP GET for /slave(748)/monitor/statistics from 172.17.0.2:53526
I0523 23:17:37.989951 32180 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics' endpoint
I0523 23:17:37.994249 32188 slave.cpp:794] Agent terminating
[       OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/0 (31 ms)
[ RUN      ] Endpoint/SlaveEndpointTest.NoAuthorizer/1
I0523 23:17:38.005280 32146 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0523 23:17:38.005805 32146 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0523 23:17:38.005908 32146 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0523 23:17:38.005940 32146 provisioner.cpp:249] Using default backend 'copy'
I0523 23:17:38.007613 32146 cluster.cpp:448] Creating default 'local' authorizer
I0523 23:17:38.009250 32166 slave.cpp:225] Mesos agent started on (749)@172.17.0.2:43089
I0523 23:17:38.009274 32166 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AHtO9p/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AHtO9p/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_AHtO9p/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_AHtO9p" --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_m9ACrS"
I0523 23:17:38.009640 32166 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AHtO9p/credential'
I0523 23:17:38.009796 32166 slave.cpp:258] Agent using credential for: test-principal
I0523 23:17:38.009819 32166 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_AHtO9p/http_credentials'
I0523 23:17:38.010035 32166 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 23:17:38.010179 32166 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 23:17:38.011687 32166 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 23:17:38.011770 32166 slave.cpp:537] Agent attributes: [  ]
I0523 23:17:38.011783 32166 slave.cpp:542] Agent hostname: 78b7e28fbdbf
I0523 23:17:38.011906 32176 status_update_manager.cpp:177] Pausing sending status updates
I0523 23:17:38.013366 32168 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_1_m9ACrS/meta'
I0523 23:17:38.013615 32178 status_update_manager.cpp:203] Recovering status update manager
I0523 23:17:38.013804 32168 containerizer.cpp:627] Recovering containerizer
I0523 23:17:38.015281 32178 provisioner.cpp:410] Provisioner recovery complete
I0523 23:17:38.015811 32175 slave.cpp:5974] Finished recovery
I0523 23:17:38.016264 32175 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 23:17:38.016535 32178 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 23:17:38.018653 32165 process.cpp:3730] Handling HTTP event for process 'slave(749)' with path: '/slave(749)/monitor/statistics.json'
I0523 23:17:38.019945 32184 http.cpp:1115] HTTP GET for /slave(749)/monitor/statistics.json from 172.17.0.2:53528
I0523 23:17:38.020035 32184 http.cpp:936] Authorizing principal 'test-principal' to GET the '/monitor/statistics.json' endpoint
I0523 23:17:38.023682 32182 slave.cpp:794] Agent terminating
[       OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/1 (28 ms)
[ RUN      ] Endpoint/SlaveEndpointTest.NoAuthorizer/2
I0523 23:17:38.034539 32146 containerizer.cpp:230] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0523 23:17:38.035082 32146 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0523 23:17:38.035178 32146 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0523 23:17:38.035208 32146 provisioner.cpp:249] Using default backend 'copy'
I0523 23:17:38.037479 32146 cluster.cpp:448] Creating default 'local' authorizer
I0523 23:17:38.039379 32177 slave.cpp:225] Mesos agent started on (750)@172.17.0.2:43089
I0523 23:17:38.039402 32177 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_MrcdfB/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_MrcdfB/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_MrcdfB/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_MrcdfB" --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_WFUHs3"
I0523 23:17:38.039788 32177 credentials.hpp:86] Loading credential for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_MrcdfB/credential'
I0523 23:17:38.039968 32177 slave.cpp:258] Agent using credential for: test-principal
I0523 23:17:38.039994 32177 credentials.hpp:37] Loading credentials for authentication from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_MrcdfB/http_credentials'
I0523 23:17:38.040246 32177 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 23:17:38.040405 32177 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 23:17:38.041908 32177 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 23:17:38.041990 32177 slave.cpp:537] Agent attributes: [  ]
I0523 23:17:38.042003 32177 slave.cpp:542] Agent hostname: 78b7e28fbdbf
I0523 23:17:38.042162 32186 status_update_manager.cpp:177] Pausing sending status updates
I0523 23:17:38.043788 32179 state.cpp:62] Recovering state from '/tmp/Endpoint_SlaveEndpointTest_NoAuthorizer_2_WFUHs3/meta'
I0523 23:17:38.044054 32175 status_update_manager.cpp:203] Recovering status update manager
I0523 23:17:38.044461 32183 containerizer.cpp:627] Recovering containerizer
I0523 23:17:38.045837 32184 provisioner.cpp:410] Provisioner recovery complete
I0523 23:17:38.046227 32182 slave.cpp:5974] Finished recovery
I0523 23:17:38.046715 32182 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 23:17:38.047127 32183 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 23:17:38.049638 32170 process.cpp:3730] Handling HTTP event for process 'slave(750)' with path: '/slave(750)/containers'
I0523 23:17:38.051007 32176 http.cpp:1115] HTTP GET for /slave(750)/containers from 172.17.0.2:53530
I0523 23:17:38.051075 32176 http.cpp:936] Authorizing principal 'test-principal' to GET the '/containers' endpoint
I0523 23:17:38.055155 32179 slave.cpp:794] Agent terminating
[       OK ] Endpoint/SlaveEndpointTest.NoAuthorizer/2 (32 ms)
[----------] 9 tests from Endpoint/SlaveEndpointTest (294 ms total)

[----------] Global test environment tear-down
[==========] 1573 tests from 176 test cases ran. (740441 ms total)
[  PASSED  ] 1569 tests.
[  FAILED  ] 4 tests, listed below:
[  FAILED  ] DefaultExecutorCheckTest.CommandCheckDeliveredAndReconciled
[  FAILED  ] DefaultExecutorCheckTest.CommandCheckStatusChange
[  FAILED  ] DefaultExecutorCheckTest.CommandCheckSeesParentsEnv
[  FAILED  ] DefaultExecutorCheckTest.CommandCheckSharesWorkDirWithTask

 4 FAILED TESTS
  YOU HAVE 22 DISABLED TESTS

E0523 23:17:38.124271 32189 process.cpp:950] 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-1495578852-19136
Untagged: mesos-1495578852-19136:latest
Deleted: sha256:f499fcbc18065d62db60cce7efe72a96d29cccb701cf8160f12154659587f32c
Deleted: sha256:1914b15b3ea08d4d58f063e55452656c202ed2dd3d6950edb6e13fd0e8509a3d
Deleted: sha256:b3e5c12685667a104e0d778df98dea9f38e36e6824ad6b6119fabd1ac8e0d230
Deleted: sha256:5536200e57f710dfbeb493a6ed77dc8c6764d50ff0f4f9ed30c6ffe95932aeb9
Deleted: sha256:ed76e10dc96b19790d266730e266a744d4fc9256a4ef684a8097ef165eb87bb4
Deleted: sha256:995e474b6acd5eb0ae8a065e37745f8d68253b0c2f90ac7c2497810b46dbd7f6
Deleted: sha256:2b234b9a8a924c48727bc5c77184e1f6c17c449e8bce88c12d646fab9079a43b
Deleted: sha256:40790d59a4458d46d3c21ba5e3b84968cee8c579c8473ce2d27bf7886280f6ac
Deleted: sha256:0d2478fcdad5fc83cffc499e452068cccf21fdc184d6d1d62387b0c05241d1c0
Deleted: sha256:086c5340d94d664e3fa8e0b4353e7cfe1fd7b512a50a9a3b8e9d0f2bad852bb2
Deleted: sha256:ff7cde52dc8bf390cf29f03c65c7f5f61c09ca8f547600903742e13502473787
Deleted: sha256:48ada7207ca93e52d9d73300a3ff876dc57ba3ded17a5e8a954ad40cf494c639
Deleted: sha256:78b942acb860b1a2870ac101e4d6348a2a069c4f14371ad95f3df40d8dcdee8f
Deleted: sha256:27857f85b49ea6a3b01d92e911b2bc7483b6f14980b5e98d57d4eb4aa33051f8
Deleted: sha256:337d6b6b238b8d036ee20d9da873247ad2f7c32b85a33363c78a6c9294c24f44
Deleted: sha256:93e32e0fefc3f231e137b419594df7e8984b56b97838695dd4a91316e2ac8f6a
Deleted: sha256:84fcda10ba5f8cf895abf1a7b4c9929ae140f2eacdccded61e47618b871e63c4
Deleted: sha256:4a445b2bfae62ba972a5b3f4ab7f7a646d2a65793c80aedfaeb98dc3fa49464d
Deleted: sha256:96ed6efda723b4afea56c22cf244a6735ed3faf4efb3632539ca4a18e767f16c
Deleted: sha256:8451515cf6b63a94d67ebe820decd3e2cab72189b66a63799e88bdbd73c35f54
Deleted: sha256:b199c0be4af73fe3607e77407d828597a5e6a65278d02e20a1746f75c44e2a1b
Deleted: sha256:eed936c6929da595d64b2aef7b77aa945cd264d01e01e3f41f4b771648f4f52f
Deleted: sha256:bf250ac93a9e998e3f883919e2a05e6064beac6482c22d0e9e7116a9e4ba7130
Deleted: sha256:93bf4c4300290672552bfb36ada3d2b30cbd0d507886f71f47f744cbd71fb39f
Deleted: sha256:cf96e07d3469e99fbf19978708836fee8d093722fc313fb7c06da083e4ba1172
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com