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/07/27 03:35:45 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3959
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3959/display/redirect?page=changes>
Changes:
[mpark] Multi-role: Adjusted `long-lived-framework` to use allocated resources.
[mpark] Added MESOS-7252 to 1.3.1 CHANGELOG.
------------------------------------------
[...truncated 23.38 MB...]
I0727 03:35:36.466253 23912 slave.cpp:2316] Queued task '6099cecc-2d35-486b-9b43-b6d05aa39c2e' for executor 'default' of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.466336 23912 slave.cpp:877] Successfully attached file '/tmp/ContentType_SchedulerTest_Message_0_ZaGMqw/slaves/8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0/frameworks/8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000/executors/default/runs/266bd4ce-6c86-4509-9d60-6af82ae65447'
I0727 03:35:36.468204 23912 executor.cpp:192] Version: 1.3.0
I0727 03:35:36.470124 23913 executor.cpp:410] Connected with the agent
I0727 03:35:36.470800 23913 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.4:43483/slave(693)/api/v1/executor
I0727 03:35:36.471951 23913 process.cpp:3741] Handling HTTP event for process 'slave(693)' with path: '/slave(693)/api/v1/executor'
I0727 03:35:36.472787 23913 http.cpp:1115] HTTP POST for /slave(693)/api/v1/executor from 172.17.0.4:59630
I0727 03:35:36.473280 23913 slave.cpp:3574] Received Subscribe request for HTTP executor 'default' of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.474385 23913 slave.cpp:2529] Sending queued task '6099cecc-2d35-486b-9b43-b6d05aa39c2e' to executor 'default' of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (via HTTP)
I0727 03:35:36.475704 23907 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.4:43483/slave(693)/api/v1/executor
I0727 03:35:36.477805 23913 executor.cpp:725] Enqueuing event LAUNCH received from http://172.17.0.4:43483/slave(693)/api/v1/executor
I0727 03:35:36.478677 23913 executor.cpp:307] Sending UPDATE call to http://172.17.0.4:43483/slave(693)/api/v1/executor
I0727 03:35:36.481981 23909 process.cpp:3741] Handling HTTP event for process 'slave(693)' with path: '/slave(693)/api/v1/executor'
I0727 03:35:36.482731 23909 http.cpp:1115] HTTP POST for /slave(693)/api/v1/executor from 172.17.0.4:59632
I0727 03:35:36.482859 23909 slave.cpp:4264] Handling status update TASK_RUNNING (UUID: 2d5765b9-8070-46a5-8e45-7a898e884ee5) for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.484002 23909 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 2d5765b9-8070-46a5-8e45-7a898e884ee5) for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.484053 23909 status_update_manager.cpp:500] Creating StatusUpdate stream for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.484930 23909 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 2d5765b9-8070-46a5-8e45-7a898e884ee5) for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 to the agent
I0727 03:35:36.485448 23910 slave.cpp:4704] Forwarding the update TASK_RUNNING (UUID: 2d5765b9-8070-46a5-8e45-7a898e884ee5) for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 to master@172.17.0.4:43483
I0727 03:35:36.485935 23910 master.cpp:6469] Status update TASK_RUNNING (UUID: 2d5765b9-8070-46a5-8e45-7a898e884ee5) for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 from agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 at slave(693)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.485991 23910 master.cpp:6537] Forwarding status update TASK_RUNNING (UUID: 2d5765b9-8070-46a5-8e45-7a898e884ee5) for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.486450 23910 master.cpp:8568] Updating the state of task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0727 03:35:36.487012 23909 slave.cpp:4598] Status update manager successfully handled status update TASK_RUNNING (UUID: 2d5765b9-8070-46a5-8e45-7a898e884ee5) for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.487725 23910 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.4:43483/master/api/v1/scheduler
I0727 03:35:36.488348 23912 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.4:43483/slave(693)/api/v1/executor
I0727 03:35:36.492020 23906 scheduler.cpp:243] Sending MESSAGE call to http://172.17.0.4:43483/master/api/v1/scheduler
I0727 03:35:36.494011 23910 process.cpp:3741] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0727 03:35:36.534788 23910 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:59628
I0727 03:35:36.534914 23910 master.cpp:5362] Processing MESSAGE call from framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (default) to agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 at slave(693)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.535975 23911 executor.cpp:725] Enqueuing event MESSAGE received from http://172.17.0.4:43483/slave(693)/api/v1/executor
I0727 03:35:36.544770 23909 master.cpp:1430] Framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (default) disconnected
I0727 03:35:36.544808 23909 master.cpp:3160] Deactivating framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (default)
I0727 03:35:36.544843 23909 master.cpp:3137] Disconnecting framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (default)
I0727 03:35:36.544870 23909 master.cpp:1445] Giving framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (default) 0ns to failover
I0727 03:35:36.544915 23910 hierarchical.cpp:374] Deactivated framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.546139 23909 master.cpp:7146] Framework failover timeout, removing framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (default)
I0727 03:35:36.546177 23909 master.cpp:8000] Removing framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (default)
I0727 03:35:36.546253 23909 master.cpp:8568] Updating the state of task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0727 03:35:36.546679 23910 slave.cpp:3063] Asked to shut down framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 by master@172.17.0.4:43483
I0727 03:35:36.546721 23910 slave.cpp:3088] Shutting down framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.546807 23910 slave.cpp:5531] Shutting down executor 'default' of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (via HTTP)
I0727 03:35:36.547164 23911 hierarchical.cpp:1114] Recovered cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 from framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.547754 23909 master.cpp:8662] Removing task 6099cecc-2d35-486b-9b43-b6d05aa39c2e with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 on agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 at slave(693)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.547951 23909 master.cpp:8691] Removing executor 'default' with resources {} of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 on agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 at slave(693)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.547843 23910 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.4:43483/slave(693)/api/v1/executor
I0727 03:35:36.548544 23909 hierarchical.cpp:325] Removed framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.550674 23908 slave.cpp:5217] Executor 'default' of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 exited with status 0
W0727 03:35:36.551411 23906 master.cpp:6592] Ignoring unknown exited executor 'default' of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 on agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 at slave(693)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.551576 23908 slave.cpp:5317] Cleaning up executor 'default' of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000 (via HTTP)
I0727 03:35:36.552146 23908 slave.cpp:5405] Cleaning up framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.552672 23906 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_Message_0_ZaGMqw/slaves/8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0/frameworks/8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000/executors/default/runs/266bd4ce-6c86-4509-9d60-6af82ae65447' for gc 6.99999361068148days in the future
I0727 03:35:36.553319 23907 status_update_manager.cpp:285] Closing status update streams for framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.553356 23907 status_update_manager.cpp:531] Cleaning up status update stream for task 6099cecc-2d35-486b-9b43-b6d05aa39c2e of framework 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000
I0727 03:35:36.553959 23905 slave.cpp:796] Agent terminating
I0727 03:35:36.554087 23906 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_Message_0_ZaGMqw/slaves/8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0/frameworks/8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000/executors/default' for gc 6.99999360966518days in the future
I0727 03:35:36.554659 23908 master.cpp:1313] Agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 at slave(693)@172.17.0.4:43483 (3e42c4a67be1) disconnected
I0727 03:35:36.554697 23908 master.cpp:3197] Disconnecting agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 at slave(693)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.554989 23906 gc.cpp:55] Scheduling '/tmp/ContentType_SchedulerTest_Message_0_ZaGMqw/slaves/8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0/frameworks/8f1a3c2c-f868-4f03-8617-f982ebbd2f35-0000' for gc 6.99999359406222days in the future
I0727 03:35:36.556257 23908 master.cpp:3216] Deactivating agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 at slave(693)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.556641 23906 hierarchical.cpp:653] Agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0 deactivated
I0727 03:35:36.584895 23905 master.cpp:1155] Master terminating
I0727 03:35:36.585458 23909 hierarchical.cpp:558] Removed agent 8f1a3c2c-f868-4f03-8617-f982ebbd2f35-S0
[ OK ] ContentType/SchedulerTest.Message/0 (193 ms)
[ RUN ] ContentType/SchedulerTest.Message/1
I0727 03:35:36.591791 23905 cluster.cpp:162] Creating default 'local' authorizer
I0727 03:35:36.593677 23909 master.cpp:436] Master 152bd2e5-5add-4419-988d-8b8e49a992a5 (3e42c4a67be1) started on 172.17.0.4:43483
I0727 03:35:36.593735 23909 master.cpp:438] 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/ZE4QoI/credentials" --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/ZE4QoI/master" --zk_session_timeout="10secs"
I0727 03:35:36.594317 23909 master.cpp:488] Master only allowing authenticated frameworks to register
I0727 03:35:36.594413 23909 master.cpp:502] Master only allowing authenticated agents to register
I0727 03:35:36.594630 23909 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0727 03:35:36.594686 23909 credentials.hpp:37] Loading credentials for authentication from '/tmp/ZE4QoI/credentials'
I0727 03:35:36.595093 23909 master.cpp:560] Using default 'crammd5' authenticator
I0727 03:35:36.595408 23909 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0727 03:35:36.595654 23909 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0727 03:35:36.595887 23909 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0727 03:35:36.596103 23909 master.cpp:640] Authorization enabled
I0727 03:35:36.597478 23909 hierarchical.cpp:158] Initialized hierarchical allocator process
I0727 03:35:36.597525 23909 whitelist_watcher.cpp:77] No whitelist given
I0727 03:35:36.598912 23909 master.cpp:2161] Elected as the leading master!
I0727 03:35:36.598948 23909 master.cpp:1700] Recovering from registrar
I0727 03:35:36.599318 23909 registrar.cpp:345] Recovering registrar
I0727 03:35:36.599931 23909 registrar.cpp:389] Successfully fetched the registry (0B) in 578816ns
I0727 03:35:36.599992 23909 registrar.cpp:493] Applied 1 operations in 78728ns; attempting to update the registry
I0727 03:35:36.600620 23909 registrar.cpp:550] Successfully updated the registry in 529920ns
I0727 03:35:36.600760 23909 registrar.cpp:422] Successfully recovered registrar
I0727 03:35:36.601250 23909 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0727 03:35:36.601590 23909 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0727 03:35:36.605973 23905 cluster.cpp:448] Creating default 'local' authorizer
I0727 03:35:36.607334 23911 slave.cpp:231] Mesos agent started on (694)@172.17.0.4:43483
I0727 03:35:36.608042 23905 scheduler.cpp:184] Version: 1.3.0
I0727 03:35:36.608790 23909 scheduler.cpp:470] New master detected at master@172.17.0.4:43483
I0727 03:35:36.608832 23909 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0727 03:35:36.607385 23911 slave.cpp:232] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/ContentType_SchedulerTest_Message_1_HSEe6O/credential" --default_role="*" --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/mesos/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_SchedulerTest_Message_1_HSEe6O/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_SchedulerTest_Message_1_HSEe6O/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_SchedulerTest_Message_1_HSEe6O" --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_SchedulerTest_Message_1_aI3wKL"
I0727 03:35:36.609613 23911 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_SchedulerTest_Message_1_HSEe6O/credential'
I0727 03:35:36.610043 23911 slave.cpp:264] Agent using credential for: test-principal
I0727 03:35:36.610072 23911 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_Message_1_HSEe6O/http_credentials'
I0727 03:35:36.610311 23911 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0727 03:35:36.610438 23911 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0727 03:35:36.612018 23911 slave.cpp:531] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0727 03:35:36.612094 23911 slave.cpp:539] Agent attributes: [ ]
I0727 03:35:36.612107 23911 slave.cpp:544] Agent hostname: 3e42c4a67be1
I0727 03:35:36.612751 23909 scheduler.cpp:361] Connected with the master at http://172.17.0.4:43483/master/api/v1/scheduler
I0727 03:35:36.613971 23909 status_update_manager.cpp:177] Pausing sending status updates
I0727 03:35:36.614276 23909 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.4:43483/master/api/v1/scheduler
I0727 03:35:36.615248 23911 state.cpp:62] Recovering state from '/tmp/ContentType_SchedulerTest_Message_1_aI3wKL/meta'
I0727 03:35:36.615541 23911 status_update_manager.cpp:203] Recovering status update manager
I0727 03:35:36.615607 23911 process.cpp:3741] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0727 03:35:36.616206 23911 slave.cpp:6075] Finished recovery
I0727 03:35:36.616739 23911 slave.cpp:6257] Querying resource estimator for oversubscribable resources
I0727 03:35:36.617156 23911 slave.cpp:924] New master detected at master@172.17.0.4:43483
I0727 03:35:36.617221 23911 slave.cpp:959] Detecting new master
I0727 03:35:36.617285 23911 slave.cpp:6271] Received oversubscribable resources {} from the resource estimator
I0727 03:35:36.617333 23911 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:59634
I0727 03:35:36.617959 23909 status_update_manager.cpp:177] Pausing sending status updates
I0727 03:35:36.618064 23911 master.cpp:2513] Received subscription request for HTTP framework 'default'
I0727 03:35:36.618084 23911 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0727 03:35:36.618593 23911 master.cpp:2628] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0727 03:35:36.619349 23912 hierarchical.cpp:273] Added framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000
I0727 03:35:36.619426 23912 hierarchical.cpp:1850] No allocations performed
I0727 03:35:36.619439 23912 hierarchical.cpp:1940] No inverse offers to send out!
I0727 03:35:36.619457 23912 hierarchical.cpp:1434] Performed allocation for 0 agents in 51019ns
I0727 03:35:36.621058 23909 master.hpp:2194] Sending heartbeat to 152bd2e5-5add-4419-988d-8b8e49a992a5-0000
I0727 03:35:36.622288 23909 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.4:43483/master/api/v1/scheduler
I0727 03:35:36.623023 23909 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.4:43483/master/api/v1/scheduler
I0727 03:35:36.627434 23909 slave.cpp:986] Authenticating with master master@172.17.0.4:43483
I0727 03:35:36.627497 23909 slave.cpp:997] Using default CRAM-MD5 authenticatee
I0727 03:35:36.627945 23909 authenticatee.cpp:121] Creating new client SASL connection
I0727 03:35:36.628512 23909 master.cpp:7475] Authenticating slave(694)@172.17.0.4:43483
I0727 03:35:36.628670 23906 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1290)@172.17.0.4:43483
I0727 03:35:36.629257 23906 authenticator.cpp:98] Creating new server SASL connection
I0727 03:35:36.629779 23906 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0727 03:35:36.629817 23906 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0727 03:35:36.630139 23907 authenticator.cpp:204] Received SASL authentication start
I0727 03:35:36.630260 23907 authenticator.cpp:326] Authentication requires more steps
I0727 03:35:36.630774 23906 authenticatee.cpp:259] Received SASL authentication step
I0727 03:35:36.631198 23906 authenticator.cpp:232] Received SASL authentication step
I0727 03:35:36.631294 23906 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3e42c4a67be1' server FQDN: '3e42c4a67be1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0727 03:35:36.631311 23906 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0727 03:35:36.631376 23906 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0727 03:35:36.631608 23906 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '3e42c4a67be1' server FQDN: '3e42c4a67be1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0727 03:35:36.631770 23906 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0727 03:35:36.631783 23906 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0727 03:35:36.632030 23906 authenticator.cpp:318] Authentication success
I0727 03:35:36.632464 23913 authenticatee.cpp:299] Authentication success
I0727 03:35:36.632952 23913 slave.cpp:1081] Successfully authenticated with master master@172.17.0.4:43483
I0727 03:35:36.633450 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.633882 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.634248 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.634732 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.635078 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.635170 23913 slave.cpp:1509] Will retry registration in 11.979007ms if necessary
I0727 03:35:36.635751 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.636122 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.636474 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.636854 23910 master.cpp:5386] Queuing up registration request from slave(694)@172.17.0.4:43483 because authentication is still in progress
I0727 03:35:36.637257 23906 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1290)@172.17.0.4:43483
I0727 03:35:36.637753 23910 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(694)@172.17.0.4:43483
I0727 03:35:36.638074 23910 master.cpp:5429] Received register agent message from slave(694)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.638113 23910 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0727 03:35:36.638619 23910 master.cpp:5564] Registering agent at slave(694)@172.17.0.4:43483 (3e42c4a67be1) with id 152bd2e5-5add-4419-988d-8b8e49a992a5-S0
I0727 03:35:36.639156 23910 registrar.cpp:493] Applied 1 operations in 44392ns; attempting to update the registry
I0727 03:35:36.639788 23910 registrar.cpp:550] Successfully updated the registry in 584192ns
I0727 03:35:36.640503 23910 master.cpp:5639] Registered agent 152bd2e5-5add-4419-988d-8b8e49a992a5-S0 at slave(694)@172.17.0.4:43483 (3e42c4a67be1) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0727 03:35:36.641057 23910 hierarchical.cpp:525] Added agent 152bd2e5-5add-4419-988d-8b8e49a992a5-S0 (3e42c4a67be1) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0727 03:35:36.641430 23913 slave.cpp:1127] Registered with master master@172.17.0.4:43483; given agent ID 152bd2e5-5add-4419-988d-8b8e49a992a5-S0
I0727 03:35:36.641470 23913 fetcher.cpp:94] Clearing fetcher cache
I0727 03:35:36.642138 23913 slave.cpp:1155] Checkpointing SlaveInfo to '/tmp/ContentType_SchedulerTest_Message_1_aI3wKL/meta/slaves/152bd2e5-5add-4419-988d-8b8e49a992a5-S0/slave.info'
I0727 03:35:36.642328 23908 status_update_manager.cpp:184] Resuming sending status updates
I0727 03:35:36.643018 23913 slave.cpp:1193] Forwarding total oversubscribed resources {}
I0727 03:35:36.643426 23913 slave.cpp:4794] Received ping from slave-observer(630)@172.17.0.4:43483
I0727 03:35:36.643774 23908 master.cpp:6324] Received update of agent 152bd2e5-5add-4419-988d-8b8e49a992a5-S0 at slave(694)@172.17.0.4:43483 (3e42c4a67be1) with total oversubscribed resources {}
I0727 03:35:36.644605 23910 hierarchical.cpp:1940] No inverse offers to send out!
I0727 03:35:36.644650 23910 hierarchical.cpp:1434] Performed allocation for 1 agents in 408370ns
I0727 03:35:36.645289 23913 master.cpp:7305] Sending 1 offers to framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000 (default)
I0727 03:35:36.647547 23913 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.4:43483/master/api/v1/scheduler
I0727 03:35:36.649338 23913 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.4:43483/master/api/v1/scheduler
I0727 03:35:36.651703 23906 process.cpp:3741] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0727 03:35:36.652861 23908 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:59636
I0727 03:35:36.654251 23908 master.cpp:3875] Processing ACCEPT call for offers: [ 152bd2e5-5add-4419-988d-8b8e49a992a5-O0 ] on agent 152bd2e5-5add-4419-988d-8b8e49a992a5-S0 at slave(694)@172.17.0.4:43483 (3e42c4a67be1) for framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000 (default)
I0727 03:35:36.654449 23908 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 34aa34d0-5d7a-49a1-8ab6-39637aa7ada8
W0727 03:35:36.656275 23906 validation.cpp:1284] Executor 'default' for task '34aa34d0-5d7a-49a1-8ab6-39637aa7ada8' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0727 03:35:36.656332 23906 validation.cpp:1296] Executor 'default' for task '34aa34d0-5d7a-49a1-8ab6-39637aa7ada8' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0727 03:35:36.656839 23906 master.cpp:9320] Adding task 34aa34d0-5d7a-49a1-8ab6-39637aa7ada8 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 152bd2e5-5add-4419-988d-8b8e49a992a5-S0 at slave(694)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.657354 23906 master.cpp:4531] Launching task 34aa34d0-5d7a-49a1-8ab6-39637aa7ada8 of framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 152bd2e5-5add-4419-988d-8b8e49a992a5-S0 at slave(694)@172.17.0.4:43483 (3e42c4a67be1)
I0727 03:35:36.658185 23906 slave.cpp:1619] Got assigned task '34aa34d0-5d7a-49a1-8ab6-39637aa7ada8' for framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000
I0727 03:35:36.659294 23909 hierarchical.cpp:850] Updated allocation of framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000 on agent 152bd2e5-5add-4419-988d-8b8e49a992a5-S0 from cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
I0727 03:35:36.660789 23906 slave.cpp:1900] Authorizing task '34aa34d0-5d7a-49a1-8ab6-39637aa7ada8' for framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000
I0727 03:35:36.660845 23906 slave.cpp:6694] Authorizing framework principal 'test-principal' to launch task 34aa34d0-5d7a-49a1-8ab6-39637aa7ada8
I0727 03:35:36.661711 23906 slave.cpp:2087] Launching task '34aa34d0-5d7a-49a1-8ab6-39637aa7ada8' for framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000
I0727 03:35:36.662803 23906 paths.cpp:573] Trying to chown '/tmp/ContentType_SchedulerTest_Message_1_aI3wKL/slaves/152bd2e5-5add-4419-988d-8b8e49a992a5-S0/frameworks/152bd2e5-5add-4419-988d-8b8e49a992a5-0000/executors/default/runs/59aa382b-b1bb-4aa6-8b61-df4eb55488ca' to user 'mesos'
I0727 03:35:36.663342 23906 slave.cpp:7038] Launching executor 'default' of framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000 with resources {} in work directory '/tmp/ContentType_SchedulerTest_Message_1_aI3wKL/slaves/152bd2e5-5add-4419-988d-8b8e49a992a5-S0/frameworks/152bd2e5-5add-4419-988d-8b8e49a992a5-0000/executors/default/runs/59aa382b-b1bb-4aa6-8b61-df4eb55488ca'
I0727 03:35:36.664348 23906 slave.cpp:2316] Queued task '34aa34d0-5d7a-49a1-8ab6-39637aa7ada8' for executor 'default' of framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000
I0727 03:35:36.664762 23906 slave.cpp:877] Successfully attached file '/tmp/ContentType_SchedulerTest_Message_1_aI3wKL/slaves/152bd2e5-5add-4419-988d-8b8e49a992a5-S0/frameworks/152bd2e5-5add-4419-988d-8b8e49a992a5-0000/executors/default/runs/59aa382b-b1bb-4aa6-8b61-df4eb55488ca'
I0727 03:35:36.666618 23908 executor.cpp:192] Version: 1.3.0
I0727 03:35:36.668334 23910 executor.cpp:410] Connected with the agent
I0727 03:35:36.669111 23910 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.4:43483/slave(694)/api/v1/executor
I0727 03:35:36.670181 23912 process.cpp:3741] Handling HTTP event for process 'slave(694)' with path: '/slave(694)/api/v1/executor'
I0727 03:35:36.671243 23912 http.cpp:1115] HTTP POST for /slave(694)/api/v1/executor from 172.17.0.4:59638
I0727 03:35:36.671677 23912 slave.cpp:3574] Received Subscribe request for HTTP executor 'default' of framework 152bd2e5-5add-4419-988d-8b8e49a992a5-0000
I0727 03:35:36.673830 23912 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.4:43483/slave(694)/api/v1/executor
*** Aborted at 1501126536 (unix time) try "date -d @1501126536" if you are using GNU date ***
PC: @ 0x2b29f81 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
*** SIGSEGV (@0x0) received by PID 23905 (TID 0x2ad0e18b7700) from PID 0; stack trace: ***
@ 0x2ad0d92c3330 (unknown)
@ 0x2b29f81 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
@ 0xc414f8 mesos::internal::tests::executor::MockHTTPExecutor<>::event()
@ 0xc40bb1 mesos::internal::tests::executor::TestMesos<>::events()
@ 0xc40d6a std::_Function_handler<>::_M_invoke()
@ 0x2ad0d50dd7ca process::AsyncExecutorProcess::execute<>()
@ 0x2ad0d50de331 _ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS8_SaIS8_EEEEESC_SG_SC_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSN_FSK_T1_T2_ET3_T4_ENKUlPNS_11ProcessBaseEE_clESY_
@ 0x2ad0d64cb677 process::ProcessManager::resume()
@ 0x2ad0d64e082f std::thread::_Impl<>::_M_run()
@ 0x2ad0d9582a60 (unknown)
@ 0x2ad0d92bb184 start_thread
@ 0x2ad0d9deeffd (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
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-1501122458-24196
Untagged: mesos-1501122458-24196:latest
Deleted: sha256:77a60af28b83a44991e7cb89207fa605316d1f23e899899ede1aecc6c07aa398
Deleted: sha256:9dbdd7be28d7316a3ca1d06b9f1af98386577df2faf911ccb8262bc4891a693d
Deleted: sha256:bbc9101ba01075c3ddd8510a00a3482cd946e3225707d779e8a5949ba5f2ec50
Deleted: sha256:a70e7b00ed750c4dfc27c5ff1cb1de8ed77107c38be8e896b6ca4e2aa675b8d4
Deleted: sha256:7e03bc3f69d73b1ab59a00c61783fcedb72c636abab5b848317e7f12267c4901
Deleted: sha256:8c60febbdf96545d53877338dae08799532e5cb872d78ccfc60f6722121d1193
Deleted: sha256:9cafab9ebdd0a3251f437ec60d1869a0b90171b6b89f6dac49f5e33c63407b2a
Deleted: sha256:88a07aa39fc37c0fe77231d8b9ae7e0940d3f55e29d27d9546844a98c5977a96
Deleted: sha256:8d522c519eeefb81c168561eea3ffb50e1161cd2c7544f61af4a407200f04b19
Deleted: sha256:46521cde62955756413f410b043ed4cb11e139baf9a1be88a18f20045dead76d
Deleted: sha256:c730d472e25ce4229d176535f091acf3f223c7b0b40180fda8f7b837ee9ea350
Deleted: sha256:2ee4a009ddf81a4eeede3f68327878a9e86b2b4c096b11428f523927c02094a1
Deleted: sha256:cf5191ea5488bd57da2e7d56d6b263d71ff777bb028356c029c70a97dc7294e5
Deleted: sha256:f2e3260befe7b6265a08bbac1935b9df26f901850c9da9860a19cd5eb64566f1
Deleted: sha256:6b061a3c86638ec641a63c5102e98443a5201afde2d1f0271127dc276b2829ea
Deleted: sha256:ec34929bb2a07bb9641d58619c4e91afec275a69c59d657447c970ae550fe5ed
Deleted: sha256:4147446dbb654e783e7a1daa884bb33b9658733bf39bb0b7e4de10acaf945a3b
Deleted: sha256:cada1c6b6297a586657dd1c2a0b7aab8b21e81314274534b72986d68b05d93a8
Deleted: sha256:00518385b7074707b1854ed67fe5b78cfc66e2b1e09b16ba315fd0d02f85c5e3
Deleted: sha256:b725731e6c7122dcf66b35c98276278b431be0d12ce8be4c8390d34b0a9437bd
Deleted: sha256:87a8fc61905d4e4baa4ed910ef3869b43073d2bed07c0dbadee80087058d352e
Deleted: sha256:59320d95b0057ea15dc55769a4ac862ef722735dda80a3de19f2040589cd8e34
Deleted: sha256:0195b7f7d3637aa6cd92f430dc106f78e21904609e50f9459be70d8b940bdf3c
Deleted: sha256:00a70fa7e9886e523e9440d739f6292ee0c39f1f2d63bf753316d22b6f4778e5
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3960
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3960/display/redirect?page=changes>