See <https://builds.apache.org/job/Mesos-Reviewbot/17363/display/redirect>
------------------------------------------
[...truncated 10.48 MB...]
I0309 03:35:58.812037 30431 sched.cpp:1041] Scheduler::statusUpdate took
144810ns
I0309 03:35:58.812723 30432 master.cpp:5167] Processing ACKNOWLEDGE call
cb397083-1433-4c4f-a621-0ddc103c592f for task 1 of framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 (default) at
scheduler-672db73e-23d6-4db1-8d32-fd7713d252a0@172.17.0.2:38040 on agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0
I0309 03:35:58.813171 30433 status_update_manager.cpp:395] Received status
update acknowledgement (UUID: cb397083-1433-4c4f-a621-0ddc103c592f) for task 1
of framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.813395 30435 exec.cpp:396] Executor received framework message
I0309 03:35:58.813499 30435 exec.cpp:405] Executor::frameworkMessage took
73569ns
I0309 03:35:58.813515 30433 slave.cpp:3106] Status update manager successfully
handled status update acknowledgement (UUID:
cb397083-1433-4c4f-a621-0ddc103c592f) for task 1 of framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.814260 30439 slave.cpp:4325] Sending message for framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 to
scheduler-672db73e-23d6-4db1-8d32-fd7713d252a0@172.17.0.2:38040
I0309 03:35:58.814492 30433 sched.cpp:1177] Scheduler::frameworkMessage took
71091ns
I0309 03:35:58.814947 30412 sched.cpp:2021] Asked to stop the driver
I0309 03:35:58.815050 30437 sched.cpp:1203] Stopping framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.815279 30439 master.cpp:7781] Processing TEARDOWN call for
framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 (default) at
scheduler-672db73e-23d6-4db1-8d32-fd7713d252a0@172.17.0.2:38040
I0309 03:35:58.815325 30439 master.cpp:7793] Removing framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 (default) at
scheduler-672db73e-23d6-4db1-8d32-fd7713d252a0@172.17.0.2:38040
I0309 03:35:58.815351 30439 master.cpp:3164] Deactivating framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 (default) at
scheduler-672db73e-23d6-4db1-8d32-fd7713d252a0@172.17.0.2:38040
I0309 03:35:58.815888 30440 hierarchical.cpp:378] Deactivated framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.816076 30439 master.cpp:8361] Updating the state of task 1 of
framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 (latest state: TASK_KILLED,
status update state: TASK_KILLED)
I0309 03:35:58.816656 30441 slave.cpp:2664] Asked to shut down framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 by master@172.17.0.2:38040
I0309 03:35:58.816743 30441 slave.cpp:2689] Shutting down framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.816814 30441 slave.cpp:5084] Shutting down executor 'default' of
framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 at
executor(74)@172.17.0.2:38040
I0309 03:35:58.816969 30435 exec.cpp:415] Executor asked to shutdown
I0309 03:35:58.817023 30435 exec.cpp:430] Executor::shutdown took 26495ns
I0309 03:35:58.817378 30431 hierarchical.cpp:1110] 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
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0 from framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.817612 30433 slave.cpp:4389] Got exited event for
executor(74)@172.17.0.2:38040
I0309 03:35:58.817698 30439 master.cpp:8455] Removing task 1 with resources
cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024;
ports(*)(allocated: *):[31000-32000] of framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 on agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0 at slave(179)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.817957 30444 slave.cpp:4770] Executor 'default' of framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 exited with status 0
I0309 03:35:58.818076 30444 slave.cpp:4870] Cleaning up executor 'default' of
framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 at
executor(74)@172.17.0.2:38040
I0309 03:35:58.818282 30439 master.cpp:8484] Removing executor 'default' with
resources {} of framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 on agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0 at slave(179)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.818727 30440 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_o9Ut4Y/slaves/1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0/frameworks/1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000/executors/default/runs/7c098975-c8b1-46b5-968e-c9749fbe9fbc'
for gc 6.99999052906074days in the future
I0309 03:35:58.819087 30444 slave.cpp:4958] Cleaning up framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.819098 30433 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_o9Ut4Y/slaves/1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0/frameworks/1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000/executors/default'
for gc 6.99999052292741days in the future
W0309 03:35:58.819088 30439 master.cpp:6383] Ignoring unknown exited executor
'default' of framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000 on agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0 at slave(179)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.819283 30441 status_update_manager.cpp:285] Closing status
update streams for framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.819337 30441 status_update_manager.cpp:531] Cleaning up status
update stream for task 1 of framework 1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.819411 30443 hierarchical.cpp:325] Removed framework
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000
I0309 03:35:58.819461 30444 slave.cpp:808] Agent terminating
I0309 03:35:58.819450 30437 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_o9Ut4Y/slaves/1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0/frameworks/1f090b74-0b0f-4ad6-8f21-dfb107e93a36-0000'
for gc 6.99999051695704days in the future
I0309 03:35:58.819871 30431 master.cpp:1318] Agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0 at slave(179)@172.17.0.2:38040
(e9d09efbd686) disconnected
I0309 03:35:58.819907 30431 master.cpp:3201] Disconnecting agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0 at slave(179)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.819979 30431 master.cpp:3220] Deactivating agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0 at slave(179)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.820137 30437 hierarchical.cpp:657] Agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0 deactivated
I0309 03:35:58.823897 30437 master.cpp:1157] Master terminating
I0309 03:35:58.825001 30434 hierarchical.cpp:562] Removed agent
1f090b74-0b0f-4ad6-8f21-dfb107e93a36-S0
[ OK ] MasterTest.FrameworkMessage (78 ms)
[ RUN ] MasterTest.MultipleExecutors
I0309 03:35:58.834087 30412 cluster.cpp:160] Creating default 'local' authorizer
I0309 03:35:58.837430 30445 master.cpp:437] Master
5e732c69-807c-4580-b719-c59adebc981d (e9d09efbd686) started on 172.17.0.2:38040
I0309 03:35:58.837466 30445 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/EtoJ8n/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" --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.3.0/_inst/share/mesos/webui"
--work_dir="/tmp/EtoJ8n/master" --zk_session_timeout="10secs"
I0309 03:35:58.837947 30445 master.cpp:489] Master only allowing authenticated
frameworks to register
I0309 03:35:58.837971 30445 master.cpp:503] Master only allowing authenticated
agents to register
I0309 03:35:58.837988 30445 master.cpp:516] Master only allowing authenticated
HTTP frameworks to register
I0309 03:35:58.838003 30445 credentials.hpp:37] Loading credentials for
authentication from '/tmp/EtoJ8n/credentials'
I0309 03:35:58.838415 30445 master.cpp:561] Using default 'crammd5'
authenticator
I0309 03:35:58.838587 30445 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0309 03:35:58.838810 30445 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0309 03:35:58.838984 30445 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0309 03:35:58.839128 30445 master.cpp:641] Authorization enabled
I0309 03:35:58.839344 30437 whitelist_watcher.cpp:77] No whitelist given
I0309 03:35:58.839382 30431 hierarchical.cpp:161] Initialized hierarchical
allocator process
I0309 03:35:58.842109 30439 master.cpp:2181] Elected as the leading master!
I0309 03:35:58.842170 30439 master.cpp:1705] Recovering from registrar
I0309 03:35:58.842319 30437 registrar.cpp:331] Recovering registrar
I0309 03:35:58.843258 30437 registrar.cpp:364] Successfully fetched the
registry (0B) in 862976ns
I0309 03:35:58.843390 30437 registrar.cpp:463] Applied 1 operations in 22909ns;
attempting to update the registry
I0309 03:35:58.844820 30437 registrar.cpp:508] Successfully updated the
registry in 1.347072ms
I0309 03:35:58.844990 30437 registrar.cpp:394] Successfully recovered registrar
I0309 03:35:58.845834 30441 master.cpp:1819] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0309 03:35:58.845865 30437 hierarchical.cpp:188] Skipping recovery of
hierarchical allocator: nothing to recover
I0309 03:35:58.851658 30412 cluster.cpp:446] Creating default 'local' authorizer
I0309 03:35:58.853703 30437 slave.cpp:215] Mesos agent started on
(180)@172.17.0.2:38040
I0309 03:35:58.853737 30437 slave.cpp:216] Flags at startup: --acls=""
--appc_simple_discovery_uri_prefix="http://";
--appc_store_dir="/tmp/mesos/store/appc" --authenticate_http_readonly="true"
--authenticate_http_readwrite="true" --authenticatee="crammd5"
--authentication_backoff_factor="1secs" --authorizer="local"
--cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false"
--cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs"
--containerizers="mesos"
--credential="/tmp/MasterTest_MultipleExecutors_M9JxYA/credential"
--default_role="*" --disk_watch_interval="1mins" --docker="docker"
--docker_kill_orphans="true" --docker_registry="https://registry-1.docker.io";
--docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock"
--docker_stop_timeout="0ns" --docker_store_dir="/tmp/mesos/store/docker"
--docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume"
--enforce_container_disk_quota="false" --executor_registration_timeout="1mins"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_M9JxYA/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_authenticators="basic"
--http_command_executor="false"
--http_credentials="/tmp/MasterTest_MultipleExecutors_M9JxYA/http_credentials"
--http_heartbeat_interval="30secs" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher="posix"
--launcher_dir="/mesos/mesos-1.3.0/_build/src" --logbufsecs="0"
--logging_level="INFO" --max_completed_executors_per_framework="150"
--oversubscribed_resources_interval="15secs" --perf_duration="10secs"
--perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false"
--recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/MasterTest_MultipleExecutors_M9JxYA"
--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/MasterTest_MultipleExecutors_RlNCyu"
I0309 03:35:58.854564 30437 credentials.hpp:86] Loading credential for
authentication from '/tmp/MasterTest_MultipleExecutors_M9JxYA/credential'
I0309 03:35:58.854858 30437 slave.cpp:358] Agent using credential for:
test-principal
I0309 03:35:58.854892 30437 credentials.hpp:37] Loading credentials for
authentication from '/tmp/MasterTest_MultipleExecutors_M9JxYA/http_credentials'
I0309 03:35:58.855231 30437 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0309 03:35:58.855415 30412 sched.cpp:232] Version: 1.3.0
I0309 03:35:58.855623 30437 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0309 03:35:58.856184 30440 sched.cpp:336] New master detected at
master@172.17.0.2:38040
I0309 03:35:58.856293 30440 sched.cpp:407] Authenticating with master
master@172.17.0.2:38040
I0309 03:35:58.856318 30440 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0309 03:35:58.856631 30438 authenticatee.cpp:121] Creating new client SASL
connection
I0309 03:35:58.856942 30445 master.cpp:7268] Authenticating
scheduler-bbf6a026-e862-4f3e-b5ea-a2395a1a3623@172.17.0.2:38040
I0309 03:35:58.857084 30431 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(412)@172.17.0.2:38040
I0309 03:35:58.857372 30438 authenticator.cpp:98] Creating new server SASL
connection
I0309 03:35:58.857347 30437 slave.cpp:545] Agent resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 03:35:58.857456 30437 slave.cpp:553] Agent attributes: [ ]
I0309 03:35:58.857475 30437 slave.cpp:558] Agent hostname: e9d09efbd686
I0309 03:35:58.857651 30439 status_update_manager.cpp:177] Pausing sending
status updates
I0309 03:35:58.857772 30444 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0309 03:35:58.857836 30444 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0309 03:35:58.857980 30432 authenticator.cpp:204] Received SASL authentication
start
I0309 03:35:58.858062 30432 authenticator.cpp:326] Authentication requires more
steps
I0309 03:35:58.858184 30444 authenticatee.cpp:259] Received SASL authentication
step
I0309 03:35:58.858646 30442 authenticator.cpp:232] Received SASL authentication
step
I0309 03:35:58.858683 30442 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'e9d09efbd686' server FQDN: 'e9d09efbd686'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0309 03:35:58.858705 30442 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0309 03:35:58.858762 30442 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0309 03:35:58.858798 30442 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'e9d09efbd686' server FQDN: 'e9d09efbd686'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0309 03:35:58.858820 30442 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0309 03:35:58.858841 30442 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0309 03:35:58.858875 30442 authenticator.cpp:318] Authentication success
I0309 03:35:58.859129 30431 master.cpp:7298] Successfully authenticated
principal 'test-principal' at
scheduler-bbf6a026-e862-4f3e-b5ea-a2395a1a3623@172.17.0.2:38040
I0309 03:35:58.859192 30432 authenticatee.cpp:299] Authentication success
I0309 03:35:58.859356 30433 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(412)@172.17.0.2:38040
I0309 03:35:58.859552 30446 state.cpp:62] Recovering state from
'/tmp/MasterTest_MultipleExecutors_RlNCyu/meta'
I0309 03:35:58.859781 30435 sched.cpp:513] Successfully authenticated with
master master@172.17.0.2:38040
I0309 03:35:58.859820 30435 sched.cpp:836] Sending SUBSCRIBE call to
master@172.17.0.2:38040
I0309 03:35:58.860067 30435 sched.cpp:869] Will retry registration in
1.517202857secs if necessary
I0309 03:35:58.860110 30433 status_update_manager.cpp:203] Recovering status
update manager
I0309 03:35:58.860291 30440 master.cpp:2833] Received SUBSCRIBE call for
framework 'default' at
scheduler-bbf6a026-e862-4f3e-b5ea-a2395a1a3623@172.17.0.2:38040
I0309 03:35:58.860394 30440 master.cpp:2217] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0309 03:35:58.860632 30436 slave.cpp:5572] Finished recovery
I0309 03:35:58.860967 30440 master.cpp:2910] Subscribing framework default with
checkpointing disabled and capabilities [ ]
I0309 03:35:58.861155 30436 slave.cpp:5754] Querying resource estimator for
oversubscribable resources
I0309 03:35:58.861512 30441 slave.cpp:936] New master detected at
master@172.17.0.2:38040
I0309 03:35:58.861526 30438 status_update_manager.cpp:177] Pausing sending
status updates
I0309 03:35:58.861621 30446 sched.cpp:759] Framework registered with
5e732c69-807c-4580-b719-c59adebc981d-0000
I0309 03:35:58.861670 30446 sched.cpp:773] Scheduler::registered took 21396ns
I0309 03:35:58.861676 30441 slave.cpp:971] Detecting new master
I0309 03:35:58.861732 30439 hierarchical.cpp:273] Added framework
5e732c69-807c-4580-b719-c59adebc981d-0000
I0309 03:35:58.861891 30439 hierarchical.cpp:1856] No allocations performed
I0309 03:35:58.861941 30439 hierarchical.cpp:1946] No inverse offers to send
out!
I0309 03:35:58.862013 30439 hierarchical.cpp:1440] Performed allocation for 0
agents in 169130ns
I0309 03:35:58.862076 30441 slave.cpp:5768] Received oversubscribable resources
{} from the resource estimator
I0309 03:35:58.871119 30444 slave.cpp:998] Authenticating with master
master@172.17.0.2:38040
I0309 03:35:58.871260 30444 slave.cpp:1009] Using default CRAM-MD5 authenticatee
I0309 03:35:58.871520 30445 authenticatee.cpp:121] Creating new client SASL
connection
I0309 03:35:58.871860 30434 master.cpp:7268] Authenticating
slave(180)@172.17.0.2:38040
I0309 03:35:58.871981 30445 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(413)@172.17.0.2:38040
I0309 03:35:58.872287 30446 authenticator.cpp:98] Creating new server SASL
connection
I0309 03:35:58.872544 30441 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0309 03:35:58.872627 30441 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0309 03:35:58.872766 30432 authenticator.cpp:204] Received SASL authentication
start
I0309 03:35:58.872856 30432 authenticator.cpp:326] Authentication requires more
steps
I0309 03:35:58.872968 30432 authenticatee.cpp:259] Received SASL authentication
step
I0309 03:35:58.873191 30445 authenticator.cpp:232] Received SASL authentication
step
I0309 03:35:58.873227 30445 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'e9d09efbd686' server FQDN: 'e9d09efbd686'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0309 03:35:58.873242 30445 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0309 03:35:58.873278 30445 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0309 03:35:58.873301 30445 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'e9d09efbd686' server FQDN: 'e9d09efbd686'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0309 03:35:58.873316 30445 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0309 03:35:58.873325 30445 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0309 03:35:58.873345 30445 authenticator.cpp:318] Authentication success
I0309 03:35:58.873435 30441 authenticatee.cpp:299] Authentication success
I0309 03:35:58.873514 30436 master.cpp:7298] Successfully authenticated
principal 'test-principal' at slave(180)@172.17.0.2:38040
I0309 03:35:58.873615 30435 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(413)@172.17.0.2:38040
I0309 03:35:58.873983 30436 slave.cpp:1093] Successfully authenticated with
master master@172.17.0.2:38040
I0309 03:35:58.874253 30436 slave.cpp:1521] Will retry registration in
15.064851ms if necessary
I0309 03:35:58.874539 30439 master.cpp:5453] Registering agent at
slave(180)@172.17.0.2:38040 (e9d09efbd686) with id
5e732c69-807c-4580-b719-c59adebc981d-S0
I0309 03:35:58.875087 30443 registrar.cpp:463] Applied 1 operations in 75327ns;
attempting to update the registry
I0309 03:35:58.876924 30440 registrar.cpp:508] Successfully updated the
registry in 1.75488ms
I0309 03:35:58.877966 30440 slave.cpp:4347] Received ping from
slave-observer(178)@172.17.0.2:38040
I0309 03:35:58.878286 30442 slave.cpp:1139] Registered with master
master@172.17.0.2:38040; given agent ID 5e732c69-807c-4580-b719-c59adebc981d-S0
I0309 03:35:58.878319 30442 fetcher.cpp:94] Clearing fetcher cache
I0309 03:35:58.878204 30434 master.cpp:5527] Registered agent
5e732c69-807c-4580-b719-c59adebc981d-S0 at slave(180)@172.17.0.2:38040
(e9d09efbd686) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0309 03:35:58.878505 30433 status_update_manager.cpp:184] Resuming sending
status updates
I0309 03:35:58.878608 30436 hierarchical.cpp:529] Added agent
5e732c69-807c-4580-b719-c59adebc981d-S0 (e9d09efbd686) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0309 03:35:58.878808 30442 slave.cpp:1167] Checkpointing SlaveInfo to
'/tmp/MasterTest_MultipleExecutors_RlNCyu/meta/slaves/5e732c69-807c-4580-b719-c59adebc981d-S0/slave.info'
I0309 03:35:58.879235 30442 slave.cpp:1205] Forwarding total oversubscribed
resources {}
I0309 03:35:58.879423 30442 master.cpp:6114] Received update of agent
5e732c69-807c-4580-b719-c59adebc981d-S0 at slave(180)@172.17.0.2:38040
(e9d09efbd686) with total oversubscribed resources {}
I0309 03:35:58.880302 30436 hierarchical.cpp:1946] No inverse offers to send
out!
I0309 03:35:58.880378 30436 hierarchical.cpp:1440] Performed allocation for 1
agents in 1.585749ms
I0309 03:35:58.880959 30438 master.cpp:7097] Sending 1 offers to framework
5e732c69-807c-4580-b719-c59adebc981d-0000 (default) at
scheduler-bbf6a026-e862-4f3e-b5ea-a2395a1a3623@172.17.0.2:38040
I0309 03:35:58.881559 30432 sched.cpp:933] Scheduler::resourceOffers took
145358ns
I0309 03:35:58.884390 30439 master.cpp:3855] Processing ACCEPT call for offers:
[ 5e732c69-807c-4580-b719-c59adebc981d-O0 ] on agent
5e732c69-807c-4580-b719-c59adebc981d-S0 at slave(180)@172.17.0.2:38040
(e9d09efbd686) for framework 5e732c69-807c-4580-b719-c59adebc981d-0000
(default) at scheduler-bbf6a026-e862-4f3e-b5ea-a2395a1a3623@172.17.0.2:38040
I0309 03:35:58.884523 30439 master.cpp:3431] Authorizing framework principal
'test-principal' to launch task 1
I0309 03:35:58.884856 30439 master.cpp:3431] Authorizing framework principal
'test-principal' to launch task 2
W0309 03:35:58.887576 30439 validation.cpp:1113] Executor 'executor-1' for task
'1' uses less CPUs (None) than the minimum required (0.01). Please update your
executor, as this will be mandatory in future releases.
W0309 03:35:58.887621 30439 validation.cpp:1125] Executor 'executor-1' for task
'1' uses less memory (None) than the minimum required (32MB). Please update
your executor, as this will be mandatory in future releases.
I0309 03:35:58.888058 30439 master.cpp:9113] Adding task 1 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
5e732c69-807c-4580-b719-c59adebc981d-S0 at slave(180)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.888275 30439 master.cpp:4505] Launching task 1 of framework
5e732c69-807c-4580-b719-c59adebc981d-0000 (default) at
scheduler-bbf6a026-e862-4f3e-b5ea-a2395a1a3623@172.17.0.2:38040 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
5e732c69-807c-4580-b719-c59adebc981d-S0 at slave(180)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.888882 30440 slave.cpp:1631] Got assigned task '1' for framework
5e732c69-807c-4580-b719-c59adebc981d-0000
W0309 03:35:58.889675 30439 validation.cpp:1113] Executor 'executor-2' for task
'2' uses less CPUs (None) than the minimum required (0.01). Please update your
executor, as this will be mandatory in future releases.
W0309 03:35:58.889715 30439 validation.cpp:1125] Executor 'executor-2' for task
'2' uses less memory (None) than the minimum required (32MB). Please update
your executor, as this will be mandatory in future releases.
I0309 03:35:58.889945 30440 slave.cpp:1791] Launching task '1' for framework
5e732c69-807c-4580-b719-c59adebc981d-0000
I0309 03:35:58.890164 30439 master.cpp:9113] Adding task 2 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
5e732c69-807c-4580-b719-c59adebc981d-S0 at slave(180)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.890365 30439 master.cpp:4505] Launching task 2 of framework
5e732c69-807c-4580-b719-c59adebc981d-0000 (default) at
scheduler-bbf6a026-e862-4f3e-b5ea-a2395a1a3623@172.17.0.2:38040 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
5e732c69-807c-4580-b719-c59adebc981d-S0 at slave(180)@172.17.0.2:38040
(e9d09efbd686)
I0309 03:35:58.890781 30440 paths.cpp:547] Trying to chown
'/tmp/MasterTest_MultipleExecutors_RlNCyu/slaves/5e732c69-807c-4580-b719-c59adebc981d-S0/frameworks/5e732c69-807c-4580-b719-c59adebc981d-0000/executors/executor-1/runs/8ecca08d-5178-4db5-a617-c088e3f820cf'
to user 'mesos'
I0309 03:35:58.891083 30440 slave.cpp:6499] Launching executor 'executor-1' of
framework 5e732c69-807c-4580-b719-c59adebc981d-0000 with resources {} in work
directory
'/tmp/MasterTest_MultipleExecutors_RlNCyu/slaves/5e732c69-807c-4580-b719-c59adebc981d-S0/frameworks/5e732c69-807c-4580-b719-c59adebc981d-0000/executors/executor-1/runs/8ecca08d-5178-4db5-a617-c088e3f820cf'
I0309 03:35:58.891734 30440 slave.cpp:2124] Queued task '1' for executor
'executor-1' of framework 5e732c69-807c-4580-b719-c59adebc981d-0000
I0309 03:35:58.891960 30440 slave.cpp:1631] Got assigned task '2' for framework
5e732c69-807c-4580-b719-c59adebc981d-0000
I0309 03:35:58.892670 30440 slave.cpp:889] Successfully attached file
'/tmp/MasterTest_MultipleExecutors_RlNCyu/slaves/5e732c69-807c-4580-b719-c59adebc981d-S0/frameworks/5e732c69-807c-4580-b719-c59adebc981d-0000/executors/executor-1/runs/8ecca08d-5178-4db5-a617-c088e3f820cf'
I0309 03:35:58.892773 30440 slave.cpp:1791] Launching task '2' for framework
5e732c69-807c-4580-b719-c59adebc981d-0000
I0309 03:35:58.893321 30440 paths.cpp:547] Trying to chown
'/tmp/MasterTest_MultipleExecutors_RlNCyu/slaves/5e732c69-807c-4580-b719-c59adebc981d-S0/frameworks/5e732c69-807c-4580-b719-c59adebc981d-0000/executors/executor-2/runs/a4555cf0-cf69-4e54-ba9a-8ccaf1210f0a'
to user 'mesos'
I0309 03:35:58.893573 30440 slave.cpp:6499] Launching executor 'executor-2' of
framework 5e732c69-807c-4580-b719-c59adebc981d-0000 with resources {} in work
directory
'/tmp/MasterTest_MultipleExecutors_RlNCyu/slaves/5e732c69-807c-4580-b719-c59adebc981d-S0/frameworks/5e732c69-807c-4580-b719-c59adebc981d-0000/executors/executor-2/runs/a4555cf0-cf69-4e54-ba9a-8ccaf1210f0a'
I0309 03:35:58.893594 30431 hierarchical.cpp:846] Updated allocation of
framework 5e732c69-807c-4580-b719-c59adebc981d-0000 on agent
5e732c69-807c-4580-b719-c59adebc981d-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]
*** Aborted at 1489030558 (unix time) try "date -d @1489030558" if you are
using GNU date ***
PC: @ 0x2addc0b1b82d (unknown)
I0309 03:35:58.894243 30438 exec.cpp:162] Version: 1.3.0
*** SIGSEGV (@0xd0) received by PID 30412 (TID 0x2addc9f94700) from PID 208;
stack trace: ***
I0309 03:35:58.894469 30431 hierarchical.cpp:1110] Recovered
ports(*)(allocated: *):[31000-32000]; disk(*)(allocated: *):1024 (total:
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], allocated:
cpus(*)(allocated: *):2; mem(*)(allocated: *):1024) on agent
5e732c69-807c-4580-b719-c59adebc981d-S0 from framework
5e732c69-807c-4580-b719-c59adebc981d-0000
I0309 03:35:58.894546 30431 hierarchical.cpp:1147] Framework
5e732c69-807c-4580-b719-c59adebc981d-0000 filtered agent
5e732c69-807c-4580-b719-c59adebc981d-S0 for 5secs
I0309 03:35:58.894587 30433 exec.cpp:212] Executor started at:
executor(75)@172.17.0.2:38040 with pid 30412
@ 0x2ade121972d7 (unknown)
@ 0x2ade1219b5b9 (unknown)
@ 0x2addc08d2330 (unknown)
@ 0x2addc0b1b82d (unknown)
@ 0x2addb66dab20 os::getenv()
@ 0x2addbd81a1db mesos::internal::slave::executorEnvironment()
@ 0x2addbd81499e mesos::internal::slave::Framework::launchExecutor()
@ 0x2addbd7ee91b mesos::internal::slave::Slave::_run()
@ 0x2addbd848a77
_ZZN7process8dispatchIN5mesos8internal5slave5SlaveERKNS_6FutureIbEERKNS1_13FrameworkInfoERKNS1_12ExecutorInfoERK6OptionINS1_8TaskInfoEERKSF_INS1_13TaskGroupInfoEES6_S9_SC_SH_SL_EEvRKNS_3PIDIT_EEMSP_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_ENKUlPNS_11ProcessBaseEE_clES16_
@ 0x2addbd87e613
_ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS0_6FutureIbEERKNS5_13FrameworkInfoERKNS5_12ExecutorInfoERK6OptionINS5_8TaskInfoEERKSJ_INS5_13TaskGroupInfoEESA_SD_SG_SL_SP_EEvRKNS0_3PIDIT_EEMST_FvT0_T1_T2_T3_T4_ET5_T6_T7_T8_T9_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x2addbe5e1cad std::function<>::operator()()
@ 0x2addbe5c7a9f process::ProcessBase::visit()
@ 0x2addbe5d23d8 process::DispatchEvent::visit()
@ 0x2addb66aee66 process::ProcessBase::serve()
@ 0x2addbe5c3d8c process::ProcessManager::resume()
@ 0x2addbe5c05d0 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x2addbe5d1b12
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2addbe5d1a69
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x2addbe5d1a02
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2addc0153a60 (unknown)
@ 0x2addc08ca184 start_thread
@ 0x2addc0bda37d (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.3.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.3.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1489027895-28031
Untagged: mesos-1489027895-28031:latest
Deleted: sha256:fadd94a8295b1cbeb0da82fe6d924cb6d3f17abaf32c4d9ee248413e121324a6
Deleted: sha256:8935d123eab099127f26243ba14be04e39d09a6f586324f90576cde93b53d150
Deleted: sha256:6ad551ac043b041716745fcac54afc5a80cb64b199a236b7980a71d8903613db
Deleted: sha256:c7a2592622765049c919b492e664efea9146650b93178ef8300ace02d474cf03
Deleted: sha256:d47554248bf89c18ae5783b728f55a941d6dadc3ad38a42bc68fb140a9157b9c
Deleted: sha256:b03816fe290d1f841bd10ade4a3d79d8e579866aca2f73698d5a5db0ea840feb
Deleted: sha256:ed029dd46e3ff382ff5c0888e0f284cef05a6de5fb03097695a8429e6160df6a
Deleted: sha256:66f2d0375eab7c5e3b69e9bdb657114fa6cef0eda867bcbd9f9694525f269f92
Deleted: sha256:0288d7d5fb98742e6ca2d568b1be3c0afe1999df720575d1bb9cc182226de2d7
Deleted: sha256:466b587312b5956492fdc3088f7467c5922d5e1274a4685d27fdfe73f5f2f5a3
Deleted: sha256:f3b475f2b527c84898648e5ebc5c4fb787c8ea031b7a1e96456635446e07f2eb
Deleted: sha256:d2a10fa8c9222cf8f6077d6c92b44e43b15304b603d7f1e4d65c994a9d59e2be
Deleted: sha256:66609ab97fce2c818b4af7b90495003d675b7e4f4b31385a0104793dfd1f5d2a
Deleted: sha256:8109b8bce469f82f199dc5be798aa60066b6279c4d49219a4987f47d4dc933fa
Deleted: sha256:06118ddf9b406f0072ad13bed5f96347f137cd9a228931aacb36b116573e859e
Deleted: sha256:3b26b8b1d3feafe1ba8c4a48fc6c3ccf19800ccdda7bbad5dd059db47a48342c
Deleted: sha256:1ff5f403edaeda964b5e8b1b6f504e341fb3ae5f456f117ca6e19f6d2cb98b34
Deleted: sha256:11b6b58d9485ccabca91b07a5d27762f67dc86b45d685705431191d84c63862b
Deleted: sha256:56c3eec293e62da6f9213a5ede2518c7c202e0a54e61a0f0d92a7d361e57f13b
Deleted: sha256:8022511132d35bcd3dc10b046834c86d96ca96dbea802dc1acdd00bc53dddd83
Deleted: sha256:f081be3e962ebcfd3804f9aeef5b66f4ea4c1108ba0e629db6865e1befa7cee0
Deleted: sha256:f46230476a041eec11d46bde70b3a0bf862363255ee5722c210a8a8680101a10
Deleted: sha256:6b2c3673b34ed04c461d6ecbee1e75b6866ae7ad0069de72c2bef46f4f052559
Deleted: sha256:b2e3f9a02c1f39682443618dfddab0e917c878814ded954cc56487f930072438
Build step 'Execute shell' marked build as failure
