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/23 05:01:54 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3708
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3708/display/redirect?page=changes>
Changes:
[kapil] Replace some EXPECTs with ASSERTs in master_tests to avoid segfaults.
[neil.conway] Avoid validation of agent re-registration message if possible.
------------------------------------------
[...truncated 25.07 MB...]
I0523 02:42:21.742839 1189 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0523 02:42:21.743835 1184 slave.cpp:5172] Executor 'default' of framework b776c757-c109-46c2-ae57-ca38803baddd-0000 exited with status 0
I0523 02:42:21.743970 1184 slave.cpp:5272] Cleaning up executor 'default' of framework b776c757-c109-46c2-ae57-ca38803baddd-0000 (via HTTP)
W0523 02:42:21.744133 1188 master.cpp:6594] Ignoring unknown exited executor 'default' of framework b776c757-c109-46c2-ae57-ca38803baddd-0000 on agent b776c757-c109-46c2-ae57-ca38803baddd-S0 at slave(705)@172.17.0.3:45152 (cfb445764a9a)
I0523 02:42:21.744205 1184 slave.cpp:5360] Cleaning up framework b776c757-c109-46c2-ae57-ca38803baddd-0000
I0523 02:42:21.744284 1182 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_nS4Aub/slaves/b776c757-c109-46c2-ae57-ca38803baddd-S0/frameworks/b776c757-c109-46c2-ae57-ca38803baddd-0000/executors/default/runs/3bbaefea-ce87-4c53-8b22-1689adf55abe' for gc 6.99999138754667days in the future
I0523 02:42:21.744398 1181 status_update_manager.cpp:285] Closing status update streams for framework b776c757-c109-46c2-ae57-ca38803baddd-0000
I0523 02:42:21.744423 1181 status_update_manager.cpp:531] Cleaning up status update stream for task edc4e0b9-546a-4dc7-8e0f-edc60ea812b4 of framework b776c757-c109-46c2-ae57-ca38803baddd-0000
I0523 02:42:21.744621 1182 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_nS4Aub/slaves/b776c757-c109-46c2-ae57-ca38803baddd-S0/frameworks/b776c757-c109-46c2-ae57-ca38803baddd-0000/executors/default' for gc 6.99999138666667days in the future
I0523 02:42:21.744652 1182 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_1_nS4Aub/slaves/b776c757-c109-46c2-ae57-ca38803baddd-S0/frameworks/b776c757-c109-46c2-ae57-ca38803baddd-0000' for gc 6.99999138577481days in the future
I0523 02:42:21.744846 27838 slave.cpp:794] Agent terminating
I0523 02:42:21.745110 1183 master.cpp:1313] Agent b776c757-c109-46c2-ae57-ca38803baddd-S0 at slave(705)@172.17.0.3:45152 (cfb445764a9a) disconnected
I0523 02:42:21.745239 1183 master.cpp:3197] Disconnecting agent b776c757-c109-46c2-ae57-ca38803baddd-S0 at slave(705)@172.17.0.3:45152 (cfb445764a9a)
I0523 02:42:21.745273 1183 master.cpp:3216] Deactivating agent b776c757-c109-46c2-ae57-ca38803baddd-S0 at slave(705)@172.17.0.3:45152 (cfb445764a9a)
I0523 02:42:21.745517 1186 hierarchical.cpp:653] Agent b776c757-c109-46c2-ae57-ca38803baddd-S0 deactivated
I0523 02:42:21.746678 27838 master.cpp:1155] Master terminating
I0523 02:42:21.746950 1183 hierarchical.cpp:558] Removed agent b776c757-c109-46c2-ae57-ca38803baddd-S0
W0523 02:42:21.747061 27838 master.hpp:2677] Failed to close HTTP pipe for b776c757-c109-46c2-ae57-ca38803baddd-0000 (default)
[ OK ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/1 (128 ms)
[ RUN ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/2
I0523 02:42:21.961248 27838 openssl.cpp:424] CA directory path unspecified! NOTE: Set CA directory path with LIBPROCESS_SSL_CA_DIR=<dirpath>
I0523 02:42:21.961287 27838 openssl.cpp:429] Will not verify peer certificate!
NOTE: Set LIBPROCESS_SSL_VERIFY_CERT=1 to enable peer certificate verification
I0523 02:42:21.961297 27838 openssl.cpp:449] LIBPROCESS_SSL_REQUIRE_CERT implies peer certificate verification.
LIBPROCESS_SSL_VERIFY_CERT set to true
I0523 02:42:21.961460 27838 openssl.cpp:478] Using CA file: /tmp/48aqhp/cert.pem
E0523 02:42:21.962086 1191 process.cpp:950] Failed to accept socket: future discarded
I0523 02:42:21.963619 27838 process.cpp:1266] libprocess is initialized on 172.17.0.3:37750 with 16 worker threads
I0523 02:42:21.964293 27838 cluster.cpp:162] Creating default 'local' authorizer
I0523 02:42:21.965111 1193 master.cpp:436] Master dc5b0340-77f8-4a83-9d35-3d118e1aa9d9 (cfb445764a9a) started on 172.17.0.3:37750
I0523 02:42:21.965128 1193 master.cpp:438] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/48aqhp/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/48aqhp/master" --zk_session_timeout="10secs"
I0523 02:42:21.965235 1193 master.cpp:488] Master only allowing authenticated frameworks to register
I0523 02:42:21.965240 1193 master.cpp:502] Master only allowing authenticated agents to register
I0523 02:42:21.965245 1193 master.cpp:515] Master only allowing authenticated HTTP frameworks to register
I0523 02:42:21.965250 1193 credentials.hpp:37] Loading credentials for authentication from '/tmp/48aqhp/credentials'
I0523 02:42:21.965353 1193 master.cpp:560] Using default 'crammd5' authenticator
I0523 02:42:21.965387 1193 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0523 02:42:21.965411 1193 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0523 02:42:21.965425 1193 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0523 02:42:21.965445 1193 master.cpp:640] Authorization enabled
I0523 02:42:21.965979 1193 hierarchical.cpp:158] Initialized hierarchical allocator process
I0523 02:42:21.965996 1193 whitelist_watcher.cpp:77] No whitelist given
I0523 02:42:21.966274 1205 master.cpp:2161] Elected as the leading master!
I0523 02:42:21.966289 1205 master.cpp:1700] Recovering from registrar
I0523 02:42:21.966418 1205 registrar.cpp:345] Recovering registrar
I0523 02:42:21.967519 1206 registrar.cpp:389] Successfully fetched the registry (0B) in 1.07392ms
I0523 02:42:21.967562 1206 registrar.cpp:493] Applied 1 operations in 4540ns; attempting to update the registry
I0523 02:42:21.968356 1206 registrar.cpp:550] Successfully updated the registry in 774912ns
I0523 02:42:21.968402 1206 registrar.cpp:422] Successfully recovered registrar
I0523 02:42:21.968533 1207 master.cpp:1799] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0523 02:42:21.968570 1198 hierarchical.cpp:185] Skipping recovery of hierarchical allocator: nothing to recover
I0523 02:42:21.970537 27838 cluster.cpp:448] Creating default 'local' authorizer
I0523 02:42:21.971122 1202 slave.cpp:225] Mesos agent started on (706)@172.17.0.3:37750
I0523 02:42:21.971139 1202 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_2_qcIhGx/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_2_qcIhGx/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_qcIhGx/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_2_qcIhGx/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/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_qcIhGx" --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_2_eolQtY"
I0523 02:42:21.971365 1202 credentials.hpp:86] Loading credential for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_qcIhGx/credential'
I0523 02:42:21.971460 1202 slave.cpp:258] Agent using credential for: test-principal
I0523 02:42:21.971475 1202 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_qcIhGx/http_credentials'
I0523 02:42:21.971638 1202 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0523 02:42:21.971680 1202 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0523 02:42:21.971753 1202 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 02:42:21.971784 1202 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0523 02:42:21.971849 1202 http.cpp:975] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 02:42:21.971887 1202 http.cpp:996] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0523 02:42:21.972225 27838 scheduler.cpp:184] Version: 1.4.0
I0523 02:42:21.972398 1202 slave.cpp:529] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 02:42:21.972441 1202 slave.cpp:537] Agent attributes: [ ]
I0523 02:42:21.972450 1202 slave.cpp:542] Agent hostname: cfb445764a9a
I0523 02:42:21.972488 1206 scheduler.cpp:470] New master detected at master@172.17.0.3:37750
I0523 02:42:21.972802 1202 status_update_manager.cpp:177] Pausing sending status updates
I0523 02:42:21.973062 1202 state.cpp:62] Recovering state from '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_eolQtY/meta'
I0523 02:42:21.973179 1202 status_update_manager.cpp:203] Recovering status update manager
I0523 02:42:21.973278 1202 slave.cpp:5974] Finished recovery
I0523 02:42:21.973546 1206 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0523 02:42:21.973651 1202 slave.cpp:6156] Querying resource estimator for oversubscribable resources
I0523 02:42:21.973719 1198 status_update_manager.cpp:177] Pausing sending status updates
I0523 02:42:21.973723 1202 slave.cpp:922] New master detected at master@172.17.0.3:37750
I0523 02:42:21.973755 1202 slave.cpp:957] Detecting new master
I0523 02:42:21.974212 1205 slave.cpp:6170] Received oversubscribable resources {} from the resource estimator
I0523 02:42:21.987620 1192 slave.cpp:984] Authenticating with master master@172.17.0.3:37750
I0523 02:42:21.987663 1192 slave.cpp:995] Using default CRAM-MD5 authenticatee
I0523 02:42:21.987819 1201 authenticatee.cpp:121] Creating new client SASL connection
I0523 02:42:21.988011 1201 master.cpp:7477] Authenticating slave(706)@172.17.0.3:37750
I0523 02:42:21.988168 1201 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1303)@172.17.0.3:37750
I0523 02:42:21.988235 1201 scheduler.cpp:361] Connected with the master at https://172.17.0.3:37750/master/api/v1/scheduler
I0523 02:42:21.988343 1201 authenticator.cpp:98] Creating new server SASL connection
I0523 02:42:21.988492 1201 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0523 02:42:21.988507 1201 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0523 02:42:21.988561 1201 authenticator.cpp:204] Received SASL authentication start
I0523 02:42:21.988597 1201 authenticator.cpp:326] Authentication requires more steps
I0523 02:42:21.988643 1201 authenticatee.cpp:259] Received SASL authentication step
I0523 02:42:21.988792 1196 authenticator.cpp:232] Received SASL authentication step
I0523 02:42:21.988816 1196 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cfb445764a9a' server FQDN: 'cfb445764a9a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0523 02:42:21.988826 1196 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0523 02:42:21.988840 1196 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0523 02:42:21.988854 1196 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'cfb445764a9a' server FQDN: 'cfb445764a9a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0523 02:42:21.988862 1196 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0523 02:42:21.988868 1196 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0523 02:42:21.988883 1196 authenticator.cpp:318] Authentication success
I0523 02:42:21.988939 1196 scheduler.cpp:243] Sending SUBSCRIBE call to https://172.17.0.3:37750/master/api/v1/scheduler
I0523 02:42:21.989061 1196 authenticatee.cpp:299] Authentication success
I0523 02:42:21.989085 1196 master.cpp:7507] Successfully authenticated principal 'test-principal' at slave(706)@172.17.0.3:37750
I0523 02:42:21.989104 1196 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1303)@172.17.0.3:37750
I0523 02:42:21.989356 1196 slave.cpp:1079] Successfully authenticated with master master@172.17.0.3:37750
I0523 02:42:21.989424 1196 slave.cpp:1507] Will retry registration in 2.753748ms if necessary
I0523 02:42:21.989512 1196 master.cpp:5429] Received register agent message from slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:21.989549 1196 master.cpp:3659] Authorizing agent with principal 'test-principal'
I0523 02:42:21.989593 1196 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0523 02:42:21.989747 1196 master.cpp:5565] Registering agent at slave(706)@172.17.0.3:37750 (cfb445764a9a) with id dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0
I0523 02:42:21.989851 1196 registrar.cpp:493] Applied 1 operations in 14397ns; attempting to update the registry
I0523 02:42:21.990751 1193 registrar.cpp:550] Successfully updated the registry in 865024ns
I0523 02:42:21.990957 1193 slave.cpp:4749] Received ping from slave-observer(639)@172.17.0.3:37750
I0523 02:42:21.990998 1203 master.cpp:5640] Registered agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0523 02:42:21.991144 1196 hierarchical.cpp:525] Added agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 (cfb445764a9a) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0523 02:42:21.991277 1193 slave.cpp:1125] Registered with master master@172.17.0.3:37750; given agent ID dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0
I0523 02:42:21.991298 1193 fetcher.cpp:94] Clearing fetcher cache
I0523 02:42:21.991315 1196 hierarchical.cpp:1850] No allocations performed
I0523 02:42:21.991333 1196 hierarchical.cpp:1434] Performed allocation for 1 agents in 45734ns
I0523 02:42:21.991399 1205 status_update_manager.cpp:184] Resuming sending status updates
I0523 02:42:21.991593 1193 slave.cpp:1153] Checkpointing SlaveInfo to '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_eolQtY/meta/slaves/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0/slave.info'
I0523 02:42:21.991827 1193 slave.cpp:1191] Forwarding total oversubscribed resources {}
I0523 02:42:21.991896 1193 master.cpp:6326] Received update of agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a) with total oversubscribed resources {}
I0523 02:42:22.026796 1194 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:55054
I0523 02:42:22.027742 1194 master.cpp:2513] Received subscription request for HTTP framework 'default'
I0523 02:42:22.027779 1194 master.cpp:2197] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0523 02:42:22.027937 1202 master.cpp:2628] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0523 02:42:22.028173 1194 hierarchical.cpp:273] Added framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.028367 1204 master.hpp:2194] Sending heartbeat to dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.028452 1194 hierarchical.cpp:1940] No inverse offers to send out!
I0523 02:42:22.028473 1194 hierarchical.cpp:1434] Performed allocation for 1 agents in 257422ns
I0523 02:42:22.028738 1195 master.cpp:7307] Sending 1 offers to framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (default)
I0523 02:42:22.066673 1198 scheduler.cpp:676] Enqueuing event SUBSCRIBED received from https://172.17.0.3:37750/master/api/v1/scheduler
I0523 02:42:22.067277 1198 scheduler.cpp:676] Enqueuing event HEARTBEAT received from https://172.17.0.3:37750/master/api/v1/scheduler
I0523 02:42:22.067528 1198 scheduler.cpp:676] Enqueuing event OFFERS received from https://172.17.0.3:37750/master/api/v1/scheduler
I0523 02:42:22.068241 1198 scheduler.cpp:243] Sending ACCEPT call to https://172.17.0.3:37750/master/api/v1/scheduler
I0523 02:42:22.068984 1198 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0523 02:42:22.106799 1195 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:55053
I0523 02:42:22.107452 1195 master.cpp:3875] Processing ACCEPT call for offers: [ dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-O0 ] on agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a) for framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (default)
I0523 02:42:22.107492 1195 master.cpp:3426] Authorizing framework principal 'test-principal' to launch task 3aef37e5-0ff4-4751-b78f-43d7845e09af
W0523 02:42:22.108191 1207 validation.cpp:1284] Executor 'default' for task '3aef37e5-0ff4-4751-b78f-43d7845e09af' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0523 02:42:22.108222 1207 validation.cpp:1296] Executor 'default' for task '3aef37e5-0ff4-4751-b78f-43d7845e09af' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0523 02:42:22.108371 1207 master.cpp:9328] Adding task 3aef37e5-0ff4-4751-b78f-43d7845e09af with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:22.108458 1207 master.cpp:4531] Launching task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (default) with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] on agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:22.108721 1205 slave.cpp:1617] Got assigned task '3aef37e5-0ff4-4751-b78f-43d7845e09af' for framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.109027 1195 hierarchical.cpp:850] Updated allocation of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 on agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-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]
I0523 02:42:22.109207 1205 slave.cpp:1898] Authorizing task '3aef37e5-0ff4-4751-b78f-43d7845e09af' for framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.109249 1205 slave.cpp:6593] Authorizing framework principal 'test-principal' to launch task 3aef37e5-0ff4-4751-b78f-43d7845e09af
I0523 02:42:22.109635 1205 slave.cpp:2085] Launching task '3aef37e5-0ff4-4751-b78f-43d7845e09af' for framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.110569 1205 paths.cpp:573] Trying to chown '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_eolQtY/slaves/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0/frameworks/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000/executors/default/runs/8302d342-421c-43df-993d-e940917ca4d5' to user 'mesos'
I0523 02:42:22.110868 1205 slave.cpp:6933] Launching executor 'default' of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 with resources {} in work directory '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_eolQtY/slaves/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0/frameworks/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000/executors/default/runs/8302d342-421c-43df-993d-e940917ca4d5'
I0523 02:42:22.111279 1205 slave.cpp:2314] Queued task '3aef37e5-0ff4-4751-b78f-43d7845e09af' for executor 'default' of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.111341 1205 slave.cpp:875] Successfully attached file '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_eolQtY/slaves/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0/frameworks/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000/executors/default/runs/8302d342-421c-43df-993d-e940917ca4d5'
I0523 02:42:22.112349 1195 executor.cpp:192] Version: 1.4.0
I0523 02:42:22.134802 1201 executor.cpp:410] Connected with the agent
I0523 02:42:22.135289 1201 executor.cpp:307] Sending SUBSCRIBE call to https://172.17.0.3:37750/slave(706)/api/v1/executor
I0523 02:42:22.136200 1201 process.cpp:3730] Handling HTTP event for process 'slave(706)' with path: '/slave(706)/api/v1/executor'
I0523 02:42:22.175175 1203 http.cpp:1115] HTTP POST for /slave(706)/api/v1/executor from 172.17.0.3:55065
I0523 02:42:22.175284 1203 slave.cpp:3572] Received Subscribe request for HTTP executor 'default' of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.175683 1203 slave.cpp:2527] Sending queued task '3aef37e5-0ff4-4751-b78f-43d7845e09af' to executor 'default' of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (via HTTP)
I0523 02:42:22.214900 1192 executor.cpp:725] Enqueuing event SUBSCRIBED received from https://172.17.0.3:37750/slave(706)/api/v1/executor
I0523 02:42:22.215574 1194 executor.cpp:725] Enqueuing event LAUNCH received from https://172.17.0.3:37750/slave(706)/api/v1/executor
I0523 02:42:22.216804 1206 executor.cpp:307] Sending UPDATE call to https://172.17.0.3:37750/slave(706)/api/v1/executor
I0523 02:42:22.217507 1193 process.cpp:3730] Handling HTTP event for process 'slave(706)' with path: '/slave(706)/api/v1/executor'
I0523 02:42:22.255022 1202 http.cpp:1115] HTTP POST for /slave(706)/api/v1/executor from 172.17.0.3:55066
I0523 02:42:22.255154 1202 slave.cpp:4219] Handling status update TASK_RUNNING (UUID: 9efbd52f-ad39-4950-9f84-08d5bed6e622) for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.255532 1202 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 9efbd52f-ad39-4950-9f84-08d5bed6e622) for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.255559 1202 status_update_manager.cpp:500] Creating StatusUpdate stream for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.255776 1202 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 9efbd52f-ad39-4950-9f84-08d5bed6e622) for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 to the agent
I0523 02:42:22.255903 1202 slave.cpp:4659] Forwarding the update TASK_RUNNING (UUID: 9efbd52f-ad39-4950-9f84-08d5bed6e622) for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 to master@172.17.0.3:37750
I0523 02:42:22.255992 1202 slave.cpp:4553] Status update manager successfully handled status update TASK_RUNNING (UUID: 9efbd52f-ad39-4950-9f84-08d5bed6e622) for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.256150 1199 master.cpp:6471] Status update TASK_RUNNING (UUID: 9efbd52f-ad39-4950-9f84-08d5bed6e622) for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 from agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:22.256176 1199 master.cpp:6539] Forwarding status update TASK_RUNNING (UUID: 9efbd52f-ad39-4950-9f84-08d5bed6e622) for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.256438 1199 master.cpp:8570] Updating the state of task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0523 02:42:22.256983 1203 executor.cpp:725] Enqueuing event ACKNOWLEDGED received from https://172.17.0.3:37750/slave(706)/api/v1/executor
I0523 02:42:22.257321 1196 scheduler.cpp:676] Enqueuing event UPDATE received from https://172.17.0.3:37750/master/api/v1/scheduler
I0523 02:42:22.257927 1200 scheduler.cpp:243] Sending TEARDOWN call to https://172.17.0.3:37750/master/api/v1/scheduler
I0523 02:42:22.258420 1200 process.cpp:3730] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0523 02:42:22.298696 1200 http.cpp:1115] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:55053
I0523 02:42:22.298796 1200 master.cpp:8002] Removing framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (default)
I0523 02:42:22.298810 1200 master.cpp:3160] Deactivating framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (default)
I0523 02:42:22.298859 1200 master.cpp:8570] Updating the state of task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (latest state: TASK_KILLED, status update state: TASK_KILLED)
I0523 02:42:22.299005 1200 master.cpp:8664] Removing task 3aef37e5-0ff4-4751-b78f-43d7845e09af with resources cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000] of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 on agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:22.299103 1200 master.cpp:8693] Removing executor 'default' with resources {} of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 on agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:22.299218 1200 hierarchical.cpp:374] Deactivated framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.299376 1200 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 dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 from framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.299445 1200 hierarchical.cpp:325] Removed framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.299470 1200 slave.cpp:3061] Asked to shut down framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 by master@172.17.0.3:37750
I0523 02:42:22.299482 1200 slave.cpp:3086] Shutting down framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.299491 1200 slave.cpp:5486] Shutting down executor 'default' of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (via HTTP)
E0523 02:42:22.300238 1200 scheduler.cpp:649] End-Of-File received from master. The master closed the event stream
I0523 02:42:22.300276 1200 executor.cpp:725] Enqueuing event SHUTDOWN received from https://172.17.0.3:37750/slave(706)/api/v1/executor
I0523 02:42:22.300477 1200 scheduler.cpp:444] Re-detecting master
I0523 02:42:22.300807 1200 scheduler.cpp:395] Ignoring disconnection attempt from stale connection
I0523 02:42:22.300834 1200 scheduler.cpp:395] Ignoring disconnection attempt from stale connection
I0523 02:42:22.300885 1200 scheduler.cpp:470] New master detected at master@172.17.0.3:37750
I0523 02:42:22.301612 1200 scheduler.cpp:479] Waiting for 0ns before initiating a re-(connection) attempt with the master
I0523 02:42:22.302755 1194 slave.cpp:5172] Executor 'default' of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 exited with status 0
I0523 02:42:22.302794 1194 slave.cpp:5272] Cleaning up executor 'default' of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (via HTTP)
I0523 02:42:22.302942 1194 slave.cpp:5360] Cleaning up framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
W0523 02:42:22.303079 1194 master.cpp:6594] Ignoring unknown exited executor 'default' of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 on agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:22.303112 1194 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_eolQtY/slaves/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0/frameworks/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000/executors/default/runs/8302d342-421c-43df-993d-e940917ca4d5' for gc 6.99999649436444days in the future
I0523 02:42:22.303153 1194 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_eolQtY/slaves/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0/frameworks/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000/executors/default' for gc 6.99999649383111days in the future
I0523 02:42:22.303174 1194 gc.cpp:55] Scheduling '/tmp/ContentTypeAndSSLConfig_SchedulerSSLTest_RunTaskAndTeardown_2_eolQtY/slaves/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0/frameworks/dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000' for gc 6.99999649324148days in the future
I0523 02:42:22.303195 1194 status_update_manager.cpp:285] Closing status update streams for framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.303205 1194 status_update_manager.cpp:531] Cleaning up status update stream for task 3aef37e5-0ff4-4751-b78f-43d7845e09af of framework dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000
I0523 02:42:22.303423 27838 slave.cpp:794] Agent terminating
I0523 02:42:22.303678 1197 master.cpp:1313] Agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a) disconnected
I0523 02:42:22.304426 1197 master.cpp:3197] Disconnecting agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:22.304519 1197 master.cpp:3216] Deactivating agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 at slave(706)@172.17.0.3:37750 (cfb445764a9a)
I0523 02:42:22.304667 1197 hierarchical.cpp:653] Agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0 deactivated
I0523 02:42:22.305449 27838 master.cpp:1155] Master terminating
W0523 02:42:22.305788 27838 master.hpp:2677] Failed to close HTTP pipe for dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-0000 (default)
I0523 02:42:22.305795 1204 hierarchical.cpp:558] Removed agent dc5b0340-77f8-4a83-9d35-3d118e1aa9d9-S0
[ OK ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/2 (559 ms)
[ RUN ] ContentTypeAndSSLConfig/SchedulerSSLTest.RunTaskAndTeardown/3
make[3]: *** [CMakeFiles/check] Killed
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-1495504106-13123
Untagged: mesos-1495504106-13123:latest
Deleted: sha256:2ce129a065efc01cf54c88b5f6cd67b42eae1c0a9b2d0c46a9e6ac3741a73cee
Deleted: sha256:2e5d073e4515f6544966c056bf30aaf786a9dde7d3af2d488fd8128faf227426
Deleted: sha256:b73baa0a6037949a07514bcb4a3ceb104f0949793c529c79dc25986182d5e8a3
Deleted: sha256:f27dbb2b0cf1bc2c5a06e6d02960585addbe115cb1213276c7e145be6343daa5
Deleted: sha256:8f950fecb8f6e98384e83a8b99d8ff5513a3c974cc9f1693e4f711bc4ec8ae8e
Deleted: sha256:57339c7582c2fdddbf6152a2c4a08c7e61f5e7a12fe06bacf4b1a1ad8bc88eb9
Deleted: sha256:2307a269a43a99510608d626f7833686321efb5ac23ac650d47e49937e88902c
Deleted: sha256:d1db6b43c7671f04b9ea8973b955b71fdb6d0d3c7ed4f0ee579f344287251f9c
Deleted: sha256:1c859f2602587a8bb76ceff4e0e4cd5c3947605a3355550c0a67f930b4699fd2
Deleted: sha256:057ebb63036be44b333ece66cd549a01cf4ed64694208b18bd00a993dc5f8b8b
Deleted: sha256:8fe5dcc1bb65620326526b3ed2b75f19d20352f12d719be22a8e9d3f92f17a3b
Deleted: sha256:796ba46376c78476bf31556183968d14b243b1261bf664529218db0120a5a6df
Deleted: sha256:224942a0b21bd1c25041671f0ef8388deb71e7b3f30901bd84b2ecf09e237fd1
Deleted: sha256:64a928dbb352d604b7c4e2f1c5b501055a454da8de41b79c7f4202de6ef13c83
Deleted: sha256:338311577238caf9916b9315e23a57710d72ccc556884e956b5c580b5ea11719
Deleted: sha256:67cd699e6c500dd6217752bd9949e84abd542ce8ec5f62140d50a452d6f75fb5
Deleted: sha256:a42152cf2b78b224f4b653921b9901998b1e95e2104067a0b9f0dd02bf705a05
Deleted: sha256:12798715810d8ee2f22145c5948f41ce090a842204d12463cabf6cd1bd12fe94
Deleted: sha256:d354c9ff46f45b6a0c5037a5304f683c22e4b42dbbb66905eb630b00ae0a5c09
Deleted: sha256:f08a62c35a56b16efd57f5c33cbd0887044ea1afe4be8e1c60bb40f316628822
Deleted: sha256:e96e5a6966d50e0709a37c0ee3cae9662c988fc56c70053ba8e1268027c06283
Deleted: sha256:83ccde4d1568698e36be6d053973c85881937fc2c8eafccedfe7b0c80921cde3
Deleted: sha256:d4890fa6ca4a5e7f2a8967b4e29cacf196916ea0c197b4f200e781e553912668
Deleted: sha256:bc60fc510a323a8899cba4e49da800220a5d156d7d695a04de06a9cc5094ac07
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,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3709
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3709/display/redirect?page=changes>