See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3443/display/redirect?page=changes>
Changes:
[yujie.jay] Fixed a formatting issue in executor.proto.
------------------------------------------
[...truncated 23.60 MB...]
I0331 22:33:01.187259 26693 master.cpp:1449] Giving framework
1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000 (default) 0ns to failover
I0331 22:33:01.187340 26704 hierarchical.cpp:376] Deactivated framework
1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000
I0331 22:33:01.187604 26693 master.cpp:1317] Agent
1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0 at slave(664)@172.17.0.3:45561
(d78ea4a1860f) disconnected
I0331 22:33:01.187667 26693 master.cpp:3185] Disconnecting agent
1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0 at slave(664)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.187717 26693 master.cpp:3204] Deactivating agent
1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0 at slave(664)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.187757 26693 master.cpp:1344] Removing framework
1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000 (default) from disconnected agent
1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0 at slave(664)@172.17.0.3:45561
(d78ea4a1860f) because the framework is not checkpointing
I0331 22:33:01.187777 26693 master.cpp:7941] Removing framework
1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000 (default) from agent
1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0 at slave(664)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.187849 26693 master.cpp:8345] Updating the state of task
0c13f32a-9a32-43f5-b973-64f94827d77c of framework
1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000 (latest state: TASK_LOST, status
update state: TASK_LOST)
I0331 22:33:01.188066 26695 hierarchical.cpp:655] Agent
1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0 deactivated
I0331 22:33:01.188076 26693 master.cpp:8439] Removing task
0c13f32a-9a32-43f5-b973-64f94827d77c with resources cpus(*)(allocated: *):2;
mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated:
*):[31000-32000] of framework 1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000 on
agent 1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0 at slave(664)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.188225 26693 master.cpp:6921] Framework failover timeout,
removing framework 1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000 (default)
I0331 22:33:01.188244 26693 master.cpp:7777] Removing framework
1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000 (default)
I0331 22:33:01.188400 26695 hierarchical.cpp:1116] 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
1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0 from framework
1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000
I0331 22:33:01.188582 26695 hierarchical.cpp:323] Removed framework
1c3875ec-9e04-4e35-8674-5ca6180cfa74-0000
I0331 22:33:01.189195 26688 master.cpp:1156] Master terminating
I0331 22:33:01.189414 26699 hierarchical.cpp:560] Removed agent
1c3875ec-9e04-4e35-8674-5ca6180cfa74-S0
[ OK ] ContentType/SchedulerTest.ReconcileTask/0 (88 ms)
[ RUN ] ContentType/SchedulerTest.ReconcileTask/1
I0331 22:33:01.192967 26688 cluster.cpp:160] Creating default 'local' authorizer
I0331 22:33:01.193752 26693 master.cpp:437] Master
6567c237-3371-4f07-8a9a-6b69e93af2e7 (d78ea4a1860f) started on 172.17.0.3:45561
I0331 22:33:01.193856 26693 master.cpp:439] 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/jGZdEY/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"
--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/jGZdEY/master"
--zk_session_timeout="10secs"
I0331 22:33:01.194099 26693 master.cpp:489] Master only allowing authenticated
frameworks to register
I0331 22:33:01.194128 26693 master.cpp:503] Master only allowing authenticated
agents to register
I0331 22:33:01.194136 26693 master.cpp:516] Master only allowing authenticated
HTTP frameworks to register
I0331 22:33:01.194142 26693 credentials.hpp:37] Loading credentials for
authentication from '/tmp/jGZdEY/credentials'
I0331 22:33:01.194268 26693 master.cpp:561] Using default 'crammd5'
authenticator
I0331 22:33:01.194314 26693 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0331 22:33:01.194346 26693 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0331 22:33:01.194373 26693 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0331 22:33:01.194430 26693 master.cpp:641] Authorization enabled
I0331 22:33:01.194494 26698 whitelist_watcher.cpp:77] No whitelist given
I0331 22:33:01.194520 26689 hierarchical.cpp:159] Initialized hierarchical
allocator process
I0331 22:33:01.195361 26693 master.cpp:2165] Elected as the leading master!
I0331 22:33:01.195376 26693 master.cpp:1704] Recovering from registrar
I0331 22:33:01.195479 26704 registrar.cpp:331] Recovering registrar
I0331 22:33:01.195842 26704 registrar.cpp:364] Successfully fetched the
registry (0B) in 265216ns
I0331 22:33:01.195878 26704 registrar.cpp:463] Applied 1 operations in 3950ns;
attempting to update the registry
I0331 22:33:01.196213 26691 registrar.cpp:508] Successfully updated the
registry in 291072ns
I0331 22:33:01.196290 26691 registrar.cpp:394] Successfully recovered registrar
I0331 22:33:01.196405 26691 master.cpp:1803] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0331 22:33:01.196498 26691 hierarchical.cpp:186] Skipping recovery of
hierarchical allocator: nothing to recover
I0331 22:33:01.198184 26688 cluster.cpp:446] Creating default 'local' authorizer
I0331 22:33:01.198714 26697 slave.cpp:226] Mesos agent started on
(665)@172.17.0.3:45561
I0331 22:33:01.198966 26688 scheduler.cpp:184] Version: 1.3.0
I0331 22:33:01.198737 26697 slave.cpp:227] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/mesos/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_ReconcileTask_1_bHzfDd/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_secret_key="secret_key" --executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ContentType_SchedulerTest_ReconcileTask_1_bHzfDd/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_ReconcileTask_1_bHzfDd/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_ReconcileTask_1_bHzfDd"
--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_ReconcileTask_1_XtsJ8A"
I0331 22:33:01.199268 26697 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_bHzfDd/credential'
I0331 22:33:01.199278 26701 scheduler.cpp:470] New master detected at
[email protected]:45561
I0331 22:33:01.199311 26701 scheduler.cpp:479] Waiting for 0ns before
initiating a re-(connection) attempt with the master
I0331 22:33:01.199422 26697 slave.cpp:259] Agent using credential for:
test-principal
I0331 22:33:01.199442 26697 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_bHzfDd/http_credentials'
I0331 22:33:01.199573 26697 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
I0331 22:33:01.199623 26697 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
I0331 22:33:01.199686 26697 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0331 22:33:01.199720 26697 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
I0331 22:33:01.199774 26697 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0331 22:33:01.199950 26697 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0331 22:33:01.200160 26704 scheduler.cpp:361] Connected with the master at
http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.200680 26697 slave.cpp:492] Agent resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0331 22:33:01.200728 26697 slave.cpp:500] Agent attributes: [ ]
I0331 22:33:01.200738 26697 slave.cpp:505] Agent hostname: d78ea4a1860f
I0331 22:33:01.200821 26690 status_update_manager.cpp:177] Pausing sending
status updates
I0331 22:33:01.201393 26693 state.cpp:62] Recovering state from
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_XtsJ8A/meta'
I0331 22:33:01.201562 26695 status_update_manager.cpp:203] Recovering status
update manager
I0331 22:33:01.201762 26698 slave.cpp:5927] Finished recovery
I0331 22:33:01.202137 26698 slave.cpp:6109] Querying resource estimator for
oversubscribable resources
I0331 22:33:01.202314 26698 slave.cpp:882] New master detected at
[email protected]:45561
I0331 22:33:01.202333 26690 status_update_manager.cpp:177] Pausing sending
status updates
I0331 22:33:01.202414 26698 slave.cpp:917] Detecting new master
I0331 22:33:01.202571 26690 scheduler.cpp:243] Sending SUBSCRIBE call to
http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.202567 26698 slave.cpp:6123] Received oversubscribable resources
{} from the resource estimator
I0331 22:33:01.203184 26702 process.cpp:3705] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0331 22:33:01.203500 26702 http.cpp:440] HTTP POST for
/master/api/v1/scheduler from 172.17.0.3:60986
I0331 22:33:01.203660 26702 master.cpp:2517] Received subscription request for
HTTP framework 'default'
I0331 22:33:01.203693 26702 master.cpp:2201] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0331 22:33:01.203881 26702 master.cpp:2632] Subscribing framework 'default'
with checkpointing disabled and capabilities [ ]
I0331 22:33:01.204171 26694 hierarchical.cpp:271] Added framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.204202 26690 master.hpp:2171] Sending heartbeat to
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.204324 26694 hierarchical.cpp:1862] No allocations performed
I0331 22:33:01.204454 26694 hierarchical.cpp:1952] No inverse offers to send
out!
I0331 22:33:01.204469 26694 hierarchical.cpp:1446] Performed allocation for 0
agents in 155764ns
I0331 22:33:01.204747 26703 scheduler.cpp:676] Enqueuing event SUBSCRIBED
received from http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.204890 26703 scheduler.cpp:676] Enqueuing event HEARTBEAT
received from http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.205294 26691 slave.cpp:944] Authenticating with master
[email protected]:45561
I0331 22:33:01.205338 26691 slave.cpp:955] Using default CRAM-MD5 authenticatee
I0331 22:33:01.205675 26704 authenticatee.cpp:121] Creating new client SASL
connection
I0331 22:33:01.205875 26691 master.cpp:7252] Authenticating
slave(665)@172.17.0.3:45561
I0331 22:33:01.205938 26704 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1246)@172.17.0.3:45561
I0331 22:33:01.206027 26704 authenticator.cpp:98] Creating new server SASL
connection
I0331 22:33:01.206174 26703 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0331 22:33:01.206200 26703 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0331 22:33:01.206382 26703 authenticator.cpp:204] Received SASL authentication
start
I0331 22:33:01.206449 26703 authenticator.cpp:326] Authentication requires more
steps
I0331 22:33:01.206501 26703 authenticatee.cpp:259] Received SASL authentication
step
I0331 22:33:01.206564 26703 authenticator.cpp:232] Received SASL authentication
step
I0331 22:33:01.206589 26703 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'd78ea4a1860f' server FQDN: 'd78ea4a1860f'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0331 22:33:01.206605 26703 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0331 22:33:01.206624 26703 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0331 22:33:01.206640 26703 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'd78ea4a1860f' server FQDN: 'd78ea4a1860f'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0331 22:33:01.206650 26703 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0331 22:33:01.206658 26703 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0331 22:33:01.206674 26703 authenticator.cpp:318] Authentication success
I0331 22:33:01.206729 26704 authenticatee.cpp:299] Authentication success
I0331 22:33:01.206763 26704 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1246)@172.17.0.3:45561
I0331 22:33:01.206827 26703 master.cpp:7282] Successfully authenticated
principal 'test-principal' at slave(665)@172.17.0.3:45561
I0331 22:33:01.206924 26699 slave.cpp:1039] Successfully authenticated with
master [email protected]:45561
I0331 22:33:01.207224 26699 slave.cpp:1467] Will retry registration in 574036ns
if necessary
I0331 22:33:01.207288 26689 master.cpp:5437] Registering agent at
slave(665)@172.17.0.3:45561 (d78ea4a1860f) with id
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0
I0331 22:33:01.207521 26704 registrar.cpp:463] Applied 1 operations in 23879ns;
attempting to update the registry
I0331 22:33:01.208133 26704 registrar.cpp:508] Successfully updated the
registry in 481792ns
I0331 22:33:01.208366 26699 slave.cpp:4709] Received ping from
slave-observer(598)@172.17.0.3:45561
I0331 22:33:01.208500 26699 slave.cpp:1085] Registered with master
[email protected]:45561; given agent ID 6567c237-3371-4f07-8a9a-6b69e93af2e7-S0
I0331 22:33:01.208379 26695 master.cpp:5511] Registered agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0331 22:33:01.208551 26700 hierarchical.cpp:527] Added agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 (d78ea4a1860f) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0331 22:33:01.208648 26699 fetcher.cpp:94] Clearing fetcher cache
I0331 22:33:01.208920 26700 hierarchical.cpp:1952] No inverse offers to send
out!
I0331 22:33:01.208992 26695 status_update_manager.cpp:184] Resuming sending
status updates
I0331 22:33:01.209070 26699 slave.cpp:1113] Checkpointing SlaveInfo to
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_XtsJ8A/meta/slaves/6567c237-3371-4f07-8a9a-6b69e93af2e7-S0/slave.info'
I0331 22:33:01.209163 26695 master.cpp:7081] Sending 1 offers to framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default)
I0331 22:33:01.209415 26699 slave.cpp:1151] Forwarding total oversubscribed
resources {}
I0331 22:33:01.209626 26695 master.cpp:6098] Received update of agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f) with total oversubscribed resources {}
I0331 22:33:01.209705 26700 hierarchical.cpp:1446] Performed allocation for 1
agents in 1.045876ms
I0331 22:33:01.210500 26700 scheduler.cpp:676] Enqueuing event OFFERS received
from http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.211334 26699 scheduler.cpp:243] Sending ACCEPT call to
http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.212492 26703 process.cpp:3705] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0331 22:33:01.213001 26691 http.cpp:440] HTTP POST for
/master/api/v1/scheduler from 172.17.0.3:60985
I0331 22:33:01.213703 26691 master.cpp:3839] Processing ACCEPT call for offers:
[ 6567c237-3371-4f07-8a9a-6b69e93af2e7-O0 ] on agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f) for framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default)
I0331 22:33:01.213773 26691 master.cpp:3415] Authorizing framework principal
'test-principal' to launch task 58aa3947-d25d-4467-8bea-13f5562e5104
W0331 22:33:01.215014 26689 validation.cpp:1113] Executor 'default' for task
'58aa3947-d25d-4467-8bea-13f5562e5104' uses less CPUs (None) than the minimum
required (0.01). Please update your executor, as this will be mandatory in
future releases.
W0331 22:33:01.215112 26689 validation.cpp:1125] Executor 'default' for task
'58aa3947-d25d-4467-8bea-13f5562e5104' uses less memory (None) than the minimum
required (32MB). Please update your executor, as this will be mandatory in
future releases.
I0331 22:33:01.215376 26689 master.cpp:9097] Adding task
58aa3947-d25d-4467-8bea-13f5562e5104 with resources cpus(*)(allocated: *):2;
mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated:
*):[31000-32000] on agent 6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at
slave(665)@172.17.0.3:45561 (d78ea4a1860f)
I0331 22:33:01.215610 26689 master.cpp:4489] Launching task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default) with resources
cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024;
ports(*)(allocated: *):[31000-32000] on agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.216125 26689 slave.cpp:1577] Got assigned task
'58aa3947-d25d-4467-8bea-13f5562e5104' for framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.216684 26689 slave.cpp:1858] Authorizing task
'58aa3947-d25d-4467-8bea-13f5562e5104' for framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.216776 26689 slave.cpp:6546] Authorizing framework principal
'test-principal' to launch task 58aa3947-d25d-4467-8bea-13f5562e5104
I0331 22:33:01.217242 26703 slave.cpp:2045] Launching task
'58aa3947-d25d-4467-8bea-13f5562e5104' for framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.217913 26703 paths.cpp:554] Trying to chown
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_XtsJ8A/slaves/6567c237-3371-4f07-8a9a-6b69e93af2e7-S0/frameworks/6567c237-3371-4f07-8a9a-6b69e93af2e7-0000/executors/default/runs/15ff2744-af74-4f7c-a293-2330a60ff081'
to user 'mesos'
I0331 22:33:01.217989 26696 hierarchical.cpp:852] Updated allocation of
framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 on agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 from cpus(*)(allocated: *):2;
mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated:
*):[31000-32000] to cpus(*)(allocated: *):2; mem(*)(allocated: *):1024;
disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
I0331 22:33:01.218145 26703 slave.cpp:6890] Launching executor 'default' of
framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 with resources {} in work
directory
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_XtsJ8A/slaves/6567c237-3371-4f07-8a9a-6b69e93af2e7-S0/frameworks/6567c237-3371-4f07-8a9a-6b69e93af2e7-0000/executors/default/runs/15ff2744-af74-4f7c-a293-2330a60ff081'
I0331 22:33:01.218462 26703 slave.cpp:2274] Queued task
'58aa3947-d25d-4467-8bea-13f5562e5104' for executor 'default' of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.218523 26703 slave.cpp:835] Successfully attached file
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_XtsJ8A/slaves/6567c237-3371-4f07-8a9a-6b69e93af2e7-S0/frameworks/6567c237-3371-4f07-8a9a-6b69e93af2e7-0000/executors/default/runs/15ff2744-af74-4f7c-a293-2330a60ff081'
I0331 22:33:01.219420 26694 executor.cpp:192] Version: 1.3.0
I0331 22:33:01.220088 26700 executor.cpp:410] Connected with the agent
I0331 22:33:01.220468 26695 executor.cpp:307] Sending SUBSCRIBE call to
http://172.17.0.3:45561/slave(665)/api/v1/executor
I0331 22:33:01.221017 26689 process.cpp:3705] Handling HTTP event for process
'slave(665)' with path: '/slave(665)/api/v1/executor'
I0331 22:33:01.221842 26701 http.cpp:331] HTTP POST for
/slave(665)/api/v1/executor from 172.17.0.3:60987
I0331 22:33:01.221938 26701 slave.cpp:3532] Received Subscribe request for HTTP
executor 'default' of framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.222474 26701 slave.cpp:2487] Sending queued task
'58aa3947-d25d-4467-8bea-13f5562e5104' to executor 'default' of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (via HTTP)
I0331 22:33:01.223065 26696 executor.cpp:725] Enqueuing event SUBSCRIBED
received from http://172.17.0.3:45561/slave(665)/api/v1/executor
I0331 22:33:01.223835 26689 executor.cpp:725] Enqueuing event LAUNCH received
from http://172.17.0.3:45561/slave(665)/api/v1/executor
I0331 22:33:01.224202 26698 executor.cpp:307] Sending UPDATE call to
http://172.17.0.3:45561/slave(665)/api/v1/executor
I0331 22:33:01.224827 26689 process.cpp:3705] Handling HTTP event for process
'slave(665)' with path: '/slave(665)/api/v1/executor'
I0331 22:33:01.225672 26689 http.cpp:331] HTTP POST for
/slave(665)/api/v1/executor from 172.17.0.3:60988
I0331 22:33:01.225778 26689 slave.cpp:4179] Handling status update TASK_RUNNING
(UUID: a4c7014c-1d41-4036-b54f-d6d4663da1f9) for task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.226152 26689 status_update_manager.cpp:323] Received status
update TASK_RUNNING (UUID: a4c7014c-1d41-4036-b54f-d6d4663da1f9) for task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.226181 26689 status_update_manager.cpp:500] Creating
StatusUpdate stream for task 58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.226366 26689 status_update_manager.cpp:377] Forwarding update
TASK_RUNNING (UUID: a4c7014c-1d41-4036-b54f-d6d4663da1f9) for task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 to the agent
I0331 22:33:01.226496 26689 slave.cpp:4619] Forwarding the update TASK_RUNNING
(UUID: a4c7014c-1d41-4036-b54f-d6d4663da1f9) for task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 to [email protected]:45561
I0331 22:33:01.226579 26689 slave.cpp:4513] Status update manager successfully
handled status update TASK_RUNNING (UUID: a4c7014c-1d41-4036-b54f-d6d4663da1f9)
for task 58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.226709 26702 master.cpp:6243] Status update TASK_RUNNING (UUID:
a4c7014c-1d41-4036-b54f-d6d4663da1f9) for task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 from agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.226815 26702 master.cpp:6311] Forwarding status update
TASK_RUNNING (UUID: a4c7014c-1d41-4036-b54f-d6d4663da1f9) for task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.227130 26702 master.cpp:8345] Updating the state of task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (latest state: TASK_RUNNING, status
update state: TASK_RUNNING)
I0331 22:33:01.227478 26690 executor.cpp:725] Enqueuing event ACKNOWLEDGED
received from http://172.17.0.3:45561/slave(665)/api/v1/executor
I0331 22:33:01.228382 26691 scheduler.cpp:676] Enqueuing event UPDATE received
from http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.228745 26691 scheduler.cpp:243] Sending RECONCILE call to
http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.229344 26695 process.cpp:3705] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0331 22:33:01.269531 26695 http.cpp:440] HTTP POST for
/master/api/v1/scheduler from 172.17.0.3:60985
I0331 22:33:01.269974 26695 master.cpp:6765] Performing explicit task state
reconciliation for 1 tasks of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default)
I0331 22:33:01.270130 26695 master.cpp:6897] Sending explicit reconciliation
state TASK_RUNNING for task 58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default)
I0331 22:33:01.271147 26698 scheduler.cpp:676] Enqueuing event UPDATE received
from http://172.17.0.3:45561/master/api/v1/scheduler
I0331 22:33:01.272338 26696 master.cpp:1434] Framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default) disconnected
I0331 22:33:01.272363 26696 master.cpp:3148] Deactivating framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default)
I0331 22:33:01.272397 26696 master.cpp:3125] Disconnecting framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default)
I0331 22:33:01.272413 26696 master.cpp:1449] Giving framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default) 0ns to failover
I0331 22:33:01.272480 26696 hierarchical.cpp:376] Deactivated framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.272614 26689 master.cpp:6921] Framework failover timeout,
removing framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default)
I0331 22:33:01.272637 26689 master.cpp:7777] Removing framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (default)
I0331 22:33:01.272694 26689 master.cpp:8345] Updating the state of task
58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
I0331 22:33:01.272903 26689 master.cpp:8439] Removing task
58aa3947-d25d-4467-8bea-13f5562e5104 with resources cpus(*)(allocated: *):2;
mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated:
*):[31000-32000] of framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 on
agent 6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.273044 26689 master.cpp:8468] Removing executor 'default' with
resources {} of framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 on agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.273152 26689 slave.cpp:3021] Asked to shut down framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 by [email protected]:45561
I0331 22:33:01.273177 26689 slave.cpp:3046] Shutting down framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.273195 26689 slave.cpp:5439] Shutting down executor 'default' of
framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (via HTTP)
I0331 22:33:01.273490 26689 hierarchical.cpp:1116] 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
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 from framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.273602 26689 hierarchical.cpp:323] Removed framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.274230 26690 executor.cpp:725] Enqueuing event SHUTDOWN received
from http://172.17.0.3:45561/slave(665)/api/v1/executor
I0331 22:33:01.274935 26694 slave.cpp:5125] Executor 'default' of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 exited with status 0
I0331 22:33:01.274987 26694 slave.cpp:5225] Cleaning up executor 'default' of
framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 (via HTTP)
W0331 22:33:01.275127 26699 master.cpp:6367] Ignoring unknown exited executor
'default' of framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000 on agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.275197 26694 slave.cpp:5313] Cleaning up framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.275202 26699 gc.cpp:55] Scheduling
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_XtsJ8A/slaves/6567c237-3371-4f07-8a9a-6b69e93af2e7-S0/frameworks/6567c237-3371-4f07-8a9a-6b69e93af2e7-0000/executors/default/runs/15ff2744-af74-4f7c-a293-2330a60ff081'
for gc 6.99999681582222days in the future
I0331 22:33:01.275303 26694 status_update_manager.cpp:285] Closing status
update streams for framework 6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.275323 26694 status_update_manager.cpp:531] Cleaning up status
update stream for task 58aa3947-d25d-4467-8bea-13f5562e5104 of framework
6567c237-3371-4f07-8a9a-6b69e93af2e7-0000
I0331 22:33:01.275583 26699 gc.cpp:55] Scheduling
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_XtsJ8A/slaves/6567c237-3371-4f07-8a9a-6b69e93af2e7-S0/frameworks/6567c237-3371-4f07-8a9a-6b69e93af2e7-0000/executors/default'
for gc 6.99999681499852days in the future
I0331 22:33:01.275635 26699 gc.cpp:55] Scheduling
'/tmp/ContentType_SchedulerTest_ReconcileTask_1_XtsJ8A/slaves/6567c237-3371-4f07-8a9a-6b69e93af2e7-S0/frameworks/6567c237-3371-4f07-8a9a-6b69e93af2e7-0000'
for gc 6.99999681403852days in the future
*** Aborted at 1490999581 (unix time) try "date -d @1490999581" if you are
using GNU date ***
I0331 22:33:01.276031 26688 slave.cpp:754] Agent terminating
I0331 22:33:01.276206 26697 master.cpp:1317] Agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f) disconnected
I0331 22:33:01.276227 26697 master.cpp:3185] Disconnecting agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.276245 26697 master.cpp:3204] Deactivating agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 at slave(665)@172.17.0.3:45561
(d78ea4a1860f)
I0331 22:33:01.276551 26697 hierarchical.cpp:655] Agent
6567c237-3371-4f07-8a9a-6b69e93af2e7-S0 deactivated
PC: @ 0x1c573b3
testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
*** SIGSEGV (@0x30) received by PID 26688 (TID 0x7fa647d2e700) from PID 48;
stack trace: ***
@ 0x7fa651804370 (unknown)
@ 0x1c573b3
testing::internal::UntypedFunctionMockerBase::UntypedInvokeWith()
@ 0xdbc4b0
mesos::internal::tests::executor::MockHTTPExecutor<>::event()
@ 0xddffd7 mesos::internal::tests::executor::TestMesos<>::events()
@ 0xdcbe9d std::_Function_handler<>::_M_invoke()
@ 0x7fa655c3aab5 process::AsyncExecutorProcess::execute<>()
@ 0x7fa655c44485
_ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingNS0_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeISC_SaISC_EEEEESG_SK_SG_EENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSR_FSO_T1_T2_ET3_T4_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x7fa654aef271 process::ProcessManager::resume()
@ 0x7fa654af82f7
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7fa6515a1230 (unknown)
@ 0x7fa6517fcdc5 start_thread
@ 0x7fa650d0a73d __clone
make[3]: *** [CMakeFiles/check] Segmentation fault
make[3]: Leaving directory `/mesos/build'
make[2]: *** [CMakeFiles/check.dir/all] Error 2
make[2]: Leaving directory `/mesos/build'
make[1]: *** [CMakeFiles/check.dir/rule] Error 2
make[1]: Leaving directory `/mesos/build'
make: *** [check] Error 2
+ docker rmi mesos-1490995924-21567
Untagged: mesos-1490995924-21567:latest
Deleted: sha256:c71151980b5883f091dbf3cabe7d1a3fd6aa7116f1905971d9e9e769bbb2181a
Deleted: sha256:11dea48231ca736dbfd4558de71b7650cd26c4351a1ab80696ddb24fbf367b45
Deleted: sha256:971e3110a373bb4844c140a5127a2a92dbcfee78afd65d04fdde5f2613a2a528
Deleted: sha256:54e7b4c4ac98111c2d6f35aeb915ee9b3e599255e3778336b0bda644c46c7c09
Deleted: sha256:d22a2068b4727cd90b19d7796db63e69f37d2c83fbf7569d3a10d1ebdd3be555
Deleted: sha256:314452a7931c9c53f507aa47e3fed48b63f077f942e5981661bbccdfadf34714
Deleted: sha256:63dfb16e66c5d3c076754af6f993d642d217e2bc0660c751dffa4730e49bbb95
Deleted: sha256:25a88ccffa8df4f783bcdfca3c6e4f1cdf79fb12efcb2375cc31504d1e7bbb60
Deleted: sha256:c2cdcb97d6ddfea6d7c78b7928046441524c890556173e1c9e920429d9ea1246
Deleted: sha256:24fe0408233a29081792af6c730ba3421a8583822559bdd5040183bf3fbc86d3
Deleted: sha256:b25217c1bf1aa95e64cf68f9e3469ba79248c69e47eb3f357f9a86caea820426
Deleted: sha256:dcc874fcd44149854f6b81bafd6e531cf2f363960e79182ad556d34adda96358
Deleted: sha256:396b2705259cd9130bb6d04d738e7dc7eaf878666077709e88e534433d39d342
Deleted: sha256:50e9c7e55e1f6559c869e97aed179939aa3ea74e367ac52b1d0fdfe41d840364
Deleted: sha256:7d8bb3c236fc7733de0bc2bde6b7092b514ac3573669f02b7b5a220883589d7a
Deleted: sha256:bde41ac3b6f27d22f8ea6f6bb3682971ddb11824790672f7ca46a39cfac88d49
Deleted: sha256:1495577fe1443b81171ad8efe6d39c285246ddae392133ca6c8f88a460597faa
Deleted: sha256:00d2bb48be7965243e11405cc6e37d04f3541dcca7ed6987047cfa57f4050bbc
Deleted: sha256:d232e1d6b9e910889cceb03580c41c9ef053b77d09026cf716993bab4e9ee640
Deleted: sha256:92d5490642f80c3dc570f4996221a0ca7b3b44ac8f894fccc9ddc54fd14741e0
Deleted: sha256:80fa02b48fd64afdc8576dd8f1e9d20ed8a63710ace13956894b4f0b64fcdf42
Deleted: sha256:0e8f0cff9a4741e94daa9f00810a6fcfe8f3eb515483b5f7f64907296195e6b6
Deleted: sha256:9bab34a849046a895c7206b98206b85b06a2991481ed7695d47d43463e29a3c0
Deleted: sha256:9557e4f1b2a8822d9e6ac051a2e7dfaf654e01e4ee42824cdc9601d0b0a4f9f9
Deleted: sha256:da9bf90c762176623e077f209ba437acdd0edac228599627b725c593732b8f21
Build step 'Execute shell' marked build as failure
