See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4452/display/redirect>
------------------------------------------
[...truncated 27.03 MB...]
3: I1114 02:49:59.070833 17265 slave.cpp:593] Agent resources:
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1114 02:49:59.071029 17265 slave.cpp:601] Agent attributes: [ ]
3: I1114 02:49:59.071043 17265 slave.cpp:610] Agent hostname: 0ab13b73ea95
3: I1114 02:49:59.071169 17260 status_update_manager.cpp:177] Pausing sending
status updates
3: I1114 02:49:59.072695 17259 state.cpp:64] Recovering state from
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_AgentEndpoint_1_pLfQ4l/meta'
3: I1114 02:49:59.073012 17270 status_update_manager.cpp:203] Recovering status
update manager
3: I1114 02:49:59.073240 17266 containerizer.cpp:662] Recovering containerizer
3: I1114 02:49:59.074589 17259 provisioner.cpp:416] Provisioner recovery
complete
3: I1114 02:49:59.074973 17272 slave.cpp:6432] Finished recovery
3: I1114 02:49:59.075659 17274 status_update_manager.cpp:177] Pausing sending
status updates
3: I1114 02:49:59.075697 17261 slave.cpp:1007] New master detected at
[email protected]:52022
3: I1114 02:49:59.075742 17261 slave.cpp:1042] Detecting new master
3: I1114 02:49:59.078347 17264 process.cpp:3925] Handling HTTP event for
process 'slave(746)' with path: '/slave(746)/api/v1/resource_provider'
3: I1114 02:49:59.079666 17266 http.cpp:1185] HTTP POST for
/slave(746)/api/v1/resource_provider from 172.17.0.2:37948
3: I1114 02:49:59.080098 17268 manager.cpp:381] Subscribing resource provider
{"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:49:59.087663 17258 slave.cpp:883] Agent terminating
3: I1114 02:49:59.093164 17258 master.cpp:1151] Master terminating
3: [ OK ] ContentType/ResourceProviderManagerHttpApiTest.AgentEndpoint/1
(46 ms)
3: [ RUN ]
ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0
3: I1114 02:49:59.098561 17258 cluster.cpp:162] Creating default 'local'
authorizer
3: I1114 02:49:59.100900 17267 master.cpp:448] Master
415a21b5-dbf4-4a77-b47d-923f9bfb4325 (0ab13b73ea95) started on 172.17.0.2:52022
3: I1114 02:49:59.100920 17267 master.cpp:450] 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/6anY1r/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/6anY1r/master"
--zk_session_timeout="10secs"
3: I1114 02:49:59.101193 17267 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1114 02:49:59.101203 17267 master.cpp:505] Master only allowing
authenticated agents to register
3: I1114 02:49:59.101210 17267 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1114 02:49:59.101217 17267 credentials.hpp:37] Loading credentials for
authentication from '/tmp/6anY1r/credentials'
3: I1114 02:49:59.101480 17267 master.cpp:555] Using default 'crammd5'
authenticator
3: I1114 02:49:59.101617 17267 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1114 02:49:59.101760 17267 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1114 02:49:59.101879 17267 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1114 02:49:59.101992 17267 master.cpp:634] Authorization enabled
3: I1114 02:49:59.102133 17262 hierarchical.cpp:171] Initialized hierarchical
allocator process
3: I1114 02:49:59.102166 17259 whitelist_watcher.cpp:77] No whitelist given
3: I1114 02:49:59.105226 17274 master.cpp:2213] Elected as the leading master!
3: I1114 02:49:59.105250 17274 master.cpp:1693] Recovering from registrar
3: I1114 02:49:59.105434 17266 registrar.cpp:347] Recovering registrar
3: I1114 02:49:59.105959 17266 registrar.cpp:391] Successfully fetched the
registry (0B) in 489984ns
3: I1114 02:49:59.106061 17266 registrar.cpp:495] Applied 1 operations in
28896ns; attempting to update the registry
3: I1114 02:49:59.106540 17266 registrar.cpp:552] Successfully updated the
registry in 430080ns
3: I1114 02:49:59.106637 17266 registrar.cpp:424] Successfully recovered
registrar
3: I1114 02:49:59.106981 17264 master.cpp:1806] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I1114 02:49:59.107007 17269 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
3: W1114 02:49:59.110878 17258 process.cpp:3178] Attempted to spawn already
running process [email protected]:52022
3: I1114 02:49:59.111666 17258 containerizer.cpp:301] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1114 02:49:59.112092 17258 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
3: W1114 02:49:59.112191 17258 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
3: I1114 02:49:59.112227 17258 provisioner.cpp:255] Using default backend 'copy'
3: I1114 02:49:59.113785 17258 cluster.cpp:448] Creating default 'local'
authorizer
3: I1114 02:49:59.115594 17270 slave.cpp:262] Mesos agent started on
(747)@172.17.0.2:52022
3: I1114 02:49:59.115613 17270 slave.cpp:263] Flags at startup: --acls=""
--agent_features="capabilities {
3: type: MULTI_ROLE
3: }
3: capabilities {
3: type: HIERARCHICAL_ROLE
3: }
3: capabilities {
3: type: RESERVATION_REFINEMENT
3: }
3: capabilities {
3: type: RESOURCE_PROVIDER
3: }
3: " --appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="false"
--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/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/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/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/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/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/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/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/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/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub"
--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/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_WMpmLO"
--zk_session_timeout="10secs"
3: I1114 02:49:59.116070 17270 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/credential'
3: I1114 02:49:59.116194 17270 slave.cpp:295] Agent using credential for:
test-principal
3: I1114 02:49:59.116210 17270 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_nNALub/http_credentials'
3: I1114 02:49:59.116396 17270 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1114 02:49:59.117677 17270 slave.cpp:593] Agent resources:
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1114 02:49:59.117867 17270 slave.cpp:601] Agent attributes: [ ]
3: I1114 02:49:59.117880 17270 slave.cpp:610] Agent hostname: 0ab13b73ea95
3: I1114 02:49:59.118021 17264 status_update_manager.cpp:177] Pausing sending
status updates
3: I1114 02:49:59.119333 17259 state.cpp:64] Recovering state from
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_WMpmLO/meta'
3: I1114 02:49:59.119632 17271 status_update_manager.cpp:203] Recovering status
update manager
3: I1114 02:49:59.119812 17267 containerizer.cpp:662] Recovering containerizer
3: I1114 02:49:59.121074 17259 provisioner.cpp:416] Provisioner recovery
complete
3: I1114 02:49:59.121433 17271 slave.cpp:6432] Finished recovery
3: I1114 02:49:59.122097 17269 status_update_manager.cpp:177] Pausing sending
status updates
3: I1114 02:49:59.122125 17265 slave.cpp:1007] New master detected at
[email protected]:52022
3: I1114 02:49:59.122167 17265 slave.cpp:1042] Detecting new master
3: I1114 02:49:59.127806 17267 slave.cpp:1069] Authenticating with master
[email protected]:52022
3: I1114 02:49:59.127876 17267 slave.cpp:1078] Using default CRAM-MD5
authenticatee
3: I1114 02:49:59.128089 17274 authenticatee.cpp:121] Creating new client SASL
connection
3: I1114 02:49:59.128353 17272 master.cpp:8285] Authenticating
slave(747)@172.17.0.2:52022
3: I1114 02:49:59.128509 17260 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1376)@172.17.0.2:52022
3: I1114 02:49:59.128721 17268 authenticator.cpp:98] Creating new server SASL
connection
3: I1114 02:49:59.128922 17261 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1114 02:49:59.128950 17261 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1114 02:49:59.129051 17261 authenticator.cpp:204] Received SASL
authentication start
3: I1114 02:49:59.129149 17261 authenticator.cpp:326] Authentication requires
more steps
3: I1114 02:49:59.129271 17262 authenticatee.cpp:259] Received SASL
authentication step
3: I1114 02:49:59.129397 17259 authenticator.cpp:232] Received SASL
authentication step
3: I1114 02:49:59.129426 17259 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '0ab13b73ea95' server FQDN: '0ab13b73ea95'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1114 02:49:59.129441 17259 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1114 02:49:59.129482 17259 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1114 02:49:59.129513 17259 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '0ab13b73ea95' server FQDN: '0ab13b73ea95'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1114 02:49:59.129523 17259 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:49:59.129526 17259 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:49:59.129539 17259 authenticator.cpp:318] Authentication success
3: I1114 02:49:59.129642 17273 authenticatee.cpp:299] Authentication success
3: I1114 02:49:59.129703 17263 master.cpp:8315] Successfully authenticated
principal 'test-principal' at slave(747)@172.17.0.2:52022
3: I1114 02:49:59.129737 17271 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1376)@172.17.0.2:52022
3: I1114 02:49:59.129899 17266 slave.cpp:1161] Successfully authenticated with
master [email protected]:52022
3: I1114 02:49:59.130146 17266 slave.cpp:1682] Will retry registration in
8.179456ms if necessary
3: I1114 02:49:59.130357 17274 master.cpp:6032] Received register agent message
from slave(747)@172.17.0.2:52022 (0ab13b73ea95)
3: I1114 02:49:59.130484 17274 master.cpp:3870] Authorizing agent with
principal 'test-principal'
3: I1114 02:49:59.130861 17260 master.cpp:6092] Authorized registration of
agent at slave(747)@172.17.0.2:52022 (0ab13b73ea95)
3: I1114 02:49:59.130954 17260 master.cpp:6185] Registering agent at
slave(747)@172.17.0.2:52022 (0ab13b73ea95) with id
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0
3: I1114 02:49:59.131331 17268 registrar.cpp:495] Applied 1 operations in
57408ns; attempting to update the registry
3: I1114 02:49:59.131842 17268 registrar.cpp:552] Successfully updated the
registry in 456960ns
3: I1114 02:49:59.132051 17273 master.cpp:6232] Admitted agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022
(0ab13b73ea95)
3: I1114 02:49:59.132740 17264 slave.cpp:1207] Registered with master
[email protected]:52022; given agent ID 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0
3: I1114 02:49:59.132622 17273 master.cpp:6263] Registered agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022
(0ab13b73ea95) with
[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1114 02:49:59.132828 17267 status_update_manager.cpp:184] Resuming sending
status updates
3: I1114 02:49:59.132933 17265 hierarchical.cpp:593] Added agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 (0ab13b73ea95) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
3: I1114 02:49:59.133008 17264 slave.cpp:1227] Checkpointing SlaveInfo to
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_WMpmLO/meta/slaves/415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0/slave.info'
3: I1114 02:49:59.133200 17265 hierarchical.cpp:1450] Performed allocation for
1 agents in 137672ns
3: I1114 02:49:59.133371 17264 slave.cpp:1285] Forwarding total resources
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:49:59.133424 17264 slave.cpp:1295] Forwarding total oversubscribed
resources {}
3: I1114 02:49:59.133962 17271 master.cpp:7051] Received update of agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022
(0ab13b73ea95) with total resources cpus:2; mem:1024; disk:1024;
ports:[31000-32000]
3: I1114 02:49:59.134016 17271 master.cpp:7064] Received update of agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022
(0ab13b73ea95) with total oversubscribed resources {}
3: I1114 02:49:59.134210 17271 master.cpp:7082] Ignoring update on agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022
(0ab13b73ea95) as it reports no changes
3: I1114 02:49:59.134333 17261 http_connection.hpp:221] New endpoint detected
at http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.136468 17273 http_connection.hpp:277] Connected with the
remote endpoint at http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.137356 17259 http_connection.hpp:129] Sending 1 call to
http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.138474 17265 process.cpp:3925] Handling HTTP event for
process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:49:59.139606 17262 http.cpp:1185] HTTP POST for
/slave(747)/api/v1/resource_provider from 172.17.0.2:37950
3: I1114 02:49:59.139871 17262 manager.cpp:381] Subscribing resource provider
{"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:49:59.142313 17272 http_connection.hpp:129] Sending 3 call to
http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.143250 17267 process.cpp:3925] Handling HTTP event for
process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:49:59.144232 17265 http.cpp:1185] HTTP POST for
/slave(747)/api/v1/resource_provider from 172.17.0.2:37949
3: I1114 02:49:59.144609 17262 slave.cpp:6719] Handling resource provider
message 'UPDATE_TOTAL_RESOURCES: 9438527e-51f8-4e13-ba7e-6f50d226f843
disk[RAW]:200'
3: I1114 02:49:59.144697 17262 slave.cpp:6764] Forwarding new total resources
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:49:59.145385 17270 master.cpp:7051] Received update of agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022
(0ab13b73ea95) with total resources cpus:2; mem:1024; disk:1024;
ports:[31000-32000]; disk[RAW]:200
3: I1114 02:49:59.146420 17272 hierarchical.cpp:660] Agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 (0ab13b73ea95) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:49:59.146595 17258 scheduler.cpp:190] Version: 1.5.0
3: I1114 02:49:59.146659 17272 hierarchical.cpp:1450] Performed allocation for
1 agents in 107228ns
3: I1114 02:49:59.146855 17259 scheduler.cpp:313] Using default 'basic' HTTP
authenticatee
3: I1114 02:49:59.147233 17265 scheduler.cpp:496] New master detected at
[email protected]:52022
3: I1114 02:49:59.147259 17265 scheduler.cpp:505] Waiting for 0ns before
initiating a re-(connection) attempt with the master
3: I1114 02:49:59.150328 17260 scheduler.cpp:387] Connected with the master at
http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.151291 17267 scheduler.cpp:249] Sending SUBSCRIBE call to
http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.152678 17261 process.cpp:3925] Handling HTTP event for
process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:49:59.153842 17262 http.cpp:1185] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:37952
3: I1114 02:49:59.154021 17262 master.cpp:2613] Received subscription request
for HTTP framework 'default'
3: I1114 02:49:59.154116 17262 master.cpp:2278] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
3: I1114 02:49:59.154594 17269 master.cpp:2748] Subscribing framework 'default'
with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1114 02:49:59.155303 17274 hierarchical.cpp:303] Added framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000
3: I1114 02:49:59.155937 17264 scheduler.cpp:741] Enqueuing event SUBSCRIBED
received from http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.156293 17264 scheduler.cpp:741] Enqueuing event HEARTBEAT
received from http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.156646 17274 hierarchical.cpp:1450] Performed allocation for
1 agents in 1.175889ms
3: I1114 02:49:59.157196 17265 master.cpp:8115] Sending 1 offers to framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (default)
3: I1114 02:49:59.158840 17270 scheduler.cpp:741] Enqueuing event OFFERS
received from http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.160204 17273 scheduler.cpp:249] Sending ACCEPT call to
http://172.17.0.2:52022/master/api/v1/scheduler
3: I1114 02:49:59.161542 17269 process.cpp:3925] Handling HTTP event for
process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:49:59.162721 17268 http.cpp:1185] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:37951
3: I1114 02:49:59.163583 17268 master.cpp:10017] Removing offer
415a21b5-dbf4-4a77-b47d-923f9bfb4325-O0
3: I1114 02:49:59.163677 17268 master.cpp:4228] Processing ACCEPT call for
offers: [ 415a21b5-dbf4-4a77-b47d-923f9bfb4325-O0 ] on agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022
(0ab13b73ea95) for framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (default)
3: I1114 02:49:59.164335 17268 master.cpp:5293] Processing CREATE_BLOCK
operation with source disk(allocated: *)[RAW]:200 from framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (default) to agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at slave(747)@172.17.0.2:52022
(0ab13b73ea95)
3: I1114 02:49:59.164806 17268 master.cpp:9900] Sending offer operation
=ªnpöSOZ¤Ð~¼ to agent 415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 at
slave(747)@172.17.0.2:52022 (0ab13b73ea95)
3: I1114 02:49:59.165954 17271 hierarchical.cpp:1125] Recovered cpus(allocated:
*):2; mem(allocated: *):1024; disk(allocated: *):1024; ports(allocated:
*):[31000-32000] (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000];
disk[RAW]:200, allocated: disk(allocated: *)[RAW]:200) on agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 from framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000
3: I1114 02:49:59.166012 17271 hierarchical.cpp:1171] Framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 filtered agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 for 5secs
3: I1114 02:49:59.167642 17267 http_connection.hpp:129] Sending 2 call to
http://172.17.0.2:52022/slave(747)/api/v1/resource_provider
3: I1114 02:49:59.168624 17265 process.cpp:3925] Handling HTTP event for
process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:49:59.206424 17263 http.cpp:1185] HTTP POST for
/slave(747)/api/v1/resource_provider from 172.17.0.2:37949
3: I1114 02:49:59.207088 17262 slave.cpp:6719] Handling resource provider
message 'UPDATE_OFFER_OPERATION_STATUS:
{"framework_id":{"value":"415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000"},"status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"9438527e-51f8-4e13-ba7e-6f50d226f843"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"latest_status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"9438527e-51f8-4e13-ba7e-6f50d226f843"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"operation_uuid":"PapucPZTT1qGpNB+D4ACvA=="}'
3: I1114 02:49:59.207437 17262 slave.cpp:6893] Updating the state of offer
operation '' (uuid: =ªnpöSOZ¤Ð~¼) of framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (latest state:
OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: I1114 02:49:59.207761 17262 slave.cpp:6817] Forwarding status update of
offer operation '' (uuid: 3daa6e70-f653-4f5a-86a4-d07e0f8002bc) for framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000
3: I1114 02:49:59.208199 17266 master.cpp:9698] Updating the state of offer
operation '' (uuid: 3daa6e70-f653-4f5a-86a4-d07e0f8002bc) of framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 (latest state:
OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: F1114 02:49:59.208480 17266 master.cpp:11027] CHECK_SOME(resources): cpus:2;
mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200 does not contain
disk(allocated: *)[RAW]:200
3: *** Check failure stack trace: ***
3: @ 0x2abd940de9fd google::LogMessage::Fail()
3: @ 0x2abd940e089d google::LogMessage::SendToLog()
3: @ 0x2abd940de5ec google::LogMessage::Flush()
3: @ 0x2abd940e11be google::LogMessageFatal::~LogMessageFatal()
3: I1114 02:49:59.210330 17267 hierarchical.cpp:849] Updated allocation of
framework 415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000 on agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 from disk(allocated: *)[RAW]:200 to
disk(allocated: *)[BLOCK]:200
3: I1114 02:49:59.210783 17267 hierarchical.cpp:1125] Recovered disk(allocated:
*)[BLOCK]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000];
disk[BLOCK]:200, allocated: {}) on agent
415a21b5-dbf4-4a77-b47d-923f9bfb4325-S0 from framework
415a21b5-dbf4-4a77-b47d-923f9bfb4325-0000
3: I1114 02:49:59.212476 17267 hierarchical.cpp:1450] Performed allocation for
1 agents in 1.469325ms
3: @ 0xc93708 _CheckFatal::~_CheckFatal()
3: @ 0x2abd91515e81 mesos::internal::master::Slave::apply()
3: @ 0x2abd9150be44
mesos::internal::master::Master::updateOfferOperation()
3: @ 0x2abd914f6e57
mesos::internal::master::Master::offerOperationStatusUpdate()
3: @ 0x2abd9155b5f5 ProtobufProcess<>::_handlerM<>()
3: @ 0x2abd916155bf
_ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSD_SF_EJLm0ELm1ELm2ELm3EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
3: @ 0x2abd915f37f6
_ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEEclIJSD_SF_EvEET0_DpOT_
3: @ 0x2abd915c8732 std::_Function_handler<>::_M_invoke()
3: @ 0xe7ef5b std::function<>::operator()()
3: @ 0x2abd9153cbcc ProtobufProcess<>::visit()
3: @ 0x2abd914ccc70 mesos::internal::master::Master::_visit()
3: @ 0x2abd914cc382 mesos::internal::master::Master::visit()
3: @ 0x2abd91521a18 process::MessageEvent::visit()
3: @ 0xcad562 process::ProcessBase::serve()
3: @ 0x2abd93b8d045 process::ProcessManager::resume()
3: @ 0x2abd93b894a4
_ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
3: @ 0x2abd93b9af4c
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
3: @ 0x2abd93b9aea3
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
3: @ 0x2abd93b9ae3c
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
3: @ 0x2abd96738a60 (unknown)
3: @ 0x2abd9430a184 start_thread
3: @ 0x2abd96fa4ffd (unknown)
3/3 Test #3: MesosTests .......................***Exception: Other401.55 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 417.17 sec
The following tests FAILED:
3 - MesosTests (OTHER_FAULT)
Errors while running CTest
make[3]: *** [CMakeFiles/check] Error 8
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1510625238-10478
Untagged: mesos-1510625238-10478:latest
Deleted: sha256:95ef1fac99116998cffb8dba1e3c58dd4256594799f9947aacd304d121ee7c4e
Deleted: sha256:d59fc9374ded57c79e79479df1ebcc3b4117a5c895c8397e5d9f867a558379b6
Deleted: sha256:bb5929aa7b7c719762e2b2a54a8156cbb9ddbfdc2a40116398197776f9b6ad8e
Deleted: sha256:e28c5f4b78d192c6d3d4326a66c49c884e6cf310902334168474e2beae0dc5c7
Deleted: sha256:01b219ef7b974d0d964740ba286e5539c406cf8140e09b4acb1757364f6dcf46
Deleted: sha256:f3b23facba7eea89941a68707aadac6ddece6104980a6428ba3ac747cd7cef50
Deleted: sha256:e56fb2675f4802314918004f55d0f8e894031111ea9663f037aa3f52c5bbcd0c
Deleted: sha256:c53d1d883e64da0418265a47528fd9c9f52995c2d8b72a6c3f709124f09dcc49
Deleted: sha256:05cc1f257136419155cd881b8ae1000613a5609a2ad9ee927e4f4da30e2acb10
Deleted: sha256:c641086ae86bcb8a29f9a81fe37ad767b49ecf4fbfc3d34b8408033a543d3263
Deleted: sha256:dab3779963e07685418382c2710b342fe822dbeb46d8b385640134411da6f4d1
Deleted: sha256:85e9c82f223c83b504a6ba9c7efe0cd716fcb9eda0a6f8737d4251b2c18eeea5
Deleted: sha256:92db81a6937af34cb3e5f7ab7228ffe5f806d34e88ce8d4738ee93688917e806
Deleted: sha256:662480982581e252d05c57e1bd3642f88a5b7cdcde51fb7cbe698cc9ba53d1ec
Deleted: sha256:ef7ecb1deb59ec4b4f3dfe456bf2c8847d5c7d90c47d2d59ab8bead8babf1020
Deleted: sha256:71be62700c28c439959f20a9e4c48972734fd6c94418fd716fdaa40e3a04d02f
Deleted: sha256:fb4ad449fcc113f0afd01920ec2b121f6181ce16beae87c48adeb98cc0c50210
Deleted: sha256:8dd97b6174063b941d2bdecc3cc0e69f285d52e6443f13538ba6085a4eb4adcd
Deleted: sha256:b29680e24801a8bf320bc326ca053e004f4dea883a76eed16b6afced8f012d64
Deleted: sha256:97414470fe28fba488af9d294a0fbf91f89656067b2dcdf15e553fe192b8120b
Deleted: sha256:0498f7b41a2eb9abe1d2849a31732c628fe3b89b22a7682335942f45b7788128
Deleted: sha256:c58d0e37ef4e4866f0d01d8a9c3159d0a72b255a60daa8c707bff11da89b4c86
Deleted: sha256:b2973188ace4ce2c6110a41f767ea6899d705e1f606b0a30c786b4eecbdc7d68
Deleted: sha256:413c80f104a3924fbc0db9c6a5aac5243de88f695caacd91f7bcacc9c1898b44
Deleted: sha256:77dfabc72561b8519f775f95f3a7b4be81a54a6dd17789b87cb9f0d6673893a3
Deleted: sha256:5628289e5d96a69f80874f8718223c6c16ce8045ac53af48758e2138204edb8a
Deleted: sha256:6f44069db13a1d5171150700918e04f1406fa08ed2a89be7f832f8deab7f9c49
Deleted: sha256:374225c8938058578d2456e276b9946b5f2ca3c64c302b60b9d9c9904a3f770e
Deleted: sha256:c92a27d21fe4d1466978ed6cc7ff6bb1e57ffaaf0c9b016e25116fd27a3ba4f8
Deleted: sha256:d85552ebc9a27f4711da2463dad5643dd82af77805222057b0351bd57c42c2e8
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user [email protected]
Not sending mail to unregistered user [email protected]
