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/10/07 06:20:09 UTC
Build failed in Jenkins: Mesos-Buildbot » autotools,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4297
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4297/display/redirect>
------------------------------------------
[...truncated 17.40 MB...]
I1007 06:20:00.856914 6101 master.cpp:9335] Sending updated checkpointed resources cpus(reservations: [(DYNAMIC,role1,test-principal)]):3 to agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:00.858356 6104 slave.cpp:3535] Updated checkpointed resources from {} to cpus(reservations: [(DYNAMIC,role1,test-principal)]):3
I1007 06:20:00.861917 6101 hierarchical.cpp:887] Updated allocation of framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 on agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 from cpus(allocated: role1):3; mem(allocated: role1):31070; disk(allocated: role1):41686; ports(allocated: role1):[31000-32000] to ports(allocated: role1):[31000-32000]; mem(allocated: role1):31070; disk(allocated: role1):41686; cpus(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):3
I1007 06:20:00.863786 6101 hierarchical.cpp:1161] Recovered ports(allocated: role1):[31000-32000]; mem(allocated: role1):31070; disk(allocated: role1):41686; cpus(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):3 (total: ports:[31000-32000]; mem:31070; disk:41686; cpus(reservations: [(DYNAMIC,role1,test-principal)]):3, allocated: {}) on agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 from framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.863986 6101 hierarchical.cpp:1207] Framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 filtered agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 for 5secs
I1007 06:20:00.864717 6099 slave.cpp:906] Unregistering and shutting down
I1007 06:20:00.864831 6099 slave.cpp:869] Agent terminating
I1007 06:20:00.865172 6100 master.cpp:8823] Removing agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827 (2d94853a9d29): the agent unregistered
I1007 06:20:00.865660 6100 master.cpp:1303] Agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827 (2d94853a9d29) disconnected
I1007 06:20:00.865702 6100 master.cpp:3336] Disconnecting agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:00.865833 6100 master.cpp:3355] Deactivating agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:00.865993 6102 hierarchical.cpp:690] Agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 deactivated
I1007 06:20:00.866165 6107 registrar.cpp:495] Applied 1 operations in 299913ns; attempting to update the registry
I1007 06:20:00.867334 6107 registrar.cpp:552] Successfully updated the registry in 0ns
I1007 06:20:00.867552 6102 master.cpp:8865] Removed agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 at slave(366)@172.17.0.2:40827 (2d94853a9d29): the agent unregistered
I1007 06:20:00.868036 6102 master.cpp:2115] Notifying framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 (default) at scheduler-bb5f5e71-7393-4348-a038-fe6e99979dfd@172.17.0.2:40827 of lost agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1 (2d94853a9d29)
I1007 06:20:00.868410 6102 sched.cpp:1087] Lost agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1
I1007 06:20:00.868481 6102 sched.cpp:1098] Scheduler::slaveLost took 35288ns
I1007 06:20:00.869659 6103 hierarchical.cpp:626] Removed agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S1
I1007 06:20:00.874199 6100 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/unreserve'
I1007 06:20:00.877521 6107 http.cpp:1185] HTTP POST for /master/unreserve from 172.17.0.2:49742
I1007 06:20:00.878650 6107 master.cpp:3691] Authorizing principal 'test-principal' to unreserve resources '[{"name":"cpus","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":4.0},"type":"SCALAR"}]'
I1007 06:20:00.881247 6107 master.cpp:9389] Removing offer 89f44c73-75af-4850-84ed-f6efd65ec32d-O1
I1007 06:20:00.881594 6103 sched.cpp:943] Rescinded offer 89f44c73-75af-4850-84ed-f6efd65ec32d-O1
I1007 06:20:00.882295 6103 sched.cpp:954] Scheduler::offerRescinded took 50998ns
I1007 06:20:00.882707 6101 hierarchical.cpp:1161] Recovered ports(allocated: role1):[31000-32000]; mem(allocated: role1):31070; disk(allocated: role1):41686; cpus(allocated: role1)(reservations: [(DYNAMIC,role1,test-principal)]):4 (total: ports:[31000-32000]; mem:31070; disk:41686; cpus(reservations: [(DYNAMIC,role1,test-principal)]):4, allocated: {}) on agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 from framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.882866 6101 hierarchical.cpp:1207] Framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 filtered agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 for 5secs
I1007 06:20:00.886261 6105 master.cpp:9335] Sending updated checkpointed resources {} to agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 at slave(365)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:00.887164 6105 slave.cpp:3535] Updated checkpointed resources from cpus(reservations: [(DYNAMIC,role1,test-principal)]):4 to {}
I1007 06:20:00.891048 6099 sched.cpp:2005] Asked to stop the driver
I1007 06:20:00.891198 6101 sched.cpp:1187] Stopping framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.891865 6106 master.cpp:8447] Processing TEARDOWN call for framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 (default) at scheduler-bb5f5e71-7393-4348-a038-fe6e99979dfd@172.17.0.2:40827
I1007 06:20:00.891917 6106 master.cpp:8459] Removing framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 (default) at scheduler-bb5f5e71-7393-4348-a038-fe6e99979dfd@172.17.0.2:40827
I1007 06:20:00.891945 6106 master.cpp:3299] Deactivating framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 (default) at scheduler-bb5f5e71-7393-4348-a038-fe6e99979dfd@172.17.0.2:40827
I1007 06:20:00.892273 6105 hierarchical.cpp:412] Deactivated framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.892608 6103 slave.cpp:3227] Asked to shut down framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000 by master@172.17.0.2:40827
I1007 06:20:00.892765 6103 slave.cpp:3242] Cannot shut down unknown framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.893069 6104 hierarchical.cpp:355] Removed framework 89f44c73-75af-4850-84ed-f6efd65ec32d-0000
I1007 06:20:00.922920 6102 slave.cpp:869] Agent terminating
I1007 06:20:00.937976 6103 master.cpp:1303] Agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 at slave(365)@172.17.0.2:40827 (2d94853a9d29) disconnected
I1007 06:20:00.938206 6103 master.cpp:3336] Disconnecting agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 at slave(365)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:00.938470 6103 master.cpp:3355] Deactivating agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 at slave(365)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:00.939765 6102 hierarchical.cpp:690] Agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0 deactivated
I1007 06:20:00.953443 6099 master.cpp:1145] Master terminating
I1007 06:20:00.954610 6102 hierarchical.cpp:626] Removed agent 89f44c73-75af-4850-84ed-f6efd65ec32d-S0
[ OK ] PersistentVolumeEndpointsTest.ReserveAndSlaveRemoval (274 ms)
[ RUN ] PersistentVolumeEndpointsTest.SlavesEndpointFullResources
I1007 06:20:00.971137 6099 cluster.cpp:162] Creating default 'local' authorizer
I1007 06:20:00.976200 6106 master.cpp:445] Master a4a4c44a-8906-4c8b-b102-5beaa0f63bf7 (2d94853a9d29) started on 172.17.0.2:40827
I1007 06:20:00.976246 6106 master.cpp:447] 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/EmeUlr/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" --roles="role1" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.5.0/_inst/share/mesos/webui" --work_dir="/tmp/EmeUlr/master" --zk_session_timeout="10secs"
I1007 06:20:00.976832 6106 master.cpp:496] Master only allowing authenticated frameworks to register
I1007 06:20:00.976850 6106 master.cpp:502] Master only allowing authenticated agents to register
I1007 06:20:00.976864 6106 master.cpp:508] Master only allowing authenticated HTTP frameworks to register
I1007 06:20:00.976883 6106 credentials.hpp:37] Loading credentials for authentication from '/tmp/EmeUlr/credentials'
I1007 06:20:00.977360 6106 master.cpp:552] Using default 'crammd5' authenticator
I1007 06:20:00.977645 6106 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1007 06:20:00.977895 6106 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1007 06:20:00.978098 6106 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1007 06:20:00.978256 6106 master.cpp:631] Authorization enabled
W1007 06:20:00.978277 6106 master.cpp:694] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information
I1007 06:20:00.978574 6105 hierarchical.cpp:171] Initialized hierarchical allocator process
I1007 06:20:00.978619 6102 whitelist_watcher.cpp:77] No whitelist given
I1007 06:20:00.982513 6101 master.cpp:2198] Elected as the leading master!
I1007 06:20:00.982568 6101 master.cpp:1687] Recovering from registrar
I1007 06:20:00.982875 6102 registrar.cpp:347] Recovering registrar
I1007 06:20:00.983736 6102 registrar.cpp:391] Successfully fetched the registry (0B) in 787968ns
I1007 06:20:00.983886 6102 registrar.cpp:495] Applied 1 operations in 36161ns; attempting to update the registry
I1007 06:20:00.984650 6102 registrar.cpp:552] Successfully updated the registry in 691200ns
I1007 06:20:00.984804 6102 registrar.cpp:424] Successfully recovered registrar
I1007 06:20:00.985342 6100 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1007 06:20:00.985365 6106 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
W1007 06:20:00.995031 6099 process.cpp:3194] Attempted to spawn already running process files@172.17.0.2:40827
I1007 06:20:00.996711 6099 containerizer.cpp:292] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
W1007 06:20:00.997622 6099 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W1007 06:20:00.997880 6099 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1007 06:20:00.998029 6099 provisioner.cpp:255] Using default backend 'copy'
I1007 06:20:01.000980 6099 cluster.cpp:448] Creating default 'local' authorizer
I1007 06:20:01.004412 6100 slave.cpp:254] Mesos agent started on (367)@172.17.0.2:40827
I1007 06:20:01.004452 6100 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/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:4;gpus:0;mem:2048;disk:4096" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4" --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/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_NTvOLK" --zk_session_timeout="10secs"
I1007 06:20:01.005247 6100 credentials.hpp:86] Loading credential for authentication from '/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/credential'
I1007 06:20:01.005523 6100 slave.cpp:287] Agent using credential for: test-principal
I1007 06:20:01.005556 6100 credentials.hpp:37] Loading credentials for authentication from '/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_8CwKb4/http_credentials'
I1007 06:20:01.005955 6100 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1007 06:20:01.006253 6100 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1007 06:20:01.008258 6100 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":4.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":4096.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I1007 06:20:01.008707 6100 slave.cpp:593] Agent attributes: [ ]
I1007 06:20:01.008729 6100 slave.cpp:602] Agent hostname: 2d94853a9d29
I1007 06:20:01.009029 6101 status_update_manager.cpp:177] Pausing sending status updates
I1007 06:20:01.011376 6103 state.cpp:64] Recovering state from '/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_NTvOLK/meta'
I1007 06:20:01.011937 6101 status_update_manager.cpp:203] Recovering status update manager
I1007 06:20:01.012285 6106 containerizer.cpp:648] Recovering containerizer
I1007 06:20:01.016584 6102 provisioner.cpp:416] Provisioner recovery complete
I1007 06:20:01.017062 6106 slave.cpp:6326] Finished recovery
I1007 06:20:01.018021 6106 slave.cpp:6512] Querying resource estimator for oversubscribable resources
I1007 06:20:01.018754 6104 status_update_manager.cpp:177] Pausing sending status updates
I1007 06:20:01.018882 6103 slave.cpp:993] New master detected at master@172.17.0.2:40827
I1007 06:20:01.019166 6103 slave.cpp:1028] Detecting new master
I1007 06:20:01.019484 6103 slave.cpp:6526] Received oversubscribable resources {} from the resource estimator
I1007 06:20:01.029572 6102 slave.cpp:1055] Authenticating with master master@172.17.0.2:40827
I1007 06:20:01.029758 6102 slave.cpp:1064] Using default CRAM-MD5 authenticatee
I1007 06:20:01.030293 6102 authenticatee.cpp:121] Creating new client SASL connection
I1007 06:20:01.030313 6106 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.030478 6106 hierarchical.cpp:1486] Performed allocation for 0 agents in 218027ns
I1007 06:20:01.031019 6101 master.cpp:7936] Authenticating slave(367)@172.17.0.2:40827
I1007 06:20:01.031486 6101 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(775)@172.17.0.2:40827
I1007 06:20:01.032119 6104 authenticator.cpp:98] Creating new server SASL connection
I1007 06:20:01.032609 6103 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1007 06:20:01.032729 6103 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1007 06:20:01.032989 6103 authenticator.cpp:204] Received SASL authentication start
I1007 06:20:01.033115 6103 authenticator.cpp:326] Authentication requires more steps
I1007 06:20:01.033471 6103 authenticatee.cpp:259] Received SASL authentication step
I1007 06:20:01.033725 6103 authenticator.cpp:232] Received SASL authentication step
I1007 06:20:01.033838 6103 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '2d94853a9d29' server FQDN: '2d94853a9d29' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1007 06:20:01.033978 6103 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1007 06:20:01.034230 6103 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1007 06:20:01.034334 6103 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '2d94853a9d29' server FQDN: '2d94853a9d29' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1007 06:20:01.034415 6103 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1007 06:20:01.034482 6103 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1007 06:20:01.034569 6103 authenticator.cpp:318] Authentication success
I1007 06:20:01.034899 6102 authenticatee.cpp:299] Authentication success
I1007 06:20:01.035621 6101 slave.cpp:1147] Successfully authenticated with master master@172.17.0.2:40827
I1007 06:20:01.036089 6107 master.cpp:7966] Successfully authenticated principal 'test-principal' at slave(367)@172.17.0.2:40827
I1007 06:20:01.036110 6101 slave.cpp:1642] Will retry registration in 3.033657ms if necessary
I1007 06:20:01.036211 6105 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(775)@172.17.0.2:40827
I1007 06:20:01.036494 6107 master.cpp:5801] Received register agent message from slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.036669 6107 master.cpp:3838] Authorizing agent with principal 'test-principal'
I1007 06:20:01.037466 6103 master.cpp:5861] Authorized registration of agent at slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.037770 6103 master.cpp:5954] Registering agent at slave(367)@172.17.0.2:40827 (2d94853a9d29) with id a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0
I1007 06:20:01.038676 6100 registrar.cpp:495] Applied 1 operations in 106868ns; attempting to update the registry
I1007 06:20:01.039664 6100 registrar.cpp:552] Successfully updated the registry in 876032ns
I1007 06:20:01.040068 6106 master.cpp:6001] Admitted agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.040624 6104 slave.cpp:1642] Will retry registration in 21.746471ms if necessary
I1007 06:20:01.042006 6102 slave.cpp:4982] Received ping from slave-observer(375)@172.17.0.2:40827
I1007 06:20:01.042445 6102 slave.cpp:1193] Registered with master master@172.17.0.2:40827; given agent ID a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0
I1007 06:20:01.042605 6107 status_update_manager.cpp:184] Resuming sending status updates
I1007 06:20:01.042942 6101 hierarchical.cpp:593] Added agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 (2d94853a9d29) with cpus:4; mem:2048; disk:4096; ports:[31000-32000] (allocated: {})
I1007 06:20:01.042084 6106 master.cpp:6032] Registered agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29) with [{"name":"cpus","scalar":{"value":4.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":4096.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
I1007 06:20:01.043763 6101 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.043853 6101 hierarchical.cpp:1486] Performed allocation for 1 agents in 293817ns
I1007 06:20:01.043884 6106 master.cpp:5801] Received register agent message from slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.044268 6106 master.cpp:3838] Authorizing agent with principal 'test-principal'
I1007 06:20:01.045053 6105 master.cpp:5861] Authorized registration of agent at slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.045343 6105 master.cpp:5932] Agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29) already registered, resending acknowledgement
I1007 06:20:01.050631 6106 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/reserve'
I1007 06:20:01.053233 6107 http.cpp:1185] HTTP POST for /master/reserve from 172.17.0.2:49744
I1007 06:20:01.055279 6107 master.cpp:3623] Authorizing principal 'test-principal' to reserve resources '[{"name":"cpus","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":1.0},"type":"SCALAR"},{"name":"mem","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":512.0},"type":"SCALAR"},{"name":"disk","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":1024.0},"type":"SCALAR"}]'
I1007 06:20:01.062232 6100 master.cpp:9335] Sending updated checkpointed resources cpus(reservations: [(DYNAMIC,role1,test-principal)]):1; mem(reservations: [(DYNAMIC,role1,test-principal)]):512; disk(reservations: [(DYNAMIC,role1,test-principal)]):1024 to agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29)
I1007 06:20:01.068478 6100 process.cpp:3929] Handling HTTP event for process 'master' with path: '/master/create-volumes'
I1007 06:20:01.071872 6103 http.cpp:1185] HTTP POST for /master/create-volumes from 172.17.0.2:49746
I1007 06:20:01.073365 6103 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"disk":{"persistence":{"id":"id1","principal":"test-principal"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"principal":"test-principal","role":"role1","type":"DYNAMIC"}],"scalar":{"value":64.0},"type":"SCALAR"}]'
I1007 06:20:01.078343 6102 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/PersistentVolumeEndpointsTest_SlavesEndpointFullResources_NTvOLK/meta/slaves/a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0/slave.info'
I1007 06:20:01.078946 6102 slave.cpp:1263] Forwarding total resources cpus:4; mem:2048; disk:4096; ports:[31000-32000]
I1007 06:20:01.079080 6102 slave.cpp:1272] Forwarding total oversubscribed resources {}
W1007 06:20:01.079293 6102 slave.cpp:1248] Already registered with master master@172.17.0.2:40827
I1007 06:20:01.079322 6102 slave.cpp:1263] Forwarding total resources cpus:4; mem:2048; disk:4096; ports:[31000-32000]
I1007 06:20:01.079414 6102 slave.cpp:1272] Forwarding total oversubscribed resources {}
I1007 06:20:01.080778 6102 slave.cpp:3535] Updated checkpointed resources from {} to cpus(reservations: [(DYNAMIC,role1,test-principal)]):1; mem(reservations: [(DYNAMIC,role1,test-principal)]):512; disk(reservations: [(DYNAMIC,role1,test-principal)]):1024
I1007 06:20:01.082378 6106 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.082535 6106 hierarchical.cpp:1486] Performed allocation for 1 agents in 477108ns
I1007 06:20:01.083534 6103 master.cpp:6804] Received update of agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29) with total resources cpus:4; mem:2048; disk:4096; ports:[31000-32000]
I1007 06:20:01.083663 6103 master.cpp:6817] Received update of agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29) with total oversubscribed resources {}
I1007 06:20:01.084277 6103 master.cpp:6804] Received update of agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29) with total resources cpus:4; mem:2048; disk:4096; ports:[31000-32000]
I1007 06:20:01.084364 6103 master.cpp:6817] Received update of agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29) with total oversubscribed resources {}
I1007 06:20:01.084578 6103 master.cpp:6828] Ignoring update on agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 at slave(367)@172.17.0.2:40827 (2d94853a9d29) as it reports no changes
F1007 06:20:01.084858 6103 master.cpp:10271] CHECK_SOME(resources): Invalid CREATE Operation: Insufficient disk resources for persistent volume disk(reservations: [(DYNAMIC,role1,test-principal)])[id1:path1]:64
*** Check failure stack trace: ***
I1007 06:20:01.086515 6105 hierarchical.cpp:660] Agent a4a4c44a-8906-4c8b-b102-5beaa0f63bf7-S0 (2d94853a9d29) updated with total resources cpus:4; mem:2048; disk:4096; ports:[31000-32000]
W1007 06:20:01.088474 6099 process.cpp:3194] Attempted to spawn already running process version@172.17.0.2:40827
I1007 06:20:01.095726 6105 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.095808 6105 hierarchical.cpp:1486] Performed allocation for 1 agents in 397248ns
I1007 06:20:01.097429 6099 sched.cpp:232] Version: 1.5.0
I1007 06:20:01.098220 6102 sched.cpp:336] New master detected at master@172.17.0.2:40827
I1007 06:20:01.098444 6102 sched.cpp:396] Authenticating with master master@172.17.0.2:40827
I1007 06:20:01.098464 6102 sched.cpp:403] Using default CRAM-MD5 authenticatee
I1007 06:20:01.098876 6106 authenticatee.cpp:121] Creating new client SASL connection
@ 0x7f1fd2b4acec google::LogMessage::Fail()
I1007 06:20:01.133831 6105 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.133916 6105 hierarchical.cpp:1486] Performed allocation for 1 agents in 295141ns
@ 0x7f1fd2b4ac4b google::LogMessage::SendToLog()
@ 0x7f1fd2b4a65c google::LogMessage::Flush()
@ 0x7f1fd2b4d390 google::LogMessageFatal::~LogMessageFatal()
@ 0x563cdd59916f _CheckFatal::~_CheckFatal()
@ 0x7f1fd14c89b7 mesos::internal::master::Slave::apply()
I1007 06:20:01.185674 6104 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.185757 6104 hierarchical.cpp:1486] Performed allocation for 1 agents in 282118ns
@ 0x7f1fd14c09b5 mesos::internal::master::Master::_apply()
@ 0x7f1fd15417ea _ZZN7process8dispatchIN5mesos8internal6master6MasterEPNS3_5SlaveERKNS1_15Offer_OperationERS6_S9_EEvRKNS_3PIDIT_EEMSC_FvT0_T1_EOT2_OT3_ENKUlSA_RS7_PNS_11ProcessBaseEE_clESA_SO_SQ_
@ 0x7f1fd15f694b _ZNSt5_BindIFZN7process8dispatchIN5mesos8internal6master6MasterEPNS4_5SlaveERKNS2_15Offer_OperationERS7_SA_EEvRKNS0_3PIDIT_EEMSD_FvT0_T1_EOT2_OT3_EUlSB_RS8_PNS0_11ProcessBaseEE_S7_S8_St12_PlaceholderILi1EEEE6__callIvJOSR_EJLm0ELm1ELm2EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE
I1007 06:20:01.237100 6101 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.237184 6101 hierarchical.cpp:1486] Performed allocation for 1 agents in 293206ns
@ 0x7f1fd15deab6 _ZNSt5_BindIFZN7process8dispatchIN5mesos8internal6master6MasterEPNS4_5SlaveERKNS2_15Offer_OperationERS7_SA_EEvRKNS0_3PIDIT_EEMSD_FvT0_T1_EOT2_OT3_EUlSB_RS8_PNS0_11ProcessBaseEE_S7_S8_St12_PlaceholderILi1EEEEclIISR_EvEET0_DpOT_
@ 0x7f1fd15b40fc _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEESt5_BindIFZNS0_8dispatchIN5mesos8internal6master6MasterEPNS8_5SlaveERKNS6_15Offer_OperationERSB_SE_EEvRKNS0_3PIDIT_EEMSH_FvT0_T1_EOT2_OT3_EUlSF_RSC_S2_E_SB_SC_St12_PlaceholderILi1EEEEE9_M_invokeERKSt9_Any_dataS2_
I1007 06:20:01.288854 6100 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.288940 6100 hierarchical.cpp:1486] Performed allocation for 1 agents in 282750ns
@ 0x7f1fd2a7946b std::function<>::operator()()
@ 0x7f1fd2a58721 process::ProcessBase::visit()
@ 0x7f1fd2a65758 process::DispatchEvent::visit()
@ 0x563cdd599f10 process::ProcessBase::serve()
@ 0x7f1fd2a55a5f process::ProcessManager::resume()
I1007 06:20:01.340214 6106 hierarchical.cpp:1943] No allocations performed
I1007 06:20:01.340294 6106 hierarchical.cpp:1486] Performed allocation for 1 agents in 261456ns
@ 0x7f1fd2a51eb4 _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x7f1fd2a64606 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x7f1fd2a6454a _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x7f1fd2a644d4 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x7f1fcaf772b0 (unknown)
@ 0x7f1fcb5dde25 start_thread
@ 0x7f1fca6df34d __clone
make[4]: *** [check-local] Aborted (core dumped)
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-1507352571-32306
Untagged: mesos-1507352571-32306:latest
Deleted: sha256:e9d265071928a52ff5c1b5a886ef908548cfe497b51aeec68f25f3d773325d81
Deleted: sha256:471250de4d7ca0de9e6afa65bba4fca5bd628b7458f4e5fd31de37ca7e6a6a00
Deleted: sha256:0855e0bb1c30a7f7314cb4581183869779cb70080f97997635d3ab8fc3c785e2
Deleted: sha256:70a108a243eb9759905931774543c15353c8bdc3123fbc78e0e86fc92302a179
Deleted: sha256:94bf92b100c14e83f325fbfcc32acaadd0e41e52ad89d09d6ba0dbc56393eff3
Deleted: sha256:cf8269178066cc33bead04366f5037dc21893d8b8666cba59ef5295d22f1a3c6
Deleted: sha256:5afa5579eb01ffe0a91cdd4c0c440cae90e39e9c4fb0006d8c90b8e41b977ece
Deleted: sha256:6746c8fdf53b85f61436b8755cc402437333977716492c75788fe5c5c73bcc83
Deleted: sha256:37017fd199c58061dc95d84659098a393ff1f78393c33d434ffe81edb6df1a48
Deleted: sha256:5cdad53b0c15801b0821da9da11e1946fe63f11e6a6a8ffcb1db3cff83f36589
Deleted: sha256:1368b6af82d8646cc99ecee84c46f45799fc4fddb841382eabb9d2efb9583b8b
Deleted: sha256:4c0e47c15454dd66bb10990027bcd65e2fe46abb40a423204b9d8d7d4f84f4b4
Deleted: sha256:11052ee348fea921cb8f94a08f94a6e440b86239028300b423a68dd3bd04add9
Deleted: sha256:a0cc13a29aba754c2944942f0035eff77191b12efc1ab60f85a7ef26194618c2
Deleted: sha256:c8ef13dde8acc54b931cfed6bc620ce2d84d182a2b6ed445553e2e872577a89b
Deleted: sha256:932f7fbe37b76283a61bf801c2a180cd05828c3066614b06f15647ad8023c5b8
Deleted: sha256:45fa75115b77f43e3d5cc1534e1880288a3a632a59d093adb142d0df89106444
Deleted: sha256:663b6528f1d719f4654c9316c684a6feabf9bd138c844e6f39168321bcb5c1d7
Deleted: sha256:5ca4bf0cfde30a43787d5a4278e5ea181561590ebf507c5f784d6d77f0fc0431
Deleted: sha256:a326ee5074728bf4d55656d4be7d3165bc0e38d9d0a0de5fa56e53de56314f1d
Deleted: sha256:53cb8ee12b720267b576912f87f54898000654d53d7317b20fe8d2b874b22cbf
Deleted: sha256:2ad583655d247e6678613fd2cdffc300c97eb4e2790303d368df585157cb5706
Deleted: sha256:c4f90871c5b0557bbbf65a2bcf27b2f5e26a990ab7d754e0bde1f92fede97f58
Deleted: sha256:d3cfd7a7d365002c6de95a3080b88507e8b7230256b226a464f531229419278b
Deleted: sha256:bc65e43b8b2b0ea87f9f17851ceb07ba204f9947bc52595466459eb310d55f5e
Deleted: sha256:6517671022c47f7f66cc1cd8c1f8aaecb2a02dd273d441c43494dd78ab1267f7
Deleted: sha256:84697dfc1edde3a0de370052904bdd24e8b3f976f2f7361fcd7f6bdb6f61cefb
Deleted: sha256:1547dd00b7e57f5ee9576dcb7c7f0ef8948cf330ed7fd3e8a173150372cb0dea
Deleted: sha256:2dd2e0cf7eea65b7daf310e7d6cd28db803275a49672a6e367a2558997f1ad47
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos-Buildbot » autotools,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4298
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=autotools,COMPILER=gcc,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4298/display/redirect?page=changes>