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/03/28 23:19:39 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3429
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3429/display/redirect?page=changes>
Changes:
[alexr] Removed non-ASCII characters from docs.
------------------------------------------
[...truncated 12.29 MB...]
I0328 23:19:33.528965 26695 slave.cpp:4523] Sending acknowledgement for status update TASK_RUNNING (UUID: d5e19da8-cf3a-400b-bf4c-58c753771473) for task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 to executor(120)@172.17.0.3:60120
I0328 23:19:33.529043 26684 master.cpp:6243] Status update TASK_RUNNING (UUID: d5e19da8-cf3a-400b-bf4c-58c753771473) for task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 from agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 at slave(292)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.529222 26691 exec.cpp:374] Executor received status update acknowledgement d5e19da8-cf3a-400b-bf4c-58c753771473 for task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.529245 26684 master.cpp:6311] Forwarding status update TASK_RUNNING (UUID: d5e19da8-cf3a-400b-bf4c-58c753771473) for task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.529332 26684 master.cpp:8345] Updating the state of task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0328 23:19:33.529429 26695 sched.cpp:1041] Scheduler::statusUpdate took 44907ns
I0328 23:19:33.529608 26697 master.cpp:5151] Processing ACKNOWLEDGE call d5e19da8-cf3a-400b-bf4c-58c753771473 for task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 (default) at scheduler-0af7afec-1518-4a06-a3ca-07bb7cfb6746@172.17.0.3:60120 on agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0
I0328 23:19:33.529906 26688 status_update_manager.cpp:395] Received status update acknowledgement (UUID: d5e19da8-cf3a-400b-bf4c-58c753771473) for task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.529950 26684 exec.cpp:396] Executor received framework message
I0328 23:19:33.530105 26684 exec.cpp:405] Executor::frameworkMessage took 49442ns
I0328 23:19:33.530017 26687 slave.cpp:3462] Status update manager successfully handled status update acknowledgement (UUID: d5e19da8-cf3a-400b-bf4c-58c753771473) for task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.530511 26685 slave.cpp:4681] Sending message for framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 to scheduler-0af7afec-1518-4a06-a3ca-07bb7cfb6746@172.17.0.3:60120
I0328 23:19:33.530660 26685 sched.cpp:1177] Scheduler::frameworkMessage took 81759ns
I0328 23:19:33.530845 26683 sched.cpp:2021] Asked to stop the driver
I0328 23:19:33.530912 26685 sched.cpp:1203] Stopping framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.531108 26685 master.cpp:7765] Processing TEARDOWN call for framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 (default) at scheduler-0af7afec-1518-4a06-a3ca-07bb7cfb6746@172.17.0.3:60120
I0328 23:19:33.531128 26685 master.cpp:7777] Removing framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 (default) at scheduler-0af7afec-1518-4a06-a3ca-07bb7cfb6746@172.17.0.3:60120
I0328 23:19:33.531209 26685 master.cpp:3148] Deactivating framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 (default) at scheduler-0af7afec-1518-4a06-a3ca-07bb7cfb6746@172.17.0.3:60120
I0328 23:19:33.531378 26685 master.cpp:8345] Updating the state of task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0328 23:19:33.531587 26685 master.cpp:8439] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 on agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 at slave(292)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.531754 26685 master.cpp:8468] Removing executor 'default' with resources {} of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 on agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 at slave(292)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.531818 26691 hierarchical.cpp:376] Deactivated framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.532243 26691 hierarchical.cpp:1116] 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 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 from framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.532347 26691 hierarchical.cpp:323] Removed framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.531836 26687 slave.cpp:3015] Asked to shut down framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 by master@172.17.0.3:60120
I0328 23:19:33.532460 26687 slave.cpp:3040] Shutting down framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.532508 26687 slave.cpp:5433] Shutting down executor 'default' of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 at executor(120)@172.17.0.3:60120
I0328 23:19:33.532661 26692 exec.cpp:415] Executor asked to shutdown
I0328 23:19:33.532712 26692 exec.cpp:430] Executor::shutdown took 29850ns
I0328 23:19:33.532832 26693 slave.cpp:4745] Got exited event for executor(120)@172.17.0.3:60120
I0328 23:19:33.533315 26693 slave.cpp:5119] Executor 'default' of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 exited with status 0
I0328 23:19:33.533537 26693 slave.cpp:5219] Cleaning up executor 'default' of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 at executor(120)@172.17.0.3:60120
W0328 23:19:33.533615 26686 master.cpp:6367] Ignoring unknown exited executor 'default' of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000 on agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 at slave(292)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.533846 26684 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_WObQhL/slaves/6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0/frameworks/6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000/executors/default/runs/7ea5d9ce-890e-4a7c-b4bb-e48980132841' for gc 6.99999382191111days in the future
I0328 23:19:33.533875 26693 slave.cpp:5307] Cleaning up framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.533898 26684 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_WObQhL/slaves/6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0/frameworks/6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000/executors/default' for gc 6.99999382104296days in the future
I0328 23:19:33.533939 26695 status_update_manager.cpp:285] Closing status update streams for framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.533963 26695 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000
I0328 23:19:33.533993 26693 slave.cpp:748] Agent terminating
I0328 23:19:33.534009 26684 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_WObQhL/slaves/6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0/frameworks/6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-0000' for gc 6.99999381998815days in the future
I0328 23:19:33.534153 26693 master.cpp:1317] Agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 at slave(292)@172.17.0.3:60120 (2cf8ea199e7a) disconnected
I0328 23:19:33.534219 26693 master.cpp:3185] Disconnecting agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 at slave(292)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.534287 26693 master.cpp:3204] Deactivating agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 at slave(292)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.534368 26691 hierarchical.cpp:655] Agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0 deactivated
I0328 23:19:33.535662 26683 master.cpp:1156] Master terminating
I0328 23:19:33.535881 26690 hierarchical.cpp:560] Removed agent 6c8d2ae3-a6fa-4c7f-aefa-9986d9b0503c-S0
[ OK ] MasterTest.FrameworkMessage (39 ms)
[ RUN ] MasterTest.MultipleExecutors
I0328 23:19:33.540411 26683 cluster.cpp:160] Creating default 'local' authorizer
I0328 23:19:33.541592 26686 master.cpp:437] Master e54682f3-3ea3-42a7-925d-4cf909dcf48c (2cf8ea199e7a) started on 172.17.0.3:60120
I0328 23:19:33.541623 26686 master.cpp:439] 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/T7bRvw/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" --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/T7bRvw/master" --zk_session_timeout="10secs"
I0328 23:19:33.541965 26686 master.cpp:489] Master only allowing authenticated frameworks to register
I0328 23:19:33.541977 26686 master.cpp:503] Master only allowing authenticated agents to register
I0328 23:19:33.541985 26686 master.cpp:516] Master only allowing authenticated HTTP frameworks to register
I0328 23:19:33.541993 26686 credentials.hpp:37] Loading credentials for authentication from '/tmp/T7bRvw/credentials'
I0328 23:19:33.542209 26686 master.cpp:561] Using default 'crammd5' authenticator
I0328 23:19:33.542275 26686 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0328 23:19:33.542417 26686 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0328 23:19:33.542470 26686 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0328 23:19:33.542516 26686 master.cpp:641] Authorization enabled
I0328 23:19:33.542598 26691 whitelist_watcher.cpp:77] No whitelist given
I0328 23:19:33.542600 26690 hierarchical.cpp:159] Initialized hierarchical allocator process
I0328 23:19:33.543548 26686 master.cpp:2165] Elected as the leading master!
I0328 23:19:33.543568 26686 master.cpp:1704] Recovering from registrar
I0328 23:19:33.543689 26686 registrar.cpp:331] Recovering registrar
I0328 23:19:33.543936 26686 registrar.cpp:364] Successfully fetched the registry (0B) in 222976ns
I0328 23:19:33.543974 26686 registrar.cpp:463] Applied 1 operations in 4128ns; attempting to update the registry
I0328 23:19:33.544260 26686 registrar.cpp:508] Successfully updated the registry in 262912ns
I0328 23:19:33.544313 26686 registrar.cpp:394] Successfully recovered registrar
I0328 23:19:33.544440 26686 master.cpp:1803] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0328 23:19:33.544509 26686 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover
I0328 23:19:33.546497 26683 cluster.cpp:446] Creating default 'local' authorizer
I0328 23:19:33.547086 26684 slave.cpp:226] Mesos agent started on (293)@172.17.0.3:60120
I0328 23:19:33.547158 26684 slave.cpp:227] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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_RCoOgp/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_secret_key="secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_RCoOgp/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_RCoOgp/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" --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_RCoOgp" --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_mackTs"
I0328 23:19:33.547628 26684 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_RCoOgp/credential'
I0328 23:19:33.547752 26683 sched.cpp:232] Version: 1.3.0
I0328 23:19:33.547804 26684 slave.cpp:259] Agent using credential for: test-principal
I0328 23:19:33.547850 26684 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_RCoOgp/http_credentials'
I0328 23:19:33.548050 26696 sched.cpp:336] New master detected at master@172.17.0.3:60120
I0328 23:19:33.548092 26696 sched.cpp:407] Authenticating with master master@172.17.0.3:60120
I0328 23:19:33.548107 26696 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0328 23:19:33.548212 26697 authenticatee.cpp:121] Creating new client SASL connection
I0328 23:19:33.548390 26696 master.cpp:7252] Authenticating scheduler-1d095b88-13c6-4d27-8e0c-b59cb2226fbc@172.17.0.3:60120
I0328 23:19:33.548395 26684 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0328 23:19:33.548440 26697 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(640)@172.17.0.3:60120
I0328 23:19:33.548532 26695 authenticator.cpp:98] Creating new server SASL connection
I0328 23:19:33.548553 26684 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0328 23:19:33.548820 26684 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0328 23:19:33.548833 26685 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0328 23:19:33.548857 26684 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0328 23:19:33.548921 26684 http.cpp:976] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0328 23:19:33.548957 26684 http.cpp:997] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0328 23:19:33.549355 26684 slave.cpp:486] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0328 23:19:33.549392 26684 slave.cpp:494] Agent attributes: [ ]
I0328 23:19:33.549397 26684 slave.cpp:499] Agent hostname: 2cf8ea199e7a
I0328 23:19:33.549705 26684 status_update_manager.cpp:177] Pausing sending status updates
I0328 23:19:33.548857 26685 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0328 23:19:33.550077 26692 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_mackTs/meta'
I0328 23:19:33.550086 26697 authenticator.cpp:204] Received SASL authentication start
I0328 23:19:33.550143 26697 authenticator.cpp:326] Authentication requires more steps
I0328 23:19:33.550248 26693 status_update_manager.cpp:203] Recovering status update manager
I0328 23:19:33.550261 26692 authenticatee.cpp:259] Received SASL authentication step
I0328 23:19:33.550470 26693 authenticator.cpp:232] Received SASL authentication step
I0328 23:19:33.550488 26696 slave.cpp:5921] Finished recovery
I0328 23:19:33.550498 26693 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '2cf8ea199e7a' server FQDN: '2cf8ea199e7a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0328 23:19:33.550570 26693 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0328 23:19:33.550609 26693 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0328 23:19:33.550647 26693 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '2cf8ea199e7a' server FQDN: '2cf8ea199e7a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0328 23:19:33.550680 26693 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0328 23:19:33.550709 26693 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0328 23:19:33.550746 26693 authenticator.cpp:318] Authentication success
I0328 23:19:33.550884 26693 authenticatee.cpp:299] Authentication success
I0328 23:19:33.550922 26693 master.cpp:7282] Successfully authenticated principal 'test-principal' at scheduler-1d095b88-13c6-4d27-8e0c-b59cb2226fbc@172.17.0.3:60120
I0328 23:19:33.550979 26693 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(640)@172.17.0.3:60120
I0328 23:19:33.551142 26693 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:60120
I0328 23:19:33.551162 26693 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:60120
I0328 23:19:33.551321 26693 sched.cpp:869] Will retry registration in 1.565339041secs if necessary
I0328 23:19:33.551373 26686 master.cpp:2817] Received SUBSCRIBE call for framework 'default' at scheduler-1d095b88-13c6-4d27-8e0c-b59cb2226fbc@172.17.0.3:60120
I0328 23:19:33.550988 26696 slave.cpp:6103] Querying resource estimator for oversubscribable resources
I0328 23:19:33.551417 26686 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0328 23:19:33.551528 26696 slave.cpp:876] New master detected at master@172.17.0.3:60120
I0328 23:19:33.551573 26696 slave.cpp:911] Detecting new master
I0328 23:19:33.551626 26686 status_update_manager.cpp:177] Pausing sending status updates
I0328 23:19:33.551703 26699 master.cpp:2894] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0328 23:19:33.551947 26699 sched.cpp:759] Framework registered with e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.551980 26699 sched.cpp:773] Scheduler::registered took 15796ns
I0328 23:19:33.551990 26689 hierarchical.cpp:271] Added framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.552084 26689 hierarchical.cpp:1862] No allocations performed
I0328 23:19:33.552103 26689 hierarchical.cpp:1952] No inverse offers to send out!
I0328 23:19:33.552119 26689 hierarchical.cpp:1446] Performed allocation for 0 agents in 44199ns
I0328 23:19:33.552172 26696 slave.cpp:6117] Received oversubscribable resources {} from the resource estimator
I0328 23:19:33.563830 26684 slave.cpp:938] Authenticating with master master@172.17.0.3:60120
I0328 23:19:33.563882 26684 slave.cpp:949] Using default CRAM-MD5 authenticatee
I0328 23:19:33.563972 26684 authenticatee.cpp:121] Creating new client SASL connection
I0328 23:19:33.564158 26684 master.cpp:7252] Authenticating slave(293)@172.17.0.3:60120
I0328 23:19:33.564218 26684 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(641)@172.17.0.3:60120
I0328 23:19:33.564354 26684 authenticator.cpp:98] Creating new server SASL connection
I0328 23:19:33.564499 26686 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0328 23:19:33.564519 26686 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0328 23:19:33.564565 26686 authenticator.cpp:204] Received SASL authentication start
I0328 23:19:33.564625 26686 authenticator.cpp:326] Authentication requires more steps
I0328 23:19:33.564666 26686 authenticatee.cpp:259] Received SASL authentication step
I0328 23:19:33.564721 26686 authenticator.cpp:232] Received SASL authentication step
I0328 23:19:33.564757 26686 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '2cf8ea199e7a' server FQDN: '2cf8ea199e7a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0328 23:19:33.564770 26686 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0328 23:19:33.564800 26686 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0328 23:19:33.564813 26686 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '2cf8ea199e7a' server FQDN: '2cf8ea199e7a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0328 23:19:33.564823 26686 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0328 23:19:33.564833 26686 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0328 23:19:33.564849 26686 authenticator.cpp:318] Authentication success
I0328 23:19:33.564915 26697 authenticatee.cpp:299] Authentication success
I0328 23:19:33.564978 26697 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(641)@172.17.0.3:60120
I0328 23:19:33.564919 26686 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(293)@172.17.0.3:60120
I0328 23:19:33.565104 26697 slave.cpp:1033] Successfully authenticated with master master@172.17.0.3:60120
I0328 23:19:33.565317 26697 slave.cpp:1461] Will retry registration in 15.231056ms if necessary
I0328 23:19:33.565452 26691 master.cpp:5437] Registering agent at slave(293)@172.17.0.3:60120 (2cf8ea199e7a) with id e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0
I0328 23:19:33.565673 26694 registrar.cpp:463] Applied 1 operations in 22186ns; attempting to update the registry
I0328 23:19:33.566133 26686 registrar.cpp:508] Successfully updated the registry in 438016ns
I0328 23:19:33.566489 26696 slave.cpp:4703] Received ping from slave-observer(287)@172.17.0.3:60120
I0328 23:19:33.566563 26696 slave.cpp:1079] Registered with master master@172.17.0.3:60120; given agent ID e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0
I0328 23:19:33.566581 26696 fetcher.cpp:94] Clearing fetcher cache
I0328 23:19:33.566496 26691 master.cpp:5511] Registered agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 at slave(293)@172.17.0.3:60120 (2cf8ea199e7a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0328 23:19:33.566623 26692 hierarchical.cpp:527] Added agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 (2cf8ea199e7a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0328 23:19:33.566902 26692 hierarchical.cpp:1952] No inverse offers to send out!
I0328 23:19:33.566922 26692 hierarchical.cpp:1446] Performed allocation for 1 agents in 219624ns
I0328 23:19:33.566954 26692 status_update_manager.cpp:184] Resuming sending status updates
I0328 23:19:33.566972 26696 slave.cpp:1107] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_mackTs/meta/slaves/e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0/slave.info'
I0328 23:19:33.567183 26695 master.cpp:7081] Sending 1 offers to framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000 (default) at scheduler-1d095b88-13c6-4d27-8e0c-b59cb2226fbc@172.17.0.3:60120
I0328 23:19:33.567385 26695 sched.cpp:933] Scheduler::resourceOffers took 50824ns
I0328 23:19:33.567476 26696 slave.cpp:1145] Forwarding total oversubscribed resources {}
I0328 23:19:33.567663 26696 master.cpp:6098] Received update of agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 at slave(293)@172.17.0.3:60120 (2cf8ea199e7a) with total oversubscribed resources {}
I0328 23:19:33.568213 26685 master.cpp:3839] Processing ACCEPT call for offers: [ e54682f3-3ea3-42a7-925d-4cf909dcf48c-O0 ] on agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 at slave(293)@172.17.0.3:60120 (2cf8ea199e7a) for framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000 (default) at scheduler-1d095b88-13c6-4d27-8e0c-b59cb2226fbc@172.17.0.3:60120
I0328 23:19:33.568265 26685 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 1
I0328 23:19:33.568383 26685 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 2
W0328 23:19:33.569003 26689 validation.cpp:1113] 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.
W0328 23:19:33.569031 26689 validation.cpp:1125] 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.
I0328 23:19:33.569113 26689 master.cpp:9097] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 at slave(293)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.569172 26689 master.cpp:4489] Launching task 1 of framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000 (default) at scheduler-1d095b88-13c6-4d27-8e0c-b59cb2226fbc@172.17.0.3:60120 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 at slave(293)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.569342 26690 slave.cpp:1571] Got assigned task '1' for framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
W0328 23:19:33.569463 26689 validation.cpp:1113] 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.
W0328 23:19:33.569484 26689 validation.cpp:1125] 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.
I0328 23:19:33.569583 26689 master.cpp:9097] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 at slave(293)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.569645 26689 master.cpp:4489] Launching task 2 of framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000 (default) at scheduler-1d095b88-13c6-4d27-8e0c-b59cb2226fbc@172.17.0.3:60120 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 at slave(293)@172.17.0.3:60120 (2cf8ea199e7a)
I0328 23:19:33.569677 26690 slave.cpp:1852] Authorizing task '1' for framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.569702 26690 slave.cpp:6540] Authorizing framework principal 'test-principal' to launch task 1
I0328 23:19:33.569929 26690 slave.cpp:1571] Got assigned task '2' for framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.570138 26690 slave.cpp:2039] Launching task '1' for framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.570363 26689 hierarchical.cpp:852] Updated allocation of framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000 on agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-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]
I0328 23:19:33.570533 26689 hierarchical.cpp:1116] 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 e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 from framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.570562 26689 hierarchical.cpp:1153] Framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000 filtered agent e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0 for 5secs
I0328 23:19:33.570740 26690 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_mackTs/slaves/e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0/frameworks/e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000/executors/executor-1/runs/971c195f-e661-4efe-aed5-0927e26d6618' to user 'mesos'
I0328 23:19:33.570919 26690 slave.cpp:6884] Launching executor 'executor-1' of framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_mackTs/slaves/e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0/frameworks/e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000/executors/executor-1/runs/971c195f-e661-4efe-aed5-0927e26d6618'
I0328 23:19:33.571177 26690 slave.cpp:2268] Queued task '1' for executor 'executor-1' of framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.571223 26690 slave.cpp:1852] Authorizing task '2' for framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.571240 26690 slave.cpp:6540] Authorizing framework principal 'test-principal' to launch task 2
I0328 23:19:33.571373 26690 slave.cpp:829] Successfully attached file '/tmp/MasterTest_MultipleExecutors_mackTs/slaves/e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0/frameworks/e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000/executors/executor-1/runs/971c195f-e661-4efe-aed5-0927e26d6618'
I0328 23:19:33.571621 26690 slave.cpp:2039] Launching task '2' for framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
I0328 23:19:33.571979 26690 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_mackTs/slaves/e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0/frameworks/e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000/executors/executor-2/runs/47e605e0-7349-4d9c-8145-5b951b3c9247' to user 'mesos'
I0328 23:19:33.572124 26690 slave.cpp:6884] Launching executor 'executor-2' of framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_mackTs/slaves/e54682f3-3ea3-42a7-925d-4cf909dcf48c-S0/frameworks/e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000/executors/executor-2/runs/47e605e0-7349-4d9c-8145-5b951b3c9247'
I0328 23:19:33.572360 26690 slave.cpp:2268] Queued task '2' for executor 'executor-2' of framework e54682f3-3ea3-42a7-925d-4cf909dcf48c-0000
*** Aborted at 1490743173 (unix time) try "date -d @1490743173" if you are using GNU date ***
I0328 23:19:33.572590 26685 exec.cpp:162] Version: 1.3.0
I0328 23:19:33.572849 26685 exec.cpp:212] Executor started at: executor(121)@172.17.0.3:60120 with pid 26683
PC: @ 0x7fb5283b80cd __GI_getenv
*** SIGSEGV (@0xf0) received by PID 26683 (TID 0x7fb51fc9c700) from PID 240; stack trace: ***
@ 0x7fb528f71370 (unknown)
@ 0x7fb5283b80cd __GI_getenv
@ 0x7fb52d02e194 mesos::internal::slave::executorEnvironment()
@ 0x7fb52d053d37 mesos::internal::slave::Slave::launchExecutor()
@ 0x7fb52c25e271 process::ProcessManager::resume()
@ 0x7fb52c2672f7 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7fb528d0e230 (unknown)
@ 0x7fb528f69dc5 start_thread
@ 0x7fb52847773d __clone
make[3]: *** [CMakeFiles/check] Segmentation fault
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-1490740275-16114
Untagged: mesos-1490740275-16114:latest
Deleted: sha256:48f8806d0cc4da999e07a4b2d9bc8273e6cd85c64442f00dc51f526e317c0514
Deleted: sha256:e12dba5a2794042d7cbf4fe9efe823c2763441ae983c1bedcbea0b915e80c637
Deleted: sha256:d2a331c7a264d0f7775b1a8a14d4a0db153ce821df48618cbe997bb7abbc77f6
Deleted: sha256:2ce69838e733e98914702be3834e423fb9a34c0507b48dc9f966d63ba01f457f
Deleted: sha256:8d458af4af69f5e574faae216c62bd3a4cf692d80446461588ad42f85678374d
Deleted: sha256:8170c40a4c59bda2c62e84b8eee95246231dcbd726280fccc3be55766291e154
Deleted: sha256:69c68b2fdd9a1a04af34211ad927ca9da46fb1db6ba1f1c030430c5bd5382cf0
Deleted: sha256:beb2926394fe6b2a7e0228624a800fff93e61bf98a070f3b5927384be87f1128
Deleted: sha256:fb6ccd15c43ee2525ed8a048e6e5da136c6009b866f64c50c968ce7800af49d9
Deleted: sha256:09c0e737b239b8570983f351e6c0dd1c96e64d025e5c70f768399a8c4ac19b12
Deleted: sha256:d5d33b0d14957d9841ccd9a96bfcc8d805c952c0adcc87b31931b63440a874e1
Deleted: sha256:5bcd4d40faeee5b01da1bcd543eea8fb4907a6a6908ef5881448c38949a4b447
Deleted: sha256:7883ded0e748ac25a3a18d1fdc134a1d0f252a5cf18192c7dcd98e3abb2eef0b
Deleted: sha256:18d0fdc4355d00aa7e4dcbc9b0c2bb227fd4dfb40cfaa214bd6764994d7ffcb3
Deleted: sha256:1e638178a969cd0a65c52a7d349157e142c1541a6f42a5ab75cfedfbdfa8590f
Deleted: sha256:64edcfd7f3aced278d8d1bc1fffc49ff384e22d65bde34ac07b2e297aef02c30
Deleted: sha256:1659abeb88bcff180669e4e4bf542c6f4326af3d68114abb1a10a87e65e0fea1
Deleted: sha256:c0cb554b3ba6e0215804f177ed26a511aa516d0260fb5fd4e89afd34a1a61deb
Deleted: sha256:3b3ec7baabdc29a8c458ec8aad70e6e3b7c2f11a247590af8e4b543e2fde96d4
Deleted: sha256:784ba189963de25de3af9aab20001bf29d3c81cb0a09aa37e5901f805dd80644
Deleted: sha256:94a1ef8cdd4e4cdd1d772bf8757273b32d340a3a52d3749fc6aefab44250fbf6
Deleted: sha256:109c8b2e40d780493e1749c5a2dbdbf8053cb550b63d2b6eeeade5be20945285
Deleted: sha256:93a54067418a07cd1fa09d8d4cd8118a636415ceca878eaae0be0bbcf53897de
Deleted: sha256:5fb104f9b861f696c7343ff8e035925f4122131762b080b72223bebf4664437c
Deleted: sha256:b533e97e056575fd5e1bb6e7a3deedd4e0c4e07a2120ec7edec5180c6678e7b5
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3430
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3430/display/redirect?page=changes>