See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4306/display/redirect>
------------------------------------------ [...truncated 14.91 MB...] 3: I1011 04:57:33.741122 15894 gc.cpp:90] Scheduling '/tmp/MasterTest_FrameworkMessage_uGjrwN/slaves/b6df879a-7f60-4bf8-9075-1087ca902d24-S0/frameworks/b6df879a-7f60-4bf8-9075-1087ca902d24-0000/executors/default/runs/0a02a77d-7bd8-4b07-8325-74e5fec3b89c' for gc 6.9999914235763days in the future 3: I1011 04:57:33.741310 15889 slave.cpp:5619] Cleaning up framework b6df879a-7f60-4bf8-9075-1087ca902d24-0000 3: I1011 04:57:33.741355 15910 gc.cpp:90] Scheduling '/tmp/MasterTest_FrameworkMessage_uGjrwN/slaves/b6df879a-7f60-4bf8-9075-1087ca902d24-S0/frameworks/b6df879a-7f60-4bf8-9075-1087ca902d24-0000/executors/default' for gc 6.99999142081185days in the future 3: I1011 04:57:33.741411 15903 status_update_manager.cpp:285] Closing status update streams for framework b6df879a-7f60-4bf8-9075-1087ca902d24-0000 3: I1011 04:57:33.741474 15903 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework b6df879a-7f60-4bf8-9075-1087ca902d24-0000 3: I1011 04:57:33.741525 15910 gc.cpp:90] Scheduling '/tmp/MasterTest_FrameworkMessage_uGjrwN/slaves/b6df879a-7f60-4bf8-9075-1087ca902d24-S0/frameworks/b6df879a-7f60-4bf8-9075-1087ca902d24-0000' for gc 6.99999141824296days in the future 3: I1011 04:57:33.741552 15889 slave.cpp:869] Agent terminating 3: W1011 04:57:33.741554 15890 master.cpp:7110] Ignoring unknown exited executor 'default' of framework b6df879a-7f60-4bf8-9075-1087ca902d24-0000 on agent b6df879a-7f60-4bf8-9075-1087ca902d24-S0 at slave(339)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.741659 15893 hierarchical.cpp:355] Removed framework b6df879a-7f60-4bf8-9075-1087ca902d24-0000 3: I1011 04:57:33.741750 15906 master.cpp:1303] Agent b6df879a-7f60-4bf8-9075-1087ca902d24-S0 at slave(339)@172.17.0.3:37459 (75cb7f59daab) disconnected 3: I1011 04:57:33.741775 15906 master.cpp:3336] Disconnecting agent b6df879a-7f60-4bf8-9075-1087ca902d24-S0 at slave(339)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.741819 15906 master.cpp:3355] Deactivating agent b6df879a-7f60-4bf8-9075-1087ca902d24-S0 at slave(339)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.741940 15911 hierarchical.cpp:690] Agent b6df879a-7f60-4bf8-9075-1087ca902d24-S0 deactivated 3: I1011 04:57:33.745445 15888 master.cpp:1145] Master terminating 3: I1011 04:57:33.745991 15898 hierarchical.cpp:626] Removed agent b6df879a-7f60-4bf8-9075-1087ca902d24-S0 3: [ OK ] MasterTest.FrameworkMessage (80 ms) 3: [ RUN ] MasterTest.MultipleExecutors 3: I1011 04:57:33.751375 15888 cluster.cpp:162] Creating default 'local' authorizer 3: I1011 04:57:33.753866 15898 master.cpp:445] Master 0fc3c5db-72ca-4bf2-ad13-75389a74ab99 (75cb7f59daab) started on 172.17.0.3:37459 3: I1011 04:57:33.753899 15898 master.cpp:447] 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/H5epgz/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/H5epgz/master" --zk_session_timeout="10secs" 3: I1011 04:57:33.754281 15898 master.cpp:496] Master only allowing authenticated frameworks to register 3: I1011 04:57:33.754288 15898 master.cpp:502] Master only allowing authenticated agents to register 3: I1011 04:57:33.754292 15898 master.cpp:508] Master only allowing authenticated HTTP frameworks to register 3: I1011 04:57:33.754300 15898 credentials.hpp:37] Loading credentials for authentication from '/tmp/H5epgz/credentials' 3: I1011 04:57:33.754621 15898 master.cpp:552] Using default 'crammd5' authenticator 3: I1011 04:57:33.754796 15898 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly' 3: I1011 04:57:33.754988 15898 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite' 3: I1011 04:57:33.755128 15898 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler' 3: I1011 04:57:33.755259 15898 master.cpp:631] Authorization enabled 3: I1011 04:57:33.755458 15899 whitelist_watcher.cpp:77] No whitelist given 3: I1011 04:57:33.755475 15902 hierarchical.cpp:171] Initialized hierarchical allocator process 3: I1011 04:57:33.758167 15898 master.cpp:2198] Elected as the leading master! 3: I1011 04:57:33.758183 15898 master.cpp:1687] Recovering from registrar 3: I1011 04:57:33.758359 15912 registrar.cpp:347] Recovering registrar 3: I1011 04:57:33.758985 15912 registrar.cpp:391] Successfully fetched the registry (0B) in 573952ns 3: I1011 04:57:33.759093 15912 registrar.cpp:495] Applied 1 operations in 31080ns; attempting to update the registry 3: I1011 04:57:33.759595 15912 registrar.cpp:552] Successfully updated the registry in 455168ns 3: I1011 04:57:33.759721 15912 registrar.cpp:424] Successfully recovered registrar 3: I1011 04:57:33.760114 15901 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register 3: I1011 04:57:33.760179 15899 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover 3: W1011 04:57:33.764924 15888 process.cpp:3194] Attempted to spawn already running process [email protected]:37459 3: I1011 04:57:33.765218 15888 cluster.cpp:448] Creating default 'local' authorizer 3: I1011 04:57:33.767370 15893 slave.cpp:254] Mesos agent started on (340)@172.17.0.3:37459 3: I1011 04:57:33.767400 15893 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"; --appc_store_dir="/tmp/MasterTest_MultipleExecutors_B4kBnX/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_B4kBnX/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --docker_store_dir="/tmp/MasterTest_MultipleExecutors_B4kBnX/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_B4kBnX/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_B4kBnX/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterTest_MultipleExecutors_B4kBnX" --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_xwEZjg" --zk_session_timeout="10secs" 3: I1011 04:57:33.767848 15893 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_B4kBnX/credential' 3: W1011 04:57:33.767989 15888 process.cpp:3194] Attempted to spawn already running process [email protected]:37459 3: I1011 04:57:33.768018 15893 slave.cpp:287] Agent using credential for: test-principal 3: I1011 04:57:33.768031 15893 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_B4kBnX/http_credentials' 3: I1011 04:57:33.768283 15893 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' 3: I1011 04:57:33.768455 15893 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' 3: I1011 04:57:33.768858 15888 sched.cpp:232] Version: 1.5.0 3: I1011 04:57:33.769358 15909 sched.cpp:336] New master detected at [email protected]:37459 3: I1011 04:57:33.769454 15909 sched.cpp:396] Authenticating with master [email protected]:37459 3: I1011 04:57:33.769470 15909 sched.cpp:403] Using default CRAM-MD5 authenticatee 3: I1011 04:57:33.769664 15897 authenticatee.cpp:121] Creating new client SASL connection 3: I1011 04:57:33.769892 15891 master.cpp:7936] Authenticating [email protected]:37459 3: I1011 04:57:33.770042 15904 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(708)@172.17.0.3:37459 3: I1011 04:57:33.769866 15893 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1011 04:57:33.770102 15893 slave.cpp:593] Agent attributes: [ ] 3: I1011 04:57:33.770112 15893 slave.cpp:602] Agent hostname: 75cb7f59daab 3: I1011 04:57:33.770247 15911 status_update_manager.cpp:177] Pausing sending status updates 3: I1011 04:57:33.770282 15898 authenticator.cpp:98] Creating new server SASL connection 3: I1011 04:57:33.770503 15905 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1011 04:57:33.770536 15905 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1011 04:57:33.770619 15905 authenticator.cpp:204] Received SASL authentication start 3: I1011 04:57:33.770680 15905 authenticator.cpp:326] Authentication requires more steps 3: I1011 04:57:33.770823 15896 authenticatee.cpp:259] Received SASL authentication step 3: I1011 04:57:33.770963 15903 authenticator.cpp:232] Received SASL authentication step 3: I1011 04:57:33.771034 15903 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '75cb7f59daab' server FQDN: '75cb7f59daab' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1011 04:57:33.771054 15903 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1011 04:57:33.771106 15903 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1011 04:57:33.771131 15903 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '75cb7f59daab' server FQDN: '75cb7f59daab' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1011 04:57:33.771140 15903 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1011 04:57:33.771147 15903 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1011 04:57:33.771159 15903 authenticator.cpp:318] Authentication success 3: I1011 04:57:33.771216 15892 authenticatee.cpp:299] Authentication success 3: I1011 04:57:33.771340 15912 master.cpp:7966] Successfully authenticated principal 'test-principal' at [email protected]:37459 3: I1011 04:57:33.771359 15892 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(708)@172.17.0.3:37459 3: I1011 04:57:33.771507 15907 sched.cpp:502] Successfully authenticated with master [email protected]:37459 3: I1011 04:57:33.771526 15907 sched.cpp:820] Sending SUBSCRIBE call to [email protected]:37459 3: I1011 04:57:33.771657 15907 sched.cpp:853] Will retry registration in 1.282212132secs if necessary 3: I1011 04:57:33.771821 15908 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at [email protected]:37459 3: I1011 04:57:33.771843 15908 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' 3: I1011 04:57:33.771957 15906 state.cpp:64] Recovering state from '/tmp/MasterTest_MultipleExecutors_xwEZjg/meta' 3: I1011 04:57:33.772181 15910 status_update_manager.cpp:203] Recovering status update manager 3: I1011 04:57:33.772302 15900 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1011 04:57:33.772649 15894 slave.cpp:6310] Finished recovery 3: I1011 04:57:33.772864 15890 sched.cpp:747] Framework registered with 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.772912 15890 sched.cpp:761] Scheduler::registered took 26582ns 3: I1011 04:57:33.773008 15905 hierarchical.cpp:303] Added framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.773067 15894 slave.cpp:6492] Querying resource estimator for oversubscribable resources 3: I1011 04:57:33.773167 15905 hierarchical.cpp:1943] No allocations performed 3: I1011 04:57:33.773206 15905 hierarchical.cpp:2033] No inverse offers to send out! 3: I1011 04:57:33.773234 15905 hierarchical.cpp:1486] Performed allocation for 0 agents in 106021ns 3: I1011 04:57:33.773308 15902 status_update_manager.cpp:177] Pausing sending status updates 3: I1011 04:57:33.773322 15912 slave.cpp:993] New master detected at [email protected]:37459 3: I1011 04:57:33.773416 15912 slave.cpp:1028] Detecting new master 3: I1011 04:57:33.773524 15912 slave.cpp:6506] Received oversubscribable resources {} from the resource estimator 3: I1011 04:57:33.782719 15907 slave.cpp:1055] Authenticating with master [email protected]:37459 3: I1011 04:57:33.782780 15907 slave.cpp:1064] Using default CRAM-MD5 authenticatee 3: I1011 04:57:33.783067 15889 authenticatee.cpp:121] Creating new client SASL connection 3: I1011 04:57:33.783432 15893 master.cpp:7936] Authenticating slave(340)@172.17.0.3:37459 3: I1011 04:57:33.783572 15908 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(709)@172.17.0.3:37459 3: I1011 04:57:33.783879 15897 authenticator.cpp:98] Creating new server SASL connection 3: I1011 04:57:33.784168 15891 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 3: I1011 04:57:33.784196 15891 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' 3: I1011 04:57:33.784302 15906 authenticator.cpp:204] Received SASL authentication start 3: I1011 04:57:33.784368 15906 authenticator.cpp:326] Authentication requires more steps 3: I1011 04:57:33.784471 15911 authenticatee.cpp:259] Received SASL authentication step 3: I1011 04:57:33.784586 15909 authenticator.cpp:232] Received SASL authentication step 3: I1011 04:57:33.784631 15909 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '75cb7f59daab' server FQDN: '75cb7f59daab' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 3: I1011 04:57:33.784643 15909 auxprop.cpp:181] Looking up auxiliary property '*userPassword' 3: I1011 04:57:33.784679 15909 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' 3: I1011 04:57:33.784696 15909 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '75cb7f59daab' server FQDN: '75cb7f59daab' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 3: I1011 04:57:33.784703 15909 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true 3: I1011 04:57:33.784709 15909 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true 3: I1011 04:57:33.784729 15909 authenticator.cpp:318] Authentication success 3: I1011 04:57:33.784802 15910 authenticatee.cpp:299] Authentication success 3: I1011 04:57:33.784852 15904 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(340)@172.17.0.3:37459 3: I1011 04:57:33.784901 15898 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(709)@172.17.0.3:37459 3: I1011 04:57:33.785076 15900 slave.cpp:1147] Successfully authenticated with master [email protected]:37459 3: I1011 04:57:33.785287 15900 slave.cpp:1626] Will retry registration in 5.752758ms if necessary 3: I1011 04:57:33.785471 15890 master.cpp:5801] Received register agent message from slave(340)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.785545 15890 master.cpp:3838] Authorizing agent with principal 'test-principal' 3: I1011 04:57:33.786053 15903 master.cpp:5861] Authorized registration of agent at slave(340)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.786167 15903 master.cpp:5954] Registering agent at slave(340)@172.17.0.3:37459 (75cb7f59daab) with id 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 3: I1011 04:57:33.786525 15905 registrar.cpp:495] Applied 1 operations in 70259ns; attempting to update the registry 3: I1011 04:57:33.787017 15905 registrar.cpp:552] Successfully updated the registry in 440832ns 3: I1011 04:57:33.787222 15907 master.cpp:6001] Admitted agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.787742 15893 slave.cpp:4966] Received ping from slave-observer(330)@172.17.0.3:37459 3: I1011 04:57:33.787878 15893 slave.cpp:1193] Registered with master [email protected]:37459; given agent ID 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 3: I1011 04:57:33.787750 15907 master.cpp:6032] Registered agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] 3: I1011 04:57:33.788007 15891 status_update_manager.cpp:184] Resuming sending status updates 3: I1011 04:57:33.788028 15908 hierarchical.cpp:593] Added agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 (75cb7f59daab) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {}) 3: I1011 04:57:33.788244 15893 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_xwEZjg/meta/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/slave.info' 3: I1011 04:57:33.788616 15893 slave.cpp:1262] Forwarding total oversubscribed resources {} 3: I1011 04:57:33.788861 15906 master.cpp:6817] Received update of agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) with total oversubscribed resources {} 3: I1011 04:57:33.789077 15908 hierarchical.cpp:2033] No inverse offers to send out! 3: I1011 04:57:33.789124 15908 hierarchical.cpp:1486] Performed allocation for 1 agents in 961187ns 3: I1011 04:57:33.789115 15906 master.cpp:6828] Ignoring update on agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) as it reports no changes 3: I1011 04:57:33.789961 15906 master.cpp:7766] Sending 1 offers to framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 (default) at [email protected]:37459 3: I1011 04:57:33.790522 15911 sched.cpp:917] Scheduler::resourceOffers took 106050ns 3: I1011 04:57:33.793210 15900 master.cpp:9389] Removing offer 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-O0 3: I1011 04:57:33.793392 15900 master.cpp:4196] Processing ACCEPT call for offers: [ 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-O0 ] on agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) for framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 (default) at [email protected]:37459 3: I1011 04:57:33.793516 15900 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task 1 3: I1011 04:57:33.793752 15900 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task 2 3: W1011 04:57:33.795265 15900 validation.cpp:1338] 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. 3: W1011 04:57:33.795298 15900 validation.cpp:1350] 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. 3: I1011 04:57:33.795680 15900 master.cpp:10135] Adding task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.796030 15900 master.cpp:4879] Launching task 1 of framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 (default) at [email protected]:37459 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.796481 15902 slave.cpp:1747] Got assigned task '1' for framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: W1011 04:57:33.797052 15900 validation.cpp:1338] 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. 3: W1011 04:57:33.797086 15900 validation.cpp:1350] 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. 3: I1011 04:57:33.797142 15902 slave.cpp:2015] Authorizing task '1' for framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.797194 15902 slave.cpp:6809] Authorizing framework principal 'test-principal' to launch task 1 3: I1011 04:57:33.797492 15900 master.cpp:10135] Adding task 2 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.797808 15900 master.cpp:4879] Launching task 2 of framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 (default) at [email protected]:37459 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 at slave(340)@172.17.0.3:37459 (75cb7f59daab) 3: I1011 04:57:33.798084 15905 slave.cpp:2183] Launching task '1' for framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.798627 15905 paths.cpp:605] Trying to chown '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-1/runs/58d44aba-29f9-42e7-9c59-7c09170c123b' to user 'mesos' 3: I1011 04:57:33.798851 15905 slave.cpp:7271] Launching executor 'executor-1' of framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-1/runs/58d44aba-29f9-42e7-9c59-7c09170c123b' 3: I1011 04:57:33.799271 15905 slave.cpp:2874] Launching container 58d44aba-29f9-42e7-9c59-7c09170c123b for executor 'executor-1' of framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.799540 15905 slave.cpp:2411] Queued task '1' for executor 'executor-1' of framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.799813 15905 slave.cpp:1747] Got assigned task '2' for framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.799895 15897 hierarchical.cpp:887] Updated allocation of framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 on agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-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] 3: I1011 04:57:33.800251 15905 slave.cpp:944] Successfully attached '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-1/runs/58d44aba-29f9-42e7-9c59-7c09170c123b' to virtual path '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-1/runs/latest' 3: I1011 04:57:33.800289 15905 slave.cpp:944] Successfully attached '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-1/runs/58d44aba-29f9-42e7-9c59-7c09170c123b' to virtual path '/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-1/runs/latest' 3: I1011 04:57:33.800323 15905 slave.cpp:944] Successfully attached '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-1/runs/58d44aba-29f9-42e7-9c59-7c09170c123b' to virtual path '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-1/runs/58d44aba-29f9-42e7-9c59-7c09170c123b' 3: I1011 04:57:33.800388 15905 slave.cpp:2015] Authorizing task '2' for framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.800436 15905 slave.cpp:6809] Authorizing framework principal 'test-principal' to launch task 2 3: I1011 04:57:33.800842 15897 hierarchical.cpp:1161] 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 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 from framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.801259 15897 hierarchical.cpp:1207] Framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 filtered agent 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0 for 5secs 3: I1011 04:57:33.801829 15909 slave.cpp:2183] Launching task '2' for framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 3: I1011 04:57:33.802264 15909 paths.cpp:605] Trying to chown '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-2/runs/ab89fe92-d3e5-4728-ae38-c52b33092b39' to user 'mesos' 3: I1011 04:57:33.802464 15909 slave.cpp:7271] Launching executor 'executor-2' of framework 0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_xwEZjg/slaves/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-S0/frameworks/0fc3c5db-72ca-4bf2-ad13-75389a74ab99-0000/executors/executor-2/runs/ab89fe92-d3e5-4728-ae38-c52b33092b39' 3: *** Aborted at 1507697853 (unix time) try "date -d @1507697853" if you are using GNU date *** 3: PC: @ 0x2ac68248082d (unknown) 3: W1011 04:57:33.803217 15891 process.cpp:3194] Attempted to spawn already running process [email protected]:37459 3: *** SIGSEGV (@0xc0) received by PID 15888 (TID 0x2ac68c092700) from PID 192; stack trace: *** 3: I1011 04:57:33.803409 15891 exec.cpp:162] Version: 1.5.0 3: @ 0x2ac67e898330 (unknown) 3: @ 0x2ac68248082d (unknown) 3: I1011 04:57:33.803681 15895 exec.cpp:212] Executor started at: executor(134)@172.17.0.3:37459 with pid 15888 3: @ 0xbcd7e8 os::getenv() 3: @ 0x2ac67a8b508b mesos::internal::slave::executorEnvironment() 3: @ 0x2ac67a8b06f9 mesos::internal::slave::Slave::launchExecutor() 3: @ 0x2ac67a8abdda mesos::internal::slave::Slave::__run() 3: @ 0x2ac67a99c1b7 _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureISt4listIbSaIbEEEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSI_INS1_13TaskGroupInfoEESB_SE_SH_SM_SQ_EEvRKNS_3PIDIT_EEMSS_FvT0_T1_T2_T3_T4_EOT5_OT6_OT7_OT8_OT9_ENKUlRS9_RSC_RSF_RSK_RSO_PNS_11ProcessBaseEE_clES1D_S1E_S1F_S1G_S1H_S1J_ 3: @ 0x2ac67a99c018 _ZNSt5_BindIFZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureISt4listIbSaIbEEEERKNS2_13FrameworkInfoERKNS2_12ExecutorInfoERK6OptionINS2_8TaskInfoEERKSJ_INS2_13TaskGroupInfoEESC_SF_SI_SN_SR_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_EOT5_OT6_OT7_OT8_OT9_EUlRSA_RSD_RSG_RSL_RSP_PNS0_11ProcessBaseEE_SA_SD_SG_SL_SP_St12_PlaceholderILi1EEEE6__callIvJOS1K_EJLm0ELm1ELm2ELm3ELm4ELm5EEEEST_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE 3: @ 0x2ac67a99bee6 _ZNSt5_BindIFZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureISt4listIbSaIbEEEERKNS2_13FrameworkInfoERKNS2_12ExecutorInfoERK6OptionINS2_8TaskInfoEERKSJ_INS2_13TaskGroupInfoEESC_SF_SI_SN_SR_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_EOT5_OT6_OT7_OT8_OT9_EUlRSA_RSD_RSG_RSL_RSP_PNS0_11ProcessBaseEE_SA_SD_SG_SL_SP_St12_PlaceholderILi1EEEEclIJS1K_EvEESX_DpOT_ 3: @ 0x2ac67a99b87f _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureISt4listIbSaIbEEEERKNS6_13FrameworkInfoERKNS6_12ExecutorInfoERK6OptionINS6_8TaskInfoEERKSN_INS6_13TaskGroupInfoEESG_SJ_SM_SR_SV_EEvRKNS0_3PIDIT_EEMSX_FvT0_T1_T2_T3_T4_EOT5_OT6_OT7_OT8_OT9_EUlRSE_RSH_RSK_RSP_RST_S2_E_SE_SH_SK_SP_ST_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_ 3: @ 0x2ac67dc31084 std::function<>::operator()() 3: @ 0x2ac67dc139c4 process::ProcessBase::visit() 3: @ 0x2ac67dcc921e process::DispatchEvent::visit() 3: @ 0xad28f1 process::ProcessBase::serve() 3: @ 0x2ac67dc111df process::ProcessManager::resume() 3: @ 0x2ac67dc20ed1 process::ProcessManager::init_threads()::$_8::operator()() 3: @ 0x2ac67dc20e15 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_8vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE 3: @ 0x2ac67dc20de5 std::_Bind_simple<>::operator()() 3: @ 0x2ac67dc20dbc std::thread::_Impl<>::_M_run() 3: @ 0x2ac681cd6a60 (unknown) 3: @ 0x2ac67e890184 start_thread 3: @ 0x2ac682542ffd (unknown) 3/3 Test #3: MesosTests .......................***Exception: SegFault186.06 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) = 205.10 sec The following tests FAILED: 3 - MesosTests (SEGFAULT) Errors while running CTest make[3]: *** [CMakeFiles/check] Error 8 make[3]: Leaving directory `/mesos/build' make[2]: *** [CMakeFiles/check.dir/all] Error 2 make[2]: Leaving directory `/mesos/build' make[1]: *** [CMakeFiles/check.dir/rule] Error 2 make[1]: Leaving directory `/mesos/build' make: *** [check] Error 2 + docker rmi mesos-1507696438-11874 Untagged: mesos-1507696438-11874:latest Deleted: sha256:f37a5387ee5d5c34b578a2f271786cebdf9bd290da686a27369ac6b5655a1db9 Deleted: sha256:f99837b88eaa7e8f83917c5ac7223cad81b5145bdce436dde6c242d80e401040 Deleted: sha256:a7e5bfc4ed3a7ff2fefbedbae1816a931b3f54add15674c970cbe92d5aef3f3c Deleted: sha256:d83495d88d982a805d24896931350b4896df21abbe0e2d71610fedf857e601c1 Deleted: sha256:b9283525a5570ff12256bec4fa1450cade176012a6c2ae39a1e44ff2e1a5bf91 Deleted: sha256:3f29d4e920c466ba583fe8e10cf1e67c35f192669247bd9628426f10c23bdbe7 Deleted: sha256:cfb35ed09fd79e43ae207eee3b0f53770776f63b9a33ad316eea7176fb63d0c7 Deleted: sha256:69e7a55cca10d8b8ce632f0aca761d27c50553ae2121d9574896f1ae8579da21 Deleted: sha256:6e1c70708a9a4a1befb4b1f3bdcb5c398ab0b4734d9ec76e4387a2aa6dd52e3f Deleted: sha256:75efbe585c3df3e05e3e65b464b013ac956cdaa631e306511464580b49108b35 Deleted: sha256:2c9aa111ed786d2ae71a04854b992a7be6c152538757f546a81007d5069e35c5 Deleted: sha256:fc5bf029c0e8986971ddfb1ff8fb5ffec706948dcd8db4d3504bace19875556d Deleted: sha256:789b2cfabe9964bffa48086cc801ff81e267c0df2134f62b31d27823c202b419 Deleted: sha256:6cd6dfdac8a46368146bc05d78a428b3dc44a9c815ef5949752a8e6ca6e29fb8 Deleted: sha256:461d7c5a6f369c3c74ec0d8d8541905f67ba32aebcb84fd5991ac9abf6ad763b Deleted: sha256:e14b405c3da07c127fc7dfad014c0de7b1f976a7ea1969fefbd26382132c22ca Deleted: sha256:4de9ebf46aa7fc64cfe8e58069cabb7e1c23d3986fa3bfbcd40a9480c06c0b35 Deleted: sha256:04349a55dddc2e5d0ad72491d705e2146fcfee279c4238dcf41567999c6f8a74 Deleted: sha256:44530c0e4e34d7238c8f38888fcad30a82059d5b17d5900e43554519c15b7146 Deleted: sha256:9f7f4e0d93375da93af8d8b7dec3a4ca91e29ad55adcb28e4115614f5c2524a9 Deleted: sha256:f5414c9c428f9a74b6fbb4187eee84d80e2bfe54f35cfdb0338ec334b14fcd3b Deleted: sha256:8f5abd0facae268f2c2b012d7d77c7ba29e4dc95d41e23184f8c39073d3a9e70 Deleted: sha256:55b0503b785045ebc21dc5e9b091078cd0cae26d5232afb55838b967868bd3b7 Deleted: sha256:7fd15ee481a7074fc185455d40357dc38474f583cfb9de17fd59bd661ebcf06e Deleted: sha256:d20fe9c4f6e7b12492a6cd64910374e6fbdaaa56138e9cc1419bd6515a57c2c8 Deleted: sha256:f029569937de1a454d07900a1f539ac98e996a7da886741fd7ccfe184ea22b71 Deleted: sha256:757976fafa1b4de5631e715cd6d69ef1931b941eb31ff6f0b67a4297aa29c717 Deleted: sha256:66e76e5dca50bf3b4a1ca7fb6483f7d3b43923c2d93832d1219304e18e42e88b Build step 'Execute shell' marked build as failure
