See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4452/display/redirect>
------------------------------------------
[...truncated 27.04 MB...]
3: I1114 02:34:39.113013 15966 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:34:39.113342 15966 slave.cpp:601] Agent attributes: [ ]
3: I1114 02:34:39.113356 15966 slave.cpp:610] Agent hostname: 7ca2192713be
3: I1114 02:34:39.113512 15982 status_update_manager.cpp:177] Pausing sending
status updates
3: I1114 02:34:39.116022 15979 state.cpp:64] Recovering state from
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_AgentEndpoint_1_Jsb1jH/meta'
3: I1114 02:34:39.116358 15976 status_update_manager.cpp:203] Recovering status
update manager
3: I1114 02:34:39.116551 15967 containerizer.cpp:662] Recovering containerizer
3: I1114 02:34:39.117885 15983 provisioner.cpp:416] Provisioner recovery
complete
3: I1114 02:34:39.118157 15985 slave.cpp:6432] Finished recovery
3: I1114 02:34:39.118876 15976 status_update_manager.cpp:177] Pausing sending
status updates
3: I1114 02:34:39.118939 15984 slave.cpp:1007] New master detected at
master@172.17.0.3:42601
3: I1114 02:34:39.119038 15984 slave.cpp:1042] Detecting new master
3: I1114 02:34:39.121557 15971 process.cpp:3925] Handling HTTP event for
process 'slave(746)' with path: '/slave(746)/api/v1/resource_provider'
3: I1114 02:34:39.122854 15978 http.cpp:1185] HTTP POST for
/slave(746)/api/v1/resource_provider from 172.17.0.3:59784
3: I1114 02:34:39.123361 15987 manager.cpp:381] Subscribing resource provider
{"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:34:39.131497 15963 slave.cpp:883] Agent terminating
3: I1114 02:34:39.137595 15963 master.cpp:1151] Master terminating
3: [ OK ] ContentType/ResourceProviderManagerHttpApiTest.AgentEndpoint/1
(54 ms)
3: [ RUN ]
ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0
3: I1114 02:34:39.144062 15963 cluster.cpp:162] Creating default 'local'
authorizer
3: I1114 02:34:39.146817 15972 master.cpp:448] Master
16bb6407-1ae6-4730-a008-15daabdaaf61 (7ca2192713be) started on 172.17.0.3:42601
3: I1114 02:34:39.146858 15972 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/31k0Zy/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/31k0Zy/master"
--zk_session_timeout="10secs"
3: I1114 02:34:39.147205 15972 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1114 02:34:39.147212 15972 master.cpp:505] Master only allowing
authenticated agents to register
3: I1114 02:34:39.147215 15972 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1114 02:34:39.147220 15972 credentials.hpp:37] Loading credentials for
authentication from '/tmp/31k0Zy/credentials'
3: I1114 02:34:39.147533 15972 master.cpp:555] Using default 'crammd5'
authenticator
3: I1114 02:34:39.147703 15972 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1114 02:34:39.147871 15972 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1114 02:34:39.147977 15972 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1114 02:34:39.148077 15972 master.cpp:634] Authorization enabled
3: I1114 02:34:39.148252 15982 whitelist_watcher.cpp:77] No whitelist given
3: I1114 02:34:39.148258 15987 hierarchical.cpp:171] Initialized hierarchical
allocator process
3: I1114 02:34:39.151640 15966 master.cpp:2213] Elected as the leading master!
3: I1114 02:34:39.151662 15966 master.cpp:1693] Recovering from registrar
3: I1114 02:34:39.151760 15978 registrar.cpp:347] Recovering registrar
3: I1114 02:34:39.152345 15978 registrar.cpp:391] Successfully fetched the
registry (0B) in 555008ns
3: I1114 02:34:39.152477 15978 registrar.cpp:495] Applied 1 operations in
46460ns; attempting to update the registry
3: I1114 02:34:39.153028 15978 registrar.cpp:552] Successfully updated the
registry in 492032ns
3: I1114 02:34:39.153129 15978 registrar.cpp:424] Successfully recovered
registrar
3: I1114 02:34:39.153493 15967 master.cpp:1806] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I1114 02:34:39.153538 15964 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
3: W1114 02:34:39.160058 15963 process.cpp:3178] Attempted to spawn already
running process files@172.17.0.3:42601
3: I1114 02:34:39.161262 15963 containerizer.cpp:301] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1114 02:34:39.162044 15963 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
3: W1114 02:34:39.162175 15963 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
3: I1114 02:34:39.162216 15963 provisioner.cpp:255] Using default backend 'copy'
3: I1114 02:34:39.164412 15963 cluster.cpp:448] Creating default 'local'
authorizer
3: I1114 02:34:39.166811 15964 slave.cpp:262] Mesos agent started on
(747)@172.17.0.3:42601
3: I1114 02:34:39.166846 15964 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_dwoYhO/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_dwoYhO/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_dwoYhO/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_dwoYhO/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_dwoYhO/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_dwoYhO"
--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_jFEYDb"
--zk_session_timeout="10secs"
3: I1114 02:34:39.167294 15964 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_dwoYhO/credential'
3: I1114 02:34:39.167511 15964 slave.cpp:295] Agent using credential for:
test-principal
3: I1114 02:34:39.167531 15964 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_dwoYhO/http_credentials'
3: I1114 02:34:39.167744 15964 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1114 02:34:39.169121 15964 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:34:39.169323 15964 slave.cpp:601] Agent attributes: [ ]
3: I1114 02:34:39.169333 15964 slave.cpp:610] Agent hostname: 7ca2192713be
3: I1114 02:34:39.169457 15966 status_update_manager.cpp:177] Pausing sending
status updates
3: I1114 02:34:39.171288 15975 state.cpp:64] Recovering state from
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_jFEYDb/meta'
3: I1114 02:34:39.171615 15986 status_update_manager.cpp:203] Recovering status
update manager
3: I1114 02:34:39.171820 15971 containerizer.cpp:662] Recovering containerizer
3: I1114 02:34:39.173213 15977 provisioner.cpp:416] Provisioner recovery
complete
3: I1114 02:34:39.173491 15966 slave.cpp:6432] Finished recovery
3: I1114 02:34:39.174304 15964 status_update_manager.cpp:177] Pausing sending
status updates
3: I1114 02:34:39.174340 15968 slave.cpp:1007] New master detected at
master@172.17.0.3:42601
3: I1114 02:34:39.174537 15968 slave.cpp:1042] Detecting new master
3: I1114 02:34:39.180851 15979 slave.cpp:1069] Authenticating with master
master@172.17.0.3:42601
3: I1114 02:34:39.180974 15979 slave.cpp:1078] Using default CRAM-MD5
authenticatee
3: I1114 02:34:39.181294 15984 authenticatee.cpp:121] Creating new client SASL
connection
3: I1114 02:34:39.181640 15986 master.cpp:8285] Authenticating
slave(747)@172.17.0.3:42601
3: I1114 02:34:39.181744 15965 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1376)@172.17.0.3:42601
3: I1114 02:34:39.181995 15967 authenticator.cpp:98] Creating new server SASL
connection
3: I1114 02:34:39.182307 15967 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1114 02:34:39.182338 15967 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1114 02:34:39.182540 15970 authenticator.cpp:204] Received SASL
authentication start
3: I1114 02:34:39.182602 15970 authenticator.cpp:326] Authentication requires
more steps
3: I1114 02:34:39.182729 15973 authenticatee.cpp:259] Received SASL
authentication step
3: I1114 02:34:39.182858 15969 authenticator.cpp:232] Received SASL
authentication step
3: I1114 02:34:39.182888 15969 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '7ca2192713be' server FQDN: '7ca2192713be'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1114 02:34:39.182900 15969 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1114 02:34:39.182946 15969 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1114 02:34:39.182962 15969 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: '7ca2192713be' server FQDN: '7ca2192713be'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1114 02:34:39.182971 15969 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:34:39.182976 15969 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:34:39.182989 15969 authenticator.cpp:318] Authentication success
3: I1114 02:34:39.183063 15980 authenticatee.cpp:299] Authentication success
3: I1114 02:34:39.183147 15971 master.cpp:8315] Successfully authenticated
principal 'test-principal' at slave(747)@172.17.0.3:42601
3: I1114 02:34:39.183197 15980 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1376)@172.17.0.3:42601
3: I1114 02:34:39.183367 15985 slave.cpp:1161] Successfully authenticated with
master master@172.17.0.3:42601
3: I1114 02:34:39.183681 15985 slave.cpp:1682] Will retry registration in
10.356269ms if necessary
3: I1114 02:34:39.183887 15976 master.cpp:6032] Received register agent message
from slave(747)@172.17.0.3:42601 (7ca2192713be)
3: I1114 02:34:39.183925 15976 master.cpp:3870] Authorizing agent with
principal 'test-principal'
3: I1114 02:34:39.184371 15981 master.cpp:6092] Authorized registration of
agent at slave(747)@172.17.0.3:42601 (7ca2192713be)
3: I1114 02:34:39.184489 15981 master.cpp:6185] Registering agent at
slave(747)@172.17.0.3:42601 (7ca2192713be) with id
16bb6407-1ae6-4730-a008-15daabdaaf61-S0
3: I1114 02:34:39.184878 15974 registrar.cpp:495] Applied 1 operations in
92145ns; attempting to update the registry
3: I1114 02:34:39.185531 15974 registrar.cpp:552] Successfully updated the
registry in 579072ns
3: I1114 02:34:39.185720 15984 master.cpp:6232] Admitted agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at slave(747)@172.17.0.3:42601
(7ca2192713be)
3: I1114 02:34:39.186472 15965 slave.cpp:1207] Registered with master
master@172.17.0.3:42601; given agent ID 16bb6407-1ae6-4730-a008-15daabdaaf61-S0
3: I1114 02:34:39.186578 15970 status_update_manager.cpp:184] Resuming sending
status updates
3: I1114 02:34:39.186342 15984 master.cpp:6263] Registered agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at slave(747)@172.17.0.3:42601
(7ca2192713be) 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:34:39.186686 15967 hierarchical.cpp:593] Added agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 (7ca2192713be) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
3: I1114 02:34:39.186877 15965 slave.cpp:1227] Checkpointing SlaveInfo to
'/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_jFEYDb/meta/slaves/16bb6407-1ae6-4730-a008-15daabdaaf61-S0/slave.info'
3: I1114 02:34:39.186990 15967 hierarchical.cpp:1450] Performed allocation for
1 agents in 158018ns
3: I1114 02:34:39.187342 15965 slave.cpp:1285] Forwarding total resources
cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:34:39.187413 15965 slave.cpp:1295] Forwarding total oversubscribed
resources {}
3: I1114 02:34:39.188241 15973 master.cpp:7051] Received update of agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at slave(747)@172.17.0.3:42601
(7ca2192713be) with total resources cpus:2; mem:1024; disk:1024;
ports:[31000-32000]
3: I1114 02:34:39.188323 15973 master.cpp:7064] Received update of agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at slave(747)@172.17.0.3:42601
(7ca2192713be) with total oversubscribed resources {}
3: I1114 02:34:39.188524 15987 http_connection.hpp:221] New endpoint detected
at http://172.17.0.3:42601/slave(747)/api/v1/resource_provider
3: I1114 02:34:39.188621 15973 master.cpp:7082] Ignoring update on agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at slave(747)@172.17.0.3:42601
(7ca2192713be) as it reports no changes
3: I1114 02:34:39.190652 15964 http_connection.hpp:277] Connected with the
remote endpoint at http://172.17.0.3:42601/slave(747)/api/v1/resource_provider
3: I1114 02:34:39.191521 15965 http_connection.hpp:129] Sending 1 call to
http://172.17.0.3:42601/slave(747)/api/v1/resource_provider
3: I1114 02:34:39.192695 15977 process.cpp:3925] Handling HTTP event for
process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:34:39.193668 15981 http.cpp:1185] HTTP POST for
/slave(747)/api/v1/resource_provider from 172.17.0.3:59786
3: I1114 02:34:39.193956 15968 manager.cpp:381] Subscribing resource provider
{"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:34:39.196357 15985 http_connection.hpp:129] Sending 3 call to
http://172.17.0.3:42601/slave(747)/api/v1/resource_provider
3: I1114 02:34:39.197398 15978 process.cpp:3925] Handling HTTP event for
process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:34:39.198339 15979 http.cpp:1185] HTTP POST for
/slave(747)/api/v1/resource_provider from 172.17.0.3:59788
3: I1114 02:34:39.198784 15967 slave.cpp:6719] Handling resource provider
message 'UPDATE_TOTAL_RESOURCES: 55860c78-4e1e-4cfa-951a-0cce7dc97087
disk[RAW]:200'
3: I1114 02:34:39.198909 15967 slave.cpp:6764] Forwarding new total resources
cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:34:39.199757 15971 master.cpp:7051] Received update of agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at slave(747)@172.17.0.3:42601
(7ca2192713be) with total resources cpus:2; mem:1024; disk:1024;
ports:[31000-32000]; disk[RAW]:200
3: I1114 02:34:39.200860 15963 scheduler.cpp:190] Version: 1.5.0
3: I1114 02:34:39.200963 15973 hierarchical.cpp:660] Agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 (7ca2192713be) updated with total
resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:34:39.201164 15964 scheduler.cpp:313] Using default 'basic' HTTP
authenticatee
3: I1114 02:34:39.201418 15973 hierarchical.cpp:1450] Performed allocation for
1 agents in 183559ns
3: I1114 02:34:39.201591 15979 scheduler.cpp:496] New master detected at
master@172.17.0.3:42601
3: I1114 02:34:39.201619 15979 scheduler.cpp:505] Waiting for 0ns before
initiating a re-(connection) attempt with the master
3: I1114 02:34:39.204567 15983 scheduler.cpp:387] Connected with the master at
http://172.17.0.3:42601/master/api/v1/scheduler
3: I1114 02:34:39.205622 15970 scheduler.cpp:249] Sending SUBSCRIBE call to
http://172.17.0.3:42601/master/api/v1/scheduler
3: I1114 02:34:39.206929 15967 process.cpp:3925] Handling HTTP event for
process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:34:39.207995 15976 http.cpp:1185] HTTP POST for
/master/api/v1/scheduler from 172.17.0.3:59790
3: I1114 02:34:39.208261 15976 master.cpp:2613] Received subscription request
for HTTP framework 'default'
3: I1114 02:34:39.208299 15976 master.cpp:2278] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
3: I1114 02:34:39.208818 15985 master.cpp:2748] Subscribing framework 'default'
with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1114 02:34:39.209534 15975 hierarchical.cpp:303] Added framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000
3: I1114 02:34:39.210137 15972 scheduler.cpp:741] Enqueuing event SUBSCRIBED
received from http://172.17.0.3:42601/master/api/v1/scheduler
3: I1114 02:34:39.210505 15972 scheduler.cpp:741] Enqueuing event HEARTBEAT
received from http://172.17.0.3:42601/master/api/v1/scheduler
3: I1114 02:34:39.210866 15975 hierarchical.cpp:1450] Performed allocation for
1 agents in 1.212882ms
3: I1114 02:34:39.211472 15972 master.cpp:8115] Sending 1 offers to framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000 (default)
3: I1114 02:34:39.213376 15973 scheduler.cpp:741] Enqueuing event OFFERS
received from http://172.17.0.3:42601/master/api/v1/scheduler
3: I1114 02:34:39.214699 15980 scheduler.cpp:249] Sending ACCEPT call to
http://172.17.0.3:42601/master/api/v1/scheduler
3: I1114 02:34:39.215986 15981 process.cpp:3925] Handling HTTP event for
process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:34:39.217139 15984 http.cpp:1185] HTTP POST for
/master/api/v1/scheduler from 172.17.0.3:59792
3: I1114 02:34:39.218134 15984 master.cpp:10017] Removing offer
16bb6407-1ae6-4730-a008-15daabdaaf61-O0
3: I1114 02:34:39.218253 15984 master.cpp:4228] Processing ACCEPT call for
offers: [ 16bb6407-1ae6-4730-a008-15daabdaaf61-O0 ] on agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at slave(747)@172.17.0.3:42601
(7ca2192713be) for framework 16bb6407-1ae6-4730-a008-15daabdaaf61-0000 (default)
3: I1114 02:34:39.218967 15984 master.cpp:5293] Processing CREATE_BLOCK
operation with source disk(allocated: *)[RAW]:200 from framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000 (default) to agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at slave(747)@172.17.0.3:42601
(7ca2192713be)
3: I1114 02:34:39.219581 15984 master.cpp:9900] Sending offer operation Ö®T
Gu½BõSC to agent 16bb6407-1ae6-4730-a008-15daabdaaf61-S0 at
slave(747)@172.17.0.3:42601 (7ca2192713be)
3: I1114 02:34:39.220638 15965 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
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 from framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000
3: I1114 02:34:39.220705 15965 hierarchical.cpp:1171] Framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000 filtered agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 for 5secs
3: I1114 02:34:39.222419 15975 http_connection.hpp:129] Sending 2 call to
http://172.17.0.3:42601/slave(747)/api/v1/resource_provider
3: I1114 02:34:39.223449 15972 process.cpp:3925] Handling HTTP event for
process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:34:39.261922 15984 http.cpp:1185] HTTP POST for
/slave(747)/api/v1/resource_provider from 172.17.0.3:59788
3: I1114 02:34:39.262662 15967 slave.cpp:6719] Handling resource provider
message 'UPDATE_OFFER_OPERATION_STATUS:
{"framework_id":{"value":"16bb6407-1ae6-4730-a008-15daabdaaf61-0000"},"status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"55860c78-4e1e-4cfa-951a-0cce7dc97087"},"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":"55860c78-4e1e-4cfa-951a-0cce7dc97087"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"operation_uuid":"1oeuVAmfR3WWvUIBGfVTQw=="}'
3: I1114 02:34:39.263017 15967 slave.cpp:6893] Updating the state of offer
operation '' (uuid: Ö®T Gu½BõSC) of framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000 (latest state:
OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: I1114 02:34:39.263345 15967 slave.cpp:6817] Forwarding status update of
offer operation '' (uuid: d687ae54-099f-4775-96bd-420119f55343) for framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000
3: I1114 02:34:39.263851 15976 master.cpp:9698] Updating the state of offer
operation '' (uuid: d687ae54-099f-4775-96bd-420119f55343) of framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000 (latest state:
OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: F1114 02:34:39.264152 15976 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: @ 0x2afb83ba7a53 google::LogMessage::SendToLog()
3: @ 0x2afb83ba7ecf google::LogMessage::Flush()
3: @ 0x2afb83bab95d google::LogMessageFatal::~LogMessageFatal()
3: I1114 02:34:39.265645 15980 hierarchical.cpp:849] Updated allocation of
framework 16bb6407-1ae6-4730-a008-15daabdaaf61-0000 on agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 from disk(allocated: *)[RAW]:200 to
disk(allocated: *)[BLOCK]:200
3: I1114 02:34:39.266182 15980 hierarchical.cpp:1125] Recovered disk(allocated:
*)[BLOCK]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000];
disk[BLOCK]:200, allocated: {}) on agent
16bb6407-1ae6-4730-a008-15daabdaaf61-S0 from framework
16bb6407-1ae6-4730-a008-15daabdaaf61-0000
3: @ 0xa9ec97 _CheckFatal::~_CheckFatal()
3: I1114 02:34:39.276633 15968 hierarchical.cpp:1450] Performed allocation for
1 agents in 1.301225ms
3: @ 0x2afb80999cbb mesos::internal::master::Slave::apply()
3: @ 0x2afb8098f554
mesos::internal::master::Master::updateOfferOperation()
3: @ 0x2afb8094058d
mesos::internal::master::Master::offerOperationStatusUpdate()
3: @ 0x2afb80ab12b4 ProtobufProcess<>::_handlerM<>()
3: @ 0x2afb80ab1d9c
_ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSD_SF_EJLm0ELm1ELm2ELm3EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
3: @ 0x2afb80ab1c93
_ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEEclIJSD_SF_EvEET0_DpOT_
3: @ 0x2afb80ab1a17 std::_Function_handler<>::_M_invoke()
3: @ 0xce8310 std::function<>::operator()()
3: @ 0x2afb809b6cc6 ProtobufProcess<>::visit()
3: @ 0x2afb8094e425 mesos::internal::master::Master::_visit()
3: @ 0x2afb8094da44 mesos::internal::master::Master::visit()
3: @ 0x2afb8094e637 mesos::internal::master::Master::visit()
3: @ 0x2afb80a903be process::MessageEvent::visit()
3: @ 0xae66a1 process::ProcessBase::serve()
3: @ 0x2afb83154282 process::ProcessManager::resume()
3: @ 0x2afb83164311
process::ProcessManager::init_threads()::$_8::operator()()
3: @ 0x2afb83164255
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvE3$_8vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE
3: @ 0x2afb83164225 std::_Bind_simple<>::operator()()
3: @ 0x2afb831641fc std::thread::_Impl<>::_M_run()
3: @ 0x2afb874c4a60 (unknown)
3: @ 0x2afb83dd4184 start_thread
3: @ 0x2afb87d30ffd (unknown)
3/3 Test #3: MesosTests .......................***Exception: Other310.04 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 329.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]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make: *** [check] Error 2
+ docker rmi mesos-1510625254-24153
Untagged: mesos-1510625254-24153:latest
Deleted: sha256:39253d8ba74af8c7ef229a91cab819d977c1410f57c5b613ec9273b96152520b
Deleted: sha256:b686f79be8707d62e32dcd269cc2be5f540782f114a903bf75a7f78e5ec808a4
Deleted: sha256:733ccf695c83b1e74a57053872bdd228d63ec7ce186e3925d8cb186525e010e9
Deleted: sha256:2cb654bf97d8b702b50cd123d8967ec6c9cc0f4c620d59bf374ee9fb3fa06941
Deleted: sha256:f8f24b76b6b3b54aca628adb886531e6f0dcdcf8dbf9a1b219a666797547b145
Deleted: sha256:ab1bc22ac7c85865d96013cc92af42278db7270d03e59aa686db26d43016070c
Deleted: sha256:d2425bc240bf457fdb027b05cf4fee68c73034598158ec23ce73d9a398414e0a
Deleted: sha256:c7c065e867d714c729ad5bef5d83ba1fcd8dba7bb8fc0a39a6d41ed6043d9a5d
Deleted: sha256:3ec298f78d9f1b4579b86f0a0338c92eed9302426e62ef2b3f343483a1519686
Deleted: sha256:bcce74e29c86e5437fd70adc3bbabf277e71f3b96e44db07ceeac859f2d64de1
Deleted: sha256:6b7ad7f666e32bf2fd33cbcfa1163ae52fef50e5221197bb86e7f2daaedb234d
Deleted: sha256:3a353ba349b9c6aa50d1ea21262d7b252192209e0f3ebf428d76b6a6394618e4
Deleted: sha256:835c59a387711906e3fc51ff573545301bdf0de4f62458334b0687c1165d55e4
Deleted: sha256:895a9c00aada2c78a8bc3571d7e65ff635cc73afe9e1879ba87293debb7c9f35
Deleted: sha256:1821a2167d67b456f1617a6fe52d6a641c11f2420e68009386ca7a082688ac6d
Deleted: sha256:7e1c39282366b99ebd9ee97dd2e10985ce23948c48d8e7e08b8154ea1374e253
Deleted: sha256:4694fba821686cc6951bfba5df3e9ab2241164e998ee89b93e9ceb1eacfee3fe
Deleted: sha256:dab41a317d018a9b09b9886399930630b05011cf8c2dc67eb8a7a287e5f17d21
Deleted: sha256:8012fff753e68635589710d16b3c453a0959b9784e265f8627cb5c4a519b2cee
Deleted: sha256:3bdfe9951a630e22514862153f253743f9b6d4fca75e01adce2d0e168f551790
Deleted: sha256:18559b3012b4a56f499224f5cbb4a6a67878ea2296a883775ae1eaa76c062d56
Deleted: sha256:fba2cf5154a6b30a3a3d3b7ce12e819e00ded13c113d2e5bc982fe30b71e6f71
Deleted: sha256:2d846f51a1abf299badc5c7e82762d631b43a9bb8052b17a83d3853ec84f7799
Deleted: sha256:61984447d1055b041747a16d1f694a3c7368695b955a164d13e0ca511b9c853a
Deleted: sha256:018e4ca8786a6def3256da540ca47f9700d79a5ad744c2536fa84a49d9272256
Deleted: sha256:9cde38dc6595b56202c2600e120b068a543a76129824bd96fad0228244627884
Deleted: sha256:b766060373df7c1a07801b8c134bde2fe1a66264285ba923aaa7d981ead59aaf
Deleted: sha256:0ddd1517bcd1ac561d45b7cb555b0cf0cd511d95a368988ebc23de403cc8d4fe
Deleted: sha256:64de86b47dfbab8a491be7db26bfd0c147dbe7f5f9bc97ac5922ae93af350db3
Deleted: sha256:e083970ed855bf7688276ae779bd6370f92d852b3051c8496001b5eed6b7304a
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1...@gmail.com
Not sending mail to unregistered user yujie....@gmail.com
