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)/3739/display/redirect>
------------------------------------------
[...truncated 13.44 MB...]
I0527 15:03:56.947402 1093 master.cpp:5194] Processing ACKNOWLEDGE call
a158b8e6-b594-41ca-b8ff-121e49ad2c97 for task 1 of framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000 (default) at
scheduler-38c368f9-8f26-4f83-aa8c-cd8f89bb1359@172.17.0.3:47110 on agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0
I0527 15:03:56.947597 1099 status_update_manager.cpp:395] Received status
update acknowledgement (UUID: a158b8e6-b594-41ca-b8ff-121e49ad2c97) for task 1
of framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.947643 1096 exec.cpp:416] Executor received framework message
I0527 15:03:56.947690 1096 exec.cpp:425] Executor::frameworkMessage took
26901ns
I0527 15:03:56.947731 1091 slave.cpp:3529] Status update manager successfully
handled status update acknowledgement (UUID:
a158b8e6-b594-41ca-b8ff-121e49ad2c97) for task 1 of framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.947970 1098 slave.cpp:4787] Sending message for framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000 to
scheduler-38c368f9-8f26-4f83-aa8c-cd8f89bb1359@172.17.0.3:47110
I0527 15:03:56.948097 1097 sched.cpp:1177] Scheduler::frameworkMessage took
23480ns
I0527 15:03:56.948310 1090 sched.cpp:2021] Asked to stop the driver
I0527 15:03:56.948369 1104 sched.cpp:1203] Stopping framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.948508 1103 master.cpp:8000] Processing TEARDOWN call for
framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000 (default) at
scheduler-38c368f9-8f26-4f83-aa8c-cd8f89bb1359@172.17.0.3:47110
I0527 15:03:56.948552 1103 master.cpp:8012] Removing framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000 (default) at
scheduler-38c368f9-8f26-4f83-aa8c-cd8f89bb1359@172.17.0.3:47110
I0527 15:03:56.948590 1103 master.cpp:3160] Deactivating framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000 (default) at
scheduler-38c368f9-8f26-4f83-aa8c-cd8f89bb1359@172.17.0.3:47110
I0527 15:03:56.948668 1103 master.cpp:8580] Updating the state of task 1 of
framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000 (latest state: TASK_KILLED,
status update state: TASK_KILLED)
I0527 15:03:56.948825 1092 slave.cpp:3082] Asked to shut down framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000 by master@172.17.0.3:47110
I0527 15:03:56.948858 1103 master.cpp:8674] Removing task 1 with resources
cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024;
ports(*)(allocated: *):[31000-32000] of framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000 on agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0 at slave(312)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.948906 1092 slave.cpp:3107] Shutting down framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.948920 1092 slave.cpp:5546] Shutting down executor 'default' of
framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000 at
executor(123)@172.17.0.3:47110
I0527 15:03:56.948835 1102 hierarchical.cpp:374] Deactivated framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.948974 1103 master.cpp:8703] Removing executor 'default' with
resources {} of framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000 on agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0 at slave(312)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.949005 1099 exec.cpp:435] Executor asked to shutdown
I0527 15:03:56.949080 1099 exec.cpp:450] Executor::shutdown took 51303ns
I0527 15:03:56.949187 1102 hierarchical.cpp:1114] 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
b57aadbd-b38b-458f-94a5-66a137115a2d-S0 from framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.949385 1102 hierarchical.cpp:325] Removed framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.949384 1099 slave.cpp:4851] Got exited event for
executor(123)@172.17.0.3:47110
I0527 15:03:56.949553 1101 slave.cpp:5232] Executor 'default' of framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000 exited with status 0
I0527 15:03:56.949699 1101 slave.cpp:5332] Cleaning up executor 'default' of
framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000 at
executor(123)@172.17.0.3:47110
W0527 15:03:56.949741 1104 master.cpp:6604] Ignoring unknown exited executor
'default' of framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000 on agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0 at slave(312)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.949967 1101 slave.cpp:5420] Cleaning up framework
b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.950027 1104 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_uUjSFQ/slaves/b57aadbd-b38b-458f-94a5-66a137115a2d-S0/frameworks/b57aadbd-b38b-458f-94a5-66a137115a2d-0000/executors/default/runs/c5819a59-1f69-4b7a-a5ec-424306db3c8a'
for gc 6.9999890063437days in the future
I0527 15:03:56.950127 1101 slave.cpp:801] Agent terminating
I0527 15:03:56.950206 1095 master.cpp:1313] Agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0 at slave(312)@172.17.0.3:47110
(01545834673b) disconnected
I0527 15:03:56.950232 1095 master.cpp:3197] Disconnecting agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0 at slave(312)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.950254 1104 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_uUjSFQ/slaves/b57aadbd-b38b-458f-94a5-66a137115a2d-S0/frameworks/b57aadbd-b38b-458f-94a5-66a137115a2d-0000/executors/default'
for gc 6.99998900512593days in the future
I0527 15:03:56.950217 1092 status_update_manager.cpp:285] Closing status
update streams for framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.950331 1104 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_uUjSFQ/slaves/b57aadbd-b38b-458f-94a5-66a137115a2d-S0/frameworks/b57aadbd-b38b-458f-94a5-66a137115a2d-0000'
for gc 6.99998900338074days in the future
I0527 15:03:56.950345 1092 status_update_manager.cpp:531] Cleaning up status
update stream for task 1 of framework b57aadbd-b38b-458f-94a5-66a137115a2d-0000
I0527 15:03:56.950273 1095 master.cpp:3216] Deactivating agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0 at slave(312)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.950433 1096 hierarchical.cpp:653] Agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0 deactivated
I0527 15:03:56.951493 1090 master.cpp:1155] Master terminating
I0527 15:03:56.951668 1092 hierarchical.cpp:558] Removed agent
b57aadbd-b38b-458f-94a5-66a137115a2d-S0
[ OK ] MasterTest.FrameworkMessage (39 ms)
[ RUN ] MasterTest.MultipleExecutors
I0527 15:03:56.955727 1090 cluster.cpp:162] Creating default 'local' authorizer
I0527 15:03:56.956972 1106 master.cpp:436] Master
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1 (01545834673b) started on 172.17.0.3:47110
I0527 15:03:56.957064 1106 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/TStvbm/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/TStvbm/master"
--zk_session_timeout="10secs"
I0527 15:03:56.957247 1106 master.cpp:488] Master only allowing authenticated
frameworks to register
I0527 15:03:56.957267 1106 master.cpp:502] Master only allowing authenticated
agents to register
I0527 15:03:56.957278 1106 master.cpp:515] Master only allowing authenticated
HTTP frameworks to register
I0527 15:03:56.957295 1106 credentials.hpp:37] Loading credentials for
authentication from '/tmp/TStvbm/credentials'
I0527 15:03:56.957468 1106 master.cpp:560] Using default 'crammd5'
authenticator
I0527 15:03:56.957545 1106 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0527 15:03:56.957628 1106 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0527 15:03:56.957690 1106 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0527 15:03:56.957775 1106 master.cpp:640] Authorization enabled
I0527 15:03:56.958082 1098 whitelist_watcher.cpp:77] No whitelist given
I0527 15:03:56.958096 1101 hierarchical.cpp:158] Initialized hierarchical
allocator process
I0527 15:03:56.959002 1104 master.cpp:2161] Elected as the leading master!
I0527 15:03:56.959018 1104 master.cpp:1700] Recovering from registrar
I0527 15:03:56.959107 1101 registrar.cpp:345] Recovering registrar
I0527 15:03:56.959520 1092 registrar.cpp:389] Successfully fetched the
registry (0B) in 351232ns
I0527 15:03:56.959563 1092 registrar.cpp:493] Applied 1 operations in 6269ns;
attempting to update the registry
I0527 15:03:56.959841 1095 registrar.cpp:550] Successfully updated the
registry in 250880ns
I0527 15:03:56.959954 1095 registrar.cpp:422] Successfully recovered registrar
I0527 15:03:56.960275 1095 hierarchical.cpp:185] Skipping recovery of
hierarchical allocator: nothing to recover
I0527 15:03:56.960278 1097 master.cpp:1799] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0527 15:03:56.962496 1090 cluster.cpp:448] Creating default 'local' authorizer
I0527 15:03:56.963089 1105 slave.cpp:232] Mesos agent started on
(313)@172.17.0.3:47110
I0527 15:03:56.963114 1105 slave.cpp:233] 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/MasterTest_MultipleExecutors_R3ZG4B/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_reregistration_timeout="2secs"
--executor_secret_key="/tmp/MasterTest_MultipleExecutors_R3ZG4B/executor_secret_key"
--executor_shutdown_grace_period="5secs"
--fetcher_cache_dir="/tmp/MasterTest_MultipleExecutors_R3ZG4B/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/MasterTest_MultipleExecutors_R3ZG4B/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/MasterTest_MultipleExecutors_R3ZG4B"
--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_txM57Y"
I0527 15:03:56.963387 1105 credentials.hpp:86] Loading credential for
authentication from '/tmp/MasterTest_MultipleExecutors_R3ZG4B/credential'
I0527 15:03:56.963490 1105 slave.cpp:265] Agent using credential for:
test-principal
I0527 15:03:56.963510 1105 credentials.hpp:37] Loading credentials for
authentication from '/tmp/MasterTest_MultipleExecutors_R3ZG4B/http_credentials'
I0527 15:03:56.963665 1090 sched.cpp:232] Version: 1.4.0
I0527 15:03:56.963728 1105 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-executor'
I0527 15:03:56.963771 1105 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-executor'
I0527 15:03:56.963871 1105 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0527 15:03:56.963881 1100 sched.cpp:336] New master detected at
master@172.17.0.3:47110
I0527 15:03:56.963918 1100 sched.cpp:407] Authenticating with master
master@172.17.0.3:47110
I0527 15:03:56.963919 1105 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readonly'
I0527 15:03:56.964016 1100 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0527 15:03:56.964102 1105 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0527 15:03:56.964148 1105 http.cpp:996] Creating default 'jwt' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0527 15:03:56.964157 1097 authenticatee.cpp:121] Creating new client SASL
connection
I0527 15:03:56.964427 1095 master.cpp:7487] Authenticating
scheduler-50e59d8c-12a4-4e05-ad66-2cc08d676631@172.17.0.3:47110
I0527 15:03:56.964495 1104 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(663)@172.17.0.3:47110
I0527 15:03:56.964649 1095 authenticator.cpp:98] Creating new server SASL
connection
I0527 15:03:56.964798 1091 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0527 15:03:56.964825 1091 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0527 15:03:56.964844 1105 slave.cpp:536] Agent resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 15:03:56.964880 1091 authenticator.cpp:204] Received SASL authentication
start
I0527 15:03:56.964884 1105 slave.cpp:544] Agent attributes: [ ]
I0527 15:03:56.964908 1105 slave.cpp:549] Agent hostname: 01545834673b
I0527 15:03:56.964931 1091 authenticator.cpp:326] Authentication requires more
steps
I0527 15:03:56.964984 1095 status_update_manager.cpp:177] Pausing sending
status updates
I0527 15:03:56.965009 1091 authenticatee.cpp:259] Received SASL authentication
step
I0527 15:03:56.965078 1091 authenticator.cpp:232] Received SASL authentication
step
I0527 15:03:56.965108 1091 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '01545834673b' server FQDN: '01545834673b'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0527 15:03:56.965127 1091 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0527 15:03:56.965145 1091 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0527 15:03:56.965165 1091 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '01545834673b' server FQDN: '01545834673b'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0527 15:03:56.965179 1091 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0527 15:03:56.965190 1091 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0527 15:03:56.965212 1091 authenticator.cpp:318] Authentication success
I0527 15:03:56.965286 1106 authenticatee.cpp:299] Authentication success
I0527 15:03:56.965333 1103 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(663)@172.17.0.3:47110
I0527 15:03:56.965348 1091 master.cpp:7517] Successfully authenticated
principal 'test-principal' at
scheduler-50e59d8c-12a4-4e05-ad66-2cc08d676631@172.17.0.3:47110
I0527 15:03:56.965507 1095 sched.cpp:513] Successfully authenticated with
master master@172.17.0.3:47110
I0527 15:03:56.965533 1095 sched.cpp:836] Sending SUBSCRIBE call to
master@172.17.0.3:47110
I0527 15:03:56.965541 1092 state.cpp:62] Recovering state from
'/tmp/MasterTest_MultipleExecutors_txM57Y/meta'
I0527 15:03:56.965634 1091 master.cpp:2813] Received SUBSCRIBE call for
framework 'default' at
scheduler-50e59d8c-12a4-4e05-ad66-2cc08d676631@172.17.0.3:47110
I0527 15:03:56.965574 1095 sched.cpp:869] Will retry registration in
1.455662253secs if necessary
I0527 15:03:56.965672 1091 master.cpp:2197] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0527 15:03:56.965744 1102 status_update_manager.cpp:203] Recovering status
update manager
I0527 15:03:56.965932 1096 master.cpp:2890] Subscribing framework default with
checkpointing disabled and capabilities [ ]
I0527 15:03:56.966080 1105 slave.cpp:6083] Finished recovery
I0527 15:03:56.966204 1097 hierarchical.cpp:273] Added framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.966215 1101 sched.cpp:759] Framework registered with
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.966269 1101 sched.cpp:773] Scheduler::registered took 22991ns
I0527 15:03:56.966301 1097 hierarchical.cpp:1854] No allocations performed
I0527 15:03:56.966325 1097 hierarchical.cpp:1944] No inverse offers to send
out!
I0527 15:03:56.966344 1097 hierarchical.cpp:1438] Performed allocation for 0
agents in 50902ns
I0527 15:03:56.966514 1105 slave.cpp:6265] Querying resource estimator for
oversubscribable resources
I0527 15:03:56.966652 1105 slave.cpp:929] New master detected at
master@172.17.0.3:47110
I0527 15:03:56.966660 1101 status_update_manager.cpp:177] Pausing sending
status updates
I0527 15:03:56.966697 1105 slave.cpp:964] Detecting new master
I0527 15:03:56.966760 1105 slave.cpp:6279] Received oversubscribable resources
{} from the resource estimator
I0527 15:03:56.973953 1097 slave.cpp:991] Authenticating with master
master@172.17.0.3:47110
I0527 15:03:56.973991 1097 slave.cpp:1002] Using default CRAM-MD5 authenticatee
I0527 15:03:56.974189 1091 authenticatee.cpp:121] Creating new client SASL
connection
I0527 15:03:56.974373 1095 master.cpp:7487] Authenticating
slave(313)@172.17.0.3:47110
I0527 15:03:56.974449 1095 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(664)@172.17.0.3:47110
I0527 15:03:56.974537 1103 authenticator.cpp:98] Creating new server SASL
connection
I0527 15:03:56.974699 1105 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0527 15:03:56.974735 1105 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0527 15:03:56.974802 1105 authenticator.cpp:204] Received SASL authentication
start
I0527 15:03:56.974861 1105 authenticator.cpp:326] Authentication requires more
steps
I0527 15:03:56.975033 1103 authenticatee.cpp:259] Received SASL authentication
step
I0527 15:03:56.975111 1103 authenticator.cpp:232] Received SASL authentication
step
I0527 15:03:56.975185 1103 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '01545834673b' server FQDN: '01545834673b'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0527 15:03:56.975208 1103 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0527 15:03:56.975230 1103 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0527 15:03:56.975258 1103 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '01545834673b' server FQDN: '01545834673b'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0527 15:03:56.975275 1103 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0527 15:03:56.975296 1103 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0527 15:03:56.975320 1103 authenticator.cpp:318] Authentication success
I0527 15:03:56.975399 1097 authenticatee.cpp:299] Authentication success
I0527 15:03:56.975426 1105 master.cpp:7517] Successfully authenticated
principal 'test-principal' at slave(313)@172.17.0.3:47110
I0527 15:03:56.975447 1103 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(664)@172.17.0.3:47110
I0527 15:03:56.975653 1097 slave.cpp:1086] Successfully authenticated with
master master@172.17.0.3:47110
I0527 15:03:56.975740 1097 slave.cpp:1506] Will retry registration in
6.155979ms if necessary
I0527 15:03:56.975836 1093 master.cpp:5429] Received register agent message
from slave(313)@172.17.0.3:47110 (01545834673b)
I0527 15:03:56.975884 1093 master.cpp:3659] Authorizing agent with principal
'test-principal'
I0527 15:03:56.976058 1104 master.cpp:5489] Authorized registration of agent
at slave(313)@172.17.0.3:47110 (01545834673b)
I0527 15:03:56.976124 1104 master.cpp:5568] Registering agent at
slave(313)@172.17.0.3:47110 (01545834673b) with id
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0
I0527 15:03:56.976287 1099 registrar.cpp:493] Applied 1 operations in 22686ns;
attempting to update the registry
I0527 15:03:56.976452 1092 registrar.cpp:550] Successfully updated the
registry in 137984ns
I0527 15:03:56.976533 1099 master.cpp:5615] Admitted agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 at slave(313)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.976707 1099 master.cpp:5646] Registered agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 at slave(313)@172.17.0.3:47110
(01545834673b) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0527 15:03:56.976836 1099 hierarchical.cpp:525] Added agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 (01545834673b) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0527 15:03:56.977087 1099 hierarchical.cpp:1944] No inverse offers to send
out!
I0527 15:03:56.977104 1099 hierarchical.cpp:1438] Performed allocation for 1
agents in 217338ns
I0527 15:03:56.977140 1099 slave.cpp:1132] Registered with master
master@172.17.0.3:47110; given agent ID 6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0
I0527 15:03:56.977354 1106 master.cpp:7317] Sending 1 offers to framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000 (default) at
scheduler-50e59d8c-12a4-4e05-ad66-2cc08d676631@172.17.0.3:47110
I0527 15:03:56.977427 1105 status_update_manager.cpp:184] Resuming sending
status updates
I0527 15:03:56.977581 1106 sched.cpp:933] Scheduler::resourceOffers took
54492ns
I0527 15:03:56.977648 1099 slave.cpp:1152] Checkpointing SlaveInfo to
'/tmp/MasterTest_MultipleExecutors_txM57Y/meta/slaves/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0/slave.info'
I0527 15:03:56.978006 1099 slave.cpp:1190] Forwarding total oversubscribed
resources {}
I0527 15:03:56.978094 1099 slave.cpp:4809] Received ping from
slave-observer(305)@172.17.0.3:47110
I0527 15:03:56.978121 1101 master.cpp:6336] Received update of agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 at slave(313)@172.17.0.3:47110
(01545834673b) with total oversubscribed resources {}
I0527 15:03:56.978404 1101 master.cpp:3875] Processing ACCEPT call for offers:
[ 6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-O0 ] on agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 at slave(313)@172.17.0.3:47110
(01545834673b) for framework 6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
(default) at scheduler-50e59d8c-12a4-4e05-ad66-2cc08d676631@172.17.0.3:47110
I0527 15:03:56.978446 1101 master.cpp:3426] Authorizing framework principal
'test-principal' to launch task 1
I0527 15:03:56.978543 1101 master.cpp:3426] Authorizing framework principal
'test-principal' to launch task 2
W0527 15:03:56.979107 1099 validation.cpp:1284] 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.
W0527 15:03:56.979131 1099 validation.cpp:1296] 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.
I0527 15:03:56.979228 1099 master.cpp:9338] Adding task 1 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 at slave(313)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.979285 1099 master.cpp:4531] Launching task 1 of framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000 (default) at
scheduler-50e59d8c-12a4-4e05-ad66-2cc08d676631@172.17.0.3:47110 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 at slave(313)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.979466 1095 slave.cpp:1616] Got assigned task '1' for framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
W0527 15:03:56.979617 1099 validation.cpp:1284] 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.
W0527 15:03:56.979641 1099 validation.cpp:1296] 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.
I0527 15:03:56.979743 1099 master.cpp:9338] Adding task 2 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 at slave(313)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.979801 1095 slave.cpp:1897] Authorizing task '1' for framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.979816 1099 master.cpp:4531] Launching task 2 of framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000 (default) at
scheduler-50e59d8c-12a4-4e05-ad66-2cc08d676631@172.17.0.3:47110 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 at slave(313)@172.17.0.3:47110
(01545834673b)
I0527 15:03:56.979830 1095 slave.cpp:6702] Authorizing framework principal
'test-principal' to launch task 1
I0527 15:03:56.980106 1095 slave.cpp:1616] Got assigned task '2' for framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.980420 1095 slave.cpp:2084] Launching task '1' for framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.980407 1101 hierarchical.cpp:850] Updated allocation of
framework 6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000 on agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-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]
I0527 15:03:56.980545 1101 hierarchical.cpp:1114] 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
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 from framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.980576 1101 hierarchical.cpp:1151] Framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000 filtered agent
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0 for 5secs
I0527 15:03:56.980903 1095 paths.cpp:573] Trying to chown
'/tmp/MasterTest_MultipleExecutors_txM57Y/slaves/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0/frameworks/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000/executors/executor-1/runs/35115b86-fb13-4546-8fb0-7e378575bd21'
to user 'mesos'
I0527 15:03:56.981061 1095 slave.cpp:7042] Launching executor 'executor-1' of
framework 6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000 with resources {} in work
directory
'/tmp/MasterTest_MultipleExecutors_txM57Y/slaves/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0/frameworks/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000/executors/executor-1/runs/35115b86-fb13-4546-8fb0-7e378575bd21'
I0527 15:03:56.981299 1095 slave.cpp:2313] Queued task '1' for executor
'executor-1' of framework 6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.981338 1095 slave.cpp:1897] Authorizing task '2' for framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.981355 1095 slave.cpp:6702] Authorizing framework principal
'test-principal' to launch task 2
I0527 15:03:56.981456 1095 slave.cpp:882] Successfully attached file
'/tmp/MasterTest_MultipleExecutors_txM57Y/slaves/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0/frameworks/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000/executors/executor-1/runs/35115b86-fb13-4546-8fb0-7e378575bd21'
I0527 15:03:56.981560 1095 slave.cpp:2779] Launching container
35115b86-fb13-4546-8fb0-7e378575bd21 for executor 'executor-1' of framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.981729 1095 slave.cpp:2084] Launching task '2' for framework
6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.982146 1095 paths.cpp:573] Trying to chown
'/tmp/MasterTest_MultipleExecutors_txM57Y/slaves/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0/frameworks/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000/executors/executor-2/runs/6fdd2f6f-9ef7-4f0d-ad10-3e62952c5457'
to user 'mesos'
I0527 15:03:56.982306 1095 slave.cpp:7042] Launching executor 'executor-2' of
framework 6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000 with resources {} in work
directory
'/tmp/MasterTest_MultipleExecutors_txM57Y/slaves/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0/frameworks/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000/executors/executor-2/runs/6fdd2f6f-9ef7-4f0d-ad10-3e62952c5457'
I0527 15:03:56.982527 1095 slave.cpp:2313] Queued task '2' for executor
'executor-2' of framework 6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000
I0527 15:03:56.982568 1095 slave.cpp:882] Successfully attached file
'/tmp/MasterTest_MultipleExecutors_txM57Y/slaves/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-S0/frameworks/6042d8bb-6ac1-4c2e-a66b-10d4b71d9dc1-0000/executors/executor-2/runs/6fdd2f6f-9ef7-4f0d-ad10-3e62952c5457'
*** Aborted at 1495897436 (unix time) try "date -d @1495897436" if you are
using GNU date ***
I0527 15:03:56.982847 1104 exec.cpp:162] Version: 1.4.0
I0527 15:03:56.982956 1096 exec.cpp:212] Executor started at:
executor(124)@172.17.0.3:47110 with pid 1090
PC: @ 0x7fd3695e60cd __GI_getenv
*** SIGSEGV (@0xf0) received by PID 1090 (TID 0x7fd361ecb700) from PID 240;
stack trace: ***
@ 0x7fd36a19f370 (unknown)
@ 0x7fd3695e60cd __GI_getenv
@ 0x7fd36e52d8d4 mesos::internal::slave::executorEnvironment()
@ 0x7fd36e54ea39 mesos::internal::slave::Slave::launchExecutor()
@ 0x7fd36d71f0c1 process::ProcessManager::resume()
@ 0x7fd36d728177
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x7fd369f3c230 (unknown)
@ 0x7fd36a197dc5 start_thread
@ 0x7fd3696a576d __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-1495894094-834
Untagged: mesos-1495894094-834:latest
Deleted: sha256:8f87436731bce1c457697f1cc4c5d2b1629cea523d5eab0f2fbfee3cdde09efc
Deleted: sha256:b5ce4d98187151895e92414b450e0833e0643eb9f574d2c18c255c349cb26643
Deleted: sha256:e7b4e06f84c1c07e09a2d7ac6063db506bc15e3c0982ebdb6581cd9ec3b46bbf
Deleted: sha256:effeb353281565808e6436476d032c7cecacb9f12b820a27d0d7c546adafd9d3
Deleted: sha256:c1811e3534571884545cce72e4992044cd0e9d86142c60c82e4e44b444e800c5
Deleted: sha256:fe58cb5e437fd17e07d20842a36a349aee1b4146335699f429ae24af7e4edc97
Deleted: sha256:ce07c52eb88c5f6e619b52f34b9e34ca63c27a80b048e3c2540a6b289d7f24c0
Deleted: sha256:be64d1366bdbf461f2cf4304efdcb0f4788c553615c5313759c1ae928d5e5dd0
Deleted: sha256:5fb25f19e34d0d1ae5d86c0d661630641bf75aa2b69b64615cf0f366bdfb695b
Deleted: sha256:95d4d92bcb0f5ed3b420ffd5f178cd3ea4b44e497abf012b00dac3b44e6ff887
Deleted: sha256:c2ef913fa8835b992fd9fbca495845da14ced27c2b6bd38838f32086d720f902
Deleted: sha256:70c1bef81e82dbf485ce350ff015f58adfa63fae92fdc3539b08e5b84c7f7363
Deleted: sha256:e6e2b4e55a51966a3069d2ba12470068d8db32b8712c5b5cbfa43084cae7ea1c
Deleted: sha256:73b558eaf623d888a0dee2cf7055882b4399a2407411dd6189885e42597d0908
Deleted: sha256:90a8df0d498f498826313603ff4b82e83d3d434f38e4be3c52541c7710c48b67
Deleted: sha256:97f9ae6e4254e30e36fc4bde9562f96709629564ab70d8ffc858bd5ccdc380bd
Deleted: sha256:b71e3e45c7bd49809331ac484242388956c1421befdfc8bd7e6bf83b19a53de6
Deleted: sha256:fa1a443b37575324182313e05a809b33700ab1b7ad04eee84dc90d353f02209b
Deleted: sha256:0e251f21c01d24d46918d54e0aeb81ee5c95407a2d2b949b7b42bf5ff9ea1173
Deleted: sha256:461b29f010e6970acd60fbfe719d6f2244b4776af38d6fa5ad93b91881fb2cef
Deleted: sha256:ec1f474fab101d3fb7bfdf89765c4a51bc95bebeb63e7f66433be4148ec1152f
Deleted: sha256:735f9118fe1c2f77f67489c22551a5f67530e39f4f9bd6ea3d1804df1d34fc51
Deleted: sha256:c39a96fb601311e005d4a2dae818f4bbb24f3c373bad4591adae1520d83be519
Deleted: sha256:4226056d0a1a126bdf270aa013be4fa5b4cc2e2f4d6d29021a5603423a89b846
Deleted: sha256:463f908b8de4587e426f30fc30be76891a720afdff49f39824ccbad96e7aae5a
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user neil.con...@gmail.com
