See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4444/display/redirect?page=changes>
Changes:
[jpeach] Made Envp conversion operator consistent with Argv.
[jpeach] Deleted Argv copy constructor and assignment members.
------------------------------------------
[...truncated 28.62 MB...]
3: I1111 02:02:35.267992 19955 master.cpp:3332] Deactivating framework
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000 (default)
3: I1111 02:02:35.268102 19966 hierarchical.cpp:412] Deactivated framework
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000
3: W1111 02:02:35.268683 19955 master.hpp:2606] Unable to send event to
framework f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000 (default): connection closed
3: I1111 02:02:35.268709 19955 master.cpp:10016] Removing offer
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-O1
3: I1111 02:02:35.268754 19955 master.cpp:3309] Disconnecting framework
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000 (default)
3: I1111 02:02:35.268774 19955 master.cpp:1438] Giving framework
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000 (default) 0ns to failover
3: I1111 02:02:35.269068 19964 master.cpp:7948] Framework failover timeout,
removing framework f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000 (default)
3: I1111 02:02:35.269088 19964 master.cpp:8805] Removing framework
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000 (default)
3: I1111 02:02:35.269289 19957 slave.cpp:3267] Asked to shut down framework
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000 by master@172.17.0.6:38163
3: I1111 02:02:35.269315 19957 slave.cpp:3282] Cannot shut down unknown
framework f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000
3: I1111 02:02:35.269822 19955 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 f2bef079-399f-4ea3-9b3a-d57e0f27fd10-S0 from framework
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000
3: I1111 02:02:35.270115 19955 hierarchical.cpp:355] Removed framework
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-0000
3: I1111 02:02:35.271344 19944 slave.cpp:883] Agent terminating
3: I1111 02:02:35.271497 19964 master.cpp:1309] Agent
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-S0 at slave(762)@172.17.0.6:38163
(aca3232fb920) disconnected
3: I1111 02:02:35.271514 19964 master.cpp:3369] Disconnecting agent
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-S0 at slave(762)@172.17.0.6:38163
(aca3232fb920)
3: I1111 02:02:35.271562 19964 master.cpp:3388] Deactivating agent
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-S0 at slave(762)@172.17.0.6:38163
(aca3232fb920)
3: I1111 02:02:35.271689 19958 hierarchical.cpp:690] Agent
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-S0 deactivated
3: I1111 02:02:35.277164 19944 master.cpp:1151] Master terminating
3: I1111 02:02:35.277865 19946 hierarchical.cpp:626] Removed agent
f2bef079-399f-4ea3-9b3a-d57e0f27fd10-S0
3: [ OK ] ContentType/SchedulerTest.Decline/1 (78 ms)
3: [ RUN ] ContentType/SchedulerTest.Revive/0
3: I1111 02:02:35.285544 19944 cluster.cpp:162] Creating default 'local'
authorizer
3: I1111 02:02:35.288887 19956 master.cpp:448] Master
7f03f34e-77b5-4c75-aea5-1013d9206238 (aca3232fb920) started on 172.17.0.6:38163
3: I1111 02:02:35.288911 19956 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/jdYSyu/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/jdYSyu/master"
--zk_session_timeout="10secs"
3: I1111 02:02:35.289332 19956 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1111 02:02:35.289341 19956 master.cpp:505] Master only allowing
authenticated agents to register
3: I1111 02:02:35.289347 19956 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1111 02:02:35.289355 19956 credentials.hpp:37] Loading credentials for
authentication from '/tmp/jdYSyu/credentials'
3: I1111 02:02:35.289742 19956 master.cpp:555] Using default 'crammd5'
authenticator
3: I1111 02:02:35.289906 19956 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1111 02:02:35.290079 19956 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1111 02:02:35.290212 19956 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1111 02:02:35.290331 19956 master.cpp:634] Authorization enabled
3: I1111 02:02:35.290570 19966 hierarchical.cpp:171] Initialized hierarchical
allocator process
3: I1111 02:02:35.290582 19953 whitelist_watcher.cpp:77] No whitelist given
3: I1111 02:02:35.293733 19956 master.cpp:2213] Elected as the leading master!
3: I1111 02:02:35.293764 19956 master.cpp:1693] Recovering from registrar
3: I1111 02:02:35.293907 19961 registrar.cpp:347] Recovering registrar
3: I1111 02:02:35.294602 19961 registrar.cpp:391] Successfully fetched the
registry (0B) in 655104ns
3: I1111 02:02:35.294734 19961 registrar.cpp:495] Applied 1 operations in
37519ns; attempting to update the registry
3: I1111 02:02:35.295429 19961 registrar.cpp:552] Successfully updated the
registry in 588032ns
3: I1111 02:02:35.295601 19961 registrar.cpp:424] Successfully recovered
registrar
3: I1111 02:02:35.296051 19952 master.cpp:1806] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: I1111 02:02:35.296087 19968 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
3: W1111 02:02:35.302309 19944 process.cpp:3178] Attempted to spawn already
running process files@172.17.0.6:38163
3: I1111 02:02:35.303493 19944 containerizer.cpp:301] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1111 02:02:35.304206 19944 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
3: W1111 02:02:35.304323 19944 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
3: I1111 02:02:35.304353 19944 provisioner.cpp:255] Using default backend 'copy'
3: I1111 02:02:35.306560 19944 cluster.cpp:448] Creating default 'local'
authorizer
3: I1111 02:02:35.308869 19951 slave.cpp:262] Mesos agent started on
(763)@172.17.0.6:38163
3: I1111 02:02:35.308890 19951 slave.cpp:263] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/ContentType_SchedulerTest_Revive_0_bNchpo/store/appc"
--authenticate_http_executors="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local"
--cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false"
--cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs"
--containerizers="mesos"
--credential="/tmp/ContentType_SchedulerTest_Revive_0_bNchpo/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/ContentType_SchedulerTest_Revive_0_bNchpo/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_secret_key="/tmp/ContentType_SchedulerTest_Revive_0_bNchpo/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ContentType_SchedulerTest_Revive_0_bNchpo/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/ContentType_SchedulerTest_Revive_0_bNchpo/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/ContentType_SchedulerTest_Revive_0_bNchpo"
--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_SchedulerTest_Revive_0_ZL03tW"
--zk_session_timeout="10secs"
3: I1111 02:02:35.309471 19951 credentials.hpp:86] Loading credential for
authentication from '/tmp/ContentType_SchedulerTest_Revive_0_bNchpo/credential'
3: I1111 02:02:35.309715 19951 slave.cpp:295] Agent using credential for:
test-principal
3: I1111 02:02:35.309736 19951 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_SchedulerTest_Revive_0_bNchpo/http_credentials'
3: I1111 02:02:35.309808 19944 scheduler.cpp:190] Version: 1.5.0
3: I1111 02:02:35.310112 19951 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1111 02:02:35.310137 19948 scheduler.cpp:313] Using default 'basic' HTTP
authenticatee
3: I1111 02:02:35.310258 19951 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1111 02:02:35.310464 19951 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1111 02:02:35.310564 19951 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1111 02:02:35.310596 19947 scheduler.cpp:496] New master detected at
master@172.17.0.6:38163
3: I1111 02:02:35.310616 19947 scheduler.cpp:505] Waiting for 0ns before
initiating a re-(connection) attempt with the master
3: I1111 02:02:35.310765 19951 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1111 02:02:35.310870 19951 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1111 02:02:35.312387 19951 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 02:02:35.312597 19951 slave.cpp:601] Agent attributes: [ ]
3: I1111 02:02:35.312605 19951 slave.cpp:610] Agent hostname: aca3232fb920
3: I1111 02:02:35.312726 19945 status_update_manager.cpp:177] Pausing sending
status updates
3: I1111 02:02:35.312988 19962 scheduler.cpp:387] Connected with the master at
http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.314232 19961 scheduler.cpp:249] Sending SUBSCRIBE call to
http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.314507 19954 state.cpp:64] Recovering state from
'/tmp/ContentType_SchedulerTest_Revive_0_ZL03tW/meta'
3: I1111 02:02:35.314769 19954 status_update_manager.cpp:203] Recovering status
update manager
3: I1111 02:02:35.314929 19958 containerizer.cpp:662] Recovering containerizer
3: I1111 02:02:35.315671 19966 process.cpp:3925] Handling HTTP event for
process 'master' with path: '/master/api/v1/scheduler'
3: I1111 02:02:35.316129 19961 provisioner.cpp:416] Provisioner recovery
complete
3: I1111 02:02:35.316485 19954 slave.cpp:6422] Finished recovery
3: I1111 02:02:35.316876 19956 http.cpp:1185] HTTP POST for
/master/api/v1/scheduler from 172.17.0.6:33268
3: I1111 02:02:35.317170 19956 master.cpp:2613] Received subscription request
for HTTP framework 'default'
3: I1111 02:02:35.317299 19956 master.cpp:2278] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
3: I1111 02:02:35.317517 19946 status_update_manager.cpp:177] Pausing sending
status updates
3: I1111 02:02:35.317515 19962 slave.cpp:1007] New master detected at
master@172.17.0.6:38163
3: I1111 02:02:35.317605 19962 slave.cpp:1042] Detecting new master
3: I1111 02:02:35.317765 19965 master.cpp:2749] Subscribing framework 'default'
with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1111 02:02:35.318496 19949 hierarchical.cpp:303] Added framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000
3: I1111 02:02:35.318694 19949 hierarchical.cpp:1450] Performed allocation for
0 agents in 72192ns
3: I1111 02:02:35.319008 19967 scheduler.cpp:741] Enqueuing event SUBSCRIBED
received from http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.319344 19967 scheduler.cpp:741] Enqueuing event HEARTBEAT
received from http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.329782 19965 slave.cpp:1069] Authenticating with master
master@172.17.0.6:38163
3: I1111 02:02:35.329877 19965 slave.cpp:1078] Using default CRAM-MD5
authenticatee
3: I1111 02:02:35.330123 19961 authenticatee.cpp:121] Creating new client SASL
connection
3: I1111 02:02:35.330464 19953 master.cpp:8286] Authenticating
slave(763)@172.17.0.6:38163
3: I1111 02:02:35.330566 19949 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1403)@172.17.0.6:38163
3: I1111 02:02:35.330791 19957 authenticator.cpp:98] Creating new server SASL
connection
3: I1111 02:02:35.330994 19948 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1111 02:02:35.331017 19948 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1111 02:02:35.331118 19952 authenticator.cpp:204] Received SASL
authentication start
3: I1111 02:02:35.331181 19952 authenticator.cpp:326] Authentication requires
more steps
3: I1111 02:02:35.331285 19951 authenticatee.cpp:259] Received SASL
authentication step
3: I1111 02:02:35.331384 19945 authenticator.cpp:232] Received SASL
authentication step
3: I1111 02:02:35.331411 19945 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'aca3232fb920' server FQDN: 'aca3232fb920'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1111 02:02:35.331424 19945 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1111 02:02:35.331462 19945 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1111 02:02:35.331491 19945 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'aca3232fb920' server FQDN: 'aca3232fb920'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1111 02:02:35.331507 19945 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1111 02:02:35.331517 19945 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1111 02:02:35.331535 19945 authenticator.cpp:318] Authentication success
3: I1111 02:02:35.331620 19959 authenticatee.cpp:299] Authentication success
3: I1111 02:02:35.331687 19954 master.cpp:8316] Successfully authenticated
principal 'test-principal' at slave(763)@172.17.0.6:38163
3: I1111 02:02:35.331742 19945 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1403)@172.17.0.6:38163
3: I1111 02:02:35.331879 19959 slave.cpp:1161] Successfully authenticated with
master master@172.17.0.6:38163
3: I1111 02:02:35.332113 19959 slave.cpp:1682] Will retry registration in
13.929029ms if necessary
3: I1111 02:02:35.332304 19967 master.cpp:6033] Received register agent message
from slave(763)@172.17.0.6:38163 (aca3232fb920)
3: I1111 02:02:35.332422 19967 master.cpp:3871] Authorizing agent with
principal 'test-principal'
3: I1111 02:02:35.333024 19966 master.cpp:6093] Authorized registration of
agent at slave(763)@172.17.0.6:38163 (aca3232fb920)
3: I1111 02:02:35.333209 19966 master.cpp:6186] Registering agent at
slave(763)@172.17.0.6:38163 (aca3232fb920) with id
7f03f34e-77b5-4c75-aea5-1013d9206238-S0
3: I1111 02:02:35.333699 19946 registrar.cpp:495] Applied 1 operations in
72620ns; attempting to update the registry
3: I1111 02:02:35.334501 19946 registrar.cpp:552] Successfully updated the
registry in 721152ns
3: I1111 02:02:35.334702 19961 master.cpp:6233] Admitted agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 at slave(763)@172.17.0.6:38163
(aca3232fb920)
3: I1111 02:02:35.335541 19949 slave.cpp:1207] Registered with master
master@172.17.0.6:38163; given agent ID 7f03f34e-77b5-4c75-aea5-1013d9206238-S0
3: I1111 02:02:35.335387 19961 master.cpp:6264] Registered agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 at slave(763)@172.17.0.6:38163
(aca3232fb920) 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 02:02:35.335631 19952 status_update_manager.cpp:184] Resuming sending
status updates
3: I1111 02:02:35.335791 19953 hierarchical.cpp:593] Added agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 (aca3232fb920) with cpus:2; mem:1024;
disk:1024; ports:[31000-32000] (allocated: {})
3: I1111 02:02:35.335893 19949 slave.cpp:1227] Checkpointing SlaveInfo to
'/tmp/ContentType_SchedulerTest_Revive_0_ZL03tW/meta/slaves/7f03f34e-77b5-4c75-aea5-1013d9206238-S0/slave.info'
3: I1111 02:02:35.336264 19949 slave.cpp:1295] Forwarding total oversubscribed
resources {}
3: I1111 02:02:35.336437 19949 master.cpp:7065] Received update of agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 at slave(763)@172.17.0.6:38163
(aca3232fb920) with total oversubscribed resources {}
3: I1111 02:02:35.336587 19949 master.cpp:7083] Ignoring update on agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 at slave(763)@172.17.0.6:38163
(aca3232fb920) as it reports no changes
3: I1111 02:02:35.337077 19953 hierarchical.cpp:1450] Performed allocation for
1 agents in 1.103195ms
3: I1111 02:02:35.337524 19964 master.cpp:8116] Sending 1 offers to framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default)
3: I1111 02:02:35.339223 19950 scheduler.cpp:741] Enqueuing event OFFERS
received from http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.340410 19955 scheduler.cpp:249] Sending DECLINE call to
http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.344040 19961 hierarchical.cpp:1450] Performed allocation for
1 agents in 194888ns
3: I1111 02:02:35.346745 19958 slave.cpp:6039] Current disk usage 84.68%. Max
allowed age: 8.932130284239722hrs
3: I1111 02:02:35.350311 19957 master.cpp:1881] Skipping periodic registry
garbage collection: no agents qualify for removal
3: I1111 02:02:35.353152 19946 scheduler.cpp:249] Sending REVIVE call to
http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.354324 19964 process.cpp:3925] Handling HTTP event for
process 'master' with path: '/master/api/v1/scheduler'
3: I1111 02:02:35.354812 19961 scheduler.cpp:741] Enqueuing event HEARTBEAT
received from http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.355830 19959 process.cpp:3925] Handling HTTP event for
process 'master' with path: '/master/api/v1/scheduler'
3: I1111 02:02:35.356478 19959 http.cpp:1185] HTTP POST for
/master/api/v1/scheduler from 172.17.0.6:33266
3: I1111 02:02:35.356621 19959 master.cpp:5433] Processing DECLINE call for
offers: [ 7f03f34e-77b5-4c75-aea5-1013d9206238-O0 ] for framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default)
3: I1111 02:02:35.357157 19959 master.cpp:10016] Removing offer
7f03f34e-77b5-4c75-aea5-1013d9206238-O0
3: I1111 02:02:35.357733 19952 http.cpp:1185] HTTP POST for
/master/api/v1/scheduler from 172.17.0.6:33266
3: I1111 02:02:35.357766 19965 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 7f03f34e-77b5-4c75-aea5-1013d9206238-S0 from framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000
3: I1111 02:02:35.357831 19965 hierarchical.cpp:1171] Framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 filtered agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 for 1hrs
3: I1111 02:02:35.357884 19952 master.cpp:5538] Processing REVIVE call for
framework 7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default)
3: I1111 02:02:35.358049 19965 hierarchical.cpp:1272] Revived offers for roles
{ * } of framework 7f03f34e-77b5-4c75-aea5-1013d9206238-0000
3: I1111 02:02:35.359333 19965 hierarchical.cpp:1450] Performed allocation for
1 agents in 1.1214ms
3: I1111 02:02:35.359717 19954 master.cpp:8116] Sending 1 offers to framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default)
3: I1111 02:02:35.361260 19960 scheduler.cpp:741] Enqueuing event OFFERS
received from http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.365167 19968 master.cpp:1423] Framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default) disconnected
3: I1111 02:02:35.365195 19968 master.cpp:3332] Deactivating framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default)
3: I1111 02:02:35.365330 19954 hierarchical.cpp:412] Deactivated framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000
3: W1111 02:02:35.365867 19968 master.hpp:2606] Unable to send event to
framework 7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default): connection closed
3: I1111 02:02:35.365895 19968 master.cpp:10016] Removing offer
7f03f34e-77b5-4c75-aea5-1013d9206238-O1
3: I1111 02:02:35.365952 19968 master.cpp:3309] Disconnecting framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default)
3: I1111 02:02:35.365977 19968 master.cpp:1438] Giving framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default) 0ns to failover
3: I1111 02:02:35.366238 19963 master.cpp:7948] Framework failover timeout,
removing framework 7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default)
3: I1111 02:02:35.366263 19963 master.cpp:8805] Removing framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 (default)
3: I1111 02:02:35.366261 19956 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 7f03f34e-77b5-4c75-aea5-1013d9206238-S0 from framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000
3: I1111 02:02:35.366390 19956 slave.cpp:3267] Asked to shut down framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000 by master@172.17.0.6:38163
3: I1111 02:02:35.366412 19956 slave.cpp:3282] Cannot shut down unknown
framework 7f03f34e-77b5-4c75-aea5-1013d9206238-0000
3: I1111 02:02:35.366788 19950 hierarchical.cpp:355] Removed framework
7f03f34e-77b5-4c75-aea5-1013d9206238-0000
3: I1111 02:02:35.368212 19944 slave.cpp:883] Agent terminating
3: I1111 02:02:35.368403 19950 master.cpp:1309] Agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 at slave(763)@172.17.0.6:38163
(aca3232fb920) disconnected
3: I1111 02:02:35.368428 19950 master.cpp:3369] Disconnecting agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 at slave(763)@172.17.0.6:38163
(aca3232fb920)
3: I1111 02:02:35.368489 19950 master.cpp:3388] Deactivating agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 at slave(763)@172.17.0.6:38163
(aca3232fb920)
3: I1111 02:02:35.368577 19958 hierarchical.cpp:690] Agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0 deactivated
3: I1111 02:02:35.374042 19944 master.cpp:1151] Master terminating
3: I1111 02:02:35.374918 19968 hierarchical.cpp:626] Removed agent
7f03f34e-77b5-4c75-aea5-1013d9206238-S0
3: [ OK ] ContentType/SchedulerTest.Revive/0 (99 ms)
3: [ RUN ] ContentType/SchedulerTest.Revive/1
3: I1111 02:02:35.384635 19944 cluster.cpp:162] Creating default 'local'
authorizer
3: I1111 02:02:35.387892 19959 master.cpp:448] Master
1c4ce437-7428-4338-9d17-3c5b5c3897e5 (aca3232fb920) started on 172.17.0.6:38163
3: I1111 02:02:35.388000 19959 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/ZESivK/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/ZESivK/master"
--zk_session_timeout="10secs"
3: I1111 02:02:35.388356 19959 master.cpp:499] Master only allowing
authenticated frameworks to register
3: I1111 02:02:35.388365 19959 master.cpp:505] Master only allowing
authenticated agents to register
3: I1111 02:02:35.388370 19959 master.cpp:511] Master only allowing
authenticated HTTP frameworks to register
3: I1111 02:02:35.388375 19959 credentials.hpp:37] Loading credentials for
authentication from '/tmp/ZESivK/credentials'
3: I1111 02:02:35.388727 19959 master.cpp:555] Using default 'crammd5'
authenticator
3: I1111 02:02:35.388875 19959 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
3: I1111 02:02:35.389029 19959 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
3: I1111 02:02:35.389158 19959 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
3: I1111 02:02:35.389328 19959 master.cpp:634] Authorization enabled
3: I1111 02:02:35.389587 19956 whitelist_watcher.cpp:77] No whitelist given
3: I1111 02:02:35.389608 19960 hierarchical.cpp:171] Initialized hierarchical
allocator process
3: I1111 02:02:35.392681 19959 master.cpp:2213] Elected as the leading master!
3: I1111 02:02:35.392710 19959 master.cpp:1693] Recovering from registrar
3: I1111 02:02:35.392911 19947 registrar.cpp:347] Recovering registrar
3: I1111 02:02:35.393543 19947 registrar.cpp:391] Successfully fetched the
registry (0B) in 591872ns
3: I1111 02:02:35.393663 19947 registrar.cpp:495] Applied 1 operations in
34623ns; attempting to update the registry
3: I1111 02:02:35.394253 19947 registrar.cpp:552] Successfully updated the
registry in 534016ns
3: I1111 02:02:35.394368 19947 registrar.cpp:424] Successfully recovered
registrar
3: I1111 02:02:35.394858 19954 hierarchical.cpp:209] Skipping recovery of
hierarchical allocator: nothing to recover
3: I1111 02:02:35.394932 19966 master.cpp:1806] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
3: W1111 02:02:35.400466 19944 process.cpp:3178] Attempted to spawn already
running process files@172.17.0.6:38163
3: I1111 02:02:35.401845 19944 containerizer.cpp:301] Using isolation {
environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1111 02:02:35.402595 19944 backend.cpp:76] Failed to create 'aufs' backend:
AufsBackend requires root privileges
3: W1111 02:02:35.402737 19944 backend.cpp:76] Failed to create 'bind' backend:
BindBackend requires root privileges
3: I1111 02:02:35.402771 19944 provisioner.cpp:255] Using default backend 'copy'
3: I1111 02:02:35.404947 19944 cluster.cpp:448] Creating default 'local'
authorizer
3: I1111 02:02:35.407586 19960 slave.cpp:262] Mesos agent started on
(764)@172.17.0.6:38163
3: I1111 02:02:35.407608 19960 slave.cpp:263] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/ContentType_SchedulerTest_Revive_1_lye6OE/store/appc"
--authenticate_http_executors="true" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local"
--cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false"
--cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs"
--containerizers="mesos"
--credential="/tmp/ContentType_SchedulerTest_Revive_1_lye6OE/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/ContentType_SchedulerTest_Revive_1_lye6OE/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_secret_key="/tmp/ContentType_SchedulerTest_Revive_1_lye6OE/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ContentType_SchedulerTest_Revive_1_lye6OE/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/ContentType_SchedulerTest_Revive_1_lye6OE/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/ContentType_SchedulerTest_Revive_1_lye6OE"
--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_SchedulerTest_Revive_1_GICbFc"
--zk_session_timeout="10secs"
3: I1111 02:02:35.409593 19960 credentials.hpp:86] Loading credential for
authentication from '/tmp/ContentType_SchedulerTest_Revive_1_lye6OE/credential'
3: I1111 02:02:35.409839 19960 slave.cpp:295] Agent using credential for:
test-principal
3: I1111 02:02:35.409854 19960 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_SchedulerTest_Revive_1_lye6OE/http_credentials'
3: I1111 02:02:35.410194 19960 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1111 02:02:35.410352 19960 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
3: I1111 02:02:35.410589 19960 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1111 02:02:35.410688 19960 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
3: I1111 02:02:35.410878 19960 http.cpp:1045] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1111 02:02:35.410977 19960 http.cpp:1066] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
3: I1111 02:02:35.412812 19960 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 02:02:35.413139 19960 slave.cpp:601] Agent attributes: [ ]
3: I1111 02:02:35.413151 19960 slave.cpp:610] Agent hostname: aca3232fb920
3: I1111 02:02:35.413372 19945 status_update_manager.cpp:177] Pausing sending
status updates
3: I1111 02:02:35.415161 19956 state.cpp:64] Recovering state from
'/tmp/ContentType_SchedulerTest_Revive_1_GICbFc/meta'
3: I1111 02:02:35.415664 19956 status_update_manager.cpp:203] Recovering status
update manager
3: I1111 02:02:35.415841 19956 containerizer.cpp:662] Recovering containerizer
3: I1111 02:02:35.416080 19944 scheduler.cpp:190] Version: 1.5.0
3: I1111 02:02:35.416429 19947 scheduler.cpp:313] Using default 'basic' HTTP
authenticatee
3: I1111 02:02:35.417176 19946 scheduler.cpp:496] New master detected at
master@172.17.0.6:38163
3: I1111 02:02:35.417362 19946 scheduler.cpp:505] Waiting for 0ns before
initiating a re-(connection) attempt with the master
3: I1111 02:02:35.417737 19958 provisioner.cpp:416] Provisioner recovery
complete
3: I1111 02:02:35.418232 19958 slave.cpp:6422] Finished recovery
3: I1111 02:02:35.419039 19957 slave.cpp:1007] New master detected at
master@172.17.0.6:38163
3: I1111 02:02:35.419090 19959 status_update_manager.cpp:177] Pausing sending
status updates
3: I1111 02:02:35.419137 19957 slave.cpp:1042] Detecting new master
3: I1111 02:02:35.420899 19950 scheduler.cpp:387] Connected with the master at
http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.426376 19952 slave.cpp:1069] Authenticating with master
master@172.17.0.6:38163
3: I1111 02:02:35.426496 19952 slave.cpp:1078] Using default CRAM-MD5
authenticatee
3: I1111 02:02:35.426868 19952 authenticatee.cpp:121] Creating new client SASL
connection
3: I1111 02:02:35.427258 19959 master.cpp:8286] Authenticating
slave(764)@172.17.0.6:38163
3: I1111 02:02:35.427439 19947 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1404)@172.17.0.6:38163
3: I1111 02:02:35.427738 19945 authenticator.cpp:98] Creating new server SASL
connection
3: I1111 02:02:35.428037 19962 authenticatee.cpp:213] Received SASL
authentication mechanisms: CRAM-MD5
3: I1111 02:02:35.428061 19962 authenticatee.cpp:239] Attempting to
authenticate with mechanism 'CRAM-MD5'
3: I1111 02:02:35.428164 19957 authenticator.cpp:204] Received SASL
authentication start
3: I1111 02:02:35.428220 19957 authenticator.cpp:326] Authentication requires
more steps
3: I1111 02:02:35.428285 19966 scheduler.cpp:249] Sending SUBSCRIBE call to
http://172.17.0.6:38163/master/api/v1/scheduler
3: I1111 02:02:35.428324 19965 authenticatee.cpp:259] Received SASL
authentication step
3: I1111 02:02:35.428443 19963 authenticator.cpp:232] Received SASL
authentication step
3: I1111 02:02:35.428472 19963 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'aca3232fb920' server FQDN: 'aca3232fb920'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
3: I1111 02:02:35.428481 19963 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
3: I1111 02:02:35.428527 19963 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
3: I1111 02:02:35.428545 19963 auxprop.cpp:109] Request to lookup properties
for user: 'test-principal' realm: 'aca3232fb920' server FQDN: 'aca3232fb920'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
3: I1111 02:02:35.428551 19963 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1111 02:02:35.428556 19963 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1111 02:02:35.428570 19963 authenticator.cpp:318] Authentication success
Build step 'Execute shell' marked build as failure
