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/05/12 16:38:42 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3672
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=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3672/display/redirect?page=changes>
Changes:
[alexr] Added MESOS-7482 to 1.1.2 CHANGELOG.
------------------------------------------
[...truncated 12.97 MB...]
I0512 16:38:36.464833 24074 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.464857 24074 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.465055 24074 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 to the agent
I0512 16:38:36.465150 24068 slave.cpp:4655] Forwarding the update TASK_RUNNING (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 to master@172.17.0.2:42390
I0512 16:38:36.465283 24053 master.cpp:6469] Status update TASK_RUNNING (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 from agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 at slave(317)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.465308 24053 master.cpp:6537] Forwarding status update TASK_RUNNING (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.465365 24053 master.cpp:8568] Updating the state of task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0512 16:38:36.465416 24068 slave.cpp:4549] Status update manager successfully handled status update TASK_RUNNING (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.465435 24068 slave.cpp:4565] Sending acknowledgement for status update TASK_RUNNING (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 to executor(124)@172.17.0.2:42390
I0512 16:38:36.465473 24073 sched.cpp:1041] Scheduler::statusUpdate took 65135ns
I0512 16:38:36.465550 24057 exec.cpp:374] Executor received status update acknowledgement 6813bc2b-9837-4fdb-a844-23cc3e404fb2 for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.465577 24062 master.cpp:5194] Processing ACKNOWLEDGE call 6813bc2b-9837-4fdb-a844-23cc3e404fb2 for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 (default) at scheduler-a5492831-ef32-4dcc-91ec-80f144e3491d@172.17.0.2:42390 on agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0
I0512 16:38:36.465797 24063 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.465895 24058 slave.cpp:3504] Status update manager successfully handled status update acknowledgement (UUID: 6813bc2b-9837-4fdb-a844-23cc3e404fb2) for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.466158 24055 exec.cpp:396] Executor received framework message
I0512 16:38:36.466210 24055 exec.cpp:405] Executor::frameworkMessage took 45242ns
I0512 16:38:36.466605 24064 slave.cpp:4723] Sending message for framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 to scheduler-a5492831-ef32-4dcc-91ec-80f144e3491d@172.17.0.2:42390
I0512 16:38:36.466794 24065 sched.cpp:1177] Scheduler::frameworkMessage took 75898ns
I0512 16:38:36.467005 24051 sched.cpp:2021] Asked to stop the driver
I0512 16:38:36.467077 24067 sched.cpp:1203] Stopping framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.467172 24059 master.cpp:7988] Processing TEARDOWN call for framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 (default) at scheduler-a5492831-ef32-4dcc-91ec-80f144e3491d@172.17.0.2:42390
I0512 16:38:36.467195 24059 master.cpp:8000] Removing framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 (default) at scheduler-a5492831-ef32-4dcc-91ec-80f144e3491d@172.17.0.2:42390
I0512 16:38:36.467209 24059 master.cpp:3160] Deactivating framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 (default) at scheduler-a5492831-ef32-4dcc-91ec-80f144e3491d@172.17.0.2:42390
I0512 16:38:36.467375 24059 master.cpp:8568] Updating the state of task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0512 16:38:36.467424 24070 hierarchical.cpp:374] Deactivated framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.467556 24059 master.cpp:8662] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 on agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 at slave(317)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.467664 24059 master.cpp:8691] Removing executor 'default' with resources {} of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 on agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 at slave(317)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.467742 24070 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 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 from framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.467443 24072 slave.cpp:3057] Asked to shut down framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 by master@172.17.0.2:42390
I0512 16:38:36.467839 24072 slave.cpp:3082] Shutting down framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.467864 24072 slave.cpp:5482] Shutting down executor 'default' of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 at executor(124)@172.17.0.2:42390
I0512 16:38:36.468000 24070 hierarchical.cpp:325] Removed framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.468183 24057 slave.cpp:4787] Got exited event for executor(124)@172.17.0.2:42390
I0512 16:38:36.468318 24052 slave.cpp:5168] Executor 'default' of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 exited with status 0
I0512 16:38:36.468427 24052 slave.cpp:5268] Cleaning up executor 'default' of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 at executor(124)@172.17.0.2:42390
W0512 16:38:36.468508 24072 master.cpp:6592] Ignoring unknown exited executor 'default' of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000 on agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 at slave(317)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.468597 24055 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_0UTfjV/slaves/850f47f2-5684-4338-a7c4-7b9c6705738d-S0/frameworks/850f47f2-5684-4338-a7c4-7b9c6705738d-0000/executors/default/runs/9c4c8b99-6494-4b84-9256-847730ced2e2' for gc 6.99999457726815days in the future
I0512 16:38:36.468628 24052 slave.cpp:5356] Cleaning up framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.468649 24055 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_0UTfjV/slaves/850f47f2-5684-4338-a7c4-7b9c6705738d-S0/frameworks/850f47f2-5684-4338-a7c4-7b9c6705738d-0000/executors/default' for gc 6.99999457624days in the future
I0512 16:38:36.468716 24057 status_update_manager.cpp:285] Closing status update streams for framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.468736 24057 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 850f47f2-5684-4338-a7c4-7b9c6705738d-0000
I0512 16:38:36.468761 24061 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_0UTfjV/slaves/850f47f2-5684-4338-a7c4-7b9c6705738d-S0/frameworks/850f47f2-5684-4338-a7c4-7b9c6705738d-0000' for gc 6.99999457525333days in the future
I0512 16:38:36.468956 24066 slave.cpp:790] Agent terminating
I0512 16:38:36.469123 24071 master.cpp:1313] Agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 at slave(317)@172.17.0.2:42390 (5f4dd9b5ed30) disconnected
I0512 16:38:36.469143 24071 master.cpp:3197] Disconnecting agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 at slave(317)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.469167 24071 master.cpp:3216] Deactivating agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 at slave(317)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.469215 24068 hierarchical.cpp:653] Agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0 deactivated
I0512 16:38:36.470435 24051 master.cpp:1155] Master terminating
I0512 16:38:36.470588 24068 hierarchical.cpp:558] Removed agent 850f47f2-5684-4338-a7c4-7b9c6705738d-S0
[ OK ] MasterTest.FrameworkMessage (31 ms)
[ RUN ] MasterTest.MultipleExecutors
I0512 16:38:36.473808 24051 cluster.cpp:162] Creating default 'local' authorizer
I0512 16:38:36.474687 24068 master.cpp:436] Master c4d66e5e-1863-41b9-a036-4aa3d8e2aba8 (5f4dd9b5ed30) started on 172.17.0.2:42390
I0512 16:38:36.474710 24068 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/74hhp2/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/74hhp2/master" --zk_session_timeout="10secs"
I0512 16:38:36.474889 24068 master.cpp:488] Master only allowing authenticated frameworks to register
I0512 16:38:36.474898 24068 master.cpp:502] Master only allowing authenticated agents to register
I0512 16:38:36.474906 24068 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0512 16:38:36.474932 24068 credentials.hpp:37] Loading credentials for authentication from '/tmp/74hhp2/credentials'
I0512 16:38:36.475095 24068 master.cpp:560] Using default 'crammd5' authenticator
I0512 16:38:36.475149 24068 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0512 16:38:36.475208 24068 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0512 16:38:36.475258 24068 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0512 16:38:36.475325 24068 master.cpp:640] Authorization enabled
I0512 16:38:36.475484 24063 whitelist_watcher.cpp:77] No whitelist given
I0512 16:38:36.475497 24055 hierarchical.cpp:158] Initialized hierarchical allocator process
I0512 16:38:36.476183 24060 master.cpp:2161] Elected as the leading master!
I0512 16:38:36.476197 24060 master.cpp:1700] Recovering from registrar
I0512 16:38:36.476274 24066 registrar.cpp:345] Recovering registrar
I0512 16:38:36.476549 24066 registrar.cpp:389] Successfully fetched the registry (0B) in 260864ns
I0512 16:38:36.476583 24066 registrar.cpp:493] Applied 1 operations in 8191ns; attempting to update the registry
I0512 16:38:36.476727 24066 registrar.cpp:550] Successfully updated the registry in 128us
I0512 16:38:36.476765 24066 registrar.cpp:422] Successfully recovered registrar
I0512 16:38:36.477110 24072 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0512 16:38:36.477133 24061 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0512 16:38:36.478907 24051 cluster.cpp:448] Creating default 'local' authorizer
I0512 16:38:36.479688 24063 slave.cpp:225] Mesos agent started on (318)@172.17.0.2:42390
I0512 16:38:36.479714 24063 slave.cpp:226] 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_B0Yq3A/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_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_B0Yq3A/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/MasterTest_MultipleExecutors_B0Yq3A/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_B0Yq3A" --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_uDGlJj"
I0512 16:38:36.479943 24063 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_B0Yq3A/credential'
I0512 16:38:36.480044 24063 slave.cpp:258] Agent using credential for: test-principal
I0512 16:38:36.480060 24063 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_B0Yq3A/http_credentials'
I0512 16:38:36.480172 24063 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0512 16:38:36.480242 24063 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0512 16:38:36.480480 24051 sched.cpp:232] Version: 1.4.0
I0512 16:38:36.480690 24063 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0512 16:38:36.480720 24063 slave.cpp:533] Agent attributes: [ ]
I0512 16:38:36.480725 24063 slave.cpp:538] Agent hostname: 5f4dd9b5ed30
I0512 16:38:36.481067 24063 status_update_manager.cpp:177] Pausing sending status updates
I0512 16:38:36.481216 24063 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_uDGlJj/meta'
I0512 16:38:36.481346 24063 sched.cpp:336] New master detected at master@172.17.0.2:42390
I0512 16:38:36.481369 24063 sched.cpp:407] Authenticating with master master@172.17.0.2:42390
I0512 16:38:36.481377 24063 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0512 16:38:36.481431 24063 status_update_manager.cpp:203] Recovering status update manager
I0512 16:38:36.481469 24063 authenticatee.cpp:121] Creating new client SASL connection
I0512 16:38:36.481616 24063 master.cpp:7475] Authenticating scheduler-79bb1e43-28b3-4333-9b36-d1b5b158bdec@172.17.0.2:42390
I0512 16:38:36.481678 24063 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(674)@172.17.0.2:42390
I0512 16:38:36.481731 24063 slave.cpp:5970] Finished recovery
I0512 16:38:36.482043 24063 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0512 16:38:36.482094 24063 authenticator.cpp:98] Creating new server SASL connection
I0512 16:38:36.482224 24063 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0512 16:38:36.482240 24063 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0512 16:38:36.482273 24063 slave.cpp:918] New master detected at master@172.17.0.2:42390
I0512 16:38:36.482303 24063 slave.cpp:953] Detecting new master
I0512 16:38:36.482342 24063 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0512 16:38:36.482369 24063 authenticator.cpp:204] Received SASL authentication start
I0512 16:38:36.482404 24063 authenticator.cpp:326] Authentication requires more steps
I0512 16:38:36.482427 24063 status_update_manager.cpp:177] Pausing sending status updates
I0512 16:38:36.482455 24063 authenticatee.cpp:259] Received SASL authentication step
I0512 16:38:36.482487 24063 authenticator.cpp:232] Received SASL authentication step
I0512 16:38:36.482498 24063 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5f4dd9b5ed30' server FQDN: '5f4dd9b5ed30' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0512 16:38:36.482506 24063 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0512 16:38:36.482517 24063 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0512 16:38:36.482524 24063 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5f4dd9b5ed30' server FQDN: '5f4dd9b5ed30' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0512 16:38:36.482529 24063 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0512 16:38:36.482533 24063 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0512 16:38:36.482543 24063 authenticator.cpp:318] Authentication success
I0512 16:38:36.482573 24063 authenticatee.cpp:299] Authentication success
I0512 16:38:36.482592 24063 master.cpp:7505] Successfully authenticated principal 'test-principal' at scheduler-79bb1e43-28b3-4333-9b36-d1b5b158bdec@172.17.0.2:42390
I0512 16:38:36.482614 24063 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(674)@172.17.0.2:42390
I0512 16:38:36.482666 24063 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:42390
I0512 16:38:36.482674 24063 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:42390
I0512 16:38:36.482699 24063 sched.cpp:869] Will retry registration in 675.578615ms if necessary
I0512 16:38:36.482758 24063 master.cpp:2813] Received SUBSCRIBE call for framework 'default' at scheduler-79bb1e43-28b3-4333-9b36-d1b5b158bdec@172.17.0.2:42390
I0512 16:38:36.482769 24063 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0512 16:38:36.482861 24063 master.cpp:2890] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0512 16:38:36.483033 24052 hierarchical.cpp:273] Added framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.483113 24052 hierarchical.cpp:1850] No allocations performed
I0512 16:38:36.483129 24052 hierarchical.cpp:1940] No inverse offers to send out!
I0512 16:38:36.483141 24052 hierarchical.cpp:1434] Performed allocation for 0 agents in 46017ns
I0512 16:38:36.483253 24062 sched.cpp:759] Framework registered with c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.483278 24062 sched.cpp:773] Scheduler::registered took 13804ns
I0512 16:38:36.492506 24063 slave.cpp:980] Authenticating with master master@172.17.0.2:42390
I0512 16:38:36.492538 24063 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0512 16:38:36.492609 24063 authenticatee.cpp:121] Creating new client SASL connection
I0512 16:38:36.492784 24063 master.cpp:7475] Authenticating slave(318)@172.17.0.2:42390
I0512 16:38:36.492880 24054 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(675)@172.17.0.2:42390
I0512 16:38:36.492974 24066 authenticator.cpp:98] Creating new server SASL connection
I0512 16:38:36.493118 24070 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0512 16:38:36.493152 24070 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0512 16:38:36.493275 24067 authenticator.cpp:204] Received SASL authentication start
I0512 16:38:36.493322 24067 authenticator.cpp:326] Authentication requires more steps
I0512 16:38:36.493371 24067 authenticatee.cpp:259] Received SASL authentication step
I0512 16:38:36.493418 24067 authenticator.cpp:232] Received SASL authentication step
I0512 16:38:36.493438 24067 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5f4dd9b5ed30' server FQDN: '5f4dd9b5ed30' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0512 16:38:36.493446 24067 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0512 16:38:36.493456 24067 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0512 16:38:36.493471 24067 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '5f4dd9b5ed30' server FQDN: '5f4dd9b5ed30' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0512 16:38:36.493479 24067 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0512 16:38:36.493489 24067 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0512 16:38:36.493502 24067 authenticator.cpp:318] Authentication success
I0512 16:38:36.493579 24063 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(318)@172.17.0.2:42390
I0512 16:38:36.493588 24067 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(675)@172.17.0.2:42390
I0512 16:38:36.493558 24054 authenticatee.cpp:299] Authentication success
I0512 16:38:36.493811 24055 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:42390
I0512 16:38:36.493891 24055 slave.cpp:1503] Will retry registration in 1.826438ms if necessary
I0512 16:38:36.493947 24061 master.cpp:5429] Received register agent message from slave(318)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.493970 24061 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0512 16:38:36.494204 24060 master.cpp:5564] Registering agent at slave(318)@172.17.0.2:42390 (5f4dd9b5ed30) with id c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0
I0512 16:38:36.494365 24057 registrar.cpp:493] Applied 1 operations in 37606ns; attempting to update the registry
I0512 16:38:36.494719 24057 registrar.cpp:550] Successfully updated the registry in 325888ns
I0512 16:38:36.494997 24057 slave.cpp:4745] Received ping from slave-observer(310)@172.17.0.2:42390
I0512 16:38:36.495018 24061 master.cpp:5639] Registered agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 at slave(318)@172.17.0.2:42390 (5f4dd9b5ed30) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0512 16:38:36.495060 24057 slave.cpp:1121] Registered with master master@172.17.0.2:42390; given agent ID c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0
I0512 16:38:36.495074 24057 fetcher.cpp:94] Clearing fetcher cache
I0512 16:38:36.495117 24062 hierarchical.cpp:525] Added agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 (5f4dd9b5ed30) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0512 16:38:36.495208 24055 status_update_manager.cpp:184] Resuming sending status updates
I0512 16:38:36.495460 24057 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_uDGlJj/meta/slaves/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0/slave.info'
I0512 16:38:36.495478 24062 hierarchical.cpp:1940] No inverse offers to send out!
I0512 16:38:36.495496 24062 hierarchical.cpp:1434] Performed allocation for 1 agents in 322106ns
I0512 16:38:36.495640 24059 master.cpp:7305] Sending 1 offers to framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000 (default) at scheduler-79bb1e43-28b3-4333-9b36-d1b5b158bdec@172.17.0.2:42390
I0512 16:38:36.495744 24057 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0512 16:38:36.495793 24057 master.cpp:6324] Received update of agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 at slave(318)@172.17.0.2:42390 (5f4dd9b5ed30) with total oversubscribed resources {}
I0512 16:38:36.495848 24075 sched.cpp:933] Scheduler::resourceOffers took 61229ns
I0512 16:38:36.496592 24063 master.cpp:3875] Processing ACCEPT call for offers: [ c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-O0 ] on agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 at slave(318)@172.17.0.2:42390 (5f4dd9b5ed30) for framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000 (default) at scheduler-79bb1e43-28b3-4333-9b36-d1b5b158bdec@172.17.0.2:42390
I0512 16:38:36.496626 24063 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 1
I0512 16:38:36.496692 24063 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 2
W0512 16:38:36.497109 24063 validation.cpp:1284] 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.
W0512 16:38:36.497128 24063 validation.cpp:1296] 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.
I0512 16:38:36.497195 24063 master.cpp:9326] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 at slave(318)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.497236 24063 master.cpp:4531] Launching task 1 of framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000 (default) at scheduler-79bb1e43-28b3-4333-9b36-d1b5b158bdec@172.17.0.2:42390 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 at slave(318)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.497375 24056 slave.cpp:1613] Got assigned task '1' for framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
W0512 16:38:36.497498 24063 validation.cpp:1284] 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.
W0512 16:38:36.497514 24063 validation.cpp:1296] 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.
I0512 16:38:36.497571 24063 master.cpp:9326] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 at slave(318)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.497583 24056 slave.cpp:1894] Authorizing task '1' for framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.497601 24056 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 1
I0512 16:38:36.497608 24063 master.cpp:4531] Launching task 2 of framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000 (default) at scheduler-79bb1e43-28b3-4333-9b36-d1b5b158bdec@172.17.0.2:42390 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 at slave(318)@172.17.0.2:42390 (5f4dd9b5ed30)
I0512 16:38:36.497756 24056 slave.cpp:1613] Got assigned task '2' for framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.497953 24056 slave.cpp:2081] Launching task '1' for framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.498293 24071 hierarchical.cpp:850] Updated allocation of framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000 on agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-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]
I0512 16:38:36.498311 24056 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_uDGlJj/slaves/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0/frameworks/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000/executors/executor-1/runs/9ec7492f-c247-49c0-a093-4b99809e98f6' to user 'mesos'
I0512 16:38:36.498428 24071 hierarchical.cpp:1114] 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 c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 from framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.498451 24071 hierarchical.cpp:1151] Framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000 filtered agent c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0 for 5secs
I0512 16:38:36.498487 24056 slave.cpp:6929] Launching executor 'executor-1' of framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_uDGlJj/slaves/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0/frameworks/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000/executors/executor-1/runs/9ec7492f-c247-49c0-a093-4b99809e98f6'
I0512 16:38:36.498677 24056 slave.cpp:2310] Queued task '1' for executor 'executor-1' of framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.498718 24056 slave.cpp:1894] Authorizing task '2' for framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.498733 24056 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 2
I0512 16:38:36.498848 24056 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_uDGlJj/slaves/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0/frameworks/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000/executors/executor-1/runs/9ec7492f-c247-49c0-a093-4b99809e98f6'
I0512 16:38:36.499032 24062 slave.cpp:2081] Launching task '2' for framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000
I0512 16:38:36.499415 24062 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_uDGlJj/slaves/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0/frameworks/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000/executors/executor-2/runs/cc5a804a-8d78-4935-9448-3ded46d29132' to user 'mesos'
I0512 16:38:36.499578 24062 slave.cpp:6929] Launching executor 'executor-2' of framework c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_uDGlJj/slaves/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-S0/frameworks/c4d66e5e-1863-41b9-a036-4aa3d8e2aba8-0000/executors/executor-2/runs/cc5a804a-8d78-4935-9448-3ded46d29132'
*** Aborted at 1494607116 (unix time) try "date -d @1494607116" if you are using GNU date ***
I0512 16:38:36.499841 24069 exec.cpp:162] Version: 1.4.0
I0512 16:38:36.499898 24072 exec.cpp:212] Executor started at: executor(125)@172.17.0.2:42390 with pid 24051
PC: @ 0x2b507706682d (unknown)
*** SIGSEGV (@0xc0) received by PID 24051 (TID 0x2b507f3f4700) from PID 192; stack trace: ***
@ 0x2b50765fd330 (unknown)
@ 0x2b507706682d (unknown)
@ 0x2b5071e97f05 mesos::internal::slave::executorEnvironment()
@ 0x2b5071e93057 mesos::internal::slave::Slave::launchExecutor()
@ 0x2b5071e8d77f mesos::internal::slave::Slave::__run()
@ 0x2b50737dc7d7 process::ProcessManager::resume()
@ 0x2b50737f1c5f std::thread::_Impl<>::_M_run()
@ 0x2b50768bca60 (unknown)
@ 0x2b50765f5184 start_thread
@ 0x2b5077128bed (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-1494605127-1231
Untagged: mesos-1494605127-1231:latest
Deleted: sha256:48962d36e3f6b0ae1792d752f1feab06006b0e5d508e68f7764b476f58a80e34
Deleted: sha256:787a050ca6c6aba9e89636c21f75e4e0ae96102561039a721b33c23e17462944
Deleted: sha256:cd8e629fb98854a1f283269c04f95899676ceb1a5245d7f9147871b4b5a87247
Deleted: sha256:6a6db77d5d1576d2f7bfa99e9e099f5f583d5cd3c793ace00746ece93303303b
Deleted: sha256:c86b43b58bf67ed47f8467c84a927f952fe0919f716b6a6850838217946832b8
Deleted: sha256:7e0a3a45a38432c9b4d644558c5bef3db83929ec3e4d19eefe78d22e2575e826
Deleted: sha256:d4bd58d135af2ff829b4e9790e071ad0e3f4797b9d97001ec2ed27aaef174dc1
Deleted: sha256:3f5781e03dd824997cbc4bdfa7c6eebd091650b1f8f57053a8e05922e625da41
Deleted: sha256:12a1b2c020c37f0b49a53c58c43f59a4b5c99ab228e5c5709a6a08bde1f7ed33
Deleted: sha256:b72194e3865b9ef4f06646c1e6c1dfbf215ff54daadc58747e8c3ec797f6cf1c
Deleted: sha256:994c90b17dba47fd07c6b6577869d3240c94e2d0a2fb1519e151a98c59192141
Deleted: sha256:5cd46aeaa091223d65bc6c2d175d1fdced69fb340909639af8cf509c04fc2987
Deleted: sha256:1c22a1862dec59a5b091da96f951fb3c43e65c8989bfd3a0e18cca2d0d895225
Deleted: sha256:f304f1c1576e21fa666fd0ed6e383dfcdefa03f0c0047325c5b4f17cedcbd84f
Deleted: sha256:1386c2338e764756f0855ac05bd430661e0e1c911cfaaa1e6700fda1f283da28
Deleted: sha256:e9436b7bc51d61b241995b38ba8844ba648914df7a509a8747949a47d957740e
Deleted: sha256:6e5ae6c1cf830bed2e6cf7d57ffffdc1547e56e9de0a7b1e79157c372b76ea89
Deleted: sha256:2159fe0c0181af40dcbaf6be02a11eaf551b0627ee5b229b2597445fd4d1e711
Deleted: sha256:1492790b7d34b6fad47f9e4912ff788a0786ee096b99b4da4b7bd783187faada
Deleted: sha256:de825702c905ceab130bbf2dc6750f27dc69b2ca984ec02c7853187a64a66148
Deleted: sha256:896a27b447124eaaca08201ece8e0d7aae80ea07c282a675645e1deac4d0543c
Deleted: sha256:9c44f19db721019611c446d3fde6396e38f285224c4744fb9bfd8c3859922b18
Deleted: sha256:aed0ca1ea2ab0dd95cd396cd5fcb6acee9db95cee98008b967dffa7c17be37c3
Deleted: sha256:42182b2c19cc45d0865c3d66ff1d5e3747a82550903066c04318b1ba8f36567c
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,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3673
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=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3673/display/redirect?page=changes>