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/02/06 21:44:04 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3234
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3234/changes>
Changes:
[bmahler] Removed an unnecessary copy of operations in the allocator.
------------------------------------------
[...truncated 70108 lines...]
I0206 21:43:57.684690 30278 slave.cpp:3060] Status update manager successfully handled status update acknowledgement (UUID: cb270050-15f5-4905-82ad-cc2469d05145) for task 1 of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.684738 30281 exec.cpp:391] Executor received framework message
I0206 21:43:57.684834 30281 exec.cpp:400] Executor::frameworkMessage took 69073ns
I0206 21:43:57.685534 30280 slave.cpp:4279] Sending message for framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 to scheduler-049ab287-de25-4c6f-bb70-d6743d6aec1b@172.17.0.2:40030
I0206 21:43:57.685771 30288 sched.cpp:1177] Scheduler::frameworkMessage took 63346ns
I0206 21:43:57.686172 30259 sched.cpp:2021] Asked to stop the driver
I0206 21:43:57.686286 30283 sched.cpp:1203] Stopping framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.686547 30286 master.cpp:7645] Processing TEARDOWN call for framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 (default) at scheduler-049ab287-de25-4c6f-bb70-d6743d6aec1b@172.17.0.2:40030
I0206 21:43:57.686588 30286 master.cpp:7657] Removing framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 (default) at scheduler-049ab287-de25-4c6f-bb70-d6743d6aec1b@172.17.0.2:40030
I0206 21:43:57.686666 30286 master.cpp:3080] Deactivating framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 (default) at scheduler-049ab287-de25-4c6f-bb70-d6743d6aec1b@172.17.0.2:40030
I0206 21:43:57.686909 30284 hierarchical.cpp:415] Deactivated framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.686995 30279 slave.cpp:2613] Asked to shut down framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 by master@172.17.0.2:40030
I0206 21:43:57.687048 30279 slave.cpp:2638] Shutting down framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.687037 30286 master.cpp:8242] Updating the state of task 1 of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0206 21:43:57.687116 30279 slave.cpp:5020] Shutting down executor 'default' of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 at executor(74)@172.17.0.2:40030
I0206 21:43:57.687250 30291 exec.cpp:410] Executor asked to shutdown
I0206 21:43:57.687305 30291 exec.cpp:425] Executor::shutdown took 24744ns
I0206 21:43:57.687590 30278 slave.cpp:4343] Got exited event for executor(74)@172.17.0.2:40030
I0206 21:43:57.688552 30286 master.cpp:8336] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 on agent 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0 at slave(179)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.688925 30288 hierarchical.cpp:1079] 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 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0 from framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.688982 30286 master.cpp:8365] Removing executor 'default' with resources {} of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 on agent 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0 at slave(179)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.689235 30288 slave.cpp:4706] Executor 'default' of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 exited with status 0
I0206 21:43:57.689340 30288 slave.cpp:4806] Cleaning up executor 'default' of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 at executor(74)@172.17.0.2:40030
I0206 21:43:57.689926 30282 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_YiZZSg/slaves/35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0/frameworks/35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000/executors/default/runs/3ad69a6d-d6c1-4881-986e-a8d14502f4aa' for gc 6.99999201908444days in the future
I0206 21:43:57.690016 30288 slave.cpp:4894] Cleaning up framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.690086 30282 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_YiZZSg/slaves/35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0/frameworks/35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000/executors/default' for gc 6.99999201454815days in the future
W0206 21:43:57.690104 30286 master.cpp:6218] Ignoring unknown exited executor 'default' of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000 on agent 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0 at slave(179)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.690217 30286 status_update_manager.cpp:285] Closing status update streams for framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.690273 30286 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.690325 30289 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_YiZZSg/slaves/35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0/frameworks/35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000' for gc 6.99999201112296days in the future
I0206 21:43:57.690343 30288 slave.cpp:803] Agent terminating
I0206 21:43:57.690491 30283 hierarchical.cpp:362] Removed framework 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-0000
I0206 21:43:57.690604 30283 master.cpp:1263] Agent 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0 at slave(179)@172.17.0.2:40030 (ad516774ad7e) disconnected
I0206 21:43:57.690634 30283 master.cpp:3117] Disconnecting agent 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0 at slave(179)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.690711 30283 master.cpp:3136] Deactivating agent 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0 at slave(179)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.690914 30288 hierarchical.cpp:617] Agent 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0 deactivated
I0206 21:43:57.693859 30292 master.cpp:1102] Master terminating
I0206 21:43:57.694737 30279 hierarchical.cpp:548] Removed agent 35772e42-7ed0-4942-a7dc-1b1b79ad7f29-S0
[ OK ] MasterTest.FrameworkMessage (70 ms)
[ RUN ] MasterTest.MultipleExecutors
I0206 21:43:57.702189 30259 cluster.cpp:160] Creating default 'local' authorizer
I0206 21:43:57.704994 30283 master.cpp:383] Master a57c2828-2426-4052-88a5-afa6271cbd46 (ad516774ad7e) started on 172.17.0.2:40030
I0206 21:43:57.705024 30283 master.cpp:385] 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/qgMBWY/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.2.0/_inst/share/mesos/webui" --work_dir="/tmp/qgMBWY/master" --zk_session_timeout="10secs"
I0206 21:43:57.705534 30283 master.cpp:435] Master only allowing authenticated frameworks to register
I0206 21:43:57.705559 30283 master.cpp:449] Master only allowing authenticated agents to register
I0206 21:43:57.705579 30283 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0206 21:43:57.705598 30283 credentials.hpp:37] Loading credentials for authentication from '/tmp/qgMBWY/credentials'
I0206 21:43:57.705929 30283 master.cpp:507] Using default 'crammd5' authenticator
I0206 21:43:57.706094 30283 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0206 21:43:57.706279 30283 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0206 21:43:57.706459 30283 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0206 21:43:57.706619 30283 master.cpp:587] Authorization enabled
I0206 21:43:57.706873 30280 hierarchical.cpp:161] Initialized hierarchical allocator process
I0206 21:43:57.706884 30289 whitelist_watcher.cpp:77] No whitelist given
I0206 21:43:57.709683 30291 master.cpp:2123] Elected as the leading master!
I0206 21:43:57.709719 30291 master.cpp:1645] Recovering from registrar
I0206 21:43:57.709970 30288 registrar.cpp:329] Recovering registrar
I0206 21:43:57.711140 30287 registrar.cpp:362] Successfully fetched the registry (0B) in 1.106176ms
I0206 21:43:57.711259 30287 registrar.cpp:461] Applied 1 operations in 18987ns; attempting to update the registry
I0206 21:43:57.712384 30287 registrar.cpp:506] Successfully updated the registry in 1.062912ms
I0206 21:43:57.712533 30287 registrar.cpp:392] Successfully recovered registrar
I0206 21:43:57.713156 30279 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0206 21:43:57.713222 30291 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0206 21:43:57.718230 30259 cluster.cpp:446] Creating default 'local' authorizer
I0206 21:43:57.719904 30287 slave.cpp:211] Mesos agent started on (180)@172.17.0.2:40030
I0206 21:43:57.719928 30287 slave.cpp:212] 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_EZWBeQ/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_EZWBeQ/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_EZWBeQ/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.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_EZWBeQ" --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_xJcBAp"
I0206 21:43:57.720729 30287 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_EZWBeQ/credential'
I0206 21:43:57.720973 30287 slave.cpp:354] Agent using credential for: test-principal
I0206 21:43:57.720998 30287 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_EZWBeQ/http_credentials'
I0206 21:43:57.721191 30259 sched.cpp:232] Version: 1.2.0
I0206 21:43:57.721295 30287 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0206 21:43:57.721529 30287 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0206 21:43:57.721845 30291 sched.cpp:336] New master detected at master@172.17.0.2:40030
I0206 21:43:57.721933 30291 sched.cpp:407] Authenticating with master master@172.17.0.2:40030
I0206 21:43:57.721951 30291 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0206 21:43:57.722164 30293 authenticatee.cpp:121] Creating new client SASL connection
I0206 21:43:57.722391 30291 master.cpp:7103] Authenticating scheduler-799161d6-000f-4e54-b36b-7b0f5d0b7f0f@172.17.0.2:40030
I0206 21:43:57.722496 30284 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(413)@172.17.0.2:40030
I0206 21:43:57.722687 30291 authenticator.cpp:98] Creating new server SASL connection
I0206 21:43:57.722887 30292 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0206 21:43:57.722918 30292 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0206 21:43:57.722883 30287 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0206 21:43:57.723021 30292 authenticator.cpp:204] Received SASL authentication start
I0206 21:43:57.723052 30287 slave.cpp:549] Agent attributes: [ ]
I0206 21:43:57.723069 30287 slave.cpp:554] Agent hostname: ad516774ad7e
I0206 21:43:57.723098 30292 authenticator.cpp:326] Authentication requires more steps
I0206 21:43:57.723250 30292 status_update_manager.cpp:177] Pausing sending status updates
I0206 21:43:57.723263 30282 authenticatee.cpp:259] Received SASL authentication step
I0206 21:43:57.723373 30293 authenticator.cpp:232] Received SASL authentication step
I0206 21:43:57.723407 30293 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ad516774ad7e' server FQDN: 'ad516774ad7e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0206 21:43:57.723428 30293 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0206 21:43:57.723469 30293 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0206 21:43:57.723492 30293 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ad516774ad7e' server FQDN: 'ad516774ad7e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0206 21:43:57.723502 30293 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0206 21:43:57.723512 30293 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0206 21:43:57.723527 30293 authenticator.cpp:318] Authentication success
I0206 21:43:57.723678 30288 master.cpp:7133] Successfully authenticated principal 'test-principal' at scheduler-799161d6-000f-4e54-b36b-7b0f5d0b7f0f@172.17.0.2:40030
I0206 21:43:57.723691 30290 authenticatee.cpp:299] Authentication success
I0206 21:43:57.723709 30281 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(413)@172.17.0.2:40030
I0206 21:43:57.723968 30290 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:40030
I0206 21:43:57.723989 30290 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:40030
I0206 21:43:57.724068 30290 sched.cpp:869] Will retry registration in 928.191535ms if necessary
I0206 21:43:57.724196 30281 master.cpp:2736] Received SUBSCRIBE call for framework 'default' at scheduler-799161d6-000f-4e54-b36b-7b0f5d0b7f0f@172.17.0.2:40030
I0206 21:43:57.724246 30281 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0206 21:43:57.724591 30285 state.cpp:60] Recovering state from '/tmp/MasterTest_MultipleExecutors_xJcBAp/meta'
I0206 21:43:57.724635 30278 master.cpp:2812] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0206 21:43:57.724839 30284 status_update_manager.cpp:203] Recovering status update manager
I0206 21:43:57.725195 30290 slave.cpp:5499] Finished recovery
I0206 21:43:57.725342 30280 sched.cpp:759] Framework registered with a57c2828-2426-4052-88a5-afa6271cbd46-0000
I0206 21:43:57.725410 30280 sched.cpp:773] Scheduler::registered took 38681ns
I0206 21:43:57.725494 30287 hierarchical.cpp:286] Added framework a57c2828-2426-4052-88a5-afa6271cbd46-0000
I0206 21:43:57.725611 30287 hierarchical.cpp:1817] No allocations performed
I0206 21:43:57.725638 30290 slave.cpp:5673] Querying resource estimator for oversubscribable resources
I0206 21:43:57.725646 30287 hierarchical.cpp:1907] No inverse offers to send out!
I0206 21:43:57.725702 30287 hierarchical.cpp:1401] Performed allocation for 0 agents in 127911ns
I0206 21:43:57.725869 30288 slave.cpp:5687] Received oversubscribable resources {} from the resource estimator
I0206 21:43:57.726032 30288 slave.cpp:931] New master detected at master@172.17.0.2:40030
I0206 21:43:57.726076 30286 status_update_manager.cpp:177] Pausing sending status updates
I0206 21:43:57.726117 30288 slave.cpp:966] Detecting new master
I0206 21:43:57.731426 30284 slave.cpp:993] Authenticating with master master@172.17.0.2:40030
I0206 21:43:57.731489 30284 slave.cpp:1004] Using default CRAM-MD5 authenticatee
I0206 21:43:57.731686 30288 authenticatee.cpp:121] Creating new client SASL connection
I0206 21:43:57.731905 30279 master.cpp:7103] Authenticating slave(180)@172.17.0.2:40030
I0206 21:43:57.732023 30283 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(414)@172.17.0.2:40030
I0206 21:43:57.732314 30287 authenticator.cpp:98] Creating new server SASL connection
I0206 21:43:57.732589 30289 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0206 21:43:57.732614 30289 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0206 21:43:57.732821 30282 authenticator.cpp:204] Received SASL authentication start
I0206 21:43:57.732898 30282 authenticator.cpp:326] Authentication requires more steps
I0206 21:43:57.733038 30278 authenticatee.cpp:259] Received SASL authentication step
I0206 21:43:57.733176 30282 authenticator.cpp:232] Received SASL authentication step
I0206 21:43:57.733211 30282 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ad516774ad7e' server FQDN: 'ad516774ad7e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0206 21:43:57.733228 30282 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0206 21:43:57.733264 30282 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0206 21:43:57.733289 30282 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'ad516774ad7e' server FQDN: 'ad516774ad7e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0206 21:43:57.733304 30282 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0206 21:43:57.733314 30282 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0206 21:43:57.733335 30282 authenticator.cpp:318] Authentication success
I0206 21:43:57.733422 30278 authenticatee.cpp:299] Authentication success
I0206 21:43:57.733505 30291 master.cpp:7133] Successfully authenticated principal 'test-principal' at slave(180)@172.17.0.2:40030
I0206 21:43:57.733562 30281 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(414)@172.17.0.2:40030
I0206 21:43:57.733845 30279 slave.cpp:1088] Successfully authenticated with master master@172.17.0.2:40030
I0206 21:43:57.734028 30279 slave.cpp:1515] Will retry registration in 19.856811ms if necessary
I0206 21:43:57.734251 30288 master.cpp:5318] Registering agent at slave(180)@172.17.0.2:40030 (ad516774ad7e) with id a57c2828-2426-4052-88a5-afa6271cbd46-S0
I0206 21:43:57.734726 30289 registrar.cpp:461] Applied 1 operations in 59293ns; attempting to update the registry
I0206 21:43:57.736433 30289 registrar.cpp:506] Successfully updated the registry in 1.630976ms
I0206 21:43:57.737337 30290 slave.cpp:4301] Received ping from slave-observer(178)@172.17.0.2:40030
I0206 21:43:57.737485 30291 master.cpp:5392] Registered agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 at slave(180)@172.17.0.2:40030 (ad516774ad7e) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0206 21:43:57.737594 30292 slave.cpp:1134] Registered with master master@172.17.0.2:40030; given agent ID a57c2828-2426-4052-88a5-afa6271cbd46-S0
I0206 21:43:57.737640 30292 fetcher.cpp:90] Clearing fetcher cache
I0206 21:43:57.737829 30289 status_update_manager.cpp:184] Resuming sending status updates
I0206 21:43:57.737907 30290 hierarchical.cpp:515] Added agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 (ad516774ad7e) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0206 21:43:57.738091 30292 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_xJcBAp/meta/slaves/a57c2828-2426-4052-88a5-afa6271cbd46-S0/slave.info'
I0206 21:43:57.738486 30292 slave.cpp:1200] Forwarding total oversubscribed resources {}
I0206 21:43:57.738669 30288 master.cpp:5949] Received update of agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 at slave(180)@172.17.0.2:40030 (ad516774ad7e) with total oversubscribed resources {}
I0206 21:43:57.739339 30290 hierarchical.cpp:1907] No inverse offers to send out!
I0206 21:43:57.739404 30290 hierarchical.cpp:1401] Performed allocation for 1 agents in 1.358476ms
I0206 21:43:57.739550 30290 hierarchical.cpp:588] Agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 (ad516774ad7e) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000])
I0206 21:43:57.739758 30290 hierarchical.cpp:1817] No allocations performed
I0206 21:43:57.739805 30290 hierarchical.cpp:1907] No inverse offers to send out!
I0206 21:43:57.739852 30290 hierarchical.cpp:1401] Performed allocation for 1 agents in 179806ns
I0206 21:43:57.739889 30292 master.cpp:6932] Sending 1 offers to framework a57c2828-2426-4052-88a5-afa6271cbd46-0000 (default) at scheduler-799161d6-000f-4e54-b36b-7b0f5d0b7f0f@172.17.0.2:40030
I0206 21:43:57.740458 30279 sched.cpp:933] Scheduler::resourceOffers took 144697ns
I0206 21:43:57.743022 30283 master.cpp:3745] Processing ACCEPT call for offers: [ a57c2828-2426-4052-88a5-afa6271cbd46-O0 ] on agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 at slave(180)@172.17.0.2:40030 (ad516774ad7e) for framework a57c2828-2426-4052-88a5-afa6271cbd46-0000 (default) at scheduler-799161d6-000f-4e54-b36b-7b0f5d0b7f0f@172.17.0.2:40030
I0206 21:43:57.743144 30283 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 1
I0206 21:43:57.743459 30283 master.cpp:3318] Authorizing framework principal 'test-principal' to launch task 2
W0206 21:43:57.746047 30293 validation.cpp:1048] 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.
W0206 21:43:57.746085 30293 validation.cpp:1060] 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.
I0206 21:43:57.746539 30293 master.cpp:8993] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 at slave(180)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.746786 30293 master.cpp:4395] Launching task 1 of framework a57c2828-2426-4052-88a5-afa6271cbd46-0000 (default) at scheduler-799161d6-000f-4e54-b36b-7b0f5d0b7f0f@172.17.0.2:40030 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 at slave(180)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.747284 30288 slave.cpp:1583] Got assigned task '1' for framework a57c2828-2426-4052-88a5-afa6271cbd46-0000
I0206 21:43:57.748034 30288 slave.cpp:1743] Launching task '1' for framework a57c2828-2426-4052-88a5-afa6271cbd46-0000
W0206 21:43:57.748225 30293 validation.cpp:1048] 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.
W0206 21:43:57.748260 30293 validation.cpp:1060] 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.
I0206 21:43:57.748703 30293 master.cpp:8993] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 at slave(180)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.748770 30288 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_xJcBAp/slaves/a57c2828-2426-4052-88a5-afa6271cbd46-S0/frameworks/a57c2828-2426-4052-88a5-afa6271cbd46-0000/executors/executor-1/runs/5d09e67f-9bc8-46bd-bba8-cd610afc38b6' to user 'mesos'
I0206 21:43:57.748900 30293 master.cpp:4395] Launching task 2 of framework a57c2828-2426-4052-88a5-afa6271cbd46-0000 (default) at scheduler-799161d6-000f-4e54-b36b-7b0f5d0b7f0f@172.17.0.2:40030 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 at slave(180)@172.17.0.2:40030 (ad516774ad7e)
I0206 21:43:57.749052 30288 slave.cpp:6414] Launching executor 'executor-1' of framework a57c2828-2426-4052-88a5-afa6271cbd46-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_xJcBAp/slaves/a57c2828-2426-4052-88a5-afa6271cbd46-S0/frameworks/a57c2828-2426-4052-88a5-afa6271cbd46-0000/executors/executor-1/runs/5d09e67f-9bc8-46bd-bba8-cd610afc38b6'
I0206 21:43:57.749768 30288 slave.cpp:2073] Queued task '1' for executor 'executor-1' of framework a57c2828-2426-4052-88a5-afa6271cbd46-0000
I0206 21:43:57.749989 30288 slave.cpp:1583] Got assigned task '2' for framework a57c2828-2426-4052-88a5-afa6271cbd46-0000
I0206 21:43:57.750531 30288 slave.cpp:884] Successfully attached file '/tmp/MasterTest_MultipleExecutors_xJcBAp/slaves/a57c2828-2426-4052-88a5-afa6271cbd46-S0/frameworks/a57c2828-2426-4052-88a5-afa6271cbd46-0000/executors/executor-1/runs/5d09e67f-9bc8-46bd-bba8-cd610afc38b6'
I0206 21:43:57.750620 30288 slave.cpp:1743] Launching task '2' for framework a57c2828-2426-4052-88a5-afa6271cbd46-0000
I0206 21:43:57.750843 30290 hierarchical.cpp:1079] 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 a57c2828-2426-4052-88a5-afa6271cbd46-S0 from framework a57c2828-2426-4052-88a5-afa6271cbd46-0000
I0206 21:43:57.750924 30290 hierarchical.cpp:1116] Framework a57c2828-2426-4052-88a5-afa6271cbd46-0000 filtered agent a57c2828-2426-4052-88a5-afa6271cbd46-S0 for 5secs
I0206 21:43:57.751147 30288 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_xJcBAp/slaves/a57c2828-2426-4052-88a5-afa6271cbd46-S0/frameworks/a57c2828-2426-4052-88a5-afa6271cbd46-0000/executors/executor-2/runs/369a559b-d958-4fc2-a2dc-72b930122284' to user 'mesos'
I0206 21:43:57.751394 30288 slave.cpp:6414] Launching executor 'executor-2' of framework a57c2828-2426-4052-88a5-afa6271cbd46-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_xJcBAp/slaves/a57c2828-2426-4052-88a5-afa6271cbd46-S0/frameworks/a57c2828-2426-4052-88a5-afa6271cbd46-0000/executors/executor-2/runs/369a559b-d958-4fc2-a2dc-72b930122284'
*** Aborted at 1486417437 (unix time) try "date -d @1486417437" if you are using GNU date ***
PC: @ 0x2ab53a58182d (unknown)
I0206 21:43:57.752024 30282 exec.cpp:162] Version: 1.2.0
*** SIGSEGV (@0xf0) received by PID 30259 (TID 0x2ab543bba700) from PID 240; stack trace: ***
I0206 21:43:57.752285 30278 exec.cpp:212] Executor started at: executor(75)@172.17.0.2:40030 with pid 30259
@ 0x2ab5507d9197 (unknown)
@ 0x2ab5507dd479 (unknown)
@ 0x2ab53a338330 (unknown)
@ 0x2ab53a58182d (unknown)
@ 0x2ab530350586 os::getenv()
@ 0x2ab5373116cd mesos::internal::slave::executorEnvironment()
@ 0x2ab53730c2ce mesos::internal::slave::Framework::launchExecutor()
@ 0x2ab5372e66a2 mesos::internal::slave::Slave::_run()
@ 0x2ab53733fb4b _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_
@ 0x2ab537374829 _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_
@ 0x2ab53809507d std::function<>::operator()()
@ 0x2ab53807ae7f process::ProcessBase::visit()
@ 0x2ab5380856fe process::DispatchEvent::visit()
@ 0x2ab5303248cc process::ProcessBase::serve()
@ 0x2ab53807716c process::ProcessManager::resume()
@ 0x2ab5380739b0 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x2ab538084e38 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2ab538084d8f _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x2ab538084d28 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2ab539bb9a60 (unknown)
@ 0x2ab53a330184 start_thread
@ 0x2ab53a64037d (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.2.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1486414702-7189
Untagged: mesos-1486414702-7189:latest
Deleted: sha256:f02f9d8c512a7def3b705860eb4a6f0b809bfa632d81f2794706f32aa4c39c86
Deleted: sha256:6647ec6fa6a2f70a609916c0bbe5d694f7ff6f6d38024ae5c3d17d8339f60834
Deleted: sha256:ab8de5dcd7c1e1585725965a5654abdc6dd2c50f326e8ad86a11a24b02079c76
Deleted: sha256:4bf28d90f3f97661da0d1787a82f87e86565076f357412f8e20398c3e362bfe5
Deleted: sha256:2e729ed4b1e4db32f4eab051cfeaedb3ae357858c10018cad92cd1d5db05d661
Deleted: sha256:4e3189bb2aa9fc6bb194338929a968a8d50c52f8e94088ec331b8c70fde8bfba
Deleted: sha256:df3722a960b71a0d320b15661bd24f49d245c91a1d92da9b2cf23a4e7a653887
Deleted: sha256:dc94d5fbae8798760da2f86d49524f0212bb61c1c237e621140900a4daf5205b
Deleted: sha256:8303d690301258078bfbf27c065de6875e526cbdff162296bbc4cd151ee7958b
Deleted: sha256:13fbe3c2ca47073ec684de3921575ad5cf22bb36c11b9d14fdcb5e12c1fd979b
Deleted: sha256:3d970ea731e4e684ceb9802fd015aeb5cefbf31bf3b3a470b83ebe85e83d0ce2
Deleted: sha256:c8608fbfe5077f47134f1fe1caeced7b3ed8588a5fb7874354ec6c457b63462c
Deleted: sha256:6d8e80bae6c6e780ae24d6a8e51c17998cfd743c85bc774bf1d0a0429e3fc557
Deleted: sha256:038f0222342b58173fa1b693eb09a5a72e0a4dba9052c76d6dd249386ae6ea14
Deleted: sha256:0ce6a9bdd4e2f8b20561f78c9d84a8094b7d01451db627ef87b75bd051bbb4c0
Deleted: sha256:91aeb5737a36ac8a2a3ce06862b56bef10deb83a8a70725139c6a0dd7e92a541
Deleted: sha256:c17d4e2f9c417dd233c9fe197d18df6d09eaa739b42e5d15f29d66716e862914
Deleted: sha256:90db544b8ffd9efb1ce40644333725ccbd09b5a729dd590dc00889bd39822868
Deleted: sha256:48a58b760d34527f6e79c4fe2b36adc7a0869dc9ea611586e742c96361b157cd
Deleted: sha256:b318089fd54b5772dc2be2625c5f3a04338585e9eca4295d8a70fd7515f98020
Deleted: sha256:19ef8de923912a852e56e16d848f37f51cbf3e9da754f87a471df8d592f811e6
Deleted: sha256:3bdaafbe9c1b21e846766be9a1915af3f3a23674fe378d2683d7b63973ea5917
Deleted: sha256:9710304f79e9f67d97052742952b865c188c252e2c6cb700f5fe03bd89435463
Deleted: sha256:6fddabad37e172c453b1af11ba5fa49abc3746e220663950aa935568589af14f
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3235
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3235/changes>