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/11 02:04:15 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3656
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)/3656/display/redirect>
------------------------------------------
[...truncated 12.69 MB...]
I0511 02:04:09.027840 24443 slave.cpp:4655] Forwarding the update TASK_RUNNING (UUID: d92e0e42-677f-4143-ba08-362f1ef3b5aa) for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 to master@172.17.0.2:42206
I0511 02:04:09.027945 24443 slave.cpp:4549] Status update manager successfully handled status update TASK_RUNNING (UUID: d92e0e42-677f-4143-ba08-362f1ef3b5aa) for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.027966 24443 slave.cpp:4565] Sending acknowledgement for status update TASK_RUNNING (UUID: d92e0e42-677f-4143-ba08-362f1ef3b5aa) for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 to executor(124)@172.17.0.2:42206
I0511 02:04:09.027986 24452 master.cpp:6469] Status update TASK_RUNNING (UUID: d92e0e42-677f-4143-ba08-362f1ef3b5aa) for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 from agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 at slave(310)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.028019 24452 master.cpp:6537] Forwarding status update TASK_RUNNING (UUID: d92e0e42-677f-4143-ba08-362f1ef3b5aa) for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.028040 24443 exec.cpp:374] Executor received status update acknowledgement d92e0e42-677f-4143-ba08-362f1ef3b5aa for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.028081 24452 master.cpp:8568] Updating the state of task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0511 02:04:09.028271 24446 sched.cpp:1041] Scheduler::statusUpdate took 36236ns
I0511 02:04:09.028424 24448 master.cpp:5194] Processing ACKNOWLEDGE call d92e0e42-677f-4143-ba08-362f1ef3b5aa for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 (default) at scheduler-d5f50e53-0fcb-4c2e-ab8c-84a4102b2b94@172.17.0.2:42206 on agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0
I0511 02:04:09.028561 24443 status_update_manager.cpp:395] Received status update acknowledgement (UUID: d92e0e42-677f-4143-ba08-362f1ef3b5aa) for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.028710 24450 slave.cpp:3504] Status update manager successfully handled status update acknowledgement (UUID: d92e0e42-677f-4143-ba08-362f1ef3b5aa) for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.028887 24450 exec.cpp:396] Executor received framework message
I0511 02:04:09.028939 24450 exec.cpp:405] Executor::frameworkMessage took 34107ns
I0511 02:04:09.029248 24441 slave.cpp:4723] Sending message for framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 to scheduler-d5f50e53-0fcb-4c2e-ab8c-84a4102b2b94@172.17.0.2:42206
I0511 02:04:09.029368 24443 sched.cpp:1177] Scheduler::frameworkMessage took 31236ns
I0511 02:04:09.029568 24436 sched.cpp:2021] Asked to stop the driver
I0511 02:04:09.029644 24439 sched.cpp:1203] Stopping framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.029765 24437 master.cpp:7988] Processing TEARDOWN call for framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 (default) at scheduler-d5f50e53-0fcb-4c2e-ab8c-84a4102b2b94@172.17.0.2:42206
I0511 02:04:09.029789 24437 master.cpp:8000] Removing framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 (default) at scheduler-d5f50e53-0fcb-4c2e-ab8c-84a4102b2b94@172.17.0.2:42206
I0511 02:04:09.029842 24437 master.cpp:3160] Deactivating framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 (default) at scheduler-d5f50e53-0fcb-4c2e-ab8c-84a4102b2b94@172.17.0.2:42206
I0511 02:04:09.029914 24437 master.cpp:8568] Updating the state of task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0511 02:04:09.030011 24438 hierarchical.cpp:374] Deactivated framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.030057 24452 slave.cpp:3057] Asked to shut down framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 by master@172.17.0.2:42206
I0511 02:04:09.030171 24437 master.cpp:8662] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 on agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 at slave(310)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.030431 24452 slave.cpp:3082] Shutting down framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.030468 24452 slave.cpp:5482] Shutting down executor 'default' of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 at executor(124)@172.17.0.2:42206
I0511 02:04:09.030498 24437 master.cpp:8691] Removing executor 'default' with resources {} of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 on agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 at slave(310)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.030583 24449 exec.cpp:415] Executor asked to shutdown
I0511 02:04:09.030702 24449 exec.cpp:430] Executor::shutdown took 37162ns
I0511 02:04:09.030777 24438 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 dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 from framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.030907 24442 slave.cpp:4787] Got exited event for executor(124)@172.17.0.2:42206
I0511 02:04:09.031033 24438 hierarchical.cpp:325] Removed framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.031081 24442 slave.cpp:5168] Executor 'default' of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 exited with status 0
I0511 02:04:09.031141 24442 slave.cpp:5268] Cleaning up executor 'default' of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 at executor(124)@172.17.0.2:42206
W0511 02:04:09.031234 24449 master.cpp:6592] Ignoring unknown exited executor 'default' of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000 on agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 at slave(310)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.031383 24450 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_10DuV5/slaves/dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0/frameworks/dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000/executors/default/runs/00921c84-b918-4d8d-a83e-e603793b3297' for gc 6.99999963723259days in the future
I0511 02:04:09.031442 24442 slave.cpp:5356] Cleaning up framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.031455 24450 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_10DuV5/slaves/dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0/frameworks/dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000/executors/default' for gc 6.99999963628444days in the future
I0511 02:04:09.031494 24443 status_update_manager.cpp:285] Closing status update streams for framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.031533 24442 slave.cpp:790] Agent terminating
I0511 02:04:09.031555 24443 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000
I0511 02:04:09.031576 24448 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_10DuV5/slaves/dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0/frameworks/dcee1ecb-9678-4211-a175-ecc1d3ab8367-0000' for gc 6.99999963527704days in the future
I0511 02:04:09.031643 24442 master.cpp:1313] Agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 at slave(310)@172.17.0.2:42206 (888916f0bfb5) disconnected
I0511 02:04:09.031662 24442 master.cpp:3197] Disconnecting agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 at slave(310)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.031687 24442 master.cpp:3216] Deactivating agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 at slave(310)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.031740 24441 hierarchical.cpp:653] Agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0 deactivated
I0511 02:04:09.032716 24436 master.cpp:1155] Master terminating
I0511 02:04:09.033004 24445 hierarchical.cpp:558] Removed agent dcee1ecb-9678-4211-a175-ecc1d3ab8367-S0
[ OK ] MasterTest.FrameworkMessage (31 ms)
[ RUN ] MasterTest.MultipleExecutors
I0511 02:04:09.036710 24436 cluster.cpp:162] Creating default 'local' authorizer
I0511 02:04:09.037852 24441 master.cpp:436] Master fe963af6-8523-4116-8bf6-ab7319e12b0c (888916f0bfb5) started on 172.17.0.2:42206
I0511 02:04:09.037953 24441 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/fPGi88/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/fPGi88/master" --zk_session_timeout="10secs"
I0511 02:04:09.038120 24441 master.cpp:488] Master only allowing authenticated frameworks to register
I0511 02:04:09.038131 24441 master.cpp:502] Master only allowing authenticated agents to register
I0511 02:04:09.038138 24441 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0511 02:04:09.038146 24441 credentials.hpp:37] Loading credentials for authentication from '/tmp/fPGi88/credentials'
I0511 02:04:09.038271 24441 master.cpp:560] Using default 'crammd5' authenticator
I0511 02:04:09.038319 24441 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0511 02:04:09.038373 24441 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0511 02:04:09.038489 24441 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0511 02:04:09.038556 24441 master.cpp:640] Authorization enabled
I0511 02:04:09.038657 24444 whitelist_watcher.cpp:77] No whitelist given
I0511 02:04:09.038664 24439 hierarchical.cpp:158] Initialized hierarchical allocator process
I0511 02:04:09.039506 24448 master.cpp:2161] Elected as the leading master!
I0511 02:04:09.039525 24448 master.cpp:1700] Recovering from registrar
I0511 02:04:09.039635 24449 registrar.cpp:345] Recovering registrar
I0511 02:04:09.039942 24449 registrar.cpp:389] Successfully fetched the registry (0B) in 278016ns
I0511 02:04:09.039981 24449 registrar.cpp:493] Applied 1 operations in 3540ns; attempting to update the registry
I0511 02:04:09.040339 24440 registrar.cpp:550] Successfully updated the registry in 338176ns
I0511 02:04:09.040397 24440 registrar.cpp:422] Successfully recovered registrar
I0511 02:04:09.040706 24442 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0511 02:04:09.041556 24449 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0511 02:04:09.043813 24436 cluster.cpp:448] Creating default 'local' authorizer
I0511 02:04:09.044500 24447 slave.cpp:225] Mesos agent started on (311)@172.17.0.2:42206
I0511 02:04:09.044517 24447 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_dX06QF/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_dX06QF/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_dX06QF/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_dX06QF" --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_LUmHuU"
I0511 02:04:09.044775 24447 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_dX06QF/credential'
I0511 02:04:09.044881 24447 slave.cpp:258] Agent using credential for: test-principal
I0511 02:04:09.044899 24447 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_dX06QF/http_credentials'
I0511 02:04:09.045012 24447 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0511 02:04:09.045120 24447 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0511 02:04:09.045222 24436 sched.cpp:232] Version: 1.4.0
I0511 02:04:09.045464 24444 sched.cpp:336] New master detected at master@172.17.0.2:42206
I0511 02:04:09.045557 24444 sched.cpp:407] Authenticating with master master@172.17.0.2:42206
I0511 02:04:09.045570 24444 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0511 02:04:09.045675 24439 authenticatee.cpp:121] Creating new client SASL connection
I0511 02:04:09.045737 24447 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0511 02:04:09.045783 24447 slave.cpp:533] Agent attributes: [ ]
I0511 02:04:09.045790 24447 slave.cpp:538] Agent hostname: 888916f0bfb5
I0511 02:04:09.045863 24449 status_update_manager.cpp:177] Pausing sending status updates
I0511 02:04:09.045961 24452 master.cpp:7475] Authenticating scheduler-83852f2f-ac77-4321-a4bf-c0e91bae9751@172.17.0.2:42206
I0511 02:04:09.046030 24452 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(664)@172.17.0.2:42206
I0511 02:04:09.046247 24437 authenticator.cpp:98] Creating new server SASL connection
I0511 02:04:09.046291 24446 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_LUmHuU/meta'
I0511 02:04:09.046429 24437 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0511 02:04:09.046456 24437 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0511 02:04:09.046517 24438 status_update_manager.cpp:203] Recovering status update manager
I0511 02:04:09.046530 24445 authenticator.cpp:204] Received SASL authentication start
I0511 02:04:09.046587 24445 authenticator.cpp:326] Authentication requires more steps
I0511 02:04:09.046725 24449 slave.cpp:5970] Finished recovery
I0511 02:04:09.046808 24452 authenticatee.cpp:259] Received SASL authentication step
I0511 02:04:09.046903 24442 authenticator.cpp:232] Received SASL authentication step
I0511 02:04:09.046937 24442 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '888916f0bfb5' server FQDN: '888916f0bfb5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0511 02:04:09.046958 24442 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0511 02:04:09.046980 24442 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0511 02:04:09.047004 24442 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '888916f0bfb5' server FQDN: '888916f0bfb5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0511 02:04:09.047024 24442 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0511 02:04:09.047039 24442 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0511 02:04:09.047065 24442 authenticator.cpp:318] Authentication success
I0511 02:04:09.047067 24449 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0511 02:04:09.047139 24452 authenticatee.cpp:299] Authentication success
I0511 02:04:09.047212 24450 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(664)@172.17.0.2:42206
I0511 02:04:09.047237 24451 master.cpp:7505] Successfully authenticated principal 'test-principal' at scheduler-83852f2f-ac77-4321-a4bf-c0e91bae9751@172.17.0.2:42206
I0511 02:04:09.047322 24449 slave.cpp:918] New master detected at master@172.17.0.2:42206
I0511 02:04:09.047336 24444 status_update_manager.cpp:177] Pausing sending status updates
I0511 02:04:09.047363 24452 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:42206
I0511 02:04:09.047400 24449 slave.cpp:953] Detecting new master
I0511 02:04:09.047435 24452 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:42206
I0511 02:04:09.047521 24449 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0511 02:04:09.047530 24452 sched.cpp:869] Will retry registration in 1.790492156secs if necessary
I0511 02:04:09.047652 24437 master.cpp:2813] Received SUBSCRIBE call for framework 'default' at scheduler-83852f2f-ac77-4321-a4bf-c0e91bae9751@172.17.0.2:42206
I0511 02:04:09.047685 24437 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0511 02:04:09.047859 24440 master.cpp:2890] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0511 02:04:09.048036 24447 hierarchical.cpp:273] Added framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.048054 24444 sched.cpp:759] Framework registered with fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.048094 24447 hierarchical.cpp:1850] No allocations performed
I0511 02:04:09.048105 24444 sched.cpp:773] Scheduler::registered took 21262ns
I0511 02:04:09.048115 24447 hierarchical.cpp:1940] No inverse offers to send out!
I0511 02:04:09.048138 24447 hierarchical.cpp:1434] Performed allocation for 0 agents in 53957ns
I0511 02:04:09.055572 24443 slave.cpp:980] Authenticating with master master@172.17.0.2:42206
I0511 02:04:09.055604 24443 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0511 02:04:09.055698 24448 authenticatee.cpp:121] Creating new client SASL connection
I0511 02:04:09.055878 24448 master.cpp:7475] Authenticating slave(311)@172.17.0.2:42206
I0511 02:04:09.055963 24449 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(665)@172.17.0.2:42206
I0511 02:04:09.056041 24437 authenticator.cpp:98] Creating new server SASL connection
I0511 02:04:09.056252 24443 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0511 02:04:09.056272 24443 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0511 02:04:09.056330 24437 authenticator.cpp:204] Received SASL authentication start
I0511 02:04:09.056391 24437 authenticator.cpp:326] Authentication requires more steps
I0511 02:04:09.056464 24443 authenticatee.cpp:259] Received SASL authentication step
I0511 02:04:09.056594 24441 authenticator.cpp:232] Received SASL authentication step
I0511 02:04:09.056619 24441 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '888916f0bfb5' server FQDN: '888916f0bfb5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0511 02:04:09.056635 24441 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0511 02:04:09.056658 24441 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0511 02:04:09.056684 24441 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '888916f0bfb5' server FQDN: '888916f0bfb5' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0511 02:04:09.056697 24441 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0511 02:04:09.056706 24441 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0511 02:04:09.056722 24441 authenticator.cpp:318] Authentication success
I0511 02:04:09.056782 24437 authenticatee.cpp:299] Authentication success
I0511 02:04:09.056843 24439 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(311)@172.17.0.2:42206
I0511 02:04:09.056887 24452 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(665)@172.17.0.2:42206
I0511 02:04:09.057055 24441 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:42206
I0511 02:04:09.057153 24441 slave.cpp:1503] Will retry registration in 14.556622ms if necessary
I0511 02:04:09.057221 24445 master.cpp:5429] Received register agent message from slave(311)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.057279 24445 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0511 02:04:09.057476 24450 master.cpp:5564] Registering agent at slave(311)@172.17.0.2:42206 (888916f0bfb5) with id fe963af6-8523-4116-8bf6-ab7319e12b0c-S0
I0511 02:04:09.057662 24443 registrar.cpp:493] Applied 1 operations in 20932ns; attempting to update the registry
I0511 02:04:09.057885 24443 registrar.cpp:550] Successfully updated the registry in 199168ns
I0511 02:04:09.058105 24445 slave.cpp:4745] Received ping from slave-observer(302)@172.17.0.2:42206
I0511 02:04:09.058126 24442 master.cpp:5639] Registered agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 at slave(311)@172.17.0.2:42206 (888916f0bfb5) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0511 02:04:09.058171 24445 slave.cpp:1121] Registered with master master@172.17.0.2:42206; given agent ID fe963af6-8523-4116-8bf6-ab7319e12b0c-S0
I0511 02:04:09.058284 24445 fetcher.cpp:94] Clearing fetcher cache
I0511 02:04:09.058284 24450 hierarchical.cpp:525] Added agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 (888916f0bfb5) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0511 02:04:09.058418 24440 status_update_manager.cpp:184] Resuming sending status updates
I0511 02:04:09.058688 24445 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_LUmHuU/meta/slaves/fe963af6-8523-4116-8bf6-ab7319e12b0c-S0/slave.info'
I0511 02:04:09.058748 24450 hierarchical.cpp:1940] No inverse offers to send out!
I0511 02:04:09.058768 24450 hierarchical.cpp:1434] Performed allocation for 1 agents in 420247ns
I0511 02:04:09.058998 24445 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0511 02:04:09.059006 24439 master.cpp:7305] Sending 1 offers to framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000 (default) at scheduler-83852f2f-ac77-4321-a4bf-c0e91bae9751@172.17.0.2:42206
I0511 02:04:09.059104 24439 master.cpp:6324] Received update of agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 at slave(311)@172.17.0.2:42206 (888916f0bfb5) with total oversubscribed resources {}
I0511 02:04:09.059240 24451 sched.cpp:933] Scheduler::resourceOffers took 67835ns
I0511 02:04:09.059931 24450 master.cpp:3875] Processing ACCEPT call for offers: [ fe963af6-8523-4116-8bf6-ab7319e12b0c-O0 ] on agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 at slave(311)@172.17.0.2:42206 (888916f0bfb5) for framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000 (default) at scheduler-83852f2f-ac77-4321-a4bf-c0e91bae9751@172.17.0.2:42206
I0511 02:04:09.059979 24450 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 1
I0511 02:04:09.060057 24450 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 2
W0511 02:04:09.060569 24447 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.
W0511 02:04:09.060592 24447 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.
I0511 02:04:09.060667 24447 master.cpp:9326] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 at slave(311)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.060737 24447 master.cpp:4531] Launching task 1 of framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000 (default) at scheduler-83852f2f-ac77-4321-a4bf-c0e91bae9751@172.17.0.2:42206 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 at slave(311)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.060952 24446 slave.cpp:1613] Got assigned task '1' for framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
W0511 02:04:09.061084 24447 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.
W0511 02:04:09.061108 24447 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.
I0511 02:04:09.061220 24447 master.cpp:9326] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 at slave(311)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.061295 24447 master.cpp:4531] Launching task 2 of framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000 (default) at scheduler-83852f2f-ac77-4321-a4bf-c0e91bae9751@172.17.0.2:42206 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 at slave(311)@172.17.0.2:42206 (888916f0bfb5)
I0511 02:04:09.061337 24446 slave.cpp:1894] Authorizing task '1' for framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.061360 24446 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 1
I0511 02:04:09.061789 24446 slave.cpp:1613] Got assigned task '2' for framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.062011 24446 slave.cpp:2081] Launching task '1' for framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.062134 24447 hierarchical.cpp:850] Updated allocation of framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000 on agent fe963af6-8523-4116-8bf6-ab7319e12b0c-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]
I0511 02:04:09.062353 24447 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 fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 from framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.062394 24447 hierarchical.cpp:1151] Framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000 filtered agent fe963af6-8523-4116-8bf6-ab7319e12b0c-S0 for 5secs
I0511 02:04:09.062600 24446 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_LUmHuU/slaves/fe963af6-8523-4116-8bf6-ab7319e12b0c-S0/frameworks/fe963af6-8523-4116-8bf6-ab7319e12b0c-0000/executors/executor-1/runs/9863ee42-3dd8-4f1f-b1d6-54ea4173e4df' to user 'mesos'
I0511 02:04:09.062799 24446 slave.cpp:6929] Launching executor 'executor-1' of framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_LUmHuU/slaves/fe963af6-8523-4116-8bf6-ab7319e12b0c-S0/frameworks/fe963af6-8523-4116-8bf6-ab7319e12b0c-0000/executors/executor-1/runs/9863ee42-3dd8-4f1f-b1d6-54ea4173e4df'
I0511 02:04:09.062974 24446 slave.cpp:2310] Queued task '1' for executor 'executor-1' of framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.063014 24446 slave.cpp:1894] Authorizing task '2' for framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.063037 24446 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 2
I0511 02:04:09.063202 24446 slave.cpp:871] Successfully attached file '/tmp/MasterTest_MultipleExecutors_LUmHuU/slaves/fe963af6-8523-4116-8bf6-ab7319e12b0c-S0/frameworks/fe963af6-8523-4116-8bf6-ab7319e12b0c-0000/executors/executor-1/runs/9863ee42-3dd8-4f1f-b1d6-54ea4173e4df'
I0511 02:04:09.063416 24446 slave.cpp:2081] Launching task '2' for framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000
I0511 02:04:09.063714 24446 paths.cpp:573] Trying to chown '/tmp/MasterTest_MultipleExecutors_LUmHuU/slaves/fe963af6-8523-4116-8bf6-ab7319e12b0c-S0/frameworks/fe963af6-8523-4116-8bf6-ab7319e12b0c-0000/executors/executor-2/runs/442d65ed-8529-4405-82c9-fa3a12c7276b' to user 'mesos'
I0511 02:04:09.063848 24446 slave.cpp:6929] Launching executor 'executor-2' of framework fe963af6-8523-4116-8bf6-ab7319e12b0c-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_LUmHuU/slaves/fe963af6-8523-4116-8bf6-ab7319e12b0c-S0/frameworks/fe963af6-8523-4116-8bf6-ab7319e12b0c-0000/executors/executor-2/runs/442d65ed-8529-4405-82c9-fa3a12c7276b'
*** Aborted at 1494468249 (unix time) try "date -d @1494468249" if you are using GNU date ***
I0511 02:04:09.064110 24440 exec.cpp:162] Version: 1.4.0
I0511 02:04:09.064174 24447 exec.cpp:212] Executor started at: executor(125)@172.17.0.2:42206 with pid 24436
PC: @ 0x7fb6ce28c0cd __GI_getenv
*** SIGSEGV (@0xb0) received by PID 24436 (TID 0x7fb6c3d14700) from PID 176; stack trace: ***
@ 0x7fb6cee45370 (unknown)
@ 0x7fb6ce28c0cd __GI_getenv
@ 0x7fb6d2a94364 mesos::internal::slave::executorEnvironment()
@ 0x7fb6d2ab97ea mesos::internal::slave::Slave::launchExecutor()
@ 0x7fb6d2abe406 mesos::internal::slave::Slave::__run()
@ 0x7fb6d1d1c001 process::ProcessManager::resume()
@ 0x7fb6d1d250b7 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7fb6cebe2230 (unknown)
@ 0x7fb6cee3ddc5 start_thread
@ 0x7fb6ce34b73d __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-1494465130-7403
Untagged: mesos-1494465130-7403:latest
Deleted: sha256:6fd427f3bcfa538ffb6648e15e9d15ddd8ec282e838e7679fea7f62799823026
Deleted: sha256:2fbc691ad2b67048ce780e109c17520c1b5d7b604d989443c110537f3e8a40c4
Deleted: sha256:44534644b6b24643046a195004a5d4a0bac81e648a8f827f9f33dfec684b4829
Deleted: sha256:686cb2dff6d3d7f358deece00d2a6e527c829456ebb6a879fc6345f92347baf1
Deleted: sha256:9906a3188794394204a3cea5ece37252e6480b4c4af75ccdd259b838d0605516
Deleted: sha256:1002698c1a0764e739447a5529842ccc32e8812fd6abefdb2b0679cf26dae51d
Deleted: sha256:ed51dd2ef196e9f78528d479e76f7f3e91746072c469a0b8761da7593167ebe4
Deleted: sha256:ba81e8f32e6696ab17e618c6e2957287ff048043ebf76c5ceafc49e05672073a
Deleted: sha256:b90174e908a1d26f538700853ab0d5711eedca4fd7281490dccc601845ca5a1f
Deleted: sha256:9bc301b79381df180e7571fe5bcf3d07bca605232010ad4b5fbb971bc4649827
Deleted: sha256:e17be6cf149daa3937dd7f427365fdfc8fb3914cad6cb57c499b04ab54cf18a2
Deleted: sha256:bc9c2a12955e463c8eea8c971f738a8b7c855517b2aa4aaadc02df08ba3114f6
Deleted: sha256:c37bda87048b459a8b68c50a19c9bd28758cae799cb000cb97a20ca51db06f65
Deleted: sha256:a17084e58d8f2ded2aabb9177f80ea65baf0d8e80aad27680d5293b2a30cada6
Deleted: sha256:e9f9b764079b9ebdcd07476451adc3e7ca8cc5c3ba48f892646152f277fdde04
Deleted: sha256:d01fee133a73f63227be649be17696a1592e87bd161d43af9a046fc545955b02
Deleted: sha256:472aa10a23d74ea30488eee0837c3384b77b49759d54baf27c492fffcbe4e737
Deleted: sha256:94023d4b60fd589afa34602029a6dad895b7793161b7d9c679b82effc22c14c3
Deleted: sha256:c91666f86f81519def7aebd5573faa802a4ab1861d8910581b42bef57b9d8f6a
Deleted: sha256:2173d197a2ed49921c95096212fdf5c43d5c70c5464b5469573af1d133bf26c5
Deleted: sha256:aae0b77963b0bd18f55bb6cb8de08d46902d556e77ef3de6ca33725be859ae59
Deleted: sha256:e50191e4dda767b7787038613948153417fb6291e7058c1925855e98aeee3285
Deleted: sha256:4f03e10e97f22e00d91fcdda4aff69323ef3b0fd673a1e8bc94e6d1c52ffd368
Deleted: sha256:cc3cb3d9be8e50f206f1b273422be375ca48e4b8ca4fa21673f1dca34de538c2
Deleted: sha256:5cf1419adbdd551ee2bd7c332c6264993918a24618c11cf2764d5bb19d7ac251
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) #3657
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)/3657/display/redirect?page=changes>