See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4297/display/redirect>
------------------------------------------
[...truncated 17.40 MB...]
I1007 06:20:00.856914 6101 master.cpp:9335] Sending updated checkpointed
resources cpus(reservations: [(DYNAMIC,role1,test-principal)]):3 to agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827
(2d94853a9d29)
I1007 06:20:00.858356 6104 slave.cpp:3535] Updated checkpointed resources from
{} to cpus(reservations: [(DYNAMIC,role1,test-principal)]):3
I1007 06:20:00.861917 6101 hierarchical.cpp:887] Updated allocation of
framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 on agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 from cpus(allocated: role1):3;
mem(allocated: role1):31070; disk(allocated: role1):41686; ports(allocated:
role1):[31000-32000] to ports(allocated: role1):[31000-32000]; mem(allocated:
role1):31070; disk(allocated: role1):41686; cpus(allocated:
role1)(reservations: [(DYNAMIC,role1,test-principal)]):3
I1007 06:20:00.863786 6101 hierarchical.cpp:1161] Recovered ports(allocated:
role1):[31000-32000]; mem(allocated: role1):31070; disk(allocated:
role1):41686; cpus(allocated: role1)(reservations:
[(DYNAMIC,role1,test-principal)]):3 (total: ports:[31000-32000]; mem:31070;
disk:41686; cpus(reservations: [(DYNAMIC,role1,test-principal)]):3, allocated:
{}) on agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 from framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.863986 6101 hierarchical.cpp:1207] Framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000 filtered agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 for 5secs
I1007 06:20:00.864717 6099 slave.cpp:906] Unregistering and shutting down
I1007 06:20:00.864831 6099 slave.cpp:869] Agent terminating
I1007 06:20:00.865172 6100 master.cpp:8823] Removing agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827
(2d94853a9d29): the agent unregistered
I1007 06:20:00.865660 6100 master.cpp:1303] Agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827
(2d94853a9d29) disconnected
I1007 06:20:00.865702 6100 master.cpp:3336] Disconnecting agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827
(2d94853a9d29)
I1007 06:20:00.865833 6100 master.cpp:3355] Deactivating agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827
(2d94853a9d29)
I1007 06:20:00.865993 6102 hierarchical.cpp:690] Agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 deactivated
I1007 06:20:00.866165 6107 registrar.cpp:495] Applied 1 operations in
299913ns; attempting to update the registry
I1007 06:20:00.867334 6107 registrar.cpp:552] Successfully updated the
registry in 0ns
I1007 06:20:00.867552 6102 master.cpp:8865] Removed agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827
(2d94853a9d29): the agent unregistered
I1007 06:20:00.868036 6102 master.cpp:2115] Notifying framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000 (default) at
[email protected]:40827 of lost agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1 (2d94853a9d29)
I1007 06:20:00.868410 6102 sched.cpp:1087] Lost agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1
I1007 06:20:00.868481 6102 sched.cpp:1098] Scheduler::slaveLost took 35288ns
I1007 06:20:00.869659 6103 hierarchical.cpp:626] Removed agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S1
I1007 06:20:00.874199 6100 process.cpp:3929] Handling HTTP event for process
'master' with path: '/master/unreserve'
I1007 06:20:00.877521 6107 http.cpp:1185] HTTP POST for /master/unreserve from
172.17.0.2:49742
I1007 06:20:00.878650 6107 master.cpp:3691] Authorizing principal
'test-principal' to unreserve resources
'[{"name":"cpus","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":4.0},"type":"SCALAR"}]'
I1007 06:20:00.881247 6107 master.cpp:9389] Removing offer
89f44c73-75af-4850-84ed-f6efd65ec32d-O1
I1007 06:20:00.881594 6103 sched.cpp:943] Rescinded offer
89f44c73-75af-4850-84ed-f6efd65ec32d-O1
I1007 06:20:00.882295 6103 sched.cpp:954] Scheduler::offerRescinded took
50998ns
I1007 06:20:00.882707 6101 hierarchical.cpp:1161] Recovered ports(allocated:
role1):[31000-32000]; mem(allocated: role1):31070; disk(allocated:
role1):41686; cpus(allocated: role1)(reservations:
[(DYNAMIC,role1,test-principal)]):4 (total: ports:[31000-32000]; mem:31070;
disk:41686; cpus(reservations: [(DYNAMIC,role1,test-principal)]):4, allocated:
{}) on agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 from framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.882866 6101 hierarchical.cpp:1207] Framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000 filtered agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S0 for 5secs
I1007 06:20:00.886261 6105 master.cpp:9335] Sending updated checkpointed
resources {} to agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 at
slave(365)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:00.887164 6105 slave.cpp:3535] Updated checkpointed resources from
cpus(reservations: [(DYNAMIC,role1,test-principal)]):4 to {}
I1007 06:20:00.891048 6099 sched.cpp:2005] Asked to stop the driver
I1007 06:20:00.891198 6101 sched.cpp:1187] Stopping framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.891865 6106 master.cpp:8447] Processing TEARDOWN call for
framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 (default) at
[email protected]:40827
I1007 06:20:00.891917 6106 master.cpp:8459] Removing framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000 (default) at
[email protected]:40827
I1007 06:20:00.891945 6106 master.cpp:3299] Deactivating framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000 (default) at
[email protected]:40827
I1007 06:20:00.892273 6105 hierarchical.cpp:412] Deactivated framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.892608 6103 slave.cpp:3227] Asked to shut down framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000 by [email protected]:40827
I1007 06:20:00.892765 6103 slave.cpp:3242] Cannot shut down unknown framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.893069 6104 hierarchical.cpp:355] Removed framework
89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.922920 6102 slave.cpp:869] Agent terminating
I1007 06:20:00.937976 6103 master.cpp:1303] Agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S0 at slave(365)@172.17.0.2:40827
(2d94853a9d29) disconnected
I1007 06:20:00.938206 6103 master.cpp:3336] Disconnecting agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S0 at slave(365)@172.17.0.2:40827
(2d94853a9d29)
I1007 06:20:00.938470 6103 master.cpp:3355] Deactivating agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S0 at slave(365)@172.17.0.2:40827
(2d94853a9d29)
I1007 06:20:00.939765 6102 hierarchical.cpp:690] Agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S0 deactivated
I1007 06:20:00.953443 6099 master.cpp:1145] Master terminating
I1007 06:20:00.954610 6102 hierarchical.cpp:626] Removed agent
89f44c73-75af-4850-84ed-f6efd65ec32d-S0
[ OK ] PersistentVolumeEndpointsTest.ReserveAndSlaveRemoval (274 ms)
[ RUN ] PersistentVolumeEndpointsTest.SlavesEndpointFullResources
I1007 06:20:00.971137 6099 cluster.cpp:162] Creating default 'local' authorizer
I1007 06:20:00.976200 6106 master.cpp:445] Master
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7 (2d94853a9d29) started on 172.17.0.2:40827
I1007 06:20:00.976246 6106 master.cpp:447] Flags at startup: --acls=""
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="50ms" --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/EmeUlr/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" --roles="role1"
--root_submissions="true" --user_sorter="drf" --version="false"
--webui_dir="/mesos/mesos-1.5.0/_inst/share/mesos/webui"
--work_dir="/tmp/EmeUlr/master" --zk_session_timeout="10secs"
I1007 06:20:00.976832 6106 master.cpp:496] Master only allowing authenticated
frameworks to register
I1007 06:20:00.976850 6106 master.cpp:502] Master only allowing authenticated
agents to register
I1007 06:20:00.976864 6106 master.cpp:508] Master only allowing authenticated
HTTP frameworks to register
I1007 06:20:00.976883 6106 credentials.hpp:37] Loading credentials for
authentication from '/tmp/EmeUlr/credentials'
I1007 06:20:00.977360 6106 master.cpp:552] Using default 'crammd5'
authenticator
I1007 06:20:00.977645 6106 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I1007 06:20:00.977895 6106 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I1007 06:20:00.978098 6106 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I1007 06:20:00.978256 6106 master.cpp:631] Authorization enabled
W1007 06:20:00.978277 6106 master.cpp:694] The '--roles' flag is deprecated.
This flag will be removed in the future. See the Mesos 0.27 upgrade notes for
more information
I1007 06:20:00.978574 6105 hierarchical.cpp:171] Initialized hierarchical
allocator process
I1007 06:20:00.978619 6102 whitelist_watcher.cpp:77] No whitelist given
I1007 06:20:00.982513 6101 master.cpp:2198] Elected as the leading master!
I1007 06:20:00.982568 6101 master.cpp:1687] Recovering from registrar
I1007 06:20:00.982875 6102 registrar.cpp:347] Recovering registrar
I1007 06:20:00.983736 6102 registrar.cpp:391] Successfully fetched the
registry (0B) in 787968ns
I1007 06:20:00.983886 6102 registrar.cpp:495] Applied 1 operations in 36161ns;
attempting to update the registry
I1007 06:20:00.984650 6102 registrar.cpp:552] Successfully updated the
registry in 691200ns
I1007 06:20:00.984804 6102 registrar.cpp:424] Successfully recovered registrar
I1007 06:20:00.985342 6100 master.cpp:1791] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I1007 06:20:00.985365 6106 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
W1007 06:20:00.995031 6099 process.cpp:3194] Attempted to spawn already
running process [email protected]:40827
I1007 06:20:00.996711 6099 containerizer.cpp:292] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1007 06:20:00.997622 6099 backend.cpp:76] Failed to create 'overlay' backend:
OverlayBackend requires root privileges
W1007 06:20:00.997880 6099 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
I1007 06:20:00.998029 6099 provisioner.cpp:255] Using default backend 'copy'
I1007 06:20:01.000980 6099 cluster.cpp:448] Creating default 'local' authorizer
I1007 06:20:01.004412 6100 slave.cpp:254] Mesos agent started on
(367)@172.17.0.2:40827
I1007 06:20:01.004452 6100 slave.cpp:255] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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:4;gpus:0;mem:2048;disk:4096"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4"
--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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_NTvOLK"
--zk_session_timeout="10secs"
I1007 06:20:01.005247 6100 credentials.hpp:86] Loading credential for
authentication from
'/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/credential'
I1007 06:20:01.005523 6100 slave.cpp:287] Agent using credential for:
test-principal
I1007 06:20:01.005556 6100 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/http_credentials'
I1007 06:20:01.005955 6100 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I1007 06:20:01.006253 6100 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I1007 06:20:01.008258 6100 slave.cpp:585] Agent resources:
[{"name":"cpus","scalar":{"value":4.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":4096.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I1007 06:20:01.008707 6100 slave.cpp:593] Agent attributes: [ ]
I1007 06:20:01.008729 6100 slave.cpp:602] Agent hostname: 2d94853a9d29
I1007 06:20:01.009029 6101 status_update_manager.cpp:177] Pausing sending
status updates
I1007 06:20:01.011376 6103 state.cpp:64] Recovering state from
'/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_NTvOLK/meta'
I1007 06:20:01.011937 6101 status_update_manager.cpp:203] Recovering status
update manager
I1007 06:20:01.012285 6106 containerizer.cpp:648] Recovering containerizer
I1007 06:20:01.016584 6102 provisioner.cpp:416] Provisioner recovery complete
I1007 06:20:01.017062 6106 slave.cpp:6326] Finished recovery
I1007 06:20:01.018021 6106 slave.cpp:6512] Querying resource estimator for
oversubscribable resources
I1007 06:20:01.018754 6104 status_update_manager.cpp:177] Pausing sending
status updates
I1007 06:20:01.018882 6103 slave.cpp:993] New master detected at
[email protected]:40827
I1007 06:20:01.019166 6103 slave.cpp:1028] Detecting new master
I1007 06:20:01.019484 6103 slave.cpp:6526] Received oversubscribable resources
{} from the resource estimator
I1007 06:20:01.029572 6102 slave.cpp:1055] Authenticating with master
[email protected]:40827
I1007 06:20:01.029758 6102 slave.cpp:1064] Using default CRAM-MD5 authenticatee
I1007 06:20:01.030293 6102 authenticatee.cpp:121] Creating new client SASL
connection
I1007 06:20:01.030313 6106 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.030478 6106 hierarchical.cpp:1486] Performed allocation for 0
agents in 218027ns
I1007 06:20:01.031019 6101 master.cpp:7936] Authenticating
slave(367)@172.17.0.2:40827
I1007 06:20:01.031486 6101 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(775)@172.17.0.2:40827
I1007 06:20:01.032119 6104 authenticator.cpp:98] Creating new server SASL
connection
I1007 06:20:01.032609 6103 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I1007 06:20:01.032729 6103 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1007 06:20:01.032989 6103 authenticator.cpp:204] Received SASL authentication
start
I1007 06:20:01.033115 6103 authenticator.cpp:326] Authentication requires more
steps
I1007 06:20:01.033471 6103 authenticatee.cpp:259] Received SASL authentication
step
I1007 06:20:01.033725 6103 authenticator.cpp:232] Received SASL authentication
step
I1007 06:20:01.033838 6103 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '2d94853a9d29' server FQDN: '2d94853a9d29'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I1007 06:20:01.033978 6103 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I1007 06:20:01.034230 6103 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1007 06:20:01.034334 6103 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '2d94853a9d29' server FQDN: '2d94853a9d29'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I1007 06:20:01.034415 6103 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1007 06:20:01.034482 6103 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1007 06:20:01.034569 6103 authenticator.cpp:318] Authentication success
I1007 06:20:01.034899 6102 authenticatee.cpp:299] Authentication success
I1007 06:20:01.035621 6101 slave.cpp:1147] Successfully authenticated with
master [email protected]:40827
I1007 06:20:01.036089 6107 master.cpp:7966] Successfully authenticated
principal 'test-principal' at slave(367)@172.17.0.2:40827
I1007 06:20:01.036110 6101 slave.cpp:1642] Will retry registration in
3.033657ms if necessary
I1007 06:20:01.036211 6105 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(775)@172.17.0.2:40827
I1007 06:20:01.036494 6107 master.cpp:5801] Received register agent message
from slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.036669 6107 master.cpp:3838] Authorizing agent with principal
'test-principal'
I1007 06:20:01.037466 6103 master.cpp:5861] Authorized registration of agent
at slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.037770 6103 master.cpp:5954] Registering agent at
slave(367)@172.17.0.2:40827 (2d94853a9d29) with id
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0
I1007 06:20:01.038676 6100 registrar.cpp:495] Applied 1 operations in
106868ns; attempting to update the registry
I1007 06:20:01.039664 6100 registrar.cpp:552] Successfully updated the
registry in 876032ns
I1007 06:20:01.040068 6106 master.cpp:6001] Admitted agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29)
I1007 06:20:01.040624 6104 slave.cpp:1642] Will retry registration in
21.746471ms if necessary
I1007 06:20:01.042006 6102 slave.cpp:4982] Received ping from
slave-observer(375)@172.17.0.2:40827
I1007 06:20:01.042445 6102 slave.cpp:1193] Registered with master
[email protected]:40827; given agent ID a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0
I1007 06:20:01.042605 6107 status_update_manager.cpp:184] Resuming sending
status updates
I1007 06:20:01.042942 6101 hierarchical.cpp:593] Added agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 (2d94853a9d29) with cpus:4; mem:2048;
disk:4096; ports:[31000-32000] (allocated: {})
I1007 06:20:01.042084 6106 master.cpp:6032] Registered agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29) with
[{"name":"cpus","scalar":{"value":4.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":4096.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I1007 06:20:01.043763 6101 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.043853 6101 hierarchical.cpp:1486] Performed allocation for 1
agents in 293817ns
I1007 06:20:01.043884 6106 master.cpp:5801] Received register agent message
from slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.044268 6106 master.cpp:3838] Authorizing agent with principal
'test-principal'
I1007 06:20:01.045053 6105 master.cpp:5861] Authorized registration of agent
at slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.045343 6105 master.cpp:5932] Agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29) already registered, resending acknowledgement
I1007 06:20:01.050631 6106 process.cpp:3929] Handling HTTP event for process
'master' with path: '/master/reserve'
I1007 06:20:01.053233 6107 http.cpp:1185] HTTP POST for /master/reserve from
172.17.0.2:49744
I1007 06:20:01.055279 6107 master.cpp:3623] Authorizing principal
'test-principal' to reserve resources
'[{"name":"cpus","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":512.0},"type":"SCALAR"},{"name":"disk","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":1024.0},"type":"SCALAR"}]'
I1007 06:20:01.062232 6100 master.cpp:9335] Sending updated checkpointed
resources cpus(reservations: [(DYNAMIC,role1,test-principal)]):1;
mem(reservations: [(DYNAMIC,role1,test-principal)]):512; disk(reservations:
[(DYNAMIC,role1,test-principal)]):1024 to agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29)
I1007 06:20:01.068478 6100 process.cpp:3929] Handling HTTP event for process
'master' with path: '/master/create-volumes'
I1007 06:20:01.071872 6103 http.cpp:1185] HTTP POST for /master/create-volumes
from 172.17.0.2:49746
I1007 06:20:01.073365 6103 master.cpp:3758] Authorizing principal
'test-principal' to create volumes
'[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
I1007 06:20:01.078343 6102 slave.cpp:1213] Checkpointing SlaveInfo to
'/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_NTvOLK/meta/slaves/a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0/slave.info'
I1007 06:20:01.078946 6102 slave.cpp:1263] Forwarding total resources cpus:4;
mem:2048; disk:4096; ports:[31000-32000]
I1007 06:20:01.079080 6102 slave.cpp:1272] Forwarding total oversubscribed
resources {}
W1007 06:20:01.079293 6102 slave.cpp:1248] Already registered with master
[email protected]:40827
I1007 06:20:01.079322 6102 slave.cpp:1263] Forwarding total resources cpus:4;
mem:2048; disk:4096; ports:[31000-32000]
I1007 06:20:01.079414 6102 slave.cpp:1272] Forwarding total oversubscribed
resources {}
I1007 06:20:01.080778 6102 slave.cpp:3535] Updated checkpointed resources from
{} to cpus(reservations: [(DYNAMIC,role1,test-principal)]):1; mem(reservations:
[(DYNAMIC,role1,test-principal)]):512; disk(reservations:
[(DYNAMIC,role1,test-principal)]):1024
I1007 06:20:01.082378 6106 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.082535 6106 hierarchical.cpp:1486] Performed allocation for 1
agents in 477108ns
I1007 06:20:01.083534 6103 master.cpp:6804] Received update of agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29) with total resources cpus:4; mem:2048; disk:4096;
ports:[31000-32000]
I1007 06:20:01.083663 6103 master.cpp:6817] Received update of agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29) with total oversubscribed resources {}
I1007 06:20:01.084277 6103 master.cpp:6804] Received update of agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29) with total resources cpus:4; mem:2048; disk:4096;
ports:[31000-32000]
I1007 06:20:01.084364 6103 master.cpp:6817] Received update of agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29) with total oversubscribed resources {}
I1007 06:20:01.084578 6103 master.cpp:6828] Ignoring update on agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827
(2d94853a9d29) as it reports no changes
F1007 06:20:01.084858 6103 master.cpp:10271] CHECK_SOME(resources): Invalid
CREATE Operation: Insufficient disk resources for persistent volume
disk(reservations: [(DYNAMIC,role1,test-principal)])[id1:path1]:64
*** Check failure stack trace: ***
I1007 06:20:01.086515 6105 hierarchical.cpp:660] Agent
a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 (2d94853a9d29) updated with total
resources cpus:4; mem:2048; disk:4096; ports:[31000-32000]
W1007 06:20:01.088474 6099 process.cpp:3194] Attempted to spawn already
running process [email protected]:40827
I1007 06:20:01.095726 6105 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.095808 6105 hierarchical.cpp:1486] Performed allocation for 1
agents in 397248ns
I1007 06:20:01.097429 6099 sched.cpp:232] Version: 1.5.0
I1007 06:20:01.098220 6102 sched.cpp:336] New master detected at
[email protected]:40827
I1007 06:20:01.098444 6102 sched.cpp:396] Authenticating with master
[email protected]:40827
I1007 06:20:01.098464 6102 sched.cpp:403] Using default CRAM-MD5 authenticatee
I1007 06:20:01.098876 6106 authenticatee.cpp:121] Creating new client SASL
connection
@ 0x7f1fd2b4acec google::LogMessage::Fail()
I1007 06:20:01.133831 6105 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.133916 6105 hierarchical.cpp:1486] Performed allocation for 1
agents in 295141ns
@ 0x7f1fd2b4ac4b google::LogMessage::SendToLog()
@ 0x7f1fd2b4a65c google::LogMessage::Flush()
@ 0x7f1fd2b4d390 google::LogMessageFatal::~LogMessageFatal()
@ 0x563cdd59916f _CheckFatal::~_CheckFatal()
@ 0x7f1fd14c89b7 mesos::internal::master::Slave::apply()
I1007 06:20:01.185674 6104 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.185757 6104 hierarchical.cpp:1486] Performed allocation for 1
agents in 282118ns
@ 0x7f1fd14c09b5 mesos::internal::master::Master::_apply()
@ 0x7f1fd15417ea
_ZZN7process8dispatchIN5mesos8internal6master6MasterEPNS3_5SlaveERKNS1_15Offer_OperationERS6_S9_EEvRKNS_3PIDIT_EEMSC_FvT0_T1_EOT2_OT3_ENKUlSA_RS7_PNS_11ProcessBaseEE_clESA_SO_SQ_
@ 0x7f1fd15f694b
_ZNSt5_BindIFZN7process8dispatchIN5mesos8internal6master6MasterEPNS4_5SlaveERKNS2_15Offer_OperationERS7_SA_EEvRKNS0_3PIDIT_EEMSD_FvT0_T1_EOT2_OT3_EUlSB_RS8_PNS0_11ProcessBaseEE_S7_S8_St12_PlaceholderILi1EEEE6__callIvJOSR_EJLm0ELm1ELm2EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
I1007 06:20:01.237100 6101 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.237184 6101 hierarchical.cpp:1486] Performed allocation for 1
agents in 293206ns
@ 0x7f1fd15deab6
_ZNSt5_BindIFZN7process8dispatchIN5mesos8internal6master6MasterEPNS4_5SlaveERKNS2_15Offer_OperationERS7_SA_EEvRKNS0_3PIDIT_EEMSD_FvT0_T1_EOT2_OT3_EUlSB_RS8_PNS0_11ProcessBaseEE_S7_S8_St12_PlaceholderILi1EEEEclIISR_EvEET0_DpOT_
@ 0x7f1fd15b40fc
_ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchIN5mesos8internal6master6MasterEPNS8_5SlaveERKNS6_15Offer_OperationERSB_SE_EEvRKNS0_3PIDIT_EEMSH_FvT0_T1_EOT2_OT3_EUlSF_RSC_S2_E_SB_SC_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_
I1007 06:20:01.288854 6100 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.288940 6100 hierarchical.cpp:1486] Performed allocation for 1
agents in 282750ns
@ 0x7f1fd2a7946b std::function<>::operator()()
@ 0x7f1fd2a58721 process::ProcessBase::visit()
@ 0x7f1fd2a65758 process::DispatchEvent::visit()
@ 0x563cdd599f10 process::ProcessBase::serve()
@ 0x7f1fd2a55a5f process::ProcessManager::resume()
I1007 06:20:01.340214 6106 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.340294 6106 hierarchical.cpp:1486] Performed allocation for 1
agents in 261456ns
@ 0x7f1fd2a51eb4
_ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x7f1fd2a64606
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x7f1fd2a6454a
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x7f1fd2a644d4
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x7f1fcaf772b0 (unknown)
@ 0x7f1fcb5dde25 start_thread
@ 0x7f1fca6df34d __clone
make[4]: *** [check-local] Aborted (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-1507352571-32306
Untagged: mesos-1507352571-32306:latest
Deleted: sha256:e9d265071928a52ff5c1b5a886ef908548cfe497b51aeec68f25f3d773325d81
Deleted: sha256:471250de4d7ca0de9e6afa65bba4fca5bd628b7458f4e5fd31de37ca7e6a6a00
Deleted: sha256:0855e0bb1c30a7f7314cb4581183869779cb70080f97997635d3ab8fc3c785e2
Deleted: sha256:70a108a243eb9759905931774543c15353c8bdc3123fbc78e0e86fc92302a179
Deleted: sha256:94bf92b100c14e83f325fbfcc32acaadd0e41e52ad89d09d6ba0dbc56393eff3
Deleted: sha256:cf8269178066cc33bead04366f5037dc21893d8b8666cba59ef5295d22f1a3c6
Deleted: sha256:5afa5579eb01ffe0a91cdd4c0c440cae90e39e9c4fb0006d8c90b8e41b977ece
Deleted: sha256:6746c8fdf53b85f61436b8755cc402437333977716492c75788fe5c5c73bcc83
Deleted: sha256:37017fd199c58061dc95d84659098a393ff1f78393c33d434ffe81edb6df1a48
Deleted: sha256:5cdad53b0c15801b0821da9da11e1946fe63f11e6a6a8ffcb1db3cff83f36589
Deleted: sha256:1368b6af82d8646cc99ecee84c46f45799fc4fddb841382eabb9d2efb9583b8b
Deleted: sha256:4c0e47c15454dd66bb10990027bcd65e2fe46abb40a423204b9d8d7d4f84f4b4
Deleted: sha256:11052ee348fea921cb8f94a08f94a6e440b86239028300b423a68dd3bd04add9
Deleted: sha256:a0cc13a29aba754c2944942f0035eff77191b12efc1ab60f85a7ef26194618c2
Deleted: sha256:c8ef13dde8acc54b931cfed6bc620ce2d84d182a2b6ed445553e2e872577a89b
Deleted: sha256:932f7fbe37b76283a61bf801c2a180cd05828c3066614b06f15647ad8023c5b8
Deleted: sha256:45fa75115b77f43e3d5cc1534e1880288a3a632a59d093adb142d0df89106444
Deleted: sha256:663b6528f1d719f4654c9316c684a6feabf9bd138c844e6f39168321bcb5c1d7
Deleted: sha256:5ca4bf0cfde30a43787d5a4278e5ea181561590ebf507c5f784d6d77f0fc0431
Deleted: sha256:a326ee5074728bf4d55656d4be7d3165bc0e38d9d0a0de5fa56e53de56314f1d
Deleted: sha256:53cb8ee12b720267b576912f87f54898000654d53d7317b20fe8d2b874b22cbf
Deleted: sha256:2ad583655d247e6678613fd2cdffc300c97eb4e2790303d368df585157cb5706
Deleted: sha256:c4f90871c5b0557bbbf65a2bcf27b2f5e26a990ab7d754e0bde1f92fede97f58
Deleted: sha256:d3cfd7a7d365002c6de95a3080b88507e8b7230256b226a464f531229419278b
Deleted: sha256:bc65e43b8b2b0ea87f9f17851ceb07ba204f9947bc52595466459eb310d55f5e
Deleted: sha256:6517671022c47f7f66cc1cd8c1f8aaecb2a02dd273d441c43494dd78ab1267f7
Deleted: sha256:84697dfc1edde3a0de370052904bdd24e8b3f976f2f7361fcd7f6bdb6f61cefb
Deleted: sha256:1547dd00b7e57f5ee9576dcb7c7f0ef8948cf330ed7fd3e8a173150372cb0dea
Deleted: sha256:2dd2e0cf7eea65b7daf310e7d6cd28db803275a49672a6e367a2558997f1ad47
Build step 'Execute shell' marked build as failure
