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/04 05:33:06 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) #3860
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)/3860/display/redirect?page=changes>
Changes:
[toenshoff] Fixed interference of unbundled dependency include paths with Boost.
[toenshoff] Added MESOS-7581 to the 1.2.2 CHANGELOG.
------------------------------------------
[...truncated 11.89 MB...]
I0704 05:33:02.418884 25449 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.418906 25449 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.419028 25449 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 to the agent
I0704 05:33:02.419118 25435 slave.cpp:4305] Forwarding the update TASK_RUNNING (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 to master@172.17.0.5:40097
I0704 05:33:02.419224 25435 slave.cpp:4199] Status update manager successfully handled status update TASK_RUNNING (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.419239 25444 master.cpp:6190] Status update TASK_RUNNING (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 from agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 at slave(286)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.419248 25435 slave.cpp:4215] Sending acknowledgement for status update TASK_RUNNING (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 to executor(120)@172.17.0.5:40097
I0704 05:33:02.419270 25444 master.cpp:6258] Forwarding status update TASK_RUNNING (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.419317 25436 exec.cpp:388] Executor received status update acknowledgement bcd923ec-98c8-481b-81e6-668bb62ef517 for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.419332 25444 master.cpp:8338] Updating the state of task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0704 05:33:02.419445 25448 sched.cpp:1041] Scheduler::statusUpdate took 46177ns
I0704 05:33:02.419585 25437 master.cpp:5092] Processing ACKNOWLEDGE call bcd923ec-98c8-481b-81e6-668bb62ef517 for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 (default) at scheduler-dff4b2e8-8fef-49f7-8ca8-de7db9bb03bc@172.17.0.5:40097 on agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0
I0704 05:33:02.419709 25432 status_update_manager.cpp:395] Received status update acknowledgement (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.419833 25432 exec.cpp:416] Executor received framework message
I0704 05:33:02.419884 25432 exec.cpp:425] Executor::frameworkMessage took 25876ns
I0704 05:33:02.419939 25446 slave.cpp:3111] Status update manager successfully handled status update acknowledgement (UUID: bcd923ec-98c8-481b-81e6-668bb62ef517) for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.420318 25444 slave.cpp:4373] Sending message for framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 to scheduler-dff4b2e8-8fef-49f7-8ca8-de7db9bb03bc@172.17.0.5:40097
I0704 05:33:02.420410 25439 sched.cpp:1177] Scheduler::frameworkMessage took 41144ns
I0704 05:33:02.420506 25429 sched.cpp:2021] Asked to stop the driver
I0704 05:33:02.420552 25453 sched.cpp:1203] Stopping framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.420682 25439 master.cpp:7741] Processing TEARDOWN call for framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 (default) at scheduler-dff4b2e8-8fef-49f7-8ca8-de7db9bb03bc@172.17.0.5:40097
I0704 05:33:02.420701 25439 master.cpp:7753] Removing framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 (default) at scheduler-dff4b2e8-8fef-49f7-8ca8-de7db9bb03bc@172.17.0.5:40097
I0704 05:33:02.420711 25439 master.cpp:3081] Deactivating framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 (default) at scheduler-dff4b2e8-8fef-49f7-8ca8-de7db9bb03bc@172.17.0.5:40097
I0704 05:33:02.420802 25443 hierarchical.cpp:415] Deactivated framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.420804 25439 master.cpp:8338] Updating the state of task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0704 05:33:02.420817 25440 slave.cpp:2664] Asked to shut down framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 by master@172.17.0.5:40097
I0704 05:33:02.420840 25440 slave.cpp:2689] Shutting down framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.420850 25440 slave.cpp:5131] Shutting down executor 'default' of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 at executor(120)@172.17.0.5:40097
I0704 05:33:02.420894 25451 exec.cpp:435] Executor asked to shutdown
I0704 05:33:02.420914 25451 exec.cpp:450] Executor::shutdown took 12949ns
I0704 05:33:02.421283 25448 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 d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 from framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.425884 25451 slave.cpp:4437] Got exited event for executor(120)@172.17.0.5:40097
I0704 05:33:02.425925 25439 master.cpp:8432] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 on agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 at slave(286)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.426009 25439 master.cpp:8461] Removing executor 'default' with resources {} of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 on agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 at slave(286)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.426259 25432 hierarchical.cpp:362] Removed framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.426545 25432 slave.cpp:4817] Executor 'default' of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 exited with status 0
I0704 05:33:02.426576 25432 slave.cpp:4917] Cleaning up executor 'default' of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 at executor(120)@172.17.0.5:40097
W0704 05:33:02.426664 25450 master.cpp:6314] Ignoring unknown exited executor 'default' of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000 on agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 at slave(286)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.426767 25444 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_IZDT0d/slaves/d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0/frameworks/d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000/executors/default/runs/183008ee-7193-41fd-822a-5a7f91a09527' for gc 6.99999506158519days in the future
I0704 05:33:02.426837 25432 slave.cpp:5005] Cleaning up framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.426882 25438 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_IZDT0d/slaves/d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0/frameworks/d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000/executors/default' for gc 6.9999950609037days in the future
I0704 05:33:02.426937 25436 status_update_manager.cpp:285] Closing status update streams for framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.426952 25436 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000
I0704 05:33:02.427059 25432 slave.cpp:809] Agent terminating
I0704 05:33:02.427058 25436 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_IZDT0d/slaves/d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0/frameworks/d2e5f6a9-e947-402b-b57d-e334fbc42dd7-0000' for gc 6.99999505782518days in the future
I0704 05:33:02.427204 25440 master.cpp:1264] Agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 at slave(286)@172.17.0.5:40097 (23a421726c5a) disconnected
I0704 05:33:02.427220 25440 master.cpp:3118] Disconnecting agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 at slave(286)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.427247 25440 master.cpp:3137] Deactivating agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 at slave(286)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.427297 25444 hierarchical.cpp:618] Agent d2e5f6a9-e947-402b-b57d-e334fbc42dd7-S0 deactivated
I0704 05:33:02.428304 25429 master.cpp:1103] Master terminating
[ OK ] MasterTest.FrameworkMessage (39 ms)
[ RUN ] MasterTest.MultipleExecutors
I0704 05:33:02.431037 25429 cluster.cpp:160] Creating default 'local' authorizer
I0704 05:33:02.431797 25433 master.cpp:383] Master 3829f37d-ca5d-473b-81e3-eca869924d34 (23a421726c5a) started on 172.17.0.5:40097
I0704 05:33:02.431823 25433 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/j6U7xG/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/j6U7xG/master" --zk_session_timeout="10secs"
I0704 05:33:02.432030 25433 master.cpp:435] Master only allowing authenticated frameworks to register
I0704 05:33:02.432041 25433 master.cpp:449] Master only allowing authenticated agents to register
I0704 05:33:02.432049 25433 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0704 05:33:02.432054 25433 credentials.hpp:37] Loading credentials for authentication from '/tmp/j6U7xG/credentials'
I0704 05:33:02.432160 25433 master.cpp:507] Using default 'crammd5' authenticator
I0704 05:33:02.432211 25433 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0704 05:33:02.432260 25433 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0704 05:33:02.432303 25433 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0704 05:33:02.432343 25433 master.cpp:587] Authorization enabled
I0704 05:33:02.432402 25453 whitelist_watcher.cpp:77] No whitelist given
I0704 05:33:02.432979 25439 hierarchical.cpp:161] Initialized hierarchical allocator process
I0704 05:33:02.433475 25433 master.cpp:2124] Elected as the leading master!
I0704 05:33:02.433492 25433 master.cpp:1646] Recovering from registrar
I0704 05:33:02.433600 25433 registrar.cpp:329] Recovering registrar
I0704 05:33:02.433845 25449 registrar.cpp:362] Successfully fetched the registry (0B) in 208896ns
I0704 05:33:02.433872 25449 registrar.cpp:461] Applied 1 operations in 6008ns; attempting to update the registry
I0704 05:33:02.434135 25449 registrar.cpp:506] Successfully updated the registry in 241152ns
I0704 05:33:02.434191 25449 registrar.cpp:392] Successfully recovered registrar
I0704 05:33:02.434669 25439 master.cpp:1762] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0704 05:33:02.434689 25436 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0704 05:33:02.437580 25429 cluster.cpp:446] Creating default 'local' authorizer
I0704 05:33:02.437954 25444 slave.cpp:217] Mesos agent started on (287)@172.17.0.5:40097
I0704 05:33:02.437973 25444 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/MasterTest_MultipleExecutors_mz2wWU/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/MasterTest_MultipleExecutors_mz2wWU/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/MasterTest_MultipleExecutors_mz2wWU/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/MasterTest_MultipleExecutors_mz2wWU" --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/MasterTest_MultipleExecutors_P71jKN"
I0704 05:33:02.438226 25444 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_mz2wWU/credential'
I0704 05:33:02.438292 25444 slave.cpp:360] Agent using credential for: test-principal
I0704 05:33:02.438303 25444 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_mz2wWU/http_credentials'
I0704 05:33:02.438385 25444 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0704 05:33:02.438421 25444 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0704 05:33:02.438544 25429 sched.cpp:232] Version: 1.2.2
I0704 05:33:02.438813 25446 sched.cpp:336] New master detected at master@172.17.0.5:40097
I0704 05:33:02.438839 25446 sched.cpp:407] Authenticating with master master@172.17.0.5:40097
I0704 05:33:02.438851 25446 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0704 05:33:02.438864 25444 slave.cpp:547] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 05:33:02.438894 25453 authenticatee.cpp:121] Creating new client SASL connection
I0704 05:33:02.438901 25444 slave.cpp:555] Agent attributes: [ ]
I0704 05:33:02.438910 25444 slave.cpp:560] Agent hostname: 23a421726c5a
I0704 05:33:02.438972 25435 status_update_manager.cpp:177] Pausing sending status updates
I0704 05:33:02.439028 25435 master.cpp:7199] Authenticating scheduler-e247f0ea-a55c-492c-b0c4-db5a0d55f758@172.17.0.5:40097
I0704 05:33:02.439131 25452 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(635)@172.17.0.5:40097
I0704 05:33:02.439199 25434 authenticator.cpp:98] Creating new server SASL connection
I0704 05:33:02.439505 25431 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 05:33:02.439549 25431 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 05:33:02.439636 25448 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_P71jKN/meta'
I0704 05:33:02.439685 25430 authenticator.cpp:204] Received SASL authentication start
I0704 05:33:02.439755 25430 authenticator.cpp:326] Authentication requires more steps
I0704 05:33:02.439808 25449 status_update_manager.cpp:203] Recovering status update manager
I0704 05:33:02.439842 25452 authenticatee.cpp:259] Received SASL authentication step
I0704 05:33:02.439920 25440 authenticator.cpp:232] Received SASL authentication step
I0704 05:33:02.439947 25440 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '23a421726c5a' server FQDN: '23a421726c5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 05:33:02.439956 25440 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 05:33:02.439970 25440 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 05:33:02.439977 25440 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '23a421726c5a' server FQDN: '23a421726c5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 05:33:02.439986 25440 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 05:33:02.439993 25440 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 05:33:02.440009 25440 authenticator.cpp:318] Authentication success
I0704 05:33:02.440073 25446 authenticatee.cpp:299] Authentication success
I0704 05:33:02.440075 25448 slave.cpp:5662] Finished recovery
I0704 05:33:02.440166 25443 master.cpp:7229] Successfully authenticated principal 'test-principal' at scheduler-e247f0ea-a55c-492c-b0c4-db5a0d55f758@172.17.0.5:40097
I0704 05:33:02.440244 25432 sched.cpp:513] Successfully authenticated with master master@172.17.0.5:40097
I0704 05:33:02.440259 25432 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.5:40097
I0704 05:33:02.440320 25432 sched.cpp:869] Will retry registration in 988.489189ms if necessary
I0704 05:33:02.440400 25447 master.cpp:2737] Received SUBSCRIBE call for framework 'default' at scheduler-e247f0ea-a55c-492c-b0c4-db5a0d55f758@172.17.0.5:40097
I0704 05:33:02.440405 25433 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(635)@172.17.0.5:40097
I0704 05:33:02.440423 25447 master.cpp:2160] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0704 05:33:02.440528 25448 slave.cpp:5836] Querying resource estimator for oversubscribable resources
I0704 05:33:02.440614 25438 master.cpp:2813] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0704 05:33:02.440623 25448 slave.cpp:937] New master detected at master@172.17.0.5:40097
I0704 05:33:02.440647 25441 status_update_manager.cpp:177] Pausing sending status updates
I0704 05:33:02.440662 25448 slave.cpp:972] Detecting new master
I0704 05:33:02.440742 25448 slave.cpp:5850] Received oversubscribable resources {} from the resource estimator
I0704 05:33:02.440840 25447 sched.cpp:759] Framework registered with 3829f37d-ca5d-473b-81e3-eca869924d34-0000
I0704 05:33:02.440852 25430 hierarchical.cpp:286] Added framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000
I0704 05:33:02.440883 25447 sched.cpp:773] Scheduler::registered took 33079ns
I0704 05:33:02.440902 25430 hierarchical.cpp:1810] No allocations performed
I0704 05:33:02.440914 25430 hierarchical.cpp:1900] No inverse offers to send out!
I0704 05:33:02.440925 25430 hierarchical.cpp:1394] Performed allocation for 0 agents in 35583ns
I0704 05:33:02.443774 25446 slave.cpp:999] Authenticating with master master@172.17.0.5:40097
I0704 05:33:02.443800 25446 slave.cpp:1010] Using default CRAM-MD5 authenticatee
I0704 05:33:02.443868 25439 authenticatee.cpp:121] Creating new client SASL connection
I0704 05:33:02.444037 25434 master.cpp:7199] Authenticating slave(287)@172.17.0.5:40097
I0704 05:33:02.444099 25439 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(636)@172.17.0.5:40097
I0704 05:33:02.444195 25435 authenticator.cpp:98] Creating new server SASL connection
I0704 05:33:02.444350 25436 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0704 05:33:02.444372 25436 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0704 05:33:02.444427 25445 authenticator.cpp:204] Received SASL authentication start
I0704 05:33:02.444464 25445 authenticator.cpp:326] Authentication requires more steps
I0704 05:33:02.444505 25445 authenticatee.cpp:259] Received SASL authentication step
I0704 05:33:02.444550 25445 authenticator.cpp:232] Received SASL authentication step
I0704 05:33:02.444567 25445 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '23a421726c5a' server FQDN: '23a421726c5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0704 05:33:02.444577 25445 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0704 05:33:02.444593 25445 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0704 05:33:02.444607 25445 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '23a421726c5a' server FQDN: '23a421726c5a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0704 05:33:02.444615 25445 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0704 05:33:02.444625 25445 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0704 05:33:02.444641 25445 authenticator.cpp:318] Authentication success
I0704 05:33:02.444685 25447 authenticatee.cpp:299] Authentication success
I0704 05:33:02.444703 25450 master.cpp:7229] Successfully authenticated principal 'test-principal' at slave(287)@172.17.0.5:40097
I0704 05:33:02.444720 25445 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(636)@172.17.0.5:40097
I0704 05:33:02.444880 25439 slave.cpp:1094] Successfully authenticated with master master@172.17.0.5:40097
I0704 05:33:02.444938 25439 slave.cpp:1521] Will retry registration in 10.880127ms if necessary
I0704 05:33:02.445019 25453 master.cpp:5396] Registering agent at slave(287)@172.17.0.5:40097 (23a421726c5a) with id 3829f37d-ca5d-473b-81e3-eca869924d34-S0
I0704 05:33:02.445173 25440 registrar.cpp:461] Applied 1 operations in 35199ns; attempting to update the registry
I0704 05:33:02.445590 25440 registrar.cpp:506] Successfully updated the registry in 378112ns
I0704 05:33:02.445802 25440 slave.cpp:4395] Received ping from slave-observer(281)@172.17.0.5:40097
I0704 05:33:02.445860 25440 slave.cpp:1140] Registered with master master@172.17.0.5:40097; given agent ID 3829f37d-ca5d-473b-81e3-eca869924d34-S0
I0704 05:33:02.445842 25445 master.cpp:5470] Registered agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 at slave(287)@172.17.0.5:40097 (23a421726c5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0704 05:33:02.445876 25440 fetcher.cpp:94] Clearing fetcher cache
I0704 05:33:02.445919 25452 hierarchical.cpp:516] Added agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 (23a421726c5a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0704 05:33:02.446028 25446 status_update_manager.cpp:184] Resuming sending status updates
I0704 05:33:02.446183 25452 hierarchical.cpp:1900] No inverse offers to send out!
I0704 05:33:02.446197 25452 hierarchical.cpp:1394] Performed allocation for 1 agents in 236154ns
I0704 05:33:02.446230 25440 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_P71jKN/meta/slaves/3829f37d-ca5d-473b-81e3-eca869924d34-S0/slave.info'
I0704 05:33:02.446317 25444 master.cpp:7028] Sending 1 offers to framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000 (default) at scheduler-e247f0ea-a55c-492c-b0c4-db5a0d55f758@172.17.0.5:40097
I0704 05:33:02.446466 25440 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0704 05:33:02.446496 25447 sched.cpp:933] Scheduler::resourceOffers took 61558ns
I0704 05:33:02.446512 25440 master.cpp:6045] Received update of agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 at slave(287)@172.17.0.5:40097 (23a421726c5a) with total oversubscribed resources {}
I0704 05:33:02.446602 25439 hierarchical.cpp:589] Agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 (23a421726c5a) 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])
I0704 05:33:02.446684 25439 hierarchical.cpp:1810] No allocations performed
I0704 05:33:02.446701 25439 hierarchical.cpp:1900] No inverse offers to send out!
I0704 05:33:02.446717 25439 hierarchical.cpp:1394] Performed allocation for 1 agents in 51585ns
I0704 05:33:02.447276 25435 master.cpp:3776] Processing ACCEPT call for offers: [ 3829f37d-ca5d-473b-81e3-eca869924d34-O0 ] on agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 at slave(287)@172.17.0.5:40097 (23a421726c5a) for framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000 (default) at scheduler-e247f0ea-a55c-492c-b0c4-db5a0d55f758@172.17.0.5:40097
I0704 05:33:02.447314 25435 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 1
I0704 05:33:02.447382 25435 master.cpp:3352] Authorizing framework principal 'test-principal' to launch task 2
W0704 05:33:02.447962 25443 validation.cpp:1064] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0704 05:33:02.447981 25443 validation.cpp:1076] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0704 05:33:02.448046 25443 master.cpp:9089] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 at slave(287)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.448086 25443 master.cpp:4426] Launching task 1 of framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000 (default) at scheduler-e247f0ea-a55c-492c-b0c4-db5a0d55f758@172.17.0.5:40097 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 at slave(287)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.448210 25452 slave.cpp:1631] Got assigned task '1' for framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000
W0704 05:33:02.448323 25443 validation.cpp:1064] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0704 05:33:02.448338 25443 validation.cpp:1076] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0704 05:33:02.448397 25443 master.cpp:9089] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 at slave(287)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.448433 25443 master.cpp:4426] Launching task 2 of framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000 (default) at scheduler-e247f0ea-a55c-492c-b0c4-db5a0d55f758@172.17.0.5:40097 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 at slave(287)@172.17.0.5:40097 (23a421726c5a)
I0704 05:33:02.448465 25452 slave.cpp:1791] Launching task '1' for framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000
I0704 05:33:02.448868 25446 hierarchical.cpp:807] Updated allocation of framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000 on agent 3829f37d-ca5d-473b-81e3-eca869924d34-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]
I0704 05:33:02.448900 25452 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_P71jKN/slaves/3829f37d-ca5d-473b-81e3-eca869924d34-S0/frameworks/3829f37d-ca5d-473b-81e3-eca869924d34-0000/executors/executor-1/runs/4a1ff740-e9f1-4344-94e6-7a7d3a6c90d7' to user 'mesos'
I0704 05:33:02.448982 25446 hierarchical.cpp:1064] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 from framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000
I0704 05:33:02.449003 25446 hierarchical.cpp:1101] Framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000 filtered agent 3829f37d-ca5d-473b-81e3-eca869924d34-S0 for 5secs
I0704 05:33:02.449075 25452 slave.cpp:6584] Launching executor 'executor-1' of framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_P71jKN/slaves/3829f37d-ca5d-473b-81e3-eca869924d34-S0/frameworks/3829f37d-ca5d-473b-81e3-eca869924d34-0000/executors/executor-1/runs/4a1ff740-e9f1-4344-94e6-7a7d3a6c90d7'
I0704 05:33:02.449319 25452 slave.cpp:2124] Queued task '1' for executor 'executor-1' of framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000
I0704 05:33:02.449391 25452 slave.cpp:1631] Got assigned task '2' for framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000
I0704 05:33:02.449568 25452 slave.cpp:890] Successfully attached file '/tmp/MasterTest_MultipleExecutors_P71jKN/slaves/3829f37d-ca5d-473b-81e3-eca869924d34-S0/frameworks/3829f37d-ca5d-473b-81e3-eca869924d34-0000/executors/executor-1/runs/4a1ff740-e9f1-4344-94e6-7a7d3a6c90d7'
I0704 05:33:02.449592 25452 slave.cpp:1791] Launching task '2' for framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000
I0704 05:33:02.449884 25452 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_P71jKN/slaves/3829f37d-ca5d-473b-81e3-eca869924d34-S0/frameworks/3829f37d-ca5d-473b-81e3-eca869924d34-0000/executors/executor-2/runs/0e1edb75-0c75-4892-acbc-1db79b6a1189' to user 'mesos'
I0704 05:33:02.450021 25452 slave.cpp:6584] Launching executor 'executor-2' of framework 3829f37d-ca5d-473b-81e3-eca869924d34-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_P71jKN/slaves/3829f37d-ca5d-473b-81e3-eca869924d34-S0/frameworks/3829f37d-ca5d-473b-81e3-eca869924d34-0000/executors/executor-2/runs/0e1edb75-0c75-4892-acbc-1db79b6a1189'
*** Aborted at 1499146382 (unix time) try "date -d @1499146382" if you are using GNU date ***
I0704 05:33:02.450242 25439 exec.cpp:162] Version: 1.2.2
I0704 05:33:02.450305 25440 exec.cpp:212] Executor started at: executor(121)@172.17.0.5:40097 with pid 25429
PC: @ 0x2b9a76c3882d (unknown)
*** SIGSEGV (@0xd0) received by PID 25429 (TID 0x2b9a807d4700) from PID 208; stack trace: ***
@ 0x2b9a761cf330 (unknown)
@ 0x2b9a76c3882d (unknown)
@ 0x2b9a71dba3c2 mesos::internal::slave::executorEnvironment()
@ 0x2b9a71d986f6 mesos::internal::slave::Framework::launchExecutor()
@ 0x2b9a71d94e8f mesos::internal::slave::Slave::_run()
@ 0x2b9a73605fc7 process::ProcessManager::resume()
@ 0x2b9a7361a76f std::thread::_Impl<>::_M_run()
@ 0x2b9a7648ea60 (unknown)
@ 0x2b9a761c7184 start_thread
@ 0x2b9a76cfaffd (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-1499144306-26437
Untagged: mesos-1499144306-26437:latest
Deleted: sha256:23bea218fa8cf18c5725259a68ba3ffe50ed606b0ec729e4a6300fdd2a717260
Deleted: sha256:84e6f99e7e2cacd14b41774b45671d5a5d3cbb3efc60861e81e6de25b58e9e99
Deleted: sha256:2c8d32a6bf640be4f88e8121c84190ea321c9d045fd65eb800ad0c3403e32532
Deleted: sha256:32058ee5b9b9880183333124bf3130bd1d9ca8e0c865619a46422f0c44209ec6
Deleted: sha256:2113e9c7b9873179cc9497cdf385e5fd57f9eabf5d95589abc3d4f180675df01
Deleted: sha256:a9e09c1dc3d90ef0f97342810545cfc13be07fd007aa72d2aa939526f75e8591
Deleted: sha256:f6d8349b959d43a3b87d7bfda7b036cc1d4114c57ee8e023251eb3f5f65dee3c
Deleted: sha256:0f73abb8ca63c61e7d13aa87050bf6dda1e08ed9d990f7c21557f73af00c907f
Deleted: sha256:26fc0d3534e40f94f4e7775cb61e68a63d136185dc8521959907067f2d96c49d
Deleted: sha256:c3e1123f57ec6b1adfbcc2c48bf8e85782bc60c0ca2a449162d061e13f61c5ea
Deleted: sha256:559c85945266e20c9d4f507c824fa74079a6728174f7eccb3e52da5406f15da3
Deleted: sha256:ef53a95b1d5838e72a8c086cdacf9bfe0f079f0192f7dcceea574f283cddb24f
Deleted: sha256:843947dd0e6a5d50f43c6f25ef7bad22138f1dcafa9946fed1ee24b129b09352
Deleted: sha256:b566f8f2c48fa1d351702bdee55923df14e0c9738abd96c38d8f9f0b9f72da5b
Deleted: sha256:6dd15be9746896c5aa41013fd0e89addbe2ef84b3a7884234d4fd06637010dde
Deleted: sha256:87a11f76bdbaf66261725e6620ebb118d3abf76be0ace28e693c7e3296c85ada
Deleted: sha256:6388f008f9d8f0619602e5140cd80654dcf5db9a0db6ca09b9993f94508b7e06
Deleted: sha256:eb3cc3d04253913c7bdf8e0313ce4ee6c0eda586014a416e3300a7a33ed277f9
Deleted: sha256:b90a5b43f9a3201602bee59574b4e9d27efc9e0ef54058efae0b7a92a04c6f5f
Deleted: sha256:123b82240dfff4fee9ab7005bfc5194258c42db9572725f038b0cf36198bb9d3
Deleted: sha256:66b7f885a0362deaad4de6b6b9e65b7f540dcda6578c4f5bd4d927ec05f11477
Deleted: sha256:2479c35d9e97794d0ff42fabc2ede70b355fcf3427eec117c1af4f1e6b70956e
Deleted: sha256:608bb5caca623a29697b0875ab76d321cb95510be4cd0572cbbae139d638d114
Deleted: sha256:9502400690c609d5b74de9556975b56176f664c2f2655b53a04b06ba6154e1c3
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user toenshoff@me.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) #3861
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)/3861/display/redirect?page=changes>