You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/11/14 02:48:05 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4452
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4452/display/redirect>
------------------------------------------
[...truncated 27.74 MB...]
3: I1114 02:47:56.776679 18233 slave.cpp:610] Agent hostname: 4db5c295ce80
3: I1114 02:47:56.776844 18228 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:47:56.778955 18211 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_AgentEndpoint_1_yaroou/meta'
3: I1114 02:47:56.779310 18213 status_update_manager.cpp:203] Recovering status update manager
3: I1114 02:47:56.779556 18231 containerizer.cpp:662] Recovering containerizer
3: I1114 02:47:56.781085 18226 provisioner.cpp:416] Provisioner recovery complete
3: I1114 02:47:56.781733 18212 slave.cpp:6432] Finished recovery
3: I1114 02:47:56.782922 18213 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:47:56.782920 18219 slave.cpp:1007] New master detected at master@172.17.0.2:44133
3: I1114 02:47:56.783013 18219 slave.cpp:1042] Detecting new master
3: I1114 02:47:56.786283 18223 process.cpp:3925] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/resource_provider'
3: I1114 02:47:56.789172 18228 http.cpp:1185] HTTP POST for /slave(747)/api/v1/resource_provider from 172.17.0.2:46334
3: I1114 02:47:56.789860 18232 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:47:56.796751 18210 slave.cpp:883] Agent terminating
3: I1114 02:47:56.806051 18210 master.cpp:1151] Master terminating
3: [ OK ] ContentType/ResourceProviderManagerHttpApiTest.AgentEndpoint/1 (61 ms)
3: [ RUN ] ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0
3: I1114 02:47:56.815045 18210 cluster.cpp:162] Creating default 'local' authorizer
3: I1114 02:47:56.818871 18214 master.cpp:448] Master 9b763f93-b786-4311-bd1b-b53089822b5f (4db5c295ce80) started on 172.17.0.2:44133
3: I1114 02:47:56.818902 18214 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/deH6ae/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/deH6ae/master" --zk_session_timeout="10secs"
3: I1114 02:47:56.819344 18214 master.cpp:499] Master only allowing authenticated frameworks to register
3: I1114 02:47:56.819355 18214 master.cpp:505] Master only allowing authenticated agents to register
3: I1114 02:47:56.819360 18214 master.cpp:511] Master only allowing authenticated HTTP frameworks to register
3: I1114 02:47:56.819366 18214 credentials.hpp:37] Loading credentials for authentication from '/tmp/deH6ae/credentials'
3: I1114 02:47:56.819723 18214 master.cpp:555] Using default 'crammd5' authenticator
3: I1114 02:47:56.819897 18214 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1114 02:47:56.820096 18214 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1114 02:47:56.820294 18214 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1114 02:47:56.820471 18214 master.cpp:634] Authorization enabled
3: I1114 02:47:56.820688 18228 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1114 02:47:56.820710 18219 whitelist_watcher.cpp:77] No whitelist given
3: I1114 02:47:56.823879 18231 master.cpp:2213] Elected as the leading master!
3: I1114 02:47:56.823909 18231 master.cpp:1693] Recovering from registrar
3: I1114 02:47:56.824055 18229 registrar.cpp:347] Recovering registrar
3: I1114 02:47:56.824723 18229 registrar.cpp:391] Successfully fetched the registry (0B) in 619008ns
3: I1114 02:47:56.824892 18229 registrar.cpp:495] Applied 1 operations in 51707ns; attempting to update the registry
3: I1114 02:47:56.825708 18229 registrar.cpp:552] Successfully updated the registry in 741888ns
3: I1114 02:47:56.825860 18229 registrar.cpp:424] Successfully recovered registrar
3: I1114 02:47:56.826344 18213 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1114 02:47:56.826407 18212 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1114 02:47:56.832712 18210 process.cpp:3178] Attempted to spawn already running process files@172.17.0.2:44133
3: I1114 02:47:56.833837 18210 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1114 02:47:56.834429 18210 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1114 02:47:56.834563 18210 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1114 02:47:56.834600 18210 provisioner.cpp:255] Using default backend 'copy'
3: I1114 02:47:56.836902 18210 cluster.cpp:448] Creating default 'local' authorizer
3: I1114 02:47:56.839413 18230 slave.cpp:262] Mesos agent started on (748)@172.17.0.2:44133
3: I1114 02:47:56.839439 18230 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_0AVseo/store/appc" --authenticate_http_executors="true" --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_0AVseo/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_0AVseo/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_secret_key="/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_0AVseo/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_0AVseo/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_0AVseo/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_0AVseo" --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_vs9LcO" --zk_session_timeout="10secs"
3: I1114 02:47:56.840070 18230 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_0AVseo/credential'
3: I1114 02:47:56.840255 18230 slave.cpp:295] Agent using credential for: test-principal
3: I1114 02:47:56.840277 18230 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_0AVseo/http_credentials'
3: I1114 02:47:56.840620 18230 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
3: I1114 02:47:56.840759 18230 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
3: I1114 02:47:56.841102 18230 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1114 02:47:56.841264 18230 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1114 02:47:56.843703 18230 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:47:56.844089 18230 slave.cpp:601] Agent attributes: [ ]
3: I1114 02:47:56.844105 18230 slave.cpp:610] Agent hostname: 4db5c295ce80
3: I1114 02:47:56.844386 18221 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:47:56.846709 18224 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_vs9LcO/meta'
3: I1114 02:47:56.847112 18224 status_update_manager.cpp:203] Recovering status update manager
3: I1114 02:47:56.847386 18220 containerizer.cpp:662] Recovering containerizer
3: I1114 02:47:56.849406 18234 provisioner.cpp:416] Provisioner recovery complete
3: I1114 02:47:56.849922 18221 slave.cpp:6432] Finished recovery
3: I1114 02:47:56.851034 18221 slave.cpp:1007] New master detected at master@172.17.0.2:44133
3: I1114 02:47:56.851063 18233 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:47:56.851147 18221 slave.cpp:1042] Detecting new master
3: I1114 02:47:56.859661 18227 slave.cpp:1069] Authenticating with master master@172.17.0.2:44133
3: I1114 02:47:56.859777 18227 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1114 02:47:56.860227 18213 authenticatee.cpp:121] Creating new client SASL connection
3: I1114 02:47:56.860657 18224 master.cpp:8285] Authenticating slave(748)@172.17.0.2:44133
3: I1114 02:47:56.861068 18216 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1378)@172.17.0.2:44133
3: I1114 02:47:56.861439 18211 authenticator.cpp:98] Creating new server SASL connection
3: I1114 02:47:56.861824 18231 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1114 02:47:56.861927 18231 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1114 02:47:56.862188 18228 authenticator.cpp:204] Received SASL authentication start
3: I1114 02:47:56.862291 18228 authenticator.cpp:326] Authentication requires more steps
3: I1114 02:47:56.862550 18225 authenticatee.cpp:259] Received SASL authentication step
3: I1114 02:47:56.862787 18219 authenticator.cpp:232] Received SASL authentication step
3: I1114 02:47:56.862838 18219 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4db5c295ce80' server FQDN: '4db5c295ce80' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1114 02:47:56.862857 18219 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1114 02:47:56.862908 18219 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1114 02:47:56.863034 18219 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4db5c295ce80' server FQDN: '4db5c295ce80' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1114 02:47:56.863075 18219 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:47:56.863086 18219 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:47:56.863112 18219 authenticator.cpp:318] Authentication success
3: I1114 02:47:56.863262 18214 authenticatee.cpp:299] Authentication success
3: I1114 02:47:56.863401 18220 master.cpp:8315] Successfully authenticated principal 'test-principal' at slave(748)@172.17.0.2:44133
3: I1114 02:47:56.863531 18218 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1378)@172.17.0.2:44133
3: I1114 02:47:56.863687 18217 slave.cpp:1161] Successfully authenticated with master master@172.17.0.2:44133
3: I1114 02:47:56.864039 18217 slave.cpp:1682] Will retry registration in 10.661507ms if necessary
3: I1114 02:47:56.864406 18222 master.cpp:6032] Received register agent message from slave(748)@172.17.0.2:44133 (4db5c295ce80)
3: I1114 02:47:56.864601 18222 master.cpp:3870] Authorizing agent with principal 'test-principal'
3: I1114 02:47:56.865312 18229 master.cpp:6092] Authorized registration of agent at slave(748)@172.17.0.2:44133 (4db5c295ce80)
3: I1114 02:47:56.865490 18229 master.cpp:6185] Registering agent at slave(748)@172.17.0.2:44133 (4db5c295ce80) with id 9b763f93-b786-4311-bd1b-b53089822b5f-S0
3: I1114 02:47:56.866159 18233 registrar.cpp:495] Applied 1 operations in 89461ns; attempting to update the registry
3: I1114 02:47:56.867024 18233 registrar.cpp:552] Successfully updated the registry in 770048ns
3: I1114 02:47:56.867339 18213 master.cpp:6232] Admitted agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80)
3: I1114 02:47:56.868449 18216 slave.cpp:1207] Registered with master master@172.17.0.2:44133; given agent ID 9b763f93-b786-4311-bd1b-b53089822b5f-S0
3: I1114 02:47:56.868597 18228 status_update_manager.cpp:184] Resuming sending status updates
3: I1114 02:47:56.868299 18213 master.cpp:6263] Registered agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80) 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:47:56.868909 18216 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_vs9LcO/meta/slaves/9b763f93-b786-4311-bd1b-b53089822b5f-S0/slave.info'
3: I1114 02:47:56.868880 18211 hierarchical.cpp:593] Added agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 (4db5c295ce80) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1114 02:47:56.869416 18211 hierarchical.cpp:1450] Performed allocation for 1 agents in 265351ns
3: I1114 02:47:56.869475 18216 slave.cpp:1285] Forwarding total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:47:56.869567 18216 slave.cpp:1295] Forwarding total oversubscribed resources {}
3: I1114 02:47:56.870440 18225 master.cpp:7051] Received update of agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:47:56.870534 18225 master.cpp:7064] Received update of agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80) with total oversubscribed resources {}
3: I1114 02:47:56.870810 18225 master.cpp:7082] Ignoring update on agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80) as it reports no changes
3: I1114 02:47:56.871076 18225 http_connection.hpp:221] New endpoint detected at http://172.17.0.2:44133/slave(748)/api/v1/resource_provider
3: I1114 02:47:56.873625 18230 http_connection.hpp:277] Connected with the remote endpoint at http://172.17.0.2:44133/slave(748)/api/v1/resource_provider
3: I1114 02:47:56.874943 18211 http_connection.hpp:129] Sending 1 call to http://172.17.0.2:44133/slave(748)/api/v1/resource_provider
3: I1114 02:47:56.877102 18220 process.cpp:3925] Handling HTTP event for process 'slave(748)' with path: '/slave(748)/api/v1/resource_provider'
3: I1114 02:47:56.878615 18217 http.cpp:1185] HTTP POST for /slave(748)/api/v1/resource_provider from 172.17.0.2:46338
3: I1114 02:47:56.879035 18215 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:47:56.883260 18218 http_connection.hpp:129] Sending 3 call to http://172.17.0.2:44133/slave(748)/api/v1/resource_provider
3: I1114 02:47:56.885274 18229 process.cpp:3925] Handling HTTP event for process 'slave(748)' with path: '/slave(748)/api/v1/resource_provider'
3: I1114 02:47:56.886814 18224 http.cpp:1185] HTTP POST for /slave(748)/api/v1/resource_provider from 172.17.0.2:46336
3: I1114 02:47:56.887471 18230 slave.cpp:6719] Handling resource provider message 'UPDATE_TOTAL_RESOURCES: 9439bfe0-f618-444c-ba8f-9f96bc1779a5 disk[RAW]:200'
3: I1114 02:47:56.887640 18230 slave.cpp:6764] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:47:56.888715 18216 master.cpp:7051] Received update of agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:47:56.890635 18210 scheduler.cpp:190] Version: 1.5.0
3: I1114 02:47:56.890803 18219 hierarchical.cpp:660] Agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 (4db5c295ce80) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:47:56.891072 18217 scheduler.cpp:313] Using default 'basic' HTTP authenticatee
3: I1114 02:47:56.891263 18219 hierarchical.cpp:1450] Performed allocation for 1 agents in 205477ns
3: I1114 02:47:56.891618 18228 scheduler.cpp:496] New master detected at master@172.17.0.2:44133
3: I1114 02:47:56.891651 18228 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master
3: I1114 02:47:56.893541 18214 scheduler.cpp:387] Connected with the master at http://172.17.0.2:44133/master/api/v1/scheduler
3: I1114 02:47:56.895010 18218 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:44133/master/api/v1/scheduler
3: I1114 02:47:56.897558 18224 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:47:56.899000 18226 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:46342
3: I1114 02:47:56.899344 18226 master.cpp:2613] Received subscription request for HTTP framework 'default'
3: I1114 02:47:56.899487 18226 master.cpp:2278] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1114 02:47:56.900163 18223 master.cpp:2748] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1114 02:47:56.901159 18212 hierarchical.cpp:303] Added framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000
3: I1114 02:47:56.902307 18228 scheduler.cpp:741] Enqueuing event SUBSCRIBED received from http://172.17.0.2:44133/master/api/v1/scheduler
3: I1114 02:47:56.902976 18228 scheduler.cpp:741] Enqueuing event HEARTBEAT received from http://172.17.0.2:44133/master/api/v1/scheduler
3: I1114 02:47:56.903614 18212 hierarchical.cpp:1450] Performed allocation for 1 agents in 2.256563ms
3: I1114 02:47:56.904294 18228 master.cpp:8115] Sending 1 offers to framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000 (default)
3: I1114 02:47:56.906893 18218 scheduler.cpp:741] Enqueuing event OFFERS received from http://172.17.0.2:44133/master/api/v1/scheduler
3: I1114 02:47:56.908813 18211 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:44133/master/api/v1/scheduler
3: I1114 02:47:56.910643 18222 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:47:56.912811 18232 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:46340
3: I1114 02:47:56.914619 18232 master.cpp:10017] Removing offer 9b763f93-b786-4311-bd1b-b53089822b5f-O0
3: I1114 02:47:56.914803 18232 master.cpp:4228] Processing ACCEPT call for offers: [ 9b763f93-b786-4311-bd1b-b53089822b5f-O0 ] on agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80) for framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000 (default)
3: I1114 02:47:56.916015 18232 master.cpp:5293] Processing CREATE_BLOCK operation with source disk(allocated: *)[RAW]:200 from framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000 (default) to agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80)
3: I1114 02:47:56.916972 18232 master.cpp:9900] Sending offer operation g1î[tE½©bÌømî to agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 at slave(748)@172.17.0.2:44133 (4db5c295ce80)
3: I1114 02:47:56.918848 18233 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 9b763f93-b786-4311-bd1b-b53089822b5f-S0 from framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000
3: I1114 02:47:56.918964 18233 hierarchical.cpp:1171] Framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000 filtered agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 for 5secs
3: I1114 02:47:56.921021 18228 http_connection.hpp:129] Sending 2 call to http://172.17.0.2:44133/slave(748)/api/v1/resource_provider
3: I1114 02:47:56.922132 18217 process.cpp:3925] Handling HTTP event for process 'slave(748)' with path: '/slave(748)/api/v1/resource_provider'
3: I1114 02:47:56.962473 18232 http.cpp:1185] HTTP POST for /slave(748)/api/v1/resource_provider from 172.17.0.2:46336
3: I1114 02:47:56.963385 18224 slave.cpp:6719] Handling resource provider message 'UPDATE_OFFER_OPERATION_STATUS: {"framework_id":{"value":"9b763f93-b786-4311-bd1b-b53089822b5f-0000"},"status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"9439bfe0-f618-444c-ba8f-9f96bc1779a5"},"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":"9439bfe0-f618-444c-ba8f-9f96bc1779a5"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"operation_uuid":"lmcx7lt0Rb2pYhTM+Adt7g=="}'
3: I1114 02:47:56.963904 18224 slave.cpp:6893] Updating the state of offer operation '' (uuid: g1î[tE½©bÌømî) of framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: I1114 02:47:56.964403 18224 slave.cpp:6817] Forwarding status update of offer operation '' (uuid: 966731ee-5b74-45bd-a962-14ccf8076dee) for framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000
3: I1114 02:47:56.965157 18230 master.cpp:9698] Updating the state of offer operation '' (uuid: 966731ee-5b74-45bd-a962-14ccf8076dee) of framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: F1114 02:47:56.965729 18230 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: @ 0x7f5e3467384d google::LogMessage::Fail()
3: I1114 02:47:56.968173 18211 hierarchical.cpp:849] Updated allocation of framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000 on agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 from disk(allocated: *)[RAW]:200 to disk(allocated: *)[BLOCK]:200
3: @ 0x7f5e3467561c google::LogMessage::SendToLog()
3: I1114 02:47:56.968827 18211 hierarchical.cpp:1125] Recovered disk(allocated: *)[BLOCK]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[BLOCK]:200, allocated: {}) on agent 9b763f93-b786-4311-bd1b-b53089822b5f-S0 from framework 9b763f93-b786-4311-bd1b-b53089822b5f-0000
3: @ 0x7f5e3467343c google::LogMessage::Flush()
3: @ 0x7f5e34675f2e google::LogMessageFatal::~LogMessageFatal()
3: I1114 02:47:56.975294 18220 hierarchical.cpp:1450] Performed allocation for 1 agents in 2.38891ms
3: @ 0xc98901 _CheckFatal::~_CheckFatal()
3: @ 0x7f5e3b647e8b mesos::internal::master::Slave::apply()
3: @ 0x7f5e3b63d736 mesos::internal::master::Master::updateOfferOperation()
3: @ 0x7f5e3b62848d mesos::internal::master::Master::offerOperationStatusUpdate()
3: @ 0x7f5e3b691f2c ProtobufProcess<>::_handlerM<>()
3: @ 0x7f5e3b756f68 _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSD_SF_EJLm0ELm1ELm2ELm3EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
3: @ 0x7f5e3b7334a3 _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEEclIJSD_SF_EvEET0_DpOT_
3: @ 0x7f5e3b704ec2 std::_Function_handler<>::_M_invoke()
3: @ 0xea285d std::function<>::operator()()
3: @ 0x7f5e3b670cda ProtobufProcess<>::visit()
3: @ 0x7f5e3b5fd743 mesos::internal::master::Master::_visit()
3: @ 0x7f5e3b5fce22 mesos::internal::master::Master::visit()
3: @ 0x7f5e3b6546a8 process::MessageEvent::visit()
3: @ 0xcb40a4 process::ProcessBase::serve()
3: @ 0x7f5e35807646 process::ProcessManager::resume()
3: @ 0x7f5e35803a20 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
3: @ 0x7f5e35816d64 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
3: @ 0x7f5e35816ca8 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
3: @ 0x7f5e35816c32 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
3: @ 0x7f5e308c72b0 (unknown)
3: @ 0x7f5e34453e25 start_thread
3: @ 0x7f5e3002f34d __clone
3/3 Test #3: MesosTests .......................***Exception: Other451.21 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 484.98 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-1510625284-3053
Untagged: mesos-1510625284-3053:latest
Deleted: sha256:cccf57e34eca3605f897b00caf65281e30b8a4cc1dd0090bcb8a6d164dd19779
Deleted: sha256:58bcfd050a73e033b64c5e9ce9d66ea30974a80ce6f82c8caf61d43260000e0e
Deleted: sha256:321645414a051072ca61746d234748855c0be502fcd88bb19e65b7ef518e2eec
Deleted: sha256:81f09ca55fcec490c7bc150c2391a50f2b10d0cea80095f79ccd7ce93d9ee5b2
Deleted: sha256:c7e5be8687a9345c839a33bec272e671389281f0cfe609e2f301a362052c25f7
Deleted: sha256:ce0d44f335fa28e05d12a23312ec58f88b1e671bb0ffb87f8bec8aef7bdfb3ea
Deleted: sha256:b32d5b4ceff7c880a5a08c713a7ba0e08e18778a508930b9d34cff57eff8a16b
Deleted: sha256:aa421aa2d02a80261b935f08c2b736baf0602a76d8cc74925a91c5562dee024c
Deleted: sha256:8bde2cad5ac4461ffe43f1efaf6336a5c8d0e98ed3c8d343455941a0c28e3554
Deleted: sha256:746e8e049e66363044aca0d603d053bd667aac6b3ead12d173b76f821a0c4d9d
Deleted: sha256:13cb00467cb6f040f2e5ecfe141b0025bdc90de764ff66ffae26b2f8d8de6125
Deleted: sha256:ede007526967465e0a6dc995b3f4e4d1a6ce6c416ffba8c9bf3210ea878ea96e
Deleted: sha256:92c44063a470487a28ce522f8e9528156fd2e1a154ff27f0ec7cfbecc49b7de6
Deleted: sha256:bfb8ebf6d536afa3467e8ef9021f8bb3c428fda73532373920eb88734079f8a5
Deleted: sha256:714ec647a288c99daf5ef2eaf12405b40a2fea670374a31e5416e2f0d6e83cbc
Deleted: sha256:1953ed81aa8c452731139e72bb206242b3f0ed3973d6d10caa9d2c3589f00529
Deleted: sha256:1ef4ab0cfbc67a94c5ebbd81bea9fb0016cc08314dce9e0eecc8dae287cfabe5
Deleted: sha256:d140408d18288d081befb112193d0caa535ea4be0c1443f90e25fe366e4ee831
Deleted: sha256:e7251808f69e0e1885d67cc87c801dd40a0a56aa2f221245ef2dc9adb5b9ded1
Deleted: sha256:6788187420d2936c3ca05f792cdf91052ef94ee1b56fa658b7cee1c8fe7417d0
Deleted: sha256:4977807abdd585f1b5a0e43ad6733e69d4b4da8c940ff1caeaa8af4b59941a0c
Deleted: sha256:c12dcb93907a76cb01983de82321bcf572914ffba5daef5ab90d59eefdad1461
Deleted: sha256:0fec4c39e5191422578741afcc6338111c08e894425a55becba6ee41ee43a865
Deleted: sha256:3c592a90b6162f961fcc9d4b61682ec9d997b2ef7cf61ea12149c32b48db8040
Deleted: sha256:85ee75b31bfc3139d9aba2742dfc93045c966c93d03768a62706fa3549649d95
Deleted: sha256:96eaba61e5a9090a18637a7e5a0c1e623224656a4ed79174a675a7aa38fbc97f
Deleted: sha256:489096f1a9e05c5a1dbd4eee24f5c229adac353cda162e05ad60f01dce835c0f
Deleted: sha256:a58d0697efb92463bf43dc6bdef2f4edae7530a982a83dbdff2fc6a9fd81317a
Deleted: sha256:b8a6c166ae2c3762180394b4653bb6febcd12e4c4c85dac8b3d67025a11c8b29
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user songzihao1990@gmail.com
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4455
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4455/display/redirect?page=changes>