You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/03/13 01:15:27 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3360
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3360/display/redirect?page=changes>
Changes:
[klueska] Added ability to dynamically load '/dev/nvidia-uvm' in GPU isolator.
------------------------------------------
[...truncated 11.86 MB...]
I0313 01:15:20.186295 23976 exec.cpp:325] Executor asked to run task '1'
I0313 01:15:20.186339 23976 exec.cpp:334] Executor::launchTask took 32442ns
I0313 01:15:20.186417 23976 exec.cpp:551] Executor sending status update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.186586 23979 slave.cpp:3817] Handling status update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 from executor(120)@172.17.0.3:58547
I0313 01:15:20.186877 23965 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.186904 23965 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.187060 23965 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 to the agent
I0313 01:15:20.187183 23971 slave.cpp:4257] Forwarding the update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 to master@172.17.0.3:58547
I0313 01:15:20.187309 23971 slave.cpp:4151] Status update manager successfully handled status update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.187330 23979 master.cpp:6243] Status update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 from agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 at slave(286)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.187340 23971 slave.cpp:4167] Sending acknowledgement for status update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 to executor(120)@172.17.0.3:58547
I0313 01:15:20.187364 23979 master.cpp:6311] Forwarding status update TASK_RUNNING (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.187429 23979 master.cpp:8345] Updating the state of task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0313 01:15:20.187433 23971 exec.cpp:374] Executor received status update acknowledgement 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00 for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.187554 23976 sched.cpp:1041] Scheduler::statusUpdate took 66299ns
I0313 01:15:20.187758 23975 master.cpp:5151] Processing ACKNOWLEDGE call 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00 for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 (default) at scheduler-260eeaf4-e650-4ea5-be21-998ad269de4c@172.17.0.3:58547 on agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0
I0313 01:15:20.187906 23975 exec.cpp:396] Executor received framework message
I0313 01:15:20.187928 23976 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.187978 23975 exec.cpp:405] Executor::frameworkMessage took 59486ns
I0313 01:15:20.188176 23972 slave.cpp:3106] Status update manager successfully handled status update acknowledgement (UUID: 0f4cfa4c-84f2-4ed5-9dba-40e4bdfd1c00) for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.188287 23976 slave.cpp:4325] Sending message for framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 to scheduler-260eeaf4-e650-4ea5-be21-998ad269de4c@172.17.0.3:58547
I0313 01:15:20.188431 23966 sched.cpp:1177] Scheduler::frameworkMessage took 51044ns
I0313 01:15:20.188674 23964 sched.cpp:2021] Asked to stop the driver
I0313 01:15:20.188745 23979 sched.cpp:1203] Stopping framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.188901 23978 master.cpp:7765] Processing TEARDOWN call for framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 (default) at scheduler-260eeaf4-e650-4ea5-be21-998ad269de4c@172.17.0.3:58547
I0313 01:15:20.188930 23978 master.cpp:7777] Removing framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 (default) at scheduler-260eeaf4-e650-4ea5-be21-998ad269de4c@172.17.0.3:58547
I0313 01:15:20.188949 23978 master.cpp:3148] Deactivating framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 (default) at scheduler-260eeaf4-e650-4ea5-be21-998ad269de4c@172.17.0.3:58547
I0313 01:15:20.189013 23978 master.cpp:8345] Updating the state of task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0313 01:15:20.189054 23970 slave.cpp:2664] Asked to shut down framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 by master@172.17.0.3:58547
I0313 01:15:20.189064 23965 hierarchical.cpp:376] Deactivated framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.189126 23970 slave.cpp:2689] Shutting down framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.189148 23970 slave.cpp:5076] Shutting down executor 'default' of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 at executor(120)@172.17.0.3:58547
I0313 01:15:20.189224 23972 exec.cpp:415] Executor asked to shutdown
I0313 01:15:20.189235 23978 master.cpp:8439] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 on agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 at slave(286)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.189297 23972 exec.cpp:430] Executor::shutdown took 60424ns
I0313 01:15:20.189371 23978 master.cpp:8468] Removing executor 'default' with resources {} of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 on agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 at slave(286)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.189393 23969 slave.cpp:4389] Got exited event for executor(120)@172.17.0.3:58547
I0313 01:15:20.189443 23965 hierarchical.cpp:1108] Recovered cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: {}) on agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 from framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.189735 23970 hierarchical.cpp:323] Removed framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.190750 23967 slave.cpp:4762] Executor 'default' of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 exited with status 0
I0313 01:15:20.190968 23967 slave.cpp:4862] Cleaning up executor 'default' of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 at executor(120)@172.17.0.3:58547
W0313 01:15:20.191000 23975 master.cpp:6367] Ignoring unknown exited executor 'default' of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000 on agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 at slave(286)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.191169 23978 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_f9RnpE/slaves/3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0/frameworks/3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000/executors/default/runs/9e5434b0-e4c7-4690-8c42-be8adfaa4a53' for gc 6.99999778804444days in the future
I0313 01:15:20.191251 23967 slave.cpp:4950] Cleaning up framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.191277 23973 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_f9RnpE/slaves/3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0/frameworks/3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000/executors/default' for gc 6.99999778669926days in the future
I0313 01:15:20.191323 23965 status_update_manager.cpp:285] Closing status update streams for framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.191345 23965 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000
I0313 01:15:20.191401 23967 slave.cpp:808] Agent terminating
I0313 01:15:20.191395 23970 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_f9RnpE/slaves/3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0/frameworks/3a03bb0a-86d6-47d2-b07d-42c37f352bff-0000' for gc 6.99999778542815days in the future
I0313 01:15:20.191522 23965 master.cpp:1317] Agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 at slave(286)@172.17.0.3:58547 (caad441f5cf0) disconnected
I0313 01:15:20.191550 23965 master.cpp:3185] Disconnecting agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 at slave(286)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.191684 23965 master.cpp:3204] Deactivating agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 at slave(286)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.191768 23967 hierarchical.cpp:655] Agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0 deactivated
I0313 01:15:20.192891 23964 master.cpp:1156] Master terminating
I0313 01:15:20.193161 23980 hierarchical.cpp:560] Removed agent 3a03bb0a-86d6-47d2-b07d-42c37f352bff-S0
[ OK ] MasterTest.FrameworkMessage (32 ms)
[ RUN ] MasterTest.MultipleExecutors
I0313 01:15:20.198256 23964 cluster.cpp:160] Creating default 'local' authorizer
I0313 01:15:20.199297 23971 master.cpp:437] Master 2b84c374-146e-4ab0-bcff-c6cff6a9d410 (caad441f5cf0) started on 172.17.0.3:58547
I0313 01:15:20.199331 23971 master.cpp:439] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/F4iGHD/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/F4iGHD/master" --zk_session_timeout="10secs"
I0313 01:15:20.199584 23971 master.cpp:489] Master only allowing authenticated frameworks to register
I0313 01:15:20.199594 23971 master.cpp:503] Master only allowing authenticated agents to register
I0313 01:15:20.199602 23971 master.cpp:516] Master only allowing authenticated HTTP frameworks to register
I0313 01:15:20.199609 23971 credentials.hpp:37] Loading credentials for authentication from '/tmp/F4iGHD/credentials'
I0313 01:15:20.199753 23971 master.cpp:561] Using default 'crammd5' authenticator
I0313 01:15:20.199805 23971 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0313 01:15:20.199878 23971 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0313 01:15:20.200040 23971 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0313 01:15:20.200150 23971 master.cpp:641] Authorization enabled
I0313 01:15:20.200382 23965 whitelist_watcher.cpp:77] No whitelist given
I0313 01:15:20.200412 23976 hierarchical.cpp:159] Initialized hierarchical allocator process
I0313 01:15:20.201289 23971 master.cpp:2165] Elected as the leading master!
I0313 01:15:20.201304 23971 master.cpp:1704] Recovering from registrar
I0313 01:15:20.201367 23980 registrar.cpp:331] Recovering registrar
I0313 01:15:20.201586 23969 registrar.cpp:364] Successfully fetched the registry (0B) in 189952ns
I0313 01:15:20.201668 23969 registrar.cpp:463] Applied 1 operations in 11669ns; attempting to update the registry
I0313 01:15:20.201911 23971 registrar.cpp:508] Successfully updated the registry in 220928ns
I0313 01:15:20.201952 23971 registrar.cpp:394] Successfully recovered registrar
I0313 01:15:20.202093 23971 master.cpp:1803] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0313 01:15:20.202194 23974 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover
I0313 01:15:20.204437 23964 cluster.cpp:446] Creating default 'local' authorizer
I0313 01:15:20.205027 23977 slave.cpp:215] Mesos agent started on (287)@172.17.0.3:58547
I0313 01:15:20.205056 23977 slave.cpp:216] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterTest_MultipleExecutors_dwr6sD/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_dwr6sD/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/MasterTest_MultipleExecutors_dwr6sD/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterTest_MultipleExecutors_dwr6sD" --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_PNSnAD"
I0313 01:15:20.205449 23977 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_dwr6sD/credential'
I0313 01:15:20.205597 23977 slave.cpp:358] Agent using credential for: test-principal
I0313 01:15:20.205610 23977 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_dwr6sD/http_credentials'
I0313 01:15:20.205749 23977 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0313 01:15:20.205816 23977 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0313 01:15:20.205891 23964 sched.cpp:232] Version: 1.3.0
I0313 01:15:20.206179 23966 sched.cpp:336] New master detected at master@172.17.0.3:58547
I0313 01:15:20.206223 23966 sched.cpp:407] Authenticating with master master@172.17.0.3:58547
I0313 01:15:20.206238 23966 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0313 01:15:20.206293 23977 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0313 01:15:20.206332 23977 slave.cpp:553] Agent attributes: [ ]
I0313 01:15:20.206341 23977 slave.cpp:558] Agent hostname: caad441f5cf0
I0313 01:15:20.206346 23967 authenticatee.cpp:121] Creating new client SASL connection
I0313 01:15:20.206396 23974 status_update_manager.cpp:177] Pausing sending status updates
I0313 01:15:20.206555 23980 master.cpp:7252] Authenticating scheduler-d6d99e36-8a15-496d-8ddd-1754f12f24f4@172.17.0.3:58547
I0313 01:15:20.206650 23979 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(635)@172.17.0.3:58547
I0313 01:15:20.206749 23979 authenticator.cpp:98] Creating new server SASL connection
I0313 01:15:20.206780 23977 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_PNSnAD/meta'
I0313 01:15:20.206975 23968 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0313 01:15:20.207002 23968 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0313 01:15:20.207057 23973 status_update_manager.cpp:203] Recovering status update manager
I0313 01:15:20.207083 23974 authenticator.cpp:204] Received SASL authentication start
I0313 01:15:20.207139 23974 authenticator.cpp:326] Authentication requires more steps
I0313 01:15:20.207231 23971 authenticatee.cpp:259] Received SASL authentication step
I0313 01:15:20.207312 23970 slave.cpp:5564] Finished recovery
I0313 01:15:20.207324 23980 authenticator.cpp:232] Received SASL authentication step
I0313 01:15:20.207352 23980 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'caad441f5cf0' server FQDN: 'caad441f5cf0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0313 01:15:20.207382 23980 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0313 01:15:20.207408 23980 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0313 01:15:20.207440 23980 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'caad441f5cf0' server FQDN: 'caad441f5cf0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0313 01:15:20.207474 23980 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0313 01:15:20.207489 23980 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0313 01:15:20.207515 23980 authenticator.cpp:318] Authentication success
I0313 01:15:20.207577 23979 authenticatee.cpp:299] Authentication success
I0313 01:15:20.207612 23969 master.cpp:7282] Successfully authenticated principal 'test-principal' at scheduler-d6d99e36-8a15-496d-8ddd-1754f12f24f4@172.17.0.3:58547
I0313 01:15:20.207636 23968 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(635)@172.17.0.3:58547
I0313 01:15:20.207752 23970 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0313 01:15:20.207782 23979 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:58547
I0313 01:15:20.207798 23979 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:58547
I0313 01:15:20.207859 23979 sched.cpp:869] Will retry registration in 286.441044ms if necessary
I0313 01:15:20.207911 23973 master.cpp:2817] Received SUBSCRIBE call for framework 'default' at scheduler-d6d99e36-8a15-496d-8ddd-1754f12f24f4@172.17.0.3:58547
I0313 01:15:20.207934 23973 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0313 01:15:20.207978 23970 slave.cpp:936] New master detected at master@172.17.0.3:58547
I0313 01:15:20.208000 23965 status_update_manager.cpp:177] Pausing sending status updates
I0313 01:15:20.208043 23970 slave.cpp:971] Detecting new master
I0313 01:15:20.208114 23980 master.cpp:2894] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0313 01:15:20.208313 23977 hierarchical.cpp:271] Added framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000
I0313 01:15:20.208324 23966 sched.cpp:759] Framework registered with 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000
I0313 01:15:20.208343 23970 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0313 01:15:20.208381 23966 sched.cpp:773] Scheduler::registered took 34917ns
I0313 01:15:20.208396 23977 hierarchical.cpp:1854] No allocations performed
I0313 01:15:20.208407 23977 hierarchical.cpp:1944] No inverse offers to send out!
I0313 01:15:20.208420 23977 hierarchical.cpp:1438] Performed allocation for 0 agents in 36521ns
I0313 01:15:20.210199 23978 slave.cpp:998] Authenticating with master master@172.17.0.3:58547
I0313 01:15:20.210225 23978 slave.cpp:1009] Using default CRAM-MD5 authenticatee
I0313 01:15:20.210301 23969 authenticatee.cpp:121] Creating new client SASL connection
I0313 01:15:20.210460 23969 master.cpp:7252] Authenticating slave(287)@172.17.0.3:58547
I0313 01:15:20.210530 23972 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(636)@172.17.0.3:58547
I0313 01:15:20.210630 23967 authenticator.cpp:98] Creating new server SASL connection
I0313 01:15:20.210825 23969 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0313 01:15:20.210845 23969 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0313 01:15:20.210875 23969 authenticator.cpp:204] Received SASL authentication start
I0313 01:15:20.210932 23969 authenticator.cpp:326] Authentication requires more steps
I0313 01:15:20.210983 23969 authenticatee.cpp:259] Received SASL authentication step
I0313 01:15:20.211040 23969 authenticator.cpp:232] Received SASL authentication step
I0313 01:15:20.211056 23969 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'caad441f5cf0' server FQDN: 'caad441f5cf0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0313 01:15:20.211064 23969 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0313 01:15:20.211073 23969 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0313 01:15:20.211083 23969 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'caad441f5cf0' server FQDN: 'caad441f5cf0' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0313 01:15:20.211093 23969 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0313 01:15:20.211103 23969 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0313 01:15:20.211115 23969 authenticator.cpp:318] Authentication success
I0313 01:15:20.211174 23967 authenticatee.cpp:299] Authentication success
I0313 01:15:20.211186 23969 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(287)@172.17.0.3:58547
I0313 01:15:20.211210 23977 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(636)@172.17.0.3:58547
I0313 01:15:20.211411 23969 slave.cpp:1093] Successfully authenticated with master master@172.17.0.3:58547
I0313 01:15:20.211475 23969 slave.cpp:1521] Will retry registration in 18.626826ms if necessary
I0313 01:15:20.211570 23976 master.cpp:5437] Registering agent at slave(287)@172.17.0.3:58547 (caad441f5cf0) with id 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0
I0313 01:15:20.211738 23974 registrar.cpp:463] Applied 1 operations in 32217ns; attempting to update the registry
I0313 01:15:20.212170 23974 registrar.cpp:508] Successfully updated the registry in 394240ns
I0313 01:15:20.212416 23966 slave.cpp:4347] Received ping from slave-observer(280)@172.17.0.3:58547
I0313 01:15:20.212450 23970 master.cpp:5511] Registered agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 at slave(287)@172.17.0.3:58547 (caad441f5cf0) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0313 01:15:20.212484 23966 slave.cpp:1139] Registered with master master@172.17.0.3:58547; given agent ID 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0
I0313 01:15:20.212523 23966 fetcher.cpp:94] Clearing fetcher cache
I0313 01:15:20.212543 23969 hierarchical.cpp:527] Added agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 (caad441f5cf0) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0313 01:15:20.212646 23978 status_update_manager.cpp:184] Resuming sending status updates
I0313 01:15:20.212865 23969 hierarchical.cpp:1944] No inverse offers to send out!
I0313 01:15:20.212872 23966 slave.cpp:1167] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_PNSnAD/meta/slaves/2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0/slave.info'
I0313 01:15:20.212883 23969 hierarchical.cpp:1438] Performed allocation for 1 agents in 269709ns
I0313 01:15:20.213022 23978 master.cpp:7081] Sending 1 offers to framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000 (default) at scheduler-d6d99e36-8a15-496d-8ddd-1754f12f24f4@172.17.0.3:58547
I0313 01:15:20.213191 23966 slave.cpp:1205] Forwarding total oversubscribed resources {}
I0313 01:15:20.213205 23975 sched.cpp:933] Scheduler::resourceOffers took 53304ns
I0313 01:15:20.213307 23975 master.cpp:6098] Received update of agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 at slave(287)@172.17.0.3:58547 (caad441f5cf0) with total oversubscribed resources {}
I0313 01:15:20.214022 23976 master.cpp:3839] Processing ACCEPT call for offers: [ 2b84c374-146e-4ab0-bcff-c6cff6a9d410-O0 ] on agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 at slave(287)@172.17.0.3:58547 (caad441f5cf0) for framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000 (default) at scheduler-d6d99e36-8a15-496d-8ddd-1754f12f24f4@172.17.0.3:58547
I0313 01:15:20.214067 23976 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 1
I0313 01:15:20.214155 23976 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 2
W0313 01:15:20.214790 23967 validation.cpp:1113] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0313 01:15:20.214815 23967 validation.cpp:1125] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0313 01:15:20.214897 23967 master.cpp:9097] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 at slave(287)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.214972 23967 master.cpp:4489] Launching task 1 of framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000 (default) at scheduler-d6d99e36-8a15-496d-8ddd-1754f12f24f4@172.17.0.3:58547 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 at slave(287)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.215183 23965 slave.cpp:1631] Got assigned task '1' for framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000
W0313 01:15:20.215279 23967 validation.cpp:1113] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0313 01:15:20.215301 23967 validation.cpp:1125] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0313 01:15:20.215389 23967 master.cpp:9097] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 at slave(287)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.215447 23967 master.cpp:4489] Launching task 2 of framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000 (default) at scheduler-d6d99e36-8a15-496d-8ddd-1754f12f24f4@172.17.0.3:58547 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 at slave(287)@172.17.0.3:58547 (caad441f5cf0)
I0313 01:15:20.215517 23965 slave.cpp:1791] Launching task '1' for framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000
I0313 01:15:20.215988 23975 hierarchical.cpp:844] Updated allocation of framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000 on agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 from cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
I0313 01:15:20.216111 23975 hierarchical.cpp:1108] Recovered ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 from framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000
I0313 01:15:20.216135 23975 hierarchical.cpp:1145] Framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000 filtered agent 2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0 for 5secs
I0313 01:15:20.216192 23965 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_PNSnAD/slaves/2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0/frameworks/2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000/executors/executor-1/runs/fa9ce576-445c-4f55-8195-d2460336361e' to user 'mesos'
I0313 01:15:20.216414 23965 slave.cpp:6491] Launching executor 'executor-1' of framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_PNSnAD/slaves/2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0/frameworks/2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000/executors/executor-1/runs/fa9ce576-445c-4f55-8195-d2460336361e'
I0313 01:15:20.216661 23965 slave.cpp:2124] Queued task '1' for executor 'executor-1' of framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000
I0313 01:15:20.216753 23965 slave.cpp:1631] Got assigned task '2' for framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000
I0313 01:15:20.217021 23965 slave.cpp:889] Successfully attached file '/tmp/MasterTest_MultipleExecutors_PNSnAD/slaves/2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0/frameworks/2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000/executors/executor-1/runs/fa9ce576-445c-4f55-8195-d2460336361e'
I0313 01:15:20.217058 23965 slave.cpp:1791] Launching task '2' for framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000
I0313 01:15:20.217470 23965 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_PNSnAD/slaves/2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0/frameworks/2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000/executors/executor-2/runs/5451c8c1-7f4c-4b14-bea2-1518c11286a3' to user 'mesos'
I0313 01:15:20.217643 23965 slave.cpp:6491] Launching executor 'executor-2' of framework 2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_PNSnAD/slaves/2b84c374-146e-4ab0-bcff-c6cff6a9d410-S0/frameworks/2b84c374-146e-4ab0-bcff-c6cff6a9d410-0000/executors/executor-2/runs/5451c8c1-7f4c-4b14-bea2-1518c11286a3'
*** Aborted at 1489367720 (unix time) try "date -d @1489367720" if you are using GNU date ***
I0313 01:15:20.217983 23968 exec.cpp:162] Version: 1.3.0
I0313 01:15:20.218108 23974 exec.cpp:212] Executor started at: executor(121)@172.17.0.3:58547 with pid 23964
PC: @ 0x2b55013a782d (unknown)
*** SIGSEGV (@0xc0) received by PID 23964 (TID 0x2b550832f700) from PID 192; stack trace: ***
@ 0x2b550093e330 (unknown)
@ 0x2b55013a782d (unknown)
@ 0x2b54fc326db5 mesos::internal::slave::executorEnvironment()
@ 0x2b54fc305496 mesos::internal::slave::Framework::launchExecutor()
@ 0x2b54fc301b9f mesos::internal::slave::Slave::_run()
@ 0x2b54fdb70fa7 process::ProcessManager::resume()
@ 0x2b54fdb86d2f std::thread::_Impl<>::_M_run()
@ 0x2b5500bfda60 (unknown)
@ 0x2b5500936184 start_thread
@ 0x2b550146637d (unknown)
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]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make: *** [check] Error 2
+ docker rmi mesos-1489365466-5116
Untagged: mesos-1489365466-5116:latest
Deleted: sha256:6a8f519cf0ae77930e0ff23a9b8d817e14916331dc970b2363cd087b6dc9d7c9
Deleted: sha256:aa3df2a6c97a31a293effc029daefa57af9914ca0751e18f5bf4507267a50a03
Deleted: sha256:15f0af0e238efd685ad047223b9915109e73cf3940e7ec2a3266762bb86b8451
Deleted: sha256:588a22a531e7b883695af7ed6f243bd752a1f6c51fd43e7fd03d04fe991daf73
Deleted: sha256:d5323bc814b7fc1fb44c48c85fd32c1f3a17981a116ded3406e0960fb1ea52dd
Deleted: sha256:5809723e8f7d6c5a6ef2074f69ee63080885ac8dd8f9350bcf9f8a0dddd622f8
Deleted: sha256:d14901d2d6ccfcdc7e038160e91d6543cd2d41a599f1217ce48bb7cca8a11fcb
Deleted: sha256:0bca808ebf6753622737caa7a9a33133a727a04cc83755c5e70b3296d5afefba
Deleted: sha256:02932ee895e9a178b564e29360beea4f9201d2edda47bd4dcccc35fa9b3a8b6a
Deleted: sha256:7dce0ded1e845a82bb0cb22ca2d46f830f1fa85593b99970a96b5f56d775088d
Deleted: sha256:1749db1f14087f1569d0cf7a42d7b5e8bbfd0669a25c187071923d7f73e82c89
Deleted: sha256:794961ebfafaea4fc30dd720a5a659b417d0395e066accec1f0cab52d9e2288d
Deleted: sha256:8ac505edf87bef643ffd1eab438f9191394e2812283a585639d5f45ff7b1fc2a
Deleted: sha256:fbfb764c2b39d0cabeb4d8d9801ef9572966a0f9cc18951cc8fa457d633d7bfc
Deleted: sha256:68a22fa089c7341d4aa0358a1e464e0d87a294c5108815bd5537c0eb3a9c9457
Deleted: sha256:90a9c3dca3dcd5150bf60d9d72d0639d54476b3509041238b7f8987eb4f3597b
Deleted: sha256:eaae9b866029294d5743478e8f236146c8a9e030b864067b3dacf071b9bb78c8
Deleted: sha256:9cb700722c38982b374df8f95017912e99df1b45cedcc6b109d7a6749c1f48f2
Deleted: sha256:40c927bf131a78c32762122c4225c046b0f524ebcd294c259316ab2ae2b45369
Deleted: sha256:ccb7c474b45c760bd56cdb4c4e40edf369ed08147adc7064038a9250d63903a1
Deleted: sha256:61f2b5c672756bb8ca3e435d4d7399809511ff13ff1fe95269bbbf1dca288256
Deleted: sha256:63f1db4907db7258c130d95041ae0ed94064b7cebf8fc144d818409fed539db7
Deleted: sha256:faf5d38cb6e4473e83d62e824ee76291d326246bdca2a68f6eb72e1ad7939c74
Deleted: sha256:41770aee9740670b8bf88a6df71362e7eb11b1f374368d924678631f2c9efbea
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3361
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3361/display/redirect?page=changes>