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/09/01 23:11:00 UTC

Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4186

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=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4186/display/redirect>

------------------------------------------
[...truncated 12.97 MB...]
I0901 23:10:51.930066  8723 hierarchical.cpp:1161] 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 34201cd2-3be4-4b16-bb4d-c72715fde255-S0 from framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000
I0901 23:10:51.930250  8713 master.cpp:9053] Removing executor 'default' with resources [] of framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000 on agent 34201cd2-3be4-4b16-bb4d-c72715fde255-S0 at slave(208)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.930269  8703 slave.cpp:5405] Executor 'default' of framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000 exited with status 0
I0901 23:10:51.930337  8703 slave.cpp:5509] Cleaning up executor 'default' of framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000 at executor(78)@172.17.0.7:45977
I0901 23:10:51.930553  8708 gc.cpp:91] Scheduling '/tmp/MasterTest_FrameworkMessage_6v6vnu/slaves/34201cd2-3be4-4b16-bb4d-c72715fde255-S0/frameworks/34201cd2-3be4-4b16-bb4d-c72715fde255-0000/executors/default/runs/540b614f-da93-4635-a626-b98608ebc313' for gc 6.99998923064889days in the future
I0901 23:10:51.930665  8703 slave.cpp:5605] Cleaning up framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000
I0901 23:10:51.930714  8708 gc.cpp:91] Scheduling '/tmp/MasterTest_FrameworkMessage_6v6vnu/slaves/34201cd2-3be4-4b16-bb4d-c72715fde255-S0/frameworks/34201cd2-3be4-4b16-bb4d-c72715fde255-0000/executors/default' for gc 6.99998922908444days in the future
W0901 23:10:51.930775  8713 master.cpp:6960] Ignoring unknown exited executor 'default' of framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000 on agent 34201cd2-3be4-4b16-bb4d-c72715fde255-S0 at slave(208)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.930816  8718 status_update_manager.cpp:285] Closing status update streams for framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000
I0901 23:10:51.930831  8708 gc.cpp:91] Scheduling '/tmp/MasterTest_FrameworkMessage_6v6vnu/slaves/34201cd2-3be4-4b16-bb4d-c72715fde255-S0/frameworks/34201cd2-3be4-4b16-bb4d-c72715fde255-0000' for gc 6.99998922729185days in the future
I0901 23:10:51.930860  8703 slave.cpp:859] Agent terminating
I0901 23:10:51.930868  8718 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000
I0901 23:10:51.930917  8710 hierarchical.cpp:355] Removed framework 34201cd2-3be4-4b16-bb4d-c72715fde255-0000
I0901 23:10:51.931058  8712 master.cpp:1321] Agent 34201cd2-3be4-4b16-bb4d-c72715fde255-S0 at slave(208)@172.17.0.7:45977 (e922e05f6b08) disconnected
I0901 23:10:51.931092  8712 master.cpp:3304] Disconnecting agent 34201cd2-3be4-4b16-bb4d-c72715fde255-S0 at slave(208)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.931159  8712 master.cpp:3323] Deactivating agent 34201cd2-3be4-4b16-bb4d-c72715fde255-S0 at slave(208)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.931253  8715 hierarchical.cpp:690] Agent 34201cd2-3be4-4b16-bb4d-c72715fde255-S0 deactivated
I0901 23:10:51.933934  8723 master.cpp:1163] Master terminating
I0901 23:10:51.934461  8719 hierarchical.cpp:626] Removed agent 34201cd2-3be4-4b16-bb4d-c72715fde255-S0
[       OK ] MasterTest.FrameworkMessage (60 ms)
[ RUN      ] MasterTest.MultipleExecutors
I0901 23:10:51.939754  8681 cluster.cpp:162] Creating default 'local' authorizer
I0901 23:10:51.942237  8709 master.cpp:445] Master 45a1618b-eec8-4155-821f-9f8325aea807 (e922e05f6b08) started on 172.17.0.7:45977
I0901 23:10:51.942275  8709 master.cpp:447] 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/4O2Bss/credentials" --filter_gpu_resources="true" --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="/mesos/mesos-1.5.0/_inst/share/mesos/webui" --work_dir="/tmp/4O2Bss/master" --zk_session_timeout="10secs"
I0901 23:10:51.942602  8709 master.cpp:497] Master only allowing authenticated frameworks to register
I0901 23:10:51.942615  8709 master.cpp:511] Master only allowing authenticated agents to register
I0901 23:10:51.942620  8709 master.cpp:524] Master only allowing authenticated HTTP frameworks to register
I0901 23:10:51.942626  8709 credentials.hpp:37] Loading credentials for authentication from '/tmp/4O2Bss/credentials'
I0901 23:10:51.942893  8709 master.cpp:569] Using default 'crammd5' authenticator
I0901 23:10:51.943045  8709 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0901 23:10:51.943195  8709 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0901 23:10:51.943315  8709 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0901 23:10:51.943428  8709 master.cpp:649] Authorization enabled
I0901 23:10:51.943598  8713 whitelist_watcher.cpp:77] No whitelist given
I0901 23:10:51.943598  8704 hierarchical.cpp:171] Initialized hierarchical allocator process
I0901 23:10:51.945963  8721 master.cpp:2166] Elected as the leading master!
I0901 23:10:51.945993  8721 master.cpp:1705] Recovering from registrar
I0901 23:10:51.946118  8708 registrar.cpp:347] Recovering registrar
I0901 23:10:51.946619  8708 registrar.cpp:391] Successfully fetched the registry (0B) in 466944ns
I0901 23:10:51.946707  8708 registrar.cpp:495] Applied 1 operations in 22006ns; attempting to update the registry
I0901 23:10:51.947178  8708 registrar.cpp:552] Successfully updated the registry in 408832ns
I0901 23:10:51.947270  8708 registrar.cpp:424] Successfully recovered registrar
I0901 23:10:51.947602  8715 master.cpp:1804] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0901 23:10:51.947643  8702 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
W0901 23:10:51.951488  8681 process.cpp:3240] Attempted to spawn already running process files@172.17.0.7:45977
I0901 23:10:51.951736  8681 cluster.cpp:448] Creating default 'local' authorizer
I0901 23:10:51.953603  8716 slave.cpp:252] Mesos agent started on (209)@172.17.0.7:45977
I0901 23:10:51.953630  8716 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_MultipleExecutors_FIsWGW/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/MasterTest_MultipleExecutors_FIsWGW/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --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/MasterTest_MultipleExecutors_FIsWGW/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_secret_key="/tmp/MasterTest_MultipleExecutors_FIsWGW/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_FIsWGW/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/MasterTest_MultipleExecutors_FIsWGW/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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/MasterTest_MultipleExecutors_FIsWGW" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --systemd_enable_support="true" --systemd_runtime_directory="/run/systemd/system" --version="false" --work_dir="/tmp/MasterTest_MultipleExecutors_QEULzH" --zk_session_timeout="10secs"
I0901 23:10:51.953989  8716 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_MultipleExecutors_FIsWGW/credential'
W0901 23:10:51.954025  8681 process.cpp:3240] Attempted to spawn already running process version@172.17.0.7:45977
I0901 23:10:51.954139  8716 slave.cpp:285] Agent using credential for: test-principal
I0901 23:10:51.954169  8716 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_MultipleExecutors_FIsWGW/http_credentials'
I0901 23:10:51.954465  8716 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0901 23:10:51.954596  8716 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0901 23:10:51.954814  8716 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0901 23:10:51.954895  8681 sched.cpp:232] Version: 1.5.0
I0901 23:10:51.954926  8716 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0901 23:10:51.955116  8716 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0901 23:10:51.955231  8716 http.cpp:1066] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0901 23:10:51.955407  8717 sched.cpp:336] New master detected at master@172.17.0.7:45977
I0901 23:10:51.955574  8717 sched.cpp:407] Authenticating with master master@172.17.0.7:45977
I0901 23:10:51.955615  8717 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0901 23:10:51.955876  8706 authenticatee.cpp:121] Creating new client SASL connection
I0901 23:10:51.956172  8711 master.cpp:7834] Authenticating scheduler-052d2457-c040-44b6-9033-12f7b580f628@172.17.0.7:45977
I0901 23:10:51.956280  8712 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(450)@172.17.0.7:45977
I0901 23:10:51.956501  8708 authenticator.cpp:98] Creating new server SASL connection
I0901 23:10:51.956734  8705 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0901 23:10:51.956759  8705 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0901 23:10:51.956585  8716 slave.cpp:581] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0901 23:10:51.956791  8716 slave.cpp:589] Agent attributes: [  ]
I0901 23:10:51.956807  8716 slave.cpp:598] Agent hostname: e922e05f6b08
I0901 23:10:51.956861  8722 authenticator.cpp:204] Received SASL authentication start
I0901 23:10:51.956923  8722 authenticator.cpp:326] Authentication requires more steps
I0901 23:10:51.956930  8713 status_update_manager.cpp:177] Pausing sending status updates
I0901 23:10:51.957008  8722 authenticatee.cpp:259] Received SASL authentication step
I0901 23:10:51.957139  8709 authenticator.cpp:232] Received SASL authentication step
I0901 23:10:51.957183  8709 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e922e05f6b08' server FQDN: 'e922e05f6b08' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0901 23:10:51.957201  8709 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0901 23:10:51.957237  8709 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0901 23:10:51.957270  8709 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e922e05f6b08' server FQDN: 'e922e05f6b08' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0901 23:10:51.957285  8709 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0901 23:10:51.957294  8709 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0901 23:10:51.957311  8709 authenticator.cpp:318] Authentication success
I0901 23:10:51.957392  8710 authenticatee.cpp:299] Authentication success
I0901 23:10:51.957451  8715 master.cpp:7864] Successfully authenticated principal 'test-principal' at scheduler-052d2457-c040-44b6-9033-12f7b580f628@172.17.0.7:45977
I0901 23:10:51.957540  8710 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(450)@172.17.0.7:45977
I0901 23:10:51.957659  8704 sched.cpp:513] Successfully authenticated with master master@172.17.0.7:45977
I0901 23:10:51.957679  8704 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.7:45977
I0901 23:10:51.957772  8704 sched.cpp:869] Will retry registration in 61.416988ms if necessary
I0901 23:10:51.957909  8714 master.cpp:2897] Received SUBSCRIBE call for framework 'default' at scheduler-052d2457-c040-44b6-9033-12f7b580f628@172.17.0.7:45977
I0901 23:10:51.957988  8714 master.cpp:2231] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0901 23:10:51.958216  8717 state.cpp:64] Recovering state from '/tmp/MasterTest_MultipleExecutors_QEULzH/meta'
I0901 23:10:51.958431  8706 master.cpp:2977] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0901 23:10:51.958487  8712 status_update_manager.cpp:203] Recovering status update manager
I0901 23:10:51.958817  8722 slave.cpp:6296] Finished recovery
I0901 23:10:51.958947  8709 sched.cpp:759] Framework registered with 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.958993  8709 sched.cpp:773] Scheduler::registered took 20465ns
I0901 23:10:51.959071  8702 hierarchical.cpp:303] Added framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.959215  8722 slave.cpp:6478] Querying resource estimator for oversubscribable resources
I0901 23:10:51.959223  8702 hierarchical.cpp:1943] No allocations performed
I0901 23:10:51.959254  8702 hierarchical.cpp:2033] No inverse offers to send out!
I0901 23:10:51.959300  8702 hierarchical.cpp:1486] Performed allocation for 0 agents in 106105ns
I0901 23:10:51.959378  8723 status_update_manager.cpp:177] Pausing sending status updates
I0901 23:10:51.959388  8722 slave.cpp:987] New master detected at master@172.17.0.7:45977
I0901 23:10:51.959435  8722 slave.cpp:1022] Detecting new master
I0901 23:10:51.959545  8722 slave.cpp:6492] Received oversubscribable resources {} from the resource estimator
I0901 23:10:51.965256  8701 slave.cpp:1049] Authenticating with master master@172.17.0.7:45977
I0901 23:10:51.965325  8701 slave.cpp:1060] Using default CRAM-MD5 authenticatee
I0901 23:10:51.965529  8707 authenticatee.cpp:121] Creating new client SASL connection
I0901 23:10:51.965732  8700 master.cpp:7834] Authenticating slave(209)@172.17.0.7:45977
I0901 23:10:51.965822  8716 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(451)@172.17.0.7:45977
I0901 23:10:51.966023  8718 authenticator.cpp:98] Creating new server SASL connection
I0901 23:10:51.966188  8708 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0901 23:10:51.966225  8708 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0901 23:10:51.966346  8717 authenticator.cpp:204] Received SASL authentication start
I0901 23:10:51.966413  8717 authenticator.cpp:326] Authentication requires more steps
I0901 23:10:51.966517  8717 authenticatee.cpp:259] Received SASL authentication step
I0901 23:10:51.966647  8711 authenticator.cpp:232] Received SASL authentication step
I0901 23:10:51.966680  8711 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e922e05f6b08' server FQDN: 'e922e05f6b08' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0901 23:10:51.966691  8711 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0901 23:10:51.966717  8711 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0901 23:10:51.966742  8711 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'e922e05f6b08' server FQDN: 'e922e05f6b08' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0901 23:10:51.966754  8711 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0901 23:10:51.966763  8711 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0901 23:10:51.966776  8711 authenticator.cpp:318] Authentication success
I0901 23:10:51.966850  8712 authenticatee.cpp:299] Authentication success
I0901 23:10:51.966892  8705 master.cpp:7864] Successfully authenticated principal 'test-principal' at slave(209)@172.17.0.7:45977
I0901 23:10:51.966933  8713 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(451)@172.17.0.7:45977
I0901 23:10:51.967108  8706 slave.cpp:1144] Successfully authenticated with master master@172.17.0.7:45977
I0901 23:10:51.967310  8706 slave.cpp:1623] Will retry registration in 13.128058ms if necessary
I0901 23:10:51.967448  8703 master.cpp:5717] Received register agent message from slave(209)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.967559  8703 master.cpp:3806] Authorizing agent with principal 'test-principal'
I0901 23:10:51.967958  8702 master.cpp:5777] Authorized registration of agent at slave(209)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.968075  8702 master.cpp:5870] Registering agent at slave(209)@172.17.0.7:45977 (e922e05f6b08) with id 45a1618b-eec8-4155-821f-9f8325aea807-S0
I0901 23:10:51.968456  8723 registrar.cpp:495] Applied 1 operations in 53099ns; attempting to update the registry
I0901 23:10:51.968981  8723 registrar.cpp:552] Successfully updated the registry in 462848ns
I0901 23:10:51.969185  8700 master.cpp:5917] Admitted agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 at slave(209)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.969700  8718 slave.cpp:4963] Received ping from slave-observer(204)@172.17.0.7:45977
I0901 23:10:51.969846  8718 slave.cpp:1190] Registered with master master@172.17.0.7:45977; given agent ID 45a1618b-eec8-4155-821f-9f8325aea807-S0
I0901 23:10:51.969949  8717 status_update_manager.cpp:184] Resuming sending status updates
I0901 23:10:51.969763  8700 master.cpp:5948] Registered agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 at slave(209)@172.17.0.7:45977 (e922e05f6b08) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0901 23:10:51.970010  8708 hierarchical.cpp:593] Added agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 (e922e05f6b08) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0901 23:10:51.970124  8718 slave.cpp:1210] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_QEULzH/meta/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/slave.info'
I0901 23:10:51.970465  8718 slave.cpp:1259] Forwarding total oversubscribed resources {}
I0901 23:10:51.970610  8718 master.cpp:6685] Received update of agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 at slave(209)@172.17.0.7:45977 (e922e05f6b08) with total oversubscribed resources {}
I0901 23:10:51.970948  8708 hierarchical.cpp:2033] No inverse offers to send out!
I0901 23:10:51.970995  8708 hierarchical.cpp:1486] Performed allocation for 1 agents in 859598ns
I0901 23:10:51.971127  8708 hierarchical.cpp:660] Agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 (e922e05f6b08) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0901 23:10:51.971423  8711 master.cpp:7664] Sending 1 offers to framework 45a1618b-eec8-4155-821f-9f8325aea807-0000 (default) at scheduler-052d2457-c040-44b6-9033-12f7b580f628@172.17.0.7:45977
I0901 23:10:51.971900  8705 sched.cpp:933] Scheduler::resourceOffers took 101762ns
I0901 23:10:51.973736  8710 master.cpp:9162] Removing offer 45a1618b-eec8-4155-821f-9f8325aea807-O0
I0901 23:10:51.973886  8710 master.cpp:4156] Processing ACCEPT call for offers: [ 45a1618b-eec8-4155-821f-9f8325aea807-O0 ] on agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 at slave(209)@172.17.0.7:45977 (e922e05f6b08) for framework 45a1618b-eec8-4155-821f-9f8325aea807-0000 (default) at scheduler-052d2457-c040-44b6-9033-12f7b580f628@172.17.0.7:45977
I0901 23:10:51.973979  8710 master.cpp:3533] Authorizing framework principal 'test-principal' to launch task 1
I0901 23:10:51.974175  8710 master.cpp:3533] Authorizing framework principal 'test-principal' to launch task 2
W0901 23:10:51.975706  8707 validation.cpp:1332] Executor 'executor-1' for task '1' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0901 23:10:51.975742  8707 validation.cpp:1344] Executor 'executor-1' for task '1' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0901 23:10:51.976060  8707 master.cpp:9908] Adding task 1 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 at slave(209)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.976372  8707 master.cpp:4819] Launching task 1 of framework 45a1618b-eec8-4155-821f-9f8325aea807-0000 (default) at scheduler-052d2457-c040-44b6-9033-12f7b580f628@172.17.0.7:45977 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 at slave(209)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.976784  8720 slave.cpp:1744] Got assigned task '1' for framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
W0901 23:10:51.977257  8707 validation.cpp:1332] Executor 'executor-2' for task '2' uses less CPUs (None) than the minimum required (0.01). Please update your executor, as this will be mandatory in future releases.
W0901 23:10:51.977284  8707 validation.cpp:1344] Executor 'executor-2' for task '2' uses less memory (None) than the minimum required (32MB). Please update your executor, as this will be mandatory in future releases.
I0901 23:10:51.977339  8720 slave.cpp:2012] Authorizing task '1' for framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.977391  8720 slave.cpp:6795] Authorizing framework principal 'test-principal' to launch task 1
I0901 23:10:51.977581  8707 master.cpp:9908] Adding task 2 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 at slave(209)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.977850  8707 master.cpp:4819] Launching task 2 of framework 45a1618b-eec8-4155-821f-9f8325aea807-0000 (default) at scheduler-052d2457-c040-44b6-9033-12f7b580f628@172.17.0.7:45977 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] on agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 at slave(209)@172.17.0.7:45977 (e922e05f6b08)
I0901 23:10:51.977970  8708 slave.cpp:2180] Launching task '1' for framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.978415  8708 paths.cpp:578] Trying to chown '/tmp/MasterTest_MultipleExecutors_QEULzH/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/frameworks/45a1618b-eec8-4155-821f-9f8325aea807-0000/executors/executor-1/runs/4d5e8926-ad70-428d-97d0-02867a08e595' to user 'mesos'
I0901 23:10:51.978612  8708 slave.cpp:7257] Launching executor 'executor-1' of framework 45a1618b-eec8-4155-821f-9f8325aea807-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_QEULzH/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/frameworks/45a1618b-eec8-4155-821f-9f8325aea807-0000/executors/executor-1/runs/4d5e8926-ad70-428d-97d0-02867a08e595'
I0901 23:10:51.979290  8708 slave.cpp:2408] Queued task '1' for executor 'executor-1' of framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.979449  8705 hierarchical.cpp:887] Updated allocation of framework 45a1618b-eec8-4155-821f-9f8325aea807-0000 on agent 45a1618b-eec8-4155-821f-9f8325aea807-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]
I0901 23:10:51.979517  8708 slave.cpp:1744] Got assigned task '2' for framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.979912  8708 slave.cpp:940] Successfully attached file '/tmp/MasterTest_MultipleExecutors_QEULzH/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/frameworks/45a1618b-eec8-4155-821f-9f8325aea807-0000/executors/executor-1/runs/4d5e8926-ad70-428d-97d0-02867a08e595'
I0901 23:10:51.979953  8708 slave.cpp:940] Successfully attached file '/tmp/MasterTest_MultipleExecutors_QEULzH/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/frameworks/45a1618b-eec8-4155-821f-9f8325aea807-0000/executors/executor-1/runs/4d5e8926-ad70-428d-97d0-02867a08e595'
I0901 23:10:51.980049  8705 hierarchical.cpp:1161] Recovered ports(allocated: *):[31000-32000]; disk(allocated: *):1024 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: cpus(allocated: *):2; mem(allocated: *):1024) on agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 from framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.980108  8705 hierarchical.cpp:1207] Framework 45a1618b-eec8-4155-821f-9f8325aea807-0000 filtered agent 45a1618b-eec8-4155-821f-9f8325aea807-S0 for 5secs
I0901 23:10:51.980144  8708 slave.cpp:2871] Launching container 4d5e8926-ad70-428d-97d0-02867a08e595 for executor 'executor-1' of framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.980427  8708 slave.cpp:2012] Authorizing task '2' for framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.980468  8708 slave.cpp:6795] Authorizing framework principal 'test-principal' to launch task 2
I0901 23:10:51.981072  8722 slave.cpp:2180] Launching task '2' for framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.981432  8722 paths.cpp:578] Trying to chown '/tmp/MasterTest_MultipleExecutors_QEULzH/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/frameworks/45a1618b-eec8-4155-821f-9f8325aea807-0000/executors/executor-2/runs/a0334290-379f-4ace-8820-8ecbfa13e5d7' to user 'mesos'
I0901 23:10:51.981622  8722 slave.cpp:7257] Launching executor 'executor-2' of framework 45a1618b-eec8-4155-821f-9f8325aea807-0000 with resources [] in work directory '/tmp/MasterTest_MultipleExecutors_QEULzH/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/frameworks/45a1618b-eec8-4155-821f-9f8325aea807-0000/executors/executor-2/runs/a0334290-379f-4ace-8820-8ecbfa13e5d7'
I0901 23:10:51.982178  8722 slave.cpp:2408] Queued task '2' for executor 'executor-2' of framework 45a1618b-eec8-4155-821f-9f8325aea807-0000
I0901 23:10:51.982254  8722 slave.cpp:940] Successfully attached file '/tmp/MasterTest_MultipleExecutors_QEULzH/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/frameworks/45a1618b-eec8-4155-821f-9f8325aea807-0000/executors/executor-2/runs/a0334290-379f-4ace-8820-8ecbfa13e5d7'
I0901 23:10:51.982288  8722 slave.cpp:940] Successfully attached file '/tmp/MasterTest_MultipleExecutors_QEULzH/slaves/45a1618b-eec8-4155-821f-9f8325aea807-S0/frameworks/45a1618b-eec8-4155-821f-9f8325aea807-0000/executors/executor-2/runs/a0334290-379f-4ace-8820-8ecbfa13e5d7'
*** Aborted at 1504307451 (unix time) try "date -d @1504307451" if you are using GNU date ***
PC: @     0x2aeb3047782d (unknown)
W0901 23:10:51.982739  8715 process.cpp:3240] Attempted to spawn already running process version@172.17.0.7:45977
I0901 23:10:51.982925  8715 exec.cpp:162] Version: 1.5.0
*** SIGSEGV (@0xf0) received by PID 8681 (TID 0x2aeb3be83700) from PID 240; stack trace: ***
I0901 23:10:51.983249  8701 exec.cpp:212] Executor started at: executor(79)@172.17.0.7:45977 with pid 8681
    @     0x2aeb3e673af7 (unknown)
    @     0x2aeb3e678449 (unknown)
    @     0x2aeb3022e330 (unknown)
    @     0x2aeb3047782d (unknown)
    @     0x55e2adb98f6d os::getenv()
    @     0x2aeb2c80a99e mesos::internal::slave::executorEnvironment()
    @     0x2aeb2c7e014d mesos::internal::slave::Slave::launchExecutor()
    @     0x2aeb2c842cba _ZZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS_6FutureINS1_6SecretEEEERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKS5_INS1_8TaskInfoEESB_SE_SH_SL_EEvRKNS_3PIDIT_EEMSN_FvT0_T1_T2_T3_EOT4_OT5_OT6_OT7_ENKUlRS9_RSC_RSF_RSJ_PNS_11ProcessBaseEE_clES15_S16_S17_S18_S1A_
    @     0x2aeb2c8ae572 _ZNSt5_BindIFZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS0_6FutureINS2_6SecretEEEERKNS2_11FrameworkIDERKNS2_10ExecutorIDERKS6_INS2_8TaskInfoEESC_SF_SI_SM_EEvRKNS0_3PIDIT_EEMSO_FvT0_T1_T2_T3_EOT4_OT5_OT6_OT7_EUlRSA_RSD_RSG_RSK_PNS0_11ProcessBaseEE_SA_SD_SG_SK_St12_PlaceholderILi1EEEE6__callIvIOS1B_EILm0ELm1ELm2ELm3ELm4EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE
    @     0x2aeb2c89ae0f _ZNSt5_BindIFZN7process8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS0_6FutureINS2_6SecretEEEERKNS2_11FrameworkIDERKNS2_10ExecutorIDERKS6_INS2_8TaskInfoEESC_SF_SI_SM_EEvRKNS0_3PIDIT_EEMSO_FvT0_T1_T2_T3_EOT4_OT5_OT6_OT7_EUlRSA_RSD_RSG_RSK_PNS0_11ProcessBaseEE_SA_SD_SG_SK_St12_PlaceholderILi1EEEEclIIS1B_EvEET0_DpOT_
    @     0x2aeb2c882c5d _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchIN5mesos8internal5slave5SlaveERK6OptionINS0_6FutureINS6_6SecretEEEERKNS6_11FrameworkIDERKNS6_10ExecutorIDERKSA_INS6_8TaskInfoEESG_SJ_SM_SQ_EEvRKNS0_3PIDIT_EEMSS_FvT0_T1_T2_T3_EOT4_OT5_OT6_OT7_EUlRSE_RSH_RSK_RSO_S2_E_SE_SH_SK_SO_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_
    @     0x2aeb2d72575d std::function<>::operator()()
    @     0x2aeb2d705585 process::ProcessBase::visit()
    @     0x2aeb2d712134 process::DispatchEvent::visit()
    @     0x55e2adb6c5e6 process::ProcessBase::serve()
    @     0x2aeb2d702ca5 process::ProcessManager::resume()
    @     0x2aeb2d6ff01c _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
    @     0x2aeb2d71113e _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
    @     0x2aeb2d711095 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
    @     0x2aeb2d71102e _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
    @     0x2aeb2faafa60 (unknown)
    @     0x2aeb30226184 start_thread
    @     0x2aeb30539ffd (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.5.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1504304399-31818
Untagged: mesos-1504304399-31818:latest
Deleted: sha256:a89bcf309d098a3d74643aafbd83ad799da0460c422eb8eeed99db83ce0a1fb9
Deleted: sha256:6fa98915bd2600ab53141cd0d70524671f404e291812fc49f7a883fecda4a159
Deleted: sha256:700bb83caa0c4e90d947ef63ad4071955aa58ec64e9735edf5544de95502636a
Deleted: sha256:77e95633e06869f7375e686e5720f16efe2db70ba6ca1feb7e32a69d6defb154
Deleted: sha256:7bb771efd2fedd20e0babfced56969f3ea26a138ca6d55810605506f9878c5e0
Deleted: sha256:b3919adc1cab68789966ec33a848d65544bb22facdd8f66cdc2a4c1f7c4b50f8
Deleted: sha256:537634f99c1f89fbdef1502efee5674a2edc559aebd052230f699906667ff6dd
Deleted: sha256:b97682f4804a669191ab89fb8a308b7ec11c4e92823b3add0a598f9b4f0cd0b0
Deleted: sha256:11c446bd132902b96055efc246414f1dd695c2e579863978db6e997b4dfafc1d
Deleted: sha256:3182354029dfe2a984fdccf252488f08e9cf0bc07bdbc5313786f67206ccbaf8
Deleted: sha256:4935313acccc39e1094e3b3026f8299a2cbed07e7c872e83a39142d18af98541
Deleted: sha256:511ea6c58ce177e79f8861558f76f2a23bc71ced8034c2ad472cae197c90942c
Deleted: sha256:a873aa7940495ebaa20f8ffb99ff6062866aa5a17de02438d502f07b1f71f665
Deleted: sha256:462fb3c5b8ed9a23fc7d605a700839147a24843cb9624c42e39a7ca3bbe67ef0
Deleted: sha256:6a426b6b3f3ad34ac0e4d7cd4b9a40e2a7d1f951a2a2b1da855e712c99642e6e
Deleted: sha256:2d8f88e12e0c1b2d033e447c9adfa1a2d9a20f3d4f51927a97d745eafc93efee
Deleted: sha256:2716ad917af9ecd7d4cfcfd20c9bc8e5b38fb8ff4e3da05bfde1e6c5c74e39bd
Deleted: sha256:aad69d14c66fc8f403f67c660d34613e9f5e338bd0ecc92ec3335bdf487f8e9a
Deleted: sha256:ba7f7159dc97744208c046a8b53f25e1f23773f6332ce1dbd08191694413a5f8
Deleted: sha256:e6a236b1b8f56452839188c01fbce387f6d0373ca32178cb7d83951e3ca8d47b
Deleted: sha256:6c837e5e4e41353e7df44aa5f8ab7182c61127fcae1d1126b76340cc030e07f9
Deleted: sha256:be5fbc9d5472c5b9fe84c658d6a63b8cb69f9a7ec4bfc53e07d2d9bbab025062
Deleted: sha256:2f9ba15f0f2fd6cbe7300ac8e7af06cd8aac06a781abe026b7da3d908c9a99d3
Deleted: sha256:978844bb8b767b245785d410c8878567a647ce030c3f20ab62f6bc40df65b325
Build step 'Execute shell' marked build as failure

Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4187

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=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4187/display/redirect?page=changes>