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/06/01 01:33:04 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3755
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3755/display/redirect?page=changes>
Changes:
[neil.conway] Ordered configuration flag docs alphabetically.
[neil.conway] Fixed various minor stylistic issues with flag help text.
------------------------------------------
[...truncated 11.82 MB...]
I0601 01:32:35.372047 7939 slave.cpp:3123] Shutting down framework 0b966650-3b40-420d-aed4-997762fd6455-0000
I0601 01:32:35.372090 7939 slave.cpp:5582] Shutting down executor 'default' of framework 0b966650-3b40-420d-aed4-997762fd6455-0000 at executor(74)@172.17.0.3:56710
I0601 01:32:35.372282 7925 exec.cpp:435] Executor asked to shutdown
I0601 01:32:35.372325 7925 exec.cpp:450] Executor::shutdown took 22008ns
I0601 01:32:35.372519 7937 slave.cpp:4867] Got exited event for executor(74)@172.17.0.3:56710
I0601 01:32:35.372663 7929 master.cpp:8601] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 0b966650-3b40-420d-aed4-997762fd6455-0000 on agent 0b966650-3b40-420d-aed4-997762fd6455-S0 at slave(198)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.372952 7929 master.cpp:8630] Removing executor 'default' with resources {} of framework 0b966650-3b40-420d-aed4-997762fd6455-0000 on agent 0b966650-3b40-420d-aed4-997762fd6455-S0 at slave(198)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.373092 7938 hierarchical.cpp:1135] 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 0b966650-3b40-420d-aed4-997762fd6455-S0 from framework 0b966650-3b40-420d-aed4-997762fd6455-0000
I0601 01:32:35.373577 7932 hierarchical.cpp:346] Removed framework 0b966650-3b40-420d-aed4-997762fd6455-0000
I0601 01:32:35.374369 7929 slave.cpp:5268] Executor 'default' of framework 0b966650-3b40-420d-aed4-997762fd6455-0000 exited with status 0
I0601 01:32:35.374584 7929 slave.cpp:5368] Cleaning up executor 'default' of framework 0b966650-3b40-420d-aed4-997762fd6455-0000 at executor(74)@172.17.0.3:56710
W0601 01:32:35.374682 7939 master.cpp:6557] Ignoring unknown exited executor 'default' of framework 0b966650-3b40-420d-aed4-997762fd6455-0000 on agent 0b966650-3b40-420d-aed4-997762fd6455-S0 at slave(198)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.375254 7933 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_rUMtLN/slaves/0b966650-3b40-420d-aed4-997762fd6455-S0/frameworks/0b966650-3b40-420d-aed4-997762fd6455-0000/executors/default/runs/400f7a87-f75e-4fcb-a49a-88958d2c0a92' for gc 6.99999565994074days in the future
I0601 01:32:35.375401 7929 slave.cpp:5456] Cleaning up framework 0b966650-3b40-420d-aed4-997762fd6455-0000
I0601 01:32:35.375474 7933 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_rUMtLN/slaves/0b966650-3b40-420d-aed4-997762fd6455-S0/frameworks/0b966650-3b40-420d-aed4-997762fd6455-0000/executors/default' for gc 6.99999565573037days in the future
I0601 01:32:35.375515 7938 status_update_manager.cpp:285] Closing status update streams for framework 0b966650-3b40-420d-aed4-997762fd6455-0000
I0601 01:32:35.375555 7938 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 0b966650-3b40-420d-aed4-997762fd6455-0000
I0601 01:32:35.375645 7929 slave.cpp:817] Agent terminating
I0601 01:32:35.375694 7933 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_rUMtLN/slaves/0b966650-3b40-420d-aed4-997762fd6455-S0/frameworks/0b966650-3b40-420d-aed4-997762fd6455-0000' for gc 6.99999565325333days in the future
I0601 01:32:35.375891 7934 master.cpp:1314] Agent 0b966650-3b40-420d-aed4-997762fd6455-S0 at slave(198)@172.17.0.3:56710 (5234c9572222) disconnected
I0601 01:32:35.376701 7934 master.cpp:3195] Disconnecting agent 0b966650-3b40-420d-aed4-997762fd6455-S0 at slave(198)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.376827 7934 master.cpp:3214] Deactivating agent 0b966650-3b40-420d-aed4-997762fd6455-S0 at slave(198)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.376946 7938 hierarchical.cpp:674] Agent 0b966650-3b40-420d-aed4-997762fd6455-S0 deactivated
I0601 01:32:35.379118 7934 master.cpp:1156] Master terminating
I0601 01:32:35.379688 7924 hierarchical.cpp:579] Removed agent 0b966650-3b40-420d-aed4-997762fd6455-S0
[ OK ] MasterTest.FrameworkMessage (76 ms)
[ RUN ] MasterTest.MultipleExecutors
I0601 01:32:35.388540 7905 cluster.cpp:162] Creating default 'local' authorizer
I0601 01:32:35.390892 7937 master.cpp:436] Master 5bd869dd-edc2-46b6-a680-b4c0a695958e (5234c9572222) started on 172.17.0.3:56710
I0601 01:32:35.391022 7937 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/prLSgc/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.4.0/_inst/share/mesos/webui" --work_dir="/tmp/prLSgc/master" --zk_session_timeout="10secs"
I0601 01:32:35.391333 7937 master.cpp:488] Master only allowing authenticated frameworks to register
I0601 01:32:35.391355 7937 master.cpp:502] Master only allowing authenticated agents to register
I0601 01:32:35.391366 7937 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0601 01:32:35.391376 7937 credentials.hpp:37] Loading credentials for authentication from '/tmp/prLSgc/credentials'
I0601 01:32:35.391683 7937 master.cpp:560] Using default 'crammd5' authenticator
I0601 01:32:35.391827 7937 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0601 01:32:35.391991 7937 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0601 01:32:35.392124 7937 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0601 01:32:35.392251 7937 master.cpp:640] Authorization enabled
I0601 01:32:35.392467 7924 hierarchical.cpp:167] Initialized hierarchical allocator process
I0601 01:32:35.392524 7939 whitelist_watcher.cpp:77] No whitelist given
I0601 01:32:35.394565 7925 master.cpp:2159] Elected as the leading master!
I0601 01:32:35.394600 7925 master.cpp:1698] Recovering from registrar
I0601 01:32:35.394783 7930 registrar.cpp:345] Recovering registrar
I0601 01:32:35.395387 7924 registrar.cpp:389] Successfully fetched the registry (0B) in 539904ns
I0601 01:32:35.395501 7924 registrar.cpp:493] Applied 1 operations in 25123ns; attempting to update the registry
I0601 01:32:35.396036 7924 registrar.cpp:550] Successfully updated the registry in 481792ns
I0601 01:32:35.396144 7924 registrar.cpp:422] Successfully recovered registrar
I0601 01:32:35.396607 7926 master.cpp:1797] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0601 01:32:35.396633 7936 hierarchical.cpp:206] Skipping recovery of hierarchical allocator: nothing to recover
I0601 01:32:35.403362 7905 cluster.cpp:448] Creating default 'local' authorizer
I0601 01:32:35.405699 7937 slave.cpp:248] Mesos agent started on (199)@172.17.0.3:56710
I0601 01:32:35.405721 7937 slave.cpp:249] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_MultipleExecutors_YesOMT/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/MasterTest_MultipleExecutors_YesOMT/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/MasterTest_MultipleExecutors_YesOMT/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/MasterTest_MultipleExecutors_YesOMT/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_YesOMT/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_YesOMT/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/MasterTest_MultipleExecutors_YesOMT" --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_VoTP1x"
I0601 01:32:35.406055 7937 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_YesOMT/credential'
I0601 01:32:35.406205 7937 slave.cpp:281] Agent using credential for: test-principal
I0601 01:32:35.406227 7937 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_YesOMT/http_credentials'
I0601 01:32:35.406520 7937 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0601 01:32:35.406635 7937 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0601 01:32:35.406832 7937 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0601 01:32:35.406946 7937 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0601 01:32:35.407122 7937 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0601 01:32:35.407222 7937 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0601 01:32:35.407317 7905 sched.cpp:232] Version: 1.4.0
I0601 01:32:35.408082 7930 sched.cpp:336] New master detected at master@172.17.0.3:56710
I0601 01:32:35.408185 7930 sched.cpp:407] Authenticating with master master@172.17.0.3:56710
I0601 01:32:35.408210 7930 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0601 01:32:35.408462 7932 authenticatee.cpp:121] Creating new client SASL connection
I0601 01:32:35.408416 7937 slave.cpp:552] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0601 01:32:35.408493 7937 slave.cpp:560] Agent attributes: [ ]
I0601 01:32:35.408509 7937 slave.cpp:565] Agent hostname: 5234c9572222
I0601 01:32:35.408625 7930 status_update_manager.cpp:177] Pausing sending status updates
I0601 01:32:35.408835 7930 master.cpp:7422] Authenticating scheduler-dc4b2231-8055-4d42-85a5-64e6d808ee6d@172.17.0.3:56710
I0601 01:32:35.408962 7932 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(431)@172.17.0.3:56710
I0601 01:32:35.409409 7932 authenticator.cpp:98] Creating new server SASL connection
I0601 01:32:35.409654 7932 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0601 01:32:35.409683 7932 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0601 01:32:35.409900 7937 authenticator.cpp:204] Received SASL authentication start
I0601 01:32:35.409914 7936 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_VoTP1x/meta'
I0601 01:32:35.409958 7937 authenticator.cpp:326] Authentication requires more steps
I0601 01:32:35.410125 7927 authenticatee.cpp:259] Received SASL authentication step
I0601 01:32:35.410298 7924 status_update_manager.cpp:203] Recovering status update manager
I0601 01:32:35.410328 7927 authenticator.cpp:232] Received SASL authentication step
I0601 01:32:35.410365 7927 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5234c9572222' server FQDN: '5234c9572222' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0601 01:32:35.410609 7927 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0601 01:32:35.410657 7927 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0601 01:32:35.410682 7927 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5234c9572222' server FQDN: '5234c9572222' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0601 01:32:35.410701 7927 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0601 01:32:35.410701 7938 slave.cpp:6119] Finished recovery
I0601 01:32:35.410709 7927 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0601 01:32:35.410742 7927 authenticator.cpp:318] Authentication success
I0601 01:32:35.410831 7932 authenticatee.cpp:299] Authentication success
I0601 01:32:35.410866 7936 master.cpp:7452] Successfully authenticated principal 'test-principal' at scheduler-dc4b2231-8055-4d42-85a5-64e6d808ee6d@172.17.0.3:56710
I0601 01:32:35.410944 7929 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(431)@172.17.0.3:56710
I0601 01:32:35.411207 7925 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:56710
I0601 01:32:35.411222 7938 slave.cpp:6301] Querying resource estimator for oversubscribable resources
I0601 01:32:35.411229 7925 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:56710
I0601 01:32:35.411402 7925 sched.cpp:869] Will retry registration in 456.991914ms if necessary
I0601 01:32:35.411631 7933 status_update_manager.cpp:177] Pausing sending status updates
I0601 01:32:35.411634 7924 master.cpp:2811] Received SUBSCRIBE call for framework 'default' at scheduler-dc4b2231-8055-4d42-85a5-64e6d808ee6d@172.17.0.3:56710
I0601 01:32:35.411628 7938 slave.cpp:945] New master detected at master@172.17.0.3:56710
I0601 01:32:35.411707 7924 master.cpp:2195] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0601 01:32:35.411772 7938 slave.cpp:980] Detecting new master
I0601 01:32:35.411921 7938 slave.cpp:6315] Received oversubscribable resources {} from the resource estimator
I0601 01:32:35.412148 7927 master.cpp:2888] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0601 01:32:35.412626 7927 sched.cpp:759] Framework registered with 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.412683 7927 sched.cpp:773] Scheduler::registered took 31538ns
I0601 01:32:35.412935 7932 hierarchical.cpp:294] Added framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.412946 7938 slave.cpp:1007] Authenticating with master master@172.17.0.3:56710
I0601 01:32:35.413020 7938 slave.cpp:1018] Using default CRAM-MD5 authenticatee
I0601 01:32:35.413096 7932 hierarchical.cpp:1865] No allocations performed
I0601 01:32:35.413157 7932 hierarchical.cpp:1955] No inverse offers to send out!
I0601 01:32:35.413235 7932 hierarchical.cpp:1447] Performed allocation for 0 agents in 174727ns
I0601 01:32:35.413245 7925 authenticatee.cpp:121] Creating new client SASL connection
I0601 01:32:35.413475 7927 master.cpp:7422] Authenticating slave(199)@172.17.0.3:56710
I0601 01:32:35.413564 7930 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(432)@172.17.0.3:56710
I0601 01:32:35.413815 7934 authenticator.cpp:98] Creating new server SASL connection
I0601 01:32:35.414007 7925 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0601 01:32:35.414041 7925 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0601 01:32:35.414150 7932 authenticator.cpp:204] Received SASL authentication start
I0601 01:32:35.414216 7932 authenticator.cpp:326] Authentication requires more steps
I0601 01:32:35.414352 7932 authenticatee.cpp:259] Received SASL authentication step
I0601 01:32:35.414497 7932 authenticator.cpp:232] Received SASL authentication step
I0601 01:32:35.414527 7932 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5234c9572222' server FQDN: '5234c9572222' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0601 01:32:35.414546 7932 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0601 01:32:35.414577 7932 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0601 01:32:35.414605 7932 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5234c9572222' server FQDN: '5234c9572222' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0601 01:32:35.414746 7932 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0601 01:32:35.414880 7932 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0601 01:32:35.414917 7932 authenticator.cpp:318] Authentication success
I0601 01:32:35.415030 7925 authenticatee.cpp:299] Authentication success
I0601 01:32:35.415073 7926 master.cpp:7452] Successfully authenticated principal 'test-principal' at slave(199)@172.17.0.3:56710
I0601 01:32:35.415092 7930 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(432)@172.17.0.3:56710
I0601 01:32:35.415529 7929 slave.cpp:1102] Successfully authenticated with master master@172.17.0.3:56710
I0601 01:32:35.415733 7929 slave.cpp:1522] Will retry registration in 19.757477ms if necessary
I0601 01:32:35.415886 7936 master.cpp:5425] Received register agent message from slave(199)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.416018 7936 master.cpp:3657] Authorizing agent with principal 'test-principal'
I0601 01:32:35.416347 7936 master.cpp:5485] Authorized registration of agent at slave(199)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.416471 7936 master.cpp:5564] Registering agent at slave(199)@172.17.0.3:56710 (5234c9572222) with id 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0
I0601 01:32:35.416899 7931 registrar.cpp:493] Applied 1 operations in 45454ns; attempting to update the registry
I0601 01:32:35.417534 7931 registrar.cpp:550] Successfully updated the registry in 581120ns
I0601 01:32:35.417716 7935 master.cpp:5611] Admitted agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 at slave(199)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.418200 7927 slave.cpp:4825] Received ping from slave-observer(194)@172.17.0.3:56710
I0601 01:32:35.418381 7927 slave.cpp:1148] Registered with master master@172.17.0.3:56710; given agent ID 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0
I0601 01:32:35.418313 7935 master.cpp:5642] Registered agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 at slave(199)@172.17.0.3:56710 (5234c9572222) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0601 01:32:35.418489 7936 status_update_manager.cpp:184] Resuming sending status updates
I0601 01:32:35.418721 7938 hierarchical.cpp:546] Added agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 (5234c9572222) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0601 01:32:35.418763 7927 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_VoTP1x/meta/slaves/5bd869dd-edc2-46b6-a680-b4c0a695958e-S0/slave.info'
I0601 01:32:35.419214 7927 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0601 01:32:35.419366 7935 master.cpp:6295] Received update of agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 at slave(199)@172.17.0.3:56710 (5234c9572222) with total oversubscribed resources {}
I0601 01:32:35.420334 7938 hierarchical.cpp:1955] No inverse offers to send out!
I0601 01:32:35.420395 7938 hierarchical.cpp:1447] Performed allocation for 1 agents in 1.494023ms
I0601 01:32:35.420922 7927 master.cpp:7252] Sending 1 offers to framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000 (default) at scheduler-dc4b2231-8055-4d42-85a5-64e6d808ee6d@172.17.0.3:56710
I0601 01:32:35.421358 7934 sched.cpp:933] Scheduler::resourceOffers took 101431ns
I0601 01:32:35.423656 7925 master.cpp:3872] Processing ACCEPT call for offers: [ 5bd869dd-edc2-46b6-a680-b4c0a695958e-O0 ] on agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 at slave(199)@172.17.0.3:56710 (5234c9572222) for framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000 (default) at scheduler-dc4b2231-8055-4d42-85a5-64e6d808ee6d@172.17.0.3:56710
I0601 01:32:35.423801 7925 master.cpp:3424] Authorizing framework principal 'test-principal' to launch task 1
I0601 01:32:35.424024 7925 master.cpp:3424] Authorizing framework principal 'test-principal' to launch task 2
W0601 01:32:35.425479 7939 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.
W0601 01:32:35.425509 7939 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.
I0601 01:32:35.425864 7939 master.cpp:9265] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 at slave(199)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.426043 7939 master.cpp:4527] Launching task 1 of framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000 (default) at scheduler-dc4b2231-8055-4d42-85a5-64e6d808ee6d@172.17.0.3:56710 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 at slave(199)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.426385 7934 slave.cpp:1632] Got assigned task '1' for framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
W0601 01:32:35.426934 7939 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.
W0601 01:32:35.426961 7939 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.
I0601 01:32:35.426981 7934 slave.cpp:1913] Authorizing task '1' for framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.427039 7934 slave.cpp:6618] Authorizing framework principal 'test-principal' to launch task 1
I0601 01:32:35.427320 7939 master.cpp:9265] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 at slave(199)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.427546 7939 master.cpp:4527] Launching task 2 of framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000 (default) at scheduler-dc4b2231-8055-4d42-85a5-64e6d808ee6d@172.17.0.3:56710 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 at slave(199)@172.17.0.3:56710 (5234c9572222)
I0601 01:32:35.427968 7927 slave.cpp:1632] Got assigned task '2' for framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.428572 7927 slave.cpp:2100] Launching task '1' for framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.429298 7927 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_VoTP1x/slaves/5bd869dd-edc2-46b6-a680-b4c0a695958e-S0/frameworks/5bd869dd-edc2-46b6-a680-b4c0a695958e-0000/executors/executor-1/runs/6e10a931-ee2c-44f6-952c-b3d498f84956' to user 'mesos'
I0601 01:32:35.429582 7927 slave.cpp:7078] Launching executor 'executor-1' of framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_VoTP1x/slaves/5bd869dd-edc2-46b6-a680-b4c0a695958e-S0/frameworks/5bd869dd-edc2-46b6-a680-b4c0a695958e-0000/executors/executor-1/runs/6e10a931-ee2c-44f6-952c-b3d498f84956'
I0601 01:32:35.429821 7925 hierarchical.cpp:871] Updated allocation of framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000 on agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-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]
I0601 01:32:35.430400 7925 hierarchical.cpp:1135] 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 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 from framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.430436 7927 slave.cpp:2329] Queued task '1' for executor 'executor-1' of framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.430451 7925 hierarchical.cpp:1172] Framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000 filtered agent 5bd869dd-edc2-46b6-a680-b4c0a695958e-S0 for 5secs
I0601 01:32:35.430570 7927 slave.cpp:1913] Authorizing task '2' for framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.430631 7927 slave.cpp:6618] Authorizing framework principal 'test-principal' to launch task 2
I0601 01:32:35.431422 7927 slave.cpp:2795] Launching container 6e10a931-ee2c-44f6-952c-b3d498f84956 for executor 'executor-1' of framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.431802 7927 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_VoTP1x/slaves/5bd869dd-edc2-46b6-a680-b4c0a695958e-S0/frameworks/5bd869dd-edc2-46b6-a680-b4c0a695958e-0000/executors/executor-1/runs/6e10a931-ee2c-44f6-952c-b3d498f84956'
I0601 01:32:35.431936 7927 slave.cpp:2100] Launching task '2' for framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.432433 7927 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_VoTP1x/slaves/5bd869dd-edc2-46b6-a680-b4c0a695958e-S0/frameworks/5bd869dd-edc2-46b6-a680-b4c0a695958e-0000/executors/executor-2/runs/15ee96b6-6c60-4234-b582-528168433f40' to user 'mesos'
I0601 01:32:35.432665 7927 slave.cpp:7078] Launching executor 'executor-2' of framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_VoTP1x/slaves/5bd869dd-edc2-46b6-a680-b4c0a695958e-S0/frameworks/5bd869dd-edc2-46b6-a680-b4c0a695958e-0000/executors/executor-2/runs/15ee96b6-6c60-4234-b582-528168433f40'
I0601 01:32:35.433362 7927 slave.cpp:2329] Queued task '2' for executor 'executor-2' of framework 5bd869dd-edc2-46b6-a680-b4c0a695958e-0000
I0601 01:32:35.433445 7927 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_VoTP1x/slaves/5bd869dd-edc2-46b6-a680-b4c0a695958e-S0/frameworks/5bd869dd-edc2-46b6-a680-b4c0a695958e-0000/executors/executor-2/runs/15ee96b6-6c60-4234-b582-528168433f40'
*** Aborted at 1496280755 (unix time) try "date -d @1496280755" if you are using GNU date ***
I0601 01:32:35.434028 7924 exec.cpp:162] Version: 1.4.0
I0601 01:32:35.434273 7926 exec.cpp:212] Executor started at: executor(75)@172.17.0.3:56710 with pid 7905
PC: @ 0x7fdace32b0cd __GI_getenv
*** SIGSEGV (@0xd0) received by PID 7905 (TID 0x7fdac588f700) from PID 208; stack trace: ***
@ 0x7fdabcfbafc5 (unknown)
@ 0x7fdabcfc02c1 (unknown)
@ 0x7fdabcfb40a8 (unknown)
@ 0x7fdacf2f0370 (unknown)
@ 0x7fdace32b0cd __GI_getenv
@ 0x7fdad7e38857 os::getenv()
@ 0x7fdad4b13c48 mesos::internal::slave::executorEnvironment()
@ 0x7fdad4aea065 mesos::internal::slave::Slave::launchExecutor()
@ 0x7fdad4b4faad _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS_6FutureINS1_6SecretEEEERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKS5_INS1_8TaskInfoEES9_SC_SF_SJ_EEvRKNS_3PIDIT_EEMSN_FvT0_T1_T2_T3_ET4_T5_T6_T7_ENKUlPNS_11ProcessBaseEE_clES12_
@ 0x7fdad4b8cc2d _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS0_6FutureINS5_6SecretEEEERKNS5_11FrameworkIDERKNS5_10ExecutorIDERKS9_INS5_8TaskInfoEESD_SG_SJ_SN_EEvRKNS0_3PIDIT_EEMSR_FvT0_T1_T2_T3_ET4_T5_T6_T7_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x7fdad5a40373 std::function<>::operator()()
@ 0x7fdad5a23cad process::ProcessBase::visit()
@ 0x7fdad5a2f0cc process::DispatchEvent::visit()
@ 0x7fdad7e094ec process::ProcessBase::serve()
@ 0x7fdad5a1fcd9 process::ProcessManager::resume()
@ 0x7fdad5a1be36 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x7fdad5a2e72a _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x7fdad5a2e66e _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x7fdad5a2e5f8 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7fdacec81230 (unknown)
@ 0x7fdacf2e8dc5 start_thread
@ 0x7fdace3ea76d __clone
make[4]: *** [check-local] Segmentation fault
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-1496278345-16681
Untagged: mesos-1496278345-16681:latest
Deleted: sha256:3d6950ba97461ab8d2e3955b721447bdc6f8617dfb2b893bcc8238167e9d62e3
Deleted: sha256:ff0ca5887b058e88d4417ab2422a0568a9c67d1bea6256a581058b248c832423
Deleted: sha256:90a0c6646c1470b1d7584d6d699c9a8832e6972b4aee6d2d29f65ab29fd204af
Deleted: sha256:ccf3b9beaa0fb6ad8b235210747d614bf8e7cd3c949ea0a53e70bb3b9c35d65a
Deleted: sha256:dc21ced8965a0ebfde8a1a2f05fe82b9cb6698d23dcd0c3d587467410654b668
Deleted: sha256:8b44484dd1f5e1035e74d78fff6fd3e8a6f88fd471906e82ada80b525a102241
Deleted: sha256:965a307c3ec8af6c4fe070e43d6242fcc1ded32c9e9b90f15d4d533b0388adc2
Deleted: sha256:6f8372b4c74f952b56161a451b8c588a7df1c07469aca1dd27beae72394a0a67
Deleted: sha256:ab7436c9e2da1420d9ddcd6d58b6d122f2f1ec67cd0ab453cc775f213b59d6fd
Deleted: sha256:8d901c3f8aff2d596df308212bbd1ae3222c0c4857e6e3fdb5216b2ea5432322
Deleted: sha256:8ffe510559f8fb89cd07fea52d24fbe5807d983eccebe98266cf0448dab1a2e7
Deleted: sha256:d15f8ccd4280b377bb9cb3bf5934a75e460eacb6aa9ef92906156a3cf90a314e
Deleted: sha256:d02030eca726f648b432a0992a561f5a2a6d34f4596854e7770e532cda56bd60
Deleted: sha256:5a473a63d80b80d8afa4137e24a0e1c6b7f16b5d9b6c11d46215a20e46c347a8
Deleted: sha256:3921c86f942b05c3ce66b7294aba0801b9d00430ded9d181bef823c7f4e8f17d
Deleted: sha256:5aedcfd74591fd6d6fc8b75661ba36856984f8428976ae89fc76fbcdcd330186
Deleted: sha256:9aa966bfbc180bd47e58ce1d8cf5de59548d32742a3bb760ba9ec53c8e22f55b
Deleted: sha256:edfa1595fb35331817878f01430a8e9c7a225074e42ab743229a9f2276766a98
Deleted: sha256:64e1701a6e1e487ce2253d14cd6c7866db657a6f98bcdb5c5fa19e629166cf10
Deleted: sha256:2ad6906cc37def397c7d592a156be813a39d5d48776bf9617b542f229bae10e3
Deleted: sha256:2b464ec94f221d7a8aec0ba3be9b5d562c371f1ea4bbbae294f2141909c44868
Deleted: sha256:7f75cdcfea473c1cabee9bedbf39b796f5dc521eb5ed73f9c036fd35a8f074e0
Deleted: sha256:c1079e0e39e382f321c16018f955b93cef87dfb3b7f18b6b61c9417a2c0e76b6
Deleted: sha256:f5a104ce22779f0c4aa9a41c4d49edff51051be9fab2e2cb626b3cff94dccc8e
Deleted: sha256:260e8ce1602592defb1524f542e58f23c2c6321a7d1c55c5e353c1a0f7f77eae
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com