See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3438/display/redirect?page=changes>
Changes: [alexr] Improved log/failure messages in the (health)checker libraries. [alexr] Enabled pause/resume for general checks. [alexr] Added support for COMMAND checks to the default executor. [alexr] Cleaned up namespaces in "checker.cpp". ------------------------------------------ [...truncated 4.39 MB...] I0330 19:04:38.168081 25377 slave.cpp:227] 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_BXEOs7/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_BXEOs7/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_BXEOs7/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_BXEOs7" --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_ljp9Va" I0330 19:04:38.168483 25377 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_BXEOs7/credential' I0330 19:04:38.168577 25377 slave.cpp:259] Agent using credential for: test-principal I0330 19:04:38.168591 25377 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_BXEOs7/http_credentials' I0330 19:04:38.168696 25377 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0330 19:04:38.168750 25377 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0330 19:04:38.169157 25377 slave.cpp:492] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0330 19:04:38.169198 25377 slave.cpp:500] Agent attributes: [ ] I0330 19:04:38.169204 25377 slave.cpp:505] Agent hostname: maintenance-host I0330 19:04:38.169260 25373 status_update_manager.cpp:177] Pausing sending status updates I0330 19:04:38.169697 25385 state.cpp:62] Recovering state from '/tmp/MasterMaintenanceTest_InverseOffersFilters_ljp9Va/meta' I0330 19:04:38.169843 25385 status_update_manager.cpp:203] Recovering status update manager I0330 19:04:38.170042 25379 slave.cpp:5927] Finished recovery I0330 19:04:38.170429 25379 slave.cpp:6109] Querying resource estimator for oversubscribable resources I0330 19:04:38.170555 25379 slave.cpp:882] New master detected at [email protected]:48037 I0330 19:04:38.170567 25380 status_update_manager.cpp:177] Pausing sending status updates I0330 19:04:38.170605 25379 slave.cpp:917] Detecting new master I0330 19:04:38.170686 25379 slave.cpp:6123] Received oversubscribable resources {} from the resource estimator I0330 19:04:38.175865 25381 slave.cpp:944] Authenticating with master [email protected]:48037 I0330 19:04:38.175899 25381 slave.cpp:955] Using default CRAM-MD5 authenticatee I0330 19:04:38.176064 25384 authenticatee.cpp:121] Creating new client SASL connection I0330 19:04:38.176236 25380 master.cpp:7252] Authenticating slave(87)@172.17.0.2:48037 I0330 19:04:38.176303 25379 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(177)@172.17.0.2:48037 I0330 19:04:38.176405 25373 authenticator.cpp:98] Creating new server SASL connection I0330 19:04:38.176625 25379 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0330 19:04:38.176656 25379 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0330 19:04:38.176843 25386 authenticator.cpp:204] Received SASL authentication start I0330 19:04:38.176908 25386 authenticator.cpp:326] Authentication requires more steps I0330 19:04:38.176971 25386 authenticatee.cpp:259] Received SASL authentication step I0330 19:04:38.177062 25384 authenticator.cpp:232] Received SASL authentication step I0330 19:04:38.177093 25384 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fe465fb929fc' server FQDN: 'fe465fb929fc' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0330 19:04:38.177111 25384 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0330 19:04:38.177134 25384 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0330 19:04:38.177155 25384 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fe465fb929fc' server FQDN: 'fe465fb929fc' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0330 19:04:38.177171 25384 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0330 19:04:38.177186 25384 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0330 19:04:38.177211 25384 authenticator.cpp:318] Authentication success I0330 19:04:38.177301 25376 authenticatee.cpp:299] Authentication success I0330 19:04:38.177322 25384 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(177)@172.17.0.2:48037 I0330 19:04:38.177304 25386 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(87)@172.17.0.2:48037 I0330 19:04:38.177489 25376 slave.cpp:1039] Successfully authenticated with master [email protected]:48037 I0330 19:04:38.177562 25376 slave.cpp:1467] Will retry registration in 3.345233ms if necessary I0330 19:04:38.177628 25377 master.cpp:5437] Registering agent at slave(87)@172.17.0.2:48037 (maintenance-host) with id 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 I0330 19:04:38.177809 25381 registrar.cpp:463] Applied 1 operations in 19109ns; attempting to update the registry I0330 19:04:38.178335 25376 registrar.cpp:508] Successfully updated the registry in 493056ns I0330 19:04:38.178596 25374 slave.cpp:4709] Received ping from slave-observer(79)@172.17.0.2:48037 I0330 19:04:38.178648 25378 master.cpp:5511] Registered agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 at slave(87)@172.17.0.2:48037 (maintenance-host) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0330 19:04:38.178751 25374 slave.cpp:1085] Registered with master [email protected]:48037; given agent ID 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 I0330 19:04:38.178747 25376 hierarchical.cpp:527] Added agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 (maintenance-host) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0330 19:04:38.178773 25374 fetcher.cpp:94] Clearing fetcher cache I0330 19:04:38.178853 25376 hierarchical.cpp:1862] No allocations performed I0330 19:04:38.178894 25378 status_update_manager.cpp:184] Resuming sending status updates I0330 19:04:38.178895 25376 hierarchical.cpp:1446] Performed allocation for 1 agents in 72558ns I0330 19:04:38.179137 25374 slave.cpp:1113] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_ljp9Va/meta/slaves/6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0/slave.info' I0330 19:04:38.179399 25374 slave.cpp:1151] Forwarding total oversubscribed resources {} I0330 19:04:38.179468 25374 master.cpp:6098] Received update of agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 at slave(87)@172.17.0.2:48037 (maintenance-host) with total oversubscribed resources {} I0330 19:04:38.181040 25372 cluster.cpp:446] Creating default 'local' authorizer I0330 19:04:38.181572 25375 slave.cpp:226] Mesos agent started on (88)@172.17.0.2:48037 I0330 19:04:38.181596 25375 slave.cpp:227] 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_D9WuGT/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_D9WuGT/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_D9WuGT/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterMaintenanceTest_InverseOffersFilters_D9WuGT" --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_LD8D7W" I0330 19:04:38.181998 25375 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_D9WuGT/credential' I0330 19:04:38.182090 25375 slave.cpp:259] Agent using credential for: test-principal I0330 19:04:38.182106 25375 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterMaintenanceTest_InverseOffersFilters_D9WuGT/http_credentials' I0330 19:04:38.182271 25375 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly' I0330 19:04:38.182343 25375 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite' I0330 19:04:38.182859 25375 slave.cpp:492] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0330 19:04:38.182904 25375 slave.cpp:500] Agent attributes: [ ] I0330 19:04:38.182917 25375 slave.cpp:505] Agent hostname: maintenance-host-2 I0330 19:04:38.182996 25383 status_update_manager.cpp:177] Pausing sending status updates I0330 19:04:38.183455 25375 state.cpp:62] Recovering state from '/tmp/MasterMaintenanceTest_InverseOffersFilters_LD8D7W/meta' I0330 19:04:38.183631 25381 status_update_manager.cpp:203] Recovering status update manager I0330 19:04:38.183881 25383 slave.cpp:5927] Finished recovery I0330 19:04:38.184250 25383 slave.cpp:6109] Querying resource estimator for oversubscribable resources I0330 19:04:38.184442 25375 slave.cpp:882] New master detected at [email protected]:48037 I0330 19:04:38.184453 25378 status_update_manager.cpp:177] Pausing sending status updates I0330 19:04:38.184496 25375 slave.cpp:917] Detecting new master I0330 19:04:38.184593 25375 slave.cpp:6123] Received oversubscribable resources {} from the resource estimator I0330 19:04:38.186691 25386 slave.cpp:944] Authenticating with master [email protected]:48037 I0330 19:04:38.186719 25386 slave.cpp:955] Using default CRAM-MD5 authenticatee I0330 19:04:38.186810 25385 authenticatee.cpp:121] Creating new client SASL connection I0330 19:04:38.187012 25380 master.cpp:7252] Authenticating slave(88)@172.17.0.2:48037 I0330 19:04:38.187083 25383 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(178)@172.17.0.2:48037 I0330 19:04:38.187216 25380 authenticator.cpp:98] Creating new server SASL connection I0330 19:04:38.187391 25380 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5 I0330 19:04:38.187417 25380 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5' I0330 19:04:38.187465 25380 authenticator.cpp:204] Received SASL authentication start I0330 19:04:38.187530 25380 authenticator.cpp:326] Authentication requires more steps I0330 19:04:38.187585 25380 authenticatee.cpp:259] Received SASL authentication step I0330 19:04:38.187674 25386 authenticator.cpp:232] Received SASL authentication step I0330 19:04:38.187711 25386 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fe465fb929fc' server FQDN: 'fe465fb929fc' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0330 19:04:38.187757 25386 auxprop.cpp:181] Looking up auxiliary property '*userPassword' I0330 19:04:38.187783 25386 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0330 19:04:38.187800 25386 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'fe465fb929fc' server FQDN: 'fe465fb929fc' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0330 19:04:38.187813 25386 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0330 19:04:38.187827 25386 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0330 19:04:38.187851 25386 authenticator.cpp:318] Authentication success I0330 19:04:38.188056 25377 authenticatee.cpp:299] Authentication success I0330 19:04:38.188055 25386 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(178)@172.17.0.2:48037 I0330 19:04:38.188107 25380 master.cpp:7282] Successfully authenticated principal 'test-principal' at slave(88)@172.17.0.2:48037 I0330 19:04:38.188367 25381 slave.cpp:1039] Successfully authenticated with master [email protected]:48037 I0330 19:04:38.188446 25381 slave.cpp:1467] Will retry registration in 7.127531ms if necessary I0330 19:04:38.188530 25378 master.cpp:5437] Registering agent at slave(88)@172.17.0.2:48037 (maintenance-host-2) with id 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 I0330 19:04:38.188711 25387 registrar.cpp:463] Applied 1 operations in 23734ns; attempting to update the registry I0330 19:04:38.189288 25387 registrar.cpp:508] Successfully updated the registry in 540160ns I0330 19:04:38.189674 25374 slave.cpp:4709] Received ping from slave-observer(80)@172.17.0.2:48037 I0330 19:04:38.189668 25376 master.cpp:5511] Registered agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 at slave(88)@172.17.0.2:48037 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0330 19:04:38.189785 25380 hierarchical.cpp:527] Added agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 (maintenance-host-2) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {}) I0330 19:04:38.189812 25374 slave.cpp:1085] Registered with master [email protected]:48037; given agent ID 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 I0330 19:04:38.189952 25374 fetcher.cpp:94] Clearing fetcher cache I0330 19:04:38.189965 25380 hierarchical.cpp:1862] No allocations performed I0330 19:04:38.189985 25380 hierarchical.cpp:1446] Performed allocation for 1 agents in 54191ns I0330 19:04:38.190114 25378 status_update_manager.cpp:184] Resuming sending status updates I0330 19:04:38.190345 25374 slave.cpp:1113] Checkpointing SlaveInfo to '/tmp/MasterMaintenanceTest_InverseOffersFilters_LD8D7W/meta/slaves/6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1/slave.info' I0330 19:04:38.190623 25374 slave.cpp:1151] Forwarding total oversubscribed resources {} I0330 19:04:38.190713 25376 master.cpp:6098] Received update of agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 at slave(88)@172.17.0.2:48037 (maintenance-host-2) with total oversubscribed resources {} I0330 19:04:38.191009 25375 process.cpp:3705] Handling HTTP event for process 'master' with path: '/master/maintenance/schedule' I0330 19:04:38.191469 25374 http.cpp:440] HTTP POST for /master/maintenance/schedule from 172.17.0.2:49214 I0330 19:04:38.191740 25385 registrar.cpp:463] Applied 1 operations in 45624ns; attempting to update the registry I0330 19:04:38.192383 25378 registrar.cpp:508] Successfully updated the registry in 613888ns I0330 19:04:38.192570 25377 master.cpp:6160] Updating unavailability of agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 at slave(87)@172.17.0.2:48037 (maintenance-host), starting at 2470.11407934859weeks I0330 19:04:38.192647 25377 master.cpp:6160] Updating unavailability of agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 at slave(88)@172.17.0.2:48037 (maintenance-host-2), starting at 2470.11407934859weeks I0330 19:04:38.192734 25383 hierarchical.cpp:1862] No allocations performed I0330 19:04:38.192767 25383 hierarchical.cpp:1446] Performed allocation for 1 agents in 66529ns I0330 19:04:38.192870 25383 hierarchical.cpp:1862] No allocations performed I0330 19:04:38.192893 25383 hierarchical.cpp:1446] Performed allocation for 1 agents in 53291ns I0330 19:04:38.193617 25372 scheduler.cpp:184] Version: 1.3.0 I0330 19:04:38.193895 25385 scheduler.cpp:470] New master detected at [email protected]:48037 I0330 19:04:38.193918 25385 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master I0330 19:04:38.195543 25384 scheduler.cpp:361] Connected with the master at http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.196291 25384 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.196738 25384 process.cpp:3705] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0330 19:04:38.197207 25380 http.cpp:440] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:49216 I0330 19:04:38.197295 25380 master.cpp:2517] Received subscription request for HTTP framework 'default' I0330 19:04:38.197329 25380 master.cpp:2201] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }' I0330 19:04:38.197522 25376 master.cpp:2632] Subscribing framework 'default' with checkpointing disabled and capabilities [ ] I0330 19:04:38.197810 25382 master.hpp:2171] Sending heartbeat to 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.197872 25388 hierarchical.cpp:271] Added framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.198243 25383 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.198695 25388 hierarchical.cpp:1446] Performed allocation for 2 agents in 715461ns I0330 19:04:38.198778 25383 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.199065 25376 master.cpp:7081] Sending 2 offers to framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 (default) I0330 19:04:38.199498 25376 master.cpp:7171] Sending 2 inverse offers to framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 (default) I0330 19:04:38.200132 25373 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.200459 25373 scheduler.cpp:676] Enqueuing event INVERSE_OFFERS received from http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.207820 25384 hierarchical.cpp:1862] No allocations performed I0330 19:04:38.207897 25384 hierarchical.cpp:1446] Performed allocation for 2 agents in 98112ns I0330 19:04:38.208518 25383 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.208741 25383 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.209089 25381 process.cpp:3705] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0330 19:04:38.209560 25381 process.cpp:3705] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' I0330 19:04:38.209982 25381 http.cpp:440] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:49215 I0330 19:04:38.210266 25381 master.cpp:3839] Processing ACCEPT call for offers: [ 6cdfb09b-4608-4d7d-bd45-5883f6576d10-O0 ] on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 at slave(87)@172.17.0.2:48037 (maintenance-host) for framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 (default) I0330 19:04:38.210314 25381 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 I0330 19:04:38.210723 25381 http.cpp:440] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:49215 I0330 19:04:38.210968 25381 master.cpp:3839] Processing ACCEPT call for offers: [ 6cdfb09b-4608-4d7d-bd45-5883f6576d10-O1 ] on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 at slave(88)@172.17.0.2:48037 (maintenance-host-2) for framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 (default) I0330 19:04:38.211021 25381 master.cpp:3415] Authorizing framework principal 'test-principal' to launch task 481a140f-a4f6-4a30-9cff-ff0ffcc46571 W0330 19:04:38.211758 25381 validation.cpp:1113] Executor 'executor-1' for task 'b4f94a23-0dae-4ea5-b6dd-aea07edefb22' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0330 19:04:38.211786 25381 validation.cpp:1125] Executor 'executor-1' for task 'b4f94a23-0dae-4ea5-b6dd-aea07edefb22' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0330 19:04:38.211913 25381 master.cpp:9097] Adding task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 at slave(87)@172.17.0.2:48037 (maintenance-host) I0330 19:04:38.212019 25381 master.cpp:4489] Launching task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 at slave(87)@172.17.0.2:48037 (maintenance-host) I0330 19:04:38.212241 25384 slave.cpp:1577] Got assigned task 'b4f94a23-0dae-4ea5-b6dd-aea07edefb22' for framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 W0330 19:04:38.212594 25381 validation.cpp:1113] Executor 'executor-2' for task '481a140f-a4f6-4a30-9cff-ff0ffcc46571' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases. W0330 19:04:38.212623 25381 validation.cpp:1125] Executor 'executor-2' for task '481a140f-a4f6-4a30-9cff-ff0ffcc46571' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases. I0330 19:04:38.212601 25384 slave.cpp:1858] Authorizing task 'b4f94a23-0dae-4ea5-b6dd-aea07edefb22' for framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.212702 25384 slave.cpp:6546] Authorizing framework principal 'test-principal' to launch task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 I0330 19:04:38.212729 25375 hierarchical.cpp:852] Updated allocation of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-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] I0330 19:04:38.212749 25381 master.cpp:9097] Adding task 481a140f-a4f6-4a30-9cff-ff0ffcc46571 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 at slave(88)@172.17.0.2:48037 (maintenance-host-2) I0330 19:04:38.212904 25381 master.cpp:4489] Launching task 481a140f-a4f6-4a30-9cff-ff0ffcc46571 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1 at slave(88)@172.17.0.2:48037 (maintenance-host-2) I0330 19:04:38.213066 25374 slave.cpp:2045] Launching task 'b4f94a23-0dae-4ea5-b6dd-aea07edefb22' for framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.213155 25377 slave.cpp:1577] Got assigned task '481a140f-a4f6-4a30-9cff-ff0ffcc46571' for framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.213521 25377 slave.cpp:1858] Authorizing task '481a140f-a4f6-4a30-9cff-ff0ffcc46571' for framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.213557 25377 slave.cpp:6546] Authorizing framework principal 'test-principal' to launch task 481a140f-a4f6-4a30-9cff-ff0ffcc46571 I0330 19:04:38.213675 25374 paths.cpp:554] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_ljp9Va/slaves/6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0/frameworks/6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000/executors/executor-1/runs/eec32379-9e0a-47ad-9bde-c50dcc13e798' to user 'mesos' I0330 19:04:38.213711 25373 hierarchical.cpp:852] Updated allocation of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-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] I0330 19:04:38.213902 25374 slave.cpp:6890] Launching executor 'executor-1' of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 with resources {} in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_ljp9Va/slaves/6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0/frameworks/6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000/executors/executor-1/runs/eec32379-9e0a-47ad-9bde-c50dcc13e798' I0330 19:04:38.214061 25376 slave.cpp:2045] Launching task '481a140f-a4f6-4a30-9cff-ff0ffcc46571' for framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.214237 25374 slave.cpp:2274] Queued task 'b4f94a23-0dae-4ea5-b6dd-aea07edefb22' for executor 'executor-1' of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.214296 25374 slave.cpp:835] Successfully attached file '/tmp/MasterMaintenanceTest_InverseOffersFilters_ljp9Va/slaves/6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0/frameworks/6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000/executors/executor-1/runs/eec32379-9e0a-47ad-9bde-c50dcc13e798' I0330 19:04:38.214926 25376 paths.cpp:554] Trying to chown '/tmp/MasterMaintenanceTest_InverseOffersFilters_LD8D7W/slaves/6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1/frameworks/6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000/executors/executor-2/runs/e119a84e-dbb2-4314-8b82-e63e99d97378' to user 'mesos' I0330 19:04:38.215168 25376 slave.cpp:6890] Launching executor 'executor-2' of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 with resources {} in work directory '/tmp/MasterMaintenanceTest_InverseOffersFilters_LD8D7W/slaves/6cdfb09b-4608-4d7d-bd45-5883f6576d10-S1/frameworks/6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000/executors/executor-2/runs/e119a84e-dbb2-4314-8b82-e63e99d97378' *** Aborted at 1490900678 (unix time) try "date -d @1490900678" if you are using GNU date *** I0330 19:04:38.215546 25386 exec.cpp:162] Version: 1.3.0 I0330 19:04:38.215622 25383 exec.cpp:212] Executor started at: executor(28)@172.17.0.2:48037 with pid 25372 PC: @ 0x2ab1624be82d (unknown) *** SIGSEGV (@0xa0) received by PID 25372 (TID 0x2ab16ac19700) from PID 160; stack trace: *** I0330 19:04:38.215899 25374 slave.cpp:3748] Got registration for executor 'executor-1' of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 from executor(28)@172.17.0.2:48037 I0330 19:04:38.216045 25384 exec.cpp:237] Executor registered on agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 I0330 19:04:38.216092 25384 exec.cpp:249] Executor::registered took 22437ns @ 0x2ab161a55330 (unknown) I0330 19:04:38.216320 25378 slave.cpp:2487] Sending queued task 'b4f94a23-0dae-4ea5-b6dd-aea07edefb22' to executor 'executor-1' of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 at executor(28)@172.17.0.2:48037 @ 0x2ab1624be82d (unknown) I0330 19:04:38.216472 25375 exec.cpp:325] Executor asked to run task 'b4f94a23-0dae-4ea5-b6dd-aea07edefb22' I0330 19:04:38.216527 25375 exec.cpp:334] Executor::launchTask took 32209ns I0330 19:04:38.216584 25375 exec.cpp:551] Executor sending status update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.216682 25375 slave.cpp:4179] Handling status update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 from executor(28)@172.17.0.2:48037 I0330 19:04:38.216980 25384 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.217005 25384 status_update_manager.cpp:500] Creating StatusUpdate stream for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.217188 25384 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 to the agent I0330 19:04:38.217316 25374 slave.cpp:4619] Forwarding the update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 to [email protected]:48037 I0330 19:04:38.217454 25374 slave.cpp:4513] Status update manager successfully handled status update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.217474 25385 master.cpp:6243] Status update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 from agent 6cdfb09b-4608-4d7d-bd45-5883f6576d10-S0 at slave(87)@172.17.0.2:48037 (maintenance-host) I0330 19:04:38.217494 25374 slave.cpp:4529] Sending acknowledgement for status update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 to executor(28)@172.17.0.2:48037 I0330 19:04:38.217514 25385 master.cpp:6311] Forwarding status update TASK_RUNNING (UUID: 99a81d43-2437-4641-ac41-0e4be15a6347) for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.217563 25374 exec.cpp:374] Executor received status update acknowledgement 99a81d43-2437-4641-ac41-0e4be15a6347 for task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 I0330 19:04:38.217631 25385 master.cpp:8345] Updating the state of task b4f94a23-0dae-4ea5-b6dd-aea07edefb22 of framework 6cdfb09b-4608-4d7d-bd45-5883f6576d10-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING) I0330 19:04:38.218066 25375 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.2:48037/master/api/v1/scheduler @ 0x2ab15ef21534 mesos::internal::slave::executorEnvironment() I0330 19:04:38.219391 25379 scheduler.cpp:243] Sending ACKNOWLEDGE call to http://172.17.0.2:48037/master/api/v1/scheduler I0330 19:04:38.219758 25373 process.cpp:3705] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler' @ 0x2ab15ef47098 mesos::internal::slave::Slave::launchExecutor() @ 0x2ab15ef4bce5 mesos::internal::slave::Slave::__run() @ 0x2ab160278e51 process::ProcessManager::resume() @ 0x2ab160282037 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv @ 0x2ab161d14a60 (unknown) @ 0x2ab161a4d184 start_thread @ 0x2ab162580bed (unknown) make[3]: *** [CMakeFiles/check] Segmentation fault make[3]: Leaving directory `/mesos/build' make[2]: *** [CMakeFiles/check.dir/all] Error 2 make[2]: Leaving directory `/mesos/build' make[1]: *** [CMakeFiles/check.dir/rule] Error 2 make[1]: Leaving directory `/mesos/build' make: *** [check] Error 2 + docker rmi mesos-1490897301-9943 Untagged: mesos-1490897301-9943:latest Deleted: sha256:da9d4d436bb1509a61854e8fe8aed918a9ff7c985a47827138ce46d182aecc2c Deleted: sha256:abeaca42a3983a2c6d8d8af8c1d933ea7be3304fae970ef66d2ca391cb7de3e1 Deleted: sha256:4eaa21747ab03b64844cf412f46b8e70700241c16c037f364b9918b18de392e4 Deleted: sha256:6d3698fb170716413730e91c32a2b8cf4f2872b250414a3be2a6e659fd1d265f Deleted: sha256:83def0a7fe20bb481ace9ca70db641aefc10b86ace917c1607e9bbf1161ec419 Deleted: sha256:82f75b5ebbc15ac3f8805a9e42b9c4f11c633b64ac3417ec3abcb8db21c04b37 Deleted: sha256:d2e570f40e7368bd360cea3c38da3ec3fbe5166e292a640194daccb7970fb620 Deleted: sha256:1bccfb9a64a17ca6c144ca94e4237194ea98a35144bd2aa096a7c01466226ba1 Deleted: sha256:0ffa7b08bc78caeec29b019e6e0c5d972dbf7b41318ec02ea53b9c8a961b8454 Deleted: sha256:b4d85ba4e81402310eba11f4d72963048be3b0b2d9cadf96b1536a347f088321 Deleted: sha256:eeb4a0a0eeab5ac1a2665e2b5dd8949db3f8147c83ad62e87c23419db4ff25ec Deleted: sha256:9522fb8a4df68cddbef8dce6cbfe43c90fec03f1fe5ac19f14339f97f8ce569e Deleted: sha256:3d79a866ef2d23e762acea29b3e24fc38cd070a310866f6d7507e18e9b63cad5 Deleted: sha256:8c4ae508a1b6dfbea3510a4f1b43cbbc597ade68eda1206636141d3bcc398011 Deleted: sha256:7a3113322cf4110932a63d9f2536b73b0354ed26b1663826d84a217da1bd0ce5 Deleted: sha256:7cee19269a47fc4f0f4f281c78c7da4f7df46e64f287eca750ba0a5da4561a89 Deleted: sha256:6e38a9ef032f0bcfc7ec04a8018767a096d3267a72d307e49fc46f8d2345c710 Deleted: sha256:52d014c5924b570277386115ae18ec4c50e37ae8a77b354c1bfb4683da0b188d Deleted: sha256:47d726fcc3a6decbd791e9f0f70af1be29fcc4058edcde0cd0c506de2a2b0f12 Deleted: sha256:39192900d7acfb6e02b8fdbb63a92ca00fee2847821909a358accfb431bc0fe3 Deleted: sha256:37de0fca4bddb8421184b5a9944c6ae9e8bbd092cdfb305dcd018a32391b8119 Deleted: sha256:d7b521d82145270b57ea5c749e9ba602b358934383d6a87276e2b2c34b0fca7c Deleted: sha256:8e6eda44a15099e23b8d76d5e5fed5e85c27d71abb3f4e8057ff0b6b0a1561fd Deleted: sha256:573044f14d326f716a793d49765d8b2bd506ce3cbe702f0a66211520a8ec179a Build step 'Execute shell' marked build as failure
