See
<https://builds.apache.org/job/Mesos-Reviewbot/23673/display/redirect?page=changes>
Changes:
[songzihao1990] Fixed the release.yml 1.5.2 release target version typo.
------------------------------------------
[...truncated 47.62 MB...]
I0128 23:30:08.831127 19732 replica.cpp:695] Replica received learned notice
for position 3 from log-network(6)@172.17.0.2:42390
I0128 23:30:08.940737 19761 slave.cpp:1894] Will retry registration in
446.620686ms if necessary
I0128 23:30:08.940934 19779 master.cpp:6686] Ignoring register agent message
from slave(36)@172.17.0.2:42390 (b8fdd4b036c3) as registration is already in
progress
I0128 23:30:09.038544 19732 leveldb.cpp:347] Persisting action (441 bytes) to
leveldb took 207.348646ms
I0128 23:30:09.038628 19732 replica.cpp:712] Persisted action APPEND at
position 3
I0128 23:30:09.040099 19746 registrar.cpp:544] Successfully updated the
registry in 369.593856ms
I0128 23:30:09.040344 19778 log.cpp:597] Attempting to truncate the log to 3
I0128 23:30:09.040401 19723 master.cpp:6923] Admitted agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0 at slave(36)@172.17.0.2:42390
(b8fdd4b036c3)
I0128 23:30:09.040524 19776 coordinator.cpp:348] Coordinator attempting to
write TRUNCATE action at position 4
I0128 23:30:09.041566 19770 replica.cpp:541] Replica received write request for
position 4 from __req_res__(115)@172.17.0.2:42390
I0128 23:30:09.041726 19723 master.cpp:6968] Registered agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0 at slave(36)@172.17.0.2:42390
(b8fdd4b036c3) with cpus(reservations: [(STATIC,foo)]):1; cpus:2;
mem(reservations: [(STATIC,foo)]):1024; mem:1024; disk(reservations:
[(STATIC,foo)]):1024; disk:1024; ports:[31000-32000]
I0128 23:30:09.042152 19763 hierarchical.cpp:604] Added agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0 (b8fdd4b036c3) with cpus(reservations:
[(STATIC,foo)]):1; cpus:2; mem(reservations: [(STATIC,foo)]):1024; mem:1024;
disk(reservations: [(STATIC,foo)]):1024; disk:1024; ports:[31000-32000]
(allocated: {})
I0128 23:30:09.042547 19763 hierarchical.cpp:1558] Performed allocation for 1
agents in 183324ns
I0128 23:30:09.042950 19750 slave.cpp:1494] Registered with master
master@172.17.0.2:42390; given agent ID c79fe58d-2f96-4904-912c-faebd2be59ce-S0
I0128 23:30:09.043148 19753 task_status_update_manager.cpp:188] Resuming
sending task status updates
I0128 23:30:09.043205 19774 status_update_manager_process.hpp:385] Resuming
operation status update manager
I0128 23:30:09.043387 19750 slave.cpp:1515] Checkpointing SlaveInfo to
'/tmp/ContentType_MasterAPITest_GetRecoveredAgents_1_95lo2b/meta/slaves/c79fe58d-2f96-4904-912c-faebd2be59ce-S0/slave.info'
I0128 23:30:09.044627 19750 slave.cpp:1564] Forwarding agent update
{"operations":{},"resource_providers":{},"resource_version_uuid":{"value":"bTx/w74qQeOxeDYuqwlfdQ=="},"slave_id":{"value":"c79fe58d-2f96-4904-912c-faebd2be59ce-S0"},"update_oversubscribed_resources":false}
I0128 23:30:09.045511 19746 master.cpp:8027] Ignoring update on agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0 at slave(36)@172.17.0.2:42390
(b8fdd4b036c3) as it reports no changes
I0128 23:30:09.046835 19763 process.cpp:3588] Handling HTTP event for process
'master' with path: '/master/reserve'
I0128 23:30:09.048645 19774 http.cpp:1105] HTTP POST for /master/reserve from
172.17.0.2:54750
I0128 23:30:09.049577 19774 master.cpp:3614] Authorizing principal
'test-principal' to reserve resources 'cpus(reservations:
[(DYNAMIC,bar,test-principal)]):1; mem(reservations:
[(DYNAMIC,bar,test-principal)]):12'
I0128 23:30:09.052868 19768 master.cpp:11633] Sending operation '' (uuid:
ad027ed6-6543-4e5c-a591-59cf43898827) to agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0 at slave(36)@172.17.0.2:42390
(b8fdd4b036c3)
I0128 23:30:09.053335 19768 slave.cpp:4203] Ignoring new checkpointed resources
and operations identical to the current version
I0128 23:30:09.053833 19768 slave.cpp:8348] Updating the state of operation
with no ID (uuid: ad027ed6-6543-4e5c-a591-59cf43898827) for an operation API
call (latest state: OPERATION_FINISHED, status update state: OPERATION_FINISHED)
I0128 23:30:09.055627 19768 slave.cpp:4279] Updated checkpointed resources from
{} to cpus(reservations: [(DYNAMIC,bar,test-principal)]):1; mem(reservations:
[(DYNAMIC,bar,test-principal)]):12
I0128 23:30:09.055729 19768 slave.cpp:4287] Updated checkpointed operations
from [ ] to [ ad027ed6-6543-4e5c-a591-59cf43898827 (RESERVE, latest state:
OPERATION_FINISHED) ]
I0128 23:30:09.055974 19753 status_update_manager_process.hpp:152] Received
operation status update OPERATION_FINISHED (Status UUID:
54193fb3-ecae-45b3-83b9-56c623d306b8) for operation UUID
ad027ed6-6543-4e5c-a591-59cf43898827 on agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0
I0128 23:30:09.056046 19753 status_update_manager_process.hpp:414] Creating
operation status update stream ad027ed6-6543-4e5c-a591-59cf43898827
checkpoint=true
I0128 23:30:09.056382 19753 status_update_manager_process.hpp:929]
Checkpointing UPDATE for operation status update OPERATION_FINISHED (Status
UUID: 54193fb3-ecae-45b3-83b9-56c623d306b8) for operation UUID
ad027ed6-6543-4e5c-a591-59cf43898827 on agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0
I0128 23:30:09.058199 19779 process.cpp:3588] Handling HTTP event for process
'master' with path: '/master/api/v1'
I0128 23:30:09.059921 19755 http.cpp:1105] HTTP POST for /master/api/v1 from
172.17.0.2:54752
I0128 23:30:09.060087 19755 http.cpp:260] Processing call GET_AGENTS
I0128 23:30:09.066911 19299 master.cpp:1109] Master terminating
I0128 23:30:09.067759 19715 slave.cpp:6063] Got exited event for
master@172.17.0.2:42390
W0128 23:30:09.067791 19715 slave.cpp:6068] Master disconnected! Waiting for a
new master to be elected
I0128 23:30:09.068289 19728 hierarchical.cpp:644] Removed agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0
I0128 23:30:09.257103 19770 leveldb.cpp:347] Persisting action (16 bytes) to
leveldb took 215.472945ms
I0128 23:30:09.257174 19770 replica.cpp:712] Persisted action TRUNCATE at
position 4
I0128 23:30:09.268745 19299 slave.cpp:923] Agent terminating
I0128 23:30:09.384032 19753 status_update_manager_process.hpp:528] Forwarding
operation status update OPERATION_FINISHED (Status UUID:
54193fb3-ecae-45b3-83b9-56c623d306b8) for operation UUID
ad027ed6-6543-4e5c-a591-59cf43898827 on agent
c79fe58d-2f96-4904-912c-faebd2be59ce-S0
I0128 23:30:10.013175 19299 cluster.cpp:174] Creating default 'local' authorizer
I0128 23:30:10.704210 19299 leveldb.cpp:174] Opened db in 690.516174ms
I0128 23:30:11.271226 19299 leveldb.cpp:181] Compacted db in 566.925664ms
I0128 23:30:11.271327 19299 leveldb.cpp:196] Created db iterator in 37045ns
I0128 23:30:11.271375 19299 leveldb.cpp:202] Seeked to beginning of db in
30360ns
I0128 23:30:11.271533 19299 leveldb.cpp:277] Iterated through 5 keys in the db
in 140463ns
I0128 23:30:11.271602 19299 replica.cpp:795] Replica recovered with log
positions 1 -> 4 with 0 holes and 1 unlearned
I0128 23:30:11.272681 19750 recover.cpp:437] Starting replica recovery
I0128 23:30:11.273058 19776 recover.cpp:468] Replica is in VOTING status
I0128 23:30:11.273288 19776 recover.cpp:447] Recover process terminated
I0128 23:30:11.276342 19770 master.cpp:414] Master
935dc49c-a69c-45c7-8628-02e373b4e83f (b8fdd4b036c3) started on 172.17.0.2:42390
I0128 23:30:11.276372 19770 master.cpp:417] Flags at startup:
--acls="view_roles {
principals {
values: "test-principal"
}
roles {
type: ANY
}
}
" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="1000secs" --allocator="hierarchical"
--authenticate_agents="true" --authenticate_frameworks="true"
--authenticate_http_frameworks="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authentication_v0_timeout="15secs"
--authenticators="crammd5" --authorizers="local"
--credentials="/tmp/Gy3guN/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_operator_event_stream_subscribers="1000"
--max_unreachable_tasks_per_framework="1000" --memory_profiling="false"
--min_allocatable_resources="cpus:0.01|mem:32" --port="5050"
--publish_per_framework_metrics="true" --quiet="false"
--recovery_agent_removal_limit="100%" --registry="replicated_log"
--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"
--require_agent_domain="false" --role_sorter="drf" --root_submissions="true"
--version="false" --webui_dir="/mesos/mesos-1.8.0/_inst/share/mesos/webui"
--work_dir="/tmp/Gy3guN/master" --zk_session_timeout="10secs"
I0128 23:30:11.276871 19770 master.cpp:466] Master only allowing authenticated
frameworks to register
I0128 23:30:11.276890 19770 master.cpp:472] Master only allowing authenticated
agents to register
I0128 23:30:11.276903 19770 master.cpp:478] Master only allowing authenticated
HTTP frameworks to register
I0128 23:30:11.276917 19770 credentials.hpp:37] Loading credentials for
authentication from '/tmp/Gy3guN/credentials'
I0128 23:30:11.277158 19770 master.cpp:522] Using default 'crammd5'
authenticator
I0128 23:30:11.277318 19770 http.cpp:965] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0128 23:30:11.277495 19770 http.cpp:965] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0128 23:30:11.277650 19770 http.cpp:965] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0128 23:30:11.277767 19770 master.cpp:603] Authorization enabled
I0128 23:30:11.278247 19719 hierarchical.cpp:176] Initialized hierarchical
allocator process
I0128 23:30:11.278277 19746 whitelist_watcher.cpp:77] No whitelist given
I0128 23:30:11.281330 19755 master.cpp:2083] Elected as the leading master!
I0128 23:30:11.281363 19755 master.cpp:1638] Recovering from registrar
I0128 23:30:11.281513 19753 registrar.cpp:339] Recovering registrar
I0128 23:30:11.282439 19753 log.cpp:554] Attempting to start the writer
I0128 23:30:11.283795 19719 replica.cpp:497] Replica received implicit promise
request from __req_res__(116)@172.17.0.2:42390 with proposal 2
I0128 23:30:11.394376 19719 leveldb.cpp:310] Persisting metadata (8 bytes) to
leveldb took 110.525861ms
I0128 23:30:11.394445 19719 replica.cpp:344] Persisted promised to 2
I0128 23:30:11.395954 19736 coordinator.cpp:238] Coordinator attempting to fill
missing positions
I0128 23:30:11.397694 19736 replica.cpp:391] Replica received explicit promise
request from __req_res__(117)@172.17.0.2:42390 for position 4 with proposal 3
I0128 23:30:11.397817 19736 leveldb.cpp:460] Reading position from leveldb took
85096ns
I0128 23:30:11.544610 19736 leveldb.cpp:347] Persisting action (16 bytes) to
leveldb took 146.722757ms
I0128 23:30:11.544682 19736 replica.cpp:712] Persisted action TRUNCATE at
position 4
I0128 23:30:11.546216 19750 replica.cpp:541] Replica received write request for
position 4 from __req_res__(118)@172.17.0.2:42390
I0128 23:30:11.546311 19750 leveldb.cpp:460] Reading position from leveldb took
50101ns
I0128 23:30:11.723553 19750 leveldb.cpp:347] Persisting action (16 bytes) to
leveldb took 177.162401ms
I0128 23:30:11.723623 19750 replica.cpp:712] Persisted action TRUNCATE at
position 4
I0128 23:30:11.724323 19774 replica.cpp:695] Replica received learned notice
for position 4 from log-network(7)@172.17.0.2:42390
I0128 23:30:11.885167 19774 leveldb.cpp:347] Persisting action (18 bytes) to
leveldb took 160.793401ms
I0128 23:30:11.886732 19774 leveldb.cpp:423] Deleting ~2 keys from leveldb took
1.505725ms
I0128 23:30:11.886773 19774 replica.cpp:712] Persisted action TRUNCATE at
position 4
I0128 23:30:11.887575 19728 log.cpp:570] Writer started with ending position 4
I0128 23:30:11.889345 19772 leveldb.cpp:460] Reading position from leveldb took
98814ns
I0128 23:30:11.889461 19772 leveldb.cpp:460] Reading position from leveldb took
33511ns
I0128 23:30:11.890972 19768 registrar.cpp:383] Successfully fetched the
registry (381B) in 609.353984ms
I0128 23:30:11.891113 19768 registrar.cpp:487] Applied 1 operations in 57831ns;
attempting to update the registry
I0128 23:30:11.891861 19723 log.cpp:578] Attempting to append 420 bytes to the
log
I0128 23:30:11.892005 19765 coordinator.cpp:348] Coordinator attempting to
write APPEND action at position 5
I0128 23:30:11.892725 19746 replica.cpp:541] Replica received write request for
position 5 from __req_res__(119)@172.17.0.2:42390
I0128 23:30:12.065093 19746 leveldb.cpp:347] Persisting action (439 bytes) to
leveldb took 172.32345ms
I0128 23:30:12.065156 19746 replica.cpp:712] Persisted action APPEND at
position 5
I0128 23:30:12.066046 19755 replica.cpp:695] Replica received learned notice
for position 5 from log-network(7)@172.17.0.2:42390
I0128 23:30:12.258862 19755 leveldb.cpp:347] Persisting action (441 bytes) to
leveldb took 192.776691ms
I0128 23:30:12.258926 19755 replica.cpp:712] Persisted action APPEND at
position 5
I0128 23:30:12.260408 19723 registrar.cpp:544] Successfully updated the
registry in 369.225216ms
I0128 23:30:12.260462 19736 log.cpp:597] Attempting to truncate the log to 5
I0128 23:30:12.260632 19723 registrar.cpp:416] Successfully recovered registrar
I0128 23:30:12.260732 19736 coordinator.cpp:348] Coordinator attempting to
write TRUNCATE action at position 6
I0128 23:30:12.261703 19719 master.cpp:1752] Recovered 1 agents from the
registry (381B); allowing 10mins for agents to reregister
I0128 23:30:12.261803 19715 hierarchical.cpp:216] Skipping recovery of
hierarchical allocator: nothing to recover
I0128 23:30:12.261853 19719 replica.cpp:541] Replica received write request for
position 6 from __req_res__(120)@172.17.0.2:42390
I0128 23:30:12.414696 19719 leveldb.cpp:347] Persisting action (16 bytes) to
leveldb took 152.793457ms
I0128 23:30:12.414768 19719 replica.cpp:712] Persisted action TRUNCATE at
position 6
I0128 23:30:12.415812 19719 replica.cpp:695] Replica received learned notice
for position 6 from log-network(7)@172.17.0.2:42390
I0128 23:30:12.562980 19719 leveldb.cpp:347] Persisting action (18 bytes) to
leveldb took 147.119337ms
I0128 23:30:12.564380 19719 leveldb.cpp:423] Deleting ~2 keys from leveldb took
1.334075ms
I0128 23:30:12.564419 19719 replica.cpp:712] Persisted action TRUNCATE at
position 6
I0128 23:30:12.568631 19728 process.cpp:3588] Handling HTTP event for process
'master' with path: '/master/api/v1'
I0128 23:30:12.570524 19776 http.cpp:1105] HTTP POST for /master/api/v1 from
172.17.0.2:54976
I0128 23:30:12.570683 19776 http.cpp:260] Processing call GET_AGENTS
W0128 23:30:12.577587 19299 process.cpp:2829] Attempted to spawn already
running process files@172.17.0.2:42390
I0128 23:30:12.578610 19299 containerizer.cpp:309] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0128 23:30:12.579156 19299 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
W0128 23:30:12.579296 19299 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
I0128 23:30:12.579339 19299 provisioner.cpp:298] Using default backend 'copy'
I0128 23:30:12.581698 19299 cluster.cpp:486] Creating default 'local' authorizer
I0128 23:30:12.584988 19746 slave.cpp:269] Mesos agent started on
(37)@172.17.0.2:42390
I0128 23:30:12.585018 19746 slave.cpp:270] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/Gy3guN/C7WF8Y/store/appc"
--authenticate_http_readonly="true" --authenticate_http_readwrite="false"
--authenticatee="crammd5" --authentication_backoff_factor="1secs"
--authentication_timeout_max="1mins" --authentication_timeout_min="5secs"
--authorizer="local" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_destroy_timeout="1mins" --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/Gy3guN/C7WF8Y/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/Gy3guN/C7WF8Y/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/Gy3guN/C7WF8Y/fetch" --fetcher_cache_size="2GB"
--fetcher_stall_timeout="1mins"
--frameworks_home="/tmp/Gy3guN/C7WF8Y/frameworks" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --gc_non_executor_container_sandboxes="false"
--help="false" --hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/Gy3guN/C7WF8Y/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/mesos-1.8.0/_build/src" --logbufsecs="0"
--logging_level="INFO" --max_completed_executors_per_framework="150"
--memory_profiling="false" --network_cni_metrics="true"
--network_cni_root_dir_persist="false"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --reconfiguration_policy="equal" --recover="reconnect"
--recovery_timeout="15mins" --registration_backoff_factor="10ms"
--resources="cpus(foo):1;cpus(*):2;gpus(*):0;mem(foo):1024;mem(*):1024;disk(foo):1024;disk(*):1024;ports(*):[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/ContentType_MasterAPITest_GetRecoveredAgents_1_APOwAA"
--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/ContentType_MasterAPITest_GetRecoveredAgents_1_95lo2b"
--zk_session_timeout="10secs"
I0128 23:30:12.585584 19746 credentials.hpp:86] Loading credential for
authentication from '/tmp/Gy3guN/C7WF8Y/credential'
I0128 23:30:12.585738 19746 slave.cpp:302] Agent using credential for:
test-principal
I0128 23:30:12.585760 19746 credentials.hpp:37] Loading credentials for
authentication from '/tmp/Gy3guN/C7WF8Y/http_credentials'
I0128 23:30:12.585949 19746 http.cpp:965] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0128 23:30:12.586261 19746 disk_profile_adaptor.cpp:80] Creating default disk
profile adaptor module
I0128 23:30:12.588171 19746 slave.cpp:617] Agent resources:
[{"name":"cpus","reservations":[{"role":"foo","type":"STATIC"}],"scalar":{"value":1.0},"type":"SCALAR"},{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","reservations":[{"role":"foo","type":"STATIC"}],"scalar":{"value":1024.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","reservations":[{"role":"foo","type":"STATIC"}],"scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0128 23:30:12.588618 19746 slave.cpp:625] Agent attributes: [ ]
I0128 23:30:12.588636 19746 slave.cpp:634] Agent hostname: b8fdd4b036c3
I0128 23:30:12.588802 19763 status_update_manager_process.hpp:379] Pausing
operation status update manager
I0128 23:30:12.588891 19715 task_status_update_manager.cpp:181] Pausing sending
task status updates
I0128 23:30:12.590348 19732 state.cpp:67] Recovering state from
'/tmp/ContentType_MasterAPITest_GetRecoveredAgents_1_95lo2b/meta'
I0128 23:30:12.593772 19740 slave.cpp:7079] Finished recovering checkpointed
state from '/tmp/ContentType_MasterAPITest_GetRecoveredAgents_1_95lo2b/meta',
beginning agent recovery
I0128 23:30:12.594995 19708 task_status_update_manager.cpp:207] Recovering task
status update manager
I0128 23:30:12.595504 19774 containerizer.cpp:735] Recovering Mesos containers
I0128 23:30:12.595816 19774 containerizer.cpp:1061] Recovering isolators
I0128 23:30:12.596494 19750 containerizer.cpp:1100] Recovering provisioner
I0128 23:30:12.597134 19763 provisioner.cpp:494] Provisioner recovery complete
I0128 23:30:12.597916 19779 composing.cpp:339] Finished recovering all
containerizers
F0128 23:30:12.598119 19708 slave.cpp:7315] Check failed:
operation.has_framework_id()
*** Check failure stack trace: ***
@ 0x2b7b935caae2 google::LogMessage::Fail()
@ 0x2b7b935caa2e google::LogMessage::SendToLog()
@ 0x2b7b935ca430 google::LogMessage::Flush()
@ 0x2b7b935cd344 google::LogMessageFatal::~LogMessageFatal()
@ 0x2b7b921db016 mesos::internal::slave::Slave::_recoverOperations()
@ 0x2b7b9225b87b
_ZZN7process8dispatchIN5mesos8internal26StatusUpdateManagerProcessIN2id4UUIDENS2_27UpdateOperationStatusRecordENS2_28UpdateOperationStatusMessageEE5StateENS2_5slave5SlaveERK6OptionINSA_5state10SlaveStateEESH_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSM_FSK_T1_EOT2_ENKUlSt10unique_ptrINS_7PromiseIS9_EESt14default_deleteISX_EEOSF_PNS_11ProcessBaseEE_clES10_S11_S13_
@ 0x2b7b92303942
_ZN5cpp176invokeIZN7process8dispatchIN5mesos8internal26StatusUpdateManagerProcessIN2id4UUIDENS4_27UpdateOperationStatusRecordENS4_28UpdateOperationStatusMessageEE5StateENS4_5slave5SlaveERK6OptionINSC_5state10SlaveStateEESJ_EENS1_6FutureIT_EERKNS1_3PIDIT0_EEMSO_FSM_T1_EOT2_EUlSt10unique_ptrINS1_7PromiseISB_EESt14default_deleteISZ_EEOSH_PNS1_11ProcessBaseEE_IS12_SH_S15_EEEDTclcl7forwardISL_Efp_Espcl7forwardIT0_Efp0_EEEOSL_DpOS17_
@ 0x2b7b922fb4ed
_ZN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal26StatusUpdateManagerProcessIN2id4UUIDENS5_27UpdateOperationStatusRecordENS5_28UpdateOperationStatusMessageEE5StateENS5_5slave5SlaveERK6OptionINSD_5state10SlaveStateEESK_EENS2_6FutureIT_EERKNS2_3PIDIT0_EEMSP_FSN_T1_EOT2_EUlSt10unique_ptrINS2_7PromiseISC_EESt14default_deleteIS10_EEOSI_PNS2_11ProcessBaseEE_JS13_SI_St12_PlaceholderILi1EEEE13invoke_expandIS17_St5tupleIJS13_SI_S19_EES1C_IJOS16_EEJLm0ELm1ELm2EEEEDTcl6invokecl7forwardIT_Efp_Espcl6expandcl3getIXT2_EEcl7forwardIT0_Efp0_EEcl7forwardIT1_Efp2_EEEEOS1G_OS1H_N5cpp1416integer_sequenceImJXspT2_EEEEOS1I_
@ 0x2b7b922f6317
_ZNO6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal26StatusUpdateManagerProcessIN2id4UUIDENS5_27UpdateOperationStatusRecordENS5_28UpdateOperationStatusMessageEE5StateENS5_5slave5SlaveERK6OptionINSD_5state10SlaveStateEESK_EENS2_6FutureIT_EERKNS2_3PIDIT0_EEMSP_FSN_T1_EOT2_EUlSt10unique_ptrINS2_7PromiseISC_EESt14default_deleteIS10_EEOSI_PNS2_11ProcessBaseEE_IS13_SI_St12_PlaceholderILi1EEEEclIIS16_EEEDTcl13invoke_expandcl4movedtdefpT1fEcl4movedtdefpT10bound_argsEcvN5cpp1416integer_sequenceImILm0ELm1ELm2EEEE_Ecl16forward_as_tuplespcl7forwardIT_Efp_EEEEDpOS1F_
@ 0x2b7b922f3277
_ZN5cpp176invokeIN6lambda8internal7PartialIZN7process8dispatchIN5mesos8internal26StatusUpdateManagerProcessIN2id4UUIDENS7_27UpdateOperationStatusRecordENS7_28UpdateOperationStatusMessageEE5StateENS7_5slave5SlaveERK6OptionINSF_5state10SlaveStateEESM_EENS4_6FutureIT_EERKNS4_3PIDIT0_EEMSR_FSP_T1_EOT2_EUlSt10unique_ptrINS4_7PromiseISE_EESt14default_deleteIS12_EEOSK_PNS4_11ProcessBaseEE_JS15_SK_St12_PlaceholderILi1EEEEEJS18_EEEDTclcl7forwardISO_Efp_Espcl7forwardIT0_Efp0_EEEOSO_DpOS1D_
@ 0x2b7b922f0c5f
_ZN6lambda8internal6InvokeIvEclINS0_7PartialIZN7process8dispatchIN5mesos8internal26StatusUpdateManagerProcessIN2id4UUIDENS8_27UpdateOperationStatusRecordENS8_28UpdateOperationStatusMessageEE5StateENS8_5slave5SlaveERK6OptionINSG_5state10SlaveStateEESN_EENS5_6FutureIT_EERKNS5_3PIDIT0_EEMSS_FSQ_T1_EOT2_EUlSt10unique_ptrINS5_7PromiseISF_EESt14default_deleteIS13_EEOSL_PNS5_11ProcessBaseEE_JS16_SL_St12_PlaceholderILi1EEEEEJS19_EEEvOSP_DpOT0_
@ 0x2b7b922ee2bd
_ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEE10CallableFnINS_8internal7PartialIZNS1_8dispatchIN5mesos8internal26StatusUpdateManagerProcessIN2id4UUIDENSB_27UpdateOperationStatusRecordENSB_28UpdateOperationStatusMessageEE5StateENSB_5slave5SlaveERK6OptionINSJ_5state10SlaveStateEESQ_EENS1_6FutureIT_EERKNS1_3PIDIT0_EEMSV_FST_T1_EOT2_EUlSt10unique_ptrINS1_7PromiseISI_EESt14default_deleteIS16_EEOSO_S3_E_IS19_SO_St12_PlaceholderILi1EEEEEEclEOS3_
@ 0x2b7b934ef5ed
_ZNO6lambda12CallableOnceIFvPN7process11ProcessBaseEEEclES3_
@ 0x2b7b934b901d process::ProcessBase::consume()
@ 0x2b7b934dd340
_ZNO7process13DispatchEvent7consumeEPNS_13EventConsumerE
@ 0x556d8d647628 process::ProcessBase::serve()
@ 0x2b7b934b62fa process::ProcessManager::resume()
@ 0x2b7b934b2774
_ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x2b7b934d818c
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b7b934d74ad
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x2b7b934d666c
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x2b7b963b0a60 (unknown)
@ 0x2b7b96b27184 start_thread
@ 0x2b7b96e3b03d (unknown)
[FAIL]: 2 shard(s) have failed tests
make[4]: *** [check-local] Error 2
make[4]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.8.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.8.0/_build'
make: *** [distcheck] Error 1
+ docker rmi --force mesos-1548714096-18320
Untagged: mesos-1548714096-18320:latest
Deleted: sha256:0652d31b0b8dcecea7f90774eaf57e579d7dc91d7f2936c457b3ca5ab7459406
Deleted: sha256:f7a16681264c15b38f48c7c8144154996b5c55a84117e9ea6937e9a6e890c918
Deleted: sha256:12eadc1fce446c381afaaf0573a7671a44c5a74c5237ebc555fd5d85ef151c2f
Deleted: sha256:7cfa853b0b18562454a769c95a4187c3363cd469a72da7dbf601ce59d3e4ddca
Deleted: sha256:4b5d70d3a72828dbff8ac35b1aba57e5111dddf5a0e69e805307e84e986cca49
Deleted: sha256:49c4bb20c253762022119fe757111af4f2caf60432a8965e13aab5cba60adece
Deleted: sha256:00fede5be6b481913efaff544879f63559901fe205d015fa2d5d9417afc62082
Deleted: sha256:c7e9f6263ea610d1dede35fa39d2214ab1b05d30e0c06e135e0b22f36d28c73f
Deleted: sha256:8e49cd80470d173d5cb954ed19073108010cd378abb1a2d0684e8d1151c2bc04
Deleted: sha256:ea221baf86c6af57872dde8f424b46cf2e14f6c6c75f8e05da54231c6c4972c5
Deleted: sha256:fc574b76d5bdc0f7f869eb508c18ff7acdc39217928c349891417a81fce84c54
Deleted: sha256:ed463cea7dba1431201f4629b4d900cfb910c8d828e1076b77cf823993ce83ba
Deleted: sha256:fd00b9470873bbc8d7076059e58e0adc19760b3f87bb6d124d0ff9a055706ae6
Deleted: sha256:b86e9f568b9c45f45433a9e85374264912e2241c7f444f644bcd537fdb82d9f0
Deleted: sha256:f5f18ae6df9ee06044b40426ef89e5d50d29dfe16f8c537832859808df0188ca
Deleted: sha256:fa3ef7413aade2b7d5ae04a3443fa17c57dc7541785f99f86046cd88ca14f113
Deleted: sha256:45e81c1b4257f3ba9838160313d1cbc40ced521e88f5d6075f42f06e3eba2a9c
Deleted: sha256:2240b96ac957e9e65402e8422b2b041851e2dd21d1113c5c897b6a4179338520
Deleted: sha256:1248f6f619f81278a3035770f0f09f7a360fc533b072d99898eaabcb93060bc0
Deleted: sha256:dcdab35cdfd0873aabff8fb7da482816bb71d3f3b0f60ac19ec8f7db24cdaf50
Deleted: sha256:adfabf2dfd8ef5c03248296c9aa3654edc2b1c5a3ab4ef78fb5813172a5a7558
Deleted: sha256:4cbc06614c03a907addac52b5ba9715af95fed1d7953564d6e2b46a3e2903e8d
Deleted: sha256:83667e15c474f19ef95b20e0829545aba9985557fabc383c6551fad67d4dfbbd
Deleted: sha256:3502fcc7b5393860aa7068a53759a468358531591d5c774150a61405eb06677a
Deleted: sha256:776a15b51b3c847e3722d9a3b0d725afecb37c5626a9a4caa7d6b76b6eef40ca
Deleted: sha256:4bac191227a9ca2c62032d365557f57f043a1c4cfffaf4ce40608243050d6142
Deleted: sha256:a2a396d830b7b77e66339fb1a07cb008f5a818bbb12972e25dc0da7bdc1b55d1
Deleted: sha256:2760ee8612568d1fd9c0dd6e4993f4f29a696f6ea49d5ab8a42b47039f2c615d
Deleted: sha256:30c29f008ea84f6fc8ec73644929f4fb175397b610d39f0d7f4deac76417e283
Deleted: sha256:899fd081f92701e5a87e4ff0d3567a8c28e44b460ae44d81b90bc71352f55684
Full log: https://builds.apache.org/job/Mesos-Reviewbot/23673/console
9b06b4567667ce6948d33ecf82b82ed09f00e48b
Error running command 'git checkout HEAD --
9b06b4567667ce6948d33ecf82b82ed09f00e48b
': b"error: pathspec '9b06b4567667ce6948d33ecf82b82ed09f00e48b' did not match
any file(s) known to git.\n"
9b06b4567667ce6948d33ecf82b82ed09f00e48b
Error running command 'git checkout HEAD --
9b06b4567667ce6948d33ecf82b82ed09f00e48b
': b"error: pathspec '9b06b4567667ce6948d33ecf82b82ed09f00e48b' did not match
any file(s) known to git.\n"
Error in atexit._run_exitfuncs:
Traceback (most recent call last):
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 98, in shell
command, stderr=subprocess.STDOUT, shell=True)
File "/usr/lib/python3.5/subprocess.py", line 626, in check_output
**kwargs).stdout
File "/usr/lib/python3.5/subprocess.py", line 708, in run
output=stdout, stderr=stderr)
subprocess.CalledProcessError: Command 'git checkout HEAD --
9b06b4567667ce6948d33ecf82b82ed09f00e48b
' returned non-zero exit status 1
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 178, in cleanup
shell("git checkout HEAD -- %s" % HEAD)
File
"<https://builds.apache.org/job/Mesos-Reviewbot/ws/support/verify-reviews.py";,>
line 101, in shell
exit(1)
File "/usr/lib/python3.5/_sitebuiltins.py", line 26, in __call__
raise SystemExit(code)
SystemExit: 1
Build step 'Execute shell' marked build as failure
