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 19:14:16 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3364
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3364/display/redirect?page=changes>
Changes:
[anand] Fixed indentation in process.cpp.
[alexr] Added `Containerizer::remove`.
[alexr] Introduced proto changes needed for the RemoveNestedContainer API call.
[alexr] Introduced changes to the authz protos needed for RemoveNestedContainer.
[alexr] Made the usage of C++ namespaces in "slave/http.cpp" consistent.
[alexr] Implemented the Agent API call `RemoveNestedContainer`.
[alexr] Added documentation for the 'RemoveNestedContainer' Agent API call.
[alexr] Removed unnecessary curly braces wrapping case statements.
------------------------------------------
[...truncated 10.79 MB...]
I0313 19:14:06.803449 32709 status_update_manager.cpp:395] Received status update acknowledgement (UUID: a8f5dea8-0f71-4b50-b19e-faeedcec96c0) for task 1 of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.803658 32720 exec.cpp:396] Executor received framework message
I0313 19:14:06.803773 32720 exec.cpp:405] Executor::frameworkMessage took 79956ns
I0313 19:14:06.803864 32711 slave.cpp:3106] Status update manager successfully handled status update acknowledgement (UUID: a8f5dea8-0f71-4b50-b19e-faeedcec96c0) for task 1 of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.804500 32719 slave.cpp:4325] Sending message for framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 to scheduler-002d8dcc-da29-4b69-8d9f-dd857c7f94eb@172.17.0.3:36284
I0313 19:14:06.804771 32719 sched.cpp:1177] Scheduler::frameworkMessage took 75219ns
I0313 19:14:06.805310 32689 sched.cpp:2021] Asked to stop the driver
I0313 19:14:06.805418 32711 sched.cpp:1203] Stopping framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.805713 32723 master.cpp:7765] Processing TEARDOWN call for framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 (default) at scheduler-002d8dcc-da29-4b69-8d9f-dd857c7f94eb@172.17.0.3:36284
I0313 19:14:06.805753 32723 master.cpp:7777] Removing framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 (default) at scheduler-002d8dcc-da29-4b69-8d9f-dd857c7f94eb@172.17.0.3:36284
I0313 19:14:06.805785 32723 master.cpp:3148] Deactivating framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 (default) at scheduler-002d8dcc-da29-4b69-8d9f-dd857c7f94eb@172.17.0.3:36284
I0313 19:14:06.806076 32717 hierarchical.cpp:376] Deactivated framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.806174 32708 slave.cpp:2664] Asked to shut down framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 by master@172.17.0.3:36284
I0313 19:14:06.806183 32723 master.cpp:8345] Updating the state of task 1 of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0313 19:14:06.806237 32708 slave.cpp:2689] Shutting down framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.806303 32708 slave.cpp:5076] Shutting down executor 'default' of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 at executor(74)@172.17.0.3:36284
I0313 19:14:06.806433 32714 exec.cpp:415] Executor asked to shutdown
I0313 19:14:06.806493 32714 exec.cpp:430] Executor::shutdown took 26157ns
I0313 19:14:06.807109 32710 slave.cpp:4389] Got exited event for executor(74)@172.17.0.3:36284
I0313 19:14:06.807580 32723 master.cpp:8439] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 on agent 47dc8a66-5b2e-4888-8220-805bacff6cab-S0 at slave(179)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.807901 32712 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 47dc8a66-5b2e-4888-8220-805bacff6cab-S0 from framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.808260 32723 master.cpp:8468] Removing executor 'default' with resources {} of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 on agent 47dc8a66-5b2e-4888-8220-805bacff6cab-S0 at slave(179)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.808544 32713 slave.cpp:4762] Executor 'default' of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 exited with status 0
I0313 19:14:06.808681 32713 slave.cpp:4862] Cleaning up executor 'default' of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 at executor(74)@172.17.0.3:36284
I0313 19:14:06.809116 32719 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_Ke940K/slaves/47dc8a66-5b2e-4888-8220-805bacff6cab-S0/frameworks/47dc8a66-5b2e-4888-8220-805bacff6cab-0000/executors/default/runs/6b2b1858-f1f9-491c-b320-1b7c820791dc' for gc 6.99999063798519days in the future
I0313 19:14:06.809201 32713 slave.cpp:4950] Cleaning up framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.809372 32710 hierarchical.cpp:323] Removed framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.809415 32715 status_update_manager.cpp:285] Closing status update streams for framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
W0313 19:14:06.809442 32723 master.cpp:6367] Ignoring unknown exited executor 'default' of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000 on agent 47dc8a66-5b2e-4888-8220-805bacff6cab-S0 at slave(179)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.809507 32719 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_Ke940K/slaves/47dc8a66-5b2e-4888-8220-805bacff6cab-S0/frameworks/47dc8a66-5b2e-4888-8220-805bacff6cab-0000/executors/default' for gc 6.99999063503407days in the future
I0313 19:14:06.809522 32715 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 47dc8a66-5b2e-4888-8220-805bacff6cab-0000
I0313 19:14:06.809887 32714 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_Ke940K/slaves/47dc8a66-5b2e-4888-8220-805bacff6cab-S0/frameworks/47dc8a66-5b2e-4888-8220-805bacff6cab-0000' for gc 6.99999062714074days in the future
I0313 19:14:06.809926 32713 slave.cpp:808] Agent terminating
I0313 19:14:06.810174 32712 master.cpp:1317] Agent 47dc8a66-5b2e-4888-8220-805bacff6cab-S0 at slave(179)@172.17.0.3:36284 (15f529e648a6) disconnected
I0313 19:14:06.810201 32712 master.cpp:3185] Disconnecting agent 47dc8a66-5b2e-4888-8220-805bacff6cab-S0 at slave(179)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.810281 32712 master.cpp:3204] Deactivating agent 47dc8a66-5b2e-4888-8220-805bacff6cab-S0 at slave(179)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.810446 32722 hierarchical.cpp:655] Agent 47dc8a66-5b2e-4888-8220-805bacff6cab-S0 deactivated
I0313 19:14:06.813172 32716 master.cpp:1156] Master terminating
I0313 19:14:06.813853 32717 hierarchical.cpp:560] Removed agent 47dc8a66-5b2e-4888-8220-805bacff6cab-S0
[ OK ] MasterTest.FrameworkMessage (84 ms)
[ RUN ] MasterTest.MultipleExecutors
I0313 19:14:06.822197 32689 cluster.cpp:160] Creating default 'local' authorizer
I0313 19:14:06.824661 32718 master.cpp:437] Master d831cc82-06a3-43a2-b3a3-dff09de9dc9f (15f529e648a6) started on 172.17.0.3:36284
I0313 19:14:06.824781 32718 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/9f6NkO/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="/mesos/mesos-1.3.0/_inst/share/mesos/webui" --work_dir="/tmp/9f6NkO/master" --zk_session_timeout="10secs"
I0313 19:14:06.825358 32718 master.cpp:489] Master only allowing authenticated frameworks to register
I0313 19:14:06.825384 32718 master.cpp:503] Master only allowing authenticated agents to register
I0313 19:14:06.825402 32718 master.cpp:516] Master only allowing authenticated HTTP frameworks to register
I0313 19:14:06.825419 32718 credentials.hpp:37] Loading credentials for authentication from '/tmp/9f6NkO/credentials'
I0313 19:14:06.825829 32718 master.cpp:561] Using default 'crammd5' authenticator
I0313 19:14:06.825984 32718 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0313 19:14:06.826174 32718 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0313 19:14:06.826326 32718 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0313 19:14:06.826474 32718 master.cpp:641] Authorization enabled
I0313 19:14:06.826632 32715 hierarchical.cpp:159] Initialized hierarchical allocator process
I0313 19:14:06.826642 32714 whitelist_watcher.cpp:77] No whitelist given
I0313 19:14:06.829313 32719 master.cpp:2165] Elected as the leading master!
I0313 19:14:06.829344 32719 master.cpp:1704] Recovering from registrar
I0313 19:14:06.829483 32713 registrar.cpp:331] Recovering registrar
I0313 19:14:06.830235 32713 registrar.cpp:364] Successfully fetched the registry (0B) in 702208ns
I0313 19:14:06.830345 32713 registrar.cpp:463] Applied 1 operations in 21507ns; attempting to update the registry
I0313 19:14:06.831488 32713 registrar.cpp:508] Successfully updated the registry in 1.078016ms
I0313 19:14:06.831625 32713 registrar.cpp:394] Successfully recovered registrar
I0313 19:14:06.832207 32711 master.cpp:1803] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0313 19:14:06.832265 32720 hierarchical.cpp:186] Skipping recovery of hierarchical allocator: nothing to recover
I0313 19:14:06.836910 32689 cluster.cpp:446] Creating default 'local' authorizer
I0313 19:14:06.838994 32708 slave.cpp:215] Mesos agent started on (180)@172.17.0.3:36284
I0313 19:14:06.839023 32708 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_NtwRhQ/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_NtwRhQ/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_NtwRhQ/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.3.0/_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_NtwRhQ" --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_4Nckjk"
I0313 19:14:06.839696 32708 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_NtwRhQ/credential'
I0313 19:14:06.839840 32708 slave.cpp:358] Agent using credential for: test-principal
I0313 19:14:06.839859 32708 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_NtwRhQ/http_credentials'
I0313 19:14:06.840131 32708 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0313 19:14:06.840282 32708 http.cpp:964] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0313 19:14:06.840339 32689 sched.cpp:232] Version: 1.3.0
I0313 19:14:06.841054 32721 sched.cpp:336] New master detected at master@172.17.0.3:36284
I0313 19:14:06.841171 32721 sched.cpp:407] Authenticating with master master@172.17.0.3:36284
I0313 19:14:06.841195 32721 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0313 19:14:06.841460 32714 authenticatee.cpp:121] Creating new client SASL connection
I0313 19:14:06.841737 32713 master.cpp:7252] Authenticating scheduler-d3be7716-f1d6-4cd2-942f-2acd0b034aa8@172.17.0.3:36284
I0313 19:14:06.841886 32719 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(413)@172.17.0.3:36284
I0313 19:14:06.841856 32708 slave.cpp:545] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0313 19:14:06.841949 32708 slave.cpp:553] Agent attributes: [ ]
I0313 19:14:06.841964 32708 slave.cpp:558] Agent hostname: 15f529e648a6
I0313 19:14:06.842160 32709 authenticator.cpp:98] Creating new server SASL connection
I0313 19:14:06.842198 32712 status_update_manager.cpp:177] Pausing sending status updates
I0313 19:14:06.842490 32715 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0313 19:14:06.842532 32715 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0313 19:14:06.842672 32719 authenticator.cpp:204] Received SASL authentication start
I0313 19:14:06.842859 32719 authenticator.cpp:326] Authentication requires more steps
I0313 19:14:06.843029 32711 authenticatee.cpp:259] Received SASL authentication step
I0313 19:14:06.843189 32711 authenticator.cpp:232] Received SASL authentication step
I0313 19:14:06.843225 32711 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '15f529e648a6' server FQDN: '15f529e648a6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0313 19:14:06.843348 32711 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0313 19:14:06.843407 32711 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0313 19:14:06.843463 32711 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '15f529e648a6' server FQDN: '15f529e648a6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0313 19:14:06.843484 32711 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0313 19:14:06.843499 32711 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0313 19:14:06.843523 32711 authenticator.cpp:318] Authentication success
I0313 19:14:06.843698 32723 authenticatee.cpp:299] Authentication success
I0313 19:14:06.843703 32714 master.cpp:7282] Successfully authenticated principal 'test-principal' at scheduler-d3be7716-f1d6-4cd2-942f-2acd0b034aa8@172.17.0.3:36284
I0313 19:14:06.843845 32722 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_4Nckjk/meta'
I0313 19:14:06.843884 32717 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(413)@172.17.0.3:36284
I0313 19:14:06.844243 32715 sched.cpp:513] Successfully authenticated with master master@172.17.0.3:36284
I0313 19:14:06.844274 32715 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.3:36284
I0313 19:14:06.844311 32713 status_update_manager.cpp:203] Recovering status update manager
I0313 19:14:06.844482 32715 sched.cpp:869] Will retry registration in 1.113404816secs if necessary
I0313 19:14:06.844650 32723 master.cpp:2817] Received SUBSCRIBE call for framework 'default' at scheduler-d3be7716-f1d6-4cd2-942f-2acd0b034aa8@172.17.0.3:36284
I0313 19:14:06.844804 32723 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0313 19:14:06.844921 32708 slave.cpp:5564] Finished recovery
I0313 19:14:06.845469 32719 master.cpp:2894] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0313 19:14:06.845521 32708 slave.cpp:5746] Querying resource estimator for oversubscribable resources
I0313 19:14:06.845855 32713 slave.cpp:936] New master detected at master@172.17.0.3:36284
I0313 19:14:06.845878 32720 status_update_manager.cpp:177] Pausing sending status updates
I0313 19:14:06.845991 32713 slave.cpp:971] Detecting new master
I0313 19:14:06.846251 32713 slave.cpp:5760] Received oversubscribable resources {} from the resource estimator
I0313 19:14:06.846271 32710 sched.cpp:759] Framework registered with d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000
I0313 19:14:06.846315 32723 hierarchical.cpp:271] Added framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000
I0313 19:14:06.846330 32710 sched.cpp:773] Scheduler::registered took 31640ns
I0313 19:14:06.846499 32723 hierarchical.cpp:1854] No allocations performed
I0313 19:14:06.846556 32723 hierarchical.cpp:1944] No inverse offers to send out!
I0313 19:14:06.846631 32723 hierarchical.cpp:1438] Performed allocation for 0 agents in 187094ns
I0313 19:14:06.853346 32721 slave.cpp:998] Authenticating with master master@172.17.0.3:36284
I0313 19:14:06.853466 32721 slave.cpp:1009] Using default CRAM-MD5 authenticatee
I0313 19:14:06.853693 32708 authenticatee.cpp:121] Creating new client SASL connection
I0313 19:14:06.853950 32721 master.cpp:7252] Authenticating slave(180)@172.17.0.3:36284
I0313 19:14:06.854161 32720 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(414)@172.17.0.3:36284
I0313 19:14:06.854401 32712 authenticator.cpp:98] Creating new server SASL connection
I0313 19:14:06.854609 32716 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0313 19:14:06.854636 32716 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0313 19:14:06.854745 32716 authenticator.cpp:204] Received SASL authentication start
I0313 19:14:06.854813 32716 authenticator.cpp:326] Authentication requires more steps
I0313 19:14:06.854914 32716 authenticatee.cpp:259] Received SASL authentication step
I0313 19:14:06.855027 32716 authenticator.cpp:232] Received SASL authentication step
I0313 19:14:06.855058 32716 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '15f529e648a6' server FQDN: '15f529e648a6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0313 19:14:06.855080 32716 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0313 19:14:06.855129 32716 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0313 19:14:06.855167 32716 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '15f529e648a6' server FQDN: '15f529e648a6' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0313 19:14:06.855185 32716 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0313 19:14:06.855192 32716 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0313 19:14:06.855211 32716 authenticator.cpp:318] Authentication success
I0313 19:14:06.855370 32716 authenticatee.cpp:299] Authentication success
I0313 19:14:06.855458 32716 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(180)@172.17.0.3:36284
I0313 19:14:06.855556 32716 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(414)@172.17.0.3:36284
I0313 19:14:06.855855 32716 slave.cpp:1093] Successfully authenticated with master master@172.17.0.3:36284
I0313 19:14:06.856051 32716 slave.cpp:1521] Will retry registration in 1.49768ms if necessary
I0313 19:14:06.856366 32716 master.cpp:5437] Registering agent at slave(180)@172.17.0.3:36284 (15f529e648a6) with id d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0
I0313 19:14:06.856863 32715 registrar.cpp:463] Applied 1 operations in 61824ns; attempting to update the registry
I0313 19:14:06.858383 32715 registrar.cpp:508] Successfully updated the registry in 1.410048ms
I0313 19:14:06.858647 32711 slave.cpp:1521] Will retry registration in 8.935017ms if necessary
I0313 19:14:06.858858 32708 master.cpp:5425] Ignoring register agent message from slave(180)@172.17.0.3:36284 (15f529e648a6) as admission is already in progress
I0313 19:14:06.859647 32709 slave.cpp:4347] Received ping from slave-observer(177)@172.17.0.3:36284
I0313 19:14:06.859730 32708 master.cpp:5511] Registered agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 at slave(180)@172.17.0.3:36284 (15f529e648a6) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0313 19:14:06.859858 32709 slave.cpp:1139] Registered with master master@172.17.0.3:36284; given agent ID d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0
I0313 19:14:06.859886 32709 fetcher.cpp:94] Clearing fetcher cache
I0313 19:14:06.860011 32721 hierarchical.cpp:527] Added agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 (15f529e648a6) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0313 19:14:06.860062 32711 status_update_manager.cpp:184] Resuming sending status updates
I0313 19:14:06.860332 32709 slave.cpp:1167] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_4Nckjk/meta/slaves/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0/slave.info'
I0313 19:14:06.860832 32709 slave.cpp:1205] Forwarding total oversubscribed resources {}
I0313 19:14:06.861011 32709 master.cpp:6098] Received update of agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 at slave(180)@172.17.0.3:36284 (15f529e648a6) with total oversubscribed resources {}
I0313 19:14:06.861292 32721 hierarchical.cpp:1944] No inverse offers to send out!
I0313 19:14:06.861361 32721 hierarchical.cpp:1438] Performed allocation for 1 agents in 1.215605ms
I0313 19:14:06.861742 32718 master.cpp:7081] Sending 1 offers to framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000 (default) at scheduler-d3be7716-f1d6-4cd2-942f-2acd0b034aa8@172.17.0.3:36284
I0313 19:14:06.862323 32723 sched.cpp:933] Scheduler::resourceOffers took 146545ns
I0313 19:14:06.865097 32711 master.cpp:3839] Processing ACCEPT call for offers: [ d831cc82-06a3-43a2-b3a3-dff09de9dc9f-O0 ] on agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 at slave(180)@172.17.0.3:36284 (15f529e648a6) for framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000 (default) at scheduler-d3be7716-f1d6-4cd2-942f-2acd0b034aa8@172.17.0.3:36284
I0313 19:14:06.865237 32711 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 1
I0313 19:14:06.865622 32711 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 2
W0313 19:14:06.868243 32721 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 19:14:06.868286 32721 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 19:14:06.868929 32721 master.cpp:9097] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 at slave(180)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.869199 32721 master.cpp:4489] Launching task 1 of framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000 (default) at scheduler-d3be7716-f1d6-4cd2-942f-2acd0b034aa8@172.17.0.3:36284 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 at slave(180)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.869706 32712 slave.cpp:1631] Got assigned task '1' for framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000
I0313 19:14:06.870364 32712 slave.cpp:1791] Launching task '1' for framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000
W0313 19:14:06.870656 32721 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 19:14:06.870695 32721 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 19:14:06.870941 32712 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_4Nckjk/slaves/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0/frameworks/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000/executors/executor-1/runs/44cef36a-0412-4ab1-befc-77a6f023b41d' to user 'mesos'
I0313 19:14:06.871196 32712 slave.cpp:6491] Launching executor 'executor-1' of framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_4Nckjk/slaves/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0/frameworks/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000/executors/executor-1/runs/44cef36a-0412-4ab1-befc-77a6f023b41d'
I0313 19:14:06.871178 32721 master.cpp:9097] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 at slave(180)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.871462 32721 master.cpp:4489] Launching task 2 of framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000 (default) at scheduler-d3be7716-f1d6-4cd2-942f-2acd0b034aa8@172.17.0.3:36284 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 at slave(180)@172.17.0.3:36284 (15f529e648a6)
I0313 19:14:06.871706 32712 slave.cpp:2124] Queued task '1' for executor 'executor-1' of framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000
I0313 19:14:06.871779 32712 slave.cpp:889] Successfully attached file '/tmp/MasterTest_MultipleExecutors_4Nckjk/slaves/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0/frameworks/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000/executors/executor-1/runs/44cef36a-0412-4ab1-befc-77a6f023b41d'
I0313 19:14:06.872251 32712 slave.cpp:1631] Got assigned task '2' for framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000
I0313 19:14:06.872715 32712 slave.cpp:1791] Launching task '2' for framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000
I0313 19:14:06.873153 32712 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_4Nckjk/slaves/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0/frameworks/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000/executors/executor-2/runs/320d2968-d054-45a3-b6df-6ed4f97b74c8' to user 'mesos'
I0313 19:14:06.873366 32712 slave.cpp:6491] Launching executor 'executor-2' of framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_4Nckjk/slaves/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0/frameworks/d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000/executors/executor-2/runs/320d2968-d054-45a3-b6df-6ed4f97b74c8'
*** Aborted at 1489432446 (unix time) try "date -d @1489432446" if you are using GNU date ***
PC: @ 0x2b88f798382d (unknown)
I0313 19:14:06.873967 32717 exec.cpp:162] Version: 1.3.0
*** SIGSEGV (@0x31) received by PID 32689 (TID 0x2b8900eca700) from PID 49; stack trace: ***
I0313 19:14:06.874264 32718 exec.cpp:212] Executor started at: executor(75)@172.17.0.3:36284 with pid 32689
I0313 19:14:06.874822 32709 hierarchical.cpp:844] Updated allocation of framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000 on agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-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 19:14:06.875435 32709 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 d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 from framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000
I0313 19:14:06.875489 32709 hierarchical.cpp:1145] Framework d831cc82-06a3-43a2-b3a3-dff09de9dc9f-0000 filtered agent d831cc82-06a3-43a2-b3a3-dff09de9dc9f-S0 for 5secs
@ 0x2b894979c2d7 (unknown)
@ 0x2b89497a05b9 (unknown)
@ 0x2b88f773a330 (unknown)
@ 0x2b88f798382d (unknown)
@ 0x2b88ed0bb4d6 os::getenv()
@ 0x2b88f4255e5d mesos::internal::slave::executorEnvironment()
@ 0x2b88f4250620 mesos::internal::slave::Framework::launchExecutor()
@ 0x2b88f422a5d9 mesos::internal::slave::Slave::_run()
@ 0x2b88f4284605 _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureIbEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSF_INS1_13TaskGroupInfoEES6_S9_SC_SH_SL_EEvRKNS_3PIDIT_EEMSP_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES16_
@ 0x2b88f42ba1a1 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureIbEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSJ_INS5_13TaskGroupInfoEESA_SD_SG_SL_SP_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x2b88f5025031 std::function<>::operator()()
@ 0x2b88f500adf9 process::ProcessBase::visit()
@ 0x2b88f5015732 process::DispatchEvent::visit()
@ 0x2b88ed08f886 process::ProcessBase::serve()
@ 0x2b88f50070e6 process::ProcessManager::resume()
@ 0x2b88f500392a _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x2b88f5014e6c _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b88f5014dc3 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x2b88f5014d5c _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2b88f6fbba60 (unknown)
@ 0x2b88f7732184 start_thread
@ 0x2b88f7a4237d (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1489429334-5466
Untagged: mesos-1489429334-5466:latest
Deleted: sha256:108e8d9da38be7bcdb24605dcbef2c7243c0e66a088747b1fed6b1d67efde7d2
Deleted: sha256:abbe5714064cc50582e13364752a68dfc8da0baa060ed8e266ca9c73dcadc4d0
Deleted: sha256:655e30b2168c3e2565aae76500c179939bccb26e38f45d96e05e0ea0fd220153
Deleted: sha256:0cfa181bb7786b0d0702bcf420bad60b6344372e21b78ce7d99d9c47e8c601d7
Deleted: sha256:b7bc3baf689547044c93e45e2a60b1dcd33e6cc5ce3dfd406ba827965d17d92e
Deleted: sha256:71a42290a293ff7ab70265071f21fdc1fd6d5d1f10e22c079f5a4ff752135f20
Deleted: sha256:3eb779b7e8ba1eb8f2c350bbb119c826cc4f0ca6233e73987a61e81fbbfc4d64
Deleted: sha256:d8cc3414d6e62f3c63f41185ed5aff81e186084ed7bebc3ec5eeeaf1b3d66666
Deleted: sha256:b3cb9d94eeb877eac5582e889b24dab276d260ffc595c7a6e03376f6e36b1f00
Deleted: sha256:bb1fad5939299fa4808e4ffc205e91521a8f589483d9a38258600ae8c36b75f0
Deleted: sha256:39364c6876c7335233ee61d68ef458fcd3563f61ae0b73b02fcfd95c479ab956
Deleted: sha256:bc3cf4aa9468ad9cacb0725f5fbdd162846eb9a54a224bc432c2fc40b53672be
Deleted: sha256:4f1c1f44dcb2f833278b8e82876ca37bd4e2ae911b770455b42ac99a4fcc5d8e
Deleted: sha256:f634431f1f9beb7303f04036ead2821e860a270f2290358b2b815d83a8988969
Deleted: sha256:71d986a3d371f58ec4a6bb616a6319a2504a8ae1f8d410291f94e7a93a9207da
Deleted: sha256:93061fe5d20880d5002766f3997c58e0fab5f52baf9dd2821012a91d4661df12
Deleted: sha256:a802adc2640b4e86304fe2a00ffd51ec40b96720e7837081b46e107d442e9fc5
Deleted: sha256:f031122a0ea885ed65f3f120bd0c460692a2e26e727ebb11e000fbe48516b852
Deleted: sha256:ef975a94d5eb36f102cd5b732b1f66d0700ef96be5550db6c1d042b960c03f52
Deleted: sha256:f10df2fc64688aa9a7f6400195f4e83660403e61a8dc24a650fa9163298cd734
Deleted: sha256:b3cd7fb9c83b025f878954ef5b95fa45454f9a7628455b3fcf36eabbaf6a3d53
Deleted: sha256:b8b9a407119cc51bbe3dde950d89054906f6a11d3a5f909329a76ca7d7f86a07
Deleted: sha256:fc07fc7a5252eb2eafd352cb65f08e56a00c8f1c19783f7c0b9561fe46eec170
Deleted: sha256:0449268046a05d1014176b509b6570ff45005983a4711674b97fb457ad9cbb8c
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3365
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3365/display/redirect?page=changes>