You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/05/12 00:07:04 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3663
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3663/display/redirect?page=changes>
Changes:
[neil.conway] Documented that Mesos 1.3.0, 1.2.1 don't allow old agents to register.
------------------------------------------
[...truncated 4.62 MB...]
I0512 00:06:47.828968 24071 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterMaintenanceTest_InverseOffersFilters_S0Tb97/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/MasterMaintenanceTest_InverseOffersFilters_S0Tb97/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname="maintenance-host" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/MasterMaintenanceTest_InverseOffersFilters_S0Tb97/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_S0Tb97" --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/MasterMaintenanceTest_InverseOffersFilters_YbFsdK"
I0512 00:06:47.829157 24071 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_S0Tb97/credential'
I0512 00:06:47.829221 24071 slave.cpp:258] Agent using credential for: test-principal
I0512 00:06:47.829231 24071 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_S0Tb97/http_credentials'
I0512 00:06:47.829298 24071 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0512 00:06:47.829339 24071 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0512 00:06:47.829802 24071 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0512 00:06:47.829831 24071 slave.cpp:533] Agent attributes: [ ]
I0512 00:06:47.829835 24071 slave.cpp:538] Agent hostname: maintenance-host
I0512 00:06:47.829887 24073 status_update_manager.cpp:177] Pausing sending status updates
I0512 00:06:47.830248 24060 state.cpp:62] Recovering state from '/tmp/MasterMaintenanceTest_InverseOffersFilters_YbFsdK/meta'
I0512 00:06:47.830435 24053 status_update_manager.cpp:203] Recovering status update manager
I0512 00:06:47.830590 24069 slave.cpp:5970] Finished recovery
I0512 00:06:47.830907 24069 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0512 00:06:47.831055 24073 status_update_manager.cpp:177] Pausing sending status updates
I0512 00:06:47.831059 24069 slave.cpp:918] New master detected at master@172.17.0.2:38459
I0512 00:06:47.831092 24069 slave.cpp:953] Detecting new master
I0512 00:06:47.831151 24069 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0512 00:06:47.840288 24066 slave.cpp:980] Authenticating with master master@172.17.0.2:38459
I0512 00:06:47.840329 24066 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0512 00:06:47.840411 24053 authenticatee.cpp:121] Creating new client SASL connection
I0512 00:06:47.840615 24065 master.cpp:7475] Authenticating slave(93)@172.17.0.2:38459
I0512 00:06:47.840672 24069 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(182)@172.17.0.2:38459
I0512 00:06:47.840760 24063 authenticator.cpp:98] Creating new server SASL connection
I0512 00:06:47.840948 24053 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0512 00:06:47.840968 24053 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0512 00:06:47.841008 24053 authenticator.cpp:204] Received SASL authentication start
I0512 00:06:47.841063 24053 authenticator.cpp:326] Authentication requires more steps
I0512 00:06:47.841145 24075 authenticatee.cpp:259] Received SASL authentication step
I0512 00:06:47.841222 24075 authenticator.cpp:232] Received SASL authentication step
I0512 00:06:47.841244 24075 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0e8f2673f2ff' server FQDN: '0e8f2673f2ff' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0512 00:06:47.841259 24075 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0512 00:06:47.841274 24075 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0512 00:06:47.841284 24075 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0e8f2673f2ff' server FQDN: '0e8f2673f2ff' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0512 00:06:47.841289 24075 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0512 00:06:47.841294 24075 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0512 00:06:47.841305 24075 authenticator.cpp:318] Authentication success
I0512 00:06:47.841363 24071 authenticatee.cpp:299] Authentication success
I0512 00:06:47.841434 24061 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(93)@172.17.0.2:38459
I0512 00:06:47.841449 24065 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(182)@172.17.0.2:38459
I0512 00:06:47.841670 24068 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:38459
I0512 00:06:47.841744 24068 slave.cpp:1503] Will retry registration in 18.068172ms if necessary
I0512 00:06:47.841835 24059 master.cpp:5429] Received register agent message from slave(93)@172.17.0.2:38459 (maintenance-host)
I0512 00:06:47.841853 24059 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0512 00:06:47.842028 24075 master.cpp:5564] Registering agent at slave(93)@172.17.0.2:38459 (maintenance-host) with id 0d76165b-f656-493d-bc8c-d36a222ea038-S0
I0512 00:06:47.842159 24054 registrar.cpp:493] Applied 1 operations in 34939ns; attempting to update the registry
I0512 00:06:47.842362 24054 registrar.cpp:550] Successfully updated the registry in 177152ns
I0512 00:06:47.842576 24074 slave.cpp:4745] Received ping from slave-observer(86)@172.17.0.2:38459
I0512 00:06:47.842592 24072 master.cpp:5639] Registered agent 0d76165b-f656-493d-bc8c-d36a222ea038-S0 at slave(93)@172.17.0.2:38459 (maintenance-host) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0512 00:06:47.842679 24067 hierarchical.cpp:525] Added agent 0d76165b-f656-493d-bc8c-d36a222ea038-S0 (maintenance-host) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0512 00:06:47.842737 24074 slave.cpp:1121] Registered with master master@172.17.0.2:38459; given agent ID 0d76165b-f656-493d-bc8c-d36a222ea038-S0
I0512 00:06:47.842752 24074 fetcher.cpp:94] Clearing fetcher cache
I0512 00:06:47.842774 24067 hierarchical.cpp:1850] No allocations performed
I0512 00:06:47.842788 24067 hierarchical.cpp:1434] Performed allocation for 1 agents in 41362ns
I0512 00:06:47.842852 24057 status_update_manager.cpp:184] Resuming sending status updates
I0512 00:06:47.843056 24074 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_YbFsdK/meta/slaves/0d76165b-f656-493d-bc8c-d36a222ea038-S0/slave.info'
I0512 00:06:47.843274 24074 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0512 00:06:47.843328 24074 master.cpp:6324] Received update of agent 0d76165b-f656-493d-bc8c-d36a222ea038-S0 at slave(93)@172.17.0.2:38459 (maintenance-host) with total oversubscribed resources {}
I0512 00:06:47.844537 24051 cluster.cpp:448] Creating default 'local' authorizer
I0512 00:06:47.845068 24059 slave.cpp:225] Mesos agent started on (94)@172.17.0.2:38459
I0512 00:06:47.845090 24059 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterMaintenanceTest_InverseOffersFilters_7lzzZF/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/MasterMaintenanceTest_InverseOffersFilters_7lzzZF/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname="maintenance-host-2" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/MasterMaintenanceTest_InverseOffersFilters_7lzzZF/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_7lzzZF" --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/MasterMaintenanceTest_InverseOffersFilters_gmdt1h"
I0512 00:06:47.845289 24059 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_7lzzZF/credential'
I0512 00:06:47.845367 24059 slave.cpp:258] Agent using credential for: test-principal
I0512 00:06:47.845376 24059 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_7lzzZF/http_credentials'
I0512 00:06:47.845453 24059 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0512 00:06:47.845508 24059 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0512 00:06:47.845865 24059 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0512 00:06:47.845890 24059 slave.cpp:533] Agent attributes: [ ]
I0512 00:06:47.845894 24059 slave.cpp:538] Agent hostname: maintenance-host-2
I0512 00:06:47.845947 24064 status_update_manager.cpp:177] Pausing sending status updates
I0512 00:06:47.846258 24062 state.cpp:62] Recovering state from '/tmp/MasterMaintenanceTest_InverseOffersFilters_gmdt1h/meta'
I0512 00:06:47.846359 24053 status_update_manager.cpp:203] Recovering status update manager
I0512 00:06:47.846578 24070 slave.cpp:5970] Finished recovery
I0512 00:06:47.846874 24070 slave.cpp:6152] Querying resource estimator for oversubscribable resources
I0512 00:06:47.847023 24052 status_update_manager.cpp:177] Pausing sending status updates
I0512 00:06:47.847026 24069 slave.cpp:918] New master detected at master@172.17.0.2:38459
I0512 00:06:47.847060 24069 slave.cpp:953] Detecting new master
I0512 00:06:47.847121 24069 slave.cpp:6166] Received oversubscribable resources {} from the resource estimator
I0512 00:06:47.850256 24066 slave.cpp:980] Authenticating with master master@172.17.0.2:38459
I0512 00:06:47.850278 24066 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0512 00:06:47.850345 24064 authenticatee.cpp:121] Creating new client SASL connection
I0512 00:06:47.850486 24073 master.cpp:7475] Authenticating slave(94)@172.17.0.2:38459
I0512 00:06:47.850533 24064 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(183)@172.17.0.2:38459
I0512 00:06:47.850613 24061 authenticator.cpp:98] Creating new server SASL connection
I0512 00:06:47.850776 24071 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0512 00:06:47.850792 24071 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0512 00:06:47.850844 24055 authenticator.cpp:204] Received SASL authentication start
I0512 00:06:47.850890 24055 authenticator.cpp:326] Authentication requires more steps
I0512 00:06:47.850942 24055 authenticatee.cpp:259] Received SASL authentication step
I0512 00:06:47.850997 24063 authenticator.cpp:232] Received SASL authentication step
I0512 00:06:47.851019 24063 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0e8f2673f2ff' server FQDN: '0e8f2673f2ff' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0512 00:06:47.851029 24063 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0512 00:06:47.851044 24063 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0512 00:06:47.851056 24063 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '0e8f2673f2ff' server FQDN: '0e8f2673f2ff' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0512 00:06:47.851063 24063 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0512 00:06:47.851068 24063 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0512 00:06:47.851078 24063 authenticator.cpp:318] Authentication success
I0512 00:06:47.851122 24058 authenticatee.cpp:299] Authentication success
I0512 00:06:47.851133 24055 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(94)@172.17.0.2:38459
I0512 00:06:47.851156 24075 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(183)@172.17.0.2:38459
I0512 00:06:47.851296 24056 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:38459
I0512 00:06:47.851352 24056 slave.cpp:1503] Will retry registration in 8.031504ms if necessary
I0512 00:06:47.851389 24067 master.cpp:5429] Received register agent message from slave(94)@172.17.0.2:38459 (maintenance-host-2)
I0512 00:06:47.851404 24067 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0512 00:06:47.851562 24062 master.cpp:5564] Registering agent at slave(94)@172.17.0.2:38459 (maintenance-host-2) with id 0d76165b-f656-493d-bc8c-d36a222ea038-S1
I0512 00:06:47.851701 24069 registrar.cpp:493] Applied 1 operations in 37744ns; attempting to update the registry
I0512 00:06:47.851972 24061 registrar.cpp:550] Successfully updated the registry in 243712ns
I0512 00:06:47.852177 24063 slave.cpp:4745] Received ping from slave-observer(87)@172.17.0.2:38459
I0512 00:06:47.852327 24055 hierarchical.cpp:525] Added agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0512 00:06:47.852372 24060 master.cpp:5639] Registered agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 at slave(94)@172.17.0.2:38459 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0512 00:06:47.852468 24068 slave.cpp:1121] Registered with master master@172.17.0.2:38459; given agent ID 0d76165b-f656-493d-bc8c-d36a222ea038-S1
I0512 00:06:47.852494 24068 fetcher.cpp:94] Clearing fetcher cache
I0512 00:06:47.852521 24055 hierarchical.cpp:1850] No allocations performed
I0512 00:06:47.852543 24055 hierarchical.cpp:1434] Performed allocation for 1 agents in 66739ns
I0512 00:06:47.852676 24074 status_update_manager.cpp:184] Resuming sending status updates
I0512 00:06:47.852835 24068 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_gmdt1h/meta/slaves/0d76165b-f656-493d-bc8c-d36a222ea038-S1/slave.info'
I0512 00:06:47.853018 24068 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0512 00:06:47.853096 24057 master.cpp:6324] Received update of agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 at slave(94)@172.17.0.2:38459 (maintenance-host-2) with total oversubscribed resources {}
I0512 00:06:47.853495 24052 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule'
I0512 00:06:47.853955 24067 http.cpp:1115] HTTP POST for /master/maintenance/schedule from 172.17.0.2:33696
I0512 00:06:47.854189 24074 registrar.cpp:493] Applied 1 operations in 47194ns; attempting to update the registry
I0512 00:06:47.854483 24070 registrar.cpp:550] Successfully updated the registry in 270336ns
I0512 00:06:47.854604 24062 master.cpp:6386] Updating unavailability of agent 0d76165b-f656-493d-bc8c-d36a222ea038-S0 at slave(93)@172.17.0.2:38459 (maintenance-host), starting at 2476.14406060292weeks
I0512 00:06:47.854660 24062 master.cpp:6386] Updating unavailability of agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 at slave(94)@172.17.0.2:38459 (maintenance-host-2), starting at 2476.14406060292weeks
I0512 00:06:47.854737 24065 hierarchical.cpp:1850] No allocations performed
I0512 00:06:47.854758 24065 hierarchical.cpp:1434] Performed allocation for 2 agents in 50494ns
I0512 00:06:47.855638 24051 scheduler.cpp:184] Version: 1.4.0
I0512 00:06:47.855859 24067 scheduler.cpp:470] New master detected at master@172.17.0.2:38459
I0512 00:06:47.855875 24067 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0512 00:06:47.857481 24068 scheduler.cpp:361] Connected with the master at http://172.17.0.2:38459/master/api/v1/scheduler
I0512 00:06:47.858342 24059 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.2:38459/master/api/v1/scheduler
I0512 00:06:47.858656 24070 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 00:06:47.859155 24073 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:33698
I0512 00:06:47.859236 24073 master.cpp:2513] Received subscription request for HTTP framework 'default'
I0512 00:06:47.859251 24073 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0512 00:06:47.859457 24053 master.cpp:2628] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0512 00:06:47.859632 24071 master.hpp:2194] Sending heartbeat to 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.859649 24066 hierarchical.cpp:273] Added framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.859978 24072 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.2:38459/master/api/v1/scheduler
I0512 00:06:47.860213 24056 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.2:38459/master/api/v1/scheduler
I0512 00:06:47.860316 24066 hierarchical.cpp:1434] Performed allocation for 2 agents in 488760ns
I0512 00:06:47.860484 24070 master.cpp:7305] Sending 2 offers to framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 (default)
I0512 00:06:47.860716 24070 master.cpp:7394] Sending 2 inverse offers to framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 (default)
I0512 00:06:47.861253 24066 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.2:38459/master/api/v1/scheduler
I0512 00:06:47.861486 24066 scheduler.cpp:676] Enqueuing event INVERSE_OFFERS received from http://172.17.0.2:38459/master/api/v1/scheduler
I0512 00:06:47.869839 24054 hierarchical.cpp:1850] No allocations performed
I0512 00:06:47.869911 24054 hierarchical.cpp:1434] Performed allocation for 2 agents in 103831ns
I0512 00:06:47.870393 24061 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.2:38459/master/api/v1/scheduler
I0512 00:06:47.870586 24061 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.2:38459/master/api/v1/scheduler
I0512 00:06:47.870867 24056 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 00:06:47.871297 24057 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0512 00:06:47.871448 24057 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:33700
I0512 00:06:47.871678 24057 master.cpp:3875] Processing ACCEPT call for offers: [ 0d76165b-f656-493d-bc8c-d36a222ea038-O0 ] on agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 at slave(94)@172.17.0.2:38459 (maintenance-host-2) for framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 (default)
I0512 00:06:47.871716 24057 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64
W0512 00:06:47.872424 24057 validation.cpp:1284] Executor 'executor-1' for task '4c66277d-2d3e-4756-ac0e-2d7db2b4eb64' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0512 00:06:47.872452 24057 validation.cpp:1296] Executor 'executor-1' for task '4c66277d-2d3e-4756-ac0e-2d7db2b4eb64' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0512 00:06:47.872555 24057 master.cpp:9326] Adding task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 at slave(94)@172.17.0.2:38459 (maintenance-host-2)
I0512 00:06:47.872627 24057 master.cpp:4531] Launching task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 at slave(94)@172.17.0.2:38459 (maintenance-host-2)
I0512 00:06:47.872813 24068 slave.cpp:1613] Got assigned task '4c66277d-2d3e-4756-ac0e-2d7db2b4eb64' for framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.872891 24060 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:33700
I0512 00:06:47.873078 24060 master.cpp:3875] Processing ACCEPT call for offers: [ 0d76165b-f656-493d-bc8c-d36a222ea038-O1 ] on agent 0d76165b-f656-493d-bc8c-d36a222ea038-S0 at slave(93)@172.17.0.2:38459 (maintenance-host) for framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 (default)
I0512 00:06:47.873086 24068 slave.cpp:1894] Authorizing task '4c66277d-2d3e-4756-ac0e-2d7db2b4eb64' for framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.873107 24060 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 9ec91d8a-8f39-4c88-84aa-48ddd8fba55f
I0512 00:06:47.873118 24068 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64
I0512 00:06:47.873190 24074 hierarchical.cpp:850] Updated allocation of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 on agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 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]
I0512 00:06:47.873458 24053 slave.cpp:2081] Launching task '4c66277d-2d3e-4756-ac0e-2d7db2b4eb64' for framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
W0512 00:06:47.873785 24060 validation.cpp:1284] Executor 'executor-2' for task '9ec91d8a-8f39-4c88-84aa-48ddd8fba55f' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0512 00:06:47.873805 24060 validation.cpp:1296] Executor 'executor-2' for task '9ec91d8a-8f39-4c88-84aa-48ddd8fba55f' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0512 00:06:47.873895 24060 master.cpp:9326] Adding task 9ec91d8a-8f39-4c88-84aa-48ddd8fba55f with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 0d76165b-f656-493d-bc8c-d36a222ea038-S0 at slave(93)@172.17.0.2:38459 (maintenance-host)
I0512 00:06:47.873920 24053 paths.cpp:573] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_gmdt1h/slaves/0d76165b-f656-493d-bc8c-d36a222ea038-S1/frameworks/0d76165b-f656-493d-bc8c-d36a222ea038-0000/executors/executor-1/runs/29e4bcac-3283-4391-94a5-6f40e1b6abf1' to user 'mesos'
I0512 00:06:47.873963 24060 master.cpp:4531] Launching task 9ec91d8a-8f39-4c88-84aa-48ddd8fba55f of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 0d76165b-f656-493d-bc8c-d36a222ea038-S0 at slave(93)@172.17.0.2:38459 (maintenance-host)
I0512 00:06:47.874100 24053 slave.cpp:6929] Launching executor 'executor-1' of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 with resources {} in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_gmdt1h/slaves/0d76165b-f656-493d-bc8c-d36a222ea038-S1/frameworks/0d76165b-f656-493d-bc8c-d36a222ea038-0000/executors/executor-1/runs/29e4bcac-3283-4391-94a5-6f40e1b6abf1'
I0512 00:06:47.874109 24073 slave.cpp:1613] Got assigned task '9ec91d8a-8f39-4c88-84aa-48ddd8fba55f' for framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.874346 24073 slave.cpp:1894] Authorizing task '9ec91d8a-8f39-4c88-84aa-48ddd8fba55f' for framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.874372 24073 slave.cpp:6589] Authorizing framework principal 'test-principal' to launch task 9ec91d8a-8f39-4c88-84aa-48ddd8fba55f
I0512 00:06:47.874461 24053 slave.cpp:2310] Queued task '4c66277d-2d3e-4756-ac0e-2d7db2b4eb64' for executor 'executor-1' of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.874523 24053 slave.cpp:871] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_gmdt1h/slaves/0d76165b-f656-493d-bc8c-d36a222ea038-S1/frameworks/0d76165b-f656-493d-bc8c-d36a222ea038-0000/executors/executor-1/runs/29e4bcac-3283-4391-94a5-6f40e1b6abf1'
I0512 00:06:47.874541 24071 hierarchical.cpp:850] Updated allocation of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 on agent 0d76165b-f656-493d-bc8c-d36a222ea038-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]
I0512 00:06:47.874748 24071 slave.cpp:2081] Launching task '9ec91d8a-8f39-4c88-84aa-48ddd8fba55f' for framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.875161 24071 paths.cpp:573] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_YbFsdK/slaves/0d76165b-f656-493d-bc8c-d36a222ea038-S0/frameworks/0d76165b-f656-493d-bc8c-d36a222ea038-0000/executors/executor-2/runs/17f72927-6e79-42ba-bf29-06758184c3f3' to user 'mesos'
I0512 00:06:47.875290 24071 slave.cpp:6929] Launching executor 'executor-2' of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 with resources {} in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_YbFsdK/slaves/0d76165b-f656-493d-bc8c-d36a222ea038-S0/frameworks/0d76165b-f656-493d-bc8c-d36a222ea038-0000/executors/executor-2/runs/17f72927-6e79-42ba-bf29-06758184c3f3'
*** Aborted at 1494547607 (unix time) try "date -d @1494547607" if you are using GNU date ***
I0512 00:06:47.875540 24069 exec.cpp:162] Version: 1.4.0
I0512 00:06:47.875592 24056 exec.cpp:212] Executor started at: executor(28)@172.17.0.2:38459 with pid 24051
PC: @ 0x2ba823a0b82d (unknown)
*** SIGSEGV (@0xb0) received by PID 24051 (TID 0x2ba82cfa2700) from PID 176; stack trace: ***
I0512 00:06:47.875753 24060 slave.cpp:3784] Got registration for executor 'executor-1' of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 from executor(28)@172.17.0.2:38459
I0512 00:06:47.875867 24062 exec.cpp:237] Executor registered on agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1
I0512 00:06:47.875893 24062 exec.cpp:249] Executor::registered took 17839ns
@ 0x2ba822fa2330 (unknown)
I0512 00:06:47.876060 24060 slave.cpp:2523] Sending queued task '4c66277d-2d3e-4756-ac0e-2d7db2b4eb64' to executor 'executor-1' of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 at executor(28)@172.17.0.2:38459
@ 0x2ba823a0b82d (unknown)
I0512 00:06:47.876164 24054 exec.cpp:325] Executor asked to run task '4c66277d-2d3e-4756-ac0e-2d7db2b4eb64'
I0512 00:06:47.876199 24054 exec.cpp:334] Executor::launchTask took 25882ns
I0512 00:06:47.876233 24054 exec.cpp:551] Executor sending status update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.876312 24054 slave.cpp:4215] Handling status update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 from executor(28)@172.17.0.2:38459
I0512 00:06:47.876559 24053 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.876585 24053 status_update_manager.cpp:500] Creating StatusUpdate stream for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.876739 24053 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 to the agent
I0512 00:06:47.876834 24057 slave.cpp:4655] Forwarding the update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 to master@172.17.0.2:38459
I0512 00:06:47.876906 24057 slave.cpp:4549] Status update manager successfully handled status update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.876926 24057 slave.cpp:4565] Sending acknowledgement for status update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 to executor(28)@172.17.0.2:38459
I0512 00:06:47.876978 24075 master.cpp:6469] Status update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 from agent 0d76165b-f656-493d-bc8c-d36a222ea038-S1 at slave(94)@172.17.0.2:38459 (maintenance-host-2)
I0512 00:06:47.876991 24054 exec.cpp:374] Executor received status update acknowledgement 3250a6ea-05d5-4869-ad39-470920bb6771 for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.877017 24075 master.cpp:6537] Forwarding status update TASK_RUNNING (UUID: 3250a6ea-05d5-4869-ad39-470920bb6771) for task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000
I0512 00:06:47.877141 24075 master.cpp:8568] Updating the state of task 4c66277d-2d3e-4756-ac0e-2d7db2b4eb64 of framework 0d76165b-f656-493d-bc8c-d36a222ea038-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0512 00:06:47.877462 24052 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.2:38459/master/api/v1/scheduler
@ 0x2ba81e83cf05 mesos::internal::slave::executorEnvironment()
I0512 00:06:47.880856 24054 scheduler.cpp:243] Sending ACKNOWLEDGE call to http://172.17.0.2:38459/master/api/v1/scheduler
@ 0x2ba81e838057 mesos::internal::slave::Slave::launchExecutor()
I0512 00:06:47.881245 24074 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
@ 0x2ba81e83277f mesos::internal::slave::Slave::__run()
@ 0x2ba8201817d7 process::ProcessManager::resume()
@ 0x2ba820196c5f std::thread::_Impl<>::_M_run()
@ 0x2ba823261a60 (unknown)
@ 0x2ba822f9a184 start_thread
@ 0x2ba823acdbed (unknown)
make[3]: *** [CMakeFiles/check] Segmentation fault (core dumped)
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make: *** [check] Error 2
+ docker rmi mesos-1494545815-9097
Untagged: mesos-1494545815-9097:latest
Deleted: sha256:ebf155547b04672826e406373aaa9481a47f4810ab2bf4aabcaee7bb4af0c0c7
Deleted: sha256:e655ddd159ec084566c422e17161964333193527e78265797c58d758ae711fd5
Deleted: sha256:3188d4724bde44f939861613da3874388bd7911684dcd960cdea9dc5a4b05026
Deleted: sha256:e27e15945b0a838ad128465b79c068f0130640fda628a8bf17e18311eea3e509
Deleted: sha256:a0f5846f77aa378f68c9d3fdd898537598d223e6f68f8901ca4af217d20c073c
Deleted: sha256:6584ebdef0599f350f4fdea1501351c010e2ebc52640e8cc569e9deca526fc4f
Deleted: sha256:50b82cb0ffae972885ee7753bc9d9ffd9cfbab5abb1474ce7b054e648061d26c
Deleted: sha256:686609ca10205208de2f2c417e7d15db842c6b23b6b697d7c8db6fe425db7eaa
Deleted: sha256:dac12ae87a39de3573dd08d2f28a40d586b8ae462e066667c730b7ad9faef4d7
Deleted: sha256:ac3fd4f881b0ead678ca771014d140c191955d91392a89106e968410460f6848
Deleted: sha256:4890fa9f80e4c78495541f2bb12f4da28ea655f11b80d31277c5e4b33e6c3b15
Deleted: sha256:5fbbc0e068d79568ba18f92a5c629e09abd6d2fbfe48a8125610d71eee40fa03
Deleted: sha256:ece65e83ccf3abc3490f335645301b4ffd88fa7ce5438826768399aa5bef868a
Deleted: sha256:71bc3e9a7b43fca0190e723eacf24034fd3fddcfac0a7bf0c53eb43f6ba41f80
Deleted: sha256:9cbaaf965cc75710acde7730c3732e2dd27e14d218f29040b8f202556edcbcf9
Deleted: sha256:5c366e280563486f4b7ca2d79403ec4c4bb46129d91b11a2d3589c8246e46bdf
Deleted: sha256:babb99c5384e43a5a95d607bebd3d7155c4f20a8eff0c7a0f74391a4535204c0
Deleted: sha256:0e8e7acbee2a20fe7dccdeb0da2524f720a3e34c1047a06f417c0e9d996990b3
Deleted: sha256:eea69b5842968c2ff052a0bcb45135f1234d4c6ec802b473e19b40cef8665abf
Deleted: sha256:3cd5168371dba286118d3bfa2737f0057042a75d6d5eaea22ae7d40ee4e08104
Deleted: sha256:272254290924bfd05a15faf7b023288c6cd459205d96b228a2400efdd50b64c4
Deleted: sha256:1d8d6abbefe3d3d5dd577be6804b1b94029274852bad05c7ad9a4268308d1bbc
Deleted: sha256:29d9fcdab7ffd2de245841db38d5688689ebf4e6053904540d653584b0d00aeb
Deleted: sha256:5d1421b8974c21398acbf73a9b8e039d4c55d03f6c212dea9eb2c03b482b9107
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.conway@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3664
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3664/display/redirect?page=changes>