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:42:26 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,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,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4452/display/redirect>
------------------------------------------
[...truncated 26.91 MB...]
3: I1114 02:42:04.541352 15903 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1114 02:42:04.542703 15903 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:42:04.542929 15903 slave.cpp:601] Agent attributes: [ ]
3: I1114 02:42:04.542938 15903 slave.cpp:610] Agent hostname: d85273cd0d8f
3: I1114 02:42:04.543061 15905 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:42:04.544489 15906 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_AgentEndpoint_1_2OU8Cd/meta'
3: I1114 02:42:04.544876 15908 status_update_manager.cpp:203] Recovering status update manager
3: I1114 02:42:04.545126 15901 containerizer.cpp:662] Recovering containerizer
3: I1114 02:42:04.546654 15906 provisioner.cpp:416] Provisioner recovery complete
3: I1114 02:42:04.547108 15908 slave.cpp:6432] Finished recovery
3: I1114 02:42:04.548061 15901 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:42:04.548074 15895 slave.cpp:1007] New master detected at master@172.17.0.2:48986
3: I1114 02:42:04.548132 15895 slave.cpp:1042] Detecting new master
3: I1114 02:42:04.551425 15893 process.cpp:3925] Handling HTTP event for process 'slave(743)' with path: '/slave(743)/api/v1/resource_provider'
3: I1114 02:42:04.553048 15908 http.cpp:1185] HTTP POST for /slave(743)/api/v1/resource_provider from 172.17.0.2:33969
3: I1114 02:42:04.553499 15905 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:42:04.560235 15892 slave.cpp:883] Agent terminating
3: I1114 02:42:04.567934 15892 master.cpp:1151] Master terminating
3: [ OK ] ContentType/ResourceProviderManagerHttpApiTest.AgentEndpoint/1 (61 ms)
3: [ RUN ] ContentType/ResourceProviderManagerHttpApiTest.ConvertResources/0
3: I1114 02:42:04.575096 15892 cluster.cpp:162] Creating default 'local' authorizer
3: I1114 02:42:04.578608 15894 master.cpp:448] Master f1d8404c-289b-4691-9647-60d13b1fcd30 (d85273cd0d8f) started on 172.17.0.2:48986
3: I1114 02:42:04.578627 15894 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/KxlaaZ/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/KxlaaZ/master" --zk_session_timeout="10secs"
3: I1114 02:42:04.578884 15894 master.cpp:499] Master only allowing authenticated frameworks to register
3: I1114 02:42:04.578891 15894 master.cpp:505] Master only allowing authenticated agents to register
3: I1114 02:42:04.578896 15894 master.cpp:511] Master only allowing authenticated HTTP frameworks to register
3: I1114 02:42:04.578900 15894 credentials.hpp:37] Loading credentials for authentication from '/tmp/KxlaaZ/credentials'
3: I1114 02:42:04.579159 15894 master.cpp:555] Using default 'crammd5' authenticator
3: I1114 02:42:04.579329 15894 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1114 02:42:04.579506 15894 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1114 02:42:04.579686 15894 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1114 02:42:04.579846 15894 master.cpp:634] Authorization enabled
3: I1114 02:42:04.580025 15897 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1114 02:42:04.580060 15904 whitelist_watcher.cpp:77] No whitelist given
3: I1114 02:42:04.583287 15907 master.cpp:2213] Elected as the leading master!
3: I1114 02:42:04.583328 15907 master.cpp:1693] Recovering from registrar
3: I1114 02:42:04.583495 15902 registrar.cpp:347] Recovering registrar
3: I1114 02:42:04.584331 15902 registrar.cpp:391] Successfully fetched the registry (0B) in 785920ns
3: I1114 02:42:04.584455 15902 registrar.cpp:495] Applied 1 operations in 32932ns; attempting to update the registry
3: I1114 02:42:04.585089 15902 registrar.cpp:552] Successfully updated the registry in 569088ns
3: I1114 02:42:04.585237 15902 registrar.cpp:424] Successfully recovered registrar
3: I1114 02:42:04.585676 15904 master.cpp:1806] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: I1114 02:42:04.585711 15908 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: W1114 02:42:04.591488 15892 process.cpp:3178] Attempted to spawn already running process files@172.17.0.2:48986
3: I1114 02:42:04.592586 15892 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1114 02:42:04.593243 15892 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1114 02:42:04.593375 15892 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1114 02:42:04.593415 15892 provisioner.cpp:255] Using default backend 'copy'
3: I1114 02:42:04.595682 15892 cluster.cpp:448] Creating default 'local' authorizer
3: I1114 02:42:04.597667 15896 slave.cpp:262] Mesos agent started on (744)@172.17.0.2:48986
3: I1114 02:42:04.597685 15896 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_uBZFIP/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_uBZFIP/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_uBZFIP/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_uBZFIP/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_uBZFIP/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_uBZFIP" --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_IhuprU" --zk_session_timeout="10secs"
3: I1114 02:42:04.598222 15896 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_uBZFIP/credential'
3: I1114 02:42:04.598448 15896 slave.cpp:295] Agent using credential for: test-principal
3: I1114 02:42:04.598479 15896 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_uBZFIP/http_credentials'
3: I1114 02:42:04.598770 15896 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1114 02:42:04.600653 15896 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:42:04.600967 15896 slave.cpp:601] Agent attributes: [ ]
3: I1114 02:42:04.600980 15896 slave.cpp:610] Agent hostname: d85273cd0d8f
3: I1114 02:42:04.601158 15907 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:42:04.603004 15899 state.cpp:64] Recovering state from '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_IhuprU/meta'
3: I1114 02:42:04.603374 15902 status_update_manager.cpp:203] Recovering status update manager
3: I1114 02:42:04.603593 15897 containerizer.cpp:662] Recovering containerizer
3: I1114 02:42:04.605185 15899 provisioner.cpp:416] Provisioner recovery complete
3: I1114 02:42:04.605671 15903 slave.cpp:6432] Finished recovery
3: I1114 02:42:04.606565 15904 status_update_manager.cpp:177] Pausing sending status updates
3: I1114 02:42:04.606616 15900 slave.cpp:1007] New master detected at master@172.17.0.2:48986
3: I1114 02:42:04.606729 15900 slave.cpp:1042] Detecting new master
3: I1114 02:42:04.617092 15906 slave.cpp:1069] Authenticating with master master@172.17.0.2:48986
3: I1114 02:42:04.617173 15906 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1114 02:42:04.617494 15908 authenticatee.cpp:121] Creating new client SASL connection
3: I1114 02:42:04.617822 15897 master.cpp:8285] Authenticating slave(744)@172.17.0.2:48986
3: I1114 02:42:04.617930 15896 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1370)@172.17.0.2:48986
3: I1114 02:42:04.618221 15894 authenticator.cpp:98] Creating new server SASL connection
3: I1114 02:42:04.618536 15898 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1114 02:42:04.618557 15898 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1114 02:42:04.618674 15901 authenticator.cpp:204] Received SASL authentication start
3: I1114 02:42:04.618734 15901 authenticator.cpp:326] Authentication requires more steps
3: I1114 02:42:04.618849 15901 authenticatee.cpp:259] Received SASL authentication step
3: I1114 02:42:04.618979 15902 authenticator.cpp:232] Received SASL authentication step
3: I1114 02:42:04.619006 15902 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd85273cd0d8f' server FQDN: 'd85273cd0d8f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1114 02:42:04.619024 15902 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1114 02:42:04.619060 15902 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1114 02:42:04.619096 15902 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd85273cd0d8f' server FQDN: 'd85273cd0d8f' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1114 02:42:04.619112 15902 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:42:04.619120 15902 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1114 02:42:04.619138 15902 authenticator.cpp:318] Authentication success
3: I1114 02:42:04.619235 15893 authenticatee.cpp:299] Authentication success
3: I1114 02:42:04.619314 15903 master.cpp:8315] Successfully authenticated principal 'test-principal' at slave(744)@172.17.0.2:48986
3: I1114 02:42:04.619345 15895 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1370)@172.17.0.2:48986
3: I1114 02:42:04.619490 15893 slave.cpp:1161] Successfully authenticated with master master@172.17.0.2:48986
3: I1114 02:42:04.619740 15893 slave.cpp:1682] Will retry registration in 12.271728ms if necessary
3: I1114 02:42:04.620062 15908 master.cpp:6032] Received register agent message from slave(744)@172.17.0.2:48986 (d85273cd0d8f)
3: I1114 02:42:04.620203 15908 master.cpp:3870] Authorizing agent with principal 'test-principal'
3: I1114 02:42:04.620693 15896 master.cpp:6092] Authorized registration of agent at slave(744)@172.17.0.2:48986 (d85273cd0d8f)
3: I1114 02:42:04.620815 15896 master.cpp:6185] Registering agent at slave(744)@172.17.0.2:48986 (d85273cd0d8f) with id f1d8404c-289b-4691-9647-60d13b1fcd30-S0
3: I1114 02:42:04.621294 15894 registrar.cpp:495] Applied 1 operations in 66921ns; attempting to update the registry
3: I1114 02:42:04.621959 15894 registrar.cpp:552] Successfully updated the registry in 598016ns
3: I1114 02:42:04.622193 15907 master.cpp:6232] Admitted agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f)
3: I1114 02:42:04.623070 15904 slave.cpp:1207] Registered with master master@172.17.0.2:48986; given agent ID f1d8404c-289b-4691-9647-60d13b1fcd30-S0
3: I1114 02:42:04.623188 15895 status_update_manager.cpp:184] Resuming sending status updates
3: I1114 02:42:04.622925 15907 master.cpp:6263] Registered agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f) 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:42:04.623370 15903 hierarchical.cpp:593] Added agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 (d85273cd0d8f) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1114 02:42:04.623476 15904 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/ContentType_ResourceProviderManagerHttpApiTest_ConvertResources_0_IhuprU/meta/slaves/f1d8404c-289b-4691-9647-60d13b1fcd30-S0/slave.info'
3: I1114 02:42:04.623764 15903 hierarchical.cpp:1450] Performed allocation for 1 agents in 176883ns
3: I1114 02:42:04.624030 15904 slave.cpp:1285] Forwarding total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:42:04.624107 15904 slave.cpp:1295] Forwarding total oversubscribed resources {}
3: I1114 02:42:04.624845 15893 master.cpp:7051] Received update of agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
3: I1114 02:42:04.624940 15893 master.cpp:7064] Received update of agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f) with total oversubscribed resources {}
3: I1114 02:42:04.625196 15893 master.cpp:7082] Ignoring update on agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f) as it reports no changes
3: I1114 02:42:04.625205 15898 http_connection.hpp:221] New endpoint detected at http://172.17.0.2:48986/slave(744)/api/v1/resource_provider
3: I1114 02:42:04.627429 15906 http_connection.hpp:277] Connected with the remote endpoint at http://172.17.0.2:48986/slave(744)/api/v1/resource_provider
3: I1114 02:42:04.628469 15894 http_connection.hpp:129] Sending 1 call to http://172.17.0.2:48986/slave(744)/api/v1/resource_provider
3: I1114 02:42:04.629941 15907 process.cpp:3925] Handling HTTP event for process 'slave(744)' with path: '/slave(744)/api/v1/resource_provider'
3: I1114 02:42:04.631559 15896 http.cpp:1185] HTTP POST for /slave(744)/api/v1/resource_provider from 172.17.0.2:33971
3: I1114 02:42:04.631965 15902 manager.cpp:381] Subscribing resource provider {"name":"test","type":"org.apache.mesos.rp.test"}
3: I1114 02:42:04.635329 15893 http_connection.hpp:129] Sending 3 call to http://172.17.0.2:48986/slave(744)/api/v1/resource_provider
3: I1114 02:42:04.636669 15894 process.cpp:3925] Handling HTTP event for process 'slave(744)' with path: '/slave(744)/api/v1/resource_provider'
3: I1114 02:42:04.638166 15896 http.cpp:1185] HTTP POST for /slave(744)/api/v1/resource_provider from 172.17.0.2:33970
3: I1114 02:42:04.638759 15901 slave.cpp:6719] Handling resource provider message 'UPDATE_TOTAL_RESOURCES: 60a58f83-d033-45c9-955b-2625621b8ee4 disk[RAW]:200'
3: I1114 02:42:04.638911 15901 slave.cpp:6764] Forwarding new total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:42:04.640033 15898 master.cpp:7051] Received update of agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f) with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:42:04.641366 15892 scheduler.cpp:190] Version: 1.5.0
3: I1114 02:42:04.641363 15899 hierarchical.cpp:660] Agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 (d85273cd0d8f) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[RAW]:200
3: I1114 02:42:04.641649 15896 scheduler.cpp:313] Using default 'basic' HTTP authenticatee
3: I1114 02:42:04.641680 15899 hierarchical.cpp:1450] Performed allocation for 1 agents in 166846ns
3: I1114 02:42:04.642066 15900 scheduler.cpp:496] New master detected at master@172.17.0.2:48986
3: I1114 02:42:04.642102 15900 scheduler.cpp:505] Waiting for 0ns before initiating a re-(connection) attempt with the master
3: I1114 02:42:04.645877 15906 scheduler.cpp:387] Connected with the master at http://172.17.0.2:48986/master/api/v1/scheduler
3: I1114 02:42:04.647070 15895 scheduler.cpp:249] Sending SUBSCRIBE call to http://172.17.0.2:48986/master/api/v1/scheduler
3: I1114 02:42:04.648871 15902 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:42:04.650478 15893 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:33973
3: I1114 02:42:04.650753 15893 master.cpp:2613] Received subscription request for HTTP framework 'default'
3: I1114 02:42:04.650866 15893 master.cpp:2278] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1114 02:42:04.651428 15895 master.cpp:2748] Subscribing framework 'default' with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1114 02:42:04.652346 15904 hierarchical.cpp:303] Added framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000
3: I1114 02:42:04.653302 15896 scheduler.cpp:741] Enqueuing event SUBSCRIBED received from http://172.17.0.2:48986/master/api/v1/scheduler
3: I1114 02:42:04.653779 15896 scheduler.cpp:741] Enqueuing event HEARTBEAT received from http://172.17.0.2:48986/master/api/v1/scheduler
3: I1114 02:42:04.654093 15904 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.581138ms
3: I1114 02:42:04.654911 15894 master.cpp:8115] Sending 1 offers to framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000 (default)
3: I1114 02:42:04.657204 15903 scheduler.cpp:741] Enqueuing event OFFERS received from http://172.17.0.2:48986/master/api/v1/scheduler
3: I1114 02:42:04.659139 15902 scheduler.cpp:249] Sending ACCEPT call to http://172.17.0.2:48986/master/api/v1/scheduler
3: I1114 02:42:04.660923 15899 process.cpp:3925] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
3: I1114 02:42:04.662503 15901 http.cpp:1185] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:33972
3: I1114 02:42:04.663713 15901 master.cpp:10017] Removing offer f1d8404c-289b-4691-9647-60d13b1fcd30-O0
3: I1114 02:42:04.663836 15901 master.cpp:4228] Processing ACCEPT call for offers: [ f1d8404c-289b-4691-9647-60d13b1fcd30-O0 ] on agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f) for framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000 (default)
3: I1114 02:42:04.664752 15901 master.cpp:5293] Processing CREATE_BLOCK operation with source disk(allocated: *)[RAW]:200 from framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000 (default) to agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f)
3: I1114 02:42:04.665410 15901 master.cpp:9900] Sending offer operation ¢) EM0íhÑ9 to agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 at slave(744)@172.17.0.2:48986 (d85273cd0d8f)
3: I1114 02:42:04.666836 15894 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 f1d8404c-289b-4691-9647-60d13b1fcd30-S0 from framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000
3: I1114 02:42:04.666919 15894 hierarchical.cpp:1171] Framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000 filtered agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 for 5secs
3: I1114 02:42:04.669116 15905 http_connection.hpp:129] Sending 2 call to http://172.17.0.2:48986/slave(744)/api/v1/resource_provider
3: I1114 02:42:04.670435 15897 process.cpp:3925] Handling HTTP event for process 'slave(744)' with path: '/slave(744)/api/v1/resource_provider'
3: I1114 02:42:04.709416 15900 http.cpp:1185] HTTP POST for /slave(744)/api/v1/resource_provider from 172.17.0.2:33970
3: I1114 02:42:04.710259 15904 slave.cpp:6719] Handling resource provider message 'UPDATE_OFFER_OPERATION_STATUS: {"framework_id":{"value":"f1d8404c-289b-4691-9647-60d13b1fcd30-0000"},"status":{"state":"OFFER_OPERATION_FINISHED","converted_resources":[{"provider_id":{"value":"60a58f83-d033-45c9-955b-2625621b8ee4"},"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":"60a58f83-d033-45c9-955b-2625621b8ee4"},"name":"disk","type":"SCALAR","scalar":{"value":200.0},"allocation_info":{"role":"*"},"disk":{"source":{"type":"BLOCK"}}}]},"operation_uuid":"opkOKZYeRYaKTTDtaNEBOQ=="}'
3: I1114 02:42:04.710659 15904 slave.cpp:6893] Updating the state of offer operation '' (uuid: ¢) EM0íhÑ9) of framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: I1114 02:42:04.711027 15904 slave.cpp:6817] Forwarding status update of offer operation '' (uuid: a2990e29-961e-4586-8a4d-30ed68d10139) for framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000
3: I1114 02:42:04.711614 15902 master.cpp:9698] Updating the state of offer operation '' (uuid: a2990e29-961e-4586-8a4d-30ed68d10139) of framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000 (latest state: OFFER_OPERATION_FINISHED, status update state: OFFER_OPERATION_FINISHED)
3: F1114 02:42:04.712028 15902 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: @ 0x7f15a34ad84d google::LogMessage::Fail()
3: I1114 02:42:04.713994 15905 hierarchical.cpp:849] Updated allocation of framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000 on agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 from disk(allocated: *)[RAW]:200 to disk(allocated: *)[BLOCK]:200
3: @ 0x7f15a34af61c google::LogMessage::SendToLog()
3: I1114 02:42:04.714493 15905 hierarchical.cpp:1125] Recovered disk(allocated: *)[BLOCK]:200 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000]; disk[BLOCK]:200, allocated: {}) on agent f1d8404c-289b-4691-9647-60d13b1fcd30-S0 from framework f1d8404c-289b-4691-9647-60d13b1fcd30-0000
3: @ 0x7f15a34ad43c google::LogMessage::Flush()
3: @ 0x7f15a34aff2e google::LogMessageFatal::~LogMessageFatal()
3: @ 0xc93991 _CheckFatal::~_CheckFatal()
3: I1114 02:42:04.723392 15898 hierarchical.cpp:1450] Performed allocation for 1 agents in 1.182498ms
3: @ 0x7f15aa3ad7eb mesos::internal::master::Slave::apply()
3: @ 0x7f15aa3a3096 mesos::internal::master::Master::updateOfferOperation()
3: @ 0x7f15aa38dded mesos::internal::master::Master::offerOperationStatusUpdate()
3: @ 0x7f15aa3f788c ProtobufProcess<>::_handlerM<>()
3: @ 0x7f15aa4bc8c8 _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSD_SF_EJLm0ELm1ELm2ELm3EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
3: @ 0x7f15aa498e03 _ZNSt5_BindIFPFvPN5mesos8internal6master6MasterEMS3_FvRKNS1_26OfferOperationStatusUpdateEERKN7process4UPIDERKSsES4_S9_St12_PlaceholderILi1EESI_ILi2EEEEclIJSD_SF_EvEET0_DpOT_
3: @ 0x7f15aa46a822 std::_Function_handler<>::_M_invoke()
3: @ 0xe9cc8d std::function<>::operator()()
3: @ 0x7f15aa3d663a ProtobufProcess<>::visit()
3: @ 0x7f15aa3630a3 mesos::internal::master::Master::_visit()
3: @ 0x7f15aa362782 mesos::internal::master::Master::visit()
3: @ 0x7f15aa3ba008 process::MessageEvent::visit()
3: @ 0xcaf134 process::ProcessBase::serve()
3: @ 0x7f15a45dbb34 process::ProcessManager::resume()
3: @ 0x7f15a45d7f0e _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
3: @ 0x7f15a45ea8fe _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
3: @ 0x7f15a45ea842 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
3: @ 0x7f15a45ea7cc _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
3: @ 0x7f159fdd42b0 (unknown)
3: @ 0x7f15a328de25 start_thread
3: @ 0x7f159f53c34d __clone
3/3 Test #3: MesosTests .......................***Exception: Other281.17 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 287.36 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-1510625261-15077
Untagged: mesos-1510625261-15077:latest
Deleted: sha256:53ae2b14e9a822f6dab56d2c74df1422177bc8473fa6da291cf26adb86d7209e
Deleted: sha256:8369a3c6bf2bbb13bdc5a12c7055e955ce8440a002f3a951f120a2fe79a5ac2f
Deleted: sha256:502ae81dfccf13531b5560f77d02cfaabfcb582f5179b90fa6bc2435735aa777
Deleted: sha256:f95904292a9f77ffa4261b6d06898cd5644ec2b44b6e50d4b30deae2dac2d4b6
Deleted: sha256:e43ee13b7dca5e1d0e4738315b6d3bd92162e26a249126d0e58bdf1ca6f6d998
Deleted: sha256:ece39ee77fcd1eeab215739eb8b5204bfa84b52cc533ab53e5b26fd852ed0b9f
Deleted: sha256:b1934247b2641462e5c42594a7ea8cff485fe9db3425924f57deff23adcd21ee
Deleted: sha256:c24462af9123b690fdcd27eef7c3d2cf5aac8359a97c3fa46e98a6c52779a196
Deleted: sha256:1183d102e362ef414c74965291e0d804ee7ca9b93706e8bf82019c34b285becd
Deleted: sha256:874a62abe928bce4ee038229090c6c84420043c0f5803049910ce6b805666960
Deleted: sha256:ce7624a233738db53befc4af42fc9979c3c0fbe2cb3e7e34a0cd61cc994dd8d4
Deleted: sha256:401c5cc542f8f37236891e034d0bede4e551edd5f8a135b0da35c9c6bf496c8e
Deleted: sha256:e85e63fb05e78ea73744785dba34306a275377b9e23292dbfdbdf83654beb6dc
Deleted: sha256:32055082ac8a39c80272460b765407fc0702b28b5d0d96ea3e4e38da1e86a75e
Deleted: sha256:871f04e71f1830e26f866e8aced24367b0d838a87c895b8c091726f7afdfebfa
Deleted: sha256:e5d3c2893dd14cbd0c7cf8c7517476652e2029a8fb71d3ff28cc043dd9f25c76
Deleted: sha256:03495c8d17bc31b6314866fe3acc5c89d2b808940cd2e40dbeadc11bf3e26b53
Deleted: sha256:bff2c44d244b2e0f6d3156447d4589155274d9994935d884654ef4427cbd8972
Deleted: sha256:16a580adad0a6497c6ff62dacf2ce54814b129bb978b5ecbb04272d1ef35adbf
Deleted: sha256:23f133cfaeb24bbe62103a3f065e2d97300e9674ee50fb92321188463a16bae1
Deleted: sha256:1bedd65c3990be1443f60d4a31911f88ec1ab70ad55235dce555d74922e2be25
Deleted: sha256:b3a624620aa5cc99926248037e0b5181c7e7b332430429e5eec7d908abaf783f
Deleted: sha256:1d75c5155ef46e3d76f3671776a64fdca92635147cd4c3070bbe2ca0da22d041
Deleted: sha256:e4caa933711c1a2761a21e2fdfa474304b72afaad9f4012949989782b23c188b
Deleted: sha256:8705c5f68248e6e89afbe8e9ca1c6f685b672c8d030bc11cf5f0dbd168185f70
Deleted: sha256:d61ed23e3dba1525ba565b6b6b142b1fb3b7d59750b8bfc88a945ca4cf33a331
Deleted: sha256:f18a41f3bcf7794e1324212bd5ad28eedd81cbc5c94dc6f013dd76429fd98a2a
Deleted: sha256:5cc641ca730a62517b958a5f04a4b483b3bc661e4876325c84f59373aa7a747e
Deleted: sha256:1aed864da10a2543a1669b00ab76fe5570b8ca1dd1dd91a82fbcd64f389620a8
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,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,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4455/display/redirect?page=changes>