See <https://builds.apache.org/job/Mesos-Reviewbot/18234/display/redirect>
------------------------------------------
[...truncated 27.75 MB...]
I0601 18:08:28.418314 4522 master.cpp:8507] Updating the state of task
69033cee-ff8a-4d2b-b41b-bb51ab3b741d of framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 (latest state: TASK_LOST, status
update state: TASK_LOST)
I0601 18:08:28.419790 4522 master.cpp:8601] Removing task
69033cee-ff8a-4d2b-b41b-bb51ab3b741d with resources cpus(*)(allocated: *):2;
mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated:
*):[31000-32000] of framework 7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 on
agent 7fdc03ab-a912-45b8-b67a-e74b200568a0-S0 at slave(745)@172.17.0.3:35323
(7950289a8145)
I0601 18:08:28.420423 4522 master.cpp:6497] Sending status update TASK_LOST
for task 69033cee-ff8a-4d2b-b41b-bb51ab3b741d of framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 'Agent 7950289a8145 disconnected'
W0601 18:08:28.420886 4522 master.hpp:2355] Unable to send event to framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 (default): connection closed
I0601 18:08:28.421284 4522 master.cpp:1428] Framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 (default) disconnected
I0601 18:08:28.421329 4522 master.cpp:3158] Deactivating framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 (default)
I0601 18:08:28.421555 4522 master.cpp:3135] Disconnecting framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 (default)
I0601 18:08:28.421617 4522 master.cpp:1443] Giving framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 (default) 0ns to failover
I0601 18:08:28.422389 4519 hierarchical.cpp:1135] 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
7fdc03ab-a912-45b8-b67a-e74b200568a0-S0 from framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000
I0601 18:08:28.423002 4519 hierarchical.cpp:395] Deactivated framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000
I0601 18:08:28.424979 4522 master.cpp:7093] Framework failover timeout,
removing framework 7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 (default)
I0601 18:08:28.425040 4522 master.cpp:7939] Removing framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000 (default)
I0601 18:08:28.425509 4522 master.cpp:1156] Master terminating
I0601 18:08:28.426817 4524 hierarchical.cpp:346] Removed framework
7fdc03ab-a912-45b8-b67a-e74b200568a0-0000
I0601 18:08:28.428053 4524 hierarchical.cpp:579] Removed agent
7fdc03ab-a912-45b8-b67a-e74b200568a0-S0
[ OK ] ContentType/SchedulerTest.Message/0 (270 ms)
[ RUN ] ContentType/SchedulerTest.Message/1
I0601 18:08:28.437911 4499 cluster.cpp:162] Creating default 'local' authorizer
I0601 18:08:28.443068 4521 master.cpp:436] Master
b14cd2bf-6dff-4250-b14c-636a8f749d4f (7950289a8145) started on 172.17.0.3:35323
I0601 18:08:28.443115 4521 master.cpp:438] 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/opZmjl/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="/mesos/mesos-1.4.0/_inst/share/mesos/webui"
--work_dir="/tmp/opZmjl/master" --zk_session_timeout="10secs"
I0601 18:08:28.443764 4521 master.cpp:488] Master only allowing authenticated
frameworks to register
I0601 18:08:28.443789 4521 master.cpp:502] Master only allowing authenticated
agents to register
I0601 18:08:28.443806 4521 master.cpp:515] Master only allowing authenticated
HTTP frameworks to register
I0601 18:08:28.443822 4521 credentials.hpp:37] Loading credentials for
authentication from '/tmp/opZmjl/credentials'
I0601 18:08:28.444522 4521 master.cpp:560] Using default 'crammd5'
authenticator
I0601 18:08:28.444746 4521 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0601 18:08:28.444908 4521 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0601 18:08:28.445188 4521 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0601 18:08:28.445291 4521 master.cpp:640] Authorization enabled
I0601 18:08:28.445987 4524 hierarchical.cpp:167] Initialized hierarchical
allocator process
I0601 18:08:28.446388 4524 whitelist_watcher.cpp:77] No whitelist given
I0601 18:08:28.449687 4523 master.cpp:2159] Elected as the leading master!
I0601 18:08:28.449739 4523 master.cpp:1698] Recovering from registrar
I0601 18:08:28.450201 4519 registrar.cpp:345] Recovering registrar
I0601 18:08:28.451268 4519 registrar.cpp:389] Successfully fetched the
registry (0B) in 979968ns
I0601 18:08:28.451503 4519 registrar.cpp:493] Applied 1 operations in 93185ns;
attempting to update the registry
I0601 18:08:28.452843 4519 registrar.cpp:550] Successfully updated the
registry in 1.060096ms
I0601 18:08:28.453060 4519 registrar.cpp:422] Successfully recovered registrar
I0601 18:08:28.454061 4521 hierarchical.cpp:206] Skipping recovery of
hierarchical allocator: nothing to recover
I0601 18:08:28.454053 4523 master.cpp:1797] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0601 18:08:28.465000 4499 cluster.cpp:448] Creating default 'local' authorizer
I0601 18:08:28.468219 4521 slave.cpp:248] Mesos agent started on
(746)@172.17.0.3:35323
I0601 18:08:28.468260 4521 slave.cpp:249] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/ContentType_SchedulerTest_Message_1_yBKHkh/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/ContentType_SchedulerTest_Message_1_yBKHkh/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/ContentType_SchedulerTest_Message_1_yBKHkh/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_reregistration_timeout="2secs"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/ContentType_SchedulerTest_Message_1_yBKHkh/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_Message_1_yBKHkh/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/mesos-1.4.0/_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_Message_1_yBKHkh"
--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_Message_1_hwz1jj"
I0601 18:08:28.469108 4521 credentials.hpp:86] Loading credential for
authentication from '/tmp/ContentType_SchedulerTest_Message_1_yBKHkh/credential'
I0601 18:08:28.469377 4521 slave.cpp:281] Agent using credential for:
test-principal
I0601 18:08:28.469406 4521 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_SchedulerTest_Message_1_yBKHkh/http_credentials'
I0601 18:08:28.469691 4499 scheduler.cpp:184] Version: 1.4.0
I0601 18:08:28.470088 4521 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0601 18:08:28.470577 4521 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0601 18:08:28.473832 4521 slave.cpp:552] Agent resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0601 18:08:28.474004 4521 slave.cpp:560] Agent attributes: [ ]
I0601 18:08:28.474025 4521 slave.cpp:565] Agent hostname: 7950289a8145
I0601 18:08:28.474074 4523 scheduler.cpp:470] New master detected at
[email protected]:35323
I0601 18:08:28.474109 4523 scheduler.cpp:479] Waiting for 0ns before
initiating a re-(connection) attempt with the master
I0601 18:08:28.474475 4524 status_update_manager.cpp:177] Pausing sending
status updates
I0601 18:08:28.483958 4518 state.cpp:62] Recovering state from
'/tmp/ContentType_SchedulerTest_Message_1_hwz1jj/meta'
I0601 18:08:28.484180 4524 scheduler.cpp:361] Connected with the master at
http://172.17.0.3:35323/master/api/v1/scheduler
I0601 18:08:28.485083 4525 status_update_manager.cpp:203] Recovering status
update manager
I0601 18:08:28.486121 4521 slave.cpp:6119] Finished recovery
I0601 18:08:28.486788 4524 scheduler.cpp:243] Sending SUBSCRIBE call to
http://172.17.0.3:35323/master/api/v1/scheduler
I0601 18:08:28.486959 4521 slave.cpp:6301] Querying resource estimator for
oversubscribable resources
I0601 18:08:28.487560 4521 slave.cpp:6315] Received oversubscribable resources
{} from the resource estimator
I0601 18:08:28.487848 4521 slave.cpp:945] New master detected at
[email protected]:35323
I0601 18:08:28.488394 4521 slave.cpp:980] Detecting new master
I0601 18:08:28.488766 4521 status_update_manager.cpp:177] Pausing sending
status updates
I0601 18:08:28.492780 4525 process.cpp:3778] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0601 18:08:28.495172 4519 http.cpp:1115] HTTP POST for
/master/api/v1/scheduler from 172.17.0.3:35584
I0601 18:08:28.496150 4519 master.cpp:2511] Received subscription request for
HTTP framework 'default'
I0601 18:08:28.496585 4519 master.cpp:2195] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0601 18:08:28.497474 4525 master.cpp:2626] Subscribing framework 'default'
with checkpointing disabled and capabilities [ ]
I0601 18:08:28.498381 4519 slave.cpp:1007] Authenticating with master
[email protected]:35323
I0601 18:08:28.498896 4519 slave.cpp:1018] Using default CRAM-MD5 authenticatee
I0601 18:08:28.499101 4521 master.hpp:2194] Sending heartbeat to
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.499470 4523 hierarchical.cpp:294] Added framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.500012 4519 authenticatee.cpp:121] Creating new client SASL
connection
I0601 18:08:28.500717 4521 master.cpp:7422] Authenticating
slave(746)@172.17.0.3:35323
I0601 18:08:28.500751 4523 hierarchical.cpp:1865] No allocations performed
I0601 18:08:28.501263 4523 hierarchical.cpp:1955] No inverse offers to send
out!
I0601 18:08:28.501350 4523 hierarchical.cpp:1447] Performed allocation for 0
agents in 665327ns
I0601 18:08:28.503193 4525 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1390)@172.17.0.3:35323
I0601 18:08:28.503831 4525 authenticator.cpp:98] Creating new server SASL
connection
I0601 18:08:28.504360 4525 scheduler.cpp:676] Enqueuing event SUBSCRIBED
received from http://172.17.0.3:35323/master/api/v1/scheduler
I0601 18:08:28.504426 4521 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0601 18:08:28.504463 4521 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0601 18:08:28.504598 4521 authenticator.cpp:204] Received SASL authentication
start
I0601 18:08:28.504829 4521 authenticator.cpp:326] Authentication requires more
steps
I0601 18:08:28.505208 4521 scheduler.cpp:676] Enqueuing event HEARTBEAT
received from http://172.17.0.3:35323/master/api/v1/scheduler
I0601 18:08:28.505242 4522 authenticatee.cpp:259] Received SASL authentication
step
I0601 18:08:28.505892 4523 authenticator.cpp:232] Received SASL authentication
step
I0601 18:08:28.505955 4523 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '7950289a8145' server FQDN: '7950289a8145'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0601 18:08:28.505980 4523 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0601 18:08:28.506036 4523 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0601 18:08:28.506069 4523 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '7950289a8145' server FQDN: '7950289a8145'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0601 18:08:28.506091 4523 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0601 18:08:28.506111 4523 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0601 18:08:28.506150 4523 authenticator.cpp:318] Authentication success
I0601 18:08:28.506660 4522 authenticatee.cpp:299] Authentication success
I0601 18:08:28.506726 4525 master.cpp:7452] Successfully authenticated
principal 'test-principal' at slave(746)@172.17.0.3:35323
I0601 18:08:28.506929 4522 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1390)@172.17.0.3:35323
I0601 18:08:28.507712 4525 slave.cpp:1102] Successfully authenticated with
master [email protected]:35323
I0601 18:08:28.509920 4525 slave.cpp:1522] Will retry registration in
13.278113ms if necessary
I0601 18:08:28.510025 4521 master.cpp:5425] Received register agent message
from slave(746)@172.17.0.3:35323 (7950289a8145)
I0601 18:08:28.510427 4521 master.cpp:3657] Authorizing agent with principal
'test-principal'
I0601 18:08:28.511221 4522 master.cpp:5485] Authorized registration of agent
at slave(746)@172.17.0.3:35323 (7950289a8145)
I0601 18:08:28.511561 4522 master.cpp:5564] Registering agent at
slave(746)@172.17.0.3:35323 (7950289a8145) with id
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0
I0601 18:08:28.512382 4520 registrar.cpp:493] Applied 1 operations in 91109ns;
attempting to update the registry
I0601 18:08:28.513365 4520 registrar.cpp:550] Successfully updated the
registry in 873216ns
I0601 18:08:28.513797 4522 master.cpp:5611] Admitted agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145)
I0601 18:08:28.514969 4520 slave.cpp:4825] Received ping from
slave-observer(681)@172.17.0.3:35323
I0601 18:08:28.515163 4520 slave.cpp:1148] Registered with master
[email protected]:35323; given agent ID b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0
I0601 18:08:28.515084 4522 master.cpp:5642] Registered agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0601 18:08:28.515633 4520 slave.cpp:1168] Checkpointing SlaveInfo to
'/tmp/ContentType_SchedulerTest_Message_1_hwz1jj/meta/slaves/b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0/slave.info'
I0601 18:08:28.516218 4520 slave.cpp:1206] Forwarding total oversubscribed
resources {}
I0601 18:08:28.516491 4520 status_update_manager.cpp:184] Resuming sending
status updates
I0601 18:08:28.516705 4522 hierarchical.cpp:546] Added agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 (7950289a8145) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0601 18:08:28.516753 4520 master.cpp:6295] Received update of agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145) with total oversubscribed resources {}
I0601 18:08:28.520128 4522 hierarchical.cpp:1955] No inverse offers to send
out!
I0601 18:08:28.523614 4518 master.cpp:7252] Sending 1 offers to framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default)
I0601 18:08:28.530716 4522 hierarchical.cpp:1447] Performed allocation for 1
agents in 3.027712ms
I0601 18:08:28.532006 4518 scheduler.cpp:676] Enqueuing event OFFERS received
from http://172.17.0.3:35323/master/api/v1/scheduler
I0601 18:08:28.536646 4520 scheduler.cpp:243] Sending ACCEPT call to
http://172.17.0.3:35323/master/api/v1/scheduler
I0601 18:08:28.542707 4524 process.cpp:3778] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0601 18:08:28.545990 4523 http.cpp:1115] HTTP POST for
/master/api/v1/scheduler from 172.17.0.3:35582
I0601 18:08:28.550138 4523 master.cpp:3872] Processing ACCEPT call for offers:
[ b14cd2bf-6dff-4250-b14c-636a8f749d4f-O0 ] on agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145) for framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default)
I0601 18:08:28.550338 4523 master.cpp:3424] Authorizing framework principal
'test-principal' to launch task 58386335-9c0d-4133-860d-dffcd40f6eb3
W0601 18:08:28.555577 4525 validation.cpp:1284] Executor 'default' for task
'58386335-9c0d-4133-860d-dffcd40f6eb3' uses less CPUs (None) than the minimum
required (0.01). Please update your executor, as this will be mandatory in
future releases.
W0601 18:08:28.555676 4525 validation.cpp:1296] Executor 'default' for task
'58386335-9c0d-4133-860d-dffcd40f6eb3' uses less memory (None) than the minimum
required (32MB). Please update your executor, as this will be mandatory in
future releases.
I0601 18:08:28.557003 4525 master.cpp:9265] Adding task
58386335-9c0d-4133-860d-dffcd40f6eb3 with resources cpus(*)(allocated: *):2;
mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated:
*):[31000-32000] on agent b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at
slave(746)@172.17.0.3:35323 (7950289a8145)
I0601 18:08:28.558053 4525 master.cpp:4527] Launching task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default) with resources
cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024;
ports(*)(allocated: *):[31000-32000] on agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145)
I0601 18:08:28.561712 4525 slave.cpp:1632] Got assigned task
'58386335-9c0d-4133-860d-dffcd40f6eb3' for framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.563169 4525 slave.cpp:1913] Authorizing task
'58386335-9c0d-4133-860d-dffcd40f6eb3' for framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.563429 4525 slave.cpp:6618] Authorizing framework principal
'test-principal' to launch task 58386335-9c0d-4133-860d-dffcd40f6eb3
I0601 18:08:28.566155 4518 slave.cpp:2100] Launching task
'58386335-9c0d-4133-860d-dffcd40f6eb3' for framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.566228 4522 hierarchical.cpp:871] Updated allocation of
framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 on agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-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]
I0601 18:08:28.567402 4518 paths.cpp:573] Trying to chown
'/tmp/ContentType_SchedulerTest_Message_1_hwz1jj/slaves/b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0/frameworks/b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000/executors/default/runs/51bc34a4-baa7-434c-9fe3-31a72b4649fc'
to user 'mesos'
I0601 18:08:28.567997 4518 slave.cpp:7078] Launching executor 'default' of
framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 with resources {} in work
directory
'/tmp/ContentType_SchedulerTest_Message_1_hwz1jj/slaves/b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0/frameworks/b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000/executors/default/runs/51bc34a4-baa7-434c-9fe3-31a72b4649fc'
I0601 18:08:28.570683 4518 slave.cpp:2795] Launching container
51bc34a4-baa7-434c-9fe3-31a72b4649fc for executor 'default' of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.571681 4518 slave.cpp:2329] Queued task
'58386335-9c0d-4133-860d-dffcd40f6eb3' for executor 'default' of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.572181 4518 slave.cpp:898] Successfully attached file
'/tmp/ContentType_SchedulerTest_Message_1_hwz1jj/slaves/b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0/frameworks/b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000/executors/default/runs/51bc34a4-baa7-434c-9fe3-31a72b4649fc'
I0601 18:08:28.575840 4519 executor.cpp:192] Version: 1.4.0
I0601 18:08:28.583991 4521 executor.cpp:410] Connected with the agent
I0601 18:08:28.585302 4521 executor.cpp:307] Sending SUBSCRIBE call to
http://172.17.0.3:35323/slave(746)/api/v1/executor
I0601 18:08:28.593068 4521 process.cpp:3778] Handling HTTP event for process
'slave(746)' with path: '/slave(746)/api/v1/executor'
I0601 18:08:28.595671 4523 http.cpp:1115] HTTP POST for
/slave(746)/api/v1/executor from 172.17.0.3:35586
I0601 18:08:28.596276 4523 slave.cpp:3609] Received Subscribe request for HTTP
executor 'default' of framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.598861 4522 slave.cpp:2542] Sending queued task
'58386335-9c0d-4133-860d-dffcd40f6eb3' to executor 'default' of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (via HTTP)
I0601 18:08:28.603744 4520 executor.cpp:725] Enqueuing event SUBSCRIBED
received from http://172.17.0.3:35323/slave(746)/api/v1/executor
I0601 18:08:28.607478 4520 executor.cpp:725] Enqueuing event LAUNCH received
from http://172.17.0.3:35323/slave(746)/api/v1/executor
I0601 18:08:28.609509 4520 executor.cpp:307] Sending UPDATE call to
http://172.17.0.3:35323/slave(746)/api/v1/executor
I0601 18:08:28.613356 4524 process.cpp:3778] Handling HTTP event for process
'slave(746)' with path: '/slave(746)/api/v1/executor'
I0601 18:08:28.615995 4524 http.cpp:1115] HTTP POST for
/slave(746)/api/v1/executor from 172.17.0.3:35588
I0601 18:08:28.616350 4524 slave.cpp:4295] Handling status update TASK_RUNNING
(UUID: d21235f9-9f7a-44b4-b258-c94b5051cb06) for task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.620578 4525 status_update_manager.cpp:323] Received status
update TASK_RUNNING (UUID: d21235f9-9f7a-44b4-b258-c94b5051cb06) for task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.620689 4525 status_update_manager.cpp:500] Creating
StatusUpdate stream for task 58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.621381 4525 status_update_manager.cpp:377] Forwarding update
TASK_RUNNING (UUID: d21235f9-9f7a-44b4-b258-c94b5051cb06) for task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 to the agent
I0601 18:08:28.622109 4525 slave.cpp:4735] Forwarding the update TASK_RUNNING
(UUID: d21235f9-9f7a-44b4-b258-c94b5051cb06) for task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 to [email protected]:35323
I0601 18:08:28.622472 4525 slave.cpp:4629] Status update manager successfully
handled status update TASK_RUNNING (UUID: d21235f9-9f7a-44b4-b258-c94b5051cb06)
for task 58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.623219 4524 master.cpp:6440] Status update TASK_RUNNING (UUID:
d21235f9-9f7a-44b4-b258-c94b5051cb06) for task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 from agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145)
I0601 18:08:28.623384 4524 master.cpp:6502] Forwarding status update
TASK_RUNNING (UUID: d21235f9-9f7a-44b4-b258-c94b5051cb06) for task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.625010 4524 master.cpp:8507] Updating the state of task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (latest state: TASK_RUNNING, status
update state: TASK_RUNNING)
I0601 18:08:28.627601 4524 executor.cpp:725] Enqueuing event ACKNOWLEDGED
received from http://172.17.0.3:35323/slave(746)/api/v1/executor
I0601 18:08:28.629444 4524 scheduler.cpp:676] Enqueuing event UPDATE received
from http://172.17.0.3:35323/master/api/v1/scheduler
I0601 18:08:28.632684 4518 scheduler.cpp:243] Sending MESSAGE call to
http://172.17.0.3:35323/master/api/v1/scheduler
I0601 18:08:28.636299 4518 process.cpp:3778] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0601 18:08:28.687149 4523 http.cpp:1115] HTTP POST for
/master/api/v1/scheduler from 172.17.0.3:35582
I0601 18:08:28.688026 4523 master.cpp:5358] Processing MESSAGE call from
framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default) to agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145)
I0601 18:08:28.692895 4519 executor.cpp:725] Enqueuing event MESSAGE received
from http://172.17.0.3:35323/slave(746)/api/v1/executor
I0601 18:08:28.697811 4519 master.cpp:1428] Framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default) disconnected
I0601 18:08:28.697877 4519 master.cpp:3158] Deactivating framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default)
I0601 18:08:28.698240 4519 master.cpp:3135] Disconnecting framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default)
I0601 18:08:28.698312 4519 master.cpp:1443] Giving framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default) 0ns to failover
I0601 18:08:28.698676 4523 hierarchical.cpp:395] Deactivated framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.702301 4519 master.cpp:7093] Framework failover timeout,
removing framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default)
I0601 18:08:28.702365 4519 master.cpp:7939] Removing framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (default)
I0601 18:08:28.702865 4519 master.cpp:8507] Updating the state of task
58386335-9c0d-4133-860d-dffcd40f6eb3 of framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (latest state: TASK_KILLED, status
update state: TASK_KILLED)
I0601 18:08:28.702996 4523 slave.cpp:3098] Asked to shut down framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 by [email protected]:35323
I0601 18:08:28.703083 4523 slave.cpp:3123] Shutting down framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.703153 4523 slave.cpp:5582] Shutting down executor 'default' of
framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 (via HTTP)
I0601 18:08:28.704483 4519 master.cpp:8601] Removing task
58386335-9c0d-4133-860d-dffcd40f6eb3 with resources cpus(*)(allocated: *):2;
mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated:
*):[31000-32000] of framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 on
agent b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145)
I0601 18:08:28.705096 4519 master.cpp:8630] Removing executor 'default' with
resources {} of framework b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000 on agent
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 at slave(746)@172.17.0.3:35323
(7950289a8145)
I0601 18:08:28.707036 4521 hierarchical.cpp:1135] 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
b14cd2bf-6dff-4250-b14c-636a8f749d4f-S0 from framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.707602 4521 hierarchical.cpp:346] Removed framework
b14cd2bf-6dff-4250-b14c-636a8f749d4f-0000
I0601 18:08:28.710001 4519 executor.cpp:725] Enqueuing event SHUTDOWN received
from http://172.17.0.3:35323/slave(746)/api/v1/executor
*** Aborted at 1496340508 (unix time) try "date -d @1496340508" if you are
using GNU date ***
PC: @ 0x55a2a2c9d52c
testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
*** SIGSEGV (@0x0) received by PID 4499 (TID 0x2b39186ac700) from PID 0; stack
trace: ***
@ 0x2b391b878417 (unknown)
@ 0x2b391b87c6f9 (unknown)
@ 0x2b390fc01330 (unknown)
@ 0x55a2a2c9d52c
testing::internal::UntypedFunctionMockerBase::SetOwnerAndName()
@ 0x55a2a1be04e5
mesos::internal::tests::executor::MockHTTPExecutor<>::shutdown()
@ 0x55a2a1bd9ad7
mesos::internal::tests::executor::MockHTTPExecutor<>::event()
@ 0x55a2a1bd2ea2 mesos::internal::tests::executor::TestMesos<>::events()
@ 0x55a2a1bf603d std::_Mem_fn<>::operator()<>()
@ 0x55a2a1bea151
_ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS1_2v18executor5MesosENS7_5EventEEEFvSt5queueIS9_St5dequeIS9_SaIS9_EEEEEPSA_St12_PlaceholderILi1EEEE6__callIvIRKSF_EILm0ELm1EEEET_OSt5tupleIIDpT0_EESt12_Index_tupleIIXspT1_EEE
@ 0x55a2a1be08e3
_ZNSt5_BindIFSt7_Mem_fnIMN5mesos8internal5tests8executor9TestMesosINS1_2v18executor5MesosENS7_5EventEEEFvSt5queueIS9_St5dequeIS9_SaIS9_EEEEEPSA_St12_PlaceholderILi1EEEEclIJRKSF_EvEET0_DpOT_
@ 0x55a2a1bd9e9d std::_Function_handler<>::_M_invoke()
@ 0x2b390c231123 std::function<>::operator()()
@ 0x2b390c22ab7f process::AsyncExecutorProcess::execute<>()
@ 0x2b390c22acc5
_ZZN7process8dispatchI7NothingNS_20AsyncExecutorProcessERKSt8functionIFvRKSt5queueIN5mesos2v18executor5EventESt5dequeIS8_SaIS8_EEEEESC_SG_SC_EENS_6FutureIT_EERKNS_3PIDIT0_EEMSN_FSK_T1_T2_ET3_T4_ENKUlPNS_11ProcessBaseEE_clESY_
@ 0x2b390c23763c
_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_
@ 0x2b390d765aa9 std::function<>::operator()()
@ 0x2b390d74af05 process::ProcessBase::visit()
@ 0x2b390d7558e6 process::DispatchEvent::visit()
@ 0x55a2a17be492 process::ProcessBase::serve()
@ 0x2b390d7471c6 process::ProcessManager::resume()
@ 0x2b390d74339a _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x2b390d754ff2
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b390d754f49
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x2b390d754ee2
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2b390f482a60 (unknown)
@ 0x2b390fbf9184 start_thread
@ 0x2b390ff0cbed (unknown)
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1496333798-31916
Untagged: mesos-1496333798-31916:latest
Deleted: sha256:d2fb2c3c7f9f15df9a55353f2204f52df91115168dfebe6cf216a9da3717731d
Deleted: sha256:5983e0bfd16af9c6fd989349d3203aaeb5b4e5c130d0f62de71877b4b168b69a
Deleted: sha256:bf648b1340faa85f93d3dce8fac06bd6e33bc41a6fc90b335947e58dd27a999c
Deleted: sha256:f13f7e40149fde25ad9cccf9dc72d81f557be4ceed1a6e30ca4086610fa0ec53
Deleted: sha256:d6f57869b2fe3a8f16d3f60c1debd2ffd39f94c44b377ead28b420c5d770b649
Deleted: sha256:94ee489a3f58749ca5002e398d5795045c6b7c34e54b868ff51a2f3fcccbce27
Deleted: sha256:dc87a3f3892ac0fa6d00977a244a53a50977e4b57141430ac5740a20bbc8af7f
Deleted: sha256:d933bf0310f59adc3cb6974ab5446711763b8524d6fa8eb8dd4a30239e6c76dc
Deleted: sha256:873213e2901e268d6de7a2bc98ed01f822d7b69c1686d9c3480c80fe975eef5a
Deleted: sha256:a3cec1b0223d07a47b2908c25c3724c9f709314446edd0be4bebf2fc4df67976
Deleted: sha256:f351b7d468cfdbb48fb5001bdd81ca9da699311462406da9ef8140d57f7143e3
Deleted: sha256:4727a8b2f7515e63e7ab8fc2d8fb8319d15402dd89e73f3c6430defbfce85fbc
Deleted: sha256:7fe3bce1b824f1eefb75a44a7ef368d87d99553f81e11526689455e4703e0a49
Deleted: sha256:4295923ed65ccc1ce028460efc88815141d80d9fbdcfe527399cff8c1c12fb3d
Deleted: sha256:6a4287140169a7989c5aa98e38d56d98d50b5aaeeafcd0fec14b995f7105105f
Deleted: sha256:51e88f7d130e24706e48cd4d43404c3e94268db080f8018844c512bac0738ded
Deleted: sha256:13345d9d581a069b7bdebb533c39da03b9244f6a2f8419b07c01e570d5004a96
Deleted: sha256:f23c31463cc01759c71def773872575c8ee5f445068ac14652f5e4c501c63ab9
Deleted: sha256:fd8bc914017812da9c91f2e6bab9062803396f6017ff890694b5c3674db0aca3
Deleted: sha256:9c6fc47de8f43c6abcc94ebc2b7970cfe30b830c0a7967b2e6f24b8728f20381
Deleted: sha256:c10f0fe641b1d77a3e792913aa12455b31d30725f5496bca8d54a35b65b03fd2
Deleted: sha256:7ce71e022f5d3fc4e8370e3fd59ae55b400a3281543247d3a0824aca7cbf469a
Deleted: sha256:dfd79b8469a3429f3a8e097f5943cacfce12845d5b485356111268a908679913
Deleted: sha256:136de2813b89b051907a7970bb4108b9e82d631f6c80e89d960f9383ad9bc06b
Build step 'Execute shell' marked build as failure
