You are viewing a plain text version of this content. The canonical link for it is here.

Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/08/23 08:08:01 UTC

Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4142

See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4142/display/redirect>

------------------------------------------
[...truncated 11.67 MB...]
I0823 08:07:41.443331  5547 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0823 08:07:41.443348  5547 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd74f8df1a78c' server FQDN: 'd74f8df1a78c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0823 08:07:41.443363  5547 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0823 08:07:41.443387  5547 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0823 08:07:41.443413  5547 authenticator.cpp:318] Authentication success
I0823 08:07:41.443554  5545 authenticatee.cpp:299] Authentication success
I0823 08:07:41.443591  5537 master.cpp:7867] Successfully authenticated principal 'test-principal' at slave(183)@172.17.0.4:42282
I0823 08:07:41.443682  5536 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(396)@172.17.0.4:42282
I0823 08:07:41.444001  5542 slave.cpp:1144] Successfully authenticated with master master@172.17.0.4:42282
I0823 08:07:41.444272  5542 slave.cpp:1588] Will retry registration in 1.054654ms if necessary
I0823 08:07:41.444464  5540 master.cpp:5712] Received register agent message from slave(183)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.444584  5540 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0823 08:07:41.445019  5539 master.cpp:5772] Authorized registration of agent at slave(183)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.445155  5539 master.cpp:5865] Registering agent at slave(183)@172.17.0.4:42282 (d74f8df1a78c) with id 1064fdef-e25f-43b0-a732-d6672f19d328-S1
I0823 08:07:41.445667  5538 registrar.cpp:495] Applied 1 operations in 87991ns; attempting to update the registry
I0823 08:07:41.445863  5541 slave.cpp:1588] Will retry registration in 25.697155ms if necessary
I0823 08:07:41.446070  5544 master.cpp:5706] Ignoring register agent message from slave(183)@172.17.0.4:42282 (d74f8df1a78c) as registration is already in progress
I0823 08:07:41.446382  5538 registrar.cpp:552] Successfully updated the registry in 643840ns
I0823 08:07:41.446635  5551 master.cpp:5912] Admitted agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 at slave(183)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.447264  5547 slave.cpp:4903] Received ping from slave-observer(179)@172.17.0.4:42282
I0823 08:07:41.447427  5547 slave.cpp:1190] Registered with master master@172.17.0.4:42282; given agent ID 1064fdef-e25f-43b0-a732-d6672f19d328-S1
I0823 08:07:41.447546  5549 status_update_manager.cpp:184] Resuming sending status updates
I0823 08:07:41.447664  5536 hierarchical.cpp:593] Added agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 (d74f8df1a78c) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0823 08:07:41.447325  5551 master.cpp:5943] Registered agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 at slave(183)@172.17.0.4:42282 (d74f8df1a78c) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0823 08:07:41.447863  5547 slave.cpp:1210] Checkpointing SlaveInfo to '/tmp/MasterQuotaTest_InsufficientResourcesMultipleAgents_q8OzTk/meta/slaves/1064fdef-e25f-43b0-a732-d6672f19d328-S1/slave.info'
I0823 08:07:41.447926  5536 hierarchical.cpp:1943] No allocations performed
I0823 08:07:41.447978  5536 hierarchical.cpp:1486] Performed allocation for 1 agents in 157703ns
I0823 08:07:41.448285  5547 slave.cpp:1248] Forwarding total oversubscribed resources {}
I0823 08:07:41.448446  5541 master.cpp:6688] Received update of agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 at slave(183)@172.17.0.4:42282 (d74f8df1a78c) with total oversubscribed resources {}
I0823 08:07:41.448818  5537 hierarchical.cpp:660] Agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 (d74f8df1a78c) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0823 08:07:41.450999  5549 process.cpp:3933] Handling HTTP event for process 'master' with path: '/master/quota'
I0823 08:07:41.452345  5548 http.cpp:1166] HTTP POST for /master/quota from 172.17.0.4:42035
I0823 08:07:41.452379  5548 quota_handler.cpp:435] Setting quota from request: '{"force":false,"guarantee":[{"name":"cpus","scalar":{"value":5.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":3072.0},"type":"SCALAR"}],"role":"role1"}'
I0823 08:07:41.452819  5548 quota_handler.cpp:745] Authorizing principal 'test-principal' to update quota for role 'role1'
I0823 08:07:41.453248  5537 quota_handler.cpp:192] Performing capacity heuristic check for a set quota request
I0823 08:07:41.457556  5539 process.cpp:3933] Handling HTTP event for process 'master' with path: '/master/quota'
I0823 08:07:41.458890  5536 http.cpp:1166] HTTP POST for /master/quota from 172.17.0.4:42036
I0823 08:07:41.458928  5536 quota_handler.cpp:435] Setting quota from request: '{"force":true,"guarantee":[{"name":"cpus","scalar":{"value":5.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":3072.0},"type":"SCALAR"}],"role":"role1"}'
I0823 08:07:41.459308  5536 quota_handler.cpp:745] Authorizing principal 'test-principal' to update quota for role 'role1'
I0823 08:07:41.459677  5548 quota_handler.cpp:559] Using force flag to override quota capacity heuristic check
I0823 08:07:41.460171  5541 registrar.cpp:495] Applied 1 operations in 88576ns; attempting to update the registry
I0823 08:07:41.460850  5541 registrar.cpp:552] Successfully updated the registry in 613888ns
I0823 08:07:41.461369  5550 hierarchical.cpp:1346] Set quota [{"name":"cpus","scalar":{"value":5.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":3072.0},"type":"SCALAR"}] for role 'role1'
I0823 08:07:41.462963  5517 slave.cpp:859] Agent terminating
I0823 08:07:41.463155  5542 master.cpp:1318] Agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 at slave(183)@172.17.0.4:42282 (d74f8df1a78c) disconnected
I0823 08:07:41.463183  5542 master.cpp:3301] Disconnecting agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 at slave(183)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.463232  5542 master.cpp:3320] Deactivating agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 at slave(183)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.463345  5550 hierarchical.cpp:690] Agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1 deactivated
I0823 08:07:41.469377  5517 slave.cpp:859] Agent terminating
I0823 08:07:41.469540  5543 master.cpp:1318] Agent 1064fdef-e25f-43b0-a732-d6672f19d328-S0 at slave(182)@172.17.0.4:42282 (d74f8df1a78c) disconnected
I0823 08:07:41.469573  5543 master.cpp:3301] Disconnecting agent 1064fdef-e25f-43b0-a732-d6672f19d328-S0 at slave(182)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.469627  5543 master.cpp:3320] Deactivating agent 1064fdef-e25f-43b0-a732-d6672f19d328-S0 at slave(182)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.469738  5551 hierarchical.cpp:690] Agent 1064fdef-e25f-43b0-a732-d6672f19d328-S0 deactivated
I0823 08:07:41.474836  5517 master.cpp:1160] Master terminating
I0823 08:07:41.475487  5540 hierarchical.cpp:626] Removed agent 1064fdef-e25f-43b0-a732-d6672f19d328-S1
I0823 08:07:41.475955  5540 hierarchical.cpp:626] Removed agent 1064fdef-e25f-43b0-a732-d6672f19d328-S0
[       OK ] MasterQuotaTest.InsufficientResourcesMultipleAgents (105 ms)
[ RUN      ] MasterQuotaTest.AvailableResourcesSingleAgent
I0823 08:07:41.484223  5517 cluster.cpp:162] Creating default 'local' authorizer
I0823 08:07:41.487642  5549 master.cpp:442] Master 0a7566b5-a745-40b3-922d-8d28ad19f1c8 (d74f8df1a78c) started on 172.17.0.4:42282
I0823 08:07:41.487676  5549 master.cpp:444] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --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/mF78I5/credentials" --filter_gpu_resources="true" --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.5.0/_inst/share/mesos/webui" --work_dir="/tmp/mF78I5/master" --zk_session_timeout="10secs"
I0823 08:07:41.488070  5549 master.cpp:494] Master only allowing authenticated frameworks to register
I0823 08:07:41.488087  5549 master.cpp:508] Master only allowing authenticated agents to register
I0823 08:07:41.488102  5549 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0823 08:07:41.488117  5549 credentials.hpp:37] Loading credentials for authentication from '/tmp/mF78I5/credentials'
I0823 08:07:41.488450  5549 master.cpp:566] Using default 'crammd5' authenticator
I0823 08:07:41.488631  5549 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0823 08:07:41.488793  5549 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0823 08:07:41.488929  5549 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0823 08:07:41.489080  5549 master.cpp:646] Authorization enabled
I0823 08:07:41.489315  5542 whitelist_watcher.cpp:77] No whitelist given
I0823 08:07:41.489357  5538 hierarchical.cpp:171] Initialized hierarchical allocator process
I0823 08:07:41.491896  5548 master.cpp:2163] Elected as the leading master!
I0823 08:07:41.491926  5548 master.cpp:1702] Recovering from registrar
I0823 08:07:41.492106  5536 registrar.cpp:347] Recovering registrar
I0823 08:07:41.492645  5536 registrar.cpp:391] Successfully fetched the registry (0B) in 497152ns
I0823 08:07:41.492756  5536 registrar.cpp:495] Applied 1 operations in 28112ns; attempting to update the registry
I0823 08:07:41.493290  5536 registrar.cpp:552] Successfully updated the registry in 480us
I0823 08:07:41.493408  5536 registrar.cpp:424] Successfully recovered registrar
I0823 08:07:41.493801  5541 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0823 08:07:41.493825  5537 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0823 08:07:41.497737  5517 process.cpp:3228] Attempting to spawn already spawned process files@172.17.0.4:42282
I0823 08:07:41.498507  5517 containerizer.cpp:288] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W0823 08:07:41.498960  5517 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0823 08:07:41.499074  5517 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0823 08:07:41.499107  5517 provisioner.cpp:255] Using default backend 'copy'
I0823 08:07:41.500874  5517 cluster.cpp:448] Creating default 'local' authorizer
I0823 08:07:41.502744  5538 slave.cpp:252] Mesos agent started on (184)@172.17.0.4:42282
I0823 08:07:41.502768  5538 slave.cpp:253] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterQuotaTest_AvailableResourcesSingleAgent_kD5Oot/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/MasterQuotaTest_AvailableResourcesSingleAgent_kD5Oot/credential" --default_role="*" --disallow_sharing_agent_pid_namespace="false" --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/MasterQuotaTest_AvailableResourcesSingleAgent_kD5Oot/store/docker" --docker_volume_checkpoint_dir="/var/run/mesos/isolators/docker/volume" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_reregistration_timeout="2secs" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/MasterQuotaTest_AvailableResourcesSingleAgent_kD5Oot/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/MasterQuotaTest_AvailableResourcesSingleAgent_kD5Oot/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.5.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/MasterQuotaTest_AvailableResourcesSingleAgent_kD5Oot" --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/MasterQuotaTest_AvailableResourcesSingleAgent_LpDYyM"
I0823 08:07:41.503221  5538 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterQuotaTest_AvailableResourcesSingleAgent_kD5Oot/credential'
I0823 08:07:41.503412  5538 slave.cpp:285] Agent using credential for: test-principal
I0823 08:07:41.503440  5538 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterQuotaTest_AvailableResourcesSingleAgent_kD5Oot/http_credentials'
I0823 08:07:41.503705  5538 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0823 08:07:41.503892  5538 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0823 08:07:41.505321  5538 slave.cpp:581] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0823 08:07:41.505548  5538 slave.cpp:589] Agent attributes: [  ]
I0823 08:07:41.505561  5538 slave.cpp:598] Agent hostname: d74f8df1a78c
I0823 08:07:41.505713  5540 status_update_manager.cpp:177] Pausing sending status updates
I0823 08:07:41.507266  5536 state.cpp:64] Recovering state from '/tmp/MasterQuotaTest_AvailableResourcesSingleAgent_LpDYyM/meta'
I0823 08:07:41.507581  5547 status_update_manager.cpp:203] Recovering status update manager
I0823 08:07:41.507756  5543 containerizer.cpp:643] Recovering containerizer
I0823 08:07:41.509310  5547 provisioner.cpp:416] Provisioner recovery complete
I0823 08:07:41.509593  5542 slave.cpp:6226] Finished recovery
I0823 08:07:41.510072  5542 slave.cpp:6408] Querying resource estimator for oversubscribable resources
I0823 08:07:41.510341  5539 status_update_manager.cpp:177] Pausing sending status updates
I0823 08:07:41.510354  5540 slave.cpp:987] New master detected at master@172.17.0.4:42282
I0823 08:07:41.510452  5540 slave.cpp:1022] Detecting new master
I0823 08:07:41.510581  5540 slave.cpp:6422] Received oversubscribable resources {} from the resource estimator
I0823 08:07:41.515923  5550 slave.cpp:1049] Authenticating with master master@172.17.0.4:42282
I0823 08:07:41.516007  5550 slave.cpp:1060] Using default CRAM-MD5 authenticatee
I0823 08:07:41.516276  5545 authenticatee.cpp:121] Creating new client SASL connection
I0823 08:07:41.516566  5536 master.cpp:7837] Authenticating slave(184)@172.17.0.4:42282
I0823 08:07:41.516674  5546 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(397)@172.17.0.4:42282
I0823 08:07:41.516935  5548 authenticator.cpp:98] Creating new server SASL connection
I0823 08:07:41.517133  5541 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0823 08:07:41.517160  5541 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0823 08:07:41.517277  5549 authenticator.cpp:204] Received SASL authentication start
I0823 08:07:41.517336  5549 authenticator.cpp:326] Authentication requires more steps
I0823 08:07:41.517468  5547 authenticatee.cpp:259] Received SASL authentication step
I0823 08:07:41.517622  5551 authenticator.cpp:232] Received SASL authentication step
I0823 08:07:41.517670  5551 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd74f8df1a78c' server FQDN: 'd74f8df1a78c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0823 08:07:41.517735  5551 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0823 08:07:41.517824  5551 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0823 08:07:41.518074  5551 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd74f8df1a78c' server FQDN: 'd74f8df1a78c' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0823 08:07:41.518096  5551 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0823 08:07:41.518227  5551 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0823 08:07:41.518333  5551 authenticator.cpp:318] Authentication success
I0823 08:07:41.518503  5537 authenticatee.cpp:299] Authentication success
I0823 08:07:41.518617  5544 master.cpp:7867] Successfully authenticated principal 'test-principal' at slave(184)@172.17.0.4:42282
I0823 08:07:41.518693  5551 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(397)@172.17.0.4:42282
I0823 08:07:41.518844  5537 slave.cpp:1144] Successfully authenticated with master master@172.17.0.4:42282
I0823 08:07:41.519109  5537 slave.cpp:1588] Will retry registration in 762338ns if necessary
I0823 08:07:41.519294  5550 master.cpp:5712] Received register agent message from slave(184)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.519434  5550 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0823 08:07:41.519835  5548 master.cpp:5772] Authorized registration of agent at slave(184)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.519963  5548 master.cpp:5865] Registering agent at slave(184)@172.17.0.4:42282 (d74f8df1a78c) with id 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0
I0823 08:07:41.520421  5541 registrar.cpp:495] Applied 1 operations in 57573ns; attempting to update the registry
I0823 08:07:41.520782  5549 slave.cpp:1588] Will retry registration in 16.591756ms if necessary
I0823 08:07:41.521005  5547 master.cpp:5706] Ignoring register agent message from slave(184)@172.17.0.4:42282 (d74f8df1a78c) as registration is already in progress
I0823 08:07:41.521142  5541 registrar.cpp:552] Successfully updated the registry in 649984ns
I0823 08:07:41.521353  5547 master.cpp:5912] Admitted agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 at slave(184)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.522017  5545 slave.cpp:4903] Received ping from slave-observer(180)@172.17.0.4:42282
I0823 08:07:41.522302  5545 slave.cpp:1190] Registered with master master@172.17.0.4:42282; given agent ID 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0
I*** Aborted at 1503475661 (unix time) try "date -d @1503475661" if you are using GNU date ***
0823 08:07:41.522541  5548 status_update_manager.cpp:184] Resuming sending status updates
I0823 08:07:41.522240  5547 master.cpp:5943] Registered agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 at slave(184)@172.17.0.4:42282 (d74f8df1a78c) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I0823 08:07:41.522580  5551 hierarchical.cpp:593] Added agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 (d74f8df1a78c) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0823 08:07:41.522773  5545 slave.cpp:1210] Checkpointing SlaveInfo to '/tmp/MasterQuotaTest_AvailableResourcesSingleAgent_LpDYyM/meta/slaves/0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0/slave.info'
I0823 08:07:41.526028  5545 slave.cpp:1248] Forwarding total oversubscribed resources {}
I0823 08:07:41.526315  5545 master.cpp:6688] Received update of agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 at slave(184)@172.17.0.4:42282 (d74f8df1a78c) with total oversubscribed resources {}
I0823 08:07:41.526664  5545 process.cpp:3933] Handling HTTP event for process 'master' with path: '/master/quota'
I0823 08:07:41.528110  5539 http.cpp:1166] HTTP POST for /master/quota from 172.17.0.4:42037
I0823 08:07:41.528234  5539 quota_handler.cpp:435] Setting quota from request: '{"force":false,"guarantee":[{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}],"role":"role1"}'
I0823 08:07:41.528365  5551 hierarchical.cpp:660] Agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 (d74f8df1a78c) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0823 08:07:41.528595  5551 hierarchical.cpp:1943] No allocations performed
I0823 08:07:41.528656  5551 hierarchical.cpp:1486] Performed allocation for 1 agents in 183680ns
I0823 08:07:41.528908  5539 quota_handler.cpp:745] Authorizing principal 'test-principal' to update quota for role 'role1'
I0823 08:07:41.529269  5548 quota_handler.cpp:192] Performing capacity heuristic check for a set quota request
I0823 08:07:41.529896  5545 registrar.cpp:495] Applied 1 operations in 62830ns; attempting to update the registry
I0823 08:07:41.530552  5547 registrar.cpp:552] Successfully updated the registry in 593920ns
I0823 08:07:41.531178  5547 hierarchical.cpp:1346] Set quota [{"name":"cpus","scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":512.0},"type":"SCALAR"}] for role 'role1'
I0823 08:07:41.533417  5517 slave.cpp:859] Agent terminating
I0823 08:07:41.533717  5549 master.cpp:1318] Agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 at slave(184)@172.17.0.4:42282 (d74f8df1a78c) disconnected
I0823 08:07:41.533746  5549 master.cpp:3301] Disconnecting agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 at slave(184)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.533798  5549 master.cpp:3320] Deactivating agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 at slave(184)@172.17.0.4:42282 (d74f8df1a78c)
I0823 08:07:41.533983  5549 hierarchical.cpp:690] Agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0 deactivated
PC: @     0x2b3304d02128 process::EventQueue::Consumer::empty()
I0823 08:07:41.540871  5546 hierarchical.cpp:1943] No allocations performed
I0823 08:07:41.540935  5546 hierarchical.cpp:1486] Performed allocation for 1 agents in 232192ns
I0823 08:07:41.543118  5517 master.cpp:1160] Master terminating
I0823 08:07:41.543836  5543 hierarchical.cpp:626] Removed agent 0a7566b5-a745-40b3-922d-8d28ad19f1c8-S0
[       OK ] MasterQuotaTest.AvailableResourcesSingleAgent (67 ms)
[ RUN      ] MasterQuotaTest.AvailableResourcesMultipleAgents
I0823 08:07:41.552309  5517 cluster.cpp:162] Creating default 'local' authorizer
I0823 08:07:41.555109  5537 master.cpp:442] Master 808b7891-4b4e-4b29-beff-cdfb0fccbd5a (d74f8df1a78c) started on 172.17.0.4:42282
I0823 08:07:41.555151  5537 master.cpp:444] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="50ms" --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/b2NW9x/credentials" --filter_gpu_resources="true" --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.5.0/_inst/share/mesos/webui" --work_dir="/tmp/b2NW9x/master" --zk_session_timeout="10secs"
I0823 08:07:41.555496  5537 master.cpp:494] Master only allowing authenticated frameworks to register
I0823 08:07:41.555512  5537 master.cpp:508] Master only allowing authenticated agents to register
I0823 08:07:41.555521  5537 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0823 08:07:41.555528  5537 credentials.hpp:37] Loading credentials for authentication from '/tmp/b2NW9x/credentials'
I0823 08:07:41.555889  5537 master.cpp:566] Using default 'crammd5' authenticator
I0823 08:07:41.556113  5537 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0823 08:07:41.556303  5537 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0823 08:07:41.556442  5537 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0823 08:07:41.556584  5537 master.cpp:646] Authorization enabled
I0823 08:07:41.556780  5547 hierarchical.cpp:171] Initialized hierarchical allocator process
I0823 08:07:41.556818  5539 whitelist_watcher.cpp:77] No whitelist given
I0823 08:07:41.559597  5546 master.cpp:2163] Elected as the leading master!
I0823 08:07:41.559628  5546 master.cpp:1702] Recovering from registrar
I0823 08:07:41.559820  5544 registrar.cpp:347] Recovering registrar
I0823 08:07:41.560461  5544 registrar.cpp:391] Successfully fetched the registry (0B) in 596224ns
I0823 08:07:41.560572  5544 registrar.cpp:495] Applied 1 operations in 29849ns; attempting to update the registry
I0823 08:07:41.561187  5544 registrar.cpp:552] Successfully updated the registry in 555008ns
I0823 08:07:41.561295  5544 registrar.cpp:424] Successfully recovered registrar
I0823 08:07:41.561754  5545 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0823 08:07:41.561754  5543 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
*** SIGSEGV (@0x8) received by PID 5517 (TID 0x2b3310551700) from PID 8; stack trace: ***
    @     0x2b3358d7faf7 (unknown)
    @     0x2b3358d84449 (unknown)
    @     0x2b33073e1330 (unknown)
    @     0x2b3304d02128 process::EventQueue::Consumer::empty()
    @     0x2b3304ceedd6 process::ProcessManager::resume()
    @     0x2b3304ceb3d2 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
    @     0x2b3304cfcb40 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
    @     0x2b3304cfca97 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
    @     0x2b3304cfca30 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
    @     0x2b3306c62a60 (unknown)
    @     0x2b33073d9184 start_thread
    @     0x2b33076ecffd (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.5.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.5.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1503472072-25498
Untagged: mesos-1503472072-25498:latest
Deleted: sha256:e28be9cb68d25f365662aef3e8d73549495c7b31093cd8f0baee2859176e4c7a
Deleted: sha256:bd67bd77e4ce376db44848a9a35b005b97c2e405481ba39abe3ec80b25250226
Deleted: sha256:87fffeb8fecc73fd0e2fd24658a6558660e3be055b3bbc3a25df7cc4eedbc80e
Deleted: sha256:01c19c82b9ef831843c22f202fd6e81db45a92fc015afbecaa3edcd0c5db1c42
Deleted: sha256:fe31859652653e17725b247c1d19075da8214f6e5750561f5f1a6b5dd40f42a8
Deleted: sha256:2c05ca56a40692a910595bd23414e75c2dae86c706b9acd9e0dedcdf178234ad
Deleted: sha256:45654ea046f762a734ae848deee51a8ce3a5df2c617b7bb7088361e24061746a
Deleted: sha256:916128443a3b38a996d7568acf05d3273236510cbda42e4c3ebd047c18196502
Deleted: sha256:ac73e9847bb1f8e4f33cfc6212c5763cc6b700de53b33befd531a8f06d3a7e98
Deleted: sha256:3c147475dfebd24d785102e8ef3c803f663d0011ffc9bfa5777479263b609022
Deleted: sha256:0aaa23bedee9d6cff9536804b37eb29841e6c8413897acb17e74281c73b0dbcb
Deleted: sha256:2c2c42c4a96fcc5ecd9c112772540d45524f4145833fd6eeeafad8af43d534c5
Deleted: sha256:8c8213d8b90ff8022cc0c1385bf24ffe2ac20745db5b04761de6cc82fcc5d160
Deleted: sha256:b051542df57082e84a555614fb2c6557fbf43d6d5139353b8984af27a7eab6bb
Deleted: sha256:66fe0822e939ede7280d2ec7a98249494fc062a0e113fedbf185667d5d059650
Deleted: sha256:43e4ea6dcbbbcd9f4f31cb07464b7d339324ccad5febf87028640ba28c3c1b9a
Deleted: sha256:ef40e52d76bc1e89c34d1ca28f370f833115be812c42a0c584112b03ee6ac857
Deleted: sha256:df9c974fac90a2ae24cdacea4529008092246ce31a9b5089ea635dbf01e4a521
Deleted: sha256:88349df27a2743f69a9326802596f478fbba1b7e92ecfeeb14dcc0565deccbbc
Deleted: sha256:04c11dfa082b7929efb281d9d9ddd052d139dc796a22fb60894f55b1a4a2b32d
Deleted: sha256:84d37b310a13b38e09e1dab9d0feeda8364bbe01e1bbcb1772e46554bd895e45
Deleted: sha256:6bd1f5da1203b0f54c0b166ab058446c5fac21a4c116b839196da203c4c21dd7
Deleted: sha256:4630ec4cee4227f3250405e5f0895d16814b7506bbe15bbb26e4b906d4c03616
Deleted: sha256:f46f914b6e287050861dc887bc7a908ee04cdaec6f4fa4814d61e5bbad047fdf
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com
Not sending mail to unregistered user xujyan@apple.com
Not sending mail to unregistered user gregorywmann@gmail.com
Not sending mail to unregistered user toenshoff@me.com