See
<https://builds.apache.org/job/Mesos/BUILDTOOL=autotools,COMPILER=clang,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-6)/2847/changes>
Changes:
[joris.van.remoortere] Introduce MESOS_{MAJOR|MINOR|PATCH}_VERSION_NUM macros.
[joris.van.remoortere] Fixed MesosNativeLibrary to use '_NUM' MESOS_VERSION
macros.
[joris.van.remoortere] Added MESOS-4638 and MESOS-6502 to 1.0.2 CHANGELOG.
------------------------------------------
[...truncated 118262 lines...]
I1028 23:44:28.205402 27919 slave.cpp:198] Agent started on )@172.17.0.2:49287
I1028 23:44:28.205441 27919 slave.cpp:199] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/mesos/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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_lKKj8s/credential"
--default_role="*" --disk_watch_interval="1mins" --docker="docker"
--docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io";
--docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock"
--docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_lKKj8s/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_authenticators="basic"
--http_command_executor="false"
--http_credentials="/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_lKKj8s/http_credentials"
--image_provisioner_backend="copy" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem"
--launcher_dir="/mesos/mesos-1.0.2/_build/src" --logbufsecs="0"
--logging_level="INFO" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins"
--qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect"
--recovery_timeout="15mins" --registration_backoff_factor="10ms"
--resources="cpus:12;mem:2048;disk:1024" --revocable_cpu_low_priority="true"
--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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_lKKj8s"
I1028 23:44:28.205984 27919 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_lKKj8s/credential'
I1028 23:44:28.206146 27919 slave.cpp:336] Agent using credential for:
test-principal
I1028 23:44:28.206166 27919 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_lKKj8s/http_credentials'
I1028 23:44:28.206374 27919 http.cpp:883] Using default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I1028 23:44:28.206529 27919 http.cpp:883] Using default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I1028 23:44:28.206720 27919 resources.cpp:572] Parsing resources as JSON
failed: cpus:12;mem:2048;disk:1024
Trying semicolon-delimited string format instead
I1028 23:44:28.206935 27919 resources.cpp:572] Parsing resources as JSON
failed: cpus:12;mem:2048;disk:1024
Trying semicolon-delimited string format instead
I1028 23:44:28.206982 27926 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.207013 27926 hierarchical.cpp:1630] No inverse offers to send
out!
I1028 23:44:28.207057 27926 hierarchical.cpp:1170] Performed allocation for 1
agents in 148251ns
I1028 23:44:28.207263 27919 slave.cpp:519] Agent resources: cpus(*):12;
mem(*):2048; disk(*):1024; ports(*):[31000-32000]
I1028 23:44:28.207317 27919 slave.cpp:527] Agent attributes: [ ]
I1028 23:44:28.207329 27919 slave.cpp:532] Agent hostname: 90e821240480
I1028 23:44:28.208879 27917 state.cpp:57] Recovering state from
'/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_lKKj8s/meta'
I1028 23:44:28.209349 27917 state.cpp:88] Agent host rebooted
I1028 23:44:28.210067 27922 status_update_manager.cpp:200] Recovering status
update manager
I1028 23:44:28.211009 27916 slave.cpp:767] Agent terminating
I1028 23:44:28.211233 27898 sched.cpp:1987] Asked to stop the driver
I1028 23:44:28.211491 27921 sched.cpp:1187] Stopping framework
'cb40e7e7-129d-43f2-af9f-d2854a3ab34f-0000'
I1028 23:44:28.211956 27921 master.cpp:6313] Processing TEARDOWN call for
framework cb40e7e7-129d-43f2-af9f-d2854a3ab34f-0000 (default) at
[email protected]:49287
I1028 23:44:28.211998 27921 master.cpp:6325] Removing framework
cb40e7e7-129d-43f2-af9f-d2854a3ab34f-0000 (default) at
[email protected]:49287
I1028 23:44:28.212704 27921 hierarchical.cpp:380] Deactivated framework
cb40e7e7-129d-43f2-af9f-d2854a3ab34f-0000
I1028 23:44:28.213006 27921 hierarchical.cpp:331] Removed framework
cb40e7e7-129d-43f2-af9f-d2854a3ab34f-0000
I1028 23:44:28.213306 27918 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.213383 27918 hierarchical.cpp:1170] Performed allocation for 1
agents in 173921ns
I1028 23:44:28.216845 27916 master.cpp:1092] Master terminating
I1028 23:44:28.217437 27925 hierarchical.cpp:508] Removed agent
cb40e7e7-129d-43f2-af9f-d2854a3ab34f-S0
I1028 23:44:28.218617 27926 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.218683 27926 hierarchical.cpp:1170] Performed allocation for 0
agents in 119843ns
I1028 23:44:28.227603 27898 cluster.cpp:155] Creating default 'local' authorizer
I1028 23:44:28.230410 27898 leveldb.cpp:174] Opened db in 2.541709ms
I1028 23:44:28.231302 27898 leveldb.cpp:181] Compacted db in 853742ns
I1028 23:44:28.231359 27898 leveldb.cpp:196] Created db iterator in 38036ns
I1028 23:44:28.231387 27898 leveldb.cpp:202] Seeked to beginning of db in
12836ns
I1028 23:44:28.231405 27898 leveldb.cpp:271] Iterated through 0 keys in the db
in 13267ns
I1028 23:44:28.231458 27898 replica.cpp:779] Replica recovered with log
positions 0 -> 0 with 1 holes and 0 unlearned
I1028 23:44:28.231988 27931 recover.cpp:451] Starting replica recovery
I1028 23:44:28.232228 27931 recover.cpp:477] Replica is in EMPTY status
I1028 23:44:28.233785 27927 replica.cpp:673] Replica in EMPTY status received a
broadcasted recover request from (11926)@172.17.0.2:49287
I1028 23:44:28.234179 27923 recover.cpp:197] Received a recover response from a
replica in EMPTY status
I1028 23:44:28.234748 27923 recover.cpp:568] Updating replica status to STARTING
I1028 23:44:28.235450 27922 leveldb.cpp:304] Persisting metadata (8 bytes) to
leveldb took 507652ns
I1028 23:44:28.235481 27922 replica.cpp:320] Persisted replica status to
STARTING
I1028 23:44:28.235576 27919 master.cpp:375] Master
611ea0cc-bcaf-4d5e-b99b-42ab22520a70 (90e821240480) started on 172.17.0.2:49287
I1028 23:44:28.235783 27927 recover.cpp:477] Replica is in STARTING status
I1028 23:44:28.235615 27919 master.cpp:377] Flags at startup: --acls=""
--agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
--allocation_interval="5ms" --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/RpNPm9/credentials"
--framework_sorter="drf" --help="false" --hostname_lookup="true"
--http_authenticators="basic" --http_framework_authenticators="basic"
--initialize_driver_logging="true" --log_auto_initialize="true"
--logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5"
--max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
--quiet="false" --recovery_agent_removal_limit="100%"
--registry="replicated_log" --registry_fetch_timeout="1mins"
--registry_store_timeout="100secs" --registry_strict="true" --roles="role"
--root_submissions="true" --user_sorter="drf" --version="false"
--webui_dir="/mesos/mesos-1.0.2/_inst/share/mesos/webui"
--work_dir="/tmp/RpNPm9/master" --zk_session_timeout="10secs"
I1028 23:44:28.235954 27919 master.cpp:427] Master only allowing authenticated
frameworks to register
I1028 23:44:28.235967 27919 master.cpp:441] Master only allowing authenticated
agents to register
I1028 23:44:28.235975 27919 master.cpp:454] Master only allowing authenticated
HTTP frameworks to register
I1028 23:44:28.235980 27919 credentials.hpp:37] Loading credentials for
authentication from '/tmp/RpNPm9/credentials'
I1028 23:44:28.236249 27919 master.cpp:499] Using default 'crammd5'
authenticator
I1028 23:44:28.236414 27919 http.cpp:883] Using default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I1028 23:44:28.236474 27929 replica.cpp:673] Replica in STARTING status
received a broadcasted recover request from (11927)@172.17.0.2:49287
I1028 23:44:28.236551 27919 http.cpp:883] Using default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I1028 23:44:28.236763 27927 recover.cpp:197] Received a recover response from a
replica in STARTING status
I1028 23:44:28.236927 27919 http.cpp:883] Using default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I1028 23:44:28.237206 27919 master.cpp:579] Authorization enabled
W1028 23:44:28.237226 27919 master.cpp:642] The '--roles' flag is deprecated.
This flag will be removed in the future. See the Mesos 0.27 upgrade notes for
more information
I1028 23:44:28.237377 27924 recover.cpp:568] Updating replica status to VOTING
I1028 23:44:28.237471 27918 whitelist_watcher.cpp:77] No whitelist given
I1028 23:44:28.237509 27922 hierarchical.cpp:149] Initialized hierarchical
allocator process
I1028 23:44:28.237658 27918 leveldb.cpp:304] Persisting metadata (8 bytes) to
leveldb took 127975ns
I1028 23:44:28.237679 27918 replica.cpp:320] Persisted replica status to VOTING
I1028 23:44:28.237766 27929 recover.cpp:582] Successfully joined the Paxos group
I1028 23:44:28.237951 27929 recover.cpp:466] Recover process terminated
I1028 23:44:28.239702 27930 master.cpp:1847] The newly elected leader is
[email protected]:49287 with id 611ea0cc-bcaf-4d5e-b99b-42ab22520a70
I1028 23:44:28.239732 27930 master.cpp:1860] Elected as the leading master!
I1028 23:44:28.239743 27930 master.cpp:1547] Recovering from registrar
I1028 23:44:28.239833 27918 registrar.cpp:332] Recovering registrar
I1028 23:44:28.240320 27921 log.cpp:553] Attempting to start the writer
I1028 23:44:28.241410 27922 replica.cpp:493] Replica received implicit promise
request from (11931)@172.17.0.2:49287 with proposal 1
I1028 23:44:28.241842 27922 leveldb.cpp:304] Persisting metadata (8 bytes) to
leveldb took 416605ns
I1028 23:44:28.241863 27922 replica.cpp:342] Persisted promised to 1
I1028 23:44:28.242458 27929 coordinator.cpp:238] Coordinator attempting to fill
missing positions
I1028 23:44:28.242815 27928 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.242888 27928 hierarchical.cpp:1170] Performed allocation for 0
agents in 134721ns
I1028 23:44:28.243683 27920 replica.cpp:388] Replica received explicit promise
request from (11932)@172.17.0.2:49287 for position 0 with proposal 2
I1028 23:44:28.244047 27920 leveldb.cpp:341] Persisting action (8 bytes) to
leveldb took 335423ns
I1028 23:44:28.244076 27920 replica.cpp:712] Persisted action at 0
I1028 23:44:28.245302 27919 replica.cpp:537] Replica received write request for
position 0 from (11933)@172.17.0.2:49287
I1028 23:44:28.245368 27919 leveldb.cpp:436] Reading position from leveldb took
44539ns
I1028 23:44:28.245563 27919 leveldb.cpp:341] Persisting action (14 bytes) to
leveldb took 165729ns
I1028 23:44:28.245591 27919 replica.cpp:712] Persisted action at 0
I1028 23:44:28.246196 27922 replica.cpp:691] Replica received learned notice
for position 0 from @0.0.0.0:0
I1028 23:44:28.246372 27922 leveldb.cpp:341] Persisting action (16 bytes) to
leveldb took 160602ns
I1028 23:44:28.246407 27922 replica.cpp:712] Persisted action at 0
I1028 23:44:28.246428 27922 replica.cpp:697] Replica learned NOP action at
position 0
I1028 23:44:28.247109 27931 log.cpp:569] Writer started with ending position 0
I1028 23:44:28.248203 27918 leveldb.cpp:436] Reading position from leveldb took
56183ns
I1028 23:44:28.248217 27920 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.248273 27920 hierarchical.cpp:1170] Performed allocation for 0
agents in 101928ns
I1028 23:44:28.249243 27921 registrar.cpp:365] Successfully fetched the
registry (0B) in 9.37088ms
I1028 23:44:28.249373 27921 registrar.cpp:464] Applied 1 operations in 36049ns;
attempting to update the 'registry'
I1028 23:44:28.250145 27917 log.cpp:577] Attempting to append 168 bytes to the
log
I1028 23:44:28.250258 27922 coordinator.cpp:348] Coordinator attempting to
write APPEND action at position 1
I1028 23:44:28.250993 27920 replica.cpp:537] Replica received write request for
position 1 from (11934)@172.17.0.2:49287
I1028 23:44:28.251461 27920 leveldb.cpp:341] Persisting action (187 bytes) to
leveldb took 440367ns
I1028 23:44:28.251489 27920 replica.cpp:712] Persisted action at 1
I1028 23:44:28.252213 27928 replica.cpp:691] Replica received learned notice
for position 1 from @0.0.0.0:0
I1028 23:44:28.252394 27928 leveldb.cpp:341] Persisting action (189 bytes) to
leveldb took 166064ns
I1028 23:44:28.252424 27928 replica.cpp:712] Persisted action at 1
I1028 23:44:28.252444 27928 replica.cpp:697] Replica learned APPEND action at
position 1
I1028 23:44:28.253500 27926 registrar.cpp:509] Successfully updated the
'registry' in 4.004864ms
I1028 23:44:28.253566 27919 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.253623 27919 hierarchical.cpp:1170] Performed allocation for 0
agents in 103171ns
I1028 23:44:28.253654 27926 registrar.cpp:395] Successfully recovered registrar
I1028 23:44:28.253700 27921 log.cpp:596] Attempting to truncate the log to 1
I1028 23:44:28.253828 27927 coordinator.cpp:348] Coordinator attempting to
write TRUNCATE action at position 2
I1028 23:44:28.254194 27921 master.cpp:1655] Recovered 0 agents from the
Registry (129B) ; allowing 10mins for agents to re-register
I1028 23:44:28.254225 27919 hierarchical.cpp:176] Skipping recovery of
hierarchical allocator: nothing to recover
I1028 23:44:28.254688 27926 replica.cpp:537] Replica received write request for
position 2 from (11935)@172.17.0.2:49287
I1028 23:44:28.255100 27926 leveldb.cpp:341] Persisting action (16 bytes) to
leveldb took 385571ns
I1028 23:44:28.255127 27926 replica.cpp:712] Persisted action at 2
I1028 23:44:28.255846 27928 replica.cpp:691] Replica received learned notice
for position 2 from @0.0.0.0:0
I1028 23:44:28.256034 27928 leveldb.cpp:341] Persisting action (18 bytes) to
leveldb took 169637ns
I1028 23:44:28.256096 27928 leveldb.cpp:399] Deleting ~1 keys from leveldb took
45798ns
I1028 23:44:28.256119 27928 replica.cpp:712] Persisted action at 2
I1028 23:44:28.256140 27928 replica.cpp:697] Replica learned TRUNCATE action at
position 2
I1028 23:44:28.264729 27921 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.264807 27921 hierarchical.cpp:1170] Performed allocation for 0
agents in 134903ns
I1028 23:44:28.270128 27917 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.270211 27917 hierarchical.cpp:1170] Performed allocation for 0
agents in 141303ns
I1028 23:44:28.270922 27916 slave.cpp:198] Agent started on )@172.17.0.2:49287
I1028 23:44:28.271533 27898 resources.cpp:572] Parsing resources as JSON
failed: cpus:8;mem:2048
Trying semicolon-delimited string format instead
I1028 23:44:28.270963 27916 slave.cpp:199] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/mesos/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/ReservationTest_IncompatibleCheckpointedResources_bqvkZh/credential"
--default_role="*" --disk_watch_interval="1mins" --docker="docker"
--docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io";
--docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock"
--docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ReservationTest_IncompatibleCheckpointedResources_bqvkZh/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_authenticators="basic"
--http_command_executor="false"
--http_credentials="/tmp/ReservationTest_IncompatibleCheckpointedResources_bqvkZh/http_credentials"
--image_provisioner_backend="copy" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem"
--launcher_dir="/mesos/mesos-1.0.2/_build/src" --logbufsecs="0"
--logging_level="INFO" --oversubscribed_resources_interval="15secs"
--perf_duration="10secs" --perf_interval="1mins"
--qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect"
--recovery_timeout="15mins" --registration_backoff_factor="10ms"
--resources="cpus:8;mem:4096" --revocable_cpu_low_priority="true"
--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/ReservationTest_IncompatibleCheckpointedResources_bqvkZh"
I1028 23:44:28.271801 27916 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ReservationTest_IncompatibleCheckpointedResources_bqvkZh/credential'
I1028 23:44:28.272017 27916 slave.cpp:336] Agent using credential for:
test-principal
I1028 23:44:28.272048 27916 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ReservationTest_IncompatibleCheckpointedResources_bqvkZh/http_credentials'
I1028 23:44:28.272409 27916 http.cpp:883] Using default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I1028 23:44:28.272578 27916 http.cpp:883] Using default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I1028 23:44:28.272779 27898 sched.cpp:226] Version: 1.0.2
I1028 23:44:28.272810 27916 resources.cpp:572] Parsing resources as JSON
failed: cpus:8;mem:4096
Trying semicolon-delimited string format instead
I1028 23:44:28.273037 27916 resources.cpp:572] Parsing resources as JSON
failed: cpus:8;mem:4096
Trying semicolon-delimited string format instead
I1028 23:44:28.273670 27916 slave.cpp:519] Agent resources: cpus(*):8;
mem(*):4096; disk(*):3.70122e+06; ports(*):[31000-32000]
I1028 23:44:28.273772 27916 slave.cpp:527] Agent attributes: [ ]
I1028 23:44:28.273787 27916 slave.cpp:532] Agent hostname: 90e821240480
I1028 23:44:28.273974 27920 sched.cpp:330] New master detected at
[email protected]:49287
I1028 23:44:28.274072 27920 sched.cpp:396] Authenticating with master
[email protected]:49287
I1028 23:44:28.274094 27920 sched.cpp:403] Using default CRAM-MD5 authenticatee
I1028 23:44:28.274338 27927 authenticatee.cpp:121] Creating new client SASL
connection
I1028 23:44:28.274804 27930 master.cpp:5909] Authenticating
[email protected]:49287
I1028 23:44:28.274936 27919 authenticator.cpp:414] Starting authentication
session for crammd5_authenticatee(647)@172.17.0.2:49287
I1028 23:44:28.275291 27919 authenticator.cpp:98] Creating new server SASL
connection
I1028 23:44:28.275544 27919 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I1028 23:44:28.275579 27919 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I1028 23:44:28.275725 27916 state.cpp:57] Recovering state from
'/tmp/ReservationTest_IncompatibleCheckpointedResources_bqvkZh/meta'
I1028 23:44:28.275799 27919 authenticator.cpp:204] Received SASL authentication
start
I1028 23:44:28.275868 27919 authenticator.cpp:326] Authentication requires more
steps
I1028 23:44:28.275988 27923 hierarchical.cpp:1535] No allocations performed
I1028 23:44:28.276002 27925 authenticatee.cpp:259] Received SASL authentication
step
I1028 23:44:28.276013 27919 status_update_manager.cpp:200] Recovering status
update manager
I1028 23:44:28.276067 27923 hierarchical.cpp:1170] Performed allocation for 0
agents in 142730ns
I1028 23:44:28.276172 27931 authenticator.cpp:232] Received SASL authentication
step
I1028 23:44:28.276216 27931 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '90e821240480' server FQDN: '90e821240480'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I1028 23:44:28.276239 27931 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I1028 23:44:28.276291 27931 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I1028 23:44:28.276330 27931 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '90e821240480' server FQDN: '90e821240480'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I1028 23:44:28.276353 27931 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I1028 23:44:28.276370 27931 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1028 23:44:28.276376 27926 slave.cpp:4810] Finished recovery
I1028 23:44:28.276397 27931 authenticator.cpp:318] Authentication success
I1028 23:44:28.276511 27925 authenticatee.cpp:299] Authentication success
I1028 23:44:28.276562 27928 master.cpp:5939] Successfully authenticated
principal 'test-principal' at
[email protected]:49287
I1028 23:44:28.276644 27931 authenticator.cpp:432] Authentication session
cleanup for crammd5_authenticatee(647)@172.17.0.2:49287
I1028 23:44:28.276927 27925 sched.cpp:502] Successfully authenticated with
master [email protected]:49287
I1028 23:44:28.276958 27925 sched.cpp:820] Sending SUBSCRIBE call to
[email protected]:49287
I1028 23:44:28.276989 27926 slave.cpp:4982] Querying resource estimator for
oversubscribable resources
I1028 23:44:28.277086 27925 sched.cpp:853] Will retry registration in
712.714571ms if necessary
I1028 23:44:28.277264 27920 status_update_manager.cpp:174] Pausing sending
status updates
I1028 23:44:28.277258 27926 slave.cpp:895] New master detected at
[email protected]:49287
I1028 23:44:28.277259 27918 master.cpp:2429] Received SUBSCRIBE call for
framework 'default' at
[email protected]:49287
I1028 23:44:28.277315 27926 slave.cpp:954] Authenticating with master
[email protected]:49287
I1028 23:44:28.277328 27918 master.cpp:1886] Authorizing framework principal
'test-principal' to receive offers for role 'role'
I1028 23:44:28.277395 27926 slave.cpp:965] Using default CRAM-MD5 authenticatee
I1028 23:44:28.282232 27919 hierarchical.cpp:1535] No allocations performed
I1028 23:44:54.592404 27919 hierarchical.cpp:1170] Performed allocation for 0
agents in 26.310256351secs
I1028 23:44:54.592967 27926 slave.cpp:927] Detecting new master
I1028 23:44:54.593330 27926 authenticatee.cpp:121] Creating new client SASL
connection
I1028 23:44:28.990341 27928 sched.cpp:820] Sending SUBSCRIBE call to
[email protected]:49287
I1028 23:44:54.594349 27928 sched.cpp:853] Will retry registration in
1.585886289secs if necessary
*** Aborted at 1477698294 (unix time) try "date -d @1477698294" if you are
using GNU date ***
I1028 23:44:54.597339 27918 master.cpp:5909] Authenticating
(11936)@172.17.0.2:49287
I1028 23:44:54.597676 27918 master.cpp:2505] Subscribing framework default with
checkpointing disabled and capabilities [ ]
I1028 23:44:54.598353 27918 master.cpp:2429] Received SUBSCRIBE call for
framework 'default' at
[email protected]:49287
I1028 23:44:54.598428 27918 master.cpp:1886] Authorizing framework principal
'test-principal' to receive offers for role 'role'
I1028 23:44:54.598682 27918 master.cpp:1284] Framework
611ea0cc-bcaf-4d5e-b99b-42ab22520a70-0000 (default) at
[email protected]:49287 disconnected
I1028 23:44:54.598759 27918 master.cpp:2735] Disconnecting framework
611ea0cc-bcaf-4d5e-b99b-42ab22520a70-0000 (default) at
[email protected]:49287
I1028 23:44:54.598817 27916 authenticator.cpp:414] Starting authentication
session for crammd5_authenticatee(648)@172.17.0.2:49287
I1028 23:44:54.598868 27918 master.cpp:2759] Deactivating framework
611ea0cc-bcaf-4d5e-b99b-42ab22520a70-0000 (default) at
[email protected]:49287
I1028 23:44:54.598984 27918 master.cpp:1297] Giving framework
611ea0cc-bcaf-4d5e-b99b-42ab22520a70-0000 (default) at
[email protected]:49287 0ns to failover
I1028 23:44:54.599184 27918 authenticator.cpp:98] Creating new server SASL
connection
I1028 23:44:54.599391 27916 master.cpp:2499] Dropping SUBSCRIBE call for
framework 'default' at
[email protected]:49287: Framework at
[email protected]:49287 is not
authenticated
I1028 23:44:54.599417 27930 hierarchical.cpp:269] Added framework
611ea0cc-bcaf-4d5e-b99b-42ab22520a70-0000
I1028 23:44:54.599498 27930 hierarchical.cpp:1535] No allocations performed
I1028 23:44:54.599539 27930 hierarchical.cpp:1630] No inverse offers to send
out!
I1028 23:44:54.599611 27930 hierarchical.cpp:1170] Performed allocation for 0
agents in 179200ns
I1028 23:44:54.599710 27930 hierarchical.cpp:380] Deactivated framework
611ea0cc-bcaf-4d5e-b99b-42ab22520a70-0000
W1028 23:44:54.599720 27918 master.cpp:5936] Failed to authenticate
(11936)@172.17.0.2:49287: Failed to communicate with authenticatee
I1028 23:44:54.599774 27930 authenticator.cpp:432] Authentication session
cleanup for crammd5_authenticatee(648)@172.17.0.2:49287
PC: @ 0x25 (unknown)
make[4]: *** [check-local] Segmentation fault
make[3]: *** [check-am] Error 2
make[2]: *** [check] Error 2
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
../../src/tests/reservation_tests.cpp:1263: Failure
Actual function call count doesn't match EXPECT_CALL(sched,
resourceOffers(&driver, _))...
Expected: to be called at least once
Actual: never called - unsatisfied and active
../../src/tests/reservation_tests.cpp:1261: Failure
Actual function call count doesn't match EXPECT_CALL(sched, registered(&driver,
_, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
make[4]: Leaving directory `/mesos/mesos-1.0.2/_build/src'
make[3]: Leaving directory `/mesos/mesos-1.0.2/_build/src'
make[2]: Leaving directory `/mesos/mesos-1.0.2/_build/src'
make[1]: Leaving directory `/mesos/mesos-1.0.2/_build'
+ docker rmi mesos-1477696575-29589
Untagged: mesos-1477696575-29589:latest
Deleted: sha256:5010f3e6fee47ace3c2200c6c5d948782fd189991eee1a22b069792ecca11959
Deleted: sha256:62acc3c69e372e12d457b1dd428ebde72cf42d45a51361426f81fdc462963f2f
Deleted: sha256:d3735b0fd8da80fbe417fdd95ee4396ae701d3c37b685a73a4a606b27f8fa86c
Deleted: sha256:01eaff80b0cb954186e54ab289a0de2e06e4aaa189058c6fa29d1a8005159212
Deleted: sha256:c86dbf2630a6d603d1f7674d90d0266c5f21029dc1a21bf043ad9186cd629728
Deleted: sha256:ed46f2109c5941e545482d3f4fa9405bb6ddabc40b660d4eef98ad5423426e3b
Deleted: sha256:13259c732a750e8ea623d07f56752561bfdf421530ecbdbc1b469494ea9770b6
Deleted: sha256:b51ed9d4016d1672d5f8b24420dc3e3052bc2d821c3eb68d2054d09d5ba1d28c
Deleted: sha256:8beb24aa0d23a3cb9e26058cf0ed038fc0b4c787abcf8b2d8b9344e6875e9e5e
Deleted: sha256:ba15e78fbba5287507358cfc10eb385d38e5f00a2317b95420323cb2fc278e82
Deleted: sha256:ae5d491d30e557426c736fb9e23e95b378a9acdbb2720fa5a5d4887c793b794e
Deleted: sha256:8132d7aef3afef2fe71d403b0caf55f5196583684163096ed710a728f0ed5df1
Deleted: sha256:66b4e5358d55d03065bea38aefff9f842ff44ceb98e85baae0023ebbf19914dd
Deleted: sha256:da57c0969fe856049a4747c808fe4ae45f435b86844cb09c9f9963b4cf318e59
Deleted: sha256:08545d68b6e423e5d3363057586eb99bc7d2c76cf50c69a5525b64fc84038c71
Deleted: sha256:9a4157690158dec7184bce8bb1893c520c9b80eee24b23c8bd7286e06e19c877
Deleted: sha256:dfb71ac01dba8f14cfbeec6b2bfc0ae9133065584e7a07e11750e3b06aae7975
Deleted: sha256:f4d658bf901278468980f61a6a8ccc6e1e54d74139b1074a51c3322d8a69407a
Deleted: sha256:cce0d8bae81a49c944571f49abb42b06ccffbb1d764d1556f051a7e60aec4c13
Deleted: sha256:e47883056d310c263fc1bf58d4c2e8f375eaa01396619762c74870674427687d
Deleted: sha256:f4d5c606039f9c7db35549d98f0efb7e1718b198e403c0a2a404944041532094
Deleted: sha256:f325fc090492a30a5c459214ec48338f844ccf23673e415208b841ac26f60f02
Build step 'Execute shell' marked build as failure
