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/15 02:07:11 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3914
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3914/display/redirect?page=changes>
Changes:
[yujie.jay] Preventing agent recovery failing from unsuccessful `docker rm`.
[yujie.jay] Added MESOS-7777 to 1.2.2 CHANGELOG.
------------------------------------------
[...truncated 19.99 MB...]
I0715 02:07:05.824300 25445 executor.cpp:302] Sending SUBSCRIBE call to http://172.17.0.5:36767/slave(548)/api/v1/executor
I0715 02:07:05.825011 25432 process.cpp:3714] Handling HTTP event for process 'slave(548)' with path: '/slave(548)/api/v1/executor'
I0715 02:07:05.825398 25451 http.cpp:307] HTTP POST for /slave(548)/api/v1/executor from 172.17.0.5:60520
I0715 02:07:05.825490 25451 slave.cpp:3175] Received Subscribe request for HTTP executor 'default' of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.825911 25451 slave.cpp:2337] Sending queued task '68dc2999-9abe-4f67-a95e-9b96489c183d' to executor 'default' of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (via HTTP)
I0715 02:07:05.826723 25437 executor.cpp:716] Enqueuing event SUBSCRIBED received from http://172.17.0.5:36767/slave(548)/api/v1/executor
I0715 02:07:05.826977 25437 executor.cpp:716] Enqueuing event LAUNCH received from http://172.17.0.5:36767/slave(548)/api/v1/executor
I0715 02:07:05.827523 25444 executor.cpp:302] Sending UPDATE call to http://172.17.0.5:36767/slave(548)/api/v1/executor
I0715 02:07:05.827962 25436 process.cpp:3714] Handling HTTP event for process 'slave(548)' with path: '/slave(548)/api/v1/executor'
I0715 02:07:05.828336 25447 http.cpp:307] HTTP POST for /slave(548)/api/v1/executor from 172.17.0.5:60522
I0715 02:07:05.828482 25447 slave.cpp:3865] Handling status update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.828863 25431 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.828893 25431 status_update_manager.cpp:500] Creating StatusUpdate stream for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.829107 25431 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 to the agent
I0715 02:07:05.829205 25444 slave.cpp:4305] Forwarding the update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 to master@172.17.0.5:36767
I0715 02:07:05.829331 25444 slave.cpp:4199] Status update manager successfully handled status update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.829375 25452 master.cpp:6190] Status update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 from agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 at slave(548)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.829404 25452 master.cpp:6258] Forwarding status update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.829536 25452 master.cpp:8966] Notifying all active subscribers about TASK_UPDATED event
I0715 02:07:05.829721 25452 master.cpp:8338] Updating the state of task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0715 02:07:05.829849 25451 executor.cpp:716] Enqueuing event ACKNOWLEDGED received from http://172.17.0.5:36767/slave(548)/api/v1/executor
I0715 02:07:05.829977 25448 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.5:36767/master/api/v1/scheduler
I0715 02:07:05.831164 25447 hierarchical.cpp:1810] No allocations performed
I0715 02:07:05.831182 25447 hierarchical.cpp:1900] No inverse offers to send out!
I0715 02:07:05.831199 25447 hierarchical.cpp:1394] Performed allocation for 1 agents in 85044ns
W0715 02:07:05.831239 25446 status_update_manager.cpp:478] Resending status update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.831284 25446 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 to the agent
I0715 02:07:05.831415 25446 slave.cpp:4305] Forwarding the update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 to master@172.17.0.5:36767
I0715 02:07:05.831523 25452 master.cpp:6190] Status update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 from agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 at slave(548)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.831543 25452 master.cpp:6258] Forwarding status update TASK_RUNNING (UUID: caac453c-bc8d-49c5-b9d7-fcbffa25f561) for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.831622 25452 master.cpp:8338] Updating the state of task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0715 02:07:05.831964 25433 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.5:36767/master/api/v1/scheduler
I0715 02:07:05.842542 25445 master.cpp:1381] Framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (default) disconnected
I0715 02:07:05.842586 25445 master.cpp:3081] Deactivating framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (default)
I0715 02:07:05.842622 25445 master.cpp:3058] Disconnecting framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (default)
I0715 02:07:05.842684 25445 master.cpp:1396] Giving framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (default) 0ns to failover
I0715 02:07:05.842653 25438 hierarchical.cpp:415] Deactivated framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.843209 25441 master.cpp:6868] Framework failover timeout, removing framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (default)
I0715 02:07:05.843235 25441 master.cpp:7753] Removing framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (default)
I0715 02:07:05.843297 25441 master.cpp:8966] Notifying all active subscribers about TASK_UPDATED event
I0715 02:07:05.843305 25446 slave.cpp:2664] Asked to shut down framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 by master@172.17.0.5:36767
I0715 02:07:05.843329 25446 slave.cpp:2689] Shutting down framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.843344 25446 slave.cpp:5131] Shutting down executor 'default' of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (via HTTP)
I0715 02:07:05.843389 25441 master.cpp:8338] Updating the state of task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0715 02:07:05.843595 25441 master.cpp:8432] Removing task 68dc2999-9abe-4f67-a95e-9b96489c183d with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 on agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 at slave(548)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.843751 25441 master.cpp:8461] Removing executor 'default' with resources {} of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 on agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 at slave(548)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.843811 25432 hierarchical.cpp:1064] 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 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 from framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.843893 25435 executor.cpp:716] Enqueuing event SHUTDOWN received from http://172.17.0.5:36767/slave(548)/api/v1/executor
I0715 02:07:05.844030 25432 hierarchical.cpp:362] Removed framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.845367 25436 slave.cpp:4817] Executor 'default' of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 exited with status 0
I0715 02:07:05.845425 25436 slave.cpp:4917] Cleaning up executor 'default' of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 (via HTTP)
W0715 02:07:05.845556 25433 master.cpp:6314] Ignoring unknown exited executor 'default' of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000 on agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 at slave(548)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.845629 25436 slave.cpp:5005] Cleaning up framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.845623 25443 gc.cpp:55] Scheduling '/tmp/ContentType_MasterAPITest_Subscribe_0_37c09K/slaves/24c08f36-d032-4aa9-b9f1-d49c02923fae-S0/frameworks/24c08f36-d032-4aa9-b9f1-d49c02923fae-0000/executors/default/runs/501861a6-07c2-42b5-9c6e-9a72da8e2160' for gc 6.99999038415407days in the future
I0715 02:07:05.845716 25439 status_update_manager.cpp:285] Closing status update streams for framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.845795 25439 status_update_manager.cpp:531] Cleaning up status update stream for task 68dc2999-9abe-4f67-a95e-9b96489c183d of framework 24c08f36-d032-4aa9-b9f1-d49c02923fae-0000
I0715 02:07:05.845823 25443 gc.cpp:55] Scheduling '/tmp/ContentType_MasterAPITest_Subscribe_0_37c09K/slaves/24c08f36-d032-4aa9-b9f1-d49c02923fae-S0/frameworks/24c08f36-d032-4aa9-b9f1-d49c02923fae-0000/executors/default' for gc 6.99999038415407days in the future
I0715 02:07:05.845914 25443 gc.cpp:55] Scheduling '/tmp/ContentType_MasterAPITest_Subscribe_0_37c09K/slaves/24c08f36-d032-4aa9-b9f1-d49c02923fae-S0/frameworks/24c08f36-d032-4aa9-b9f1-d49c02923fae-0000' for gc 6.99999038415407days in the future
I0715 02:07:05.846055 25441 slave.cpp:809] Agent terminating
I0715 02:07:05.846177 25446 master.cpp:1264] Agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 at slave(548)@172.17.0.5:36767 (f9001f1d6273) disconnected
I0715 02:07:05.846261 25446 master.cpp:3118] Disconnecting agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 at slave(548)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.846357 25446 master.cpp:3137] Deactivating agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 at slave(548)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.846542 25432 hierarchical.cpp:618] Agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0 deactivated
I0715 02:07:05.848050 25429 master.cpp:1103] Master terminating
I0715 02:07:05.848237 25446 hierarchical.cpp:549] Removed agent 24c08f36-d032-4aa9-b9f1-d49c02923fae-S0
[ OK ] ContentType/MasterAPITest.Subscribe/0 (64 ms)
[ RUN ] ContentType/MasterAPITest.Subscribe/1
I0715 02:07:05.852962 25429 cluster.cpp:160] Creating default 'local' authorizer
I0715 02:07:05.854120 25446 master.cpp:383] Master 95e8fa90-0a74-4ac0-bda2-6a6273ce131b (f9001f1d6273) started on 172.17.0.5:36767
I0715 02:07:05.854369 25446 master.cpp:385] 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/07HdU9/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/07HdU9/master" --zk_session_timeout="10secs"
I0715 02:07:05.854854 25446 master.cpp:435] Master only allowing authenticated frameworks to register
I0715 02:07:05.854874 25446 master.cpp:449] Master only allowing authenticated agents to register
I0715 02:07:05.854889 25446 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0715 02:07:05.854903 25446 credentials.hpp:37] Loading credentials for authentication from '/tmp/07HdU9/credentials'
I0715 02:07:05.855192 25446 master.cpp:507] Using default 'crammd5' authenticator
I0715 02:07:05.855298 25446 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0715 02:07:05.855415 25446 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0715 02:07:05.855639 25446 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0715 02:07:05.855868 25446 master.cpp:587] Authorization enabled
I0715 02:07:05.857017 25446 hierarchical.cpp:161] Initialized hierarchical allocator process
I0715 02:07:05.857192 25449 whitelist_watcher.cpp:77] No whitelist given
I0715 02:07:05.858093 25449 master.cpp:2124] Elected as the leading master!
I0715 02:07:05.858469 25449 master.cpp:1646] Recovering from registrar
I0715 02:07:05.858747 25446 registrar.cpp:329] Recovering registrar
I0715 02:07:05.859294 25431 registrar.cpp:362] Successfully fetched the registry (0B) in 237824ns
I0715 02:07:05.859678 25431 registrar.cpp:461] Applied 1 operations in 16010ns; attempting to update the registry
I0715 02:07:05.860191 25431 registrar.cpp:506] Successfully updated the registry in 346112ns
I0715 02:07:05.860471 25431 registrar.cpp:392] Successfully recovered registrar
I0715 02:07:05.860785 25431 master.cpp:1762] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0715 02:07:05.860939 25438 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0715 02:07:05.864739 25429 cluster.cpp:446] Creating default 'local' authorizer
I0715 02:07:05.867905 25447 slave.cpp:217] Mesos agent started on (549)@172.17.0.5:36767
I0715 02:07:05.868196 25447 slave.cpp:218] 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_MasterAPITest_Subscribe_1_6WkRvR/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_MasterAPITest_Subscribe_1_6WkRvR/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/ContentType_MasterAPITest_Subscribe_1_6WkRvR/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_MasterAPITest_Subscribe_1_6WkRvR" --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_MasterAPITest_Subscribe_1_1cz1Hv"
I0715 02:07:05.868827 25447 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentType_MasterAPITest_Subscribe_1_6WkRvR/credential'
I0715 02:07:05.869110 25447 slave.cpp:360] Agent using credential for: test-principal
I0715 02:07:05.869246 25447 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_MasterAPITest_Subscribe_1_6WkRvR/http_credentials'
I0715 02:07:05.869487 25447 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0715 02:07:05.869685 25447 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0715 02:07:05.869828 25429 scheduler.cpp:184] Version: 1.2.2
I0715 02:07:05.870306 25447 slave.cpp:547] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0715 02:07:05.870678 25447 slave.cpp:555] Agent attributes: [ ]
I0715 02:07:05.870810 25447 slave.cpp:560] Agent hostname: f9001f1d6273
I0715 02:07:05.871351 25432 status_update_manager.cpp:177] Pausing sending status updates
I0715 02:07:05.871743 25432 state.cpp:62] Recovering state from '/tmp/ContentType_MasterAPITest_Subscribe_1_1cz1Hv/meta'
I0715 02:07:05.872022 25432 status_update_manager.cpp:203] Recovering status update manager
I0715 02:07:05.872370 25432 slave.cpp:5662] Finished recovery
I0715 02:07:05.872949 25432 slave.cpp:5836] Querying resource estimator for oversubscribable resources
I0715 02:07:05.873248 25432 slave.cpp:937] New master detected at master@172.17.0.5:36767
I0715 02:07:05.873510 25432 slave.cpp:972] Detecting new master
I0715 02:07:05.873697 25432 slave.cpp:5850] Received oversubscribable resources {} from the resource estimator
I0715 02:07:05.873852 25432 status_update_manager.cpp:177] Pausing sending status updates
I0715 02:07:05.878525 25449 scheduler.cpp:470] New master detected at master@172.17.0.5:36767
I0715 02:07:05.878744 25449 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0715 02:07:05.879988 25444 scheduler.cpp:361] Connected with the master at http://172.17.0.5:36767/master/api/v1/scheduler
I0715 02:07:05.881115 25441 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.5:36767/master/api/v1/scheduler
I0715 02:07:05.881791 25436 process.cpp:3714] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0715 02:07:05.882458 25441 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:60524
I0715 02:07:05.882820 25441 master.cpp:2423] Received subscription request for HTTP framework 'default'
I0715 02:07:05.883011 25441 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0715 02:07:05.883235 25441 slave.cpp:999] Authenticating with master master@172.17.0.5:36767
I0715 02:07:05.883385 25441 slave.cpp:1010] Using default CRAM-MD5 authenticatee
I0715 02:07:05.883702 25441 authenticatee.cpp:121] Creating new client SASL connection
I0715 02:07:05.884261 25441 master.cpp:2537] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0715 02:07:05.884680 25441 master.cpp:7199] Authenticating slave(549)@172.17.0.5:36767
I0715 02:07:05.885026 25441 hierarchical.cpp:286] Added framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000
I0715 02:07:05.885212 25441 hierarchical.cpp:1810] No allocations performed
I0715 02:07:05.885344 25441 hierarchical.cpp:1900] No inverse offers to send out!
I0715 02:07:05.885473 25441 hierarchical.cpp:1394] Performed allocation for 0 agents in 275205ns
I0715 02:07:05.885687 25441 master.hpp:2119] Sending heartbeat to 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000
I0715 02:07:05.885866 25443 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1116)@172.17.0.5:36767
I0715 02:07:05.886376 25451 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.5:36767/master/api/v1/scheduler
I0715 02:07:05.886425 25443 authenticator.cpp:98] Creating new server SASL connection
I0715 02:07:05.886642 25449 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.5:36767/master/api/v1/scheduler
I0715 02:07:05.887022 25451 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0715 02:07:05.887181 25451 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0715 02:07:05.887374 25451 authenticator.cpp:204] Received SASL authentication start
I0715 02:07:05.887579 25451 authenticator.cpp:326] Authentication requires more steps
I0715 02:07:05.887747 25451 authenticatee.cpp:259] Received SASL authentication step
I0715 02:07:05.887961 25451 authenticator.cpp:232] Received SASL authentication step
I0715 02:07:05.888136 25451 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9001f1d6273' server FQDN: 'f9001f1d6273' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0715 02:07:05.888279 25451 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0715 02:07:05.888414 25451 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0715 02:07:05.888547 25451 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f9001f1d6273' server FQDN: 'f9001f1d6273' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0715 02:07:05.888677 25451 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0715 02:07:05.888801 25451 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0715 02:07:05.888933 25451 authenticator.cpp:318] Authentication success
I0715 02:07:05.889129 25449 authenticatee.cpp:299] Authentication success
I0715 02:07:05.902453 25451 master.cpp:7229] Successfully authenticated principal 'test-principal' at slave(549)@172.17.0.5:36767
I0715 02:07:05.902714 25451 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1116)@172.17.0.5:36767
I0715 02:07:05.902942 25449 slave.cpp:1094] Successfully authenticated with master master@172.17.0.5:36767
I0715 02:07:05.903151 25449 slave.cpp:1521] Will retry registration in 8.559337ms if necessary
I0715 02:07:05.903422 25443 master.cpp:5396] Registering agent at slave(549)@172.17.0.5:36767 (f9001f1d6273) with id 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0
I0715 02:07:05.903600 25449 registrar.cpp:461] Applied 1 operations in 32400ns; attempting to update the registry
I0715 02:07:05.904168 25449 registrar.cpp:506] Successfully updated the registry in 525056ns
I0715 02:07:05.904582 25449 master.cpp:5470] Registered agent 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0 at slave(549)@172.17.0.5:36767 (f9001f1d6273) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0715 02:07:05.904834 25453 hierarchical.cpp:516] Added agent 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0 (f9001f1d6273) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0715 02:07:05.904884 25451 slave.cpp:1140] Registered with master master@172.17.0.5:36767; given agent ID 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0
I0715 02:07:05.905643 25451 fetcher.cpp:94] Clearing fetcher cache
I0715 02:07:05.905875 25453 hierarchical.cpp:1900] No inverse offers to send out!
I0715 02:07:05.906112 25445 master.cpp:7028] Sending 1 offers to framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000 (default)
I0715 02:07:05.907714 25445 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.5:36767/master/api/v1/scheduler
I0715 02:07:05.908571 25451 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/ContentType_MasterAPITest_Subscribe_1_1cz1Hv/meta/slaves/95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0/slave.info'
I0715 02:07:05.909066 25451 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0715 02:07:05.909271 25451 slave.cpp:4395] Received ping from slave-observer(521)@172.17.0.5:36767
I0715 02:07:05.908675 25436 status_update_manager.cpp:184] Resuming sending status updates
I0715 02:07:05.909966 25436 master.cpp:6045] Received update of agent 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0 at slave(549)@172.17.0.5:36767 (f9001f1d6273) with total oversubscribed resources {}
I0715 02:07:05.910066 25436 process.cpp:3714] Handling HTTP event for process 'master' with path: '/master/api/v1'
I0715 02:07:05.910677 25434 http.cpp:420] HTTP POST for /master/api/v1 from 172.17.0.5:60528
I0715 02:07:05.910737 25434 http.cpp:525] Processing call SUBSCRIBE
I0715 02:07:05.911159 25434 master.cpp:8988] Added subscriber: 417eef25-03a3-4328-8354-c2b97f02dc79 to the list of active subscribers
I0715 02:07:05.914350 25453 hierarchical.cpp:1394] Performed allocation for 1 agents in 9.094907ms
I0715 02:07:05.914420 25453 hierarchical.cpp:589] Agent 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0 (f9001f1d6273) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000])
I0715 02:07:05.914571 25453 hierarchical.cpp:1810] No allocations performed
I0715 02:07:05.914602 25453 hierarchical.cpp:1900] No inverse offers to send out!
I0715 02:07:05.914625 25453 hierarchical.cpp:1394] Performed allocation for 1 agents in 83700ns
I0715 02:07:05.916726 25436 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.5:36767/master/api/v1/scheduler
I0715 02:07:05.917831 25436 process.cpp:3714] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0715 02:07:05.918396 25436 http.cpp:420] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:60526
I0715 02:07:05.919319 25436 master.cpp:3776] Processing ACCEPT call for offers: [ 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-O0 ] on agent 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0 at slave(549)@172.17.0.5:36767 (f9001f1d6273) for framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000 (default)
I0715 02:07:05.919603 25436 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 2dc25eda-2ac0-439e-ac56-deeebd621186
W0715 02:07:05.920639 25436 validation.cpp:1064] Executor 'default' for task '2dc25eda-2ac0-439e-ac56-deeebd621186' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0715 02:07:05.921028 25436 validation.cpp:1076] Executor 'default' for task '2dc25eda-2ac0-439e-ac56-deeebd621186' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0715 02:07:05.921392 25436 master.cpp:8966] Notifying all active subscribers about TASK_ADDED event
I0715 02:07:05.921937 25436 master.cpp:9089] Adding task 2dc25eda-2ac0-439e-ac56-deeebd621186 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0 at slave(549)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.922264 25436 master.cpp:4426] Launching task 2dc25eda-2ac0-439e-ac56-deeebd621186 of framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0 at slave(549)@172.17.0.5:36767 (f9001f1d6273)
I0715 02:07:05.922818 25447 slave.cpp:1631] Got assigned task '2dc25eda-2ac0-439e-ac56-deeebd621186' for framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000
I0715 02:07:05.923171 25447 slave.cpp:1791] Launching task '2dc25eda-2ac0-439e-ac56-deeebd621186' for framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000
I0715 02:07:05.923316 25436 hierarchical.cpp:807] Updated allocation of framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000 on agent 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-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]
I0715 02:07:05.923871 25447 paths.cpp:547] Trying to chown '/tmp/ContentType_MasterAPITest_Subscribe_1_1cz1Hv/slaves/95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0/frameworks/95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000/executors/default/runs/50704a1b-c6b3-418b-bfca-1914334634a3' to user 'mesos'
I0715 02:07:05.924103 25447 slave.cpp:6584] Launching executor 'default' of framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000 with resources {} in work directory '/tmp/ContentType_MasterAPITest_Subscribe_1_1cz1Hv/slaves/95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0/frameworks/95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000/executors/default/runs/50704a1b-c6b3-418b-bfca-1914334634a3'
I0715 02:07:05.924341 25447 slave.cpp:2124] Queued task '2dc25eda-2ac0-439e-ac56-deeebd621186' for executor 'default' of framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000
I0715 02:07:05.924388 25447 slave.cpp:890] Successfully attached file '/tmp/ContentType_MasterAPITest_Subscribe_1_1cz1Hv/slaves/95e8fa90-0a74-4ac0-bda2-6a6273ce131b-S0/frameworks/95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000/executors/default/runs/50704a1b-c6b3-418b-bfca-1914334634a3'
I0715 02:07:05.925427 25436 executor.cpp:192] Version: 1.2.2
I0715 02:07:05.926488 25445 executor.cpp:401] Connected with the agent
I0715 02:07:05.926911 25445 executor.cpp:302] Sending SUBSCRIBE call to http://172.17.0.5:36767/slave(549)/api/v1/executor
I0715 02:07:05.927691 25445 process.cpp:3714] Handling HTTP event for process 'slave(549)' with path: '/slave(549)/api/v1/executor'
I0715 02:07:05.928150 25445 http.cpp:307] HTTP POST for /slave(549)/api/v1/executor from 172.17.0.5:60530
I0715 02:07:05.928444 25445 slave.cpp:3175] Received Subscribe request for HTTP executor 'default' of framework 95e8fa90-0a74-4ac0-bda2-6a6273ce131b-0000
I0715 02:07:05.929630 25440 executor.cpp:716] Enqueuing event SUBSCRIBED received from http://172.17.0.5:36767/slave(549)/api/v1/executor
*** Aborted at 1500084425 (unix time) try "date -d @1500084425" if you are using GNU date ***
PC: @ 0x289e1d1 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
*** SIGSEGV (@0x0) received by PID 25429 (TID 0x2b5843e5f700) from PID 0; stack trace: ***
@ 0x2b583b066330 (unknown)
@ 0x289e1d1 testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
@ 0xbff7c8 mesos::internal::tests::executor::MockHTTPExecutor<>::event()
@ 0xbfee81 mesos::internal::tests::executor::TestMesos<>::events()
@ 0xbff03a std::_Function_handler<>::_M_invoke()
@ 0x2b583714adea process::AsyncExecutorProcess::execute<>()
@ 0x2b583714b963 _ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS8_SaIS8_EEEEESC_PvSG_SC_SJ_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSO_FSL_T1_T2_T3_ET4_T5_T6_ENKUlPNS_11ProcessBaseEE_clES11_
@ 0x2b583849d2c7 process::ProcessManager::resume()
@ 0x2b58384b1a6f std::thread::_Impl<>::_M_run()
@ 0x2b583b325a60 (unknown)
@ 0x2b583b05e184 start_thread
@ 0x2b583bb91ffd (unknown)
make[3]: Leaving directory `/mesos/build'
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
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-1500081943-3147
Untagged: mesos-1500081943-3147:latest
Deleted: sha256:2740f9018fa64eaa7b1a5db7234d8685d68346b5b7da98d8f57873eaf798e180
Deleted: sha256:1b7ee91d0aee8538f266a9a787c0ee7055ca1e8112f7883023583636e67444f5
Deleted: sha256:53b9f9f46c068d55580901d02623a3f3951e19f6e0371750f1be8167a7787df2
Deleted: sha256:fcdd96adf291f09a8e52f4b796715b408e1876e2bd5e9ae2f487df3507e84244
Deleted: sha256:8229a1e7a9831c52d77dd09f32bd25a0f8b317564473254bcf05fa74ac4b7f91
Deleted: sha256:3e0586a14d507811a6b8b1ed70cce7f726cb9b7b36b41592b0488dd717c1a4ba
Deleted: sha256:cf505af60d4cc56ecf2e12960a2248df2d4c82acbadf8112f5ecf1e95b74634c
Deleted: sha256:609a9b967bfa8e9eafff1d5d9650fe73764d560ded8d779f9537e66beb63bf71
Deleted: sha256:20066c011150610ade3733fc612429b0b2751930cd9a14f08450ac5ec68a93ad
Deleted: sha256:6c5134869965f9fca5564c32bc7d438adbf7fe4668d9a2282da35c6a6d137cb1
Deleted: sha256:5d7e17ed53503d84e892a25aeb2d017f285cb0bcc9c00cc730e947b1f881c443
Deleted: sha256:df476cad0b2195efad8887a476562f3276bdeaa9f5ed1bcab2b9ebc1caacdf24
Deleted: sha256:4887c473103a649aaa1f774e01ad72bef5c4b79a70ecf35c6ede6fcfb6d7aa34
Deleted: sha256:a6b2982ada93a6c0167d0397e36bc4fc7f2ab09f9b5f37f3a4985b514dd77fd4
Deleted: sha256:37b81fcec13b952ad4792f5d596785bb4b2a89ede24205622ff416e05c54636e
Deleted: sha256:bc6a1a4a4c9f939198a2c413e672e31620ee7136da519b91c6f275a84f763560
Deleted: sha256:9781c55ab432b22527af97aa15ad656ec2702d52f4ebde04dbc9f952f671c822
Deleted: sha256:f64f96b9087c024442139b8b1b195c3991458dfc663ee9568036ff13f835b9c9
Deleted: sha256:0c6b4ad8a384231b38fd55ce891b79d842b555fcc3404c348475825afbd669db
Deleted: sha256:22b9424425a7b34f934d9a0404faaad8399c5020a2d18fb1dd3a344a518c6559
Deleted: sha256:923e84d56ab2710d1230e69b64d86f9952f8cbf64ac211101b59de1ff8c0a79b
Deleted: sha256:00a94d956d8b9949b6cb55d4e25dfa82bb34ff35c2993077967d9886892390c6
Deleted: sha256:696d6c6e82f87848ff4c320790a4a324fe6f09084d163fdcc432b19c4b2815a8
Deleted: sha256:aabe96102f725fdb012b1d835a5e5d3bf309c2be1ad3c7a119642d83045a8a88
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3915
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3915/display/redirect?page=changes>