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/06/06 19:20:47 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3769
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=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3769/display/redirect?page=changes>
Changes:
[neil.conway] Updated comment for addition of SharedInfo to Resource.
------------------------------------------
[...truncated 13.20 MB...]
I0606 19:20:41.826036 31380 master.cpp:6545] Forwarding status update TASK_RUNNING (UUID: bcc67adc-22ce-4c2d-a08f-4e6ba7ec6ea6) for task 1 of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.826109 31380 master.cpp:8556] Updating the state of task 1 of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0606 19:20:41.826148 31377 exec.cpp:388] Executor received status update acknowledgement bcc67adc-22ce-4c2d-a08f-4e6ba7ec6ea6 for task 1 of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.826231 31387 sched.cpp:1041] Scheduler::statusUpdate took 40542ns
I0606 19:20:41.826356 31379 master.cpp:5232] Processing ACKNOWLEDGE call bcc67adc-22ce-4c2d-a08f-4e6ba7ec6ea6 for task 1 of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 (default) at scheduler-c16842b6-7aed-4920-afda-574d5a10069a@172.17.0.2:32784 on agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0
I0606 19:20:41.826560 31377 status_update_manager.cpp:395] Received status update acknowledgement (UUID: bcc67adc-22ce-4c2d-a08f-4e6ba7ec6ea6) for task 1 of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.826629 31369 exec.cpp:416] Executor received framework message
I0606 19:20:41.826683 31371 slave.cpp:3545] Status update manager successfully handled status update acknowledgement (UUID: bcc67adc-22ce-4c2d-a08f-4e6ba7ec6ea6) for task 1 of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.826699 31369 exec.cpp:425] Executor::frameworkMessage took 47638ns
I0606 19:20:41.827102 31365 slave.cpp:4803] Sending message for framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 to scheduler-c16842b6-7aed-4920-afda-574d5a10069a@172.17.0.2:32784
I0606 19:20:41.827201 31380 sched.cpp:1177] Scheduler::frameworkMessage took 30390ns
I0606 19:20:41.827380 31364 sched.cpp:2021] Asked to stop the driver
I0606 19:20:41.827452 31365 sched.cpp:1203] Stopping framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.827585 31369 master.cpp:7976] Processing TEARDOWN call for framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 (default) at scheduler-c16842b6-7aed-4920-afda-574d5a10069a@172.17.0.2:32784
I0606 19:20:41.827607 31369 master.cpp:7988] Removing framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 (default) at scheduler-c16842b6-7aed-4920-afda-574d5a10069a@172.17.0.2:32784
I0606 19:20:41.827615 31369 master.cpp:3200] Deactivating framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 (default) at scheduler-c16842b6-7aed-4920-afda-574d5a10069a@172.17.0.2:32784
I0606 19:20:41.827688 31369 master.cpp:8556] Updating the state of task 1 of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0606 19:20:41.827695 31374 hierarchical.cpp:411] Deactivated framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.827769 31375 slave.cpp:3098] Asked to shut down framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 by master@172.17.0.2:32784
I0606 19:20:41.827792 31375 slave.cpp:3123] Shutting down framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.827801 31375 slave.cpp:5590] Shutting down executor 'default' of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 at executor(123)@172.17.0.2:32784
I0606 19:20:41.827862 31370 exec.cpp:435] Executor asked to shutdown
I0606 19:20:41.827863 31369 master.cpp:8650] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 on agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0 at slave(314)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.827894 31370 exec.cpp:450] Executor::shutdown took 18076ns
I0606 19:20:41.827963 31369 master.cpp:8679] Removing executor 'default' with resources {} of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 on agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0 at slave(314)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.827972 31382 hierarchical.cpp:1178] 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 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0 from framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.828109 31371 slave.cpp:4867] Got exited event for executor(123)@172.17.0.2:32784
I0606 19:20:41.828591 31371 hierarchical.cpp:354] Removed framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.828649 31381 slave.cpp:5268] Executor 'default' of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 exited with status 0
I0606 19:20:41.828723 31381 slave.cpp:5368] Cleaning up executor 'default' of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 at executor(123)@172.17.0.2:32784
W0606 19:20:41.828755 31365 master.cpp:6600] Ignoring unknown exited executor 'default' of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000 on agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0 at slave(314)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.828946 31380 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_MQGfXT/slaves/5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0/frameworks/5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000/executors/default/runs/04e70b94-7e4b-453b-b985-9fe726fcc0cb' for gc 6.99999040659556days in the future
I0606 19:20:41.828969 31381 slave.cpp:5464] Cleaning up framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.828997 31380 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_MQGfXT/slaves/5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0/frameworks/5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000/executors/default' for gc 6.99999040563556days in the future
I0606 19:20:41.829150 31382 status_update_manager.cpp:285] Closing status update streams for framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.829172 31382 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000
I0606 19:20:41.829248 31379 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_MQGfXT/slaves/5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0/frameworks/5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-0000' for gc 6.99999040412148days in the future
I0606 19:20:41.829252 31381 slave.cpp:817] Agent terminating
I0606 19:20:41.829463 31373 master.cpp:1314] Agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0 at slave(314)@172.17.0.2:32784 (806c23aaf2c8) disconnected
I0606 19:20:41.829488 31373 master.cpp:3237] Disconnecting agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0 at slave(314)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.829649 31373 master.cpp:3256] Deactivating agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0 at slave(314)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.829730 31374 hierarchical.cpp:716] Agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0 deactivated
I0606 19:20:41.830951 31364 master.cpp:1156] Master terminating
I0606 19:20:41.831151 31381 hierarchical.cpp:621] Removed agent 5a651f86-7af5-4352-a1bc-be0ca7fa1d0b-S0
[ OK ] MasterTest.FrameworkMessage (34 ms)
[ RUN ] MasterTest.MultipleExecutors
I0606 19:20:41.834517 31364 cluster.cpp:162] Creating default 'local' authorizer
I0606 19:20:41.835731 31387 master.cpp:436] Master 391d7d1c-92c1-45b0-8bca-bb631f8e852e (806c23aaf2c8) started on 172.17.0.2:32784
I0606 19:20:41.835747 31387 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/0u8l4A/credentials" --filter_gpu_resources="true" --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/0u8l4A/master" --zk_session_timeout="10secs"
I0606 19:20:41.835923 31387 master.cpp:488] Master only allowing authenticated frameworks to register
I0606 19:20:41.835937 31387 master.cpp:502] Master only allowing authenticated agents to register
I0606 19:20:41.835947 31387 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0606 19:20:41.835958 31387 credentials.hpp:37] Loading credentials for authentication from '/tmp/0u8l4A/credentials'
I0606 19:20:41.836099 31387 master.cpp:560] Using default 'crammd5' authenticator
I0606 19:20:41.836166 31387 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0606 19:20:41.836230 31387 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0606 19:20:41.836297 31387 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0606 19:20:41.836403 31387 master.cpp:640] Authorization enabled
I0606 19:20:41.836565 31373 whitelist_watcher.cpp:77] No whitelist given
I0606 19:20:41.836567 31378 hierarchical.cpp:169] Initialized hierarchical allocator process
I0606 19:20:41.837402 31387 master.cpp:2159] Elected as the leading master!
I0606 19:20:41.837420 31387 master.cpp:1698] Recovering from registrar
I0606 19:20:41.837487 31365 registrar.cpp:345] Recovering registrar
I0606 19:20:41.837746 31379 registrar.cpp:389] Successfully fetched the registry (0B) in 224768ns
I0606 19:20:41.837775 31379 registrar.cpp:493] Applied 1 operations in 4478ns; attempting to update the registry
I0606 19:20:41.838049 31379 registrar.cpp:550] Successfully updated the registry in 257024ns
I0606 19:20:41.838094 31379 registrar.cpp:422] Successfully recovered registrar
I0606 19:20:41.838358 31388 master.cpp:1797] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0606 19:20:41.838376 31374 hierarchical.cpp:208] Skipping recovery of hierarchical allocator: nothing to recover
I0606 19:20:41.840461 31364 cluster.cpp:448] Creating default 'local' authorizer
I0606 19:20:41.841027 31383 slave.cpp:248] Mesos agent started on (315)@172.17.0.2:32784
I0606 19:20:41.841045 31383 slave.cpp:249] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_MultipleExecutors_A3sJJr/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_A3sJJr/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/MasterTest_MultipleExecutors_A3sJJr/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_A3sJJr/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_A3sJJr/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_A3sJJr" --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_WHj2o1"
I0606 19:20:41.841213 31383 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_A3sJJr/credential'
I0606 19:20:41.841290 31383 slave.cpp:281] Agent using credential for: test-principal
I0606 19:20:41.841305 31383 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_A3sJJr/http_credentials'
I0606 19:20:41.841406 31383 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0606 19:20:41.841449 31383 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0606 19:20:41.841869 31364 sched.cpp:232] Version: 1.4.0
I0606 19:20:41.842154 31383 slave.cpp:552] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0606 19:20:41.842191 31383 slave.cpp:560] Agent attributes: [ ]
I0606 19:20:41.842197 31383 slave.cpp:565] Agent hostname: 806c23aaf2c8
I0606 19:20:41.842203 31371 sched.cpp:336] New master detected at master@172.17.0.2:32784
I0606 19:20:41.842245 31371 sched.cpp:407] Authenticating with master master@172.17.0.2:32784
I0606 19:20:41.842258 31371 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0606 19:20:41.842308 31384 status_update_manager.cpp:177] Pausing sending status updates
I0606 19:20:41.842519 31385 authenticatee.cpp:121] Creating new client SASL connection
I0606 19:20:41.842634 31370 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_WHj2o1/meta'
I0606 19:20:41.842701 31369 master.cpp:7465] Authenticating scheduler-d55052fe-7522-4c84-80a7-4541d3885a70@172.17.0.2:32784
I0606 19:20:41.842772 31385 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(664)@172.17.0.2:32784
I0606 19:20:41.842859 31388 authenticator.cpp:98] Creating new server SASL connection
I0606 19:20:41.842901 31377 status_update_manager.cpp:203] Recovering status update manager
I0606 19:20:41.843014 31374 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0606 19:20:41.843039 31374 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0606 19:20:41.843190 31377 authenticator.cpp:204] Received SASL authentication start
I0606 19:20:41.843207 31381 slave.cpp:6127] Finished recovery
I0606 19:20:41.843240 31377 authenticator.cpp:326] Authentication requires more steps
I0606 19:20:41.843308 31378 authenticatee.cpp:259] Received SASL authentication step
I0606 19:20:41.843385 31373 authenticator.cpp:232] Received SASL authentication step
I0606 19:20:41.843410 31373 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '806c23aaf2c8' server FQDN: '806c23aaf2c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0606 19:20:41.843420 31373 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0606 19:20:41.843432 31373 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0606 19:20:41.843442 31373 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '806c23aaf2c8' server FQDN: '806c23aaf2c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0606 19:20:41.843451 31373 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0606 19:20:41.843456 31373 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0606 19:20:41.843469 31373 authenticator.cpp:318] Authentication success
I0606 19:20:41.843518 31377 authenticatee.cpp:299] Authentication success
I0606 19:20:41.843552 31387 master.cpp:7495] Successfully authenticated principal 'test-principal' at scheduler-d55052fe-7522-4c84-80a7-4541d3885a70@172.17.0.2:32784
I0606 19:20:41.843560 31378 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(664)@172.17.0.2:32784
I0606 19:20:41.843566 31381 slave.cpp:6309] Querying resource estimator for oversubscribable resources
I0606 19:20:41.843750 31379 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:32784
I0606 19:20:41.843762 31379 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:32784
I0606 19:20:41.843852 31381 slave.cpp:945] New master detected at master@172.17.0.2:32784
I0606 19:20:41.843858 31379 sched.cpp:869] Will retry registration in 1.136459461secs if necessary
I0606 19:20:41.843857 31387 status_update_manager.cpp:177] Pausing sending status updates
I0606 19:20:41.843891 31381 slave.cpp:980] Detecting new master
I0606 19:20:41.843899 31370 master.cpp:2850] Received SUBSCRIBE call for framework 'default' at scheduler-d55052fe-7522-4c84-80a7-4541d3885a70@172.17.0.2:32784
I0606 19:20:41.843925 31370 master.cpp:2195] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0606 19:20:41.844028 31381 slave.cpp:6323] Received oversubscribable resources {} from the resource estimator
I0606 19:20:41.844107 31371 master.cpp:2930] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0606 19:20:41.844250 31369 sched.cpp:759] Framework registered with 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.844259 31388 hierarchical.cpp:302] Added framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.844281 31369 sched.cpp:773] Scheduler::registered took 12131ns
I0606 19:20:41.844303 31388 hierarchical.cpp:1912] No allocations performed
I0606 19:20:41.844318 31388 hierarchical.cpp:2002] No inverse offers to send out!
I0606 19:20:41.844326 31388 hierarchical.cpp:1494] Performed allocation for 0 agents in 25795ns
I0606 19:20:41.848083 31371 slave.cpp:1007] Authenticating with master master@172.17.0.2:32784
I0606 19:20:41.848115 31371 slave.cpp:1018] Using default CRAM-MD5 authenticatee
I0606 19:20:41.848181 31383 authenticatee.cpp:121] Creating new client SASL connection
I0606 19:20:41.848326 31388 master.cpp:7465] Authenticating slave(315)@172.17.0.2:32784
I0606 19:20:41.848451 31369 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(665)@172.17.0.2:32784
I0606 19:20:41.848547 31366 authenticator.cpp:98] Creating new server SASL connection
I0606 19:20:41.848774 31381 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0606 19:20:41.848791 31381 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0606 19:20:41.848826 31381 authenticator.cpp:204] Received SASL authentication start
I0606 19:20:41.848860 31381 authenticator.cpp:326] Authentication requires more steps
I0606 19:20:41.848894 31381 authenticatee.cpp:259] Received SASL authentication step
I0606 19:20:41.848935 31381 authenticator.cpp:232] Received SASL authentication step
I0606 19:20:41.848958 31381 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '806c23aaf2c8' server FQDN: '806c23aaf2c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0606 19:20:41.848968 31381 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0606 19:20:41.848978 31381 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0606 19:20:41.848994 31381 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '806c23aaf2c8' server FQDN: '806c23aaf2c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0606 19:20:41.849007 31381 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0606 19:20:41.849020 31381 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0606 19:20:41.849037 31381 authenticator.cpp:318] Authentication success
I0606 19:20:41.849098 31379 authenticatee.cpp:299] Authentication success
I0606 19:20:41.849114 31378 master.cpp:7495] Successfully authenticated principal 'test-principal' at slave(315)@172.17.0.2:32784
I0606 19:20:41.849138 31388 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(665)@172.17.0.2:32784
I0606 19:20:41.849293 31373 slave.cpp:1102] Successfully authenticated with master master@172.17.0.2:32784
I0606 19:20:41.849360 31373 slave.cpp:1522] Will retry registration in 1.341194ms if necessary
I0606 19:20:41.849412 31384 master.cpp:5467] Received register agent message from slave(315)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.849467 31384 master.cpp:3699] Authorizing agent with principal 'test-principal'
I0606 19:20:41.849616 31369 master.cpp:5527] Authorized registration of agent at slave(315)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.849668 31369 master.cpp:5606] Registering agent at slave(315)@172.17.0.2:32784 (806c23aaf2c8) with id 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0
I0606 19:20:41.849786 31366 registrar.cpp:493] Applied 1 operations in 13739ns; attempting to update the registry
I0606 19:20:41.850106 31369 registrar.cpp:550] Successfully updated the registry in 283904ns
I0606 19:20:41.850201 31387 master.cpp:5653] Admitted agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 at slave(315)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.850373 31373 slave.cpp:4825] Received ping from slave-observer(305)@172.17.0.2:32784
I0606 19:20:41.850373 31387 master.cpp:5684] Registered agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 at slave(315)@172.17.0.2:32784 (806c23aaf2c8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0606 19:20:41.850450 31373 slave.cpp:1148] Registered with master master@172.17.0.2:32784; given agent ID 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0
I0606 19:20:41.850517 31380 status_update_manager.cpp:184] Resuming sending status updates
I0606 19:20:41.850512 31372 hierarchical.cpp:588] Added agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 (806c23aaf2c8) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0606 19:20:41.850692 31373 slave.cpp:1168] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_WHj2o1/meta/slaves/391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0/slave.info'
I0606 19:20:41.850757 31372 hierarchical.cpp:2002] No inverse offers to send out!
I0606 19:20:41.850775 31372 hierarchical.cpp:1494] Performed allocation for 1 agents in 202145ns
I0606 19:20:41.850888 31369 master.cpp:7295] Sending 1 offers to framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000 (default) at scheduler-d55052fe-7522-4c84-80a7-4541d3885a70@172.17.0.2:32784
I0606 19:20:41.850908 31373 slave.cpp:1206] Forwarding total oversubscribed resources {}
I0606 19:20:41.851008 31369 master.cpp:6338] Received update of agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 at slave(315)@172.17.0.2:32784 (806c23aaf2c8) with total oversubscribed resources {}
I0606 19:20:41.851106 31373 sched.cpp:933] Scheduler::resourceOffers took 52697ns
I0606 19:20:41.851914 31388 master.cpp:3914] Processing ACCEPT call for offers: [ 391d7d1c-92c1-45b0-8bca-bb631f8e852e-O0 ] on agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 at slave(315)@172.17.0.2:32784 (806c23aaf2c8) for framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000 (default) at scheduler-d55052fe-7522-4c84-80a7-4541d3885a70@172.17.0.2:32784
I0606 19:20:41.851960 31388 master.cpp:3466] Authorizing framework principal 'test-principal' to launch task 1
I0606 19:20:41.852046 31388 master.cpp:3466] Authorizing framework principal 'test-principal' to launch task 2
W0606 19:20:41.852569 31382 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.
W0606 19:20:41.852594 31382 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.
I0606 19:20:41.852660 31382 master.cpp:9314] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 at slave(315)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.852730 31382 master.cpp:4569] Launching task 1 of framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000 (default) at scheduler-d55052fe-7522-4c84-80a7-4541d3885a70@172.17.0.2:32784 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 at slave(315)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.852885 31380 slave.cpp:1632] Got assigned task '1' for framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
W0606 19:20:41.852982 31382 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.
W0606 19:20:41.853001 31382 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.
I0606 19:20:41.853070 31382 master.cpp:9314] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 at slave(315)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.853096 31380 slave.cpp:1913] Authorizing task '1' for framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.853118 31380 slave.cpp:6626] Authorizing framework principal 'test-principal' to launch task 1
I0606 19:20:41.853116 31382 master.cpp:4569] Launching task 2 of framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000 (default) at scheduler-d55052fe-7522-4c84-80a7-4541d3885a70@172.17.0.2:32784 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 at slave(315)@172.17.0.2:32784 (806c23aaf2c8)
I0606 19:20:41.853440 31380 slave.cpp:1632] Got assigned task '2' for framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.853667 31380 slave.cpp:2100] Launching task '1' for framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.853876 31375 hierarchical.cpp:913] Updated allocation of framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000 on agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-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]
I0606 19:20:41.854107 31375 hierarchical.cpp:1178] 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 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 from framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.854153 31375 hierarchical.cpp:1215] Framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000 filtered agent 391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0 for 5secs
I0606 19:20:41.854255 31380 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_WHj2o1/slaves/391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0/frameworks/391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000/executors/executor-1/runs/5f43abfe-d1a4-49c6-9a09-f0daec733ae7' to user 'mesos'
I0606 19:20:41.854395 31380 slave.cpp:7086] Launching executor 'executor-1' of framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_WHj2o1/slaves/391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0/frameworks/391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000/executors/executor-1/runs/5f43abfe-d1a4-49c6-9a09-f0daec733ae7'
I0606 19:20:41.854521 31380 slave.cpp:2795] Launching container 5f43abfe-d1a4-49c6-9a09-f0daec733ae7 for executor 'executor-1' of framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.854646 31380 slave.cpp:2329] Queued task '1' for executor 'executor-1' of framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.854696 31380 slave.cpp:1913] Authorizing task '2' for framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.854715 31380 slave.cpp:6626] Authorizing framework principal 'test-principal' to launch task 2
I0606 19:20:41.854856 31380 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_WHj2o1/slaves/391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0/frameworks/391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000/executors/executor-1/runs/5f43abfe-d1a4-49c6-9a09-f0daec733ae7'
I0606 19:20:41.854902 31380 slave.cpp:898] Successfully attached file '/tmp/MasterTest_MultipleExecutors_WHj2o1/slaves/391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0/frameworks/391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000/executors/executor-1/runs/5f43abfe-d1a4-49c6-9a09-f0daec733ae7'
I0606 19:20:41.855049 31369 slave.cpp:2100] Launching task '2' for framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000
I0606 19:20:41.855381 31369 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_WHj2o1/slaves/391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0/frameworks/391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000/executors/executor-2/runs/deae3b84-6701-41a0-af80-cd0ffcfd09b2' to user 'mesos'
I0606 19:20:41.855532 31369 slave.cpp:7086] Launching executor 'executor-2' of framework 391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_WHj2o1/slaves/391d7d1c-92c1-45b0-8bca-bb631f8e852e-S0/frameworks/391d7d1c-92c1-45b0-8bca-bb631f8e852e-0000/executors/executor-2/runs/deae3b84-6701-41a0-af80-cd0ffcfd09b2'
*** Aborted at 1496776841 (unix time) try "date -d @1496776841" if you are using GNU date ***
I0606 19:20:41.855849 31386 exec.cpp:162] Version: 1.4.0
I0606 19:20:41.855980 31384 exec.cpp:212] Executor started at: executor(124)@172.17.0.2:32784 with pid 31364
PC: @ 0x7f3bdcd830cd __GI_getenv
*** SIGSEGV (@0xf0) received by PID 31364 (TID 0x7f3bd500e700) from PID 240; stack trace: ***
@ 0x7f3bdd93c370 (unknown)
@ 0x7f3bdcd830cd __GI_getenv
@ 0x7f3be17f8d54 mesos::internal::slave::executorEnvironment()
@ 0x7f3be181967a mesos::internal::slave::Slave::launchExecutor()
@ 0x7f3be181e81d mesos::internal::slave::Slave::__run()
@ 0x7f3be0a56e41 process::ProcessManager::resume()
@ 0x7f3be0a60787 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7f3bdd6d9230 (unknown)
@ 0x7f3bdd934dc5 start_thread
@ 0x7f3bdce4276d __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-1496774002-9675
Untagged: mesos-1496774002-9675:latest
Deleted: sha256:6688003b01808c42e1267680e19bed4ea1bbf4ad958fe45eb159cdd8d09fb165
Deleted: sha256:3b43e7743e09206b64bbc4d1eadf1f4c1d914e511d230c61938a6524b104849a
Deleted: sha256:cbe9c1281d0fcebdb709325227475976cf2c4649b2ca5b39dd6f60be1db9b31a
Deleted: sha256:c5897053d2e2e63d03e6e55d868e6ec27573cd57485d183c49118928d2d8b77e
Deleted: sha256:e0ed88ece31406678e88f5c96ec932d8489a49dcf7323f4b8e49aecb5641975f
Deleted: sha256:b0c1ce8aea64f4423f1eb890489a8f78cd261f998c57526981b385fc7d1f5a23
Deleted: sha256:2fcdb649ee54c6f0062f2637ebdca3d1a2dcb69a817a8d2c9ea345355f9e2121
Deleted: sha256:6b82f8cba24c32d13737eb67c06c1a036d92a9567c825401359ffe51bf575529
Deleted: sha256:eb61e8eadce4ee97ee8cf049436e4785f5d3e4e37de6b80d83651472133c8b97
Deleted: sha256:fdc8a8ff6b320b303b768594238be849ad8ed482d0b13a7de524719fb9845067
Deleted: sha256:47b19d7702e910885f2efdff892056059038257f7f255a319fbb71eaf3550933
Deleted: sha256:6623a052e407c4422fe3d90b1ad582af77896ca5c6c861c96994aa2fc7fc2809
Deleted: sha256:deeb7807db14e35cb3715afc166a7b33e1fb4716eee4357c2e85d2561615dd7a
Deleted: sha256:340963e15e73b05fadbcb06fb0e876b39b1be9e5aac4065e38c4e342115240b7
Deleted: sha256:43d1a27891e4db0202b9c1f17ded73390e85d540145416ede1997e52999dd72c
Deleted: sha256:e2af9cce61ef7bb5f4902c4dc844f624a5a667fd380420ff5f6089d4c9a044c3
Deleted: sha256:2998d9336b25b1c165053763873e279bd46af29016ef4e35ac54c8ae1e2934aa
Deleted: sha256:6c254b813b1f020e6450fd43fc9469efa09f65403298345186ed5488140cb983
Deleted: sha256:738fe3ea043cad0de9c50f2c9c05d1004821b62f263e23945eefce4cdc5df086
Deleted: sha256:29daaa467673dd1b2ff56c1a45630aa4afde3e0ee5148b99e75ccbafdf5d004c
Deleted: sha256:4ebb533bee77236de48697e64834fca2664075d1c3f4aa0cfb693fc1d917246f
Deleted: sha256:3dfd18dec009de19b4e1fbbb1de83bf113b26b517e40d82ff633291f4d9f1730
Deleted: sha256:b22eb9a998ad02ff7e18726d8022b0bc9e866d86593a717f344e587d51ba4202
Deleted: sha256:584ff69ba0040b217ac45d1dbac2b689df37ae69dac7057d5e03d4ccf972f258
Deleted: sha256:dae3dd986f24671cef0006c52e9b000d32af7186d6883e69339aa9cab1c3d6b3
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3770
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=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3770/display/redirect?page=changes>