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/14 14:24:38 UTC

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

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=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3798/display/redirect>

------------------------------------------
[...truncated 11.68 MB...]
I0614 14:24:30.782238  5922 master.cpp:7988] Removing framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 (default) at scheduler-cfcdc892-3e87-4984-b009-18054d6bb100@172.17.0.4:38750
I0614 14:24:30.782258  5922 master.cpp:3200] Deactivating framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 (default) at scheduler-cfcdc892-3e87-4984-b009-18054d6bb100@172.17.0.4:38750
I0614 14:24:30.782434  5925 hierarchical.cpp:410] Deactivated framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000
I0614 14:24:30.782505  5927 slave.cpp:3098] Asked to shut down framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 by master@172.17.0.4:38750
I0614 14:24:30.782551  5927 slave.cpp:3123] Shutting down framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000
I0614 14:24:30.782541  5922 master.cpp:8556] Updating the state of task 1 of framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0614 14:24:30.782606  5927 slave.cpp:5590] Shutting down executor 'default' of framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 at executor(78)@172.17.0.4:38750
I0614 14:24:30.783351  5923 exec.cpp:435] Executor asked to shutdown
I0614 14:24:30.783488  5923 exec.cpp:450] Executor::shutdown took 29247ns
I0614 14:24:30.783802  5928 slave.cpp:4867] Got exited event for executor(78)@172.17.0.4:38750
I0614 14:24:30.784809  5926 hierarchical.cpp:1177] 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 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0 from framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000
I0614 14:24:30.784906  5922 master.cpp:8650] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 on agent 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0 at slave(202)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.785388  5922 master.cpp:8679] Removing executor 'default' with resources {} of framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 on agent 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0 at slave(202)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.786569  5927 hierarchical.cpp:353] Removed framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000
I0614 14:24:30.786603  5915 slave.cpp:5268] Executor 'default' of framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 exited with status 0
I0614 14:24:30.786903  5915 slave.cpp:5368] Cleaning up executor 'default' of framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 at executor(78)@172.17.0.4:38750
I0614 14:24:30.787241  5919 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_P7kwfU/slaves/06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0/frameworks/06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000/executors/default/runs/702fefce-d37d-4482-bfdc-99880d31026f' for gc 6.99999088967704days in the future
W0614 14:24:30.787276  5921 master.cpp:6600] Ignoring unknown exited executor 'default' of framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000 on agent 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0 at slave(202)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.787459  5919 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_P7kwfU/slaves/06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0/frameworks/06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000/executors/default' for gc 6.99999088652741days in the future
I0614 14:24:30.787500  5915 slave.cpp:5464] Cleaning up framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000
I0614 14:24:30.787621  5929 status_update_manager.cpp:285] Closing status update streams for framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000
I0614 14:24:30.787679  5929 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000
I0614 14:24:30.787722  5928 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_P7kwfU/slaves/06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0/frameworks/06bd79a3-ce0c-46b3-983a-2b564ab995b7-0000' for gc 6.99999088401481days in the future
I0614 14:24:30.787747  5915 slave.cpp:817] Agent terminating
I0614 14:24:30.788058  5912 master.cpp:1314] Agent 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0 at slave(202)@172.17.0.4:38750 (8c5a60b13734) disconnected
I0614 14:24:30.788090  5912 master.cpp:3237] Disconnecting agent 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0 at slave(202)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.790532  5912 master.cpp:3256] Deactivating agent 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0 at slave(202)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.790803  5925 hierarchical.cpp:715] Agent 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0 deactivated
I0614 14:24:30.791241  5922 master.cpp:1156] Master terminating
I0614 14:24:30.791983  5908 hierarchical.cpp:620] Removed agent 06bd79a3-ce0c-46b3-983a-2b564ab995b7-S0
[       OK ] MasterTest.FrameworkMessage (98 ms)
[ RUN      ] MasterTest.MultipleExecutors
I0614 14:24:30.800294  5889 cluster.cpp:162] Creating default 'local' authorizer
I0614 14:24:30.802834  5919 master.cpp:436] Master e746577e-6f42-41a4-86bc-8d05663f696f (8c5a60b13734) started on 172.17.0.4:38750
I0614 14:24:30.802887  5919 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/BiW4ST/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/BiW4ST/master" --zk_session_timeout="10secs"
I0614 14:24:30.803385  5919 master.cpp:488] Master only allowing authenticated frameworks to register
I0614 14:24:30.803400  5919 master.cpp:502] Master only allowing authenticated agents to register
I0614 14:24:30.803411  5919 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0614 14:24:30.803423  5919 credentials.hpp:37] Loading credentials for authentication from '/tmp/BiW4ST/credentials'
I0614 14:24:30.803895  5919 master.cpp:560] Using default 'crammd5' authenticator
I0614 14:24:30.804126  5919 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0614 14:24:30.804332  5919 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0614 14:24:30.804453  5919 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0614 14:24:30.804565  5919 master.cpp:640] Authorization enabled
I0614 14:24:30.806355  5926 whitelist_watcher.cpp:77] No whitelist given
I0614 14:24:30.806475  5927 hierarchical.cpp:169] Initialized hierarchical allocator process
I0614 14:24:30.807674  5925 master.cpp:2159] Elected as the leading master!
I0614 14:24:30.807724  5925 master.cpp:1698] Recovering from registrar
I0614 14:24:30.807857  5929 registrar.cpp:345] Recovering registrar
I0614 14:24:30.808991  5929 registrar.cpp:389] Successfully fetched the registry (0B) in 1.03808ms
I0614 14:24:30.809168  5929 registrar.cpp:493] Applied 1 operations in 37621ns; attempting to update the registry
I0614 14:24:30.809994  5929 registrar.cpp:550] Successfully updated the registry in 760064ns
I0614 14:24:30.810109  5929 registrar.cpp:422] Successfully recovered registrar
I0614 14:24:30.810680  5912 hierarchical.cpp:207] Skipping recovery of hierarchical allocator: nothing to recover
I0614 14:24:30.810663  5927 master.cpp:1797] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0614 14:24:30.826259  5889 cluster.cpp:448] Creating default 'local' authorizer
I0614 14:24:30.830703  5889 sched.cpp:232] Version: 1.4.0
I0614 14:24:30.833261  5912 slave.cpp:248] Mesos agent started on (203)@172.17.0.4:38750
I0614 14:24:30.834297  5925 sched.cpp:336] New master detected at master@172.17.0.4:38750
I0614 14:24:30.834408  5925 sched.cpp:407] Authenticating with master master@172.17.0.4:38750
I0614 14:24:30.834427  5925 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0614 14:24:30.834069  5912 slave.cpp:249] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_MultipleExecutors_ig3T2o/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_ig3T2o/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_ig3T2o/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_ig3T2o/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_ig3T2o/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_ig3T2o" --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_2SjYsE"
I0614 14:24:30.834740  5912 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_ig3T2o/credential'
I0614 14:24:30.834811  5911 authenticatee.cpp:121] Creating new client SASL connection
I0614 14:24:30.835047  5912 slave.cpp:281] Agent using credential for: test-principal
I0614 14:24:30.835074  5912 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_ig3T2o/http_credentials'
I0614 14:24:30.835233  5911 master.cpp:7465] Authenticating scheduler-42bc7a13-9610-4aa4-ae55-5a517982c200@172.17.0.4:38750
I0614 14:24:30.835448  5912 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0614 14:24:30.835469  5911 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(442)@172.17.0.4:38750
I0614 14:24:30.835718  5912 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0614 14:24:30.838861  5913 authenticator.cpp:98] Creating new server SASL connection
I0614 14:24:30.839181  5913 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0614 14:24:30.839205  5913 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0614 14:24:30.839295  5913 authenticator.cpp:204] Received SASL authentication start
I0614 14:24:30.839361  5913 authenticator.cpp:326] Authentication requires more steps
I0614 14:24:30.839443  5913 authenticatee.cpp:259] Received SASL authentication step
I0614 14:24:30.839531  5913 authenticator.cpp:232] Received SASL authentication step
I0614 14:24:30.839558  5913 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8c5a60b13734' server FQDN: '8c5a60b13734' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0614 14:24:30.839572  5913 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0614 14:24:30.839617  5913 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0614 14:24:30.839637  5913 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8c5a60b13734' server FQDN: '8c5a60b13734' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0614 14:24:30.839650  5913 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0614 14:24:30.839659  5913 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0614 14:24:30.839676  5913 authenticator.cpp:318] Authentication success
I0614 14:24:30.839854  5913 authenticatee.cpp:299] Authentication success
I0614 14:24:30.839946  5913 master.cpp:7495] Successfully authenticated principal 'test-principal' at scheduler-42bc7a13-9610-4aa4-ae55-5a517982c200@172.17.0.4:38750
I0614 14:24:30.840049  5913 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(442)@172.17.0.4:38750
I0614 14:24:30.840214  5912 slave.cpp:552] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0614 14:24:30.840342  5912 slave.cpp:560] Agent attributes: [  ]
I0614 14:24:30.840358  5912 slave.cpp:565] Agent hostname: 8c5a60b13734
I0614 14:24:30.840435  5913 sched.cpp:513] Successfully authenticated with master master@172.17.0.4:38750
I0614 14:24:30.840458  5913 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.4:38750
I0614 14:24:30.840566  5913 sched.cpp:869] Will retry registration in 182.440096ms if necessary
I0614 14:24:30.840762  5913 status_update_manager.cpp:177] Pausing sending status updates
I0614 14:24:30.840924  5913 master.cpp:2850] Received SUBSCRIBE call for framework 'default' at scheduler-42bc7a13-9610-4aa4-ae55-5a517982c200@172.17.0.4:38750
I0614 14:24:30.840997  5913 master.cpp:2195] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0614 14:24:30.841729  5913 master.cpp:2930] Subscribing framework default with checkpointing disabled and capabilities [  ]
I0614 14:24:30.842561  5916 sched.cpp:759] Framework registered with e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.842655  5916 sched.cpp:773] Scheduler::registered took 31806ns
I0614 14:24:30.842725  5916 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_2SjYsE/meta'
I0614 14:24:30.843112  5913 hierarchical.cpp:301] Added framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.843235  5911 status_update_manager.cpp:203] Recovering status update manager
I0614 14:24:30.843816  5913 hierarchical.cpp:1908] No allocations performed
I0614 14:24:30.843945  5913 hierarchical.cpp:1998] No inverse offers to send out!
I0614 14:24:30.844053  5913 hierarchical.cpp:1493] Performed allocation for 0 agents in 283959ns
I0614 14:24:30.844738  5913 slave.cpp:6127] Finished recovery
I0614 14:24:30.845420  5913 slave.cpp:6309] Querying resource estimator for oversubscribable resources
I0614 14:24:30.845633  5913 slave.cpp:945] New master detected at master@172.17.0.4:38750
I0614 14:24:30.845729  5918 status_update_manager.cpp:177] Pausing sending status updates
I0614 14:24:30.845800  5913 slave.cpp:980] Detecting new master
I0614 14:24:30.846139  5913 slave.cpp:6323] Received oversubscribable resources {} from the resource estimator
I0614 14:24:30.857852  5918 slave.cpp:1007] Authenticating with master master@172.17.0.4:38750
I0614 14:24:30.858042  5918 slave.cpp:1018] Using default CRAM-MD5 authenticatee
I0614 14:24:30.858417  5910 authenticatee.cpp:121] Creating new client SASL connection
I0614 14:24:30.858853  5910 master.cpp:7465] Authenticating slave(203)@172.17.0.4:38750
I0614 14:24:30.859091  5910 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(443)@172.17.0.4:38750
I0614 14:24:30.859514  5910 authenticator.cpp:98] Creating new server SASL connection
I0614 14:24:30.859783  5910 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0614 14:24:30.859814  5910 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0614 14:24:30.859906  5910 authenticator.cpp:204] Received SASL authentication start
I0614 14:24:30.859973  5910 authenticator.cpp:326] Authentication requires more steps
I0614 14:24:30.860074  5910 authenticatee.cpp:259] Received SASL authentication step
I0614 14:24:30.860188  5910 authenticator.cpp:232] Received SASL authentication step
I0614 14:24:30.860224  5910 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8c5a60b13734' server FQDN: '8c5a60b13734' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0614 14:24:30.860245  5910 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0614 14:24:30.860303  5910 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0614 14:24:30.860337  5910 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '8c5a60b13734' server FQDN: '8c5a60b13734' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0614 14:24:30.860352  5910 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0614 14:24:30.860364  5910 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0614 14:24:30.860385  5910 authenticator.cpp:318] Authentication success
I0614 14:24:30.860594  5910 authenticatee.cpp:299] Authentication success
I0614 14:24:30.860713  5910 master.cpp:7495] Successfully authenticated principal 'test-principal' at slave(203)@172.17.0.4:38750
I0614 14:24:30.860823  5910 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(443)@172.17.0.4:38750
I0614 14:24:30.861168  5910 slave.cpp:1102] Successfully authenticated with master master@172.17.0.4:38750
I0614 14:24:30.861407  5910 slave.cpp:1522] Will retry registration in 18.554597ms if necessary
I0614 14:24:30.861845  5910 master.cpp:5467] Received register agent message from slave(203)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.861968  5910 master.cpp:3699] Authorizing agent with principal 'test-principal'
I0614 14:24:30.862720  5910 master.cpp:5527] Authorized registration of agent at slave(203)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.862855  5910 master.cpp:5606] Registering agent at slave(203)@172.17.0.4:38750 (8c5a60b13734) with id e746577e-6f42-41a4-86bc-8d05663f696f-S0
I0614 14:24:30.863360  5910 registrar.cpp:493] Applied 1 operations in 68536ns; attempting to update the registry
I0614 14:24:30.864472  5910 registrar.cpp:550] Successfully updated the registry in 1.019136ms
I0614 14:24:30.864948  5910 master.cpp:5653] Admitted agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 at slave(203)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.865816  5910 master.cpp:5684] Registered agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 at slave(203)@172.17.0.4:38750 (8c5a60b13734) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0614 14:24:30.866266  5909 slave.cpp:1148] Registered with master master@172.17.0.4:38750; given agent ID e746577e-6f42-41a4-86bc-8d05663f696f-S0
I0614 14:24:30.866477  5911 hierarchical.cpp:587] Added agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 (8c5a60b13734) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0614 14:24:30.866809  5927 status_update_manager.cpp:184] Resuming sending status updates
I0614 14:24:30.867038  5909 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_2SjYsE/meta/slaves/e746577e-6f42-41a4-86bc-8d05663f696f-S0/slave.info'
I0614 14:24:30.867485  5909 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0614 14:24:30.867627  5909 slave.cpp:4825] Received ping from slave-observer(199)@172.17.0.4:38750
I0614 14:24:30.867751  5911 hierarchical.cpp:1998] No inverse offers to send out!
I0614 14:24:30.867771  5927 master.cpp:6338] Received update of agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 at slave(203)@172.17.0.4:38750 (8c5a60b13734) with total oversubscribed resources {}
I0614 14:24:30.867812  5911 hierarchical.cpp:1493] Performed allocation for 1 agents in 1.066212ms
I0614 14:24:30.868448  5927 master.cpp:7295] Sending 1 offers to framework e746577e-6f42-41a4-86bc-8d05663f696f-0000 (default) at scheduler-42bc7a13-9610-4aa4-ae55-5a517982c200@172.17.0.4:38750
I0614 14:24:30.869004  5921 sched.cpp:933] Scheduler::resourceOffers took 120690ns
I0614 14:24:30.871603  5922 master.cpp:3914] Processing ACCEPT call for offers: [ e746577e-6f42-41a4-86bc-8d05663f696f-O0 ] on agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 at slave(203)@172.17.0.4:38750 (8c5a60b13734) for framework e746577e-6f42-41a4-86bc-8d05663f696f-0000 (default) at scheduler-42bc7a13-9610-4aa4-ae55-5a517982c200@172.17.0.4:38750
I0614 14:24:30.871742  5922 master.cpp:3466] Authorizing framework principal 'test-principal' to launch task 1
I0614 14:24:30.872066  5922 master.cpp:3466] Authorizing framework principal 'test-principal' to launch task 2
W0614 14:24:30.874505  5921 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.
W0614 14:24:30.874614  5921 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.
I0614 14:24:30.875138  5921 master.cpp:9314] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 at slave(203)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.875488  5921 master.cpp:4569] Launching task 1 of framework e746577e-6f42-41a4-86bc-8d05663f696f-0000 (default) at scheduler-42bc7a13-9610-4aa4-ae55-5a517982c200@172.17.0.4:38750 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 at slave(203)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.876298  5920 slave.cpp:1632] Got assigned task '1' for framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.877028  5920 slave.cpp:1913] Authorizing task '1' for framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.877080  5920 slave.cpp:6626] Authorizing framework principal 'test-principal' to launch task 1
W0614 14:24:30.877184  5921 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.
W0614 14:24:30.877238  5921 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.
I0614 14:24:30.877766  5921 master.cpp:9314] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 at slave(203)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.878028  5921 master.cpp:4569] Launching task 2 of framework e746577e-6f42-41a4-86bc-8d05663f696f-0000 (default) at scheduler-42bc7a13-9610-4aa4-ae55-5a517982c200@172.17.0.4:38750 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 at slave(203)@172.17.0.4:38750 (8c5a60b13734)
I0614 14:24:30.878381  5920 slave.cpp:2100] Launching task '1' for framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.879081  5920 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_2SjYsE/slaves/e746577e-6f42-41a4-86bc-8d05663f696f-S0/frameworks/e746577e-6f42-41a4-86bc-8d05663f696f-0000/executors/executor-1/runs/c5e6a2b6-d50f-4336-af67-3a48c17ad439' to user 'mesos'
I0614 14:24:30.879385  5920 slave.cpp:7086] Launching executor 'executor-1' of framework e746577e-6f42-41a4-86bc-8d05663f696f-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_2SjYsE/slaves/e746577e-6f42-41a4-86bc-8d05663f696f-S0/frameworks/e746577e-6f42-41a4-86bc-8d05663f696f-0000/executors/executor-1/runs/c5e6a2b6-d50f-4336-af67-3a48c17ad439'
I0614 14:24:30.879778  5920 slave.cpp:2795] Launching container c5e6a2b6-d50f-4336-af67-3a48c17ad439 for executor 'executor-1' of framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.880156  5920 slave.cpp:2329] Queued task '1' for executor 'executor-1' of framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.880436  5920 slave.cpp:1632] Got assigned task '2' for framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.880870  5920 slave.cpp:1913] Authorizing task '2' for framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.880913  5920 slave.cpp:6626] Authorizing framework principal 'test-principal' to launch task 2
I0614 14:24:30.881287  5921 hierarchical.cpp:912] Updated allocation of framework e746577e-6f42-41a4-86bc-8d05663f696f-0000 on agent e746577e-6f42-41a4-86bc-8d05663f696f-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]
I0614 14:24:30.881933  5921 hierarchical.cpp:1177] 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 e746577e-6f42-41a4-86bc-8d05663f696f-S0 from framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.881988  5921 hierarchical.cpp:1214] Framework e746577e-6f42-41a4-86bc-8d05663f696f-0000 filtered agent e746577e-6f42-41a4-86bc-8d05663f696f-S0 for 5secs
I0614 14:24:30.882679  5921 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_2SjYsE/slaves/e746577e-6f42-41a4-86bc-8d05663f696f-S0/frameworks/e746577e-6f42-41a4-86bc-8d05663f696f-0000/executors/executor-1/runs/c5e6a2b6-d50f-4336-af67-3a48c17ad439'
I0614 14:24:30.882766  5921 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_2SjYsE/slaves/e746577e-6f42-41a4-86bc-8d05663f696f-S0/frameworks/e746577e-6f42-41a4-86bc-8d05663f696f-0000/executors/executor-1/runs/c5e6a2b6-d50f-4336-af67-3a48c17ad439'
I0614 14:24:30.883065  5921 slave.cpp:2100] Launching task '2' for framework e746577e-6f42-41a4-86bc-8d05663f696f-0000
I0614 14:24:30.883625  5921 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_2SjYsE/slaves/e746577e-6f42-41a4-86bc-8d05663f696f-S0/frameworks/e746577e-6f42-41a4-86bc-8d05663f696f-0000/executors/executor-2/runs/efc6ca2f-2761-4cd0-9ebe-4bfaf6736556' to user 'mesos'
I0614 14:24:30.884039  5921 slave.cpp:7086] Launching executor 'executor-2' of framework e746577e-6f42-41a4-86bc-8d05663f696f-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_2SjYsE/slaves/e746577e-6f42-41a4-86bc-8d05663f696f-S0/frameworks/e746577e-6f42-41a4-86bc-8d05663f696f-0000/executors/executor-2/runs/efc6ca2f-2761-4cd0-9ebe-4bfaf6736556'
*** Aborted at 1497450270 (unix time) try "date -d @1497450270" if you are using GNU date ***
I0614 14:24:30.885043  5920 exec.cpp:162] Version: 1.4.0
I0614 14:24:30.885516  5930 exec.cpp:212] Executor started at: executor(79)@172.17.0.4:38750 with pid 5889
PC: @     0x7ff42d2990cd __GI_getenv
*** SIGSEGV (@0x20) received by PID 5889 (TID 0x7ff41fa59700) from PID 32; stack trace: ***
    @     0x7ff3b396cfc5 (unknown)
    @     0x7ff3b39722c1 (unknown)
    @     0x7ff3b39660a8 (unknown)
    @     0x7ff42e25e370 (unknown)
    @     0x7ff42d2990cd __GI_getenv
    @     0x56199fa926e7 os::getenv()
    @     0x7ff4335ffcd2 mesos::internal::slave::executorEnvironment()
    @     0x7ff4335d5955 mesos::internal::slave::Slave::launchExecutor()
    @     0x7ff4335d0fca mesos::internal::slave::Slave::__run()
    @     0x7ff43363aaa8 _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_
    @     0x7ff43367824b _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_
    @     0x7ff43452ccf1 std::function<>::operator()()
    @     0x7ff434510653 process::ProcessBase::visit()
    @     0x7ff43451ba72 process::DispatchEvent::visit()
    @     0x56199fa6337c process::ProcessBase::serve()
    @     0x7ff43450c67f process::ProcessManager::resume()
    @     0x7ff4345087dc _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
    @     0x7ff43451b0d0 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
    @     0x7ff43451b014 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
    @     0x7ff43451af9e _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
    @     0x7ff42dbef230 (unknown)
    @     0x7ff42e256dc5 start_thread
    @     0x7ff42d35876d __clone
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1497447551-84
Untagged: mesos-1497447551-84:latest
Deleted: sha256:eed05d82af19d5ed452f9d71cd62e62a31a2823c36d1cf25d3a48979682947b9
Deleted: sha256:6eb0330030b7f5f0d1a537e78d4d601bdf0bcbae3ed08a638a9c704de0a570ce
Deleted: sha256:d5d76142ac038621e0f3bdf9223eacda7929916cfb66f04216e79432facf9607
Deleted: sha256:cabec819eb89da91fbeea9ffe0cbaa2011dd822c3d48aab1eda6427ddee96a64
Deleted: sha256:a09a96a17b2d2bb1f5b41a8c8308632a49abc615691d1fa3acf9110f02926435
Deleted: sha256:43d0f58e8a9feb045f8c1e246446e71b1382b0ed313167d68435965e4e241ca5
Deleted: sha256:af4273c36cafff6200865f6f1c3365a9fe070698d516594eb41b878ed69e6ac9
Deleted: sha256:0966d70b65d1e1144d8f28aa00def87f7e0f1dbd4076da550d3817e0d5b236ab
Deleted: sha256:d189a20dce610004ef58a07f3421cd596d2e65809fa0dbbca1746226d456f6b5
Deleted: sha256:afeb7ddbc84308746c7d684b9daf2ebb74899f836e6e3c7cf4a4a0c8debda853
Deleted: sha256:0492f5823e9399bf8c1b620f9091f1dad556c2e715dd80768ae3cbf793db8cd1
Deleted: sha256:2fa52e7df2ae984da1a9585760b1843b1edee9852304479059163f98c134bf76
Deleted: sha256:ce7dcb3e2a14899a0cea31c5805d0a9b9015fd2b6ac4dfb6447c76d907fe40cd
Deleted: sha256:e4b66f4d37eff12b3bdba9a97fb940386bb033bea8e74f4886d4313f18e1bf8b
Deleted: sha256:a952b37948a727401296a808dd75e816a79a959b8518aed0a683e870c7b606a9
Deleted: sha256:d58367352bdfa09866d308911040ded85d378474f3f7015b36451cbaf66c4944
Deleted: sha256:45699ddfe4ed108ca96d02773b10534609ddc21ff23e5f11a5496c40a4533cb2
Deleted: sha256:5997df9f8f4cbee72df880bc3781544cafd03dc1f260e68f5ab5da940aad75ec
Deleted: sha256:863c10a0c26083954e243c63f21582fdaddf45d9a014f2f97cf05a726b8a17d3
Deleted: sha256:fd5c09d2c21340bf1672dfe5fcaee9ba23756310ec9675b84a6fd768846ede48
Deleted: sha256:dfd176e8e92d19a5ae81334b609834c257006694138a07c7846e96bebcd0e489
Deleted: sha256:bdd530baf77d5d1731602496115420f8fe2541b07391c79e21c044d458a83b9b
Deleted: sha256:f35569b07397fb775218c9627b63b3762815f56758a1f465e53b2898b77b2a3b
Deleted: sha256:adb63d788c912165a8c0c188ce05de75e3e51468ef33a4779efc33ed25b6ef44
Deleted: sha256:40a0ef0bebee5ef303c1f5a7f63f20ca3689f96cf34744b1883ee83b4ee9f132
Build step 'Execute shell' marked build as failure