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 2016/12/12 01:28:05 UTC
Build failed in Jenkins: mesos-reviewbot #16414
See <https://builds.apache.org/job/mesos-reviewbot/16414/changes>
Changes:
[vinodkone] Cleaned up expectations in api_tests.cpp.
[vinodkone] Added new test for LAUNCH_NESTED_CONTAINER_SESSION.
[vinodkone] Added a TTY test for LAUNCH_NESTED_CONTAINER_SESSION.
[vinodkone] Removed the TODO in AgentAPITest.AttachContainerOutputFailure test.
[vinodkone] Added AgentAPITest.LaunchNestedContainerSessionDisconnected.
------------------------------------------
[...truncated 108545 lines...]
I1212 01:27:38.554700 29737 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.554735 29737 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:38.554797 29737 hierarchical.cpp:1291] Performed allocation for 0 agents in 145517ns
I1212 01:27:38.555008 29730 registrar.cpp:506] Successfully updated the registry in 1.661184ms
I1212 01:27:38.555850 29732 slave.cpp:4267] Received ping from slave-observer(363)@172.17.0.3:52876
I1212 01:27:38.555882 29735 master.cpp:5232] Registered agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 at (395)@172.17.0.3:52876 (4f863e827c94) with cpus(*):8; mem(*):4096; disk(*):3701220; ports(*):[31000-32000]
I1212 01:27:38.556063 29732 slave.cpp:1119] Registered with master master@172.17.0.3:52876; given agent ID 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0
I1212 01:27:38.556089 29732 fetcher.cpp:90] Clearing fetcher cache
I1212 01:27:38.556258 29735 status_update_manager.cpp:184] Resuming sending status updates
I1212 01:27:38.556335 29728 hierarchical.cpp:490] Added agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 (4f863e827c94) with cpus(*):8; mem(*):4096; disk(*):3701220; ports(*):[31000-32000] (allocated: {})
I1212 01:27:38.556473 29732 slave.cpp:1142] Checkpointing SlaveInfo to '/tmp/ReservationTest_CompatibleCheckpointedResources_RxGNET/meta/slaves/43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0/slave.info'
I1212 01:27:38.557514 29728 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:38.557588 29728 hierarchical.cpp:1314] Performed allocation for agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 in 1.206045ms
I1212 01:27:38.557998 29733 master.cpp:6577] Sending 1 offers to framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000 (default) at scheduler-93665790-d144-4a32-b8fe-6b9a71656814@172.17.0.3:52876
I1212 01:27:38.558501 29733 sched.cpp:923] Scheduler::resourceOffers took 119891ns
I1212 01:27:38.560365 29739 master.cpp:3588] Processing ACCEPT call for offers: [ 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-O0 ] on agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 at (395)@172.17.0.3:52876 (4f863e827c94) for framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000 (default) at scheduler-93665790-d144-4a32-b8fe-6b9a71656814@172.17.0.3:52876
I1212 01:27:38.560477 29727 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.560516 29727 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:38.560571 29727 hierarchical.cpp:1291] Performed allocation for 1 agents in 213787ns
I1212 01:27:38.560519 29739 master.cpp:3214] Authorizing principal 'test-principal' to reserve resources 'cpus(role, test-principal):8; mem(role, test-principal):2048'
I1212 01:27:38.561856 29729 master.cpp:3888] Applying RESERVE operation for resources cpus(role, test-principal):8; mem(role, test-principal):2048 from framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000 (default) at scheduler-93665790-d144-4a32-b8fe-6b9a71656814@172.17.0.3:52876 to agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 at (395)@172.17.0.3:52876 (4f863e827c94)
I1212 01:27:38.562268 29729 master.cpp:8022] Sending checkpointed resources cpus(role, test-principal):8; mem(role, test-principal):2048 to agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 at (395)@172.17.0.3:52876 (4f863e827c94)
I1212 01:27:38.563506 29734 slave.cpp:2866] Updated checkpointed resources from {} to cpus(role, test-principal):8; mem(role, test-principal):2048
I1212 01:27:38.563581 29734 slave.cpp:791] Agent terminating
I1212 01:27:38.563788 29728 master.cpp:1258] Agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 at (395)@172.17.0.3:52876 (4f863e827c94) disconnected
I1212 01:27:38.563824 29728 master.cpp:2977] Disconnecting agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 at (395)@172.17.0.3:52876 (4f863e827c94)
I1212 01:27:38.563968 29728 master.cpp:2996] Deactivating agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 at (395)@172.17.0.3:52876 (4f863e827c94)
I1212 01:27:38.566289 29725 hierarchical.cpp:780] Updated allocation of framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000 on agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 from cpus(*):8; mem(*):4096; disk(*):3701220; ports(*):[31000-32000] to ports(*):[31000-32000]; mem(*):2048; disk(*):3701220; cpus(role, test-principal):8; mem(role, test-principal):2048 with RESERVE operation
I1212 01:27:38.566857 29729 slave.cpp:209] Mesos agent started on @172.17.0.3:52876
I1212 01:27:38.566882 29729 slave.cpp:210] 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/ReservationTest_CompatibleCheckpointedResources_bUi6XZ/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/ReservationTest_CompatibleCheckpointedResources_bUi6XZ/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/ReservationTest_CompatibleCheckpointedResources_bUi6XZ/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:12;mem:2048" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ReservationTest_CompatibleCheckpointedResources_bUi6XZ" --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/ReservationTest_CompatibleCheckpointedResources_RxGNET"
I1212 01:27:38.567469 29729 credentials.hpp:86] Loading credential for authentication from '/tmp/ReservationTest_CompatibleCheckpointedResources_bUi6XZ/credential'
I1212 01:27:38.567495 29725 hierarchical.cpp:1023] Recovered ports(*):[31000-32000]; mem(*):2048; disk(*):3701220; cpus(role, test-principal):8; mem(role, test-principal):2048 (total: ports(*):[31000-32000]; mem(*):2048; disk(*):3701220; cpus(role, test-principal):8; mem(role, test-principal):2048, allocated: {}) on agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 from framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000
I1212 01:27:38.567610 29725 hierarchical.cpp:589] Agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0 deactivated
I1212 01:27:38.567631 29729 slave.cpp:347] Agent using credential for: test-principal
I1212 01:27:38.567656 29729 credentials.hpp:37] Loading credentials for authentication from '/tmp/ReservationTest_CompatibleCheckpointedResources_bUi6XZ/http_credentials'
I1212 01:27:38.567754 29725 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.567793 29725 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:38.567849 29725 hierarchical.cpp:1291] Performed allocation for 1 agents in 198388ns
I1212 01:27:38.567911 29729 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1212 01:27:38.568045 29729 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1212 01:27:38.568958 29729 slave.cpp:534] Agent resources: cpus(*):12; mem(*):2048; disk(*):3701220; ports(*):[31000-32000]
I1212 01:27:38.569077 29729 slave.cpp:542] Agent attributes: [ ]
I1212 01:27:38.569092 29729 slave.cpp:547] Agent hostname: 4f863e827c94
I1212 01:27:38.570677 29731 state.cpp:57] Recovering state from '/tmp/ReservationTest_CompatibleCheckpointedResources_RxGNET/meta'
I1212 01:27:38.571156 29731 state.cpp:88] Agent host rebooted
I1212 01:27:38.571795 29732 status_update_manager.cpp:203] Recovering status update manager
I1212 01:27:38.573426 29725 slave.cpp:791] Agent terminating
I1212 01:27:38.573480 29731 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.573526 29731 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:38.573592 29731 hierarchical.cpp:1291] Performed allocation for 1 agents in 276138ns
I1212 01:27:38.573633 29706 sched.cpp:2008] Asked to stop the driver
I1212 01:27:38.573729 29736 sched.cpp:1193] Stopping framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000
I1212 01:27:38.574002 29729 master.cpp:7287] Processing TEARDOWN call for framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000 (default) at scheduler-93665790-d144-4a32-b8fe-6b9a71656814@172.17.0.3:52876
I1212 01:27:38.574036 29729 master.cpp:7299] Removing framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000 (default) at scheduler-93665790-d144-4a32-b8fe-6b9a71656814@172.17.0.3:52876
I1212 01:27:38.574288 29728 hierarchical.cpp:391] Deactivated framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000
I1212 01:27:38.574677 29730 hierarchical.cpp:342] Removed framework 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-0000
I1212 01:27:38.579123 29725 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.579187 29725 hierarchical.cpp:1291] Performed allocation for 1 agents in 170487ns
I1212 01:27:38.580049 29740 master.cpp:1097] Master terminating
I1212 01:27:38.581171 29726 hierarchical.cpp:522] Removed agent 43186c6c-1af2-40ed-9fef-bfd99aa6bab3-S0
[ OK ] ReservationTest.CompatibleCheckpointedResources (61 ms)
[ RUN ] ReservationTest.CompatibleCheckpointedResourcesWithPersistentVolumes
I1212 01:27:38.589143 29706 cluster.cpp:160] Creating default 'local' authorizer
I1212 01:27:38.592198 29728 master.cpp:380] Master 5061b5a8-6301-4c68-ba05-699a77bdfcc3 (4f863e827c94) started on 172.17.0.3:52876
I1212 01:27:38.592224 29728 master.cpp:382] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="5ms" --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/B3F2sp/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" --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="role" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.2.0/_inst/share/mesos/webui" --work_dir="/tmp/B3F2sp/master" --zk_session_timeout="10secs"
I1212 01:27:38.592681 29728 master.cpp:432] Master only allowing authenticated frameworks to register
I1212 01:27:38.592695 29728 master.cpp:446] Master only allowing authenticated agents to register
I1212 01:27:38.592703 29728 master.cpp:459] Master only allowing authenticated HTTP frameworks to register
I1212 01:27:38.592712 29728 credentials.hpp:37] Loading credentials for authentication from '/tmp/B3F2sp/credentials'
I1212 01:27:38.592993 29728 master.cpp:504] Using default 'crammd5' authenticator
I1212 01:27:38.593124 29728 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1212 01:27:38.593267 29728 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1212 01:27:38.593436 29728 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1212 01:27:38.593585 29728 master.cpp:584] Authorization enabled
W1212 01:27:38.593641 29728 master.cpp:647] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information
I1212 01:27:38.593874 29737 hierarchical.cpp:149] Initialized hierarchical allocator process
I1212 01:27:38.593932 29740 whitelist_watcher.cpp:77] No whitelist given
I1212 01:27:38.597671 29739 master.cpp:2045] Elected as the leading master!
I1212 01:27:38.597699 29739 master.cpp:1568] Recovering from registrar
I1212 01:27:38.597873 29740 registrar.cpp:329] Recovering registrar
I1212 01:27:38.598922 29739 registrar.cpp:362] Successfully fetched the registry (0B) in 997632ns
I1212 01:27:38.599027 29739 registrar.cpp:461] Applied 1 operations in 18667ns; attempting to update the registry
I1212 01:27:38.599359 29732 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.599429 29732 hierarchical.cpp:1291] Performed allocation for 0 agents in 107026ns
I1212 01:27:38.600034 29732 registrar.cpp:506] Successfully updated the registry in 952832ns
I1212 01:27:38.600173 29732 registrar.cpp:392] Successfully recovered registrar
I1212 01:27:38.600872 29739 master.cpp:1684] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1212 01:27:38.600935 29738 hierarchical.cpp:176] Skipping recovery of hierarchical allocator: nothing to recover
I1212 01:27:38.605645 29732 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.605705 29732 hierarchical.cpp:1291] Performed allocation for 0 agents in 94407ns
I1212 01:27:38.606528 29725 slave.cpp:209] Mesos agent started on @172.17.0.3:52876
I1212 01:27:38.606557 29725 slave.cpp:210] 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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_Vp8fqC/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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_Vp8fqC/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_command_executor="false" --http_credentials="/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_Vp8fqC/http_credentials" --image_provisioner_backend="copy" --initialize_driver_logging="true" --io_switchboard_enable_server="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.2.0/_build/src" --logbufsecs="0" --logging_level="INFO" --max_completed_executors_per_framework="150" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:8;mem:4096;disk:2048" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_Vp8fqC" --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/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_PIODWv"
I1212 01:27:38.607311 29725 credentials.hpp:86] Loading credential for authentication from '/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_Vp8fqC/credential'
I1212 01:27:38.607478 29725 slave.cpp:347] Agent using credential for: test-principal
I1212 01:27:38.607501 29725 credentials.hpp:37] Loading credentials for authentication from '/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_Vp8fqC/http_credentials'
I1212 01:27:38.607794 29725 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1212 01:27:38.607818 29706 sched.cpp:232] Version: 1.2.0
I1212 01:27:38.607945 29725 http.cpp:922] Using default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1212 01:27:38.608340 29733 sched.cpp:336] New master detected at master@172.17.0.3:52876
I1212 01:27:38.608418 29733 sched.cpp:402] Authenticating with master master@172.17.0.3:52876
I1212 01:27:38.608434 29733 sched.cpp:409] Using default CRAM-MD5 authenticatee
I1212 01:27:38.608640 29734 authenticatee.cpp:121] Creating new client SASL connection
I1212 01:27:38.608827 29739 master.cpp:6748] Authenticating scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876
I1212 01:27:38.608938 29731 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(769)@172.17.0.3:52876
I1212 01:27:38.609200 29728 authenticator.cpp:98] Creating new server SASL connection
I1212 01:27:38.609484 29727 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1212 01:27:38.609519 29727 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1212 01:27:38.609470 29725 slave.cpp:534] Agent resources: cpus(*):8; mem(*):4096; disk(*):2048; ports(*):[31000-32000]
I1212 01:27:38.609581 29725 slave.cpp:542] Agent attributes: [ ]
I1212 01:27:38.609596 29725 slave.cpp:547] Agent hostname: 4f863e827c94
I1212 01:27:38.609648 29737 authenticator.cpp:204] Received SASL authentication start
I1212 01:27:38.609727 29737 authenticator.cpp:326] Authentication requires more steps
I1212 01:27:38.609879 29727 authenticatee.cpp:259] Received SASL authentication step
I1212 01:27:38.609987 29727 authenticator.cpp:232] Received SASL authentication step
I1212 01:27:38.610040 29727 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4f863e827c94' server FQDN: '4f863e827c94' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1212 01:27:38.610059 29727 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1212 01:27:38.610090 29727 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1212 01:27:38.610113 29727 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4f863e827c94' server FQDN: '4f863e827c94' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1212 01:27:38.610126 29727 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1212 01:27:38.610136 29727 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1212 01:27:38.610153 29727 authenticator.cpp:318] Authentication success
I1212 01:27:38.610319 29734 authenticatee.cpp:299] Authentication success
I1212 01:27:38.610503 29737 master.cpp:6778] Successfully authenticated principal 'test-principal' at scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876
I1212 01:27:38.610580 29729 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(769)@172.17.0.3:52876
I1212 01:27:38.610839 29735 sched.cpp:508] Successfully authenticated with master master@172.17.0.3:52876
I1212 01:27:38.610863 29735 sched.cpp:826] Sending SUBSCRIBE call to master@172.17.0.3:52876
I1212 01:27:38.610966 29735 sched.cpp:859] Will retry registration in 1.120569007secs if necessary
I1212 01:27:38.611131 29729 master.cpp:2633] Received SUBSCRIBE call for framework 'default' at scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876
I1212 01:27:38.611202 29729 master.cpp:2081] Authorizing framework principal 'test-principal' to receive offers for role 'role'
I1212 01:27:38.611240 29732 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.611294 29732 hierarchical.cpp:1291] Performed allocation for 0 agents in 109635ns
I1212 01:27:38.611454 29732 state.cpp:57] Recovering state from '/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_PIODWv/meta'
I1212 01:27:38.611815 29733 master.cpp:2709] Subscribing framework default with checkpointing disabled and capabilities [ ]
I1212 01:27:38.611840 29737 status_update_manager.cpp:203] Recovering status update manager
I1212 01:27:38.612298 29736 slave.cpp:5415] Finished recovery
I1212 01:27:38.612473 29738 sched.cpp:749] Framework registered with 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000
I1212 01:27:38.612512 29738 sched.cpp:763] Scheduler::registered took 21405ns
I1212 01:27:38.612509 29728 hierarchical.cpp:276] Added framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000
I1212 01:27:38.612591 29728 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.612615 29728 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:38.612665 29728 hierarchical.cpp:1291] Performed allocation for 0 agents in 116529ns
I1212 01:27:38.612684 29736 slave.cpp:5589] Querying resource estimator for oversubscribable resources
I1212 01:27:38.612854 29727 status_update_manager.cpp:177] Pausing sending status updates
I1212 01:27:38.612854 29736 slave.cpp:919] New master detected at master@172.17.0.3:52876
I1212 01:27:38.612889 29736 slave.cpp:978] Authenticating with master master@172.17.0.3:52876
I1212 01:27:38.612936 29736 slave.cpp:989] Using default CRAM-MD5 authenticatee
I1212 01:27:38.613060 29736 slave.cpp:951] Detecting new master
I1212 01:27:38.613154 29733 authenticatee.cpp:121] Creating new client SASL connection
I1212 01:27:38.613416 29734 master.cpp:6748] Authenticating (398)@172.17.0.3:52876
I1212 01:27:38.613526 29732 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(770)@172.17.0.3:52876
I1212 01:27:38.613761 29735 authenticator.cpp:98] Creating new server SASL connection
I1212 01:27:38.613934 29737 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1212 01:27:38.613955 29737 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1212 01:27:38.614061 29733 authenticator.cpp:204] Received SASL authentication start
I1212 01:27:38.614190 29733 authenticator.cpp:326] Authentication requires more steps
I1212 01:27:38.614303 29737 authenticatee.cpp:259] Received SASL authentication step
I1212 01:27:38.614456 29728 authenticator.cpp:232] Received SASL authentication step
I1212 01:27:38.614490 29728 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4f863e827c94' server FQDN: '4f863e827c94' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1212 01:27:38.614507 29728 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1212 01:27:38.614558 29728 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1212 01:27:38.614586 29728 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4f863e827c94' server FQDN: '4f863e827c94' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1212 01:27:38.614599 29728 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1212 01:27:38.614609 29728 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1212 01:27:38.614626 29728 authenticator.cpp:318] Authentication success
I1212 01:27:38.614760 29734 authenticatee.cpp:299] Authentication success
I1212 01:27:38.614816 29727 master.cpp:6778] Successfully authenticated principal 'test-principal' at (398)@172.17.0.3:52876
I1212 01:27:38.614864 29732 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(770)@172.17.0.3:52876
I1212 01:27:38.615480 29735 slave.cpp:1073] Successfully authenticated with master master@172.17.0.3:52876
I1212 01:27:38.615665 29735 slave.cpp:1487] Will retry registration in 4.735098ms if necessary
I1212 01:27:38.615893 29730 master.cpp:5161] Registering agent at (398)@172.17.0.3:52876 (4f863e827c94) with id 5061b5a8-6301-4c68-ba05-699a77bdfcc3-S0
I1212 01:27:38.616310 29731 registrar.cpp:461] Applied 1 operations in 57293ns; attempting to update the registry
I1212 01:27:38.616806 29725 hierarchical.cpp:1689] No allocations performed
I1212 01:27:38.616843 29725 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:38.616896 29725 hierarchical.cpp:1291] Performed allocation for 0 agents in 168291ns
I1212 01:27:38.618041 29731 registrar.cpp:506] Successfully updated the registry in 1.657856ms
I1212 01:27:38.618943 29733 slave.cpp:4267] Received ping from slave-observer(364)@172.17.0.3:52876
I1212 01:27:38.619420 29735 master.cpp:5232] Registered agent 5061b5a8-6301-4c68-ba05-699a77bdfcc3-S0 at (398)@172.17.0.3:52876 (4f863e827c94) with cpus(*):8; mem(*):4096; disk(*):2048; ports(*):[31000-32000]
I1212 01:27:38.619619 29731 slave.cpp:1119] Registered with master master@172.17.0.3:52876; given agent ID 5061b5a8-6301-4c68-ba05-699a77bdfcc3-S0
I1212 01:27:38.619648 29731 fetcher.cpp:90] Clearing fetcher cache
I../../src/tests/reservation_tests.cpp:1164: Failure
Failed to wait 15secs for offers
../../src/tests/reservation_tests.cpp:1159: Failure
Actual function call count doesn't match EXPECT_CALL(sched, resourceOffers(&driver, _))...
Expected: to be called once
1212 01:27:38.619724 29734 hierarchical.cpp:490] Added agent 5061b5a8-6301-4c68-ba05-699a77bdfcc3-S0 (4f863e827c94) with cpus(*):8; mem(*):4096; disk(*):2048; ports(*):[31000-32000] (allocated: {})
Actual: never called - unsatisfied and active
I1212 01:27:54.408318 29734 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:38.620008 29731 slave.cpp:1142] Checkpointing SlaveInfo to '/tmp/ReservationTest_CompatibleCheckpointedResourcesWithPersistentVolumes_PIODWv/meta/slaves/5061b5a8-6301-4c68-ba05-699a77bdfcc3-S0/slave.info'
I1212 01:27:53.610275 29739 master.cpp:1305] Framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000 (default) at scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876 disconnected
I1212 01:27:38.619819 29725 status_update_manager.cpp:184] Resuming sending status updates
I1212 01:27:54.408457 29734 hierarchical.cpp:1314] Performed allocation for agent 5061b5a8-6301-4c68-ba05-699a77bdfcc3-S0 in 1.825629ms
I1212 01:27:54.408516 29739 master.cpp:2918] Disconnecting framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000 (default) at scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876
I1212 01:27:54.408653 29739 master.cpp:2942] Deactivating framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000 (default) at scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876
I1212 01:27:54.408836 29739 master.cpp:1318] Giving framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000 (default) at scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876 0ns to failover
I1212 01:27:54.408875 29734 hierarchical.cpp:1689] No allocations performed
I1212 01:27:54.408931 29734 hierarchical.cpp:1784] No inverse offers to send out!
I1212 01:27:54.409059 29734 hierarchical.cpp:1291] Performed allocation for 1 agents in 378581ns
W1212 01:27:54.409073 29739 master.cpp:6442] Master returning resources offered to framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000 because the framework has terminated or is inactive
I1212 01:27:54.409229 29734 hierarchical.cpp:391] Deactivated framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000
I1212 01:27:54.410310 29740 master.cpp:6429] Framework failover timeout, removing framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000 (default) at scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876
I1212 01:27:54.410315 29734 hierarchical.cpp:1023] Recovered cpus(*):8; mem(*):4096; disk(*):2048; ports(*):[31000-32000] (total: cpus(*):8; mem(*):4096; disk(*):2048; ports(*):[31000-32000], allocated: {}) on agent 5061b5a8-6301-4c68-ba05-699a77bdfcc3-S0 from framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000
I1212 01:27:54.410351 29740 master.cpp:7299] Removing framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000 (default) at scheduler-f7d47b50-25fc-488f-b773-4cb3ce54d7ac@172.17.0.3:52876
I1212 01:27:54.410920 29739 hierarchical.cpp:342] Removed framework 5061b5a8-6301-4c68-ba05-699a77bdfcc3-0000
*** Aborted at 1481506074 (unix time) try "date -d @1481506074" if you are using GNU date ***
I1212 01:27:54.412015 29706 master.cpp:1097] Master terminating
I1212 01:27:54.413022 29732 hierarchical.cpp:522] Removed agent 5061b5a8-6301-4c68-ba05-699a77bdfcc3-S0
PC: @ 0x0 (unknown)
*** SIGSEGV (@0x0) received by PID 29706 (TID 0x2b08606bf700) from PID 0; stack trace: ***
I1212 01:27:54.414558 29725 hierarchical.cpp:1689] No allocations performed
I1212 01:27:54.414626 29725 hierarchical.cpp:1291] Performed allocation for 0 agents in 118250ns
@ 0x2b08ac7d9197 (unknown)
@ 0x2b08ac7dd479 (unknown)
@ 0x2b085764b330 (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.2.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.2.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1481503578-13961
Untagged: mesos-1481503578-13961:latest
Deleted: sha256:d2aa535505a8bd08db391a93b4fc5d1138df3d84c86a99a0cafa7d3ff9b1c269
Deleted: sha256:522098b78a5e4e7b73f0ccd431df453aaa5c25e418a80c8e5664845083e2cfa5
Deleted: sha256:213a6209e7b8e262929e875a98aa63f3a8ce814b545463ddf6273b9b48732d6b
Deleted: sha256:11d18a8346443641ded0834decd5c895a3d22cf32b05918098341ace3a38b7cd
Deleted: sha256:1dd7a7f2822953796718872133c107c94e867df8b5139d5ae6bff429be6119e3
Deleted: sha256:8bafe783b98d186cc353d8caeeebb83796d812a74c253b8a243f8968be2dd8ae
Deleted: sha256:e06f5bc0cedd8d6bc9cf8668c4b0c71ec9c702edd47c20896af0014904ec2b92
Deleted: sha256:be1d5e089db178b20ab93c89a47736be2eddbb2d50757afea4ef17e4acf90cbf
Deleted: sha256:0199924efcca006cb64d592f7614caf7d1968ebfcfc7089bd2819b97e32b6234
Deleted: sha256:ea18ad81e47098819fdcb89a8c4e0bd900e8052bbe488044e8f56fbfac30194b
Deleted: sha256:901d252b32899f72d978f0c138b0a79b9770cbed8ce7b8db9052bf76c3b7a565
Deleted: sha256:36ed7a8c56cfeff73bc4a85bd47548b9b6ddcf7983614bb91941dfcdacd4e609
Deleted: sha256:bb89e4f5e0b86b8ca8261df0f5e54a1adb1fddca66f325e849009d78650a041d
Deleted: sha256:fa89388e094eb130dfe69428858d2e7217552392570a86e05919ede5570600a5
Deleted: sha256:a6cc934d4536a803141c3df321daa7f08a5f2b15059377f88064b46f21797297
Deleted: sha256:95b5ddac84e28d130261ca04335729a651fd2b2de3e35dcc6267593e8d5dc75f
Deleted: sha256:f41eacd1a1c72b453bc4f1ae1463529b29aa9d8ef0abf86437a9e88b070e36cc
Deleted: sha256:5ca2b09f73a62abf28decd91fad64af4f10c7772384aeb7e56e12beef90d73e4
Deleted: sha256:fb19b753e2e85c066b95d3dd4e0528e992a218c375cdff9d09028f94e395119b
Deleted: sha256:4e6a5f04cb53e6d7f424b72b402cf3074beeb64a39a5c60e4f4a27f3ec83a99c
Deleted: sha256:98fd19dbe95cb7b391fb6360e4dae169d630c7d089295339e3badc3ca6eed959
Deleted: sha256:4609db30054e5e4a24b086c5907df5a83773e6a2f40be6b524ea0a57da0faabe
Deleted: sha256:83b3f8a982ababd68baad2cfc8b82b92175c9fb5556de6b28299af5fe87149e0
Deleted: sha256:203130ad983f23770e9316211c8677a434c8edd18c5909d9f98452c01c177d73
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #16415
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/16415/changes>