See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4239/display/redirect>
------------------------------------------
[...truncated 22.25 MB...]
I0919 11:40:07.910229 5804 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0919 11:40:07.910441 5804 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0919 11:40:07.913013 5804 slave.cpp:583] 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"}]
I0919 11:40:07.913444 5804 slave.cpp:591] Agent attributes: [ ]
I0919 11:40:07.913483 5804 slave.cpp:600] Agent hostname: 40c2ed1c5dd7
I0919 11:40:07.913676 5794 status_update_manager.cpp:177] Pausing sending
status updates
I0919 11:40:07.916262 5798 state.cpp:64] Recovering state from
'/tmp/SlaveTest_ContainersEndpointNoExecutor_BIntAV/meta'
I0919 11:40:07.916312 5804 process.cpp:3929] Handling HTTP event for process
'slave(531)' with path: '/slave(531)/containers'
I0919 11:40:07.917227 5790 status_update_manager.cpp:203] Recovering status
update manager
I0919 11:40:07.917649 5804 containerizer.cpp:648] Recovering containerizer
I0919 11:40:07.918318 5799 http.cpp:1185] HTTP GET for /slave(531)/containers
from 172.17.0.3:43102
I0919 11:40:07.918419 5799 http.cpp:976] Authorizing principal
'test-principal' to GET the '/containers' endpoint
I0919 11:40:07.919272 5798 provisioner.cpp:416] Provisioner recovery complete
I0919 11:40:07.920279 5793 slave.cpp:6298] Finished recovery
I0919 11:40:07.920920 5793 slave.cpp:6480] Querying resource estimator for
oversubscribable resources
I0919 11:40:07.921273 5806 status_update_manager.cpp:177] Pausing sending
status updates
I0919 11:40:07.921298 5797 slave.cpp:989] New master detected at
master@172.17.0.3:34180
I0919 11:40:07.921424 5797 slave.cpp:1024] Detecting new master
I0919 11:40:07.921592 5797 slave.cpp:6494] Received oversubscribable resources
{} from the resource estimator
I0919 11:40:07.923030 5807 slave.cpp:861] Agent terminating
I0919 11:40:07.929728 5771 master.cpp:1163] Master terminating
[ OK ] SlaveTest.ContainersEndpointNoExecutor (53 ms)
[ RUN ] SlaveTest.ContainersEndpoint
I0919 11:40:07.936419 5771 cluster.cpp:162] Creating default 'local' authorizer
I0919 11:40:07.939780 5809 master.cpp:445] Master
9077c136-aa58-41b6-8537-21bb41558a28 (40c2ed1c5dd7) started on 172.17.0.3:34180
I0919 11:40:07.939810 5809 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/1Dn2dr/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.5.0/_inst/share/mesos/webui"
--work_dir="/tmp/1Dn2dr/master" --zk_session_timeout="10secs"
I0919 11:40:07.940088 5809 master.cpp:497] Master only allowing authenticated
frameworks to register
I0919 11:40:07.940101 5809 master.cpp:511] Master only allowing authenticated
agents to register
I0919 11:40:07.940109 5809 master.cpp:524] Master only allowing authenticated
HTTP frameworks to register
I0919 11:40:07.940119 5809 credentials.hpp:37] Loading credentials for
authentication from '/tmp/1Dn2dr/credentials'
I0919 11:40:07.940389 5809 master.cpp:569] Using default 'crammd5'
authenticator
I0919 11:40:07.940533 5809 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0919 11:40:07.940716 5809 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0919 11:40:07.940860 5809 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0919 11:40:07.940985 5809 master.cpp:649] Authorization enabled
I0919 11:40:07.941156 5800 hierarchical.cpp:171] Initialized hierarchical
allocator process
I0919 11:40:07.941220 5796 whitelist_watcher.cpp:77] No whitelist given
I0919 11:40:07.943733 5809 master.cpp:2166] Elected as the leading master!
I0919 11:40:07.943765 5809 master.cpp:1705] Recovering from registrar
I0919 11:40:07.943902 5791 registrar.cpp:347] Recovering registrar
I0919 11:40:07.944586 5791 registrar.cpp:391] Successfully fetched the
registry (0B) in 637184ns
I0919 11:40:07.944748 5791 registrar.cpp:495] Applied 1 operations in 23067ns;
attempting to update the registry
I0919 11:40:07.945297 5791 registrar.cpp:552] Successfully updated the
registry in 489984ns
I0919 11:40:07.945425 5791 registrar.cpp:424] Successfully recovered registrar
I0919 11:40:07.945808 5807 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
I0919 11:40:07.945812 5795 master.cpp:1804] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
W0919 11:40:07.950479 5771 process.cpp:3194] Attempted to spawn already
running process files@172.17.0.3:34180
I0919 11:40:07.950762 5771 cluster.cpp:448] Creating default 'local' authorizer
I0919 11:40:07.952780 5792 slave.cpp:252] Mesos agent started on
(532)@172.17.0.3:34180
I0919 11:40:07.952816 5792 slave.cpp:253] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/SlaveTest_ContainersEndpoint_RxjtNr/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/SlaveTest_ContainersEndpoint_RxjtNr/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_RxjtNr/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/SlaveTest_ContainersEndpoint_RxjtNr/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_RxjtNr/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/mesos-1.5.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/SlaveTest_ContainersEndpoint_RxjtNr"
--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_I5gKvW"
--zk_session_timeout="10secs"
I0919 11:40:07.953295 5792 credentials.hpp:86] Loading credential for
authentication from '/tmp/SlaveTest_ContainersEndpoint_RxjtNr/credential'
W0919 11:40:07.953387 5771 process.cpp:3194] Attempted to spawn already
running process version@172.17.0.3:34180
I0919 11:40:07.953465 5792 slave.cpp:285] Agent using credential for:
test-principal
I0919 11:40:07.953490 5792 credentials.hpp:37] Loading credentials for
authentication from '/tmp/SlaveTest_ContainersEndpoint_RxjtNr/http_credentials'
I0919 11:40:07.953769 5792 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0919 11:40:07.953943 5792 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0919 11:40:07.954361 5771 sched.cpp:232] Version: 1.5.0
I0919 11:40:07.954886 5801 sched.cpp:336] New master detected at
master@172.17.0.3:34180
I0919 11:40:07.954979 5801 sched.cpp:407] Authenticating with master
master@172.17.0.3:34180
I0919 11:40:07.955013 5801 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0919 11:40:07.955265 5803 authenticatee.cpp:121] Creating new client SASL
connection
I0919 11:40:07.955598 5800 master.cpp:7895] Authenticating
scheduler-e77c67a0-076a-437c-8095-5c94c14e9e7d@172.17.0.3:34180
I0919 11:40:07.955425 5792 slave.cpp:583] 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"}]
I0919 11:40:07.955718 5792 slave.cpp:591] Agent attributes: [ ]
I0919 11:40:07.955739 5792 slave.cpp:600] Agent hostname: 40c2ed1c5dd7
I0919 11:40:07.955736 5796 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1069)@172.17.0.3:34180
I0919 11:40:07.955904 5800 status_update_manager.cpp:177] Pausing sending
status updates
I0919 11:40:07.956004 5810 authenticator.cpp:98] Creating new server SASL
connection
I0919 11:40:07.956254 5811 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0919 11:40:07.956300 5811 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0919 11:40:07.956472 5811 authenticator.cpp:204] Received SASL authentication
start
I0919 11:40:07.956560 5811 authenticator.cpp:326] Authentication requires more
steps
I0919 11:40:07.956702 5813 authenticatee.cpp:259] Received SASL authentication
step
I0919 11:40:07.956861 5802 authenticator.cpp:232] Received SASL authentication
step
I0919 11:40:07.956900 5802 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '40c2ed1c5dd7' server FQDN: '40c2ed1c5dd7'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0919 11:40:07.956918 5802 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0919 11:40:07.956959 5802 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0919 11:40:07.956990 5802 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '40c2ed1c5dd7' server FQDN: '40c2ed1c5dd7'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0919 11:40:07.957003 5802 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0919 11:40:07.957012 5802 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0919 11:40:07.957031 5802 authenticator.cpp:318] Authentication success
I0919 11:40:07.957123 5793 authenticatee.cpp:299] Authentication success
I0919 11:40:07.957182 5808 master.cpp:7925] Successfully authenticated
principal 'test-principal' at
scheduler-e77c67a0-076a-437c-8095-5c94c14e9e7d@172.17.0.3:34180
I0919 11:40:07.957231 5791 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1069)@172.17.0.3:34180
I0919 11:40:07.957469 5802 sched.cpp:513] Successfully authenticated with
master master@172.17.0.3:34180
I0919 11:40:07.957495 5802 sched.cpp:836] Sending SUBSCRIBE call to
master@172.17.0.3:34180
I0919 11:40:07.957609 5802 sched.cpp:869] Will retry registration in
1.97274368secs if necessary
I0919 11:40:07.957701 5807 state.cpp:64] Recovering state from
'/tmp/SlaveTest_ContainersEndpoint_I5gKvW/meta'
I0919 11:40:07.957823 5804 master.cpp:2897] Received SUBSCRIBE call for
framework 'default' at
scheduler-e77c67a0-076a-437c-8095-5c94c14e9e7d@172.17.0.3:34180
I0919 11:40:07.957945 5804 master.cpp:2231] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0919 11:40:07.958031 5807 status_update_manager.cpp:203] Recovering status
update manager
I0919 11:40:07.958425 5800 master.cpp:2977] Subscribing framework default with
checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0919 11:40:07.958441 5796 slave.cpp:6298] Finished recovery
I0919 11:40:07.958982 5796 slave.cpp:6480] Querying resource estimator for
oversubscribable resources
I0919 11:40:07.959015 5809 sched.cpp:759] Framework registered with
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.959070 5809 sched.cpp:773] Scheduler::registered took 27858ns
I0919 11:40:07.959211 5806 hierarchical.cpp:303] Added framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.959259 5799 slave.cpp:989] New master detected at
master@172.17.0.3:34180
I0919 11:40:07.959269 5798 status_update_manager.cpp:177] Pausing sending
status updates
I0919 11:40:07.959363 5799 slave.cpp:1024] Detecting new master
I0919 11:40:07.959420 5806 hierarchical.cpp:1943] No allocations performed
I0919 11:40:07.959465 5806 hierarchical.cpp:2033] No inverse offers to send
out!
I0919 11:40:07.959491 5799 slave.cpp:6494] Received oversubscribable resources
{} from the resource estimator
I0919 11:40:07.959519 5806 hierarchical.cpp:1486] Performed allocation for 0
agents in 135793ns
I0919 11:40:07.966614 5812 slave.cpp:1051] Authenticating with master
master@172.17.0.3:34180
I0919 11:40:07.966676 5812 slave.cpp:1062] Using default CRAM-MD5 authenticatee
I0919 11:40:07.966846 5795 authenticatee.cpp:121] Creating new client SASL
connection
I0919 11:40:07.967061 5802 master.cpp:7895] Authenticating
slave(532)@172.17.0.3:34180
I0919 11:40:07.967180 5792 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1070)@172.17.0.3:34180
I0919 11:40:07.967509 5794 authenticator.cpp:98] Creating new server SASL
connection
I0919 11:40:07.967700 5801 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0919 11:40:07.967725 5801 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0919 11:40:07.967814 5804 authenticator.cpp:204] Received SASL authentication
start
I0919 11:40:07.967885 5804 authenticator.cpp:326] Authentication requires more
steps
I0919 11:40:07.967994 5807 authenticatee.cpp:259] Received SASL authentication
step
I0919 11:40:07.968122 5790 authenticator.cpp:232] Received SASL authentication
step
I0919 11:40:07.968152 5790 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '40c2ed1c5dd7' server FQDN: '40c2ed1c5dd7'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0919 11:40:07.968175 5790 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0919 11:40:07.968201 5790 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0919 11:40:07.968224 5790 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '40c2ed1c5dd7' server FQDN: '40c2ed1c5dd7'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0919 11:40:07.968233 5790 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0919 11:40:07.968242 5790 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0919 11:40:07.968261 5790 authenticator.cpp:318] Authentication success
I0919 11:40:07.968348 5810 authenticatee.cpp:299] Authentication success
I0919 11:40:07.968389 5803 master.cpp:7925] Successfully authenticated
principal 'test-principal' at slave(532)@172.17.0.3:34180
I0919 11:40:07.968425 5797 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1070)@172.17.0.3:34180
I0919 11:40:07.968649 5800 slave.cpp:1146] Successfully authenticated with
master master@172.17.0.3:34180
I0919 11:40:07.968873 5800 slave.cpp:1625] Will retry registration in
2.181181ms if necessary
I0919 11:40:07.969050 5796 master.cpp:5769] Received register agent message
from slave(532)@172.17.0.3:34180 (40c2ed1c5dd7)
I0919 11:40:07.969177 5796 master.cpp:3806] Authorizing agent with principal
'test-principal'
I0919 11:40:07.969557 5793 master.cpp:5829] Authorized registration of agent
at slave(532)@172.17.0.3:34180 (40c2ed1c5dd7)
I0919 11:40:07.969660 5793 master.cpp:5922] Registering agent at
slave(532)@172.17.0.3:34180 (40c2ed1c5dd7) with id
9077c136-aa58-41b6-8537-21bb41558a28-S0
I0919 11:40:07.970067 5808 registrar.cpp:495] Applied 1 operations in 52702ns;
attempting to update the registry
I0919 11:40:07.970631 5808 registrar.cpp:552] Successfully updated the
registry in 507136ns
I0919 11:40:07.970839 5812 master.cpp:5969] Admitted agent
9077c136-aa58-41b6-8537-21bb41558a28-S0 at slave(532)@172.17.0.3:34180
(40c2ed1c5dd7)
I0919 11:40:07.971335 5802 slave.cpp:4965] Received ping from
slave-observer(506)@172.17.0.3:34180
I0919 11:40:07.971493 5802 slave.cpp:1192] Registered with master
master@172.17.0.3:34180; given agent ID 9077c136-aa58-41b6-8537-21bb41558a28-S0
I0919 11:40:07.971374 5812 master.cpp:6000] Registered agent
9077c136-aa58-41b6-8537-21bb41558a28-S0 at slave(532)@172.17.0.3:34180
(40c2ed1c5dd7) 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"}]
I0919 11:40:07.971619 5801 status_update_manager.cpp:184] Resuming sending
status updates
I0919 11:40:07.971681 5792 hierarchical.cpp:593] Added agent
9077c136-aa58-41b6-8537-21bb41558a28-S0 (40c2ed1c5dd7) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
I0919 11:40:07.971837 5802 slave.cpp:1212] Checkpointing SlaveInfo to
'/tmp/SlaveTest_ContainersEndpoint_I5gKvW/meta/slaves/9077c136-aa58-41b6-8537-21bb41558a28-S0/slave.info'
I0919 11:40:07.972208 5802 slave.cpp:1261] Forwarding total oversubscribed
resources {}
I0919 11:40:07.972358 5804 master.cpp:6746] Received update of agent
9077c136-aa58-41b6-8537-21bb41558a28-S0 at slave(532)@172.17.0.3:34180
(40c2ed1c5dd7) with total oversubscribed resources {}
I0919 11:40:07.972769 5792 hierarchical.cpp:2033] No inverse offers to send
out!
I0919 11:40:07.972817 5792 hierarchical.cpp:1486] Performed allocation for 1
agents in 989660ns
I0919 11:40:07.972955 5792 hierarchical.cpp:660] Agent
9077c136-aa58-41b6-8537-21bb41558a28-S0 (40c2ed1c5dd7) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0919 11:40:07.973282 5807 master.cpp:7725] Sending 1 offers to framework
9077c136-aa58-41b6-8537-21bb41558a28-0000 (default) at
scheduler-e77c67a0-076a-437c-8095-5c94c14e9e7d@172.17.0.3:34180
I0919 11:40:07.973745 5790 sched.cpp:933] Scheduler::resourceOffers took
101164ns
I0919 11:40:07.975778 5796 master.cpp:9225] Removing offer
9077c136-aa58-41b6-8537-21bb41558a28-O0
I0919 11:40:07.975920 5796 master.cpp:4164] Processing ACCEPT call for offers:
[ 9077c136-aa58-41b6-8537-21bb41558a28-O0 ] on agent
9077c136-aa58-41b6-8537-21bb41558a28-S0 at slave(532)@172.17.0.3:34180
(40c2ed1c5dd7) for framework 9077c136-aa58-41b6-8537-21bb41558a28-0000
(default) at scheduler-e77c67a0-076a-437c-8095-5c94c14e9e7d@172.17.0.3:34180
I0919 11:40:07.976016 5796 master.cpp:3533] Authorizing framework principal
'test-principal' to launch task 1
I0919 11:40:07.976218 5796 master.cpp:3533] Authorizing framework principal
'test-principal' to launch task 2
W0919 11:40:07.977794 5796 validation.cpp:1332] 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.
W0919 11:40:07.977829 5796 validation.cpp:1344] 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.
I0919 11:40:07.978164 5796 master.cpp:9971] 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 9077c136-aa58-41b6-8537-21bb41558a28-S0 at
slave(532)@172.17.0.3:34180 (40c2ed1c5dd7)
I0919 11:40:07.978480 5796 master.cpp:4847] Launching task 1 of framework
9077c136-aa58-41b6-8537-21bb41558a28-0000 (default) at
scheduler-e77c67a0-076a-437c-8095-5c94c14e9e7d@172.17.0.3:34180 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 9077c136-aa58-41b6-8537-21bb41558a28-S0 at
slave(532)@172.17.0.3:34180 (40c2ed1c5dd7)
I0919 11:40:07.978951 5798 slave.cpp:1746] Got assigned task '1' for framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
W0919 11:40:07.979440 5796 validation.cpp:1332] 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.
W0919 11:40:07.979470 5796 validation.cpp:1344] 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.
I0919 11:40:07.979593 5798 slave.cpp:2014] Authorizing task '1' for framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.979662 5798 slave.cpp:6797] Authorizing framework principal
'test-principal' to launch task 1
I0919 11:40:07.979804 5796 master.cpp:9971] 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 9077c136-aa58-41b6-8537-21bb41558a28-S0 at
slave(532)@172.17.0.3:34180 (40c2ed1c5dd7)
I0919 11:40:07.980243 5796 master.cpp:4847] Launching task 2 of framework
9077c136-aa58-41b6-8537-21bb41558a28-0000 (default) at
scheduler-e77c67a0-076a-437c-8095-5c94c14e9e7d@172.17.0.3:34180 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 9077c136-aa58-41b6-8537-21bb41558a28-S0 at
slave(532)@172.17.0.3:34180 (40c2ed1c5dd7)
I0919 11:40:07.980383 5795 slave.cpp:2182] Launching task '1' for framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.980949 5795 paths.cpp:580] Trying to chown
'/tmp/SlaveTest_ContainersEndpoint_I5gKvW/slaves/9077c136-aa58-41b6-8537-21bb41558a28-S0/frameworks/9077c136-aa58-41b6-8537-21bb41558a28-0000/executors/executor-1/runs/887ef4e0-2b7d-4a29-8f9b-71fe4a71d9df'
to user 'mesos'
I0919 11:40:07.981174 5795 slave.cpp:7259] Launching executor 'executor-1' of
framework 9077c136-aa58-41b6-8537-21bb41558a28-0000 with resources [] in work
directory
'/tmp/SlaveTest_ContainersEndpoint_I5gKvW/slaves/9077c136-aa58-41b6-8537-21bb41558a28-S0/frameworks/9077c136-aa58-41b6-8537-21bb41558a28-0000/executors/executor-1/runs/887ef4e0-2b7d-4a29-8f9b-71fe4a71d9df'
I0919 11:40:07.981572 5795 slave.cpp:2873] Launching container
887ef4e0-2b7d-4a29-8f9b-71fe4a71d9df for executor 'executor-1' of framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.981859 5795 slave.cpp:2410] Queued task '1' for executor
'executor-1' of framework 9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.982087 5795 slave.cpp:1746] Got assigned task '2' for framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.982089 5812 hierarchical.cpp:887] Updated allocation of
framework 9077c136-aa58-41b6-8537-21bb41558a28-0000 on agent
9077c136-aa58-41b6-8537-21bb41558a28-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]
I0919 11:40:07.982470 5795 slave.cpp:942] Successfully attached file
'/tmp/SlaveTest_ContainersEndpoint_I5gKvW/slaves/9077c136-aa58-41b6-8537-21bb41558a28-S0/frameworks/9077c136-aa58-41b6-8537-21bb41558a28-0000/executors/executor-1/runs/887ef4e0-2b7d-4a29-8f9b-71fe4a71d9df'
I0919 11:40:07.982516 5795 slave.cpp:942] Successfully attached file
'/tmp/SlaveTest_ContainersEndpoint_I5gKvW/slaves/9077c136-aa58-41b6-8537-21bb41558a28-S0/frameworks/9077c136-aa58-41b6-8537-21bb41558a28-0000/executors/executor-1/runs/887ef4e0-2b7d-4a29-8f9b-71fe4a71d9df'
I0919 11:40:07.982590 5795 slave.cpp:2014] Authorizing task '2' for framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.982636 5795 slave.cpp:6797] Authorizing framework principal
'test-principal' to launch task 2
I0919 11:40:07.982720 5812 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 9077c136-aa58-41b6-8537-21bb41558a28-S0 from framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.982779 5812 hierarchical.cpp:1207] Framework
9077c136-aa58-41b6-8537-21bb41558a28-0000 filtered agent
9077c136-aa58-41b6-8537-21bb41558a28-S0 for 5secs
I0919 11:40:07.983306 5790 slave.cpp:2182] Launching task '2' for framework
9077c136-aa58-41b6-8537-21bb41558a28-0000
I0919 11:40:07.983700 5790 paths.cpp:580] Trying to chown
'/tmp/SlaveTest_ContainersEndpoint_I5gKvW/slaves/9077c136-aa58-41b6-8537-21bb41558a28-S0/frameworks/9077c136-aa58-41b6-8537-21bb41558a28-0000/executors/executor-2/runs/d4fb336e-42ee-41dc-8cd0-0e9d0f4c8184'
to user 'mesos'
I0919 11:40:07.983904 5790 slave.cpp:7259] Launching executor 'executor-2' of
framework 9077c136-aa58-41b6-8537-21bb41558a28-0000 with resources [] in work
directory
'/tmp/SlaveTest_ContainersEndpoint_I5gKvW/slaves/9077c136-aa58-41b6-8537-21bb41558a28-S0/frameworks/9077c136-aa58-41b6-8537-21bb41558a28-0000/executors/executor-2/runs/d4fb336e-42ee-41dc-8cd0-0e9d0f4c8184'
*** Aborted at 1505821207 (unix time) try "date -d @1505821207" if you are
using GNU date ***
PC: @ 0x2b6bd110482d (unknown)
*** SIGSEGV (@0x20) received by PID 5771 (TID 0x2b6bd9425700) from PID 32;
stack trace: ***
W0919 11:40:07.984887 5802 process.cpp:3194] Attempted to spawn already
running process version@172.17.0.3:34180
I0919 11:40:07.985337 5802 exec.cpp:162] Version: 1.5.0
I0919 11:40:07.985873 5811 exec.cpp:212] Executor started at:
executor(135)@172.17.0.3:34180 with pid 5771
@ 0x2b6bdec07af7 (unknown)
@ 0x2b6bdec0c449 (unknown)
@ 0x2b6bd0ebb330 (unknown)
@ 0x2b6bd110482d (unknown)
@ 0x562d9c4b8a1d os::getenv()
@ 0x2b6bcd8adf32 mesos::internal::slave::executorEnvironment()
@ 0x2b6bcd8836e1 mesos::internal::slave::Slave::launchExecutor()
@ 0x2b6bcd87ec8a mesos::internal::slave::Slave::__run()
@ 0x2b6bcd8e4bc2
_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_
@ 0x2b6bcd9515eb
_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_EJLm0ELm1ELm2ELm3ELm4ELm5EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
@ 0x2b6bcd93d7c1
_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_PlaceholderILi1EEEEclIIS1K_EvEET0_DpOT_
@ 0x2b6bcd925293
_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_
@ 0x2b6bce7d20ef std::function<>::operator()()
@ 0x2b6bce7b32f1 process::ProcessBase::visit()
@ 0x2b6bce7bf620 process::DispatchEvent::visit()
@ 0x562d9c48c096 process::ProcessBase::serve()
@ 0x2b6bce7b08e3 process::ProcessManager::resume()
@ 0x2b6bce7acddc
_ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b6bce7be62a
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b6bce7be581
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b6bce7be51a
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2b6bd073ca60 (unknown)
@ 0x2b6bd0eb3184 start_thread
@ 0x2b6bd11c6ffd (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.5.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1505818218-15587
Untagged: mesos-1505818218-15587:latest
Deleted: sha256:4ad4e488bf0b8cded8f00563d8a8d5da536f3285c8d4718cd7cb9b9a756cb9b6
Deleted: sha256:2d771814b0d8b91ea716dc348ba9ac36128b95df12397cf8e6bc25e38fcde412
Deleted: sha256:8b10b0e26c229e722e23da82e8a8873e83a11f60449459414558296d6c0b54f1
Deleted: sha256:212c1834acb4422d1ea446dbbf9cc97df9330e0923bc230b1d642bb82706684f
Deleted: sha256:66dfe181292c9aa5ff5cd5a400f921e1589cecd41fa0b1e8e45cb1ba5b580252
Deleted: sha256:1ef42a5f4e7dc5511c09c0f1f6ce659374533dd9c85f317796d972eededfd2fa
Deleted: sha256:63a231f4003e109591fd4148375c27515fc7a59c0b1082ae3db0b2c12e6be1f7
Deleted: sha256:d22afe2923d9f3ff991bbfd41e4bdd58d03e113e06356d061b0fa9c71a9bf542
Deleted: sha256:d4c505f24b388b70ff3ac6e7a57c2e8bcc46383a29fa6692fa30de1bceb7b8dc
Deleted: sha256:64b442fb1f688399da381109d53399b93f44ebed2e4461963d32dbe68f30f5af
Deleted: sha256:18e843e9c48ddc7f7fee66881f4a786484a2f61ba21eb197e45188b358ddf36e
Deleted: sha256:497ec0f249498279c7f4e12798bec924fca57e61083fdfadff3983b9f5c7e660
Deleted: sha256:ddd00f71f5138f834b268cd446dd593a4e300586134e9e14e06e73f207e6e678
Deleted: sha256:aafc16eb09e9f45e768f493f3f3ed9fcdf5b3eefad80decea6ea21eb6f3f277f
Deleted: sha256:c8cd8a06feded9e3f4fd7d6ff5b985f1986140f641371a3bd05c43997684826c
Deleted: sha256:e7b37bde2cb14fa7da5413da3042b3df8ec79ed09d9918c4ea33229240915b8c
Deleted: sha256:080115156f7a7e06e68db78ac9a831567ab80066f8ea93a1b90d7f0bb1515cbe
Deleted: sha256:fa76716e18b93da2a2f4bebaf447b17cf6284f8489ed010e2e5cad10c44c108b
Deleted: sha256:618884484ba8e2a51260659998f1c68091a3f32d0b6142006f6c113ecec91e51
Deleted: sha256:ac480512c64d67d9d38f94ddcb3c296391dffa08699c4a233ecc354ac8351ecd
Deleted: sha256:c80fdb746b489a44e2e02731124e68eb2b66cb5ebfaf211a0910bd4c060d3891
Deleted: sha256:c3af59aeb880486216a70fc3b263fdb054175e3210e5e234edb20bd7c4041f78
Deleted: sha256:f67bdbbf52ea841e3d32c5ae8de94e571e64073846d7a7abfbb4af264d871ddb
Deleted: sha256:b8b1276771557fb4da18134ad87a413fdda78a215d4e3e477bc4f2a2897807e0
Deleted: sha256:533f03a2174ab6239526eaf60aa883ef4d9950afabf96fe46a3486f6d6dbc5da
Deleted: sha256:459287d36ed57dbeffaa15101ce168baba599a2528bde9433e171a67237cedd6
Deleted: sha256:105ff74ffd582a116d9de77dd113b6d65fec7ccef90060b60ef9525cef16e4df
Deleted: sha256:6ba5fdfa015031b8fd29e48aab34cf94a77fdd4caceb09ef7fc0e6d483b804ae
Build step 'Execute shell' marked build as failure
