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/09 17:10:43 UTC

Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3638

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3638/display/redirect?page=changes>

Changes:

[vinodkone] Add new MUG for Tokyo.

------------------------------------------
[...truncated 28.18 MB...]
I0509 17:10:23.141093 18930 master.cpp:502] Master only allowing authenticated agents to register
I0509 17:10:23.141098 18930 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0509 17:10:23.141106 18930 credentials.hpp:37] Loading credentials for authentication from '/tmp/QgXIDm/credentials'
I0509 17:10:23.141373 18930 master.cpp:560] Using default 'crammd5' authenticator
I0509 17:10:23.141507 18930 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0509 17:10:23.141683 18930 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0509 17:10:23.141842 18930 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0509 17:10:23.141978 18930 master.cpp:640] Authorization enabled
I0509 17:10:23.142139 18924 hierarchical.cpp:158] Initialized hierarchical allocator process
I0509 17:10:23.142174 18932 whitelist_watcher.cpp:77] No whitelist given
I0509 17:10:23.144489 18925 master.cpp:2161] Elected as the leading master!
I0509 17:10:23.144521 18925 master.cpp:1700] Recovering from registrar
I0509 17:10:23.144681 18931 registrar.cpp:345] Recovering registrar
I0509 17:10:23.146811 18919 registrar.cpp:389] Successfully fetched the registry (0B) in 2080us
I0509 17:10:23.146958 18919 registrar.cpp:493] Applied 1 operations in 30498ns; attempting to update the registry
I0509 17:10:23.149063 18919 registrar.cpp:550] Successfully updated the registry in 2.044928ms
I0509 17:10:23.149186 18919 registrar.cpp:422] Successfully recovered registrar
I0509 17:10:23.149613 18923 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0509 17:10:23.149758 18919 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0509 17:10:23.157670  1433 cluster.cpp:448] Creating default 'local' authorizer
I0509 17:10:23.159327 18923 slave.cpp:225] Mesos agent started on (747)@172.17.0.2:37663
I0509 17:10:23.159350 18923 slave.cpp:226] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_executors="true" --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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_6p22VP/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_secret_key="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_6p22VP/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_6p22VP/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_command_executor="false" --http_credentials="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_6p22VP/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.0/_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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_6p22VP" --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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB"
I0509 17:10:23.159818 18923 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_6p22VP/credential'
I0509 17:10:23.159973  1433 scheduler.cpp:184] Version: 1.4.0
I0509 17:10:23.160046 18923 slave.cpp:258] Agent using credential for: test-principal
I0509 17:10:23.160070 18923 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_6p22VP/http_credentials'
I0509 17:10:23.160555 18923 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0509 17:10:23.160715 18923 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0509 17:10:23.160933 18929 scheduler.cpp:470] New master detected at master@172.17.0.2:37663
I0509 17:10:23.160953 18923 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0509 17:10:23.161069 18923 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0509 17:10:23.161264 18923 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0509 17:10:23.161370 18923 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0509 17:10:23.162679 18929 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0509 17:10:23.162850 18923 slave.cpp:525] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0509 17:10:23.162971 18923 slave.cpp:533] Agent attributes: [  ]
I0509 17:10:23.162993 18923 slave.cpp:538] Agent hostname: f09954bfa156
I0509 17:10:23.163332 18920 status_update_manager.cpp:177] Pausing sending status updates
I0509 17:10:23.165094 18931 state.cpp:62] Recovering state from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB/meta'
I0509 17:10:23.165171 18920 scheduler.cpp:361] Connected with the master at http://172.17.0.2:37663/master/api/v1/scheduler
I0509 17:10:23.165630 18923 status_update_manager.cpp:203] Recovering status update manager
I0509 17:10:23.166257 18922 slave.cpp:5963] Finished recovery
I0509 17:10:23.166663 18921 scheduler.cpp:243] Sending SUBSCRIBE call to http://172.17.0.2:37663/master/api/v1/scheduler
I0509 17:10:23.166820 18922 slave.cpp:6145] Querying resource estimator for oversubscribable resources
I0509 17:10:23.167078 18922 slave.cpp:6159] Received oversubscribable resources {} from the resource estimator
I0509 17:10:23.167356 18920 status_update_manager.cpp:177] Pausing sending status updates
I0509 17:10:23.167379 18924 slave.cpp:918] New master detected at master@172.17.0.2:37663
I0509 17:10:23.167503 18924 slave.cpp:953] Detecting new master
I0509 17:10:23.168078 18920 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0509 17:10:23.168598 18921 slave.cpp:980] Authenticating with master master@172.17.0.2:37663
I0509 17:10:23.168675 18921 slave.cpp:991] Using default CRAM-MD5 authenticatee
I0509 17:10:23.169075 18930 authenticatee.cpp:121] Creating new client SASL connection
I0509 17:10:23.169519 18928 master.cpp:7475] Authenticating slave(747)@172.17.0.2:37663
I0509 17:10:23.169651 18929 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1390)@172.17.0.2:37663
I0509 17:10:23.170070 18926 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:53630
I0509 17:10:23.170171 18918 authenticator.cpp:98] Creating new server SASL connection
I0509 17:10:23.172063 18926 master.cpp:2513] Received subscription request for HTTP framework 'default'
I0509 17:10:23.172153 18926 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0509 17:10:23.172644 18923 master.cpp:2628] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I0509 17:10:23.172683 18917 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0509 17:10:23.172986 18917 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0509 17:10:23.173163 18917 authenticator.cpp:204] Received SASL authentication start
I0509 17:10:23.173337 18917 authenticator.cpp:326] Authentication requires more steps
I0509 17:10:23.173550 18927 hierarchical.cpp:273] Added framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.173596 18925 authenticatee.cpp:259] Received SASL authentication step
I0509 17:10:23.173730 18927 hierarchical.cpp:1850] No allocations performed
I0509 17:10:23.173784 18932 authenticator.cpp:232] Received SASL authentication step
I0509 17:10:23.173789 18927 hierarchical.cpp:1940] No inverse offers to send out!
I0509 17:10:23.173830 18932 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f09954bfa156' server FQDN: 'f09954bfa156' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0509 17:10:23.173851 18932 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0509 17:10:23.173889 18932 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0509 17:10:23.173882 18927 hierarchical.cpp:1434] Performed allocation for 0 agents in 199879ns
I0509 17:10:23.173918 18932 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f09954bfa156' server FQDN: 'f09954bfa156' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0509 17:10:23.173928 18932 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0509 17:10:23.173935 18932 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0509 17:10:23.173955 18932 authenticator.cpp:318] Authentication success
I0509 17:10:23.174160 18932 authenticatee.cpp:299] Authentication success
I0509 17:10:23.174264 18932 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1390)@172.17.0.2:37663
I0509 17:10:23.174597 18932 slave.cpp:1075] Successfully authenticated with master master@172.17.0.2:37663
I0509 17:10:23.174790 18932 slave.cpp:1503] Will retry registration in 18.002055ms if necessary
I0509 17:10:23.175709 18932 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from http://172.17.0.2:37663/master/api/v1/scheduler
I0509 17:10:23.176761 18932 master.hpp:2194] Sending heartbeat to 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.176789 18923 master.cpp:7505] Successfully authenticated principal 'test-principal' at slave(747)@172.17.0.2:37663
I0509 17:10:23.177105 18923 master.cpp:5429] Received register agent message from slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.177270 18923 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0509 17:10:23.177789 18920 master.cpp:5564] Registering agent at slave(747)@172.17.0.2:37663 (f09954bfa156) with id 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0
I0509 17:10:23.177880 18930 scheduler.cpp:676] Enqueuing event HEARTBEAT received from http://172.17.0.2:37663/master/api/v1/scheduler
I0509 17:10:23.178450 18917 registrar.cpp:493] Applied 1 operations in 83968ns; attempting to update the registry
I0509 17:10:23.180702 18917 registrar.cpp:550] Successfully updated the registry in 2.185984ms
I0509 17:10:23.181404 18921 slave.cpp:4745] Received ping from slave-observer(680)@172.17.0.2:37663
I0509 17:10:23.181475 18923 master.cpp:5639] Registered agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0509 17:10:23.182003 18926 hierarchical.cpp:525] Added agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 (f09954bfa156) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0509 17:10:23.183646 18926 hierarchical.cpp:1940] No inverse offers to send out!
I0509 17:10:23.183704 18926 hierarchical.cpp:1434] Performed allocation for 1 agents in 1.562807ms
I0509 17:10:23.183815 18921 slave.cpp:1121] Registered with master master@172.17.0.2:37663; given agent ID 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0
I0509 17:10:23.183846 18921 fetcher.cpp:94] Clearing fetcher cache
I0509 17:10:23.184029 18928 status_update_manager.cpp:184] Resuming sending status updates
I0509 17:10:23.184239 18918 master.cpp:7305] Sending 1 offers to framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (default)
I0509 17:10:23.184299 18921 slave.cpp:1149] Checkpointing SlaveInfo to '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB/meta/slaves/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0/slave.info'
I0509 17:10:23.184729 18921 slave.cpp:1187] Forwarding total oversubscribed resources {}
I0509 17:10:23.185482 18918 master.cpp:6324] Received update of agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156) with total oversubscribed resources {}
I0509 17:10:23.187999 18917 scheduler.cpp:676] Enqueuing event OFFERS received from http://172.17.0.2:37663/master/api/v1/scheduler
I0509 17:10:23.190560 18917 scheduler.cpp:243] Sending ACCEPT call to http://172.17.0.2:37663/master/api/v1/scheduler
I0509 17:10:23.192453 18920 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0509 17:10:23.194124 18926 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:53629
I0509 17:10:23.196380 18926 master.cpp:3875] Processing ACCEPT call for offers: [ 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-O0 ] on agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156) for framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (default)
I0509 17:10:23.196521 18926 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task aa7d3963-79a7-4282-9d42-468cc002a272
W0509 17:10:23.199139 18923 validation.cpp:1284] Executor 'default' for task 'aa7d3963-79a7-4282-9d42-468cc002a272' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0509 17:10:23.199177 18923 validation.cpp:1296] Executor 'default' for task 'aa7d3963-79a7-4282-9d42-468cc002a272' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0509 17:10:23.199699 18923 master.cpp:9326] Adding task aa7d3963-79a7-4282-9d42-468cc002a272 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.200031 18923 master.cpp:4531] Launching task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.200711 18924 slave.cpp:1613] Got assigned task 'aa7d3963-79a7-4282-9d42-468cc002a272' for framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.201766 18924 slave.cpp:1894] Authorizing task 'aa7d3963-79a7-4282-9d42-468cc002a272' for framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.202044 18924 slave.cpp:6582] Authorizing framework principal 'test-principal' to launch task aa7d3963-79a7-4282-9d42-468cc002a272
I0509 17:10:23.203369 18926 slave.cpp:2081] Launching task 'aa7d3963-79a7-4282-9d42-468cc002a272' for framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.203790 18922 hierarchical.cpp:850] Updated allocation of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 on agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-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]
I0509 17:10:23.203980 18926 paths.cpp:573] Trying to chown '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB/slaves/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0/frameworks/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000/executors/default/runs/d8779779-e33c-49c1-ac8c-2b7bdc11f451' to user 'mesos'
I0509 17:10:23.204267 18926 slave.cpp:6922] Launching executor 'default' of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 with resources {} in work directory '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB/slaves/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0/frameworks/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000/executors/default/runs/d8779779-e33c-49c1-ac8c-2b7bdc11f451'
I0509 17:10:23.205121 18926 slave.cpp:2310] Queued task 'aa7d3963-79a7-4282-9d42-468cc002a272' for executor 'default' of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.205194 18926 slave.cpp:871] Successfully attached file '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB/slaves/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0/frameworks/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000/executors/default/runs/d8779779-e33c-49c1-ac8c-2b7bdc11f451'
I0509 17:10:23.207649 18925 executor.cpp:192] Version: 1.4.0
I0509 17:10:23.209862 18920 executor.cpp:410] Connected with the agent
I0509 17:10:23.210577 18931 executor.cpp:307] Sending SUBSCRIBE call to http://172.17.0.2:37663/slave(747)/api/v1/executor
I0509 17:10:23.211664 18926 process.cpp:3730] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/executor'
I0509 17:10:23.214634 18927 http.cpp:1115] HTTP POST for /slave(747)/api/v1/executor from 172.17.0.2:53633
I0509 17:10:23.214829 18927 slave.cpp:3568] Received Subscribe request for HTTP executor 'default' of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.216248 18927 slave.cpp:2523] Sending queued task 'aa7d3963-79a7-4282-9d42-468cc002a272' to executor 'default' of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (via HTTP)
I0509 17:10:23.217861 18925 executor.cpp:725] Enqueuing event SUBSCRIBED received from http://172.17.0.2:37663/slave(747)/api/v1/executor
I0509 17:10:23.218641 18927 executor.cpp:725] Enqueuing event LAUNCH received from http://172.17.0.2:37663/slave(747)/api/v1/executor
I0509 17:10:23.219657 18917 executor.cpp:307] Sending UPDATE call to http://172.17.0.2:37663/slave(747)/api/v1/executor
I0509 17:10:23.220518 18921 process.cpp:3730] Handling HTTP event for process 'slave(747)' with path: '/slave(747)/api/v1/executor'
I0509 17:10:23.223026 18917 http.cpp:1115] HTTP POST for /slave(747)/api/v1/executor from 172.17.0.2:53634
I0509 17:10:23.223227 18917 slave.cpp:4215] Handling status update TASK_RUNNING (UUID: b9bb2473-76d3-4a0c-a76c-33e5f0a52b21) for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.224031 18927 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: b9bb2473-76d3-4a0c-a76c-33e5f0a52b21) for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.224124 18927 status_update_manager.cpp:500] Creating StatusUpdate stream for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.224687 18927 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: b9bb2473-76d3-4a0c-a76c-33e5f0a52b21) for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 to the agent
I0509 17:10:23.224979 18932 slave.cpp:4655] Forwarding the update TASK_RUNNING (UUID: b9bb2473-76d3-4a0c-a76c-33e5f0a52b21) for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 to master@172.17.0.2:37663
I0509 17:10:23.225280 18932 slave.cpp:4549] Status update manager successfully handled status update TASK_RUNNING (UUID: b9bb2473-76d3-4a0c-a76c-33e5f0a52b21) for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.225472 18923 master.cpp:6469] Status update TASK_RUNNING (UUID: b9bb2473-76d3-4a0c-a76c-33e5f0a52b21) for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 from agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.225534 18923 master.cpp:6537] Forwarding status update TASK_RUNNING (UUID: b9bb2473-76d3-4a0c-a76c-33e5f0a52b21) for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.226207 18923 master.cpp:8568] Updating the state of task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0509 17:10:23.226459 18931 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from http://172.17.0.2:37663/slave(747)/api/v1/executor
I0509 17:10:23.227748 18923 scheduler.cpp:676] Enqueuing event UPDATE received from http://172.17.0.2:37663/master/api/v1/scheduler
I0509 17:10:23.229053 18923 scheduler.cpp:243] Sending TEARDOWN call to http://172.17.0.2:37663/master/api/v1/scheduler
I0509 17:10:23.230149 18931 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0509 17:10:23.267568 18931 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:53629
I0509 17:10:23.267861 18931 master.cpp:8000] Removing framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (default)
I0509 17:10:23.267895 18931 master.cpp:3160] Deactivating framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (default)
I0509 17:10:23.268097 18922 hierarchical.cpp:374] Deactivated framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.268146 18930 slave.cpp:3057] Asked to shut down framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 by master@172.17.0.2:37663
I0509 17:10:23.268187 18931 master.cpp:8568] Updating the state of task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0509 17:10:23.268290 18930 slave.cpp:3082] Shutting down framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.268379 18930 slave.cpp:5475] Shutting down executor 'default' of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (via HTTP)
I0509 17:10:23.269076 18931 master.cpp:8662] Removing task aa7d3963-79a7-4282-9d42-468cc002a272 with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 on agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.269444 18930 executor.cpp:725] Enqueuing event SHUTDOWN received from http://172.17.0.2:37663/slave(747)/api/v1/executor
I0509 17:10:23.269470 18931 master.cpp:8691] Removing executor 'default' with resources {} of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 on agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.269980 18932 hierarchical.cpp:1114] 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 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 from framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.270586 18932 hierarchical.cpp:325] Removed framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
E0509 17:10:23.271215 18924 scheduler.cpp:649] End-Of-File received from master. The master closed the event stream
I0509 17:10:23.271620 18930 scheduler.cpp:444] Re-detecting master
I0509 17:10:23.272217 18930 scheduler.cpp:395] Ignoring disconnection attempt from stale connection
I0509 17:10:23.272339 18930 scheduler.cpp:395] Ignoring disconnection attempt from stale connection
I0509 17:10:23.272439 18930 scheduler.cpp:470] New master detected at master@172.17.0.2:37663
I0509 17:10:23.274272 18930 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0509 17:10:23.276834 18927 slave.cpp:5161] Executor 'default' of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 exited with status 0
I0509 17:10:23.277009 18927 slave.cpp:5261] Cleaning up executor 'default' of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (via HTTP)
W0509 17:10:23.277138 18919 master.cpp:6592] Ignoring unknown exited executor 'default' of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 on agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.277463 18928 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB/slaves/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0/frameworks/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000/executors/default/runs/d8779779-e33c-49c1-ac8c-2b7bdc11f451' for gc 6.99999678964148days in the future
I0509 17:10:23.277660 18927 slave.cpp:5349] Cleaning up framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.277660 18931 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB/slaves/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0/frameworks/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000/executors/default' for gc 6.99999678711704days in the future
I0509 17:10:23.278038 18932 status_update_manager.cpp:285] Closing status update streams for framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.278095 18932 status_update_manager.cpp:531] Cleaning up status update stream for task aa7d3963-79a7-4282-9d42-468cc002a272 of framework 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000
I0509 17:10:23.278131 18928 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_3_NqfShB/slaves/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0/frameworks/03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000' for gc 6.99999678167704days in the future
I0509 17:10:23.278177 18927 slave.cpp:790] Agent terminating
I0509 17:10:23.278522 18931 master.cpp:1313] Agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156) disconnected
I0509 17:10:23.278573 18931 master.cpp:3197] Disconnecting agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.278897 18931 master.cpp:3216] Deactivating agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 at slave(747)@172.17.0.2:37663 (f09954bfa156)
I0509 17:10:23.279040 18922 hierarchical.cpp:653] Agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0 deactivated
I0509 17:10:23.281328 18921 master.cpp:1155] Master terminating
I0509 17:10:23.281937 18920 hierarchical.cpp:558] Removed agent 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-S0
W0509 17:10:23.283430  1433 master.hpp:2677] Failed to close HTTP pipe for 03fa4b85-13d5-4a30-9d9d-9704ae85dea2-0000 (default)
[       OK ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/3 (153 ms)
E0509 17:10:23.287585 18933 process.cpp:950] Failed to accept socket: future discarded
I0509 17:10:23.293468  1433 process.cpp:1266] libprocess is initialized on 172.17.0.2:40036 with 16 worker threads
[----------] 4 tests from ContentTypeAndSSLConfig/SchedulerSSLTest (1818 ms total)

[----------] 9 tests from Endpoint/SlaveEndpointTest
*** Aborted at 1494349823 (unix time) try "date -d @1494349823" if you are using GNU date ***
PC: @     0x7f410f4d111a __GI_strcmp
*** SIGSEGV (@0x0) received by PID 1433 (TID 0x7f411afb78c0) from PID 0; stack trace: ***
    @     0x7f40fe10dfc5 (unknown)
    @     0x7f40fe1132c1 (unknown)
    @     0x7f40fe1070a8 (unknown)
    @     0x7f4110449370 (unknown)
    @     0x7f410f4d111a __GI_strcmp
    @     0x7f410f53f7b0 __tfind
    @     0x7f410f48441c __add_to_environ
    @     0x7f4118fbff71 os::setenv()
    @     0x7f41196ae1e0 mesos::internal::tests::MesosTest::SetUpTestCase()
    @     0x7f411a09bbc8 testing::TestCase::RunSetUpTestCase()
    @     0x7f411a0a5b1f testing::internal::HandleSehExceptionsInMethodIfSupported<>()
    @     0x7f411a0a0d16 testing::internal::HandleExceptionsInMethodIfSupported<>()
    @     0x7f411a082bad testing::TestCase::Run()
    @     0x7f411a0894b2 testing::internal::UnitTestImpl::RunAllTests()
    @     0x7f411a0a624f testing::internal::HandleSehExceptionsInMethodIfSupported<>()
    @     0x7f411a0a1290 testing::internal::HandleExceptionsInMethodIfSupported<>()
    @     0x7f411a0881f8 testing::UnitTest::Run()
    @     0x7f41193f90a3 RUN_ALL_TESTS()
    @     0x7f41193f8b74 main
    @     0x7f410f46db35 __libc_start_main
    @     0x7f4118ba7ad9 (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1494347233-25127
Untagged: mesos-1494347233-25127:latest
Deleted: sha256:b5ec8327bd9526440c821e37ad28f188b16463ea6b50bd1355bf3471e9ff92d3
Deleted: sha256:062cd87b685b107c223e02744117de28d13f4a2271460175f9e0d894615770b8
Deleted: sha256:26e40d240e6f2e4e4b4650cfda0f5a8efe971143e3c84f6657cfc08e76fc88a6
Deleted: sha256:16c5f1316d4b29cac40ec7abec82b44310380ff0a18b0a55a62483cb102e8320
Deleted: sha256:cbe62b5d27322304efb17aa5623e45988cdd5d3b26ddfd369fe0edcfe65111b9
Deleted: sha256:dcc9bd51f517919606cd13717b96ece4ffc40551a9506b4f0f9944816a3726a7
Deleted: sha256:c9a2826b9172b13ebb2a1a4fd074cd5ef525fc52493925415bdadf97ca21739c
Deleted: sha256:14dfaa1f1717875ab4b5d1cd64655acfce7c33379e845682afc0058063148344
Deleted: sha256:81593bc7be092f473b1eef492bf9469ae0bb00153589f51bc79a45a163ef2e00
Deleted: sha256:5a5e4ff7c37fe99223654a7051831f2b4e6f9a94bc20716f1ef9da7da2f8b0a8
Deleted: sha256:6b4ce86dfa9d978878ae603f0ae9edbc6180cf56d65093a72b9782a58f8a5a32
Deleted: sha256:cd17f57977cff24ee06c5665f15829506a3bbf34c8b7ee4e1dd1c5228734b745
Deleted: sha256:debe4ceae836b041e718aaae29c657811e41eda4e91b89d51107dad66e7936cd
Deleted: sha256:39917cb548510508f11a5e1692ddc84eebab7aaae330a1860206ee7a3a9b4aab
Deleted: sha256:411502e01bd14438e3d78340d42f4b628aebcdff4281f07509343162cb255069
Deleted: sha256:c0c418f2f34675eebc47a40aaabfd109ef32c6ce17945bf80a82807f5a34bba7
Deleted: sha256:42a33870a767a840514ab2d1d20a11673ed0e26f5bceba3a7fe9b71ce6fb2126
Deleted: sha256:6e5847749fe5aed3ecbd0eb010ff1762c0d9fa64333c0da83679fc5c7ef60a18
Deleted: sha256:7d1f25c7b975a33052a919564c8b5c7f407914332296c0b4b5ed86b7b7722c21
Deleted: sha256:b64a1d74a0ee249a262dfbd80bc3e580584756cfe16b10944be2ec5d9d594763
Deleted: sha256:df7580e85c89b238ec7a92eb6cae6c322cd48209550ac8cd6e2091e17eb02ce1
Deleted: sha256:b0e5afabe70667fb7b167a2b91885babf2ba4da37b10cd8ced4c0650a815ee14
Deleted: sha256:f97c37e3833e25dd424e6a655824852f73ce77c02d77c0fe20b05c170557acbc
Deleted: sha256:8d1d6ef68dee51dea433a0142198e3ea47d27ebe7e0bc6870d4e17003e85809b
Deleted: sha256:c05bc7b66f32e7089b85d61331230f84ea5c729208553d4650b8b88d1b9cb270
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com

Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3639

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3639/display/redirect?page=changes>


Re: Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3638

Posted by Vinod Kone <vi...@apache.org>.
Hmm. This looks new?

On Tue, May 9, 2017 at 10:10 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:

> [       OK ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/3
> (153 ms)
> E0509 17:10:23.287585 18933 process.cpp:950] Failed to accept socket:
> future discarded
> I0509 17:10:23.293468  1433 process.cpp:1266] libprocess is initialized on
> 172.17.0.2:40036 with 16 worker threads
> [----------] 4 tests from ContentTypeAndSSLConfig/SchedulerSSLTest (1818
> ms total)
>
> [----------] 9 tests from Endpoint/SlaveEndpointTest
> *** Aborted at 1494349823 (unix time) try "date -d @1494349823" if you are
> using GNU date ***
> PC: @     0x7f410f4d111a __GI_strcmp
> *** SIGSEGV (@0x0) received by PID 1433 (TID 0x7f411afb78c0) from PID 0;
> stack trace: ***
>     @     0x7f40fe10dfc5 (unknown)
>     @     0x7f40fe1132c1 (unknown)
>     @     0x7f40fe1070a8 (unknown)
>     @     0x7f4110449370 (unknown)
>     @     0x7f410f4d111a __GI_strcmp
>     @     0x7f410f53f7b0 __tfind
>     @     0x7f410f48441c __add_to_environ
>     @     0x7f4118fbff71 os::setenv()
>     @     0x7f41196ae1e0 mesos::internal::tests::
> MesosTest::SetUpTestCase()
>     @     0x7f411a09bbc8 testing::TestCase::RunSetUpTestCase()
>     @     0x7f411a0a5b1f testing::internal::HandleSehExceptionsInMethodIfS
> upported<>()
>     @     0x7f411a0a0d16 testing::internal::HandleExceptionsInMethodIfSupp
> orted<>()
>     @     0x7f411a082bad testing::TestCase::Run()
>     @     0x7f411a0894b2 testing::internal::UnitTestImpl::RunAllTests()
>     @     0x7f411a0a624f testing::internal::HandleSehExceptionsInMethodIfS
> upported<>()
>     @     0x7f411a0a1290 testing::internal::HandleExceptionsInMethodIfSupp
> orted<>()
>     @     0x7f411a0881f8 testing::UnitTest::Run()
>     @     0x7f41193f90a3 RUN_ALL_TESTS()
>     @     0x7f41193f8b74 main
>     @     0x7f410f46db35 __libc_start_main
>     @     0x7f4118ba7ad9 (unknown)
> make[4]: *** [check-local] Segmentation fault
> make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
> make: *** [distcheck] Error 1
>