See <https://builds.apache.org/job/Mesos-Reviewbot/17718/display/redirect>
------------------------------------------
[...truncated 22.08 MB...]
I0411 03:22:46.971563 30683 coordinator.cpp:348] Coordinator attempting to
write TRUNCATE action at position 8
I0411 03:22:46.972326 30683 slave.cpp:4711] Received ping from
slave-observer(547)@172.17.0.2:41283
I0411 03:22:46.972465 30695 master.cpp:5918] Re-registered agent
1ce86f48-a18a-476d-8997-83e08a901b66-S0 at slave(572)@172.17.0.2:41283
(53465a10ac97) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0411 03:22:46.972586 30695 master.cpp:6024] Sending updated checkpointed
resources {} to agent 1ce86f48-a18a-476d-8997-83e08a901b66-S0 at
slave(572)@172.17.0.2:41283 (53465a10ac97)
I0411 03:22:46.972697 30683 slave.cpp:1195] Re-registered with master
[email protected]:41283
I0411 03:22:46.972795 30683 slave.cpp:1232] Forwarding total oversubscribed
resources {}
I0411 03:22:46.972856 30688 hierarchical.cpp:527] Added agent
1ce86f48-a18a-476d-8997-83e08a901b66-S0 (53465a10ac97) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0411 03:22:46.972906 30695 status_update_manager.cpp:184] Resuming sending
status updates
I0411 03:22:46.972945 30683 slave.cpp:3255] Ignoring new checkpointed resources
identical to the current version: {}
I0411 03:22:46.973038 30696 replica.cpp:539] Replica received write request for
position 8 from __req_res__(1661)@172.17.0.2:41283
I0411 03:22:46.973146 30688 hierarchical.cpp:1862] No allocations performed
I0411 03:22:46.973209 30688 hierarchical.cpp:1446] Performed allocation for 1
agents in 206708ns
I0411 03:22:46.973280 30683 master.cpp:6115] Received update of agent
1ce86f48-a18a-476d-8997-83e08a901b66-S0 at slave(572)@172.17.0.2:41283
(53465a10ac97) with total oversubscribed resources {}
I0411 03:22:46.973512 30696 leveldb.cpp:341] Persisting action (16 bytes) to
leveldb took 414740ns
I0411 03:22:46.973776 30696 replica.cpp:710] Persisted action TRUNCATE at
position 8
I0411 03:22:46.974716 30688 replica.cpp:693] Replica received learned notice
for position 8 from @0.0.0.0:0
I0411 03:22:46.975250 30688 leveldb.cpp:341] Persisting action (18 bytes) to
leveldb took 343436ns
I0411 03:22:46.975527 30688 leveldb.cpp:399] Deleting ~2 keys from leveldb took
45139ns
I0411 03:22:46.975556 30688 replica.cpp:710] Persisted action TRUNCATE at
position 8
I0411 03:22:46.976507 30683 process.cpp:3722] Handling HTTP event for process
'master' with path: '/master/api/v1'
I0411 03:22:46.978170 30692 http.cpp:1115] HTTP POST for /master/api/v1 from
172.17.0.2:47518
I0411 03:22:46.978385 30692 http.cpp:538] Processing call GET_AGENTS
I0411 03:22:46.983803 30692 slave.cpp:756] Agent terminating
I0411 03:22:46.984259 30682 master.cpp:1318] Agent
1ce86f48-a18a-476d-8997-83e08a901b66-S0 at slave(572)@172.17.0.2:41283
(53465a10ac97) disconnected
I0411 03:22:46.984474 30682 master.cpp:3202] Disconnecting agent
1ce86f48-a18a-476d-8997-83e08a901b66-S0 at slave(572)@172.17.0.2:41283
(53465a10ac97)
I0411 03:22:46.984601 30682 master.cpp:3221] Deactivating agent
1ce86f48-a18a-476d-8997-83e08a901b66-S0 at slave(572)@172.17.0.2:41283
(53465a10ac97)
I0411 03:22:46.984889 30682 hierarchical.cpp:655] Agent
1ce86f48-a18a-476d-8997-83e08a901b66-S0 deactivated
I0411 03:22:47.005434 30696 master.cpp:1157] Master terminating
I0411 03:22:47.006379 30693 hierarchical.cpp:560] Removed agent
1ce86f48-a18a-476d-8997-83e08a901b66-S0
[ OK ] ContentType/MasterAPITest.GetRecoveredAgents/1 (223 ms)
[ RUN ] ContentType/MasterAPITest.Subscribe/0
I0411 03:22:47.015697 30663 cluster.cpp:162] Creating default 'local' authorizer
I0411 03:22:47.018998 30690 master.cpp:438] Master
5bd03197-56d9-4820-83ac-a18390cbe727 (53465a10ac97) started on 172.17.0.2:41283
I0411 03:22:47.019029 30690 master.cpp:440] 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/37US3f/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="/mesos/mesos-1.3.0/_inst/share/mesos/webui"
--work_dir="/tmp/37US3f/master" --zk_session_timeout="10secs"
I0411 03:22:47.019374 30690 master.cpp:490] Master only allowing authenticated
frameworks to register
I0411 03:22:47.019388 30690 master.cpp:504] Master only allowing authenticated
agents to register
I0411 03:22:47.019393 30690 master.cpp:517] Master only allowing authenticated
HTTP frameworks to register
I0411 03:22:47.019399 30690 credentials.hpp:37] Loading credentials for
authentication from '/tmp/37US3f/credentials'
I0411 03:22:47.019670 30690 master.cpp:562] Using default 'crammd5'
authenticator
I0411 03:22:47.019814 30690 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readonly'
I0411 03:22:47.019948 30690 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-readwrite'
I0411 03:22:47.020040 30690 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-master-scheduler'
I0411 03:22:47.020133 30690 master.cpp:642] Authorization enabled
I0411 03:22:47.020642 30685 whitelist_watcher.cpp:77] No whitelist given
I0411 03:22:47.020692 30695 hierarchical.cpp:159] Initialized hierarchical
allocator process
I0411 03:22:47.023517 30694 master.cpp:2166] Elected as the leading master!
I0411 03:22:47.023550 30694 master.cpp:1705] Recovering from registrar
I0411 03:22:47.023716 30696 registrar.cpp:331] Recovering registrar
I0411 03:22:47.024353 30692 registrar.cpp:364] Successfully fetched the
registry (0B) in 582912ns
I0411 03:22:47.024459 30692 registrar.cpp:463] Applied 1 operations in 18257ns;
attempting to update the registry
I0411 03:22:47.025410 30692 registrar.cpp:508] Successfully updated the
registry in 896us
I0411 03:22:47.025530 30692 registrar.cpp:394] Successfully recovered registrar
I0411 03:22:47.026029 30693 master.cpp:1804] Recovered 0 agents from the
registry (129B); allowing 10mins for agents to re-register
I0411 03:22:47.026360 30689 hierarchical.cpp:186] Skipping recovery of
hierarchical allocator: nothing to recover
I0411 03:22:47.031286 30663 cluster.cpp:448] Creating default 'local' authorizer
I0411 03:22:47.033517 30685 slave.cpp:225] Mesos agent started on
(573)@172.17.0.2:41283
I0411 03:22:47.033558 30685 slave.cpp:226] 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/ContentType_MasterAPITest_Subscribe_0_XHSzni/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/ContentType_MasterAPITest_Subscribe_0_XHSzni/fetch"
--fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks"
--gc_disk_headroom="0.1" --hadoop_home="" --help="false"
--hostname_lookup="true" --http_command_executor="false"
--http_credentials="/tmp/ContentType_MasterAPITest_Subscribe_0_XHSzni/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" --port="5051" --qos_correction_interval_min="0ns"
--quiet="false" --recover="reconnect" --recovery_timeout="15mins"
--registration_backoff_factor="10ms"
--resources="cpus:2;gpus:0;mem:1024;disk:1024;ports:[31000-32000]"
--revocable_cpu_low_priority="true"
--runtime_dir="/tmp/ContentType_MasterAPITest_Subscribe_0_XHSzni"
--sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true"
--systemd_enable_support="true"
--systemd_runtime_directory="/run/systemd/system" --version="false"
--work_dir="/tmp/ContentType_MasterAPITest_Subscribe_0_qzDIdM"
I0411 03:22:47.034019 30685 credentials.hpp:86] Loading credential for
authentication from
'/tmp/ContentType_MasterAPITest_Subscribe_0_XHSzni/credential'
I0411 03:22:47.034263 30685 slave.cpp:258] Agent using credential for:
test-principal
I0411 03:22:47.034284 30685 credentials.hpp:37] Loading credentials for
authentication from
'/tmp/ContentType_MasterAPITest_Subscribe_0_XHSzni/http_credentials'
I0411 03:22:47.034287 30663 scheduler.cpp:184] Version: 1.3.0
I0411 03:22:47.034529 30685 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readonly'
I0411 03:22:47.034746 30685 http.cpp:975] Creating default 'basic' HTTP
authenticator for realm 'mesos-agent-readwrite'
I0411 03:22:47.036159 30685 slave.cpp:491] Agent resources: cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0411 03:22:47.036240 30685 slave.cpp:499] Agent attributes: [ ]
I0411 03:22:47.036281 30685 slave.cpp:504] Agent hostname: 53465a10ac97
I0411 03:22:47.037786 30685 status_update_manager.cpp:177] Pausing sending
status updates
I0411 03:22:47.038444 30685 state.cpp:62] Recovering state from
'/tmp/ContentType_MasterAPITest_Subscribe_0_qzDIdM/meta'
I0411 03:22:47.038915 30685 status_update_manager.cpp:203] Recovering status
update manager
I0411 03:22:47.039225 30685 slave.cpp:5929] Finished recovery
I0411 03:22:47.039721 30685 slave.cpp:6111] Querying resource estimator for
oversubscribable resources
I0411 03:22:47.040211 30685 slave.cpp:884] New master detected at
[email protected]:41283
I0411 03:22:47.040367 30685 slave.cpp:919] Detecting new master
I0411 03:22:47.040493 30685 slave.cpp:6125] Received oversubscribable resources
{} from the resource estimator
I0411 03:22:47.040632 30689 status_update_manager.cpp:177] Pausing sending
status updates
I0411 03:22:47.041260 30686 scheduler.cpp:470] New master detected at
[email protected]:41283
I0411 03:22:47.041479 30686 scheduler.cpp:479] Waiting for 0ns before
initiating a re-(connection) attempt with the master
I0411 03:22:47.045330 30682 scheduler.cpp:361] Connected with the master at
http://172.17.0.2:41283/master/api/v1/scheduler
I0411 03:22:47.047094 30687 scheduler.cpp:243] Sending SUBSCRIBE call to
http://172.17.0.2:41283/master/api/v1/scheduler
I0411 03:22:47.052938 30693 slave.cpp:946] Authenticating with master
[email protected]:41283
I0411 03:22:47.053082 30693 slave.cpp:957] Using default CRAM-MD5 authenticatee
I0411 03:22:47.053453 30694 authenticatee.cpp:121] Creating new client SASL
connection
I0411 03:22:47.053839 30689 master.cpp:7269] Authenticating
slave(573)@172.17.0.2:41283
I0411 03:22:47.054011 30691 authenticator.cpp:414] Starting authentication
session for crammd5-authenticatee(1155)@172.17.0.2:41283
I0411 03:22:47.054270 30689 authenticator.cpp:98] Creating new server SASL
connection
I0411 03:22:47.054476 30691 authenticatee.cpp:213] Received SASL authentication
mechanisms: CRAM-MD5
I0411 03:22:47.054708 30691 authenticatee.cpp:239] Attempting to authenticate
with mechanism 'CRAM-MD5'
I0411 03:22:47.054913 30691 authenticator.cpp:204] Received SASL authentication
start
I0411 03:22:47.054600 30689 process.cpp:3722] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0411 03:22:47.055151 30691 authenticator.cpp:326] Authentication requires more
steps
I0411 03:22:47.055347 30691 authenticatee.cpp:259] Received SASL authentication
step
I0411 03:22:47.055547 30691 authenticator.cpp:232] Received SASL authentication
step
I0411 03:22:47.055671 30691 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '53465a10ac97' server FQDN: '53465a10ac97'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: false
I0411 03:22:47.055771 30691 auxprop.cpp:181] Looking up auxiliary property
'*userPassword'
I0411 03:22:47.055899 30691 auxprop.cpp:181] Looking up auxiliary property
'*cmusaslsecretCRAM-MD5'
I0411 03:22:47.056833 30691 auxprop.cpp:109] Request to lookup properties for
user: 'test-principal' realm: '53465a10ac97' server FQDN: '53465a10ac97'
SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false
SASL_AUXPROP_AUTHZID: true
I0411 03:22:47.057178 30691 auxprop.cpp:131] Skipping auxiliary property
'*userPassword' since SASL_AUXPROP_AUTHZID == true
I0411 03:22:47.057221 30691 auxprop.cpp:131] Skipping auxiliary property
'*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0411 03:22:47.057353 30691 authenticator.cpp:318] Authentication success
I0411 03:22:47.057654 30691 authenticatee.cpp:299] Authentication success
I0411 03:22:47.057804 30683 authenticator.cpp:432] Authentication session
cleanup for crammd5-authenticatee(1155)@172.17.0.2:41283
I0411 03:22:47.056967 30694 http.cpp:1115] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:47520
I0411 03:22:47.058228 30688 slave.cpp:1041] Successfully authenticated with
master [email protected]:41283
I0411 03:22:47.058241 30694 master.cpp:2518] Received subscription request for
HTTP framework 'default'
I0411 03:22:47.058357 30694 master.cpp:2202] Authorizing framework principal
'test-principal' to receive offers for roles '{ * }'
I0411 03:22:47.058478 30688 slave.cpp:1469] Will retry registration in
17.977775ms if necessary
I0411 03:22:47.058789 30694 master.cpp:7299] Successfully authenticated
principal 'test-principal' at slave(573)@172.17.0.2:41283
I0411 03:22:47.059134 30694 master.cpp:5454] Registering agent at
slave(573)@172.17.0.2:41283 (53465a10ac97) with id
5bd03197-56d9-4820-83ac-a18390cbe727-S0
I0411 03:22:47.059711 30696 registrar.cpp:463] Applied 1 operations in 69836ns;
attempting to update the registry
I0411 03:22:47.060626 30694 master.cpp:2633] Subscribing framework 'default'
with checkpointing disabled and capabilities [ ]
I0411 03:22:47.061539 30686 hierarchical.cpp:271] Added framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.061584 30694 registrar.cpp:508] Successfully updated the
registry in 1.783296ms
I0411 03:22:47.061684 30686 hierarchical.cpp:1862] No allocations performed
I0411 03:22:47.061730 30686 hierarchical.cpp:1952] No inverse offers to send
out!
I0411 03:22:47.061789 30686 hierarchical.cpp:1446] Performed allocation for 0
agents in 150674ns
I0411 03:22:47.061959 30694 master.hpp:2167] Sending heartbeat to
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.063002 30687 scheduler.cpp:676] Enqueuing event SUBSCRIBED
received from http://172.17.0.2:41283/master/api/v1/scheduler
I0411 03:22:47.063344 30683 hierarchical.cpp:527] Added agent
5bd03197-56d9-4820-83ac-a18390cbe727-S0 (53465a10ac97) with cpus(*):2;
mem(*):1024; disk(*):1024; ports(*):[31000-32000] (allocated: {})
I0411 03:22:47.064149 30696 scheduler.cpp:676] Enqueuing event HEARTBEAT
received from http://172.17.0.2:41283/master/api/v1/scheduler
I0411 03:22:47.064554 30683 hierarchical.cpp:1952] No inverse offers to send
out!
I0411 03:22:47.064667 30694 slave.cpp:4711] Received ping from
slave-observer(548)@172.17.0.2:41283
I0411 03:22:47.064678 30683 hierarchical.cpp:1446] Performed allocation for 1
agents in 1.193292ms
I0411 03:22:47.064643 30686 master.cpp:5528] Registered agent
5bd03197-56d9-4820-83ac-a18390cbe727-S0 at slave(573)@172.17.0.2:41283
(53465a10ac97) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0411 03:22:47.065022 30694 slave.cpp:1087] Registered with master
[email protected]:41283; given agent ID 5bd03197-56d9-4820-83ac-a18390cbe727-S0
I0411 03:22:47.065045 30694 fetcher.cpp:94] Clearing fetcher cache
I0411 03:22:47.065254 30689 status_update_manager.cpp:184] Resuming sending
status updates
I0411 03:22:47.065456 30686 master.cpp:7098] Sending 1 offers to framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000 (default)
I0411 03:22:47.065510 30694 slave.cpp:1115] Checkpointing SlaveInfo to
'/tmp/ContentType_MasterAPITest_Subscribe_0_qzDIdM/meta/slaves/5bd03197-56d9-4820-83ac-a18390cbe727-S0/slave.info'
I0411 03:22:47.065907 30694 slave.cpp:1153] Forwarding total oversubscribed
resources {}
I0411 03:22:47.066164 30686 master.cpp:6115] Received update of agent
5bd03197-56d9-4820-83ac-a18390cbe727-S0 at slave(573)@172.17.0.2:41283
(53465a10ac97) with total oversubscribed resources {}
I0411 03:22:47.067657 30683 scheduler.cpp:676] Enqueuing event OFFERS received
from http://172.17.0.2:41283/master/api/v1/scheduler
I0411 03:22:47.072052 30696 process.cpp:3722] Handling HTTP event for process
'master' with path: '/master/api/v1'
I0411 03:22:47.073834 30688 http.cpp:1115] HTTP POST for /master/api/v1 from
172.17.0.2:47521
I0411 03:22:47.073951 30688 http.cpp:538] Processing call SUBSCRIBE
I0411 03:22:47.075232 30696 master.cpp:9013] Added subscriber:
cfa70bfa-26bf-4230-b968-5a9e50c822e7 to the list of active subscribers
I0411 03:22:47.084362 30693 scheduler.cpp:243] Sending ACCEPT call to
http://172.17.0.2:41283/master/api/v1/scheduler
I0411 03:22:47.086663 30689 process.cpp:3722] Handling HTTP event for process
'master' with path: '/master/api/v1/scheduler'
I0411 03:22:47.089334 30691 http.cpp:1115] HTTP POST for
/master/api/v1/scheduler from 172.17.0.2:47519
I0411 03:22:47.090636 30691 master.cpp:3856] Processing ACCEPT call for offers:
[ 5bd03197-56d9-4820-83ac-a18390cbe727-O0 ] on agent
5bd03197-56d9-4820-83ac-a18390cbe727-S0 at slave(573)@172.17.0.2:41283
(53465a10ac97) for framework 5bd03197-56d9-4820-83ac-a18390cbe727-0000 (default)
I0411 03:22:47.090739 30691 master.cpp:3432] Authorizing framework principal
'test-principal' to launch task f76818f7-3c25-437f-85b5-57694d4c0f39
W0411 03:22:47.093452 30691 validation.cpp:1113] Executor 'default' for task
'f76818f7-3c25-437f-85b5-57694d4c0f39' uses less CPUs (None) than the minimum
required (0.01). Please update your executor, as this will be mandatory in
future releases.
W0411 03:22:47.093502 30691 validation.cpp:1125] Executor 'default' for task
'f76818f7-3c25-437f-85b5-57694d4c0f39' uses less memory (None) than the minimum
required (32MB). Please update your executor, as this will be mandatory in
future releases.
I0411 03:22:47.094149 30691 master.cpp:8991] Notifying all active subscribers
about TASK_ADDED event
I0411 03:22:47.094571 30691 master.cpp:9114] Adding task
f76818f7-3c25-437f-85b5-57694d4c0f39 with resources cpus(*)(allocated: *):2;
mem(*)(allocated: *):1024; disk(*)(allocated: *):1024; ports(*)(allocated:
*):[31000-32000] on agent 5bd03197-56d9-4820-83ac-a18390cbe727-S0 at
slave(573)@172.17.0.2:41283 (53465a10ac97)
I0411 03:22:47.094976 30691 master.cpp:4506] Launching task
f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000 (default) with resources
cpus(*)(allocated: *):2; mem(*)(allocated: *):1024; disk(*)(allocated: *):1024;
ports(*)(allocated: *):[31000-32000] on agent
5bd03197-56d9-4820-83ac-a18390cbe727-S0 at slave(573)@172.17.0.2:41283
(53465a10ac97)
I0411 03:22:47.095522 30690 slave.cpp:1579] Got assigned task
'f76818f7-3c25-437f-85b5-57694d4c0f39' for framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.096431 30690 slave.cpp:1860] Authorizing task
'f76818f7-3c25-437f-85b5-57694d4c0f39' for framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.096499 30690 slave.cpp:6548] Authorizing framework principal
'test-principal' to launch task f76818f7-3c25-437f-85b5-57694d4c0f39
I0411 03:22:47.097582 30695 slave.cpp:2047] Launching task
'f76818f7-3c25-437f-85b5-57694d4c0f39' for framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.097646 30683 hierarchical.cpp:852] Updated allocation of
framework 5bd03197-56d9-4820-83ac-a18390cbe727-0000 on agent
5bd03197-56d9-4820-83ac-a18390cbe727-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]
I0411 03:22:47.098224 30695 paths.cpp:554] Trying to chown
'/tmp/ContentType_MasterAPITest_Subscribe_0_qzDIdM/slaves/5bd03197-56d9-4820-83ac-a18390cbe727-S0/frameworks/5bd03197-56d9-4820-83ac-a18390cbe727-0000/executors/default/runs/4816684e-49c1-4201-b99b-9b63d3e4769f'
to user 'mesos'
I0411 03:22:47.098528 30695 slave.cpp:6892] Launching executor 'default' of
framework 5bd03197-56d9-4820-83ac-a18390cbe727-0000 with resources {} in work
directory
'/tmp/ContentType_MasterAPITest_Subscribe_0_qzDIdM/slaves/5bd03197-56d9-4820-83ac-a18390cbe727-S0/frameworks/5bd03197-56d9-4820-83ac-a18390cbe727-0000/executors/default/runs/4816684e-49c1-4201-b99b-9b63d3e4769f'
I0411 03:22:47.099336 30695 slave.cpp:2276] Queued task
'f76818f7-3c25-437f-85b5-57694d4c0f39' for executor 'default' of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.099428 30695 slave.cpp:837] Successfully attached file
'/tmp/ContentType_MasterAPITest_Subscribe_0_qzDIdM/slaves/5bd03197-56d9-4820-83ac-a18390cbe727-S0/frameworks/5bd03197-56d9-4820-83ac-a18390cbe727-0000/executors/default/runs/4816684e-49c1-4201-b99b-9b63d3e4769f'
*** Aborted at 1491880967 (unix time) try "date -d @1491880967" if you are
using GNU date ***
PC: @ 0x2b42baca882d (unknown)
*** SIGSEGV (@0xd0) received by PID 30663 (TID 0x2b42c4927700) from PID 208;
stack trace: ***
I0411 03:22:47.103080 30690 executor.cpp:192] Version: 1.3.0
@ 0x2b43107d92d7 (unknown)
@ 0x2b43107dd5b9 (unknown)
@ 0x2b42baa5f330 (unknown)
@ 0x2b42baca882d (unknown)
I0411 03:22:47.105777 30693 executor.cpp:410] Connected with the agent
@ 0x2b42bac9e6f1 (unknown)
@ 0x2b42bacf588e (unknown)
I0411 03:22:47.106637 30697 executor.cpp:307] Sending SUBSCRIBE call to
http://172.17.0.2:41283/slave(573)/api/v1/executor
I0411 03:22:47.108635 30691 process.cpp:3722] Handling HTTP event for process
'slave(573)' with path: '/slave(573)/api/v1/executor'
I0411 03:22:47.110093 30692 http.cpp:1115] HTTP POST for
/slave(573)/api/v1/executor from 172.17.0.2:47522
I0411 03:22:47.110328 30692 slave.cpp:3534] Received Subscribe request for HTTP
executor 'default' of framework 5bd03197-56d9-4820-83ac-a18390cbe727-0000
@ 0x2b42b050b28b os::strerror()
I0411 03:22:47.112192 30685 slave.cpp:2489] Sending queued task
'f76818f7-3c25-437f-85b5-57694d4c0f39' to executor 'default' of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000 (via HTTP)
I0411 03:22:47.114161 30692 executor.cpp:725] Enqueuing event SUBSCRIBED
received from http://172.17.0.2:41283/slave(573)/api/v1/executor
@ 0x2b42b05251f9 ErrnoError::ErrnoError()
I0411 03:22:47.120892 30693 executor.cpp:725] Enqueuing event LAUNCH received
from http://172.17.0.2:41283/slave(573)/api/v1/executor
I0411 03:22:47.121960 30696 executor.cpp:307] Sending UPDATE call to
http://172.17.0.2:41283/slave(573)/api/v1/executor
I0411 03:22:47.123926 30691 process.cpp:3722] Handling HTTP event for process
'slave(573)' with path: '/slave(573)/api/v1/executor'
I0411 03:22:47.125661 30694 http.cpp:1115] HTTP POST for
/slave(573)/api/v1/executor from 172.17.0.2:47523
I0411 03:22:47.126155 30694 slave.cpp:4181] Handling status update TASK_RUNNING
(UUID: 220d102d-799b-4fce-ad15-95ef39963b00) for task
f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
@ 0x2b42b05251d5 ErrnoError::ErrnoError()
I0411 03:22:47.127435 30694 status_update_manager.cpp:323] Received status
update TASK_RUNNING (UUID: 220d102d-799b-4fce-ad15-95ef39963b00) for task
f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.127483 30694 status_update_manager.cpp:500] Creating
StatusUpdate stream for task f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.128031 30694 status_update_manager.cpp:377] Forwarding update
TASK_RUNNING (UUID: 220d102d-799b-4fce-ad15-95ef39963b00) for task
f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000 to the agent
I0411 03:22:47.128693 30691 slave.cpp:4621] Forwarding the update TASK_RUNNING
(UUID: 220d102d-799b-4fce-ad15-95ef39963b00) for task
f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000 to [email protected]:41283
I0411 03:22:47.129169 30691 slave.cpp:4515] Status update manager successfully
handled status update TASK_RUNNING (UUID: 220d102d-799b-4fce-ad15-95ef39963b00)
for task f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.129339 30688 master.cpp:6260] Status update TASK_RUNNING (UUID:
220d102d-799b-4fce-ad15-95ef39963b00) for task
f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000 from agent
5bd03197-56d9-4820-83ac-a18390cbe727-S0 at slave(573)@172.17.0.2:41283
(53465a10ac97)
I0411 03:22:47.129410 30688 master.cpp:6328] Forwarding status update
TASK_RUNNING (UUID: 220d102d-799b-4fce-ad15-95ef39963b00) for task
f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000
I0411 03:22:47.129855 30688 master.cpp:8991] Notifying all active subscribers
about TASK_UPDATED event
@ 0x2b42b865d9c8 _ZZN7process2io8internal4readEiPvmENKUlvE_clEv
I0411 03:22:47.130167 30688 master.cpp:8362] Updating the state of task
f76818f7-3c25-437f-85b5-57694d4c0f39 of framework
5bd03197-56d9-4820-83ac-a18390cbe727-0000 (latest state: TASK_RUNNING, status
update state: TASK_RUNNING)
I0411 03:22:47.131484 30682 executor.cpp:725] Enqueuing event ACKNOWLEDGED
received from http://172.17.0.2:41283/slave(573)/api/v1/executor
I0411 03:22:47.132778 30694 scheduler.cpp:676] Enqueuing event UPDATE received
from http://172.17.0.2:41283/master/api/v1/scheduler
@ 0x2b42b86612a3
_ZN7process8internal4LoopIZNS_2io8internal4readEiPvmEUlvE_ZNS3_4readEiS4_mEUlRK6OptionImEE0_S7_mE5startEv
@ 0x2b42b866077c
_ZN7process4loopIZNS_2io8internal4readEiPvmEUlvE_ZNS2_4readEiS3_mEUlRK6OptionImEE0_S6_NS_11ControlFlowImEEmEENS_6FutureIT3_EERKS5_INS_4UPIDEEOT_OT0_
@ 0x2b42b865dcbb process::io::internal::read()
@ 0x2b42b865e23b process::io::read()
@ 0x2b42b86b7650 process::network::internal::PollSocketImpl::recv()
@ 0x2b42b8759f2e process::network::internal::SocketImpl::recv()
@ 0x2b42b862ed0f process::network::internal::Socket<>::recv()
@ 0x2b42b862b4b2 process::http::internal::ConnectionProcess::read()
@ 0x2b42b862be75 process::http::internal::ConnectionProcess::_read()
@ 0x2b42b8633a83
_ZZN7process8dispatchINS_4http8internal17ConnectionProcessERKNS_6FutureISsEES5_EEvRKNS_3PIDIT_EEMS9_FvT0_ET1_ENKUlPNS_11ProcessBaseEE_clESI_
@ 0x2b42b863cfbc
_ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchINS0_4http8internal17ConnectionProcessERKNS0_6FutureISsEES9_EEvRKNS0_3PIDIT_EEMSD_FvT0_ET1_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
@ 0x2b42b86f6c49 std::function<>::operator()()
@ 0x2b42b86dca37 process::ProcessBase::visit()
@ 0x2b42b86e7370 process::DispatchEvent::visit()
@ 0x2b42b050c336 process::ProcessBase::serve()
@ 0x2b42b86d8d60 process::ProcessManager::resume()
@ 0x2b42b86d55a4 _ZZN7process14ProcessManager12init_threadsEvENKUt_clEv
@ 0x2b42b86e6aaa
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x2b42b86e6a01
_ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEclEv
@ 0x2b42b86e699a
_ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUt_vEEE6_M_runEv
@ 0x2b42ba2e0a60 (unknown)
@ 0x2b42baa57184 start_thread
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-1491877873-32077
Untagged: mesos-1491877873-32077:latest
Deleted: sha256:457d0f8176f94ffbc1af24d7787c771defcdd3ceeac376a9f5f7ce133b20b33d
Deleted: sha256:13a41bb236de397a1aff85f4480ed3686975f751207f79231c6fb94a74455ba9
Deleted: sha256:214daed527b8845a4c81ca968538891821f41a42a9b47ed511bdcbd276fe2f54
Deleted: sha256:97a1fba327c96416e28322f2fcda901d2769b1cf4055883fca8b575b62ceed8a
Deleted: sha256:dafbc3cdb50c4a51d7b88e0e66dbc21f0be2d95cf80e1d6e0969a2acc047798b
Deleted: sha256:e5b87923e83edf39346ce2a5bab7696bad4b292fea2603c77cb849ce1171ab6c
Deleted: sha256:2360a1883aa5fc00739ec684593ed977b7250d48dfd70c741e89d210b62568f8
Deleted: sha256:ee1f424a4f8f1c52d4a2a5c774320dbc1b93d28148d935c84bd50339e5d69d8f
Deleted: sha256:73d88bb84cd6d00c47213f16c40a33423eeb6be17f94878f2683d9fd3d9ee419
Deleted: sha256:44650e116750d84b77cc309c1faa0823000214f2a2c4e08d9aa9b3f23adcb704
Deleted: sha256:3ce7042fc0f594488fc40bd930a36d5f3552383b2d75f31111b5180b3c6d1a4e
Deleted: sha256:67d22437afd10759f47e9341e9129eeb39d02ba3321acb2bc2c827806a969eb0
Deleted: sha256:202dfccfc69934da222568f029dadeeff9053dcc2b2c98f3c0a7f1e35db99838
Deleted: sha256:dd45096a16021fe9782515e69ae67c8218dfa470c44cfa24596c4544ebf055b3
Deleted: sha256:8498b40873c90f54c2ab4d4b5738be945605f19a13098831c9c461d564acd1d4
Deleted: sha256:c35e00390eca3295259169396aa2732cdd39ea065143b59a90643985d2412b23
Deleted: sha256:eb6745cce521ca632662aa5900365c16032fe69cc78a30df582d499e97b43d8a
Deleted: sha256:1cbdb9852580058f94cd62d28e60ba0b2b33b539d58981f15d7a3955b2544857
Deleted: sha256:5215ba81eb7ab0d9aeebc3a66481670291c11e78a76a871ef3df20da3cdd155c
Deleted: sha256:7130a917d34140eb87e94017320d4cc27a21815bc51c232dc83a5a94ccac7310
Deleted: sha256:c38271e87420b476c320edd1e43243a5d5811f9ef6101b6efbb1969f8c3a4483
Deleted: sha256:362eb0a89c8a030387c40abcad0b038ffa456aaa3cad88314f2c5ee9eadafce4
Deleted: sha256:6dd52aac2dd6f5b90c018aec21956549e38a73d1c4ffda2e564cacc6f3ad40b3
Deleted: sha256:577412d0785a9be68af2f7e2666ce7623b78460aae4e24c08814cb371cf1aa66
Build step 'Execute shell' marked build as failure
