You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/05/09 21:59:45 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3640
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)/3640/display/redirect?page=changes>
Changes:
[bmahler] Fixed the MULTI_ROLE upgrade path for an old master and new agent.
------------------------------------------
[...truncated 12.69 MB...]
I0509 21:59:28.287470 24446 slave.cpp:4655] Forwarding the update TASK_RUNNING (UUID: fe139c44-5446-46de-91ac-8a554185f0ae) for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000 to master@172.17.0.3:42681
I0509 21:59:28.287571 24446 slave.cpp:4549] Status update manager successfully handled status update TASK_RUNNING (UUID: fe139c44-5446-46de-91ac-8a554185f0ae) for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.287597 24446 slave.cpp:4565] Sending acknowledgement for status update TASK_RUNNING (UUID: fe139c44-5446-46de-91ac-8a554185f0ae) for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000 to executor(124)@172.17.0.3:42681
I0509 21:59:28.287598 24452 master.cpp:6469] Status update TASK_RUNNING (UUID: fe139c44-5446-46de-91ac-8a554185f0ae) for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000 from agent e1680964-3268-4f13-938f-33a9843f3200-S0 at slave(310)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.287636 24452 master.cpp:6537] Forwarding status update TASK_RUNNING (UUID: fe139c44-5446-46de-91ac-8a554185f0ae) for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.287672 24440 exec.cpp:374] Executor received status update acknowledgement fe139c44-5446-46de-91ac-8a554185f0ae for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.287699 24452 master.cpp:8568] Updating the state of task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0509 21:59:28.287822 24442 sched.cpp:1041] Scheduler::statusUpdate took 46799ns
I0509 21:59:28.287963 24445 master.cpp:5194] Processing ACKNOWLEDGE call fe139c44-5446-46de-91ac-8a554185f0ae for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000 (default) at scheduler-255ed017-e5ea-409f-b5ea-28ef50c6bc35@172.17.0.3:42681 on agent e1680964-3268-4f13-938f-33a9843f3200-S0
I0509 21:59:28.288069 24445 status_update_manager.cpp:395] Received status update acknowledgement (UUID: fe139c44-5446-46de-91ac-8a554185f0ae) for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.288223 24443 slave.cpp:3504] Status update manager successfully handled status update acknowledgement (UUID: fe139c44-5446-46de-91ac-8a554185f0ae) for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.288225 24439 exec.cpp:396] Executor received framework message
I0509 21:59:28.288295 24439 exec.cpp:405] Executor::frameworkMessage took 25436ns
I0509 21:59:28.288532 24440 slave.cpp:4723] Sending message for framework e1680964-3268-4f13-938f-33a9843f3200-0000 to scheduler-255ed017-e5ea-409f-b5ea-28ef50c6bc35@172.17.0.3:42681
I0509 21:59:28.288627 24444 sched.cpp:1177] Scheduler::frameworkMessage took 18841ns
I0509 21:59:28.288759 24437 sched.cpp:2021] Asked to stop the driver
I0509 21:59:28.288812 24442 sched.cpp:1203] Stopping framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.288918 24448 master.cpp:7988] Processing TEARDOWN call for framework e1680964-3268-4f13-938f-33a9843f3200-0000 (default) at scheduler-255ed017-e5ea-409f-b5ea-28ef50c6bc35@172.17.0.3:42681
I0509 21:59:28.288941 24448 master.cpp:8000] Removing framework e1680964-3268-4f13-938f-33a9843f3200-0000 (default) at scheduler-255ed017-e5ea-409f-b5ea-28ef50c6bc35@172.17.0.3:42681
I0509 21:59:28.288964 24448 master.cpp:3160] Deactivating framework e1680964-3268-4f13-938f-33a9843f3200-0000 (default) at scheduler-255ed017-e5ea-409f-b5ea-28ef50c6bc35@172.17.0.3:42681
I0509 21:59:28.289005 24444 hierarchical.cpp:374] Deactivated framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.289058 24448 master.cpp:8568] Updating the state of task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0509 21:59:28.289072 24445 slave.cpp:3057] Asked to shut down framework e1680964-3268-4f13-938f-33a9843f3200-0000 by master@172.17.0.3:42681
I0509 21:59:28.289116 24445 slave.cpp:3082] Shutting down framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.289131 24445 slave.cpp:5482] Shutting down executor 'default' of framework e1680964-3268-4f13-938f-33a9843f3200-0000 at executor(124)@172.17.0.3:42681
I0509 21:59:28.289191 24439 exec.cpp:415] Executor asked to shutdown
I0509 21:59:28.289260 24448 master.cpp:8662] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework e1680964-3268-4f13-938f-33a9843f3200-0000 on agent e1680964-3268-4f13-938f-33a9843f3200-S0 at slave(310)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.289353 24439 exec.cpp:430] Executor::shutdown took 56085ns
I0509 21:59:28.289386 24448 master.cpp:8691] Removing executor 'default' with resources {} of framework e1680964-3268-4f13-938f-33a9843f3200-0000 on agent e1680964-3268-4f13-938f-33a9843f3200-S0 at slave(310)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.289449 24443 hierarchical.cpp:1114] Recovered cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent e1680964-3268-4f13-938f-33a9843f3200-S0 from framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.289592 24449 hierarchical.cpp:325] Removed framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.289813 24452 slave.cpp:4787] Got exited event for executor(124)@172.17.0.3:42681
I0509 21:59:28.289842 24452 slave.cpp:5168] Executor 'default' of framework e1680964-3268-4f13-938f-33a9843f3200-0000 exited with status 0
I0509 21:59:28.289872 24452 slave.cpp:5268] Cleaning up executor 'default' of framework e1680964-3268-4f13-938f-33a9843f3200-0000 at executor(124)@172.17.0.3:42681
W0509 21:59:28.289973 24440 master.cpp:6592] Ignoring unknown exited executor 'default' of framework e1680964-3268-4f13-938f-33a9843f3200-0000 on agent e1680964-3268-4f13-938f-33a9843f3200-S0 at slave(310)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.290053 24452 slave.cpp:5356] Cleaning up framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.290009 24450 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_DeznyZ/slaves/e1680964-3268-4f13-938f-33a9843f3200-S0/frameworks/e1680964-3268-4f13-938f-33a9843f3200-0000/executors/default/runs/2d0ca364-654f-42d1-bba1-fb9211ffcee3' for gc 6.99999664398222days in the future
I0509 21:59:28.290136 24446 status_update_manager.cpp:285] Closing status update streams for framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.290156 24446 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework e1680964-3268-4f13-938f-33a9843f3200-0000
I0509 21:59:28.290163 24450 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_DeznyZ/slaves/e1680964-3268-4f13-938f-33a9843f3200-S0/frameworks/e1680964-3268-4f13-938f-33a9843f3200-0000/executors/default' for gc 6.99999664318222days in the future
I0509 21:59:28.290180 24452 slave.cpp:790] Agent terminating
I0509 21:59:28.290241 24450 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_DeznyZ/slaves/e1680964-3268-4f13-938f-33a9843f3200-S0/frameworks/e1680964-3268-4f13-938f-33a9843f3200-0000' for gc 6.9999966416563days in the future
I0509 21:59:28.290313 24449 master.cpp:1313] Agent e1680964-3268-4f13-938f-33a9843f3200-S0 at slave(310)@172.17.0.3:42681 (99de33280306) disconnected
I0509 21:59:28.290328 24449 master.cpp:3197] Disconnecting agent e1680964-3268-4f13-938f-33a9843f3200-S0 at slave(310)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.290359 24449 master.cpp:3216] Deactivating agent e1680964-3268-4f13-938f-33a9843f3200-S0 at slave(310)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.290441 24450 hierarchical.cpp:653] Agent e1680964-3268-4f13-938f-33a9843f3200-S0 deactivated
I0509 21:59:28.291390 24437 master.cpp:1155] Master terminating
I0509 21:59:28.291638 24442 hierarchical.cpp:558] Removed agent e1680964-3268-4f13-938f-33a9843f3200-S0
[ OK ] MasterTest.FrameworkMessage (58 ms)
[ RUN ] MasterTest.MultipleExecutors
I0509 21:59:28.294718 24437 cluster.cpp:162] Creating default 'local' authorizer
I0509 21:59:28.295455 24440 master.cpp:436] Master 2d5c9e6e-c118-4eb3-9134-33b15544dd94 (99de33280306) started on 172.17.0.3:42681
I0509 21:59:28.295469 24440 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/rMxdxa/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/rMxdxa/master" --zk_session_timeout="10secs"
I0509 21:59:28.295581 24440 master.cpp:488] Master only allowing authenticated frameworks to register
I0509 21:59:28.295588 24440 master.cpp:502] Master only allowing authenticated agents to register
I0509 21:59:28.295594 24440 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0509 21:59:28.295598 24440 credentials.hpp:37] Loading credentials for authentication from '/tmp/rMxdxa/credentials'
I0509 21:59:28.295692 24440 master.cpp:560] Using default 'crammd5' authenticator
I0509 21:59:28.295727 24440 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0509 21:59:28.295778 24440 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0509 21:59:28.295828 24440 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0509 21:59:28.295861 24440 master.cpp:640] Authorization enabled
I0509 21:59:28.295941 24448 hierarchical.cpp:158] Initialized hierarchical allocator process
I0509 21:59:28.295985 24447 whitelist_watcher.cpp:77] No whitelist given
I0509 21:59:28.296533 24439 master.cpp:2161] Elected as the leading master!
I0509 21:59:28.296548 24439 master.cpp:1700] Recovering from registrar
I0509 21:59:28.296597 24450 registrar.cpp:345] Recovering registrar
I0509 21:59:28.296762 24438 registrar.cpp:389] Successfully fetched the registry (0B) in 143104ns
I0509 21:59:28.296794 24438 registrar.cpp:493] Applied 1 operations in 3225ns; attempting to update the registry
I0509 21:59:28.297001 24438 registrar.cpp:550] Successfully updated the registry in 188928ns
I0509 21:59:28.297040 24438 registrar.cpp:422] Successfully recovered registrar
I0509 21:59:28.297143 24450 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0509 21:59:28.297220 24448 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0509 21:59:28.298780 24437 cluster.cpp:448] Creating default 'local' authorizer
I0509 21:59:28.299171 24452 slave.cpp:225] Mesos agent started on (311)@172.17.0.3:42681
I0509 21:59:28.299193 24452 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterTest_MultipleExecutors_xIJLeL/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_xIJLeL/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_xIJLeL/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_xIJLeL" --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_cAlZSX"
I0509 21:59:28.299365 24452 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_xIJLeL/credential'
I0509 21:59:28.299685 24437 sched.cpp:232] Version: 1.4.0
I0509 21:59:28.299834 24445 sched.cpp:336] New master detected at master@172.17.0.3:42681
I0509 21:59:28.299860 24445 sched.cpp:407] Authenticating with master master@172.17.0.3:42681
I0509 21:59:28.299866 24445 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0509 21:59:28.299926 24439 authenticatee.cpp:121] Creating new client SASL connection
I0509 21:59:28.300102 24451 master.cpp:7475] Authenticating scheduler-89ba4c61-509d-4ef9-a74d-94b89287f310@172.17.0.3:42681
I0509 21:59:28.300146 24439 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(663)@172.17.0.3:42681
I0509 21:59:28.300220 24449 authenticator.cpp:98] Creating new server SASL connection
I0509 21:59:28.300331 24447 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0509 21:59:28.300354 24447 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0509 21:59:28.300398 24449 authenticator.cpp:204] Received SASL authentication start
I0509 21:59:28.300432 24449 authenticator.cpp:326] Authentication requires more steps
I0509 21:59:28.300477 24447 authenticatee.cpp:259] Received SASL authentication step
I0509 21:59:28.300534 24449 authenticator.cpp:232] Received SASL authentication step
I0509 21:59:28.300551 24449 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '99de33280306' server FQDN: '99de33280306' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0509 21:59:28.300559 24449 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0509 21:59:28.300567 24449 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0509 21:59:28.300576 24449 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '99de33280306' server FQDN: '99de33280306' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0509 21:59:28.300582 24449 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0509 21:59:28.300588 24449 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0509 21:59:28.300599 24449 authenticator.cpp:318] Authentication success
I0509 21:59:28.300637 24443 authenticatee.cpp:299] Authentication success
I0509 21:59:28.300649 24447 master.cpp:7505] Successfully authenticated principal 'test-principal' at scheduler-89ba4c61-509d-4ef9-a74d-94b89287f310@172.17.0.3:42681
I0509 21:59:28.300683 24453 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(663)@172.17.0.3:42681
I0509 21:59:28.300766 24443 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:42681
I0509 21:59:28.300781 24443 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:42681
I0509 21:59:28.300832 24443 sched.cpp:869] Will retry registration in 659.513832ms if necessary
I0509 21:59:28.300869 24439 master.cpp:2813] Received SUBSCRIBE call for framework 'default' at scheduler-89ba4c61-509d-4ef9-a74d-94b89287f310@172.17.0.3:42681
I0509 21:59:28.300896 24439 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0509 21:59:28.301007 24448 master.cpp:2890] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0509 21:59:28.301138 24447 hierarchical.cpp:273] Added framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.301205 24442 sched.cpp:759] Framework registered with 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.301223 24447 hierarchical.cpp:1850] No allocations performed
I0509 21:59:28.301229 24442 sched.cpp:773] Scheduler::registered took 11706ns
I0509 21:59:28.301234 24447 hierarchical.cpp:1940] No inverse offers to send out!
I0509 21:59:28.301242 24447 hierarchical.cpp:1434] Performed allocation for 0 agents in 25748ns
I0509 21:59:28.318845 24452 slave.cpp:258] Agent using credential for: test-principal
I0509 21:59:28.318859 24452 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_xIJLeL/http_credentials'
I0509 21:59:28.318941 24452 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0509 21:59:28.318984 24452 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0509 21:59:28.319562 24452 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0509 21:59:28.319597 24452 slave.cpp:533] Agent attributes: [ ]
I0509 21:59:28.319603 24452 slave.cpp:538] Agent hostname: 99de33280306
I0509 21:59:28.319665 24450 status_update_manager.cpp:177] Pausing sending status updates
I0509 21:59:28.320034 24446 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_cAlZSX/meta'
I0509 21:59:28.320179 24440 status_update_manager.cpp:203] Recovering status update manager
I0509 21:59:28.320353 24442 slave.cpp:5970] Finished recovery
I0509 21:59:28.320729 24442 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0509 21:59:28.320951 24452 slave.cpp:918] New master detected at master@172.17.0.3:42681
I0509 21:59:28.320982 24438 status_update_manager.cpp:177] Pausing sending status updates
I0509 21:59:28.320993 24452 slave.cpp:953] Detecting new master
I0509 21:59:28.321153 24452 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0509 21:59:28.328285 24441 slave.cpp:980] Authenticating with master master@172.17.0.3:42681
I0509 21:59:28.328321 24441 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0509 21:59:28.328402 24450 authenticatee.cpp:121] Creating new client SASL connection
I0509 21:59:28.328614 24450 master.cpp:7475] Authenticating slave(311)@172.17.0.3:42681
I0509 21:59:28.328696 24451 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(664)@172.17.0.3:42681
I0509 21:59:28.328814 24443 authenticator.cpp:98] Creating new server SASL connection
I0509 21:59:28.328982 24452 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0509 21:59:28.329005 24452 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0509 21:59:28.329075 24442 authenticator.cpp:204] Received SASL authentication start
I0509 21:59:28.329131 24442 authenticator.cpp:326] Authentication requires more steps
I0509 21:59:28.329179 24442 authenticatee.cpp:259] Received SASL authentication step
I0509 21:59:28.329233 24442 authenticator.cpp:232] Received SASL authentication step
I0509 21:59:28.329257 24442 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '99de33280306' server FQDN: '99de33280306' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0509 21:59:28.329268 24442 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0509 21:59:28.329280 24442 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0509 21:59:28.329291 24442 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '99de33280306' server FQDN: '99de33280306' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0509 21:59:28.329304 24442 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0509 21:59:28.329319 24442 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0509 21:59:28.329342 24442 authenticator.cpp:318] Authentication success
I0509 21:59:28.329394 24439 authenticatee.cpp:299] Authentication success
I0509 21:59:28.329432 24447 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(311)@172.17.0.3:42681
I0509 21:59:28.329465 24438 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(664)@172.17.0.3:42681
I0509 21:59:28.329509 24439 slave.cpp:1075] Successfully authenticated with master master@172.17.0.3:42681
I0509 21:59:28.329581 24439 slave.cpp:1503] Will retry registration in 16.639529ms if necessary
I0509 21:59:28.329648 24450 master.cpp:5429] Received register agent message from slave(311)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.329722 24450 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0509 21:59:28.329941 24443 master.cpp:5564] Registering agent at slave(311)@172.17.0.3:42681 (99de33280306) with id 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0
I0509 21:59:28.330096 24447 registrar.cpp:493] Applied 1 operations in 23434ns; attempting to update the registry
I0509 21:59:28.330358 24453 registrar.cpp:550] Successfully updated the registry in 228096ns
I0509 21:59:28.330647 24450 slave.cpp:4745] Received ping from slave-observer(302)@172.17.0.3:42681
I0509 21:59:28.330778 24444 slave.cpp:1121] Registered with master master@172.17.0.3:42681; given agent ID 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0
I0509 21:59:28.330731 24447 master.cpp:5639] Registered agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 at slave(311)@172.17.0.3:42681 (99de33280306) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0509 21:59:28.330847 24449 hierarchical.cpp:525] Added agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 (99de33280306) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0509 21:59:28.330806 24444 fetcher.cpp:94] Clearing fetcher cache
I0509 21:59:28.331010 24441 status_update_manager.cpp:184] Resuming sending status updates
I0509 21:59:28.331239 24449 hierarchical.cpp:1940] No inverse offers to send out!
I0509 21:59:28.331255 24444 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_cAlZSX/meta/slaves/2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0/slave.info'
I0509 21:59:28.331271 24449 hierarchical.cpp:1434] Performed allocation for 1 agents in 339198ns
I0509 21:59:28.331454 24439 master.cpp:7305] Sending 1 offers to framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000 (default) at scheduler-89ba4c61-509d-4ef9-a74d-94b89287f310@172.17.0.3:42681
I0509 21:59:28.331562 24444 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0509 21:59:28.331677 24439 master.cpp:6324] Received update of agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 at slave(311)@172.17.0.3:42681 (99de33280306) with total oversubscribed resources {}
I0509 21:59:28.331704 24452 sched.cpp:933] Scheduler::resourceOffers took 84660ns
I0509 21:59:28.332453 24448 master.cpp:3875] Processing ACCEPT call for offers: [ 2d5c9e6e-c118-4eb3-9134-33b15544dd94-O0 ] on agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 at slave(311)@172.17.0.3:42681 (99de33280306) for framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000 (default) at scheduler-89ba4c61-509d-4ef9-a74d-94b89287f310@172.17.0.3:42681
I0509 21:59:28.332504 24448 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 1
I0509 21:59:28.332628 24448 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 2
W0509 21:59:28.333317 24441 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.
W0509 21:59:28.333344 24441 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.
I0509 21:59:28.333444 24441 master.cpp:9326] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 at slave(311)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.333528 24441 master.cpp:4531] Launching task 1 of framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000 (default) at scheduler-89ba4c61-509d-4ef9-a74d-94b89287f310@172.17.0.3:42681 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 at slave(311)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.333721 24440 slave.cpp:1613] Got assigned task '1' for framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
W0509 21:59:28.333923 24441 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.
W0509 21:59:28.333948 24441 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.
I0509 21:59:28.334035 24440 slave.cpp:1894] Authorizing task '1' for framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.334039 24441 master.cpp:9326] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 at slave(311)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.334074 24440 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 1
I0509 21:59:28.334115 24441 master.cpp:4531] Launching task 2 of framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000 (default) at scheduler-89ba4c61-509d-4ef9-a74d-94b89287f310@172.17.0.3:42681 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 at slave(311)@172.17.0.3:42681 (99de33280306)
I0509 21:59:28.334395 24440 slave.cpp:1613] Got assigned task '2' for framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.334668 24440 slave.cpp:2081] Launching task '1' for framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.335016 24438 hierarchical.cpp:850] Updated allocation of framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000 on agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-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]
I0509 21:59:28.335245 24438 hierarchical.cpp:1114] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 from framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.335268 24440 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_cAlZSX/slaves/2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0/frameworks/2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000/executors/executor-1/runs/277a12fc-a853-4951-8863-0a131d8ecd48' to user 'mesos'
I0509 21:59:28.335291 24438 hierarchical.cpp:1151] Framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000 filtered agent 2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0 for 5secs
I0509 21:59:28.335500 24440 slave.cpp:6929] Launching executor 'executor-1' of framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_cAlZSX/slaves/2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0/frameworks/2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000/executors/executor-1/runs/277a12fc-a853-4951-8863-0a131d8ecd48'
I0509 21:59:28.335798 24440 slave.cpp:2310] Queued task '1' for executor 'executor-1' of framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.335860 24440 slave.cpp:1894] Authorizing task '2' for framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.335882 24440 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 2
I0509 21:59:28.336045 24440 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_cAlZSX/slaves/2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0/frameworks/2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000/executors/executor-1/runs/277a12fc-a853-4951-8863-0a131d8ecd48'
I0509 21:59:28.336179 24441 slave.cpp:2081] Launching task '2' for framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000
I0509 21:59:28.336484 24441 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_cAlZSX/slaves/2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0/frameworks/2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000/executors/executor-2/runs/a153ffe3-66ad-4ba6-bdea-e9b318f9c5b3' to user 'mesos'
I0509 21:59:28.336627 24441 slave.cpp:6929] Launching executor 'executor-2' of framework 2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_cAlZSX/slaves/2d5c9e6e-c118-4eb3-9134-33b15544dd94-S0/frameworks/2d5c9e6e-c118-4eb3-9134-33b15544dd94-0000/executors/executor-2/runs/a153ffe3-66ad-4ba6-bdea-e9b318f9c5b3'
*** Aborted at 1494367168 (unix time) try "date -d @1494367168" if you are using GNU date ***
I0509 21:59:28.336894 24438 exec.cpp:162] Version: 1.4.0
I0509 21:59:28.336992 24448 exec.cpp:212] Executor started at: executor(125)@172.17.0.3:42681 with pid 24437
PC: @ 0x7f1c4ad050cd __GI_getenv
*** SIGSEGV (@0xd0) received by PID 24437 (TID 0x7f1c4378f700) from PID 208; stack trace: ***
@ 0x7f1c4b8be370 (unknown)
@ 0x7f1c4ad050cd __GI_getenv
@ 0x7f1c4f50cf44 mesos::internal::slave::executorEnvironment()
@ 0x7f1c4f5323ca mesos::internal::slave::Slave::launchExecutor()
@ 0x7f1c4f536fe6 mesos::internal::slave::Slave::__run()
@ 0x7f1c4e795001 process::ProcessManager::resume()
@ 0x7f1c4e79e0b7 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7f1c4b65b230 (unknown)
@ 0x7f1c4b8b6dc5 start_thread
@ 0x7f1c4adc473d __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-1494364149-4750
Untagged: mesos-1494364149-4750:latest
Deleted: sha256:5e8263c20daf6a595a54955b80590faed2778080d931ce1d9b6270dbe8af57e7
Deleted: sha256:a86e27d78838cc389e232bd2a36e73f697659a320d8a17badce95f5c15994f03
Deleted: sha256:cef55d8add329abdf97224248533f8e165deb27aacee56388e47ecbc961451ff
Deleted: sha256:aeec56cb9bf7e2767730469407f7ac5d03dacb0652d33852c54a60d17b882920
Deleted: sha256:4e2acd45615c215d7e0e0922ea4ea9366a179c95460f3bf84751a72de074077b
Deleted: sha256:ab6baf9e7430da2154e0d0f1929f26a491221a60e6acf431acd3bc946d38a86f
Deleted: sha256:5252e75851f4e836863b4ba43ce81c678104fed69300a505b57b056fcad7b754
Deleted: sha256:60310a88f83bda9f810c4213cd5ed2401bfc100c9fddf0f9e8953fb0fdc5e3fe
Deleted: sha256:e15b2347a3585615002a03acb294fddb5369e1887e102aff3f3474dd6ccc99dd
Deleted: sha256:a11e200f45db4fbb6b0d0de8e8de4b63745ce156cf01e9382a08065ae5d8311f
Deleted: sha256:d652ff2aee96d0564e48cd501558052caa6a9e43d2fc7a759b8f0ac783c6ca8c
Deleted: sha256:77ea5841bf854ba1f852914cc5df7cec1fc1aebdbbebf07c826e4b2d2872ac69
Deleted: sha256:e4e124badec9a76dcb47f8b2e438750f55fdfe8dfb1e57021ebb8226c83028c4
Deleted: sha256:ab9ffa021b3795de141d4985b4589b725881aaf42c332a8ad38b0d8cd1fcdf48
Deleted: sha256:eaded1375fe93625c0e8882e53b77da532d0cafaff98ce5142ac6ff0b9f0d6cf
Deleted: sha256:3fe949ab8f43ec3a9edf547dd3ba5f2778492234dd653f1adaf3cb0b607e925e
Deleted: sha256:fd3c379e85ed4f9ab3d5aa8d9cf0c154f2b2044e94860696525ee1ab8c022f20
Deleted: sha256:bfc1eadb0aa99520ceabe84400953506c7345a5a9bfc481ebb624fdf0a9057b0
Deleted: sha256:1bd73f21d2e64048489b41656202072f2e05722531f55d8250820cadab5ec3c8
Deleted: sha256:527753755ca11001cd52765175d5c78d80448b73e53c0847aae80ef260009f0c
Deleted: sha256:bb996b97dc013971a2de4a4feeb16abd7c4c020ecba918ece3c0650863dd17cb
Deleted: sha256:f9622fbf45f781fa8f0d958bed76db9c3ecd2517a402e2fa49c716f9349ff92c
Deleted: sha256:39e229ffb42b3d198b2f68c9cb03a8427e7069906519ef20f1758a2b9f0bcab0
Deleted: sha256:3be14fd188466d3b9c6c692008dfba48786b87a8d9c86260a1e18a7d18c2203e
Deleted: sha256:d73574e3a886a998f3056d43598936f9e099608d4fdfdb7cffb69bb000161b7a
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) #3641
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)/3641/display/redirect?page=changes>