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/10/21 00:32:42 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,clang,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4350
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4350/display/redirect?page=changes>
Changes:
[alexr] Fixed 'SlaveRecoveryTest.RegisterDisconnectedSlave'.
[gregorywmann] Reorganized and updated the contribution guidelines.
[gregorywmann] Moved building docs to `building.md`.
[gregorywmann] Added the Getting Started landing page.
[jpeach] Windows: Fixed off-by-one error in long path support.
------------------------------------------
[...truncated 14.43 MB...]
I1020 21:26:36.100257 3544 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(568)@172.17.0.2:37412
I1020 21:26:36.100718 3549 sched.cpp:502] Successfully authenticated with master master@172.17.0.2:37412
I1020 21:26:36.100751 3549 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:37412
I1020 21:26:36.101092 3549 sched.cpp:853] Will retry registration in 695.22879ms if necessary
I1020 21:26:36.101517 3545 master.cpp:2919] Refusing subscription of framework 'default' at scheduler-7ecbec9b-9cbd-41fa-ae5f-1146061e04ed@172.17.0.2:37412: The framework failover_timeout (1e+17) is invalid
I1020 21:26:36.101891 3545 sched.cpp:1171] Got error 'The framework failover_timeout (1e+17) is invalid'
I1020 21:26:36.102136 3545 sched.cpp:2039] Asked to abort the driver
I1020 21:26:36.102517 3545 sched.cpp:1182] Scheduler::error took 101241ns
I1020 21:26:36.102815 3545 sched.cpp:1217] Aborting framework
I1020 21:26:36.103044 3545 sched.cpp:1222] Not sending a deactivate message as master is disconnected
I1020 21:26:36.104414 3549 master.cpp:1145] Master terminating
[ OK ] MasterTest.RejectFrameworkWithInvalidFailoverTimeout (34 ms)
[ RUN ] MasterTest.FailoverAgentReregisterFirst
I1020 21:26:36.114727 3542 cluster.cpp:162] Creating default 'local' authorizer
I1020 21:26:36.120611 3545 master.cpp:445] Master 634e9d82-e040-402a-b6e9-df38d07576d4 (430952e103c8) started on 172.17.0.2:37412
I1020 21:26:36.120679 3545 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/oZxiM7/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/oZxiM7/master" --zk_session_timeout="10secs"
I1020 21:26:36.121085 3545 master.cpp:496] Master only allowing authenticated frameworks to register
I1020 21:26:36.121223 3545 master.cpp:502] Master only allowing authenticated agents to register
I1020 21:26:36.121261 3545 master.cpp:508] Master only allowing authenticated HTTP frameworks to register
I1020 21:26:36.121490 3545 credentials.hpp:37] Loading credentials for authentication from '/tmp/oZxiM7/credentials'
I1020 21:26:36.121901 3545 master.cpp:552] Using default 'crammd5' authenticator
I1020 21:26:36.122196 3545 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1020 21:26:36.122524 3545 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1020 21:26:36.122824 3545 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1020 21:26:36.123123 3545 master.cpp:631] Authorization enabled
I1020 21:26:36.123512 3549 hierarchical.cpp:171] Initialized hierarchical allocator process
I1020 21:26:36.123710 3543 whitelist_watcher.cpp:77] No whitelist given
I1020 21:26:36.126732 3547 master.cpp:2198] Elected as the leading master!
I1020 21:26:36.126767 3547 master.cpp:1687] Recovering from registrar
I1020 21:26:36.127081 3544 registrar.cpp:347] Recovering registrar
I1020 21:26:36.128139 3544 registrar.cpp:391] Successfully fetched the registry (0B) in 1.007616ms
I1020 21:26:36.128300 3544 registrar.cpp:495] Applied 1 operations in 77518ns; attempting to update the registry
I1020 21:26:36.129124 3544 registrar.cpp:552] Successfully updated the registry in 726016ns
I1020 21:26:36.129302 3544 registrar.cpp:424] Successfully recovered registrar
I1020 21:26:36.129878 3545 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1020 21:26:36.129972 3548 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
W1020 21:26:36.135048 3542 process.cpp:3193] Attempted to spawn already running process files@172.17.0.2:37412
I1020 21:26:36.136325 3542 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1020 21:26:36.137349 3542 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W1020 21:26:36.137691 3542 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1020 21:26:36.137876 3542 provisioner.cpp:255] Using default backend 'copy'
I1020 21:26:36.140777 3542 cluster.cpp:448] Creating default 'local' authorizer
I1020 21:26:36.143600 3547 slave.cpp:254] Mesos agent started on (269)@172.17.0.2:37412
I1020 21:26:36.143638 3547 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterTest_FailoverAgentReregisterFirst_2ILgvs/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticatee="crammd5" --authentication_backoff_factor="1secs" --authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterTest_FailoverAgentReregisterFirst_2ILgvs/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_FailoverAgentReregisterFirst_2ILgvs/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_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterTest_FailoverAgentReregisterFirst_2ILgvs/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_FailoverAgentReregisterFirst_2ILgvs/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_FailoverAgentReregisterFirst_2ILgvs" --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_FailoverAgentReregisterFirst_ASxL8h" --zk_session_timeout="10secs"
I1020 21:26:36.144136 3547 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterTest_FailoverAgentReregisterFirst_2ILgvs/credential'
I1020 21:26:36.144542 3547 slave.cpp:287] Agent using credential for: test-principal
I1020 21:26:36.144577 3547 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterTest_FailoverAgentReregisterFirst_2ILgvs/http_credentials'
W1020 21:26:36.144896 3542 process.cpp:3193] Attempted to spawn already running process version@172.17.0.2:37412
I1020 21:26:36.144908 3547 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1020 21:26:36.145470 3547 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1020 21:26:36.146391 3542 sched.cpp:232] Version: 1.5.0
I1020 21:26:36.147132 3550 sched.cpp:336] New master detected at master@172.17.0.2:37412
I1020 21:26:36.147415 3550 sched.cpp:396] Authenticating with master master@172.17.0.2:37412
I1020 21:26:36.147522 3550 sched.cpp:403] Using default CRAM-MD5 authenticatee
I1020 21:26:36.147657 3547 slave.cpp:585] 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"}]
I1020 21:26:36.148005 3547 slave.cpp:593] Agent attributes: [ ]
I1020 21:26:36.148021 3547 slave.cpp:602] Agent hostname: 430952e103c8
I1020 21:26:36.148319 3548 status_update_manager.cpp:177] Pausing sending status updates
I1020 21:26:36.148525 3548 authenticatee.cpp:121] Creating new client SASL connection
I1020 21:26:36.149049 3545 master.cpp:7936] Authenticating scheduler-38c4493d-8896-40d7-8080-00fe5ca0171e@172.17.0.2:37412
I1020 21:26:36.149492 3545 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(569)@172.17.0.2:37412
I1020 21:26:36.150079 3545 authenticator.cpp:98] Creating new server SASL connection
I1020 21:26:36.150540 3547 state.cpp:64] Recovering state from '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/meta'
I1020 21:26:36.150583 3543 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1020 21:26:36.150640 3543 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1020 21:26:36.150976 3549 authenticator.cpp:204] Received SASL authentication start
I1020 21:26:36.151108 3549 authenticator.cpp:326] Authentication requires more steps
I1020 21:26:36.151628 3549 authenticatee.cpp:259] Received SASL authentication step
I1020 21:26:36.151268 3545 status_update_manager.cpp:203] Recovering status update manager
I1020 21:26:36.151937 3547 authenticator.cpp:232] Received SASL authentication step
I1020 21:26:36.151995 3547 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '430952e103c8' server FQDN: '430952e103c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1020 21:26:36.152012 3547 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1020 21:26:36.152051 3547 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1020 21:26:36.152108 3547 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '430952e103c8' server FQDN: '430952e103c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1020 21:26:36.152242 3547 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1020 21:26:36.152257 3547 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1020 21:26:36.152272 3547 authenticator.cpp:318] Authentication success
I1020 21:26:36.152312 3545 containerizer.cpp:662] Recovering containerizer
I1020 21:26:36.152395 3548 authenticatee.cpp:299] Authentication success
I1020 21:26:36.152767 3544 master.cpp:7966] Successfully authenticated principal 'test-principal' at scheduler-38c4493d-8896-40d7-8080-00fe5ca0171e@172.17.0.2:37412
I1020 21:26:36.152858 3547 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(569)@172.17.0.2:37412
I1020 21:26:36.153110 3548 sched.cpp:502] Successfully authenticated with master master@172.17.0.2:37412
I1020 21:26:36.153153 3548 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.2:37412
I1020 21:26:36.153460 3548 sched.cpp:853] Will retry registration in 1.110330625secs if necessary
I1020 21:26:36.153964 3547 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-38c4493d-8896-40d7-8080-00fe5ca0171e@172.17.0.2:37412
I1020 21:26:36.153995 3547 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I1020 21:26:36.154731 3543 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I1020 21:26:36.155783 3543 sched.cpp:747] Framework registered with 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.155875 3543 sched.cpp:761] Scheduler::registered took 67700ns
I1020 21:26:36.156208 3549 hierarchical.cpp:303] Added framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.156543 3549 hierarchical.cpp:1488] Performed allocation for 0 agents in 169630ns
I1020 21:26:36.156849 3545 provisioner.cpp:416] Provisioner recovery complete
I1020 21:26:36.157312 3543 slave.cpp:6310] Finished recovery
I1020 21:26:36.158259 3547 status_update_manager.cpp:177] Pausing sending status updates
I1020 21:26:36.158262 3543 slave.cpp:993] New master detected at master@172.17.0.2:37412
I1020 21:26:36.158452 3543 slave.cpp:1028] Detecting new master
I1020 21:26:36.167887 3545 slave.cpp:1055] Authenticating with master master@172.17.0.2:37412
I1020 21:26:36.168256 3545 slave.cpp:1064] Using default CRAM-MD5 authenticatee
I1020 21:26:36.168817 3545 authenticatee.cpp:121] Creating new client SASL connection
I1020 21:26:36.169378 3548 master.cpp:7936] Authenticating slave(269)@172.17.0.2:37412
I1020 21:26:36.169751 3546 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(570)@172.17.0.2:37412
I1020 21:26:36.170210 3547 authenticator.cpp:98] Creating new server SASL connection
I1020 21:26:36.170560 3549 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1020 21:26:36.170599 3549 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1020 21:26:36.170886 3549 authenticator.cpp:204] Received SASL authentication start
I1020 21:26:36.170989 3549 authenticator.cpp:326] Authentication requires more steps
I1020 21:26:36.171221 3549 authenticatee.cpp:259] Received SASL authentication step
I1020 21:26:36.171445 3549 authenticator.cpp:232] Received SASL authentication step
I1020 21:26:36.171488 3549 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '430952e103c8' server FQDN: '430952e103c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1020 21:26:36.171501 3549 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1020 21:26:36.171535 3549 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1020 21:26:36.171684 3549 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '430952e103c8' server FQDN: '430952e103c8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1020 21:26:36.171708 3549 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1020 21:26:36.171715 3549 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1020 21:26:36.171730 3549 authenticator.cpp:318] Authentication success
I1020 21:26:36.172040 3548 authenticatee.cpp:299] Authentication success
I1020 21:26:36.172207 3547 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(269)@172.17.0.2:37412
I1020 21:26:36.172441 3546 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(570)@172.17.0.2:37412
I1020 21:26:36.172673 3548 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:37412
I1020 21:26:36.173190 3548 slave.cpp:1626] Will retry registration in 2.244279ms if necessary
I1020 21:26:36.173651 3546 master.cpp:5801] Received register agent message from slave(269)@172.17.0.2:37412 (430952e103c8)
I1020 21:26:36.173933 3546 master.cpp:3838] Authorizing agent with principal 'test-principal'
I1020 21:26:36.174650 3546 master.cpp:5861] Authorized registration of agent at slave(269)@172.17.0.2:37412 (430952e103c8)
I1020 21:26:36.175035 3546 master.cpp:5954] Registering agent at slave(269)@172.17.0.2:37412 (430952e103c8) with id 634e9d82-e040-402a-b6e9-df38d07576d4-S0
I1020 21:26:36.175904 3547 registrar.cpp:495] Applied 1 operations in 84230ns; attempting to update the registry
I1020 21:26:36.175950 3548 slave.cpp:1626] Will retry registration in 20.286924ms if necessary
I1020 21:26:36.176312 3548 master.cpp:5795] Ignoring register agent message from slave(269)@172.17.0.2:37412 (430952e103c8) as registration is already in progress
I1020 21:26:36.176811 3547 registrar.cpp:552] Successfully updated the registry in 829952ns
I1020 21:26:36.177147 3548 master.cpp:6001] Admitted agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8)
I1020 21:26:36.177942 3549 slave.cpp:1193] Registered with master master@172.17.0.2:37412; given agent ID 634e9d82-e040-402a-b6e9-df38d07576d4-S0
I1020 21:26:36.177845 3548 master.cpp:6032] Registered agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8) 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"}]
I1020 21:26:36.178222 3548 status_update_manager.cpp:184] Resuming sending status updates
I1020 21:26:36.178586 3549 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/meta/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/slave.info'
I1020 21:26:36.178900 3550 hierarchical.cpp:593] Added agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 (430952e103c8) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I1020 21:26:36.179195 3549 slave.cpp:1262] Forwarding total oversubscribed resources {}
I1020 21:26:36.179507 3549 master.cpp:6817] Received update of agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8) with total oversubscribed resources {}
I1020 21:26:36.179730 3549 master.cpp:6828] Ignoring update on agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8) as it reports no changes
I1020 21:26:36.181251 3550 hierarchical.cpp:1488] Performed allocation for 1 agents in 1.794346ms
I1020 21:26:36.181761 3548 master.cpp:7766] Sending 1 offers to framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 (default) at scheduler-38c4493d-8896-40d7-8080-00fe5ca0171e@172.17.0.2:37412
I1020 21:26:36.182400 3548 sched.cpp:917] Scheduler::resourceOffers took 129751ns
I1020 21:26:36.185578 3547 master.cpp:9389] Removing offer 634e9d82-e040-402a-b6e9-df38d07576d4-O0
I1020 21:26:36.185912 3547 master.cpp:4196] Processing ACCEPT call for offers: [ 634e9d82-e040-402a-b6e9-df38d07576d4-O0 ] on agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8) for framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 (default) at scheduler-38c4493d-8896-40d7-8080-00fe5ca0171e@172.17.0.2:37412
I1020 21:26:36.186172 3547 master.cpp:3565] Authorizing framework principal 'test-principal' to launch task e32b9615-a5d9-4292-8d8f-6188f5030e45
I1020 21:26:36.188797 3547 master.cpp:10135] Adding task e32b9615-a5d9-4292-8d8f-6188f5030e45 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8)
I1020 21:26:36.189545 3547 master.cpp:4879] Launching task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 (default) at scheduler-38c4493d-8896-40d7-8080-00fe5ca0171e@172.17.0.2:37412 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] on agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8)
I1020 21:26:36.190557 3548 slave.cpp:1747] Got assigned task 'e32b9615-a5d9-4292-8d8f-6188f5030e45' for framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.191427 3548 slave.cpp:2015] Authorizing task 'e32b9615-a5d9-4292-8d8f-6188f5030e45' for framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.191506 3548 slave.cpp:6809] Authorizing framework principal 'test-principal' to launch task e32b9615-a5d9-4292-8d8f-6188f5030e45
I1020 21:26:36.192625 3543 slave.cpp:2183] Launching task 'e32b9615-a5d9-4292-8d8f-6188f5030e45' for framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.193092 3550 hierarchical.cpp:887] Updated allocation of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 on agent 634e9d82-e040-402a-b6e9-df38d07576d4-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]
I1020 21:26:36.193385 3543 paths.cpp:605] Trying to chown '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3' to user 'mesos'
I1020 21:26:36.194028 3543 slave.cpp:7283] Launching executor 'e32b9615-a5d9-4292-8d8f-6188f5030e45' of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":0.1},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":32.0},"type":"SCALAR"}] in work directory '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3'
I1020 21:26:36.195194 3543 slave.cpp:2874] Launching container 2286dbb0-e9e7-4e51-95a8-b53b4f04eff3 for executor 'e32b9615-a5d9-4292-8d8f-6188f5030e45' of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.195806 3543 slave.cpp:2411] Queued task 'e32b9615-a5d9-4292-8d8f-6188f5030e45' for executor 'e32b9615-a5d9-4292-8d8f-6188f5030e45' of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.196128 3549 containerizer.cpp:1136] Starting container 2286dbb0-e9e7-4e51-95a8-b53b4f04eff3
I1020 21:26:36.196138 3543 slave.cpp:944] Successfully attached '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3' to virtual path '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/latest'
I1020 21:26:36.196271 3543 slave.cpp:944] Successfully attached '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3' to virtual path '/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/latest'
I1020 21:26:36.196331 3543 slave.cpp:944] Successfully attached '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3' to virtual path '/tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3'
I1020 21:26:36.197011 3549 containerizer.cpp:2784] Transitioning the state of container 2286dbb0-e9e7-4e51-95a8-b53b4f04eff3 from PROVISIONING to PREPARING
I1020 21:26:36.202291 3550 containerizer.cpp:1734] Launching 'mesos-containerizer' with flags '--help="false" --launch_info="{"command":{"arguments":["mesos-executor","--launcher_dir=\/mesos\/mesos-1.5.0\/_build\/src"],"shell":false,"value":"\/mesos\/mesos-1.5.0\/_build\/src\/mesos-executor"},"environment":{"variables":[{"name":"LIBPROCESS_PORT","type":"VALUE","value":"0"},{"name":"MESOS_AGENT_ENDPOINT","type":"VALUE","value":"172.17.0.2:37412"},{"name":"MESOS_CHECKPOINT","type":"VALUE","value":"0"},{"name":"MESOS_DIRECTORY","type":"VALUE","value":"\/tmp\/MasterTest_FailoverAgentReregisterFirst_ASxL8h\/slaves\/634e9d82-e040-402a-b6e9-df38d07576d4-S0\/frameworks\/634e9d82-e040-402a-b6e9-df38d07576d4-0000\/executors\/e32b9615-a5d9-4292-8d8f-6188f5030e45\/runs\/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3"},{"name":"MESOS_EXECUTOR_ID","type":"VALUE","value":"e32b9615-a5d9-4292-8d8f-6188f5030e45"},{"name":"MESOS_EXECUTOR_SHUTDOWN_GRACE_PERIOD","type":"VALUE","value":"5secs"},{"name":"MESOS_FRAMEWORK_ID","type":"VALUE","value":"634e9d82-e040-402a-b6e9-df38d07576d4-0000"},{"name":"MESOS_HTTP_COMMAND_EXECUTOR","type":"VALUE","value":"0"},{"name":"MESOS_SLAVE_ID","type":"VALUE","value":"634e9d82-e040-402a-b6e9-df38d07576d4-S0"},{"name":"MESOS_SLAVE_PID","type":"VALUE","value":"slave(269)@172.17.0.2:37412"},{"name":"MESOS_SANDBOX","type":"VALUE","value":"\/tmp\/MasterTest_FailoverAgentReregisterFirst_ASxL8h\/slaves\/634e9d82-e040-402a-b6e9-df38d07576d4-S0\/frameworks\/634e9d82-e040-402a-b6e9-df38d07576d4-0000\/executors\/e32b9615-a5d9-4292-8d8f-6188f5030e45\/runs\/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3"}]},"task_environment":{},"user":"mesos","working_directory":"\/tmp\/MasterTest_FailoverAgentReregisterFirst_ASxL8h\/slaves\/634e9d82-e040-402a-b6e9-df38d07576d4-S0\/frameworks\/634e9d82-e040-402a-b6e9-df38d07576d4-0000\/executors\/e32b9615-a5d9-4292-8d8f-6188f5030e45\/runs\/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3"}" --pipe_read="22" --pipe_write="23" --runtime_directory="/tmp/MasterTest_FailoverAgentReregisterFirst_2ILgvs/containers/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3" --unshare_namespace_mnt="false"'
I1020 21:26:36.205777 3550 launcher.cpp:140] Forked child with pid '6614' for container '2286dbb0-e9e7-4e51-95a8-b53b4f04eff3'
I1020 21:26:36.206631 3550 containerizer.cpp:2784] Transitioning the state of container 2286dbb0-e9e7-4e51-95a8-b53b4f04eff3 from PREPARING to ISOLATING
I1020 21:26:36.208947 3550 containerizer.cpp:2784] Transitioning the state of container 2286dbb0-e9e7-4e51-95a8-b53b4f04eff3 from ISOLATING to FETCHING
I1020 21:26:36.209355 3545 fetcher.cpp:377] Starting to fetch URIs for container: 2286dbb0-e9e7-4e51-95a8-b53b4f04eff3, directory: /tmp/MasterTest_FailoverAgentReregisterFirst_ASxL8h/slaves/634e9d82-e040-402a-b6e9-df38d07576d4-S0/frameworks/634e9d82-e040-402a-b6e9-df38d07576d4-0000/executors/e32b9615-a5d9-4292-8d8f-6188f5030e45/runs/2286dbb0-e9e7-4e51-95a8-b53b4f04eff3
I1020 21:26:36.210409 3550 containerizer.cpp:2784] Transitioning the state of container 2286dbb0-e9e7-4e51-95a8-b53b4f04eff3 from FETCHING to RUNNING
I1020 21:26:36.385502 6621 exec.cpp:162] Version: 1.5.0
I1020 21:26:36.397904 3545 slave.cpp:3941] Got registration for executor 'e32b9615-a5d9-4292-8d8f-6188f5030e45' of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 from executor(1)@172.17.0.2:39205
I1020 21:26:36.402312 3545 slave.cpp:2613] Sending queued task 'e32b9615-a5d9-4292-8d8f-6188f5030e45' to executor 'e32b9615-a5d9-4292-8d8f-6188f5030e45' of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 at executor(1)@172.17.0.2:39205
I1020 21:26:36.403805 6620 exec.cpp:237] Executor registered on agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0
I1020 21:26:36.407734 6621 executor.cpp:171] Received SUBSCRIBED event
I1020 21:26:36.409059 6621 executor.cpp:175] Subscribed executor on 430952e103c8
I1020 21:26:36.409365 6621 executor.cpp:171] Received LAUNCH event
I1020 21:26:36.409895 6621 executor.cpp:637] Starting task e32b9615-a5d9-4292-8d8f-6188f5030e45
I1020 21:26:36.412861 3548 slave.cpp:4395] Handling status update TASK_STARTING (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 from executor(1)@172.17.0.2:39205
I1020 21:26:36.414875 3550 status_update_manager.cpp:323] Received status update TASK_STARTING (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.415005 3550 status_update_manager.cpp:500] Creating StatusUpdate stream for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.416627 3550 status_update_manager.cpp:377] Forwarding update TASK_STARTING (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 to the agent
I1020 21:26:36.417239 3550 slave.cpp:4876] Forwarding the update TASK_STARTING (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 to master@172.17.0.2:37412
I1020 21:26:36.417671 3550 slave.cpp:4770] Status update manager successfully handled status update TASK_STARTING (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.417874 3550 slave.cpp:4786] Sending acknowledgement for status update TASK_STARTING (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 to executor(1)@172.17.0.2:39205
I1020 21:26:36.417937 3548 master.cpp:6993] Status update TASK_STARTING (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 from agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8)
I1020 21:26:36.418102 3548 master.cpp:7055] Forwarding status update TASK_STARTING (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.418572 3548 master.cpp:9157] Updating the state of task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 (latest state: TASK_STARTING, status update state: TASK_STARTING)
I1020 21:26:36.419103 3545 sched.cpp:1025] Scheduler::statusUpdate took 149130ns
I1020 21:26:36.419591 3545 master.cpp:5566] Processing ACKNOWLEDGE call d457a5dc-42bb-4a67-a341-079d637ebf15 for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 (default) at scheduler-38c4493d-8896-40d7-8080-00fe5ca0171e@172.17.0.2:37412 on agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0
I1020 21:26:36.420341 3550 status_update_manager.cpp:395] Received status update acknowledgement (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.420766 3548 slave.cpp:3679] Status update manager successfully handled status update acknowledgement (UUID: d457a5dc-42bb-4a67-a341-079d637ebf15) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.431982 6621 executor.cpp:477] Running '/mesos/mesos-1.5.0/_build/src/mesos-containerizer launch <POSSIBLY-SENSITIVE-DATA>'
I1020 21:26:36.437235 6621 executor.cpp:650] Forked command at 6625
I1020 21:26:36.441263 3547 slave.cpp:4395] Handling status update TASK_RUNNING (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 from executor(1)@172.17.0.2:39205
I1020 21:26:36.443070 3550 status_update_manager.cpp:323] Received status update TASK_RUNNING (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.443475 3550 status_update_manager.cpp:377] Forwarding update TASK_RUNNING (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 to the agent
I1020 21:26:36.444005 3543 slave.cpp:4876] Forwarding the update TASK_RUNNING (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 to master@172.17.0.2:37412
I1020 21:26:36.444357 3543 slave.cpp:4770] Status update manager successfully handled status update TASK_RUNNING (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.444458 3543 slave.cpp:4786] Sending acknowledgement for status update TASK_RUNNING (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 to executor(1)@172.17.0.2:39205
I1020 21:26:36.444809 3546 master.cpp:6993] Status update TASK_RUNNING (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 from agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8)
I1020 21:26:36.444953 3546 master.cpp:7055] Forwarding status update TASK_RUNNING (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.445442 3546 master.cpp:9157] Updating the state of task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 (latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I1020 21:26:36.445799 3548 sched.cpp:1025] Scheduler::statusUpdate took 113299ns
I1020 21:26:36.446235 3548 master.cpp:5566] Processing ACKNOWLEDGE call 40a2e665-6925-4723-9331-dcbab1d7c165 for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 (default) at scheduler-38c4493d-8896-40d7-8080-00fe5ca0171e@172.17.0.2:37412 on agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0
I1020 21:26:36.446718 3545 status_update_manager.cpp:395] Received status update acknowledgement (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.447162 3545 slave.cpp:3679] Status update manager successfully handled status update acknowledgement (UUID: 40a2e665-6925-4723-9331-dcbab1d7c165) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.448410 3542 master.cpp:1145] Master terminating
I1020 21:26:36.448745 3549 slave.cpp:986] Lost leading master
I1020 21:26:36.448758 3543 status_update_manager.cpp:177] Pausing sending status updates
I1020 21:26:36.448804 3546 sched.cpp:330] Scheduler::disconnected took 58221ns
I1020 21:26:36.448772 3549 slave.cpp:1028] Detecting new master
I1020 21:26:36.448935 3546 sched.cpp:363] No master detected
W1020 21:26:36.448570 3542 master.cpp:9237] Removing task e32b9615-a5d9-4292-8d8f-6188f5030e45 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}] of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 on agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0 at slave(269)@172.17.0.2:37412 (430952e103c8) in non-removable state TASK_RUNNING
I1020 21:26:36.449388 3547 hierarchical.cpp:626] Removed agent 634e9d82-e040-402a-b6e9-df38d07576d4-S0
I1020 21:26:36.450808 3546 hierarchical.cpp:355] Removed framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:26:36.451099 3547 slave.cpp:5008] Got exited event for master@172.17.0.2:37412
W1020 21:26:36.451179 3547 slave.cpp:5013] Master disconnected! Waiting for a new master to be elected
I1020 21:26:36.467150 3542 cluster.cpp:162] Creating default 'local' authorizer
I1020 21:26:36.471004 3545 master.cpp:445] Master 612511ee-3f06-4a12-a588-66577e8b6b4c (430952e103c8) started on 172.17.0.2:37412
I1020 21:26:36.471122 3545 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/oZxiM7/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/oZxiM7/master" --zk_session_timeout="10secs"
I1020 21:26:36.472525 3545 master.cpp:496] Master only allowing authenticated frameworks to register
I1020 21:26:36.472698 3545 master.cpp:502] Master only allowing authenticated agents to register
I1020 21:26:36.472805 3545 master.cpp:508] Master only allowing authenticated HTTP frameworks to register
I1020 21:26:36.473160 3545 credentials.hpp:37] Loading credentials for authentication from '/tmp/oZxiM7/credentials'
I1020 21:26:36.474439 3545 master.cpp:552] Using default 'crammd5' authenticator
I1020 21:26:36.474795 3545 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1020 21:26:36.475165 3545 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1020 21:26:36.475913 3545 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1020 21:26:36.477740 3545 master.cpp:631] Authorization enabled
I1020 21:26:36.477986 3543 hierarchical.cpp:171] Initialized hierarchical allocator process
I1020 21:26:36.478245 3543 whitelist_watcher.cpp:77] No whitelist given
I1020 21:26:36.482036 3547 master.cpp:2198] Elected as the leading master!
I1020 21:26:36.482079 3547 master.cpp:1687] Recovering from registrar
I1020 21:26:36.482321 3550 registrar.cpp:347] Recovering registrar
I1020 21:26:36.483183 3550 registrar.cpp:391] Successfully fetched the registry (0B) in 683008ns
I1020 21:26:36.483598 3550 registrar.cpp:495] Applied 1 operations in 59947ns; attempting to update the registry
I1020 21:26:36.484937 3543 registrar.cpp:552] Successfully updated the registry in 1.202176ms
I1020 21:26:36.485121 3543 registrar.cpp:424] Successfully recovered registrar
I1020 21:26:36.485671 3549 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1020 21:26:36.485736 3548 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I1020 21:28:16.582042 6622 executor.cpp:929] Command exited with status 0 (pid: 6625)
I1020 21:28:16.619235 3550 slave.cpp:4395] Handling status update TASK_FINISHED (UUID: 72b23952-7988-4d07-8576-a379e3d1859d) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 from executor(1)@172.17.0.2:39205
I1020 21:28:16.631116 3547 status_update_manager.cpp:323] Received status update TASK_FINISHED (UUID: 72b23952-7988-4d07-8576-a379e3d1859d) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:28:16.631851 3545 slave.cpp:4770] Status update manager successfully handled status update TASK_FINISHED (UUID: 72b23952-7988-4d07-8576-a379e3d1859d) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000
I1020 21:28:16.631938 3545 slave.cpp:4786] Sending acknowledgement for status update TASK_FINISHED (UUID: 72b23952-7988-4d07-8576-a379e3d1859d) for task e32b9615-a5d9-4292-8d8f-6188f5030e45 of framework 634e9d82-e040-402a-b6e9-df38d07576d4-0000 to executor(1)@172.17.0.2:39205
I1020 21:28:17.626111 6624 process.cpp:1067] Failed to accept socket: future discarded
I1020 21:28:17.648064 3546 slave.cpp:5008] Got exited event for executor(1)@172.17.0.2:39205
W1020 21:28:17.648142 3546 slave.cpp:5013] Master disconnected! Waiting for a new master to be elected
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1508528103-15625
Error response from daemon: conflict: unable to remove repository reference "mesos-1508528103-15625" (must force) - container 430952e103c8 is using its referenced image 62fd2caf9371
Not sending mail to unregistered user gregorywmann@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » autotools,clang,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4351
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4351/display/redirect?page=changes>