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/09 08:20:08 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) #3250
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)/3250/changes>
Changes:
[adam] Added framework principal to the agent state endpoint.
------------------------------------------
[...truncated 70282 lines...]
I0209 08:20:01.847573 30466 slave.cpp:4321] Sending message for framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 to scheduler-899fb314-d043-4985-91e3-33a5c068a5f1@172.17.0.2:41898
I0209 08:20:01.847862 30464 sched.cpp:1177] Scheduler::frameworkMessage took 144872ns
I0209 08:20:01.848304 30439 sched.cpp:2021] Asked to stop the driver
I0209 08:20:01.848399 30469 sched.cpp:1203] Stopping framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000
I0209 08:20:01.848623 30466 master.cpp:7644] Processing TEARDOWN call for framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 (default) at scheduler-899fb314-d043-4985-91e3-33a5c068a5f1@172.17.0.2:41898
I0209 08:20:01.848661 30466 master.cpp:7656] Removing framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 (default) at scheduler-899fb314-d043-4985-91e3-33a5c068a5f1@172.17.0.2:41898
I0209 08:20:01.848690 30466 master.cpp:3080] Deactivating framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 (default) at scheduler-899fb314-d043-4985-91e3-33a5c068a5f1@172.17.0.2:41898
I0209 08:20:01.848884 30461 hierarchical.cpp:415] Deactivated framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000
I0209 08:20:01.848963 30466 master.cpp:8241] Updating the state of task 1 of framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0209 08:20:01.849006 30470 slave.cpp:2655] Asked to shut down framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 by master@172.17.0.2:41898
I0209 08:20:01.849071 30470 slave.cpp:2680] Shutting down framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000
I0209 08:20:01.849167 30470 slave.cpp:5080] Shutting down executor 'default' of framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 at executor(74)@172.17.0.2:41898
I0209 08:20:01.849282 30463 exec.cpp:410] Executor asked to shutdown
I0209 08:20:01.849328 30463 exec.cpp:425] Executor::shutdown took 17688ns
I0209 08:20:01.849496 30465 slave.cpp:4385] Got exited event for executor(74)@172.17.0.2:41898
I0209 08:20:01.849931 30466 master.cpp:8335] Removing task 1 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 on agent 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0 at slave(179)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.850570 30466 master.cpp:8364] Removing executor 'default' with resources {} of framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 on agent 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0 at slave(179)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.850554 30461 hierarchical.cpp:1064] 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 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0 from framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000
I0209 08:20:01.850867 30461 slave.cpp:4766] Executor 'default' of framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 exited with status 0
I0209 08:20:01.850958 30461 slave.cpp:4866] Cleaning up executor 'default' of framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 at executor(74)@172.17.0.2:41898
I0209 08:20:01.851269 30472 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_zilUSJ/slaves/1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0/frameworks/1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000/executors/default/runs/351bd489-e894-4823-afa4-8d644e596585' for gc 6.99999014853037days in the future
I0209 08:20:01.851485 30461 slave.cpp:4954] Cleaning up framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000
I0209 08:20:01.851503 30463 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_zilUSJ/slaves/1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0/frameworks/1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000/executors/default' for gc 6.99999014585778days in the future
I0209 08:20:01.851605 30460 status_update_manager.cpp:285] Closing status update streams for framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000
I0209 08:20:01.851656 30460 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000
I0209 08:20:01.851905 30467 gc.cpp:55] Scheduling '/tmp/MasterTest_FrameworkMessage_zilUSJ/slaves/1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0/frameworks/1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000' for gc 6.99999014285926days in the future
I0209 08:20:01.851953 30461 slave.cpp:803] Agent terminating
W0209 08:20:01.851944 30466 master.cpp:6217] Ignoring unknown exited executor 'default' of framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000 on agent 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0 at slave(179)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.852186 30467 master.cpp:1263] Agent 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0 at slave(179)@172.17.0.2:41898 (fb0754746251) disconnected
I0209 08:20:01.852215 30467 master.cpp:3117] Disconnecting agent 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0 at slave(179)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.852267 30467 master.cpp:3136] Deactivating agent 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0 at slave(179)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.852325 30471 hierarchical.cpp:362] Removed framework 1ef33bc1-6d2d-4396-81c5-66f6961b6540-0000
I0209 08:20:01.852382 30471 hierarchical.cpp:618] Agent 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0 deactivated
I0209 08:20:01.854888 30461 master.cpp:1102] Master terminating
I0209 08:20:01.855654 30465 hierarchical.cpp:549] Removed agent 1ef33bc1-6d2d-4396-81c5-66f6961b6540-S0
[ OK ] MasterTest.FrameworkMessage (67 ms)
[ RUN ] MasterTest.MultipleExecutors
I0209 08:20:01.861083 30439 cluster.cpp:160] Creating default 'local' authorizer
I0209 08:20:01.863576 30464 master.cpp:383] Master 75dc2922-0fbf-49ca-b139-6cc5995f5ce5 (fb0754746251) started on 172.17.0.2:41898
I0209 08:20:01.863601 30464 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/BkKDBH/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/BkKDBH/master" --zk_session_timeout="10secs"
I0209 08:20:01.863987 30464 master.cpp:435] Master only allowing authenticated frameworks to register
I0209 08:20:01.864002 30464 master.cpp:449] Master only allowing authenticated agents to register
I0209 08:20:01.864008 30464 master.cpp:462] Master only allowing authenticated HTTP frameworks to register
I0209 08:20:01.864018 30464 credentials.hpp:37] Loading credentials for authentication from '/tmp/BkKDBH/credentials'
I0209 08:20:01.864265 30464 master.cpp:507] Using default 'crammd5' authenticator
I0209 08:20:01.864382 30464 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0209 08:20:01.864531 30464 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0209 08:20:01.864652 30464 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0209 08:20:01.864768 30464 master.cpp:587] Authorization enabled
I0209 08:20:01.864926 30472 whitelist_watcher.cpp:77] No whitelist given
I0209 08:20:01.864962 30473 hierarchical.cpp:161] Initialized hierarchical allocator process
I0209 08:20:01.866919 30460 master.cpp:2123] Elected as the leading master!
I0209 08:20:01.866950 30460 master.cpp:1645] Recovering from registrar
I0209 08:20:01.867094 30473 registrar.cpp:329] Recovering registrar
I0209 08:20:01.867720 30473 registrar.cpp:362] Successfully fetched the registry (0B) in 583936ns
I0209 08:20:01.867802 30473 registrar.cpp:461] Applied 1 operations in 14876ns; attempting to update the registry
I0209 08:20:01.868908 30473 registrar.cpp:506] Successfully updated the registry in 1.053184ms
I0209 08:20:01.869035 30473 registrar.cpp:392] Successfully recovered registrar
I0209 08:20:01.869468 30472 master.cpp:1761] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0209 08:20:01.869496 30465 hierarchical.cpp:188] Skipping recovery of hierarchical allocator: nothing to recover
I0209 08:20:01.875704 30439 cluster.cpp:446] Creating default 'local' authorizer
I0209 08:20:01.877264 30459 slave.cpp:211] Mesos agent started on (180)@172.17.0.2:41898
I0209 08:20:01.877290 30459 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_9WMLHG/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_9WMLHG/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_9WMLHG/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_9WMLHG" --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_id6G9G"
I0209 08:20:01.877756 30459 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_9WMLHG/credential'
I0209 08:20:01.877917 30459 slave.cpp:354] Agent using credential for: test-principal
I0209 08:20:01.877940 30459 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_9WMLHG/http_credentials'
I0209 08:20:01.878161 30459 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0209 08:20:01.878303 30459 http.cpp:919] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0209 08:20:01.878741 30439 sched.cpp:232] Version: 1.2.0
I0209 08:20:01.879235 30467 sched.cpp:336] New master detected at master@172.17.0.2:41898
I0209 08:20:01.879317 30467 sched.cpp:407] Authenticating with master master@172.17.0.2:41898
I0209 08:20:01.879335 30467 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0209 08:20:01.879554 30462 authenticatee.cpp:121] Creating new client SASL connection
I0209 08:20:01.879550 30459 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0209 08:20:01.879647 30459 slave.cpp:549] Agent attributes: [ ]
I0209 08:20:01.879669 30459 slave.cpp:554] Agent hostname: fb0754746251
I0209 08:20:01.879851 30461 master.cpp:7102] Authenticating scheduler-55106bce-f856-4db9-b2c2-d25a8df01d3d@172.17.0.2:41898
I0209 08:20:01.879987 30462 status_update_manager.cpp:177] Pausing sending status updates
I0209 08:20:01.880009 30463 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(411)@172.17.0.2:41898
I0209 08:20:01.880288 30467 authenticator.cpp:98] Creating new server SASL connection
I0209 08:20:01.880540 30462 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0209 08:20:01.880584 30462 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0209 08:20:01.880720 30468 authenticator.cpp:204] Received SASL authentication start
I0209 08:20:01.880800 30468 authenticator.cpp:326] Authentication requires more steps
I0209 08:20:01.880926 30468 authenticatee.cpp:259] Received SASL authentication step
I0209 08:20:01.881047 30468 authenticator.cpp:232] Received SASL authentication step
I0209 08:20:01.881081 30468 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fb0754746251' server FQDN: 'fb0754746251' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0209 08:20:01.881099 30468 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0209 08:20:01.881145 30468 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0209 08:20:01.881170 30468 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fb0754746251' server FQDN: 'fb0754746251' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0209 08:20:01.881192 30468 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0209 08:20:01.881209 30468 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0209 08:20:01.881232 30468 authenticator.cpp:318] Authentication success
I0209 08:20:01.881330 30463 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_id6G9G/meta'
I0209 08:20:01.881340 30460 authenticatee.cpp:299] Authentication success
I0209 08:20:01.881384 30467 master.cpp:7132] Successfully authenticated principal 'test-principal' at scheduler-55106bce-f856-4db9-b2c2-d25a8df01d3d@172.17.0.2:41898
I0209 08:20:01.881494 30464 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(411)@172.17.0.2:41898
I0209 08:20:01.881680 30466 status_update_manager.cpp:203] Recovering status update manager
I0209 08:20:01.881960 30461 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:41898
I0209 08:20:01.881989 30461 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:41898
I0209 08:20:01.882190 30461 sched.cpp:869] Will retry registration in 1.733268903secs if necessary
I0209 08:20:01.882259 30458 slave.cpp:5555] Finished recovery
I0209 08:20:01.882336 30471 master.cpp:2736] Received SUBSCRIBE call for framework 'default' at scheduler-55106bce-f856-4db9-b2c2-d25a8df01d3d@172.17.0.2:41898
I0209 08:20:01.882392 30471 master.cpp:2159] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0209 08:20:01.882778 30458 slave.cpp:5729] Querying resource estimator for oversubscribable resources
I0209 08:20:01.882778 30459 master.cpp:2812] Subscribing framework default with checkpointing disabled and capabilities [ ]
I0209 08:20:01.883019 30470 status_update_manager.cpp:177] Pausing sending status updates
I0209 08:20:01.883023 30458 slave.cpp:931] New master detected at master@172.17.0.2:41898
I0209 08:20:01.883198 30458 slave.cpp:966] Detecting new master
I0209 08:20:01.883318 30459 sched.cpp:759] Framework registered with 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000
I0209 08:20:01.883376 30458 slave.cpp:5743] Received oversubscribable resources {} from the resource estimator
I0209 08:20:01.883390 30459 sched.cpp:773] Scheduler::registered took 45327ns
I0209 08:20:01.883391 30468 hierarchical.cpp:286] Added framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000
I0209 08:20:01.883517 30468 hierarchical.cpp:1802] No allocations performed
I0209 08:20:01.883549 30468 hierarchical.cpp:1892] No inverse offers to send out!
I0209 08:20:01.883584 30468 hierarchical.cpp:1386] Performed allocation for 0 agents in 104451ns
I0209 08:20:01.893596 30467 slave.cpp:993] Authenticating with master master@172.17.0.2:41898
I0209 08:20:01.893664 30467 slave.cpp:1004] Using default CRAM-MD5 authenticatee
I0209 08:20:01.893859 30469 authenticatee.cpp:121] Creating new client SASL connection
I0209 08:20:01.894089 30473 master.cpp:7102] Authenticating slave(180)@172.17.0.2:41898
I0209 08:20:01.894187 30471 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(412)@172.17.0.2:41898
I0209 08:20:01.894377 30469 authenticator.cpp:98] Creating new server SASL connection
I0209 08:20:01.894582 30460 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0209 08:20:01.894615 30460 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0209 08:20:01.894726 30461 authenticator.cpp:204] Received SASL authentication start
I0209 08:20:01.894798 30461 authenticator.cpp:326] Authentication requires more steps
I0209 08:20:01.894917 30460 authenticatee.cpp:259] Received SASL authentication step
I0209 08:20:01.895035 30469 authenticator.cpp:232] Received SASL authentication step
I0209 08:20:01.895067 30469 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fb0754746251' server FQDN: 'fb0754746251' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0209 08:20:01.895090 30469 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0209 08:20:01.895136 30469 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0209 08:20:01.895172 30469 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fb0754746251' server FQDN: 'fb0754746251' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0209 08:20:01.895187 30469 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0209 08:20:01.895196 30469 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0209 08:20:01.895211 30469 authenticator.cpp:318] Authentication success
I0209 08:20:01.895298 30464 authenticatee.cpp:299] Authentication success
I0209 08:20:01.895334 30460 master.cpp:7132] Successfully authenticated principal 'test-principal' at slave(180)@172.17.0.2:41898
I0209 08:20:01.895402 30469 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(412)@172.17.0.2:41898
I0209 08:20:01.895599 30464 slave.cpp:1088] Successfully authenticated with master master@172.17.0.2:41898
I0209 08:20:01.895753 30464 slave.cpp:1515] Will retry registration in 73340ns if necessary
I0209 08:20:01.895939 30466 master.cpp:5317] Registering agent at slave(180)@172.17.0.2:41898 (fb0754746251) with id 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0
I0209 08:20:01.896399 30463 registrar.cpp:461] Applied 1 operations in 49097ns; attempting to update the registry
I0209 08:20:01.897241 30471 slave.cpp:1515] Will retry registration in 35.673163ms if necessary
I0209 08:20:01.897368 30470 master.cpp:5305] Ignoring register agent message from slave(180)@172.17.0.2:41898 (fb0754746251) as admission is already in progress
I0209 08:20:01.897836 30463 registrar.cpp:506] Successfully updated the registry in 1.378048ms
I0209 08:20:01.898538 30458 slave.cpp:4343] Received ping from slave-observer(177)@172.17.0.2:41898
I0209 08:20:01.898577 30459 master.cpp:5391] Registered agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 at slave(180)@172.17.0.2:41898 (fb0754746251) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0209 08:20:01.898684 30458 slave.cpp:1134] Registered with master master@172.17.0.2:41898; given agent ID 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0
I0209 08:20:01.898708 30458 fetcher.cpp:90] Clearing fetcher cache
I0209 08:20:01.898857 30466 status_update_manager.cpp:184] Resuming sending status updates
I0209 08:20:01.898888 30471 hierarchical.cpp:516] Added agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 (fb0754746251) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0209 08:20:01.899080 30458 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_id6G9G/meta/slaves/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0/slave.info'
I0209 08:20:01.899425 30458 slave.cpp:1200] Forwarding total oversubscribed resources {}
I0209 08:20:01.899582 30458 master.cpp:5948] Received update of agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 at slave(180)@172.17.0.2:41898 (fb0754746251) with total oversubscribed resources {}
I0209 08:20:01.900049 30471 hierarchical.cpp:1892] No inverse offers to send out!
I0209 08:20:01.900116 30471 hierarchical.cpp:1386] Performed allocation for 1 agents in 1.124442ms
I0209 08:20:01.900245 30471 hierarchical.cpp:589] Agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 (fb0754746251) 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])
I0209 08:20:01.900413 30471 hierarchical.cpp:1802] No allocations performed
I0209 08:20:01.900449 30471 hierarchical.cpp:1892] No inverse offers to send out!
I0209 08:20:01.900486 30471 hierarchical.cpp:1386] Performed allocation for 1 agents in 137042ns
I0209 08:20:01.900615 30472 master.cpp:6931] Sending 1 offers to framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000 (default) at scheduler-55106bce-f856-4db9-b2c2-d25a8df01d3d@172.17.0.2:41898
I0209 08:20:01.901121 30473 sched.cpp:933] Scheduler::resourceOffers took 136282ns
I0209 08:20:01.903244 30471 master.cpp:3744] Processing ACCEPT call for offers: [ 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-O0 ] on agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 at slave(180)@172.17.0.2:41898 (fb0754746251) for framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000 (default) at scheduler-55106bce-f856-4db9-b2c2-d25a8df01d3d@172.17.0.2:41898
I0209 08:20:01.903332 30471 master.cpp:3320] Authorizing framework principal 'test-principal' to launch task 1
I0209 08:20:01.903628 30471 master.cpp:3320] Authorizing framework principal 'test-principal' to launch task 2
W0209 08:20:01.905490 30470 validation.cpp:1064] 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.
W0209 08:20:01.905519 30470 validation.cpp:1076] 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.
I0209 08:20:01.905828 30470 master.cpp:8992] Adding task 1 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 at slave(180)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.905974 30470 master.cpp:4394] Launching task 1 of framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000 (default) at scheduler-55106bce-f856-4db9-b2c2-d25a8df01d3d@172.17.0.2:41898 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 at slave(180)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.906337 30458 slave.cpp:1625] Got assigned task '1' for framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000
I0209 08:20:01.906875 30458 slave.cpp:1785] Launching task '1' for framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000
W0209 08:20:01.906952 30470 validation.cpp:1064] 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.
W0209 08:20:01.906980 30470 validation.cpp:1076] 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.
I0209 08:20:01.907291 30470 master.cpp:8992] Adding task 2 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 at slave(180)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.907418 30458 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_id6G9G/slaves/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0/frameworks/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000/executors/executor-1/runs/ff7efc78-4299-40e0-af55-9ac12b6bab78' to user 'mesos'
I0209 08:20:01.907435 30470 master.cpp:4394] Launching task 2 of framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000 (default) at scheduler-55106bce-f856-4db9-b2c2-d25a8df01d3d@172.17.0.2:41898 with resources cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 at slave(180)@172.17.0.2:41898 (fb0754746251)
I0209 08:20:01.907641 30458 slave.cpp:6477] Launching executor 'executor-1' of framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_id6G9G/slaves/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0/frameworks/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000/executors/executor-1/runs/ff7efc78-4299-40e0-af55-9ac12b6bab78'
I0209 08:20:01.908188 30458 slave.cpp:2115] Queued task '1' for executor 'executor-1' of framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000
I0209 08:20:01.908370 30458 slave.cpp:1625] Got assigned task '2' for framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000
I0209 08:20:01.908756 30458 slave.cpp:884] Successfully attached file '/tmp/MasterTest_MultipleExecutors_id6G9G/slaves/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0/frameworks/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000/executors/executor-1/runs/ff7efc78-4299-40e0-af55-9ac12b6bab78'
I0209 08:20:01.908821 30458 slave.cpp:1785] Launching task '2' for framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000
I0209 08:20:01.909288 30458 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_id6G9G/slaves/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0/frameworks/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000/executors/executor-2/runs/5d627930-c8c4-4d09-8129-ced2779eb9fe' to user 'mesos'
I0209 08:20:01.909510 30458 slave.cpp:6477] Launching executor 'executor-2' of framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000 with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_id6G9G/slaves/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0/frameworks/75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000/executors/executor-2/runs/5d627930-c8c4-4d09-8129-ced2779eb9fe'
*** Aborted at 1486628401 (unix time) try "date -d @1486628401" if you are using GNU date ***
PC: @ 0x2b9a1c80a82d (unknown)
I0209 08:20:01.910089 30473 exec.cpp:162] Version: 1.2.0
*** SIGSEGV (@0xf0) received by PID 30439 (TID 0x2b9a24a39700) from PID 240; stack trace: ***
I0209 08:20:01.910339 30460 exec.cpp:212] Executor started at: executor(75)@172.17.0.2:41898 with pid 30439
I0209 08:20:01.911005 30464 hierarchical.cpp:807] Updated allocation of framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000 on agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-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]
@ 0x2b9a6dd822d7 (unknown)
I0209 08:20:01.911860 30464 hierarchical.cpp:1064] 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 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 from framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000
I0209 08:20:01.911931 30464 hierarchical.cpp:1101] Framework 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-0000 filtered agent 75dc2922-0fbf-49ca-b139-6cc5995f5ce5-S0 for 5secs
@ 0x2b9a6dd865b9 (unknown)
@ 0x2b9a1c5c1330 (unknown)
@ 0x2b9a1c80a82d (unknown)
@ 0x2b9a12553526 os::getenv()
@ 0x2b9a19563d81 mesos::internal::slave::executorEnvironment()
@ 0x2b9a1955e554 mesos::internal::slave::Framework::launchExecutor()
@ 0x2b9a1953839c mesos::internal::slave::Slave::_run()
@ 0x2b9a1959209f _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_
@ 0x2b9a195c6e1d _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_
@ 0x2b9a1a3047c7 std::function<>::operator()()
@ 0x2b9a1a2ea697 process::ProcessBase::visit()
@ 0x2b9a1a2f4f16 process::DispatchEvent::visit()
@ 0x2b9a1252786c process::ProcessBase::serve()
@ 0x2b9a1a2e6984 process::ProcessManager::resume()
@ 0x2b9a1a2e31c8 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x2b9a1a2f4650 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b9a1a2f45a7 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x2b9a1a2f4540 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2b9a1be42a60 (unknown)
@ 0x2b9a1c5b9184 start_thread
@ 0x2b9a1c8c937d (unknown)
make[4]: *** [check-local] Segmentation fault
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-1486625739-16154
Untagged: mesos-1486625739-16154:latest
Deleted: sha256:0decf570538b0f05172c780a591702890ec670d22a39afcfbc77d8afb8ebf2b9
Deleted: sha256:f56953a6fe7154b09be19e5a11ee9ad0a356d2c759ea0d961526202285d9b34e
Deleted: sha256:04ef1a4d402970a1c97caf785c187e3c6484f1871b7f74ddc7b4d95709c8ce54
Deleted: sha256:4300ca1ddff96515e05d3fe8ea350f850dbee493a71d6a6356e4ddd2a3ad09ac
Deleted: sha256:b01a068046a94591619e74b22a7c7c9967a6f2a1b2953dac2767e1968e0721ae
Deleted: sha256:f0674b7a96f2574af3e8211e7f8186757a73d7164d6c9951f4dfb6a33025257a
Deleted: sha256:8f13b581856fa3dd1b1af0903806e4dc621a25ed91d8aeb25039fbbd8990b327
Deleted: sha256:c4e684a1472dddebc4e73879aaa296b547c8dbebf794ecd40777c69f94777f47
Deleted: sha256:c069c53ad326dc2319292e05351f6e467c9d1f122bc7cae64d1843cbcc9d8c8b
Deleted: sha256:d6d7598c89fdc568308398653856b5d66988cf07d245cafc8f954e371faf1bc0
Deleted: sha256:92246d1aa612e9a98241fb1d13c57c54d05c16ec1337ef4217fd0f8952a43145
Deleted: sha256:d5efb11b58d6bfc652d4befb7cb3122776c953d71756719affdf8c050bfbb115
Deleted: sha256:9946491318c143a74f94e6229325034aa5c2b84df1e195b6c2978ad60a4f83d7
Deleted: sha256:8a08d42df28ba89f4db558dcff0985d3b3976209702dabf9c23ce3c98e436112
Deleted: sha256:5bb6247e5dcb040eebcb59555eb863de933cc0fd1fae5e4de63f4aff7fa14c59
Deleted: sha256:f6d3ab5fd9db5c8df7f2714e0522e6580fb4d84e8bb11f63bdbd03d3469f9f2e
Deleted: sha256:41c786e1651046b96b868939f6cb29f5374ec6b3598759d503e222556c76885a
Deleted: sha256:e64a6c2dd04a23f95d49788ce58e90ff7ec9ac8fc6a0e93aefba689ce82e4382
Deleted: sha256:cdb96d41b84f655613fbd83af76336645d22a393765ed92279df1dd2b689aa82
Deleted: sha256:84dff82c574d8f5b59d5490e275a57c2ba0fb5651db8a99690999e232806efb8
Deleted: sha256:32c9013915030691e6be390f3e1a9b26ccc39de7ee342b3533e1a6848e5ac47a
Deleted: sha256:381ec700b31b1109671838febb01c69f3d849ba7f8383bc7afed984a45cd2abe
Deleted: sha256:15bd38eed2b8d3796be08989acdfe57ed2a8ceee1f9d537b87b3153922b106c3
Deleted: sha256:1ac5d162508584671f151db3a1521a30cdec284cf1afb3b2fc76909053821d90
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) #3251
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)/3251/changes>