See
<https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3345/display/redirect?page=changes>
Changes:
[mpark] Migrated `MultiRoleSchedulerUpgrade` from `MasterTest` to `UpgradeTest`.
------------------------------------------
[...truncated 10.76 MB...]
I0307 20:26:58.752442 32496 sched.cpp:1041] Scheduler::statusUpdate took
123625ns
I0307 20:26:58.752712 32490 master.cpp:5167] Processing ACKNOWLEDGE call
a9653332-0823-4b84-bd39-91c9e12b9307 for task 1 of framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 (default) at
scheduler-940847b7-2b27-46e0-b6b9-01dd9b002353@172.17.0.2:37356 on agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0
I0307 20:26:58.753304 32501 status_update_manager.cpp:395] Received status
update acknowledgement (UUID: a9653332-0823-4b84-bd39-91c9e12b9307) for task 1
of framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.753660 32498 slave.cpp:3106] Status update manager successfully
handled status update acknowledgement (UUID:
a9653332-0823-4b84-bd39-91c9e12b9307) for task 1 of framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.753754 32501 exec.cpp:396] Executor received framework message
I0307 20:26:58.753859 32501 exec.cpp:405] Executor::frameworkMessage took
68986ns
I0307 20:26:58.754472 32495 slave.cpp:4325] Sending message for framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 to
scheduler-940847b7-2b27-46e0-b6b9-01dd9b002353@172.17.0.2:37356
I0307 20:26:58.754683 32503 sched.cpp:1177] Scheduler::frameworkMessage took
60014ns
I0307 20:26:58.755103 32470 sched.cpp:2021] Asked to stop the driver
I0307 20:26:58.755208 32489 sched.cpp:1203] Stopping framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.755497 32502 master.cpp:7781] Processing TEARDOWN call for
framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 (default) at
scheduler-940847b7-2b27-46e0-b6b9-01dd9b002353@172.17.0.2:37356
I0307 20:26:58.755533 32502 master.cpp:7793] Removing framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 (default) at
scheduler-940847b7-2b27-46e0-b6b9-01dd9b002353@172.17.0.2:37356
I0307 20:26:58.755549 32502 master.cpp:3164] Deactivating framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 (default) at
scheduler-940847b7-2b27-46e0-b6b9-01dd9b002353@172.17.0.2:37356
I0307 20:26:58.755720 32492 hierarchical.cpp:378] Deactivated framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.755808 32502 master.cpp:8361] Updating the state of task 1 of
framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 (latest state: TASK_KILLED,
status update state: TASK_KILLED)
I0307 20:26:58.755877 32495 slave.cpp:2664] Asked to shut down framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 by master@172.17.0.2:37356
I0307 20:26:58.756045 32495 slave.cpp:2689] Shutting down framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.756100 32495 slave.cpp:5084] Shutting down executor 'default' of
framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 at
executor(73)@172.17.0.2:37356
I0307 20:26:58.756386 32498 exec.cpp:415] Executor asked to shutdown
I0307 20:26:58.756449 32498 exec.cpp:430] Executor::shutdown took 42133ns
I0307 20:26:58.756856 32504 slave.cpp:4389] Got exited event for
executor(73)@172.17.0.2:37356
I0307 20:26:58.756904 32502 master.cpp:8455] Removing task 1 with resources
cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024;
ports(*)(allocated: *):[31000-32000] of framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 on agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0 at slave(179)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.757272 32494 slave.cpp:4770] Executor 'default' of framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 exited with status 0
I0307 20:26:58.757342 32502 master.cpp:8484] Removing executor 'default' with
resources {} of framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 on agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0 at slave(179)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.757519 32494 slave.cpp:4870] Cleaning up executor 'default' of
framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 at
executor(73)@172.17.0.2:37356
I0307 20:26:58.757580 32503 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
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0 from framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.757762 32504 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_ggdwgC/slaves/1c3e976b-8961-40e2-934f-2f09c3b6882b-S0/frameworks/1c3e976b-8961-40e2-934f-2f09c3b6882b-0000/executors/default/runs/ff9367fa-1c55-49f1-9101-1f2d297d1b95'
for gc 6.99999123049778days in the future
I0307 20:26:58.757985 32494 slave.cpp:4958] Cleaning up framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.758023 32489 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_ggdwgC/slaves/1c3e976b-8961-40e2-934f-2f09c3b6882b-S0/frameworks/1c3e976b-8961-40e2-934f-2f09c3b6882b-0000/executors/default'
for gc 6.99999122832296days in the future
W0307 20:26:58.758147 32502 master.cpp:6383] Ignoring unknown exited executor
'default' of framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000 on agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0 at slave(179)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.758213 32490 hierarchical.cpp:325] Removed framework
1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.758218 32493 status_update_manager.cpp:285] Closing status
update streams for framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.758313 32504 gc.cpp:55] Scheduling
'/tmp/MasterTest_FrameworkMessage_ggdwgC/slaves/1c3e976b-8961-40e2-934f-2f09c3b6882b-S0/frameworks/1c3e976b-8961-40e2-934f-2f09c3b6882b-0000'
for gc 6.99999122417778days in the future
I0307 20:26:58.758357 32494 slave.cpp:808] Agent terminating
I0307 20:26:58.758369 32493 status_update_manager.cpp:531] Cleaning up status
update stream for task 1 of framework 1c3e976b-8961-40e2-934f-2f09c3b6882b-0000
I0307 20:26:58.758751 32501 master.cpp:1318] Agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0 at slave(179)@172.17.0.2:37356
(fb03ec989e3d) disconnected
I0307 20:26:58.758810 32501 master.cpp:3201] Disconnecting agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0 at slave(179)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.758999 32501 master.cpp:3220] Deactivating agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0 at slave(179)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.759145 32496 hierarchical.cpp:657] Agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0 deactivated
I0307 20:26:58.761596 32504 master.cpp:1157] Master terminating
I0307 20:26:58.762379 32493 hierarchical.cpp:562] Removed agent
1c3e976b-8961-40e2-934f-2f09c3b6882b-S0
[ OK ] MasterTest.FrameworkMessage (67 ms)
[ RUN ] MasterTest.MultipleExecutors
I0307 20:26:58.769342 32470 cluster.cpp:160] Creating default 'local' authorizer
I0307 20:26:58.772639 32492 master.cpp:437] Master
95613c08-3a0b-42fc-b028-526c58c6de15 (fb03ec989e3d) started on 172.17.0.2:37356
I0307 20:26:58.772670 32492 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/IWfmRU/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/IWfmRU/master" --zk_session_timeout="10secs"
I0307 20:26:58.773113 32492 master.cpp:489] Master only allowing authenticated
frameworks to register
I0307 20:26:58.773123 32492 master.cpp:503] Master only allowing authenticated
agents to register
I0307 20:26:58.773128 32492 master.cpp:516] Master only allowing authenticated
HTTP frameworks to register
I0307 20:26:58.773135 32492 credentials.hpp:37] Loading credentials for
authentication from '/tmp/IWfmRU/credentials'
I0307 20:26:58.773443 32492 master.cpp:561] Using default 'crammd5'
authenticator
I0307 20:26:58.773592 32492 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0307 20:26:58.773744 32492 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0307 20:26:58.773880 32492 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0307 20:26:58.773988 32492 master.cpp:641] Authorization enabled
I0307 20:26:58.774204 32494 whitelist_watcher.cpp:77] No whitelist given
I0307 20:26:58.774236 32503 hierarchical.cpp:161] Initialized hierarchical
allocator process
I0307 20:26:58.776684 32504 master.cpp:2181] Elected as the leading master!
I0307 20:26:58.776717 32504 master.cpp:1705] Recovering from registrar
I0307 20:26:58.776901 32503 registrar.cpp:331] Recovering registrar
I0307 20:26:58.777804 32495 registrar.cpp:364] Successfully fetched the
registry (0B) in 839936ns
I0307 20:26:58.777915 32495 registrar.cpp:463] Applied 1 operations in 17277ns;
attempting to update the registry
I0307 20:26:58.778833 32495 registrar.cpp:508] Successfully updated the
registry in 856064ns
I0307 20:26:58.778977 32495 registrar.cpp:394] Successfully recovered registrar
I0307 20:26:58.779593 32493 master.cpp:1819] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0307 20:26:58.779603 32489 hierarchical.cpp:188] Skipping recovery of
hierarchical allocator: nothing to recover
I0307 20:26:58.784624 32470 cluster.cpp:446] Creating default 'local' authorizer
I0307 20:26:58.786586 32492 slave.cpp:215] Mesos agent started on
(180)@172.17.0.2:37356
I0307 20:26:58.786614 32492 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_MCZop4/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_MCZop4/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_MCZop4/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_MCZop4"
--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_q13mDZ"
I0307 20:26:58.787307 32492 credentials.hpp:86] Loading credential for
authentication from '/tmp/MasterTest_MultipleExecutors_MCZop4/credential'
I0307 20:26:58.787539 32492 slave.cpp:358] Agent using credential for:
test-principal
I0307 20:26:58.787573 32492 credentials.hpp:37] Loading credentials for
authentication from '/tmp/MasterTest_MultipleExecutors_MCZop4/http_credentials'
I0307 20:26:58.787881 32492 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0307 20:26:58.788090 32492 http.cpp:964] Using default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0307 20:26:58.788308 32470 sched.cpp:232] Version: 1.3.0
I0307 20:26:58.788974 32494 sched.cpp:336] New master detected at
master@172.17.0.2:37356
I0307 20:26:58.789127 32494 sched.cpp:407] Authenticating with master
master@172.17.0.2:37356
I0307 20:26:58.789172 32494 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0307 20:26:58.789439 32495 authenticatee.cpp:121] Creating new client SASL
connection
I0307 20:26:58.789949 32498 master.cpp:7268] Authenticating
scheduler-8c164133-73e4-491c-b50f-31ce5e8c3b31@172.17.0.2:37356
I0307 20:26:58.790056 32493 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(413)@172.17.0.2:37356
I0307 20:26:58.790329 32495 authenticator.cpp:98] Creating new server SASL
connection
I0307 20:26:58.790457 32492 slave.cpp:545] Agent resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0307 20:26:58.790616 32492 slave.cpp:553] Agent attributes: [ ]
I0307 20:26:58.790638 32492 slave.cpp:558] Agent hostname: fb03ec989e3d
I0307 20:26:58.790680 32501 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0307 20:26:58.790788 32494 status_update_manager.cpp:177] Pausing sending
status updates
I0307 20:26:58.790803 32501 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0307 20:26:58.791107 32502 authenticator.cpp:204] Received SASL authentication
start
I0307 20:26:58.791203 32502 authenticator.cpp:326] Authentication requires more
steps
I0307 20:26:58.791332 32496 authenticatee.cpp:259] Received SASL authentication
step
I0307 20:26:58.791501 32491 authenticator.cpp:232] Received SASL authentication
step
I0307 20:26:58.791538 32491 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'fb03ec989e3d' server FQDN: 'fb03ec989e3d'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0307 20:26:58.791551 32491 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0307 20:26:58.791591 32491 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0307 20:26:58.791610 32491 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'fb03ec989e3d' server FQDN: 'fb03ec989e3d'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0307 20:26:58.791625 32491 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0307 20:26:58.791640 32491 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0307 20:26:58.791664 32491 authenticator.cpp:318] Authentication success
I0307 20:26:58.791779 32490 authenticatee.cpp:299] Authentication success
I0307 20:26:58.791864 32497 master.cpp:7298] Successfully authenticated
principal 'test-principal' at
scheduler-8c164133-73e4-491c-b50f-31ce5e8c3b31@172.17.0.2:37356
I0307 20:26:58.791867 32502 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(413)@172.17.0.2:37356
I0307 20:26:58.792345 32490 sched.cpp:513] Successfully authenticated with
master master@172.17.0.2:37356
I0307 20:26:58.792369 32490 sched.cpp:836] Sending SUBSCRIBE call to
master@172.17.0.2:37356
I0307 20:26:58.792498 32490 sched.cpp:869] Will retry registration in
1.979070911secs if necessary
I0307 20:26:58.792670 32493 state.cpp:62] Recovering state from
'/tmp/MasterTest_MultipleExecutors_q13mDZ/meta'
I0307 20:26:58.792670 32498 master.cpp:2833] Received SUBSCRIBE call for
framework 'default' at
scheduler-8c164133-73e4-491c-b50f-31ce5e8c3b31@172.17.0.2:37356
I0307 20:26:58.792852 32498 master.cpp:2217] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0307 20:26:58.793174 32502 status_update_manager.cpp:203] Recovering status
update manager
I0307 20:26:58.793521 32499 master.cpp:2910] Subscribing framework default with
checkpointing disabled and capabilities [ ]
I0307 20:26:58.793581 32501 slave.cpp:5572] Finished recovery
I0307 20:26:58.794059 32501 slave.cpp:5754] Querying resource estimator for
oversubscribable resources
I0307 20:26:58.794098 32500 sched.cpp:759] Framework registered with
95613c08-3a0b-42fc-b028-526c58c6de15-0000
I0307 20:26:58.794164 32500 sched.cpp:773] Scheduler::registered took 28947ns
I0307 20:26:58.794260 32491 hierarchical.cpp:273] Added framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000
I0307 20:26:58.794386 32489 status_update_manager.cpp:177] Pausing sending
status updates
I0307 20:26:58.794387 32498 slave.cpp:936] New master detected at
master@172.17.0.2:37356
I0307 20:26:58.794555 32498 slave.cpp:971] Detecting new master
I0307 20:26:58.794622 32491 hierarchical.cpp:1856] No allocations performed
I0307 20:26:58.794670 32491 hierarchical.cpp:1946] No inverse offers to send
out!
I0307 20:26:58.794718 32498 slave.cpp:5768] Received oversubscribable resources
{} from the resource estimator
I0307 20:26:58.794734 32491 hierarchical.cpp:1440] Performed allocation for 0
agents in 156352ns
I0307 20:26:58.797760 32494 slave.cpp:998] Authenticating with master
master@172.17.0.2:37356
I0307 20:26:58.797852 32494 slave.cpp:1009] Using default CRAM-MD5 authenticatee
I0307 20:26:58.798065 32503 authenticatee.cpp:121] Creating new client SASL
connection
I0307 20:26:58.798311 32490 master.cpp:7268] Authenticating
slave(180)@172.17.0.2:37356
I0307 20:26:58.798421 32492 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(414)@172.17.0.2:37356
I0307 20:26:58.798653 32501 authenticator.cpp:98] Creating new server SASL
connection
I0307 20:26:58.798887 32503 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0307 20:26:58.798929 32503 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0307 20:26:58.799070 32498 authenticator.cpp:204] Received SASL authentication
start
I0307 20:26:58.799134 32498 authenticator.cpp:326] Authentication requires more
steps
I0307 20:26:58.799249 32498 authenticatee.cpp:259] Received SASL authentication
step
I0307 20:26:58.799448 32503 authenticator.cpp:232] Received SASL authentication
step
I0307 20:26:58.799482 32503 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'fb03ec989e3d' server FQDN: 'fb03ec989e3d'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0307 20:26:58.799501 32503 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0307 20:26:58.799540 32503 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0307 20:26:58.799592 32503 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: 'fb03ec989e3d' server FQDN: 'fb03ec989e3d'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0307 20:26:58.799621 32503 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0307 20:26:58.799639 32503 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0307 20:26:58.799674 32503 authenticator.cpp:318] Authentication success
I0307 20:26:58.799785 32489 authenticatee.cpp:299] Authentication success
I0307 20:26:58.799933 32502 master.cpp:7298] Successfully authenticated
principal 'test-principal' at slave(180)@172.17.0.2:37356
I0307 20:26:58.800034 32494 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(414)@172.17.0.2:37356
I0307 20:26:58.800467 32492 slave.cpp:1093] Successfully authenticated with
master master@172.17.0.2:37356
I0307 20:26:58.800668 32492 slave.cpp:1521] Will retry registration in
6.092852ms if necessary
I0307 20:26:58.800873 32489 master.cpp:5453] Registering agent at
slave(180)@172.17.0.2:37356 (fb03ec989e3d) with id
95613c08-3a0b-42fc-b028-526c58c6de15-S0
I0307 20:26:58.801334 32496 registrar.cpp:463] Applied 1 operations in 56281ns;
attempting to update the registry
I0307 20:26:58.803153 32496 registrar.cpp:508] Successfully updated the
registry in 1.740032ms
I0307 20:26:58.803997 32496 slave.cpp:4347] Received ping from
slave-observer(178)@172.17.0.2:37356
I0307 20:26:58.804312 32494 slave.cpp:1139] Registered with master
master@172.17.0.2:37356; given agent ID 95613c08-3a0b-42fc-b028-526c58c6de15-S0
I0307 20:26:58.804188 32502 master.cpp:5527] Registered agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 at slave(180)@172.17.0.2:37356
(fb03ec989e3d) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0307 20:26:58.804353 32494 fetcher.cpp:94] Clearing fetcher cache
I0307 20:26:58.804584 32492 status_update_manager.cpp:184] Resuming sending
status updates
I0307 20:26:58.804694 32489 hierarchical.cpp:529] Added agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 (fb03ec989e3d) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0307 20:26:58.804760 32494 slave.cpp:1167] Checkpointing SlaveInfo to
'/tmp/MasterTest_MultipleExecutors_q13mDZ/meta/slaves/95613c08-3a0b-42fc-b028-526c58c6de15-S0/slave.info'
I0307 20:26:58.805137 32494 slave.cpp:1205] Forwarding total oversubscribed
resources {}
I0307 20:26:58.805302 32494 master.cpp:6114] Received update of agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 at slave(180)@172.17.0.2:37356
(fb03ec989e3d) with total oversubscribed resources {}
I0307 20:26:58.806165 32489 hierarchical.cpp:1946] No inverse offers to send
out!
I0307 20:26:58.806241 32489 hierarchical.cpp:1440] Performed allocation for 1
agents in 1.397011ms
I0307 20:26:58.806738 32504 master.cpp:7097] Sending 1 offers to framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000 (default) at
scheduler-8c164133-73e4-491c-b50f-31ce5e8c3b31@172.17.0.2:37356
I0307 20:26:58.807260 32495 sched.cpp:933] Scheduler::resourceOffers took
128747ns
I0307 20:26:58.809789 32494 master.cpp:3855] Processing ACCEPT call for offers:
[ 95613c08-3a0b-42fc-b028-526c58c6de15-O0 ] on agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 at slave(180)@172.17.0.2:37356
(fb03ec989e3d) for framework 95613c08-3a0b-42fc-b028-526c58c6de15-0000
(default) at scheduler-8c164133-73e4-491c-b50f-31ce5e8c3b31@172.17.0.2:37356
I0307 20:26:58.809926 32494 master.cpp:3431] Authorizing framework principal
'test-principal' to launch task 1
I0307 20:26:58.810235 32494 master.cpp:3431] Authorizing framework principal
'test-principal' to launch task 2
W0307 20:26:58.812489 32494 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.
W0307 20:26:58.812530 32494 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.
I0307 20:26:58.812989 32494 master.cpp:9113] Adding task 1 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 at slave(180)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.813235 32494 master.cpp:4505] Launching task 1 of framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000 (default) at
scheduler-8c164133-73e4-491c-b50f-31ce5e8c3b31@172.17.0.2:37356 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 at slave(180)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.813815 32499 slave.cpp:1631] Got assigned task '1' for framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000
I0307 20:26:58.814494 32499 slave.cpp:1791] Launching task '1' for framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000
W0307 20:26:58.814707 32494 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.
W0307 20:26:58.814754 32494 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.
I0307 20:26:58.815253 32499 paths.cpp:547] Trying to chown
'/tmp/MasterTest_MultipleExecutors_q13mDZ/slaves/95613c08-3a0b-42fc-b028-526c58c6de15-S0/frameworks/95613c08-3a0b-42fc-b028-526c58c6de15-0000/executors/executor-1/runs/0a3582ab-e0bf-40b0-bc62-9236b0ed7a45'
to user 'mesos'
I0307 20:26:58.815232 32494 master.cpp:9113] Adding task 2 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 at slave(180)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.815521 32499 slave.cpp:6499] Launching executor 'executor-1' of
framework 95613c08-3a0b-42fc-b028-526c58c6de15-0000 with resources {} in work
directory
'/tmp/MasterTest_MultipleExecutors_q13mDZ/slaves/95613c08-3a0b-42fc-b028-526c58c6de15-S0/frameworks/95613c08-3a0b-42fc-b028-526c58c6de15-0000/executors/executor-1/runs/0a3582ab-e0bf-40b0-bc62-9236b0ed7a45'
I0307 20:26:58.815493 32494 master.cpp:4505] Launching task 2 of framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000 (default) at
scheduler-8c164133-73e4-491c-b50f-31ce5e8c3b31@172.17.0.2:37356 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 at slave(180)@172.17.0.2:37356
(fb03ec989e3d)
I0307 20:26:58.816226 32499 slave.cpp:2124] Queued task '1' for executor
'executor-1' of framework 95613c08-3a0b-42fc-b028-526c58c6de15-0000
I0307 20:26:58.816480 32499 slave.cpp:1631] Got assigned task '2' for framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000
I0307 20:26:58.817023 32499 slave.cpp:889] Successfully attached file
'/tmp/MasterTest_MultipleExecutors_q13mDZ/slaves/95613c08-3a0b-42fc-b028-526c58c6de15-S0/frameworks/95613c08-3a0b-42fc-b028-526c58c6de15-0000/executors/executor-1/runs/0a3582ab-e0bf-40b0-bc62-9236b0ed7a45'
I0307 20:26:58.817124 32499 slave.cpp:1791] Launching task '2' for framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000
I0307 20:26:58.817684 32499 paths.cpp:547] Trying to chown
'/tmp/MasterTest_MultipleExecutors_q13mDZ/slaves/95613c08-3a0b-42fc-b028-526c58c6de15-S0/frameworks/95613c08-3a0b-42fc-b028-526c58c6de15-0000/executors/executor-2/runs/a266e5fd-f0d8-4668-aee5-0ed1da010555'
to user 'mesos'
I0307 20:26:58.817955 32499 slave.cpp:6499] Launching executor 'executor-2' of
framework 95613c08-3a0b-42fc-b028-526c58c6de15-0000 with resources {} in work
directory
'/tmp/MasterTest_MultipleExecutors_q13mDZ/slaves/95613c08-3a0b-42fc-b028-526c58c6de15-S0/frameworks/95613c08-3a0b-42fc-b028-526c58c6de15-0000/executors/executor-2/runs/a266e5fd-f0d8-4668-aee5-0ed1da010555'
*** Aborted at 1488918418 (unix time) try "date -d @1488918418" if you are
using GNU date ***
PC: @ 0x2ac2c912d82d (unknown)
I0307 20:26:58.818629 32491 exec.cpp:162] Version: 1.3.0
*** SIGSEGV (@0xf0) received by PID 32470 (TID 0x2ac2d327c700) from PID 240;
stack trace: ***
I0307 20:26:58.818935 32498 exec.cpp:212] Executor started at:
executor(74)@172.17.0.2:37356 with pid 32470
I0307 20:26:58.819222 32503 hierarchical.cpp:846] Updated allocation of
framework 95613c08-3a0b-42fc-b028-526c58c6de15-0000 on agent
95613c08-3a0b-42fc-b028-526c58c6de15-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]
I0307 20:26:58.820062 32503 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
95613c08-3a0b-42fc-b028-526c58c6de15-S0 from framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000
I0307 20:26:58.820133 32503 hierarchical.cpp:1147] Framework
95613c08-3a0b-42fc-b028-526c58c6de15-0000 filtered agent
95613c08-3a0b-42fc-b028-526c58c6de15-S0 for 5secs
@ 0x2ac2daeea2d7 (unknown)
@ 0x2ac2daeee5b9 (unknown)
@ 0x2ac2c8ee4330 (unknown)
@ 0x2ac2c912d82d (unknown)
@ 0x2ac2be8b33c0 os::getenv()
@ 0x2ac2c5a253fb mesos::internal::slave::executorEnvironment()
@ 0x2ac2c5a1fbbe mesos::internal::slave::Framework::launchExecutor()
@ 0x2ac2c59f9b3b mesos::internal::slave::Slave::_run()
@ 0x2ac2c5a53c97
_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_
@ 0x2ac2c5a89833
_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_
@ 0x2ac2c67eda23 std::function<>::operator()()
@ 0x2ac2c67d37eb process::ProcessBase::visit()
@ 0x2ac2c67de124 process::DispatchEvent::visit()
@ 0x2ac2be887706 process::ProcessBase::serve()
@ 0x2ac2c67cfad8 process::ProcessManager::resume()
@ 0x2ac2c67cc31c _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x2ac2c67dd85e
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2ac2c67dd7b5
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x2ac2c67dd74e
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2ac2c8765a60 (unknown)
@ 0x2ac2c8edc184 start_thread
@ 0x2ac2c91ec37d (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-1488915562-25920
Untagged: mesos-1488915562-25920:latest
Deleted: sha256:1127edda4173b46d7c4394891dce7569e022eb10fecc89bce6b95986682588e9
Deleted: sha256:68052e87b8362da8d1373ab587c98a6803d14d078a74ced39abac79e4c00eb6e
Deleted: sha256:7697e57d100c50d69339f79e6fa81052edf5f00dcc7110905122c6411c99eade
Deleted: sha256:be69b0a435092026fb66f831e956d7e58d12fb4cc1a02f107ac753b6b5dd32e8
Deleted: sha256:1212a5b6b0571ee39dbc2f39b10dcb7ae9c266a16d25c50358a44ff367001cd2
Deleted: sha256:d37c23b9fd0cc8e8b0e1beb8498c7e171af610272c5d8b3046f78bbc7c3c76ef
Deleted: sha256:4064089730c6b8295086e8e729647edf10ee699fe8dcdafec50039a7f99aa03f
Deleted: sha256:56204e0bc3cf992c47c04cb0932e257e3fab9f4504a656d6c41cbdcf94d22b58
Deleted: sha256:8d31cf3d3cbe3ecbdb0a645f2026da8d880eae6b101ecc5a0508f3f74f769660
Deleted: sha256:07fc98055e080270f4067902d8b41bf890c6606ebd9ee3eac1a4226c8eae960f
Deleted: sha256:337a3845c8be47bcdd910b2b827e17b253213e73df6279c1d61dc8b6babf183f
Deleted: sha256:443c59099a10919b086b7446b0d3cd607215b535d3dc5ed4696c7856f94ec59e
Deleted: sha256:b09e78e6cab5468bc4e622ef23a96e368c1aa7385380305ac174fa3d1201e606
Deleted: sha256:f4aec76f8ced941c096deaafbaa8181ea56757084aa068d65d43b8fe26c29533
Deleted: sha256:0f25a7f6e8660eb40dcf501f3baf628fb30c9616ee3fa2ced40a50270f72ebbd
Deleted: sha256:6215d387c38962280ef0fb19e99d914cbee8268524a56c7989e65c44aaa7d915
Deleted: sha256:0bf45c55ede4a708ddd4bc002ac94d38a09d3f45cae90e2055b0371f90042e88
Deleted: sha256:9bf6df717d1ea239eb1ce08f17cd1f7df4319799e4667d3e08ddf95812f208ff
Deleted: sha256:06bcdc81d721323bc9cba2337ca14a9bea402e2fced5c72a7f5019865c1d24ba
Deleted: sha256:b656fbf8cc20e8e49bfb888fc4227ae6ddb72415d4dadfbadab6e68bc8518f14
Deleted: sha256:04e5e000b97611e19758ad88da7062bc8c6e466ecae158041cc2c5a7086321bb
Deleted: sha256:d6547de5041ffb2e9ed27d3efd4ba751b3125a47e73b7c0185a50ed3a630d41b
Deleted: sha256:80b081daee3a790d3f079d50a486d0dfa58b55d332c4e566143ce3c7f8c8f1af
Deleted: sha256:5bb2145c94d8b9e51ffa49c5d3b2c64569c01b8964b150066eed81ab8d840b36
Build step 'Execute shell' marked build as failure
