mesos-builds mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4445
Date Sat, 11 Nov 2017 06:54:54 GMT
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4445/display/redirect>

------------------------------------------
[...truncated 9.66 MB...]
3: I1111 06:54:45.096679 15960 master.cpp:9503] Updating the state of task 0 of framework
0752be44-1079-4e4f-9602-b1327b80e0ce-0000 (latest state: TASK_KILLED, status update state:
TASK_KILLED)
3: I1111 06:54:45.097008 15968 slave.cpp:3267] Asked to shut down framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000
by master@172.17.0.6:40721
3: I1111 06:54:45.097039 15968 slave.cpp:3292] Shutting down framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000
3: I1111 06:54:45.097093 15968 slave.cpp:5855] Shutting down executor 'default' of framework
0752be44-1079-4e4f-9602-b1327b80e0ce-0000 at executor(87)@172.17.0.6:40721
3: I1111 06:54:45.097263 15960 master.cpp:9597] Removing task 0 with resources [{"allocation_info":{"role":"*"},"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"allocation_info":{"role":"*"},"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}]
of framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000 on agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
at slave(236)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.097587 15963 exec.cpp:435] Executor asked to shutdown
3: I1111 06:54:45.097625 15963 exec.cpp:450] Executor::shutdown took 22827ns
3: I1111 06:54:45.097748 15968 hierarchical.cpp:1125] Recovered cpus(allocated: *):1; mem(allocated:
*):512 (total: cpus:2; mem:1024; disk:1024; ports:[31000-32000], allocated: {}) on agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
from framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000
3: I1111 06:54:45.097772 15960 master.cpp:9626] Removing executor 'default' with resources
[] of framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000 on agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
at slave(236)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.098026 15977 slave.cpp:5120] Got exited event for executor(87)@172.17.0.6:40721
3: I1111 06:54:45.098574 15958 hierarchical.cpp:355] Removed framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000
3: I1111 06:54:45.100450 15974 slave.cpp:5520] Executor 'default' of framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000
exited with status 0
3: I1111 06:54:45.100534 15974 slave.cpp:5624] Cleaning up executor 'default' of framework
0752be44-1079-4e4f-9602-b1327b80e0ce-0000 at executor(87)@172.17.0.6:40721
3: W1111 06:54:45.100666 15965 master.cpp:7454] Ignoring unknown exited executor 'default'
of framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000 on agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
at slave(236)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.100806 15976 gc.cpp:90] Scheduling '/tmp/StatusUpdateManagerTest_LatestTaskState_YrreR1/slaves/0752be44-1079-4e4f-9602-b1327b80e0ce-S0/frameworks/0752be44-1079-4e4f-9602-b1327b80e0ce-0000/executors/default/runs/a094c63a-27a8-4383-b1ca-718efa067a07'
for gc 6.99999905582222days in the future
3: I1111 06:54:45.100953 15974 slave.cpp:5731] Cleaning up framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000
3: I1111 06:54:45.100975 15976 gc.cpp:90] Scheduling '/tmp/StatusUpdateManagerTest_LatestTaskState_YrreR1/slaves/0752be44-1079-4e4f-9602-b1327b80e0ce-S0/frameworks/0752be44-1079-4e4f-9602-b1327b80e0ce-0000/executors/default'
for gc 6.99999905582222days in the future
3: I1111 06:54:45.101050 15959 status_update_manager.cpp:285] Closing status update streams
for framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000
3: I1111 06:54:45.101095 15959 status_update_manager.cpp:531] Cleaning up status update stream
for task 0 of framework 0752be44-1079-4e4f-9602-b1327b80e0ce-0000
3: I1111 06:54:45.101151 15968 gc.cpp:90] Scheduling '/tmp/StatusUpdateManagerTest_LatestTaskState_YrreR1/slaves/0752be44-1079-4e4f-9602-b1327b80e0ce-S0/frameworks/0752be44-1079-4e4f-9602-b1327b80e0ce-0000'
for gc 6.99999905582222days in the future
3: I1111 06:54:45.101665 15975 slave.cpp:883] Agent terminating
3: I1111 06:54:45.101934 15980 master.cpp:1309] Agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
at slave(236)@172.17.0.6:40721 (568cb3f90890) disconnected
3: I1111 06:54:45.101965 15980 master.cpp:3369] Disconnecting agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
at slave(236)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.102030 15980 master.cpp:3388] Deactivating agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
at slave(236)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.102159 15962 hierarchical.cpp:690] Agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
deactivated
3: I1111 06:54:45.105345 15958 master.cpp:1151] Master terminating
3: I1111 06:54:45.106026 15979 hierarchical.cpp:626] Removed agent 0752be44-1079-4e4f-9602-b1327b80e0ce-S0
3: [       OK ] StatusUpdateManagerTest.LatestTaskState (78 ms)
3: [ RUN      ] StatusUpdateManagerTest.DuplicatedTerminalStatusUpdate
3: I1111 06:54:45.333436 15956 cluster.cpp:162] Creating default 'local' authorizer
3: I1111 06:54:45.336782 15973 master.cpp:448] Master dab3a7a5-f31e-4097-9133-c72186599b0d
(568cb3f90890) started on 172.17.0.6:40721
3: I1111 06:54:45.336812 15973 master.cpp:450] 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/UZ5JtH/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="/usr/local/share/mesos/webui" --work_dir="/tmp/UZ5JtH/master"
--zk_session_timeout="10secs"
3: I1111 06:54:45.337152 15973 master.cpp:499] Master only allowing authenticated frameworks
to register
3: I1111 06:54:45.337159 15973 master.cpp:505] Master only allowing authenticated agents to
register
3: I1111 06:54:45.337163 15973 master.cpp:511] Master only allowing authenticated HTTP frameworks
to register
3: I1111 06:54:45.337168 15973 credentials.hpp:37] Loading credentials for authentication
from '/tmp/UZ5JtH/credentials'
3: I1111 06:54:45.337519 15973 master.cpp:555] Using default 'crammd5' authenticator
3: I1111 06:54:45.337678 15973 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readonly'
3: I1111 06:54:45.337842 15973 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-readwrite'
3: I1111 06:54:45.337954 15973 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-master-scheduler'
3: I1111 06:54:45.338073 15973 master.cpp:634] Authorization enabled
3: I1111 06:54:45.338227 15969 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1111 06:54:45.338284 15967 whitelist_watcher.cpp:77] No whitelist given
3: I1111 06:54:45.341200 15974 master.cpp:2213] Elected as the leading master!
3: I1111 06:54:45.341225 15974 master.cpp:1693] Recovering from registrar
3: I1111 06:54:45.341375 15976 registrar.cpp:347] Recovering registrar
3: I1111 06:54:45.341917 15976 registrar.cpp:391] Successfully fetched the registry (0B) in
504064ns
3: I1111 06:54:45.342025 15976 registrar.cpp:495] Applied 1 operations in 28269ns; attempting
to update the registry
3: I1111 06:54:45.342538 15976 registrar.cpp:552] Successfully updated the registry in 462080ns
3: I1111 06:54:45.342645 15976 registrar.cpp:424] Successfully recovered registrar
3: I1111 06:54:45.343016 15971 master.cpp:1806] Recovered 0 agents from the registry (129B);
allowing 10mins for agents to re-register
3: I1111 06:54:45.343060 15964 hierarchical.cpp:209] Skipping recovery of hierarchical allocator:
nothing to recover
3: W1111 06:54:45.347856 15956 process.cpp:3178] Attempted to spawn already running process
files@172.17.0.6:40721
3: I1111 06:54:45.348134 15956 cluster.cpp:448] Creating default 'local' authorizer
3: I1111 06:54:45.350200 15961 slave.cpp:262] Mesos agent started on (237)@172.17.0.6:40721
3: I1111 06:54:45.350220 15961 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://"
--appc_store_dir="/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_O80xmy/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/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_O80xmy/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/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_O80xmy/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/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_O80xmy/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/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_O80xmy/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/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_O80xmy" --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/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7"
--zk_session_timeout="10secs"
3: I1111 06:54:45.350580 15961 credentials.hpp:86] Loading credential for authentication from
'/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_O80xmy/credential'
3: W1111 06:54:45.350630 15956 process.cpp:3178] Attempted to spawn already running process
version@172.17.0.6:40721
3: I1111 06:54:45.350733 15961 slave.cpp:295] Agent using credential for: test-principal
3: I1111 06:54:45.350751 15961 credentials.hpp:37] Loading credentials for authentication
from '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_O80xmy/http_credentials'
3: I1111 06:54:45.350980 15961 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readonly'
3: I1111 06:54:45.351122 15961 http.cpp:1045] Creating default 'basic' HTTP authenticator
for realm 'mesos-agent-readwrite'
3: I1111 06:54:45.351477 15956 sched.cpp:232] Version: 1.5.0
3: I1111 06:54:45.351974 15967 sched.cpp:336] New master detected at master@172.17.0.6:40721
3: I1111 06:54:45.352066 15967 sched.cpp:396] Authenticating with master master@172.17.0.6:40721
3: I1111 06:54:45.352082 15967 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1111 06:54:45.352330 15969 authenticatee.cpp:121] Creating new client SASL connection
3: I1111 06:54:45.352618 15973 master.cpp:8286] Authenticating scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
3: I1111 06:54:45.352728 15960 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(486)@172.17.0.6:40721
3: I1111 06:54:45.352565 15961 slave.cpp:593] 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"}]
3: I1111 06:54:45.352809 15961 slave.cpp:601] Agent attributes: [  ]
3: I1111 06:54:45.352828 15961 slave.cpp:610] Agent hostname: 568cb3f90890
3: I1111 06:54:45.352986 15965 authenticator.cpp:98] Creating new server SASL connection
3: I1111 06:54:45.353003 15968 status_update_manager.cpp:177] Pausing sending status updates
3: I1111 06:54:45.353207 15958 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1111 06:54:45.353232 15958 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1111 06:54:45.353358 15958 authenticator.cpp:204] Received SASL authentication start
3: I1111 06:54:45.353457 15958 authenticator.cpp:326] Authentication requires more steps
3: I1111 06:54:45.353785 15958 authenticatee.cpp:259] Received SASL authentication step
3: I1111 06:54:45.353945 15958 authenticator.cpp:232] Received SASL authentication step
3: I1111 06:54:45.354001 15958 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '568cb3f90890' server FQDN: '568cb3f90890' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1111 06:54:45.354018 15958 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1111 06:54:45.354068 15958 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1111 06:54:45.354086 15958 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '568cb3f90890' server FQDN: '568cb3f90890' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1111 06:54:45.354099 15958 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1111 06:54:45.354104 15958 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1111 06:54:45.354116 15958 authenticator.cpp:318] Authentication success
3: I1111 06:54:45.354179 15977 authenticatee.cpp:299] Authentication success
3: I1111 06:54:45.354346 15963 master.cpp:8316] Successfully authenticated principal 'test-principal'
at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
3: I1111 06:54:45.354382 15972 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(486)@172.17.0.6:40721
3: I1111 06:54:45.354679 15957 sched.cpp:502] Successfully authenticated with master master@172.17.0.6:40721
3: I1111 06:54:45.354725 15957 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.6:40721
3: I1111 06:54:45.354923 15957 sched.cpp:857] Will retry registration in 761.421742ms if necessary
3: I1111 06:54:45.355167 15966 master.cpp:2962] Received SUBSCRIBE call for framework 'default'
at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
3: I1111 06:54:45.355226 15978 state.cpp:64] Recovering state from '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta'
3: I1111 06:54:45.355242 15966 master.cpp:2278] Authorizing framework principal 'test-principal'
to receive offers for roles '{ * }'
3: I1111 06:54:45.355561 15969 status_update_manager.cpp:203] Recovering status update manager
3: I1111 06:54:45.355672 15973 master.cpp:3042] Subscribing framework default with checkpointing
enabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1111 06:54:45.355916 15965 slave.cpp:6422] Finished recovery
3: I1111 06:54:45.356233 15979 sched.cpp:751] Framework registered with dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.356278 15979 sched.cpp:765] Scheduler::registered took 22339ns
3: I1111 06:54:45.356389 15959 hierarchical.cpp:303] Added framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.356503 15963 status_update_manager.cpp:177] Pausing sending status updates
3: I1111 06:54:45.356515 15958 slave.cpp:1007] New master detected at master@172.17.0.6:40721
3: I1111 06:54:45.356634 15958 slave.cpp:1042] Detecting new master
3: I1111 06:54:45.356683 15959 hierarchical.cpp:1450] Performed allocation for 0 agents in
178125ns
3: I1111 06:54:45.365043 15972 slave.cpp:1069] Authenticating with master master@172.17.0.6:40721
3: I1111 06:54:45.365104 15972 slave.cpp:1078] Using default CRAM-MD5 authenticatee
3: I1111 06:54:45.365345 15957 authenticatee.cpp:121] Creating new client SASL connection
3: I1111 06:54:45.365715 15980 master.cpp:8286] Authenticating slave(237)@172.17.0.6:40721
3: I1111 06:54:45.365892 15961 authenticator.cpp:414] Starting authentication session for
crammd5-authenticatee(487)@172.17.0.6:40721
3: I1111 06:54:45.366103 15966 authenticator.cpp:98] Creating new server SASL connection
3: I1111 06:54:45.366299 15978 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
3: I1111 06:54:45.366323 15978 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
3: I1111 06:54:45.366407 15978 authenticator.cpp:204] Received SASL authentication start
3: I1111 06:54:45.366449 15978 authenticator.cpp:326] Authentication requires more steps
3: I1111 06:54:45.366528 15978 authenticatee.cpp:259] Received SASL authentication step
3: I1111 06:54:45.366616 15978 authenticator.cpp:232] Received SASL authentication step
3: I1111 06:54:45.366637 15978 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '568cb3f90890' server FQDN: '568cb3f90890' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
3: I1111 06:54:45.366645 15978 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1111 06:54:45.366668 15978 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1111 06:54:45.366683 15978 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '568cb3f90890' server FQDN: '568cb3f90890' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
3: I1111 06:54:45.366689 15978 auxprop.cpp:131] Skipping auxiliary property '*userPassword'
since SASL_AUXPROP_AUTHZID == true
3: I1111 06:54:45.366694 15978 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
3: I1111 06:54:45.366705 15978 authenticator.cpp:318] Authentication success
3: I1111 06:54:45.366786 15969 authenticatee.cpp:299] Authentication success
3: I1111 06:54:45.366848 15960 master.cpp:8316] Successfully authenticated principal 'test-principal'
at slave(237)@172.17.0.6:40721
3: I1111 06:54:45.366945 15978 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(487)@172.17.0.6:40721
3: I1111 06:54:45.367020 15968 slave.cpp:1161] Successfully authenticated with master master@172.17.0.6:40721
3: I1111 06:54:45.367228 15968 slave.cpp:1682] Will retry registration in 13.200018ms if necessary
3: I1111 06:54:45.367427 15976 master.cpp:6033] Received register agent message from slave(237)@172.17.0.6:40721
(568cb3f90890)
3: I1111 06:54:45.367543 15976 master.cpp:3871] Authorizing agent with principal 'test-principal'
3: I1111 06:54:45.367915 15977 master.cpp:6093] Authorized registration of agent at slave(237)@172.17.0.6:40721
(568cb3f90890)
3: I1111 06:54:45.368021 15977 master.cpp:6186] Registering agent at slave(237)@172.17.0.6:40721
(568cb3f90890) with id dab3a7a5-f31e-4097-9133-c72186599b0d-S0
3: I1111 06:54:45.368432 15963 registrar.cpp:495] Applied 1 operations in 58804ns; attempting
to update the registry
3: I1111 06:54:45.368927 15963 registrar.cpp:552] Successfully updated the registry in 440064ns
3: I1111 06:54:45.369145 15957 master.cpp:6233] Admitted agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.369885 15961 slave.cpp:1207] Registered with master master@172.17.0.6:40721;
given agent ID dab3a7a5-f31e-4097-9133-c72186599b0d-S0
3: I1111 06:54:45.369765 15957 master.cpp:6264] Registered agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890) 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"}]
3: I1111 06:54:45.370012 15967 status_update_manager.cpp:184] Resuming sending status updates
3: I1111 06:54:45.370088 15980 hierarchical.cpp:593] Added agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
(568cb3f90890) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
3: I1111 06:54:45.370203 15961 slave.cpp:1227] Checkpointing SlaveInfo to '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/slave.info'
3: I1111 06:54:45.370558 15961 slave.cpp:1295] Forwarding total oversubscribed resources {}
3: I1111 06:54:45.370754 15970 master.cpp:7065] Received update of agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890) with total oversubscribed resources {}
3: I1111 06:54:45.370956 15970 master.cpp:7083] Ignoring update on agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890) as it reports no changes
3: I1111 06:54:45.371647 15980 hierarchical.cpp:1450] Performed allocation for 1 agents in
1.415162ms
3: I1111 06:54:45.372081 15969 master.cpp:8116] Sending 1 offers to framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
(default) at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
3: I1111 06:54:45.372560 15969 sched.cpp:921] Scheduler::resourceOffers took 99817ns
3: I1111 06:54:45.374505 15976 master.cpp:10016] Removing offer dab3a7a5-f31e-4097-9133-c72186599b0d-O0
3: I1111 06:54:45.374661 15976 master.cpp:4229] Processing ACCEPT call for offers: [ dab3a7a5-f31e-4097-9133-c72186599b0d-O0
] on agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0 at slave(237)@172.17.0.6:40721 (568cb3f90890)
for framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 (default) at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
3: I1111 06:54:45.374753 15976 master.cpp:3598] Authorizing framework principal 'test-principal'
to launch task 1
3: W1111 06:54:45.376639 15976 validation.cpp:1404] Executor 'default' 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.
3: W1111 06:54:45.376667 15976 validation.cpp:1416] Executor 'default' for task '1' uses less
memory (None) than the minimum required (32MB). Please update your executor, as this will
be mandatory in future releases.
3: I1111 06:54:45.377207 15976 master.cpp:10762] Adding task 1 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 dab3a7a5-f31e-4097-9133-c72186599b0d-S0 at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.377841 15976 master.cpp:4977] Launching task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
(default) at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721 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 dab3a7a5-f31e-4097-9133-c72186599b0d-S0 at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.378495 15971 slave.cpp:1803] Got assigned task '1' for framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.378654 15971 slave.cpp:7423] Checkpointing FrameworkInfo to '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/framework.info'
3: I1111 06:54:45.379010 15971 slave.cpp:7434] Checkpointing framework pid 'scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721'
to '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/framework.pid'
3: I1111 06:54:45.379796 15971 slave.cpp:2071] Authorizing task '1' for framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.379859 15971 slave.cpp:7030] Authorizing framework principal 'test-principal'
to launch task 1
3: I1111 06:54:45.380758 15966 slave.cpp:2239] Launching task '1' for framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.381331 15966 paths.cpp:605] Trying to chown '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588'
to user 'mesos'
3: I1111 06:54:45.381570 15966 slave.cpp:8128] Checkpointing ExecutorInfo to '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/executor.info'
3: I1111 06:54:45.382160 15966 slave.cpp:7504] Launching executor 'default' of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
with resources [] in work directory '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588'
3: I1111 06:54:45.382709 15966 slave.cpp:2930] Launching container 59589ef2-7346-458c-891d-5d61cd21a588
for executor 'default' of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.383070 15966 slave.cpp:8171] Checkpointing TaskInfo to '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588/tasks/1/task.info'
3: I1111 06:54:45.383566 15966 slave.cpp:2467] Queued task '1' for executor 'default' of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.383654 15966 slave.cpp:958] Successfully attached '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588'
to virtual path '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/latest'
3: I1111 06:54:45.383697 15966 slave.cpp:958] Successfully attached '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588'
to virtual path '/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/latest'
3: I1111 06:54:45.383729 15966 slave.cpp:958] Successfully attached '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588'
to virtual path '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588'
3: W1111 06:54:45.385332 15957 process.cpp:3178] Attempted to spawn already running process
version@172.17.0.6:40721
3: I1111 06:54:45.385504 15957 exec.cpp:162] Version: 1.5.0
3: I1111 06:54:45.385746 15970 exec.cpp:212] Executor started at: executor(88)@172.17.0.6:40721
with pid 15956
3: I1111 06:54:45.385949 15980 slave.cpp:4053] Got registration for executor 'default' of
framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 from executor(88)@172.17.0.6:40721
3: I1111 06:54:45.386083 15980 slave.cpp:4139] Checkpointing executor pid 'executor(88)@172.17.0.6:40721'
to '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588/pids/libprocess.pid'
3: I1111 06:54:45.386829 15973 exec.cpp:237] Executor registered on agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
3: I1111 06:54:45.386873 15973 exec.cpp:249] Executor::registered took 22856ns
3: I1111 06:54:45.387420 15980 slave.cpp:2669] Sending queued task '1' to executor 'default'
of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 at executor(88)@172.17.0.6:40721
3: I1111 06:54:45.387795 15979 exec.cpp:331] Executor asked to run task '1'
3: I1111 06:54:45.387873 15979 exec.cpp:340] Executor::launchTask took 58924ns
3: I1111 06:54:45.387967 15979 exec.cpp:571] Executor sending status update TASK_FINISHED
(UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.388239 15960 slave.cpp:4507] Handling status update TASK_FINISHED (UUID:
007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
from executor(88)@172.17.0.6:40721
3: I1111 06:54:45.389170 15968 status_update_manager.cpp:323] Received status update TASK_FINISHED
(UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.389209 15968 status_update_manager.cpp:500] Creating StatusUpdate stream
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.389709 15968 status_update_manager.cpp:834] Checkpointing UPDATE for status
update TASK_FINISHED (UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.389946 15968 status_update_manager.cpp:377] Forwarding update TASK_FINISHED
(UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
to the agent
3: I1111 06:54:45.390174 15975 slave.cpp:4988] Forwarding the update TASK_FINISHED (UUID:
007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
to master@172.17.0.6:40721
3: I1111 06:54:45.390353 15975 slave.cpp:4882] Status update manager successfully handled
status update TASK_FINISHED (UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.390398 15975 slave.cpp:4898] Sending acknowledgement for status update TASK_FINISHED
(UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
to executor(88)@172.17.0.6:40721
3: I1111 06:54:45.390545 15977 exec.cpp:388] Executor received status update acknowledgement
007c4dbe-0142-45d0-a3c7-b07ea5043251 for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.390588 15965 master.cpp:7248] Status update TASK_FINISHED (UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251)
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 from agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.390651 15965 master.cpp:7310] Forwarding status update TASK_FINISHED (UUID:
007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.390831 15965 master.cpp:9503] Updating the state of task 1 of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000 (latest state: TASK_FINISHED, status update state:
TASK_FINISHED)
3: I1111 06:54:45.391044 15958 sched.cpp:1029] Scheduler::statusUpdate took 92639ns
3: I1111 06:54:45.391538 15965 master.cpp:5792] Processing ACKNOWLEDGE call 007c4dbe-0142-45d0-a3c7-b07ea5043251
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 (default) at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
on agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
3: I1111 06:54:45.391593 15965 master.cpp:9597] Removing task 1 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 dab3a7a5-f31e-4097-9133-c72186599b0d-0000 on agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.391902 15976 hierarchical.cpp:1125] 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 dab3a7a5-f31e-4097-9133-c72186599b0d-S0
from framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.392221 15961 status_update_manager.cpp:395] Received status update acknowledgement
(UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.392338 15961 status_update_manager.cpp:834] Checkpointing ACK for status
update TASK_FINISHED (UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.392426 15961 status_update_manager.cpp:531] Cleaning up status update stream
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.392730 15970 slave.cpp:3791] Status update manager successfully handled
status update acknowledgement (UUID: 007c4dbe-0142-45d0-a3c7-b07ea5043251) for task 1 of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.392768 15970 slave.cpp:8109] Completing task 1
3: I1111 06:54:45.393165 15979 exec.cpp:571] Executor sending status update TASK_KILLED (UUID:
6305df2a-e054-446d-98e7-914935559261) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.393451 15960 slave.cpp:4507] Handling status update TASK_KILLED (UUID: 6305df2a-e054-446d-98e7-914935559261)
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 from executor(88)@172.17.0.6:40721
3: W1111 06:54:45.393508 15960 slave.cpp:4622] Could not find the executor for status update
TASK_KILLED (UUID: 6305df2a-e054-446d-98e7-914935559261) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.393712 15974 status_update_manager.cpp:323] Received status update TASK_KILLED
(UUID: 6305df2a-e054-446d-98e7-914935559261) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.393752 15974 status_update_manager.cpp:500] Creating StatusUpdate stream
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.394081 15974 status_update_manager.cpp:377] Forwarding update TASK_KILLED
(UUID: 6305df2a-e054-446d-98e7-914935559261) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
to the agent
3: I1111 06:54:45.394280 15978 slave.cpp:4988] Forwarding the update TASK_KILLED (UUID: 6305df2a-e054-446d-98e7-914935559261)
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 to master@172.17.0.6:40721
3: I1111 06:54:45.394485 15978 slave.cpp:4882] Status update manager successfully handled
status update TASK_KILLED (UUID: 6305df2a-e054-446d-98e7-914935559261) for task 1 of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.394527 15978 slave.cpp:4898] Sending acknowledgement for status update TASK_KILLED
(UUID: 6305df2a-e054-446d-98e7-914935559261) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
to executor(88)@172.17.0.6:40721
3: I1111 06:54:45.394625 15964 master.cpp:7248] Status update TASK_KILLED (UUID: 6305df2a-e054-446d-98e7-914935559261)
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 from agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.394677 15964 master.cpp:7310] Forwarding status update TASK_KILLED (UUID:
6305df2a-e054-446d-98e7-914935559261) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.394733 15968 exec.cpp:388] Executor received status update acknowledgement
6305df2a-e054-446d-98e7-914935559261 for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: W1111 06:54:45.394824 15964 master.cpp:7278] Could not lookup task for status update TASK_KILLED
(UUID: 6305df2a-e054-446d-98e7-914935559261) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
from agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0 at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.395090 15968 sched.cpp:1029] Scheduler::statusUpdate took 90351ns
3: I1111 06:54:45.395334 15977 master.cpp:5792] Processing ACKNOWLEDGE call 6305df2a-e054-446d-98e7-914935559261
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 (default) at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
on agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
3: I1111 06:54:45.395597 15972 status_update_manager.cpp:395] Received status update acknowledgement
(UUID: 6305df2a-e054-446d-98e7-914935559261) for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.395756 15972 status_update_manager.cpp:531] Cleaning up status update stream
for task 1 of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.396064 15959 slave.cpp:3791] Status update manager successfully handled
status update acknowledgement (UUID: 6305df2a-e054-446d-98e7-914935559261) for task 1 of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: E1111 06:54:45.396098 15959 slave.cpp:3815] Status update acknowledgement (UUID: 6305df2a-e054-446d-98e7-914935559261)
for task 1 of unknown executor
3: I1111 06:54:45.398386 15962 process.cpp:3925] Handling HTTP event for process 'master'
with path: '/master/tasks'
3: I1111 06:54:45.399600 15973 http.cpp:1185] HTTP GET for /master/tasks from 172.17.0.6:58814
3: I1111 06:54:45.407189 15956 sched.cpp:2009] Asked to stop the driver
3: I1111 06:54:45.407292 15977 sched.cpp:1191] Stopping framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.407565 15972 master.cpp:8793] Processing TEARDOWN call for framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
(default) at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
3: I1111 06:54:45.407595 15972 master.cpp:8805] Removing framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
(default) at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
3: I1111 06:54:45.407614 15972 master.cpp:3332] Deactivating framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
(default) at scheduler-ae049975-cad6-4044-9404-55f3fc5487b9@172.17.0.6:40721
3: I1111 06:54:45.407750 15963 hierarchical.cpp:412] Deactivated framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.407796 15967 slave.cpp:3267] Asked to shut down framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
by master@172.17.0.6:40721
3: I1111 06:54:45.407836 15967 slave.cpp:3292] Shutting down framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.407866 15972 master.cpp:9626] Removing executor 'default' with resources
[] of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 on agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.407886 15967 slave.cpp:5855] Shutting down executor 'default' of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000 at executor(88)@172.17.0.6:40721
3: I1111 06:54:45.408005 15965 exec.cpp:435] Executor asked to shutdown
3: I1111 06:54:45.408054 15965 exec.cpp:450] Executor::shutdown took 28963ns
3: I1111 06:54:45.408368 15980 slave.cpp:5120] Got exited event for executor(88)@172.17.0.6:40721
3: I1111 06:54:45.408689 15978 hierarchical.cpp:355] Removed framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.409159 15967 slave.cpp:5520] Executor 'default' of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
exited with status 0
3: I1111 06:54:45.409243 15967 slave.cpp:5624] Cleaning up executor 'default' of framework
dab3a7a5-f31e-4097-9133-c72186599b0d-0000 at executor(88)@172.17.0.6:40721
3: W1111 06:54:45.409425 15962 master.cpp:7454] Ignoring unknown exited executor 'default'
of framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000 on agent dab3a7a5-f31e-4097-9133-c72186599b0d-S0
at slave(237)@172.17.0.6:40721 (568cb3f90890)
3: I1111 06:54:45.409682 15964 gc.cpp:90] Scheduling '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588'
for gc 6.99999525944296days in the future
3: I1111 06:54:45.409876 15964 gc.cpp:90] Scheduling '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default'
for gc 6.99999525745481days in the future
3: I1111 06:54:45.409974 15967 slave.cpp:5731] Cleaning up framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.410007 15964 gc.cpp:90] Scheduling '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default/runs/59589ef2-7346-458c-891d-5d61cd21a588'
for gc 6.99999525630815days in the future
3: I1111 06:54:45.410061 15959 status_update_manager.cpp:285] Closing status update streams
for framework dab3a7a5-f31e-4097-9133-c72186599b0d-0000
3: I1111 06:54:45.410135 15964 gc.cpp:90] Scheduling '/tmp/StatusUpdateManagerTest_DuplicatedTerminalStatusUpdate_cqj8U7/meta/slaves/dab3a7a5-f31e-4097-9133-c72186599b0d-S0/frameworks/dab3a7a5-f31e-4097-9133-c72186599b0d-0000/executors/default'
for gc 6.99999525545185days in the future
write /dev/stdout: resource temporarily unavailable
+ docker rmi mesos-1510381231-10736
Build step 'Execute shell' marked build as failure


Mime
View raw message