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/13 18:59:01 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3364
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3364/display/redirect?page=changes>
Changes:
[anand] Fixed indentation in process.cpp.
[alexr] Added `Containerizer::remove`.
[alexr] Introduced proto changes needed for the RemoveNestedContainer API call.
[alexr] Introduced changes to the authz protos needed for RemoveNestedContainer.
[alexr] Made the usage of C++ namespaces in "slave/http.cpp" consistent.
[alexr] Implemented the Agent API call `RemoveNestedContainer`.
[alexr] Added documentation for the 'RemoveNestedContainer' Agent API call.
[alexr] Removed unnecessary curly braces wrapping case statements.
------------------------------------------
[...truncated 11.71 MB...]
I0313 18:58:41.180018 24501 exec.cpp:334] Executor::launchTask took 17905ns
I0313 18:58:41.180065 24501 exec.cpp:551] Executor sending status update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.180150 24501 slave.cpp:3817] Handling status update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 from executor(120)@172.17.0.2:44429
I0313 18:58:41.180517 24490 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.180541 24490 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.180640 24490 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 to the agent
I0313 18:58:41.180768 24499 slave.cpp:4257] Forwarding the update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 to master@172.17.0.2:44429
I0313 18:58:41.180863 24499 slave.cpp:4151] Status update manager successfully handled status update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.180884 24499 slave.cpp:4167] Sending acknowledgement for status update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 to executor(120)@172.17.0.2:44429
I0313 18:58:41.180887 24482 master.cpp:6243] Status update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 from agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 at slave(283)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.180922 24482 master.cpp:6311] Forwarding status update TASK_RUNNING (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.180963 24493 exec.cpp:374] Executor received status update acknowledgement 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45 for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.180995 24482 master.cpp:8345] Updating the state of task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0313 18:58:41.181085 24489 sched.cpp:1041] Scheduler::statusUpdate took 46073ns
I0313 18:58:41.181206 24503 master.cpp:5151] Processing ACKNOWLEDGE call 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45 for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 (default) at scheduler-6bf84532-1d68-4bdd-a7d1-4611a4a9e09d@172.17.0.2:44429 on agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0
I0313 18:58:41.181483 24492 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.181614 24499 exec.cpp:396] Executor received framework message
I0313 18:58:41.181628 24490 slave.cpp:3106] Status update manager successfully handled status update acknowledgement (UUID: 324eaaf6-b60a-4fcd-8e8c-db809dfe7a45) for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.181663 24499 exec.cpp:405] Executor::frameworkMessage took 33066ns
I0313 18:58:41.182078 24489 slave.cpp:4325] Sending message for framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 to scheduler-6bf84532-1d68-4bdd-a7d1-4611a4a9e09d@172.17.0.2:44429
I0313 18:58:41.182211 24486 sched.cpp:1177] Scheduler::frameworkMessage took 50424ns
I0313 18:58:41.182379 24480 sched.cpp:2021] Asked to stop the driver
I0313 18:58:41.182435 24486 sched.cpp:1203] Stopping framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.182621 24484 master.cpp:7765] Processing TEARDOWN call for framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 (default) at scheduler-6bf84532-1d68-4bdd-a7d1-4611a4a9e09d@172.17.0.2:44429
I0313 18:58:41.182648 24484 master.cpp:7777] Removing framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 (default) at scheduler-6bf84532-1d68-4bdd-a7d1-4611a4a9e09d@172.17.0.2:44429
I0313 18:58:41.182657 24484 master.cpp:3148] Deactivating framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 (default) at scheduler-6bf84532-1d68-4bdd-a7d1-4611a4a9e09d@172.17.0.2:44429
I0313 18:58:41.182986 24489 hierarchical.cpp:376] Deactivated framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.182999 24484 master.cpp:8345] Updating the state of task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0313 18:58:41.183030 24495 slave.cpp:2664] Asked to shut down framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 by master@172.17.0.2:44429
I0313 18:58:41.183055 24495 slave.cpp:2689] Shutting down framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.183069 24495 slave.cpp:5076] Shutting down executor 'default' of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 at executor(120)@172.17.0.2:44429
I0313 18:58:41.183218 24494 exec.cpp:415] Executor asked to shutdown
I0313 18:58:41.183260 24494 exec.cpp:430] Executor::shutdown took 14409ns
I0313 18:58:41.183300 24484 master.cpp:8439] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 on agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 at slave(283)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.183388 24484 master.cpp:8468] Removing executor 'default' with resources {} of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 on agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 at slave(283)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.183658 24493 slave.cpp:4389] Got exited event for executor(120)@172.17.0.2:44429
I0313 18:58:41.183702 24487 hierarchical.cpp:1108] 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 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 from framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.183750 24494 slave.cpp:4762] Executor 'default' of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 exited with status 0
I0313 18:58:41.183790 24494 slave.cpp:4862] Cleaning up executor 'default' of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 at executor(120)@172.17.0.2:44429
W0313 18:58:41.183850 24493 master.cpp:6367] Ignoring unknown exited executor 'default' of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000 on agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 at slave(283)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.183872 24487 hierarchical.cpp:323] Removed framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.184118 24497 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_A8BqG4/slaves/0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0/frameworks/0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000/executors/default/runs/d46cd70b-cf6c-4c63-a8b8-826b09028e0a' for gc 6.99999787175704days in the future
I0313 18:58:41.184207 24494 slave.cpp:4950] Cleaning up framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.184279 24496 status_update_manager.cpp:285] Closing status update streams for framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.184299 24496 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000
I0313 18:58:41.184381 24492 slave.cpp:808] Agent terminating
I0313 18:58:41.184521 24486 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_A8BqG4/slaves/0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0/frameworks/0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000/executors/default' for gc 6.99999786837926days in the future
I0313 18:58:41.184559 24486 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_A8BqG4/slaves/0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0/frameworks/0100e248-6ca5-45a2-a0ff-a1bac22cc404-0000' for gc 6.99999786693333days in the future
I0313 18:58:41.184617 24482 master.cpp:1317] Agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 at slave(283)@172.17.0.2:44429 (55582b48a078) disconnected
I0313 18:58:41.184638 24482 master.cpp:3185] Disconnecting agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 at slave(283)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.184669 24482 master.cpp:3204] Deactivating agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 at slave(283)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.184810 24487 hierarchical.cpp:655] Agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0 deactivated
I0313 18:58:41.185932 24480 master.cpp:1156] Master terminating
I0313 18:58:41.186175 24488 hierarchical.cpp:560] Removed agent 0100e248-6ca5-45a2-a0ff-a1bac22cc404-S0
[ OK ] MasterTest.FrameworkMessage (27 ms)
[ RUN ] MasterTest.MultipleExecutors
I0313 18:58:41.189568 24480 cluster.cpp:160] Creating default 'local' authorizer
I0313 18:58:41.190752 24493 master.cpp:437] Master f40445be-b28a-44f2-856d-bd1faee44b45 (55582b48a078) started on 172.17.0.2:44429
I0313 18:58:41.190768 24493 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/2XEkas/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/2XEkas/master" --zk_session_timeout="10secs"
I0313 18:58:41.190992 24493 master.cpp:489] Master only allowing authenticated frameworks to register
I0313 18:58:41.190999 24493 master.cpp:503] Master only allowing authenticated agents to register
I0313 18:58:41.191002 24493 master.cpp:516] Master only allowing authenticated HTTP frameworks to register
I0313 18:58:41.191006 24493 credentials.hpp:37] Loading credentials for authentication from '/tmp/2XEkas/credentials'
I0313 18:58:41.191117 24493 master.cpp:561] Using default 'crammd5' authenticator
I0313 18:58:41.191162 24493 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0313 18:58:41.191228 24493 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0313 18:58:41.191277 24493 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0313 18:58:41.191447 24493 master.cpp:641] Authorization enabled
I0313 18:58:41.191704 24501 hierarchical.cpp:159] Initialized hierarchical allocator process
I0313 18:58:41.191710 24494 whitelist_watcher.cpp:77] No whitelist given
I0313 18:58:41.192639 24481 master.cpp:2165] Elected as the leading master!
I0313 18:58:41.192656 24481 master.cpp:1704] Recovering from registrar
I0313 18:58:41.192708 24493 registrar.cpp:331] Recovering registrar
I0313 18:58:41.193027 24493 registrar.cpp:364] Successfully fetched the registry (0B) in 284928ns
I0313 18:58:41.193060 24493 registrar.cpp:463] Applied 1 operations in 4574ns; attempting to update the registry
I0313 18:58:41.193367 24493 registrar.cpp:508] Successfully updated the registry in 282112ns
I0313 18:58:41.193405 24493 registrar.cpp:394] Successfully recovered registrar
I0313 18:58:41.193678 24496 master.cpp:1803] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0313 18:58:41.193732 24494 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover
I0313 18:58:41.195520 24480 cluster.cpp:446] Creating default 'local' authorizer
I0313 18:58:41.196014 24501 slave.cpp:215] Mesos agent started on (284)@172.17.0.2:44429
I0313 18:58:41.196029 24501 slave.cpp:216] 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_kZye0D/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_kZye0D/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_kZye0D/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_kZye0D" --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_s3bh52"
I0313 18:58:41.196384 24501 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_kZye0D/credential'
I0313 18:58:41.196473 24501 slave.cpp:358] Agent using credential for: test-principal
I0313 18:58:41.196491 24501 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_kZye0D/http_credentials'
I0313 18:58:41.196581 24501 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0313 18:58:41.196766 24501 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0313 18:58:41.196807 24480 sched.cpp:232] Version: 1.3.0
I0313 18:58:41.197052 24487 sched.cpp:336] New master detected at master@172.17.0.2:44429
I0313 18:58:41.197082 24487 sched.cpp:407] Authenticating with master master@172.17.0.2:44429
I0313 18:58:41.197091 24487 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0313 18:58:41.197163 24495 authenticatee.cpp:121] Creating new client SASL connection
I0313 18:58:41.197377 24502 master.cpp:7252] Authenticating scheduler-79bf8987-1457-44fd-8b33-56da30f84486@172.17.0.2:44429
I0313 18:58:41.197449 24482 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(627)@172.17.0.2:44429
I0313 18:58:41.197535 24501 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0313 18:58:41.197571 24501 slave.cpp:553] Agent attributes: [ ]
I0313 18:58:41.197577 24501 slave.cpp:558] Agent hostname: 55582b48a078
I0313 18:58:41.197610 24486 authenticator.cpp:98] Creating new server SASL connection
I0313 18:58:41.197710 24481 status_update_manager.cpp:177] Pausing sending status updates
I0313 18:58:41.197790 24492 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0313 18:58:41.197811 24492 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0313 18:58:41.197859 24492 authenticator.cpp:204] Received SASL authentication start
I0313 18:58:41.197893 24492 authenticator.cpp:326] Authentication requires more steps
I0313 18:58:41.197963 24492 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_s3bh52/meta'
I0313 18:58:41.197993 24500 authenticatee.cpp:259] Received SASL authentication step
I0313 18:58:41.198097 24500 authenticator.cpp:232] Received SASL authentication step
I0313 18:58:41.198123 24500 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '55582b48a078' server FQDN: '55582b48a078' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0313 18:58:41.198137 24500 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0313 18:58:41.198151 24500 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0313 18:58:41.198168 24500 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '55582b48a078' server FQDN: '55582b48a078' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0313 18:58:41.198171 24492 status_update_manager.cpp:203] Recovering status update manager
I0313 18:58:41.198185 24500 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0313 18:58:41.198205 24500 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0313 18:58:41.198225 24500 authenticator.cpp:318] Authentication success
I0313 18:58:41.198309 24501 master.cpp:7282] Successfully authenticated principal 'test-principal' at scheduler-79bf8987-1457-44fd-8b33-56da30f84486@172.17.0.2:44429
I0313 18:58:41.198377 24503 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(627)@172.17.0.2:44429
I0313 18:58:41.198407 24490 authenticatee.cpp:299] Authentication success
I0313 18:58:41.198689 24484 slave.cpp:5564] Finished recovery
I0313 18:58:41.198845 24498 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:44429
I0313 18:58:41.198864 24498 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:44429
I0313 18:58:41.198904 24498 sched.cpp:869] Will retry registration in 252.700025ms if necessary
I0313 18:58:41.198948 24503 master.cpp:2817] Received SUBSCRIBE call for framework 'default' at scheduler-79bf8987-1457-44fd-8b33-56da30f84486@172.17.0.2:44429
I0313 18:58:41.198985 24503 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0313 18:58:41.199007 24484 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0313 18:58:41.199118 24484 slave.cpp:936] New master detected at master@172.17.0.2:44429
I0313 18:58:41.199142 24499 master.cpp:2894] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0313 18:58:41.199156 24484 slave.cpp:971] Detecting new master
I0313 18:58:41.199146 24490 status_update_manager.cpp:177] Pausing sending status updates
I0313 18:58:41.199261 24484 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0313 18:58:41.199295 24486 hierarchical.cpp:271] Added framework f40445be-b28a-44f2-856d-bd1faee44b45-0000
I0313 18:58:41.199347 24486 hierarchical.cpp:1854] No allocations performed
I0313 18:58:41.199357 24486 hierarchical.cpp:1944] No inverse offers to send out!
I0313 18:58:41.199365 24486 hierarchical.cpp:1438] Performed allocation for 0 agents in 25724ns
I0313 18:58:41.199429 24493 sched.cpp:759] Framework registered with f40445be-b28a-44f2-856d-bd1faee44b45-0000
I0313 18:58:41.199456 24493 sched.cpp:773] Scheduler::registered took 10727ns
I0313 18:58:41.202328 24492 slave.cpp:998] Authenticating with master master@172.17.0.2:44429
I0313 18:58:41.202350 24492 slave.cpp:1009] Using default CRAM-MD5 authenticatee
I0313 18:58:41.202422 24489 authenticatee.cpp:121] Creating new client SASL connection
I0313 18:58:41.202601 24487 master.cpp:7252] Authenticating slave(284)@172.17.0.2:44429
I0313 18:58:41.202661 24489 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(628)@172.17.0.2:44429
I0313 18:58:41.202742 24486 authenticator.cpp:98] Creating new server SASL connection
I0313 18:58:41.202886 24503 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0313 18:58:41.202910 24503 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0313 18:58:41.203012 24504 authenticator.cpp:204] Received SASL authentication start
I0313 18:58:41.203052 24504 authenticator.cpp:326] Authentication requires more steps
I0313 18:58:41.203114 24483 authenticatee.cpp:259] Received SASL authentication step
I0313 18:58:41.203179 24497 authenticator.cpp:232] Received SASL authentication step
I0313 18:58:41.203200 24497 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '55582b48a078' server FQDN: '55582b48a078' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0313 18:58:41.203209 24497 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0313 18:58:41.203223 24497 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0313 18:58:41.203234 24497 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '55582b48a078' server FQDN: '55582b48a078' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0313 18:58:41.203243 24497 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0313 18:58:41.203248 24497 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0313 18:58:41.203258 24497 authenticator.cpp:318] Authentication success
I0313 18:58:41.203317 24492 authenticatee.cpp:299] Authentication success
I0313 18:58:41.203337 24494 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(284)@172.17.0.2:44429
I0313 18:58:41.203397 24483 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(628)@172.17.0.2:44429
I0313 18:58:41.203593 24501 slave.cpp:1093] Successfully authenticated with master master@172.17.0.2:44429
I0313 18:58:41.203656 24501 slave.cpp:1521] Will retry registration in 5.616416ms if necessary
I0313 18:58:41.203724 24499 master.cpp:5437] Registering agent at slave(284)@172.17.0.2:44429 (55582b48a078) with id f40445be-b28a-44f2-856d-bd1faee44b45-S0
I0313 18:58:41.203866 24489 registrar.cpp:463] Applied 1 operations in 14471ns; attempting to update the registry
I0313 18:58:41.204226 24483 registrar.cpp:508] Successfully updated the registry in 337152ns
I0313 18:58:41.204501 24491 slave.cpp:4347] Received ping from slave-observer(277)@172.17.0.2:44429
I0313 18:58:41.204504 24484 master.cpp:5511] Registered agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 at slave(284)@172.17.0.2:44429 (55582b48a078) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0313 18:58:41.204573 24491 slave.cpp:1139] Registered with master master@172.17.0.2:44429; given agent ID f40445be-b28a-44f2-856d-bd1faee44b45-S0
I0313 18:58:41.204589 24491 fetcher.cpp:94] Clearing fetcher cache
I0313 18:58:41.204591 24497 hierarchical.cpp:527] Added agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 (55582b48a078) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0313 18:58:41.204689 24484 status_update_manager.cpp:184] Resuming sending status updates
I0313 18:58:41.204854 24497 hierarchical.cpp:1944] No inverse offers to send out!
I0313 18:58:41.204859 24491 slave.cpp:1167] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_s3bh52/meta/slaves/f40445be-b28a-44f2-856d-bd1faee44b45-S0/slave.info'
I0313 18:58:41.204879 24497 hierarchical.cpp:1438] Performed allocation for 1 agents in 232876ns
I0313 18:58:41.204998 24489 master.cpp:7081] Sending 1 offers to framework f40445be-b28a-44f2-856d-bd1faee44b45-0000 (default) at scheduler-79bf8987-1457-44fd-8b33-56da30f84486@172.17.0.2:44429
I0313 18:58:41.205107 24491 slave.cpp:1205] Forwarding total oversubscribed resources {}
I0313 18:58:41.205169 24492 sched.cpp:933] Scheduler::resourceOffers took 46513ns
I0313 18:58:41.205181 24483 master.cpp:6098] Received update of agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 at slave(284)@172.17.0.2:44429 (55582b48a078) with total oversubscribed resources {}
I0313 18:58:41.205899 24503 master.cpp:3839] Processing ACCEPT call for offers: [ f40445be-b28a-44f2-856d-bd1faee44b45-O0 ] on agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 at slave(284)@172.17.0.2:44429 (55582b48a078) for framework f40445be-b28a-44f2-856d-bd1faee44b45-0000 (default) at scheduler-79bf8987-1457-44fd-8b33-56da30f84486@172.17.0.2:44429
I0313 18:58:41.205938 24503 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 1
I0313 18:58:41.206015 24503 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 2
W0313 18:58:41.206775 24486 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.
W0313 18:58:41.206821 24486 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.
I0313 18:58:41.206913 24486 master.cpp:9097] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 at slave(284)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.206961 24486 master.cpp:4489] Launching task 1 of framework f40445be-b28a-44f2-856d-bd1faee44b45-0000 (default) at scheduler-79bf8987-1457-44fd-8b33-56da30f84486@172.17.0.2:44429 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 at slave(284)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.207126 24493 slave.cpp:1631] Got assigned task '1' for framework f40445be-b28a-44f2-856d-bd1faee44b45-0000
W0313 18:58:41.207298 24486 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.
W0313 18:58:41.207319 24486 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.
I0313 18:58:41.207366 24493 slave.cpp:1791] Launching task '1' for framework f40445be-b28a-44f2-856d-bd1faee44b45-0000
I0313 18:58:41.207392 24486 master.cpp:9097] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 at slave(284)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.207456 24486 master.cpp:4489] Launching task 2 of framework f40445be-b28a-44f2-856d-bd1faee44b45-0000 (default) at scheduler-79bf8987-1457-44fd-8b33-56da30f84486@172.17.0.2:44429 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 at slave(284)@172.17.0.2:44429 (55582b48a078)
I0313 18:58:41.207811 24493 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_s3bh52/slaves/f40445be-b28a-44f2-856d-bd1faee44b45-S0/frameworks/f40445be-b28a-44f2-856d-bd1faee44b45-0000/executors/executor-1/runs/cd6638c8-4871-4aa2-8cfe-0002f8183e92' to user 'mesos'
I0313 18:58:41.207978 24493 slave.cpp:6491] Launching executor 'executor-1' of framework f40445be-b28a-44f2-856d-bd1faee44b45-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_s3bh52/slaves/f40445be-b28a-44f2-856d-bd1faee44b45-S0/frameworks/f40445be-b28a-44f2-856d-bd1faee44b45-0000/executors/executor-1/runs/cd6638c8-4871-4aa2-8cfe-0002f8183e92'
I0313 18:58:41.208043 24491 hierarchical.cpp:844] Updated allocation of framework f40445be-b28a-44f2-856d-bd1faee44b45-0000 on agent f40445be-b28a-44f2-856d-bd1faee44b45-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]
I0313 18:58:41.208176 24493 slave.cpp:2124] Queued task '1' for executor 'executor-1' of framework f40445be-b28a-44f2-856d-bd1faee44b45-0000
I0313 18:58:41.208189 24491 hierarchical.cpp:1108] 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 f40445be-b28a-44f2-856d-bd1faee44b45-S0 from framework f40445be-b28a-44f2-856d-bd1faee44b45-0000
I0313 18:58:41.208215 24491 hierarchical.cpp:1145] Framework f40445be-b28a-44f2-856d-bd1faee44b45-0000 filtered agent f40445be-b28a-44f2-856d-bd1faee44b45-S0 for 5secs
I0313 18:58:41.208256 24493 slave.cpp:1631] Got assigned task '2' for framework f40445be-b28a-44f2-856d-bd1faee44b45-0000
I0313 18:58:41.208438 24493 slave.cpp:889] Successfully attached file '/tmp/MasterTest_MultipleExecutors_s3bh52/slaves/f40445be-b28a-44f2-856d-bd1faee44b45-S0/frameworks/f40445be-b28a-44f2-856d-bd1faee44b45-0000/executors/executor-1/runs/cd6638c8-4871-4aa2-8cfe-0002f8183e92'
I0313 18:58:41.208467 24493 slave.cpp:1791] Launching task '2' for framework f40445be-b28a-44f2-856d-bd1faee44b45-0000
I0313 18:58:41.208752 24493 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_s3bh52/slaves/f40445be-b28a-44f2-856d-bd1faee44b45-S0/frameworks/f40445be-b28a-44f2-856d-bd1faee44b45-0000/executors/executor-2/runs/7c0449c5-dbd2-4d12-b094-c6600173f21a' to user 'mesos'
I0313 18:58:41.208909 24493 slave.cpp:6491] Launching executor 'executor-2' of framework f40445be-b28a-44f2-856d-bd1faee44b45-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_s3bh52/slaves/f40445be-b28a-44f2-856d-bd1faee44b45-S0/frameworks/f40445be-b28a-44f2-856d-bd1faee44b45-0000/executors/executor-2/runs/7c0449c5-dbd2-4d12-b094-c6600173f21a'
*** Aborted at 1489431521 (unix time) try "date -d @1489431521" if you are using GNU date ***
I0313 18:58:41.209189 24482 exec.cpp:162] Version: 1.3.0
I0313 18:58:41.209281 24497 exec.cpp:212] Executor started at: executor(121)@172.17.0.2:44429 with pid 24480
PC: @ 0x7f6b509eb0cd __GI_getenv
*** SIGSEGV (@0xf0) received by PID 24480 (TID 0x7f6b44c7f700) from PID 240; stack trace: ***
@ 0x7f6b515a4370 (unknown)
@ 0x7f6b509eb0cd __GI_getenv
@ 0x7f6b55146b54 mesos::internal::slave::executorEnvironment()
@ 0x7f6b55171d9e mesos::internal::slave::Framework::launchExecutor()
@ 0x7f6b55174c33 mesos::internal::slave::Slave::_run()
@ 0x7f6b54429881 process::ProcessManager::resume()
@ 0x7f6b54432917 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7f6b51341230 (unknown)
@ 0x7f6b5159cdc5 start_thread
@ 0x7f6b50aaa73d __clone
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-1489429357-24474
Untagged: mesos-1489429357-24474:latest
Deleted: sha256:9c589570ac57c8c159332649a1830f19fae509764e8a61ec435e3d85cf6144b5
Deleted: sha256:1010e7511e5ea6f237fd780c957d44893527ffc659a4eaa801bc6b585b869ff9
Deleted: sha256:497bd732d518431cfa14c1d5b5babf49782eb9a6471a61325ade850e141c4a67
Deleted: sha256:044726c9afeac4be3b5afc114b2568b0655311b88bdea30671b79dd2c2aaa744
Deleted: sha256:59fb6f7e5e40fbfb293214046410377698b2d84cd05dbeb5624f73bbd75d62f6
Deleted: sha256:73dd4118c4d2260da0689e1d8ccb2b5ea2bce3dec47891505114b0c47d6b290e
Deleted: sha256:731ab64995297bcc151c22786ff627211d849102508994f72ce08eb64dc2c448
Deleted: sha256:d0dea3fd86985fd9cc8520fe94313ab95076ebc7dffab670ec0bf1f9f7615645
Deleted: sha256:2189417425ac58c37c4d5654e13d329f831499b6440222748fc853ffd18f3dfd
Deleted: sha256:921c6cd7cafec6219c949eec1e4156f5bc05669f7344c962b5db3b4839147eba
Deleted: sha256:757a9a4f0bfc95fd730ff894a65b45705bd888df999ca18d867d39ae019ba8fc
Deleted: sha256:83dbbeba1cf00951993a7ef12a2999143caf39b7a2fe2279232f24413240c69c
Deleted: sha256:12a5857c21c8ebbc3d4d183be4927f04541ac602075b1d86e77f60380b29e26e
Deleted: sha256:d5f57d1a85efef9bd381ddec60639bbab7daa54eaeb69ac14866d51b388786e0
Deleted: sha256:129d6f68adea2a76b9ce5473150b423bfcc337ac41f8bba5fd4ff2b7a0e0c76f
Deleted: sha256:53ff48972dd8e69e84b4f6bc568c1c8be0f4eee419862f1014eeb595425125e7
Deleted: sha256:78492acb54a63bfdefad2bf4570ff0a629b3028e0578b367d04fac27004e904f
Deleted: sha256:6b50a958019d67b4041d48d68b7144b3ccd8634da584eecff69215f5df5fd428
Deleted: sha256:ffe0dd225b228779adbad8d337cb4f7d12ced6c0f4b65972bc893adba9bcba99
Deleted: sha256:e5a8d55a18a0a09f98cf519026172652895dd34618198f6e16077e2e20985c8c
Deleted: sha256:64f94a654c19b369a8b2f03f5afb5b021fdd68ce10ee7eb1822faad57dee63c6
Deleted: sha256:309d5cd1ec0f4e396e8dc246b5e2c08161ed1d0f94fc75c8662c7195005b0706
Deleted: sha256:c138a4a5232eeb5a8ab2802f85e7cfedb95f39d4fb9c561644f371ae66fe0f21
Deleted: sha256:107533bff4230381bf26491331e9900e2a6b848c712c5001326accdcf90f7843
Deleted: sha256:449f182c6031fe0ebc6ff44674395802e3096b5bac514631a7991906c03d9276
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3365
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3365/display/redirect?page=changes>