mesos-builds mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2) #3245
Date Wed, 08 Feb 2017 16:38:39 GMT
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=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)&&(!ubuntu-eu2)/3245/changes>

Changes:

[alexr] Updated the naming of unacknowledged tasks in executors for clarity.

[alexr] Improved style in `HealthChecker`.

------------------------------------------
[...truncated 86635 lines...]
I0208 16:38:33.941190 27169 exec.cpp:237] Executor registered on agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
I0208 16:38:33.941221 27169 exec.cpp:249] Executor::registered took 13609ns
I0208 16:38:33.941501 27160 slave.cpp:2286] Sending queued task '1' to executor 'default'
of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000 at executor(120)@172.17.0.2:58573
I0208 16:38:33.941642 27162 exec.cpp:320] Executor asked to run task '1'
I0208 16:38:33.941704 27162 exec.cpp:329] Executor::launchTask took 35119ns
I0208 16:38:33.941787 27162 exec.cpp:546] Executor sending status update TASK_RUNNING (UUID:
8033ae17-71a5-4a0a-a30a-1689bc5fdc5d) for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.941920 27162 slave.cpp:3771] Handling status update TASK_RUNNING (UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d)
for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000 from executor(120)@172.17.0.2:58573
I0208 16:38:33.942219 27162 status_update_manager.cpp:323] Received status update TASK_RUNNING
(UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d) for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.942250 27162 status_update_manager.cpp:500] Creating StatusUpdate stream for
task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.942430 27162 status_update_manager.cpp:377] Forwarding update TASK_RUNNING
(UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d) for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
to the agent
I0208 16:38:33.942554 27158 slave.cpp:4211] Forwarding the update TASK_RUNNING (UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d)
for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000 to master@172.17.0.2:58573
I0208 16:38:33.942715 27158 slave.cpp:4105] Status update manager successfully handled status
update TASK_RUNNING (UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d) for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.942736 27160 master.cpp:6094] Status update TASK_RUNNING (UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d)
for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000 from agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
at slave(286)@172.17.0.2:58573 (324c8fc3066c)
I0208 16:38:33.942754 27158 slave.cpp:4121] Sending acknowledgement for status update TASK_RUNNING
(UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d) for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
to executor(120)@172.17.0.2:58573
I0208 16:38:33.942770 27160 master.cpp:6162] Forwarding status update TASK_RUNNING (UUID:
8033ae17-71a5-4a0a-a30a-1689bc5fdc5d) for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.942857 27160 master.cpp:8242] Updating the state of task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
(latest state: TASK_RUNNING, status update state: TASK_RUNNING)
I0208 16:38:33.942894 27156 exec.cpp:369] Executor received status update acknowledgement
8033ae17-71a5-4a0a-a30a-1689bc5fdc5d for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.943015 27162 sched.cpp:1041] Scheduler::statusUpdate took 44276ns
I0208 16:38:33.943159 27162 master.cpp:5032] Processing ACKNOWLEDGE call 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d
for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000 (default) at scheduler-5c99ca1f-4701-42d5-bd9f-563d6a56a79e@172.17.0.2:58573
on agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
I0208 16:38:33.943405 27164 status_update_manager.cpp:395] Received status update acknowledgement
(UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d) for task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.943534 27167 slave.cpp:3060] Status update manager successfully handled status
update acknowledgement (UUID: 8033ae17-71a5-4a0a-a30a-1689bc5fdc5d) for task 1 of framework
165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.943640 27162 exec.cpp:391] Executor received framework message
I0208 16:38:33.943681 27162 exec.cpp:400] Executor::frameworkMessage took 26517ns
I0208 16:38:33.944056 27169 slave.cpp:4279] Sending message for framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
to scheduler-5c99ca1f-4701-42d5-bd9f-563d6a56a79e@172.17.0.2:58573
I0208 16:38:33.944191 27163 sched.cpp:1177] Scheduler::frameworkMessage took 22316ns
I0208 16:38:33.944382 27155 sched.cpp:2021] Asked to stop the driver
I0208 16:38:33.944444 27165 sched.cpp:1203] Stopping framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.944545 27169 master.cpp:7645] Processing TEARDOWN call for framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
(default) at scheduler-5c99ca1f-4701-42d5-bd9f-563d6a56a79e@172.17.0.2:58573
I0208 16:38:33.944638 27169 master.cpp:7657] Removing framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
(default) at scheduler-5c99ca1f-4701-42d5-bd9f-563d6a56a79e@172.17.0.2:58573
I0208 16:38:33.944766 27169 master.cpp:3080] Deactivating framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
(default) at scheduler-5c99ca1f-4701-42d5-bd9f-563d6a56a79e@172.17.0.2:58573
I0208 16:38:33.944908 27171 hierarchical.cpp:415] Deactivated framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.944936 27158 slave.cpp:2613] Asked to shut down framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
by master@172.17.0.2:58573
I0208 16:38:33.945049 27158 slave.cpp:2638] Shutting down framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.944924 27169 master.cpp:8242] Updating the state of task 1 of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
(latest state: TASK_KILLED, status update state: TASK_KILLED)
I0208 16:38:33.945070 27158 slave.cpp:5020] Shutting down executor 'default' of framework
165290e7-4390-4e5c-bf18-e0d571519fb9-0000 at executor(120)@172.17.0.2:58573
I0208 16:38:33.945195 27168 exec.cpp:410] Executor asked to shutdown
I0208 16:38:33.945322 27168 exec.cpp:425] Executor::shutdown took 42655ns
I0208 16:38:33.945277 27169 master.cpp:8336] Removing task 1 with resources cpus(*)(allocated:
*):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated: *):[31000-32000]
of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000 on agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
at slave(286)@172.17.0.2:58573 (324c8fc3066c)
I0208 16:38:33.945802 27169 master.cpp:8365] Removing executor 'default' with resources {}
of framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000 on agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
at slave(286)@172.17.0.2:58573 (324c8fc3066c)
I0208 16:38:33.945590 27160 slave.cpp:4343] Got exited event for executor(120)@172.17.0.2:58573
I0208 16:38:33.945938 27160 slave.cpp:803] Agent terminating
I0208 16:38:33.945953 27160 slave.cpp:2613] Asked to shut down framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
by @0.0.0.0:0
W0208 16:38:33.945971 27160 slave.cpp:2634] Ignoring shutdown framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
because it is terminating
I0208 16:38:33.945399 27164 hierarchical.cpp:1064] 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 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
from framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.946142 27164 hierarchical.cpp:362] Removed framework 165290e7-4390-4e5c-bf18-e0d571519fb9-0000
I0208 16:38:33.946141 27160 master.cpp:1263] Agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
at slave(286)@172.17.0.2:58573 (324c8fc3066c) disconnected
I0208 16:38:33.946219 27160 master.cpp:3117] Disconnecting agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
at slave(286)@172.17.0.2:58573 (324c8fc3066c)
I0208 16:38:33.946254 27160 master.cpp:3136] Deactivating agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
at slave(286)@172.17.0.2:58573 (324c8fc3066c)
I0208 16:38:33.946372 27170 hierarchical.cpp:618] Agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
deactivated
I0208 16:38:33.947602 27155 master.cpp:1102] Master terminating
I0208 16:38:33.947821 27171 hierarchical.cpp:549] Removed agent 165290e7-4390-4e5c-bf18-e0d571519fb9-S0
[       OK ] MasterTest.FrameworkMessage (36 ms)
[ RUN      ] MasterTest.MultipleExecutors
I0208 16:38:33.952513 27155 cluster.cpp:160] Creating default 'local' authorizer
I0208 16:38:33.953575 27162 master.cpp:383] Master 13d72473-a4c3-4b2b-974e-5a00c53c1c58 (324c8fc3066c)
started on 172.17.0.2:58573
I0208 16:38:33.953594 27162 master.cpp:385] 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/55et27/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="/usr/local/share/mesos/webui"
--work_dir="/tmp/55et27/master" --zk_session_timeout="10secs"
I0208 16:38:33.953852 27162 master.cpp:435] Master only allowing authenticated frameworks
to register
I0208 16:38:33.953866 27162 master.cpp:449] Master only allowing authenticated agents to register
I0208 16:38:33.953873 27162 master.cpp:462] Master only allowing authenticated HTTP frameworks
to register
I0208 16:38:33.953883 27162 credentials.hpp:37] Loading credentials for authentication from
'/tmp/55et27/credentials'
I0208 16:38:33.954027 27162 master.cpp:507] Using default 'crammd5' authenticator
I0208 16:38:33.954092 27162 http.cpp:919] Using default 'basic' HTTP authenticator for realm
'mesos-master-readonly'
I0208 16:38:33.954155 27162 http.cpp:919] Using default 'basic' HTTP authenticator for realm
'mesos-master-readwrite'
I0208 16:38:33.954196 27162 http.cpp:919] Using default 'basic' HTTP authenticator for realm
'mesos-master-scheduler'
I0208 16:38:33.954228 27162 master.cpp:587] Authorization enabled
I0208 16:38:33.954344 27157 hierarchical.cpp:161] Initialized hierarchical allocator process
I0208 16:38:33.954440 27161 whitelist_watcher.cpp:77] No whitelist given
I0208 16:38:33.955003 27162 master.cpp:2123] Elected as the leading master!
I0208 16:38:33.955018 27162 master.cpp:1645] Recovering from registrar
I0208 16:38:33.955071 27157 registrar.cpp:329] Recovering registrar
I0208 16:38:33.955369 27169 registrar.cpp:362] Successfully fetched the registry (0B) in 225024ns
I0208 16:38:33.955405 27169 registrar.cpp:461] Applied 1 operations in 5567ns; attempting
to update the registry
I0208 16:38:33.955690 27170 registrar.cpp:506] Successfully updated the registry in 201984ns
I0208 16:38:33.955762 27170 registrar.cpp:392] Successfully recovered registrar
I0208 16:38:33.955916 27170 master.cpp:1761] Recovered 0 agents from the registry (129B);
allowing 10mins for agents to re-register
I0208 16:38:33.957821 27164 hierarchical.cpp:188] Skipping recovery of hierarchical allocator:
nothing to recover
I0208 16:38:33.959738 27155 cluster.cpp:446] Creating default 'local' authorizer
I0208 16:38:33.960275 27171 slave.cpp:211] Mesos agent started on (287)@172.17.0.2:58573
I0208 16:38:33.960291 27171 slave.cpp:212] 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_9QGuT7/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_9QGuT7/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_9QGuT7/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" --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_9QGuT7"
--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_aSgYXC"
I0208 16:38:33.960687 27171 credentials.hpp:86] Loading credential for authentication from
'/tmp/MasterTest_MultipleExecutors_9QGuT7/credential'
I0208 16:38:33.960780 27155 sched.cpp:232] Version: 1.2.0
I0208 16:38:33.960836 27171 slave.cpp:354] Agent using credential for: test-principal
I0208 16:38:33.960964 27171 credentials.hpp:37] Loading credentials for authentication from
'/tmp/MasterTest_MultipleExecutors_9QGuT7/http_credentials'
I0208 16:38:33.961191 27159 sched.cpp:336] New master detected at master@172.17.0.2:58573
I0208 16:38:33.961231 27159 sched.cpp:407] Authenticating with master master@172.17.0.2:58573
I0208 16:38:33.961246 27159 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0208 16:38:33.961304 27171 http.cpp:919] Using default 'basic' HTTP authenticator for realm
'mesos-agent-readonly'
I0208 16:38:33.961405 27160 authenticatee.cpp:121] Creating new client SASL connection
I0208 16:38:33.961431 27171 http.cpp:919] Using default 'basic' HTTP authenticator for realm
'mesos-agent-readwrite'
I0208 16:38:33.961658 27159 master.cpp:7103] Authenticating scheduler-297bf554-741b-4cc5-9e7f-005eabd6a2ef@172.17.0.2:58573
I0208 16:38:33.961719 27161 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(633)@172.17.0.2:58573
I0208 16:38:33.961946 27171 slave.cpp:541] Agent resources: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0208 16:38:33.961992 27171 slave.cpp:549] Agent attributes: [  ]
I0208 16:38:33.962002 27171 slave.cpp:554] Agent hostname: 324c8fc3066c
I0208 16:38:33.962064 27160 status_update_manager.cpp:177] Pausing sending status updates
I0208 16:38:33.962098 27170 authenticator.cpp:98] Creating new server SASL connection
I0208 16:38:33.962333 27162 state.cpp:62] Recovering state from '/tmp/MasterTest_MultipleExecutors_aSgYXC/meta'
I0208 16:38:33.962481 27169 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
I0208 16:38:33.962503 27169 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
I0208 16:38:33.962618 27169 status_update_manager.cpp:203] Recovering status update manager
I0208 16:38:33.962724 27157 authenticator.cpp:204] Received SASL authentication start
I0208 16:38:33.962879 27157 authenticator.cpp:326] Authentication requires more steps
I0208 16:38:33.962844 27158 slave.cpp:5499] Finished recovery
I0208 16:38:33.962985 27157 authenticatee.cpp:259] Received SASL authentication step
I0208 16:38:33.963079 27157 authenticator.cpp:232] Received SASL authentication step
I0208 16:38:33.963106 27157 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '324c8fc3066c' server FQDN: '324c8fc3066c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0208 16:38:33.963120 27157 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0208 16:38:33.963135 27157 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0208 16:38:33.963155 27157 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '324c8fc3066c' server FQDN: '324c8fc3066c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0208 16:38:33.963165 27157 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0208 16:38:33.963172 27157 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0208 16:38:33.963192 27157 authenticator.cpp:318] Authentication success
I0208 16:38:33.963249 27169 authenticatee.cpp:299] Authentication success
I0208 16:38:33.963274 27166 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(633)@172.17.0.2:58573
I0208 16:38:33.963342 27157 master.cpp:7133] Successfully authenticated principal 'test-principal'
at scheduler-297bf554-741b-4cc5-9e7f-005eabd6a2ef@172.17.0.2:58573
I0208 16:38:33.963346 27158 slave.cpp:5673] Querying resource estimator for oversubscribable
resources
I0208 16:38:33.963421 27162 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:58573
I0208 16:38:33.963440 27162 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:58573
I0208 16:38:33.963443 27158 slave.cpp:5687] Received oversubscribable resources {} from the
resource estimator
I0208 16:38:33.963491 27162 sched.cpp:869] Will retry registration in 1.275951902secs if necessary
I0208 16:38:33.963567 27157 master.cpp:2736] Received SUBSCRIBE call for framework 'default'
at scheduler-297bf554-741b-4cc5-9e7f-005eabd6a2ef@172.17.0.2:58573
I0208 16:38:33.963594 27157 master.cpp:2159] Authorizing framework principal 'test-principal'
to receive offers for role '*'
I0208 16:38:33.963757 27157 master.cpp:2812] Subscribing framework default with checkpointing
disabled and capabilities [  ]
I0208 16:38:33.963939 27159 slave.cpp:931] New master detected at master@172.17.0.2:58573
I0208 16:38:33.963968 27158 sched.cpp:759] Framework registered with 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
I0208 16:38:33.963991 27158 sched.cpp:773] Scheduler::registered took 10622ns
I0208 16:38:33.964012 27165 hierarchical.cpp:286] Added framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
I0208 16:38:33.964025 27159 slave.cpp:966] Detecting new master
I0208 16:38:33.964082 27165 hierarchical.cpp:1802] No allocations performed
I0208 16:38:33.964136 27165 hierarchical.cpp:1892] No inverse offers to send out!
I0208 16:38:33.964182 27156 status_update_manager.cpp:177] Pausing sending status updates
I0208 16:38:33.964187 27165 hierarchical.cpp:1386] Performed allocation for 0 agents in 113382ns
I0208 16:38:33.972167 27159 slave.cpp:993] Authenticating with master master@172.17.0.2:58573
I0208 16:38:33.972198 27159 slave.cpp:1004] Using default CRAM-MD5 authenticatee
I0208 16:38:33.972306 27166 authenticatee.cpp:121] Creating new client SASL connection
I0208 16:38:33.972517 27159 master.cpp:7103] Authenticating slave(287)@172.17.0.2:58573
I0208 16:38:33.972581 27166 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(634)@172.17.0.2:58573
I0208 16:38:33.972734 27160 authenticator.cpp:98] Creating new server SASL connection
I0208 16:38:33.972935 27159 authenticatee.cpp:213] Received SASL authentication mechanisms:
CRAM-MD5
I0208 16:38:33.972966 27159 authenticatee.cpp:239] Attempting to authenticate with mechanism
'CRAM-MD5'
I0208 16:38:33.973063 27171 authenticator.cpp:204] Received SASL authentication start
I0208 16:38:33.973130 27171 authenticator.cpp:326] Authentication requires more steps
I0208 16:38:33.973202 27171 authenticatee.cpp:259] Received SASL authentication step
I0208 16:38:33.973315 27160 authenticator.cpp:232] Received SASL authentication step
I0208 16:38:33.973345 27160 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '324c8fc3066c' server FQDN: '324c8fc3066c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0208 16:38:33.973366 27160 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0208 16:38:33.973388 27160 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0208 16:38:33.973407 27160 auxprop.cpp:109] Request to lookup properties for user: 'test-principal'
realm: '324c8fc3066c' server FQDN: '324c8fc3066c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0208 16:38:33.973423 27160 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0208 16:38:33.973438 27160 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0208 16:38:33.973462 27160 authenticator.cpp:318] Authentication success
I0208 16:38:33.973531 27160 authenticatee.cpp:299] Authentication success
I0208 16:38:33.973573 27160 master.cpp:7133] Successfully authenticated principal 'test-principal'
at slave(287)@172.17.0.2:58573
I0208 16:38:33.973618 27160 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(634)@172.17.0.2:58573
I0208 16:38:33.973711 27160 slave.cpp:1088] Successfully authenticated with master master@172.17.0.2:58573
I0208 16:38:33.973886 27160 slave.cpp:1515] Will retry registration in 3.832328ms if necessary
I0208 16:38:33.974040 27167 master.cpp:5318] Registering agent at slave(287)@172.17.0.2:58573
(324c8fc3066c) with id 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
I0208 16:38:33.974295 27159 registrar.cpp:461] Applied 1 operations in 21282ns; attempting
to update the registry
I0208 16:38:33.974882 27159 registrar.cpp:506] Successfully updated the registry in 515072ns
I0208 16:38:33.975183 27164 master.cpp:5392] Registered agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
at slave(287)@172.17.0.2:58573 (324c8fc3066c) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0208 16:38:33.975226 27171 slave.cpp:4301] Received ping from slave-observer(280)@172.17.0.2:58573
I0208 16:38:33.975291 27171 slave.cpp:1134] Registered with master master@172.17.0.2:58573;
given agent ID 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
I0208 16:38:33.975312 27171 fetcher.cpp:90] Clearing fetcher cache
I0208 16:38:33.975291 27165 hierarchical.cpp:516] Added agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
(324c8fc3066c) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated:
{})
I0208 16:38:33.975450 27166 status_update_manager.cpp:184] Resuming sending status updates
I0208 16:38:33.975698 27171 slave.cpp:1162] Checkpointing SlaveInfo to '/tmp/MasterTest_MultipleExecutors_aSgYXC/meta/slaves/13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0/slave.info'
I0208 16:38:33.975705 27165 hierarchical.cpp:1892] No inverse offers to send out!
I0208 16:38:33.975723 27165 hierarchical.cpp:1386] Performed allocation for 1 agents in 328835ns
I0208 16:38:33.975868 27159 master.cpp:6932] Sending 1 offers to framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
(default) at scheduler-297bf554-741b-4cc5-9e7f-005eabd6a2ef@172.17.0.2:58573
I0208 16:38:33.976008 27171 slave.cpp:1200] Forwarding total oversubscribed resources {}
I0208 16:38:33.976097 27171 master.cpp:5949] Received update of agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
at slave(287)@172.17.0.2:58573 (324c8fc3066c) with total oversubscribed resources {}
I0208 16:38:33.976104 27165 sched.cpp:933] Scheduler::resourceOffers took 56984ns
I0208 16:38:33.976205 27159 hierarchical.cpp:589] Agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
(324c8fc3066c) updated with oversubscribed resources {} (total: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000], allocated: cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated:
*):1024; ports(*)(allocated: *):[31000-32000])
I0208 16:38:33.976269 27159 hierarchical.cpp:1802] No allocations performed
I0208 16:38:33.976284 27159 hierarchical.cpp:1892] No inverse offers to send out!
I0208 16:38:33.976296 27159 hierarchical.cpp:1386] Performed allocation for 1 agents in 39877ns
I0208 16:38:33.977018 27162 master.cpp:3745] Processing ACCEPT call for offers: [ 13d72473-a4c3-4b2b-974e-5a00c53c1c58-O0
] on agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0 at slave(287)@172.17.0.2:58573 (324c8fc3066c)
for framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000 (default) at scheduler-297bf554-741b-4cc5-9e7f-005eabd6a2ef@172.17.0.2:58573
I0208 16:38:33.977068 27162 master.cpp:3318] Authorizing framework principal 'test-principal'
to launch task 1
I0208 16:38:33.977167 27162 master.cpp:3318] Authorizing framework principal 'test-principal'
to launch task 2
W0208 16:38:33.977768 27163 validation.cpp:1062] 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.
W0208 16:38:33.977792 27163 validation.cpp:1074] 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.
I0208 16:38:33.977864 27163 master.cpp:8993] Adding task 1 with resources cpus(*)(allocated:
*):1; mem(*)(allocated: *):512 on agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0 at slave(287)@172.17.0.2:58573
(324c8fc3066c)
I0208 16:38:33.977916 27163 master.cpp:4395] Launching task 1 of framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
(default) at scheduler-297bf554-741b-4cc5-9e7f-005eabd6a2ef@172.17.0.2:58573 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
at slave(287)@172.17.0.2:58573 (324c8fc3066c)
I0208 16:38:33.978055 27165 slave.cpp:1583] Got assigned task '1' for framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
W0208 16:38:33.978186 27163 validation.cpp:1062] 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.
W0208 16:38:33.978207 27163 validation.cpp:1074] 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.
I0208 16:38:33.978281 27163 master.cpp:8993] Adding task 2 with resources cpus(*)(allocated:
*):1; mem(*)(allocated: *):512 on agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0 at slave(287)@172.17.0.2:58573
(324c8fc3066c)
I0208 16:38:33.978333 27163 master.cpp:4395] Launching task 2 of framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
(default) at scheduler-297bf554-741b-4cc5-9e7f-005eabd6a2ef@172.17.0.2:58573 with resources
cpus(*)(allocated: *):1; mem(*)(allocated: *):512 on agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
at slave(287)@172.17.0.2:58573 (324c8fc3066c)
I0208 16:38:33.978442 27165 slave.cpp:1743] Launching task '1' for framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
I0208 16:38:33.978842 27162 hierarchical.cpp:807] Updated allocation of framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
on agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-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]
I0208 16:38:33.978972 27162 hierarchical.cpp:1064] 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 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0
from framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
I0208 16:38:33.979001 27162 hierarchical.cpp:1101] Framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
filtered agent 13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0 for 5secs
I0208 16:38:33.979019 27165 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_aSgYXC/slaves/13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0/frameworks/13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000/executors/executor-1/runs/40c9a427-b7ef-4cd5-93ab-57f1cdb14ae5'
to user 'mesos'
I0208 16:38:33.979291 27165 slave.cpp:6414] Launching executor 'executor-1' of framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_aSgYXC/slaves/13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0/frameworks/13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000/executors/executor-1/runs/40c9a427-b7ef-4cd5-93ab-57f1cdb14ae5'
I0208 16:38:33.979460 27165 slave.cpp:2073] Queued task '1' for executor 'executor-1' of framework
13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
I0208 16:38:33.979534 27165 slave.cpp:1583] Got assigned task '2' for framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
I0208 16:38:33.979712 27165 slave.cpp:884] Successfully attached file '/tmp/MasterTest_MultipleExecutors_aSgYXC/slaves/13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0/frameworks/13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000/executors/executor-1/runs/40c9a427-b7ef-4cd5-93ab-57f1cdb14ae5'
I0208 16:38:33.979751 27165 slave.cpp:1743] Launching task '2' for framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
I0208 16:38:33.980049 27165 paths.cpp:547] Trying to chown '/tmp/MasterTest_MultipleExecutors_aSgYXC/slaves/13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0/frameworks/13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000/executors/executor-2/runs/44b8550a-fe83-462a-934c-3a27e9a2fcfc'
to user 'mesos'
I0208 16:38:33.980181 27165 slave.cpp:6414] Launching executor 'executor-2' of framework 13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000
with resources {} in work directory '/tmp/MasterTest_MultipleExecutors_aSgYXC/slaves/13d72473-a4c3-4b2b-974e-5a00c53c1c58-S0/frameworks/13d72473-a4c3-4b2b-974e-5a00c53c1c58-0000/executors/executor-2/runs/44b8550a-fe83-462a-934c-3a27e9a2fcfc'
*** Aborted at 1486571913 (unix time) try "date -d @1486571913" if you are using GNU date
***
I0208 16:38:33.980386 27163 exec.cpp:162] Version: 1.2.0
PC: @     0x2b1d379f282d (unknown)
I0208 16:38:33.980623 27163 exec.cpp:212] Executor started at: executor(121)@172.17.0.2:58573
with pid 27155
*** SIGSEGV (@0xd0) received by PID 27155 (TID 0x2b1d40b32700) from PID 208; stack trace:
***
    @     0x2b1d36f89330 (unknown)
    @     0x2b1d379f282d (unknown)
    @     0x2b1d34502684 mesos::internal::slave::executorEnvironment()
    @     0x2b1d3452d3f8 mesos::internal::slave::Framework::launchExecutor()
    @     0x2b1d345302f7 mesos::internal::slave::Slave::_run()
    @     0x2b1d357b5511 process::ProcessManager::resume()
    @     0x2b1d357be347 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
    @     0x2b1d37248a60 (unknown)
    @     0x2b1d36f81184 start_thread
    @     0x2b1d37ab137d (unknown)
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-1486568952-12701
Untagged: mesos-1486568952-12701:latest
Deleted: sha256:55d2bd8a912ab978ed83b272509acb19e75ec647b1e363e17854d88bd5815a43
Deleted: sha256:1f5b17fba653c82142c17a1cb08ea1b1bf0b186ae7c6f581b2027515b8ec6e82
Deleted: sha256:8b943ea28dca057fe400f6d504ba4e88ee53696bd1bbec5160bea357deb9b750
Deleted: sha256:d81159ab3a71a52988a5ac2510fff7708a973fd594b04d620519bd23d3d06bc3
Deleted: sha256:2c466bcf6c9573d1d796d23f5a00e03f690aeef88a73655c3105d9083ac86293
Deleted: sha256:e0b78dd41693cfc96ab75762448a368c23e9f21715f7b73cacb71c11557e2d5e
Deleted: sha256:6ebc55f3f248394052e80a1a26295aac28eea7f5693080afecd4d92cc0d6c899
Deleted: sha256:eea7f96f9bd57fbf9f2d4c908575dba32f2267db08433bcea8552ac765c9336b
Deleted: sha256:63e57c213eb5c1c99316de608905187d2ec9910778b206b6b0676ac8db8ed13f
Deleted: sha256:575d83bf1e9246f1526e078fb4191877241c80662cff5ee1bbf8a419f859b717
Deleted: sha256:b5e0538b69ca98ba8bc2a82dce526d4a70cd750b79844e1e7863b69d212d3363
Deleted: sha256:a2bb94f3f3fdbafecfe921068c260ebe4084e7b5f54356b1273d8b7cfb131c1d
Deleted: sha256:47dbb449b205ce139063ef1fe96df2f907123565227a7e70efc349c19e3fd414
Deleted: sha256:8b10f1e9e578ba4e68e1295ad3cd165dcfc3641852d65df6366d0274f54e57fc
Deleted: sha256:ddf2cec64d86a3759d3aa8b6cf04be049e3a9f261fb4869e3c59d27f1b560150
Deleted: sha256:a5e63c843ad175ca44db4ee7a5ce363a325cc27cb87ff0b81382b0aa45e81031
Deleted: sha256:1afbe4cb17e65dbce5cd4800af45c6bb059cb35240f80ab75e6f64efc1c093ec
Deleted: sha256:927f72a85cc85cdb87bfb06cd944a24fb7d28ee3d798fb2fd2167999e226a47b
Deleted: sha256:4c80cc1f45038856fe9b66b11790efa377bc78f2158ddba82ed7ccd76549939d
Deleted: sha256:a88a7876d23c37d740150f85b9a93b351450ab27408f235d517ac1049c0a115e
Deleted: sha256:1b9e2fe9a15f8477cd90aaeb50bdfa7bb17ebb11cd7131b2eca8b180c334c11d
Deleted: sha256:4198499c220562852792ffe25d142c4b41c313c26013e2da96441636f26f42a3
Deleted: sha256:42e3a179e09c16e68412d67885e67538a31257d41192d84d2d1500851cbb849e
Deleted: sha256:fdea8203d9e8e8196786961a012c35a3ad57c8d53c7ba032444f9924b0f55603
Build step 'Execute shell' marked build as failure

Mime
View raw message