See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4357/display/redirect>
------------------------------------------
[...truncated 7.95 MB...]
I1023 04:58:23.549506 2758 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I1023 04:58:23.549675 2758 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
I1023 04:58:23.549998 2758 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I1023 04:58:23.550155 2758 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
I1023 04:58:23.552693 2758 slave.cpp:565] 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"}]
I1023 04:58:23.552987 2758 slave.cpp:573] Agent attributes: [ ]
I1023 04:58:23.553000 2758 slave.cpp:582] Agent hostname: 62baec6496c1
I1023 04:58:23.553277 2766 status_update_manager.cpp:177] Pausing sending
status updates
I1023 04:58:23.555351 2770 state.cpp:64] Recovering state from
'/tmp/SlaveTest_ContainersEndpointNoExecutor_3vooeB/meta'
I1023 04:58:23.555416 2758 process.cpp:3938] Handling HTTP event for process
'slave(167)' with path: '/slave(167)/containers'
I1023 04:58:23.556282 2775 status_update_manager.cpp:203] Recovering status
update manager
I1023 04:58:23.556704 2758 containerizer.cpp:609] Recovering containerizer
I1023 04:58:23.558595 2776 http.cpp:1185] HTTP GET for /slave(167)/containers
from 172.17.0.3:34776
I1023 04:58:23.558727 2776 http.cpp:976] Authorizing principal
'test-principal' to GET the '/containers' endpoint
I1023 04:58:23.558964 2763 provisioner.cpp:416] Provisioner recovery complete
I1023 04:58:23.560379 2759 slave.cpp:6295] Finished recovery
I1023 04:58:23.561076 2759 slave.cpp:6477] Querying resource estimator for
oversubscribable resources
I1023 04:58:23.562031 2759 slave.cpp:971] New master detected at
[email protected]:42906
I1023 04:58:23.562068 2775 status_update_manager.cpp:177] Pausing sending
status updates
I1023 04:58:23.562137 2759 slave.cpp:1006] Detecting new master
I1023 04:58:23.562305 2759 slave.cpp:6491] Received oversubscribable resources
{} from the resource estimator
I1023 04:58:23.565470 2761 slave.cpp:843] Agent terminating
I1023 04:58:23.573655 2754 master.cpp:1160] Master terminating
[ OK ] SlaveTest.ContainersEndpointNoExecutor (64 ms)
[ RUN ] SlaveTest.ContainersEndpoint
I1023 04:58:23.581178 2754 cluster.cpp:162] Creating default 'local' authorizer
I1023 04:58:23.584719 2768 master.cpp:442] Master
0c9daaa8-536e-4970-ab54-98b5dcff334e (62baec6496c1) started on 172.17.0.3:42906
I1023 04:58:23.584751 2768 master.cpp:444] 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/zCMnpS/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/zCMnpS/master"
--zk_session_timeout="10secs"
I1023 04:58:23.585140 2768 master.cpp:494] Master only allowing authenticated
frameworks to register
I1023 04:58:23.585150 2768 master.cpp:508] Master only allowing authenticated
agents to register
I1023 04:58:23.585157 2768 master.cpp:521] Master only allowing authenticated
HTTP frameworks to register
I1023 04:58:23.585163 2768 credentials.hpp:37] Loading credentials for
authentication from '/tmp/zCMnpS/credentials'
I1023 04:58:23.585577 2768 master.cpp:566] Using default 'crammd5'
authenticator
I1023 04:58:23.585803 2768 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I1023 04:58:23.586024 2768 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I1023 04:58:23.586239 2768 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I1023 04:58:23.586390 2768 master.cpp:646] Authorization enabled
I1023 04:58:23.586664 2770 hierarchical.cpp:171] Initialized hierarchical
allocator process
I1023 04:58:23.586719 2767 whitelist_watcher.cpp:77] No whitelist given
I1023 04:58:23.590315 2768 master.cpp:2163] Elected as the leading master!
I1023 04:58:23.590363 2768 master.cpp:1702] Recovering from registrar
I1023 04:58:23.590528 2765 registrar.cpp:347] Recovering registrar
I1023 04:58:23.591219 2765 registrar.cpp:391] Successfully fetched the
registry (0B) in 644864ns
I1023 04:58:23.591372 2765 registrar.cpp:495] Applied 1 operations in 44931ns;
attempting to update the registry
I1023 04:58:23.592077 2765 registrar.cpp:552] Successfully updated the
registry in 640us
I1023 04:58:23.592242 2765 registrar.cpp:424] Successfully recovered registrar
I1023 04:58:23.592748 2775 master.cpp:1801] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I1023 04:58:23.592810 2760 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
W1023 04:58:23.598645 2754 process.cpp:3196] Attempted to spawn already
running process [email protected]:42906
I1023 04:58:23.599027 2754 cluster.cpp:448] Creating default 'local' authorizer
I1023 04:58:23.601303 2773 slave.cpp:250] Mesos agent started on
(168)@172.17.0.3:42906
I1023 04:58:23.601321 2773 slave.cpp:251] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/SlaveTest_ContainersEndpoint_0ToZHo/store/appc"
--authenticate_http_executors="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local"
--cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false"
--cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs"
--containerizers="mesos"
--credential="/tmp/SlaveTest_ContainersEndpoint_0ToZHo/credential"
--default_role="*" --disallow_sharing_agent_pid_namespace="false"
--disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true"
--docker_registry="https://registry-1.docker.io"; --docker_remove_delay="6hrs"
--docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns"
--docker_store_dir="/tmp/SlaveTest_ContainersEndpoint_0ToZHo/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_secret_key="/tmp/SlaveTest_ContainersEndpoint_0ToZHo/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/SlaveTest_ContainersEndpoint_0ToZHo/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/SlaveTest_ContainersEndpoint_0ToZHo/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO"
--max_completed_executors_per_framework="150"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/SlaveTest_ContainersEndpoint_0ToZHo"
--sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true"
--systemd_enable_support="true"
--systemd_runtime_directory="/run/systemd/system" --version="false"
--work_dir="/tmp/SlaveTest_ContainersEndpoint_Cj0FyD"
I1023 04:58:23.601699 2773 credentials.hpp:86] Loading credential for
authentication from '/tmp/SlaveTest_ContainersEndpoint_0ToZHo/credential'
W1023 04:58:23.601819 2754 process.cpp:3196] Attempted to spawn already
running process [email protected]:42906
I1023 04:58:23.601853 2773 slave.cpp:283] Agent using credential for:
test-principal
I1023 04:58:23.601871 2773 credentials.hpp:37] Loading credentials for
authentication from '/tmp/SlaveTest_ContainersEndpoint_0ToZHo/http_credentials'
I1023 04:58:23.602160 2773 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
I1023 04:58:23.602283 2773 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
I1023 04:58:23.602566 2773 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I1023 04:58:23.602697 2773 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
I1023 04:58:23.602872 2754 sched.cpp:232] Version: 1.4.0
I1023 04:58:23.602962 2773 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I1023 04:58:23.603121 2773 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
I1023 04:58:23.603601 2758 sched.cpp:336] New master detected at
[email protected]:42906
I1023 04:58:23.603729 2758 sched.cpp:407] Authenticating with master
[email protected]:42906
I1023 04:58:23.603749 2758 sched.cpp:414] Using default CRAM-MD5 authenticatee
I1023 04:58:23.604054 2768 authenticatee.cpp:121] Creating new client SASL
connection
I1023 04:58:23.604365 2774 master.cpp:7843] Authenticating
[email protected]:42906
I1023 04:58:23.604537 2776 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(353)@172.17.0.3:42906
I1023 04:58:23.604871 2761 authenticator.cpp:98] Creating new server SASL
connection
I1023 04:58:23.605106 2757 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I1023 04:58:23.605144 2757 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1023 04:58:23.605262 2772 authenticator.cpp:204] Received SASL authentication
start
I1023 04:58:23.605057 2773 slave.cpp:565] 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"}]
I1023 04:58:23.605341 2772 authenticator.cpp:326] Authentication requires more
steps
I1023 04:58:23.605346 2773 slave.cpp:573] Agent attributes: [ ]
I1023 04:58:23.605381 2773 slave.cpp:582] Agent hostname: 62baec6496c1
I1023 04:58:23.605479 2765 authenticatee.cpp:259] Received SASL authentication
step
I1023 04:58:23.605562 2778 status_update_manager.cpp:177] Pausing sending
status updates
I1023 04:58:23.605667 2763 authenticator.cpp:232] Received SASL authentication
step
I1023 04:58:23.605700 2763 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '62baec6496c1' server FQDN: '62baec6496c1'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I1023 04:58:23.605731 2763 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I1023 04:58:23.605782 2763 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1023 04:58:23.605839 2763 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '62baec6496c1' server FQDN: '62baec6496c1'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I1023 04:58:23.605857 2763 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1023 04:58:23.605866 2763 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1023 04:58:23.605908 2763 authenticator.cpp:318] Authentication success
I1023 04:58:23.606045 2760 authenticatee.cpp:299] Authentication success
I1023 04:58:23.606092 2775 master.cpp:7873] Successfully authenticated
principal 'test-principal' at
[email protected]:42906
I1023 04:58:23.606160 2762 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(353)@172.17.0.3:42906
I1023 04:58:23.606458 2763 sched.cpp:513] Successfully authenticated with
master [email protected]:42906
I1023 04:58:23.606478 2763 sched.cpp:836] Sending SUBSCRIBE call to
[email protected]:42906
I1023 04:58:23.606627 2763 sched.cpp:869] Will retry registration in
1.144960913secs if necessary
I1023 04:58:23.606870 2764 master.cpp:2894] Received SUBSCRIBE call for
framework 'default' at
[email protected]:42906
I1023 04:58:23.606966 2764 master.cpp:2228] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I1023 04:58:23.607439 2766 master.cpp:2974] Subscribing framework default with
checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I1023 04:58:23.607547 2755 state.cpp:64] Recovering state from
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/meta'
I1023 04:58:23.607863 2761 status_update_manager.cpp:203] Recovering status
update manager
I1023 04:58:23.608029 2772 sched.cpp:759] Framework registered with
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.608086 2772 sched.cpp:773] Scheduler::registered took 33236ns
I1023 04:58:23.608250 2759 slave.cpp:6295] Finished recovery
I1023 04:58:23.608322 2774 hierarchical.cpp:303] Added framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.608547 2774 hierarchical.cpp:1925] No allocations performed
I1023 04:58:23.608626 2774 hierarchical.cpp:2015] No inverse offers to send
out!
I1023 04:58:23.608677 2774 hierarchical.cpp:1468] Performed allocation for 0
agents in 201631ns
I1023 04:58:23.608778 2759 slave.cpp:6477] Querying resource estimator for
oversubscribable resources
I1023 04:58:23.609038 2771 status_update_manager.cpp:177] Pausing sending
status updates
I1023 04:58:23.609053 2762 slave.cpp:971] New master detected at
[email protected]:42906
I1023 04:58:23.609154 2762 slave.cpp:1006] Detecting new master
I1023 04:58:23.609364 2762 slave.cpp:6491] Received oversubscribable resources
{} from the resource estimator
I1023 04:58:23.617630 2756 slave.cpp:1033] Authenticating with master
[email protected]:42906
I1023 04:58:23.617738 2756 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I1023 04:58:23.618196 2764 authenticatee.cpp:121] Creating new client SASL
connection
I1023 04:58:23.618763 2769 master.cpp:7843] Authenticating
slave(168)@172.17.0.3:42906
I1023 04:58:23.618988 2758 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(354)@172.17.0.3:42906
I1023 04:58:23.619339 2777 authenticator.cpp:98] Creating new server SASL
connection
I1023 04:58:23.619675 2773 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I1023 04:58:23.619709 2773 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1023 04:58:23.619801 2773 authenticator.cpp:204] Received SASL authentication
start
I1023 04:58:23.619858 2773 authenticator.cpp:326] Authentication requires more
steps
I1023 04:58:23.619983 2776 authenticatee.cpp:259] Received SASL authentication
step
I1023 04:58:23.620147 2768 authenticator.cpp:232] Received SASL authentication
step
I1023 04:58:23.620187 2768 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '62baec6496c1' server FQDN: '62baec6496c1'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I1023 04:58:23.620204 2768 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I1023 04:58:23.620231 2768 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1023 04:58:23.620265 2768 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '62baec6496c1' server FQDN: '62baec6496c1'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I1023 04:58:23.620285 2768 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1023 04:58:23.620295 2768 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1023 04:58:23.620308 2768 authenticator.cpp:318] Authentication success
I1023 04:58:23.620386 2765 authenticatee.cpp:299] Authentication success
I1023 04:58:23.620492 2757 master.cpp:7873] Successfully authenticated
principal 'test-principal' at slave(168)@172.17.0.3:42906
I1023 04:58:23.620555 2766 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(354)@172.17.0.3:42906
I1023 04:58:23.620705 2761 slave.cpp:1128] Successfully authenticated with
master [email protected]:42906
I1023 04:58:23.620898 2761 slave.cpp:1607] Will retry registration in
11.601392ms if necessary
I1023 04:58:23.621058 2774 master.cpp:5714] Received register agent message
from slave(168)@172.17.0.3:42906 (62baec6496c1)
I1023 04:58:23.621182 2774 master.cpp:3803] Authorizing agent with principal
'test-principal'
I1023 04:58:23.621561 2770 master.cpp:5774] Authorized registration of agent
at slave(168)@172.17.0.3:42906 (62baec6496c1)
I1023 04:58:23.621675 2770 master.cpp:5867] Registering agent at
slave(168)@172.17.0.3:42906 (62baec6496c1) with id
0c9daaa8-536e-4970-ab54-98b5dcff334e-S0
I1023 04:58:23.622047 2767 registrar.cpp:495] Applied 1 operations in 50182ns;
attempting to update the registry
I1023 04:58:23.622604 2767 registrar.cpp:552] Successfully updated the
registry in 505088ns
I1023 04:58:23.622959 2764 master.cpp:5914] Admitted agent
0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 at slave(168)@172.17.0.3:42906
(62baec6496c1)
I1023 04:58:23.623919 2758 slave.cpp:4970] Received ping from
slave-observer(162)@172.17.0.3:42906
I1023 04:58:23.624104 2758 slave.cpp:1174] Registered with master
[email protected]:42906; given agent ID 0c9daaa8-536e-4970-ab54-98b5dcff334e-S0
I1023 04:58:23.623909 2764 master.cpp:5945] Registered agent
0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 at slave(168)@172.17.0.3:42906
(62baec6496c1) 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"}]
I1023 04:58:23.624249 2773 status_update_manager.cpp:184] Resuming sending
status updates
I1023 04:58:23.624378 2769 hierarchical.cpp:593] Added agent
0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 (62baec6496c1) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
I1023 04:58:23.624498 2758 slave.cpp:1194] Checkpointing SlaveInfo to
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/meta/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/slave.info'
I1023 04:58:23.625124 2758 slave.cpp:1243] Forwarding total oversubscribed
resources {}
I1023 04:58:23.625377 2758 master.cpp:6694] Received update of agent
0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 at slave(168)@172.17.0.3:42906
(62baec6496c1) with total oversubscribed resources {}
I1023 04:58:23.626127 2769 hierarchical.cpp:2015] No inverse offers to send
out!
I1023 04:58:23.626191 2769 hierarchical.cpp:1468] Performed allocation for 1
agents in 1.573368ms
I1023 04:58:23.626390 2769 hierarchical.cpp:660] Agent
0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 (62baec6496c1) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I1023 04:58:23.626816 2765 master.cpp:7673] Sending 1 offers to framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000 (default) at
[email protected]:42906
I1023 04:58:23.627454 2765 sched.cpp:933] Scheduler::resourceOffers took
144041ns
I1023 04:58:23.629717 2774 master.cpp:9170] Removing offer
0c9daaa8-536e-4970-ab54-98b5dcff334e-O0
I1023 04:58:23.629875 2774 master.cpp:4153] Processing ACCEPT call for offers:
[ 0c9daaa8-536e-4970-ab54-98b5dcff334e-O0 ] on agent
0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 at slave(168)@172.17.0.3:42906
(62baec6496c1) for framework 0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
(default) at [email protected]:42906
I1023 04:58:23.629969 2774 master.cpp:3530] Authorizing framework principal
'test-principal' to launch task 1
I1023 04:58:23.630187 2774 master.cpp:3530] Authorizing framework principal
'test-principal' to launch task 2
W1023 04:58:23.631793 2763 validation.cpp:1294] 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.
W1023 04:58:23.631824 2763 validation.cpp:1306] 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.
I1023 04:58:23.632200 2763 master.cpp:9730] 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 0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 at
slave(168)@172.17.0.3:42906 (62baec6496c1)
I1023 04:58:23.632478 2763 master.cpp:4816] Launching task 1 of framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000 (default) at
[email protected]:42906 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 0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 at
slave(168)@172.17.0.3:42906 (62baec6496c1)
I1023 04:58:23.632961 2762 slave.cpp:1736] Got assigned task '1' for framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
W1023 04:58:23.633450 2763 validation.cpp:1294] 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.
W1023 04:58:23.633477 2763 validation.cpp:1306] 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.
I1023 04:58:23.633549 2762 slave.cpp:2003] Authorizing task '1' for framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.633594 2762 slave.cpp:6794] Authorizing framework principal
'test-principal' to launch task 1
I1023 04:58:23.633841 2763 master.cpp:9730] 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 0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 at
slave(168)@172.17.0.3:42906 (62baec6496c1)
I1023 04:58:23.634136 2763 master.cpp:4816] Launching task 2 of framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000 (default) at
[email protected]:42906 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 0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 at
slave(168)@172.17.0.3:42906 (62baec6496c1)
I1023 04:58:23.634702 2755 slave.cpp:1736] Got assigned task '2' for framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.635443 2755 slave.cpp:2171] Launching task '1' for framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.636159 2755 paths.cpp:578] Trying to chown
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/frameworks/0c9daaa8-536e-4970-ab54-98b5dcff334e-0000/executors/executor-1/runs/3e9f54cd-d07f-477c-96b7-e2245ce1039b'
to user 'mesos'
I1023 04:58:23.636472 2755 slave.cpp:7256] Launching executor 'executor-1' of
framework 0c9daaa8-536e-4970-ab54-98b5dcff334e-0000 with resources [] in work
directory
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/frameworks/0c9daaa8-536e-4970-ab54-98b5dcff334e-0000/executors/executor-1/runs/3e9f54cd-d07f-477c-96b7-e2245ce1039b'
I1023 04:58:23.636559 2764 hierarchical.cpp:887] Updated allocation of
framework 0c9daaa8-536e-4970-ab54-98b5dcff334e-0000 on agent
0c9daaa8-536e-4970-ab54-98b5dcff334e-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]
I1023 04:58:23.637413 2764 hierarchical.cpp:1152] 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 0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 from framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.637476 2764 hierarchical.cpp:1189] Framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000 filtered agent
0c9daaa8-536e-4970-ab54-98b5dcff334e-S0 for 5secs
I1023 04:58:23.637500 2755 slave.cpp:2400] Queued task '1' for executor
'executor-1' of framework 0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.637650 2755 slave.cpp:2003] Authorizing task '2' for framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.637730 2755 slave.cpp:6794] Authorizing framework principal
'test-principal' to launch task 2
I1023 04:58:23.638330 2755 slave.cpp:924] Successfully attached file
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/frameworks/0c9daaa8-536e-4970-ab54-98b5dcff334e-0000/executors/executor-1/runs/3e9f54cd-d07f-477c-96b7-e2245ce1039b'
I1023 04:58:23.638458 2755 slave.cpp:924] Successfully attached file
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/frameworks/0c9daaa8-536e-4970-ab54-98b5dcff334e-0000/executors/executor-1/runs/3e9f54cd-d07f-477c-96b7-e2245ce1039b'
I1023 04:58:23.638871 2755 slave.cpp:2858] Launching container
3e9f54cd-d07f-477c-96b7-e2245ce1039b for executor 'executor-1' of framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.639354 2755 slave.cpp:2171] Launching task '2' for framework
0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.639874 2755 paths.cpp:578] Trying to chown
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/frameworks/0c9daaa8-536e-4970-ab54-98b5dcff334e-0000/executors/executor-2/runs/152aa82c-01d1-47f9-9d3b-c9b8f6000e0b'
to user 'mesos'
I1023 04:58:23.640153 2755 slave.cpp:7256] Launching executor 'executor-2' of
framework 0c9daaa8-536e-4970-ab54-98b5dcff334e-0000 with resources [] in work
directory
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/frameworks/0c9daaa8-536e-4970-ab54-98b5dcff334e-0000/executors/executor-2/runs/152aa82c-01d1-47f9-9d3b-c9b8f6000e0b'
I1023 04:58:23.641080 2755 slave.cpp:2400] Queued task '2' for executor
'executor-2' of framework 0c9daaa8-536e-4970-ab54-98b5dcff334e-0000
I1023 04:58:23.641196 2755 slave.cpp:924] Successfully attached file
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/frameworks/0c9daaa8-536e-4970-ab54-98b5dcff334e-0000/executors/executor-2/runs/152aa82c-01d1-47f9-9d3b-c9b8f6000e0b'
I1023 04:58:23.641240 2755 slave.cpp:924] Successfully attached file
'/tmp/SlaveTest_ContainersEndpoint_Cj0FyD/slaves/0c9daaa8-536e-4970-ab54-98b5dcff334e-S0/frameworks/0c9daaa8-536e-4970-ab54-98b5dcff334e-0000/executors/executor-2/runs/152aa82c-01d1-47f9-9d3b-c9b8f6000e0b'
*** Aborted at 1508734703 (unix time) try "date -d @1508734703" if you are
using GNU date ***
PC: @ 0x2aab73d8182d (unknown)
W1023 04:58:23.641849 2772 process.cpp:3196] Attempted to spawn already
running process [email protected]:42906
I1023 04:58:23.642124 2772 exec.cpp:162] Version: 1.4.0
*** SIGSEGV (@0xc0) received by PID 2754 (TID 0x2aab7c783700) from PID 192;
stack trace: ***
@ 0x2aab73318330 (unknown)
I1023 04:58:23.642459 2760 exec.cpp:212] Executor started at:
executor(48)@172.17.0.3:42906 with pid 2754
@ 0x2aab73d8182d (unknown)
@ 0x2794fba os::getenv()
@ 0x2aab6dd68244 mesos::internal::slave::executorEnvironment()
@ 0x2aab6dd3da7f mesos::internal::slave::Slave::launchExecutor()
@ 0x2aab6ddb9e5e
_ZZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS_6FutureINS1_6SecretEEEERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKS5_INS1_8TaskInfoEESB_SE_SH_SL_EEvRKNS_3PIDIT_EEMSN_FvT0_T1_T2_T3_EOT4_OT5_OT6_OT7_ENKUlRS9_RSC_RSF_RSJ_PNS_11ProcessBaseEE_clES15_S16_S17_S18_S1A_
@ 0x2aab6de43f84
_ZNSt5_BindIFZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS0_6FutureINS2_6SecretEEEERKNS2_11FrameworkIDERKNS2_10ExecutorIDERKS6_INS2_8TaskInfoEESC_SF_SI_SM_EEvRKNS0_3PIDIT_EEMSO_FvT0_T1_T2_T3_EOT4_OT5_OT6_OT7_EUlRSA_RSD_RSG_RSK_PNS0_11ProcessBaseEE_SA_SD_SG_SK_St12_PlaceholderILi1EEEE6__callIvIOS1B_EILm0ELm1ELm2ELm3ELm4EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE
@ 0x2aab6de2b575
_ZNSt5_BindIFZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS0_6FutureINS2_6SecretEEEERKNS2_11FrameworkIDERKNS2_10ExecutorIDERKS6_INS2_8TaskInfoEESC_SF_SI_SM_EEvRKNS0_3PIDIT_EEMSO_FvT0_T1_T2_T3_EOT4_OT5_OT6_OT7_EUlRSA_RSD_RSG_RSK_PNS0_11ProcessBaseEE_SA_SD_SG_SK_St12_PlaceholderILi1EEEEclIIS1B_EvEET0_DpOT_
@ 0x2aab6de0bdf7
_ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS0_6FutureINS6_6SecretEEEERKNS6_11FrameworkIDERKNS6_10ExecutorIDERKSA_INS6_8TaskInfoEESG_SJ_SM_SQ_EEvRKNS0_3PIDIT_EEMSS_FvT0_T1_T2_T3_EOT4_OT5_OT6_OT7_EUlRSE_RSH_RSK_RSO_S2_E_SE_SH_SK_SO_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_
@ 0x2aab70c09127 std::function<>::operator()()
@ 0x2aab70be29fb process::ProcessBase::visit()
@ 0x2aab70bf051c process::DispatchEvent::visit()
@ 0x26af60e process::ProcessBase::serve()
@ 0x2aab70be0073 process::ProcessManager::resume()
@ 0x2aab70bdc4f0
_ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2aab70bee5b4
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2aab70bee50b
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2aab70bee4a4
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2aab735d7a60 (unknown)
@ 0x2aab73310184 start_thread
@ 0x2aab73e43ffd (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
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-1508732029-14210
Untagged: mesos-1508732029-14210:latest
Deleted: sha256:e4a3cfbe309e1fa37995563a508f61ab0be9385bd5e170b7b0aaf497fa7bd0ab
Deleted: sha256:27c9e9e58c9d4167044df1df5159cc97adb885bf66db2ce897881245504654b8
Deleted: sha256:cc0c90666efe08e0c2fc95f663b7f10cc0894118692f177c2d69ecacaa2abd74
Deleted: sha256:1da820b02dce6bf50f4fd80be158d0fb3b647ff2f4fed773eb48e770b79512c1
Deleted: sha256:0e01a5d3456a51b62e2c245b46b0751dafaf992239055b127177dab271e2fdc2
Deleted: sha256:289ab2032cb47a9db148c2e29f902b4cbaf1f3fcc9a0f01301e4ce3636b406fd
Deleted: sha256:f95974e0e046fee00391050ba3dc11b22b2fd4d8a463d2876629803d3905620f
Deleted: sha256:77536f09313e107c8f9222244802f717a9b5eb9d5cf30cc27f3aee29e02f2ae5
Deleted: sha256:fb6bffdb49115f63cb7500eef0b0656d51a26b2e7374a17685be5932fc631d0c
Deleted: sha256:cdc83fb3d13bb094cbbe4e4a556158859671cdc5a185a722cf8da83e3e5d7b57
Deleted: sha256:0de363c34941edb2285dc914266f44d5cd2e45119aef930c3cd28f74be2fc0c5
Deleted: sha256:ef8bad312b8a10df51434cbbba38af977ef6b3efcc7e0995828655e13c1e7026
Deleted: sha256:9e4ef707b0b92f6cea1e4816a0982b21006d0eebbf3d32cbfb03bfe96fa4bdf4
Deleted: sha256:bbcd25f8d7c62e2a0655bfc84b101bf38fc5d47445d50d381c9cd6812f96189f
Deleted: sha256:aa3115b86ff21a99f4a15ab75048f9c726c3b582a8f98b4f0878c84344b52291
Deleted: sha256:5224349c511357cb49e6f8c218696e5f73dbe891e6a393ff8698b7042fbf5e60
Deleted: sha256:9559a24208e4e6c903888b4b068fcfb4aa3139378ff5eb27c90bf7bf07ccbc66
Deleted: sha256:bff51c67751604d26d22486aa5b423d5b8f94fda9a208994391f6c8cda099bc7
Deleted: sha256:cc7c80aa34cf751509205097d1b5f98c8b7194b7e58c3f240e5c8ffae2e136b6
Deleted: sha256:4bd5b1731a12ac0b3da85001373482edc463699c07c569fbf1a358f92ff74787
Deleted: sha256:e32ad22171810eeb9798bf9aa369757d9068610da725d772960fea6f045b613f
Deleted: sha256:8f4d408226e444a7641d33c1cd7de023daf48d9af20d296e6372fce85291ced9
Deleted: sha256:8aebd68289263ea6a4e819f4308a643d954b8181b16baf4311bba2e2b0faaf44
Deleted: sha256:87adbec3ae39d92082b29a7f84232c5633cf204e433e5f190ddc363c3b32105f
Build step 'Execute shell' marked build as failure
