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/06 06:35:20 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) #4291
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)/4291/display/redirect>
------------------------------------------
[...truncated 16.41 MB...]
I1006 06:35:13.297924 6165 slave.cpp:5920] Current disk usage 69.88%. Max allowed age: 1.408399598781181days
I1006 06:35:13.298094 6165 slave.cpp:6491] Received oversubscribable resources {} from the resource estimator
I1006 06:35:13.298629 6167 hierarchical.cpp:1925] No allocations performed
I1006 06:35:13.298759 6167 hierarchical.cpp:2015] No inverse offers to send out!
I1006 06:35:13.298857 6167 hierarchical.cpp:1468] Performed allocation for 1 agents in 419202ns
I1006 06:35:13.300746 6162 slave.cpp:6477] Querying resource estimator for oversubscribable resources
I1006 06:35:13.301038 6162 slave.cpp:4970] Received ping from slave-observer(343)@172.17.0.2:41803
I1006 06:35:13.301133 6163 master.cpp:7062] Marking agent 97b3b110-e76b-482a-9908-d7741a917162-S1 at slave(337)@172.17.0.2:41803 (594f4d6e6416) unreachable: health check timed out
I1006 06:35:13.301357 6162 slave.cpp:6491] Received oversubscribable resources {} from the resource estimator
I1006 06:35:13.301421 6166 hierarchical.cpp:1925] No allocations performed
I1006 06:35:13.301563 6166 hierarchical.cpp:2015] No inverse offers to send out!
I1006 06:35:13.301651 6166 hierarchical.cpp:1468] Performed allocation for 1 agents in 584097ns
I1006 06:35:13.302150 6162 registrar.cpp:495] Applied 1 operations in 139561ns; attempting to update the registry
I1006 06:35:13.303130 6162 registrar.cpp:552] Successfully updated the registry in 0ns
I1006 06:35:13.303468 6165 master.cpp:7110] Marked agent 97b3b110-e76b-482a-9908-d7741a917162-S1 at slave(337)@172.17.0.2:41803 (594f4d6e6416) unreachable: health check timed out
I1006 06:35:13.304556 6165 master.cpp:9159] Removing offer 97b3b110-e76b-482a-9908-d7741a917162-O1
I1006 06:35:13.304735 6167 hierarchical.cpp:626] Removed agent 97b3b110-e76b-482a-9908-d7741a917162-S1
I1006 06:35:13.305160 6166 sched.cpp:959] Rescinded offer 97b3b110-e76b-482a-9908-d7741a917162-O1
I1006 06:35:13.305374 6165 master.cpp:2080] Notifying framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803 of lost agent 97b3b110-e76b-482a-9908-d7741a917162-S1 (594f4d6e6416)
I1006 06:35:13.305392 6166 sched.cpp:970] Scheduler::offerRescinded took 159371ns
I1006 06:35:13.305846 6168 sched.cpp:1103] Lost agent 97b3b110-e76b-482a-9908-d7741a917162-S1
I1006 06:35:13.305975 6168 sched.cpp:1114] Scheduler::slaveLost took 87349ns
I1006 06:35:13.307214 6142 slave.cpp:843] Agent terminating
I1006 06:35:13.316424 6164 master.cpp:1869] Skipping periodic registry garbage collection: no agents qualify for removal
I1006 06:35:13.316656 6163 hierarchical.cpp:1925] No allocations performed
I1006 06:35:13.316706 6163 hierarchical.cpp:2015] No inverse offers to send out!
I1006 06:35:13.316761 6163 hierarchical.cpp:1468] Performed allocation for 0 agents in 175421ns
I1006 06:35:13.317987 6168 master.cpp:7341] Performing explicit task state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.318303 6168 master.cpp:7470] Sending explicit reconciliation state TASK_UNREACHABLE for task e9b87e3a-b794-43e6-9604-f48f752bf109 of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.318907 6168 sched.cpp:1041] Scheduler::statusUpdate took 59861ns
I1006 06:35:13.319574 6162 master.cpp:7341] Performing explicit task state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.319875 6162 master.cpp:7470] Sending explicit reconciliation state TASK_UNREACHABLE for task d6fb067b-b723-408c-b0a1-20dd216be339 of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.320674 6166 sched.cpp:1041] Scheduler::statusUpdate took 227502ns
I1006 06:35:13.322454 6161 master.cpp:1874] Attempting to remove 1 unreachable agents from the registry
I1006 06:35:13.322546 6164 hierarchical.cpp:1925] No allocations performed
I1006 06:35:13.322592 6164 hierarchical.cpp:2015] No inverse offers to send out!
I1006 06:35:13.322646 6164 hierarchical.cpp:1468] Performed allocation for 0 agents in 165556ns
I1006 06:35:13.323392 6168 registrar.cpp:495] Applied 1 operations in 78978ns; attempting to update the registry
I1006 06:35:13.324610 6164 registrar.cpp:552] Successfully updated the registry in 0ns
I1006 06:35:13.324941 6164 master.cpp:1917] Garbage collected 1 unreachable agents from the registry
I1006 06:35:13.325754 6168 master.cpp:7341] Performing explicit task state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.325974 6168 master.cpp:7470] Sending explicit reconciliation state TASK_UNKNOWN for task 4acedae8-b102-4139-9709-6a7c9ef40755 of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.326484 6168 sched.cpp:1041] Scheduler::statusUpdate took 56562ns
I1006 06:35:13.327170 6166 master.cpp:7341] Performing explicit task state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.327437 6166 master.cpp:7470] Sending explicit reconciliation state TASK_UNREACHABLE for task bebc0388-63d7-4460-b7ff-7141a479cfc0 of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.327997 6162 sched.cpp:1041] Scheduler::statusUpdate took 61906ns
I1006 06:35:13.329732 6161 master.cpp:1874] Attempting to remove 1 unreachable agents from the registry
I1006 06:35:13.329900 6164 hierarchical.cpp:1925] No allocations performed
I1006 06:35:13.329948 6164 hierarchical.cpp:2015] No inverse offers to send out!
I1006 06:35:13.330008 6164 hierarchical.cpp:1468] Performed allocation for 0 agents in 174668ns
I1006 06:35:13.330613 6168 registrar.cpp:495] Applied 1 operations in 69625ns; attempting to update the registry
I1006 06:35:13.331553 6167 registrar.cpp:552] Successfully updated the registry in 0ns
I1006 06:35:13.331908 6163 master.cpp:1917] Garbage collected 1 unreachable agents from the registry
I1006 06:35:13.332917 6161 master.cpp:7341] Performing explicit task state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.333231 6161 master.cpp:7470] Sending explicit reconciliation state TASK_UNKNOWN for task 34e1282c-fe08-460f-add3-e76802014fe0 of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.333880 6168 sched.cpp:1041] Scheduler::statusUpdate took 59710ns
I1006 06:35:13.334275 6142 sched.cpp:2021] Asked to stop the driver
I1006 06:35:13.334552 6162 sched.cpp:1203] Stopping framework 97b3b110-e76b-482a-9908-d7741a917162-0000
I1006 06:35:13.335064 6165 master.cpp:8343] Processing TEARDOWN call for framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.335160 6165 master.cpp:8355] Removing framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.335285 6165 master.cpp:3264] Deactivating framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
I1006 06:35:13.335538 6164 hierarchical.cpp:412] Deactivated framework 97b3b110-e76b-482a-9908-d7741a917162-0000
I1006 06:35:13.336413 6162 hierarchical.cpp:355] Removed framework 97b3b110-e76b-482a-9908-d7741a917162-0000
I1006 06:35:13.337273 6167 master.cpp:1160] Master terminating
[ OK ] PartitionTest.RegistryGcByAge (215 ms)
[ RUN ] PartitionTest.RegistryGcRace
I1006 06:35:13.348095 6142 cluster.cpp:162] Creating default 'local' authorizer
I1006 06:35:13.353413 6165 master.cpp:442] Master bbdfc6c4-fc0f-46c5-9265-d1bba925503b (594f4d6e6416) started on 172.17.0.2:41803
I1006 06:35:13.353485 6165 master.cpp:444] Flags at startup: --acls="" --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="true" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/eTNp8c/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="1" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-1.4.1/_inst/share/mesos/webui" --work_dir="/tmp/eTNp8c/master" --zk_session_timeout="10secs"
I1006 06:35:13.354131 6165 master.cpp:494] Master only allowing authenticated frameworks to register
I1006 06:35:13.354233 6165 master.cpp:508] Master only allowing authenticated agents to register
I1006 06:35:13.354393 6165 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I1006 06:35:13.354457 6165 credentials.hpp:37] Loading credentials for authentication from '/tmp/eTNp8c/credentials'
I1006 06:35:13.354990 6165 master.cpp:566] Using default 'crammd5' authenticator
I1006 06:35:13.355381 6165 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I1006 06:35:13.355710 6165 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I1006 06:35:13.356060 6165 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I1006 06:35:13.356334 6165 master.cpp:646] Authorization enabled
I1006 06:35:13.356683 6161 hierarchical.cpp:171] Initialized hierarchical allocator process
I1006 06:35:13.356719 6163 whitelist_watcher.cpp:77] No whitelist given
I1006 06:35:13.360934 6162 master.cpp:2163] Elected as the leading master!
I1006 06:35:13.360990 6162 master.cpp:1702] Recovering from registrar
I1006 06:35:13.361346 6168 registrar.cpp:347] Recovering registrar
I1006 06:35:13.362251 6168 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
I1006 06:35:13.362467 6168 registrar.cpp:495] Applied 1 operations in 37625ns; attempting to update the registry
I1006 06:35:13.363365 6163 registrar.cpp:552] Successfully updated the registry in 0ns
I1006 06:35:13.363564 6163 registrar.cpp:424] Successfully recovered registrar
I1006 06:35:13.364114 6165 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I1006 06:35:13.364162 6164 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
W1006 06:35:13.372690 6142 process.cpp:3196] Attempted to spawn already running process files@172.17.0.2:41803
I1006 06:35:13.374418 6142 containerizer.cpp:246] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W1006 06:35:13.375206 6142 backend.cpp:76] Failed to create 'overlay' backend: OverlayBackend requires root privileges
W1006 06:35:13.375461 6142 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I1006 06:35:13.375509 6142 provisioner.cpp:255] Using default backend 'copy'
I1006 06:35:13.378484 6142 cluster.cpp:448] Creating default 'local' authorizer
I1006 06:35:13.381826 6163 slave.cpp:250] Mesos agent started on (338)@172.17.0.2:41803
I1006 06:35:13.381863 6163 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/PartitionTest_RegistryGcRace_2KNCbH/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/PartitionTest_RegistryGcRace_2KNCbH/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/PartitionTest_RegistryGcRace_2KNCbH/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/PartitionTest_RegistryGcRace_2KNCbH/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/PartitionTest_RegistryGcRace_2KNCbH/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/mesos-1.4.1/_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/PartitionTest_RegistryGcRace_2KNCbH" --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/PartitionTest_RegistryGcRace_Syu09r"
I1006 06:35:13.382609 6163 credentials.hpp:86] Loading credential for authentication from '/tmp/PartitionTest_RegistryGcRace_2KNCbH/credential'
I1006 06:35:13.382876 6163 slave.cpp:283] Agent using credential for: test-principal
I1006 06:35:13.382905 6163 credentials.hpp:37] Loading credentials for authentication from '/tmp/PartitionTest_RegistryGcRace_2KNCbH/http_credentials'
I1006 06:35:13.383304 6163 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I1006 06:35:13.383646 6163 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I1006 06:35:13.385949 6163 slave.cpp:565] 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"}]
I1006 06:35:13.386394 6163 slave.cpp:573] Agent attributes: [ ]
I1006 06:35:13.386416 6163 slave.cpp:582] Agent hostname: 594f4d6e6416
I1006 06:35:13.386745 6165 status_update_manager.cpp:177] Pausing sending status updates
I1006 06:35:13.388905 6164 state.cpp:64] Recovering state from '/tmp/PartitionTest_RegistryGcRace_Syu09r/meta'
I1006 06:35:13.410207 6165 status_update_manager.cpp:203] Recovering status update manager
I1006 06:35:13.410825 6168 containerizer.cpp:609] Recovering containerizer
I1006 06:35:13.413388 6163 provisioner.cpp:416] Provisioner recovery complete
I1006 06:35:13.413952 6163 slave.cpp:6295] Finished recovery
I1006 06:35:13.414634 6163 slave.cpp:6477] Querying resource estimator for oversubscribable resources
I1006 06:35:13.415042 6167 status_update_manager.cpp:177] Pausing sending status updates
I1006 06:35:13.415082 6162 slave.cpp:971] New master detected at master@172.17.0.2:41803
I1006 06:35:13.415357 6162 slave.cpp:1006] Detecting new master
I1006 06:35:13.415689 6162 slave.cpp:6491] Received oversubscribable resources {} from the resource estimator
I1006 06:35:13.416640 6165 slave.cpp:1033] Authenticating with master master@172.17.0.2:41803
I1006 06:35:13.416880 6165 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I1006 06:35:13.417382 6163 authenticatee.cpp:121] Creating new client SASL connection
I1006 06:35:13.417912 6163 master.cpp:7832] Authenticating slave(338)@172.17.0.2:41803
I1006 06:35:13.418272 6161 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(729)@172.17.0.2:41803
I1006 06:35:13.418856 6166 authenticator.cpp:98] Creating new server SASL connection
I1006 06:35:13.419370 6167 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1006 06:35:13.419415 6167 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1006 06:35:13.419638 6167 authenticator.cpp:204] Received SASL authentication start
I1006 06:35:13.419752 6167 authenticator.cpp:326] Authentication requires more steps
I1006 06:35:13.420002 6162 authenticatee.cpp:259] Received SASL authentication step
I1006 06:35:13.420261 6162 authenticator.cpp:232] Received SASL authentication step
I1006 06:35:13.420302 6162 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '594f4d6e6416' server FQDN: '594f4d6e6416' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1006 06:35:13.420378 6162 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1006 06:35:13.420449 6162 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1006 06:35:13.420532 6162 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '594f4d6e6416' server FQDN: '594f4d6e6416' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1006 06:35:13.420553 6162 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1006 06:35:13.420572 6162 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1006 06:35:13.420599 6162 authenticator.cpp:318] Authentication success
I1006 06:35:13.420960 6163 authenticatee.cpp:299] Authentication success
I1006 06:35:13.421017 6161 master.cpp:7862] Successfully authenticated principal 'test-principal' at slave(338)@172.17.0.2:41803
I1006 06:35:13.421124 6162 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(729)@172.17.0.2:41803
I1006 06:35:13.421561 6166 slave.cpp:1128] Successfully authenticated with master master@172.17.0.2:41803
I1006 06:35:13.421998 6166 slave.cpp:1607] Will retry registration in 15.417366ms if necessary
I1006 06:35:13.422312 6164 master.cpp:5714] Received register agent message from slave(338)@172.17.0.2:41803 (594f4d6e6416)
I1006 06:35:13.422503 6164 master.cpp:3803] Authorizing agent with principal 'test-principal'
I1006 06:35:13.423179 6168 master.cpp:5774] Authorized registration of agent at slave(338)@172.17.0.2:41803 (594f4d6e6416)
I1006 06:35:13.423399 6168 master.cpp:5867] Registering agent at slave(338)@172.17.0.2:41803 (594f4d6e6416) with id bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0
I1006 06:35:13.424064 6165 registrar.cpp:495] Applied 1 operations in 101064ns; attempting to update the registry
I1006 06:35:13.425149 6165 registrar.cpp:552] Successfully updated the registry in 0ns
I1006 06:35:13.425624 6164 master.cpp:5914] Admitted agent bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 at slave(338)@172.17.0.2:41803 (594f4d6e6416)
I1006 06:35:13.426674 6167 slave.cpp:4970] Received ping from slave-observer(344)@172.17.0.2:41803
I1006 06:35:13.427247 6163 hierarchical.cpp:593] Added agent bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 (594f4d6e6416) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I1006 06:35:13.427723 6163 hierarchical.cpp:1925] No allocations performed
I1006 06:35:13.427798 6163 hierarchical.cpp:1468] Performed allocation for 1 agents in 276014ns
I1006 06:35:13.428185 6162 slave.cpp:1174] Registered with master master@172.17.0.2:41803; given agent ID bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0
I1006 06:35:13.427940 6164 master.cpp:5945] Registered agent bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 at slave(338)@172.17.0.2:41803 (594f4d6e6416) 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"}]
I1006 06:35:13.428568 6166 status_update_manager.cpp:184] Resuming sending status updates
I1006 06:35:13.428890 6162 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/PartitionTest_RegistryGcRace_Syu09r/meta/slaves/bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0/slave.info'
I1006 06:35:13.429491 6162 slave.cpp:1243] Forwarding total oversubscribed resources {}
I1006 06:35:13.429890 6165 master.cpp:6683] Received update of agent bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 at slave(338)@172.17.0.2:41803 (594f4d6e6416) with total oversubscribed resources {}
I1006 06:35:13.430629 6167 hierarchical.cpp:660] Agent bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 (594f4d6e6416) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
W1006 06:35:13.431653 6142 process.cpp:3196] Attempted to spawn already running process version@172.17.0.2:41803
I1006 06:35:13.433135 6142 sched.cpp:232] Version: 1.4.1
I1006 06:35:13.434239 6162 sched.cpp:336] New master detected at master@172.17.0.2:41803
I1006 06:35:13.434525 6162 sched.cpp:407] Authenticating with master master@172.17.0.2:41803
I1006 06:35:13.434551 6162 sched.cpp:414] Using default CRAM-MD5 authenticatee
I1006 06:35:13.435107 6165 authenticatee.cpp:121] Creating new client SASL connection
I1006 06:35:13.435746 6161 master.cpp:7832] Authenticating scheduler-925f744e-179e-44ae-a358-fcc313f2b43e@172.17.0.2:41803
I1006 06:35:13.435986 6168 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(730)@172.17.0.2:41803
I1006 06:35:13.436537 6163 authenticator.cpp:98] Creating new server SASL connection
I1006 06:35:13.437063 6163 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I1006 06:35:13.437111 6163 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I1006 06:35:13.437273 6163 authenticator.cpp:204] Received SASL authentication start
I1006 06:35:13.437345 6163 authenticator.cpp:326] Authentication requires more steps
I1006 06:35:13.437598 6162 authenticatee.cpp:259] Received SASL authentication step
I1006 06:35:13.437973 6167 authenticator.cpp:232] Received SASL authentication step
I1006 06:35:13.438081 6167 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '594f4d6e6416' server FQDN: '594f4d6e6416' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I1006 06:35:13.438139 6167 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I1006 06:35:13.438216 6167 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1006 06:35:13.438253 6167 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '594f4d6e6416' server FQDN: '594f4d6e6416' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I1006 06:35:13.438295 6167 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I1006 06:35:13.438340 6167 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I1006 06:35:13.438370 6167 authenticator.cpp:318] Authentication success
I1006 06:35:13.438664 6165 authenticatee.cpp:299] Authentication success
I1006 06:35:13.438710 6161 master.cpp:7862] Successfully authenticated principal 'test-principal' at scheduler-925f744e-179e-44ae-a358-fcc313f2b43e@172.17.0.2:41803
I1006 06:35:13.438908 6168 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(730)@172.17.0.2:41803
*** Aborted at 1507271713 (unix time) try "date -d @1507271713" if you are using GNU date ***
I1006 06:35:13.439643 6166 sched.cpp:513] Successfully authenticated with master master@172.17.0.2:41803
I1006 06:35:13.439745 6166 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.2:41803
I1006 06:35:13.440186 6166 sched.cpp:869] Will retry registration in 427.907251ms if necessary
I1006 06:35:13.440557 6161 master.cpp:2894] Received SUBSCRIBE call for framework 'default' at scheduler-925f744e-179e-44ae-a358-fcc313f2b43e@172.17.0.2:41803
I1006 06:35:13.440874 6161 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I1006 06:35:13.441699 6162 master.cpp:2974] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT, PARTITION_AWARE ]
I1006 06:35:13.442826 6162 sched.cpp:759] Framework registered with bbdfc6c4-fc0f-46c5-9265-d1bba925503b-0000
I1006 06:35:13.442858 6164 hierarchical.cpp:303] Added framework bbdfc6c4-fc0f-46c5-9265-d1bba925503b-0000
I1006 06:35:13.442903 6162 sched.cpp:773] Scheduler::registered took 28us
I1006 06:35:13.444886 6164 hierarchical.cpp:2015] No inverse offers to send out!
I1006 06:35:13.444964 6164 hierarchical.cpp:1468] Performed allocation for 1 agents in 1.889777ms
I1006 06:35:13.446193 6166 master.cpp:7662] Sending 1 offers to framework bbdfc6c4-fc0f-46c5-9265-d1bba925503b-0000 (default) at scheduler-925f744e-179e-44ae-a358-fcc313f2b43e@172.17.0.2:41803
I1006 06:35:13.447209 6161 sched.cpp:933] Scheduler::resourceOffers took 35920ns
PC: @ 0x7fb3c214373a process::EventQueue::Consumer::empty()
*** SIGSEGV (@0x8) received by PID 6142 (TID 0x7fb3afeae700) from PID 8; stack trace: ***
@ 0x7fb3acd64455 (unknown)
@ 0x7fb3acd69a71 (unknown)
@ 0x7fb3acd5d2b8 (unknown)
@ 0x7fb3bafec5e0 (unknown)
@ 0x7fb3c214373a process::EventQueue::Consumer::empty()
@ 0x7fb3c212f282 process::ProcessManager::resume()
@ 0x7fb3c212b8ea _ZZN7process14ProcessManager12init_threadsEvENKUlvE_clEv
@ 0x7fb3c213df94 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
@ 0x7fb3c213ded8 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEclEv
@ 0x7fb3c213de62 _ZNSt6thread5_ImplISt12_Bind_simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
@ 0x7fb3ba97e2b0 (unknown)
@ 0x7fb3bafe4e25 start_thread
@ 0x7fb3ba0e634d __clone
make[4]: *** [check-local] Segmentation fault (core dumped)
make[4]: Leaving directory `/mesos/mesos-1.4.1/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-1.4.1/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-1.4.1/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-1.4.1/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1507267039-17894
Untagged: mesos-1507267039-17894:latest
Deleted: sha256:187e02475b4c9590934b6641c399c208cf8ec91eaf3312f6c0c1c51358ab09a4
Deleted: sha256:67758dfc3e28b48fd132a52136e7a26d74e5ccd53d4ddbcbd49de30970d8d857
Deleted: sha256:2dc08d31b0ac981ca77ad1a7fef1ac0aaec9fdb4ade150d9a87229a457bf52ff
Deleted: sha256:3c66f42e636317cfa35771da07e69f996c5a5760906a275dfba4a312ef1de230
Deleted: sha256:ea50cd159916e28b59a9243666b313789189bc17b95f98b9c0748f29d5430a5b
Deleted: sha256:a33560dccd9384fe0862faf8b73ca81c8c5411929ee08189ff29167dd591c8c7
Deleted: sha256:a8873720be596216d9348ac008d51e4ccc48868f3784d3842bfc5f4f84007176
Deleted: sha256:4113536ff3594981355ec9fb3cca10a64dd9447ea871a641f70fda9bb98cb5bf
Deleted: sha256:03166f0dafbd1a588d8e508d1deaaa2fc54b8140cbd84a9cb24e025c8a8f6866
Deleted: sha256:96a1a7556f94541899f0e44a5857fb5cf2e25149600fd97a27b9e016e3ea58dc
Deleted: sha256:a45fb07c85f14c6acd8fd0abdc40f3f7eaf71a0decd42164a2ee3adb3ac8dae9
Deleted: sha256:28e5443e6c5aa41749aafdefaca81ea9789d5de3adf6c2b223bd83a55789e875
Deleted: sha256:cad14753f8b4c27bd92d96bad8dbf5abc6811eaf557355ad3f30f2bae4acbd0c
Deleted: sha256:3aa56910e01910d5e480ce37afd6cfe57bdbec487e8533a72ec35003fb115f7c
Deleted: sha256:a8a3a85ada91bea4ec4713ff0fab12acd723511176234f9cc8700827dfedd8fd
Deleted: sha256:71788490a2e0c9057da8b7472092c8cbf5a4609a0e9f11a0381ef8f00e73af03
Deleted: sha256:d6d309e10dbe3e0e491e936f66b04cc53ddc8810b14aee9ff3ffa6a60d499aa4
Deleted: sha256:2752b067ae0c9816531e450cc5f87f9b5b8a04432256be4877155802cb4d663b
Deleted: sha256:443b57e555a12f4a5e571d9876a3fff63ddff05e9ca7ae61a3f5343c29e89298
Deleted: sha256:f50e955c991f8eec979aea50b3c232bf7f7db165db19b8416d44c63429978b59
Deleted: sha256:f287e8cfd96648948ad0a708b96ff77a23ff08d805787cbf95148808eb76549c
Deleted: sha256:99b823b7901c7b14b72bbc1e359d6bccd4c6643b4e70773eaf14ac2aecea4389
Deleted: sha256:a5a237b277bf1dffd9cff5859dc313b4a765d4abee3f66f8a10de0d009b24196
Deleted: sha256:9f5de8d69ae821582565a05ba20b23bd44e56cbf9c44d79372191af90e1622ac
Deleted: sha256:5194125ccf307cec77e7a070cf20ca9fc93c7c769894d80efc8f4b4bf9bcff29
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) #4292
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)/4292/display/redirect?page=changes>
Re: 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) #4291
Posted by Benjamin Mahler <bm...@apache.org>.
I wasn't able to reproduce deterministically when I ran this particular
test in repetition.
It has happened a few other times in CI since this one, but across
different tests:
PartitionTest.RegistryGcRace (this one)
SlaveTest.IgnoreNonLeaderStatusUpdateAcknowledgement
ReservationEndpointsTest.BadCredentials
PersistentVolumeTest.SharedPersistentVolumeMasterFailover
I re-opened https://issues.apache.org/jira/browse/MESOS-7921 for now,
targeted as a blocker for 1.5.0 and 1.4.1 since this happens without the
lock free code enabled.
On Thu, Oct 5, 2017 at 11:35 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> 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)/4291/display/redirect>
>
> ------------------------------------------
> [...truncated 16.41 MB...]
> I1006 06:35:13.297924 6165 slave.cpp:5920] Current disk usage 69.88%. Max
> allowed age: 1.408399598781181days
> I1006 06:35:13.298094 6165 slave.cpp:6491] Received oversubscribable
> resources {} from the resource estimator
> I1006 06:35:13.298629 6167 hierarchical.cpp:1925] No allocations performed
> I1006 06:35:13.298759 6167 hierarchical.cpp:2015] No inverse offers to
> send out!
> I1006 06:35:13.298857 6167 hierarchical.cpp:1468] Performed allocation
> for 1 agents in 419202ns
> I1006 06:35:13.300746 6162 slave.cpp:6477] Querying resource estimator
> for oversubscribable resources
> I1006 06:35:13.301038 6162 slave.cpp:4970] Received ping from
> slave-observer(343)@172.17.0.2:41803
> I1006 06:35:13.301133 6163 master.cpp:7062] Marking agent
> 97b3b110-e76b-482a-9908-d7741a917162-S1 at slave(337)@172.17.0.2:41803
> (594f4d6e6416) unreachable: health check timed out
> I1006 06:35:13.301357 6162 slave.cpp:6491] Received oversubscribable
> resources {} from the resource estimator
> I1006 06:35:13.301421 6166 hierarchical.cpp:1925] No allocations performed
> I1006 06:35:13.301563 6166 hierarchical.cpp:2015] No inverse offers to
> send out!
> I1006 06:35:13.301651 6166 hierarchical.cpp:1468] Performed allocation
> for 1 agents in 584097ns
> I1006 06:35:13.302150 6162 registrar.cpp:495] Applied 1 operations in
> 139561ns; attempting to update the registry
> I1006 06:35:13.303130 6162 registrar.cpp:552] Successfully updated the
> registry in 0ns
> I1006 06:35:13.303468 6165 master.cpp:7110] Marked agent
> 97b3b110-e76b-482a-9908-d7741a917162-S1 at slave(337)@172.17.0.2:41803
> (594f4d6e6416) unreachable: health check timed out
> I1006 06:35:13.304556 6165 master.cpp:9159] Removing offer
> 97b3b110-e76b-482a-9908-d7741a917162-O1
> I1006 06:35:13.304735 6167 hierarchical.cpp:626] Removed agent
> 97b3b110-e76b-482a-9908-d7741a917162-S1
> I1006 06:35:13.305160 6166 sched.cpp:959] Rescinded offer
> 97b3b110-e76b-482a-9908-d7741a917162-O1
> I1006 06:35:13.305374 6165 master.cpp:2080] Notifying framework
> 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803 of lost
> agent 97b3b110-e76b-482a-9908-d7741a917162-S1 (594f4d6e6416)
> I1006 06:35:13.305392 6166 sched.cpp:970] Scheduler::offerRescinded took
> 159371ns
> I1006 06:35:13.305846 6168 sched.cpp:1103] Lost agent
> 97b3b110-e76b-482a-9908-d7741a917162-S1
> I1006 06:35:13.305975 6168 sched.cpp:1114] Scheduler::slaveLost took
> 87349ns
> I1006 06:35:13.307214 6142 slave.cpp:843] Agent terminating
> I1006 06:35:13.316424 6164 master.cpp:1869] Skipping periodic registry
> garbage collection: no agents qualify for removal
> I1006 06:35:13.316656 6163 hierarchical.cpp:1925] No allocations performed
> I1006 06:35:13.316706 6163 hierarchical.cpp:2015] No inverse offers to
> send out!
> I1006 06:35:13.316761 6163 hierarchical.cpp:1468] Performed allocation
> for 0 agents in 175421ns
> I1006 06:35:13.317987 6168 master.cpp:7341] Performing explicit task
> state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000
> (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:
> 41803
> I1006 06:35:13.318303 6168 master.cpp:7470] Sending explicit
> reconciliation state TASK_UNREACHABLE for task e9b87e3a-b794-43e6-9604-f48f752bf109
> of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
> I1006 06:35:13.318907 6168 sched.cpp:1041] Scheduler::statusUpdate took
> 59861ns
> I1006 06:35:13.319574 6162 master.cpp:7341] Performing explicit task
> state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000
> (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:
> 41803
> I1006 06:35:13.319875 6162 master.cpp:7470] Sending explicit
> reconciliation state TASK_UNREACHABLE for task d6fb067b-b723-408c-b0a1-20dd216be339
> of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
> I1006 06:35:13.320674 6166 sched.cpp:1041] Scheduler::statusUpdate took
> 227502ns
> I1006 06:35:13.322454 6161 master.cpp:1874] Attempting to remove 1
> unreachable agents from the registry
> I1006 06:35:13.322546 6164 hierarchical.cpp:1925] No allocations performed
> I1006 06:35:13.322592 6164 hierarchical.cpp:2015] No inverse offers to
> send out!
> I1006 06:35:13.322646 6164 hierarchical.cpp:1468] Performed allocation
> for 0 agents in 165556ns
> I1006 06:35:13.323392 6168 registrar.cpp:495] Applied 1 operations in
> 78978ns; attempting to update the registry
> I1006 06:35:13.324610 6164 registrar.cpp:552] Successfully updated the
> registry in 0ns
> I1006 06:35:13.324941 6164 master.cpp:1917] Garbage collected 1
> unreachable agents from the registry
> I1006 06:35:13.325754 6168 master.cpp:7341] Performing explicit task
> state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000
> (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:
> 41803
> I1006 06:35:13.325974 6168 master.cpp:7470] Sending explicit
> reconciliation state TASK_UNKNOWN for task 4acedae8-b102-4139-9709-6a7c9ef40755
> of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
> I1006 06:35:13.326484 6168 sched.cpp:1041] Scheduler::statusUpdate took
> 56562ns
> I1006 06:35:13.327170 6166 master.cpp:7341] Performing explicit task
> state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000
> (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:
> 41803
> I1006 06:35:13.327437 6166 master.cpp:7470] Sending explicit
> reconciliation state TASK_UNREACHABLE for task bebc0388-63d7-4460-b7ff-7141a479cfc0
> of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
> I1006 06:35:13.327997 6162 sched.cpp:1041] Scheduler::statusUpdate took
> 61906ns
> I1006 06:35:13.329732 6161 master.cpp:1874] Attempting to remove 1
> unreachable agents from the registry
> I1006 06:35:13.329900 6164 hierarchical.cpp:1925] No allocations performed
> I1006 06:35:13.329948 6164 hierarchical.cpp:2015] No inverse offers to
> send out!
> I1006 06:35:13.330008 6164 hierarchical.cpp:1468] Performed allocation
> for 0 agents in 174668ns
> I1006 06:35:13.330613 6168 registrar.cpp:495] Applied 1 operations in
> 69625ns; attempting to update the registry
> I1006 06:35:13.331553 6167 registrar.cpp:552] Successfully updated the
> registry in 0ns
> I1006 06:35:13.331908 6163 master.cpp:1917] Garbage collected 1
> unreachable agents from the registry
> I1006 06:35:13.332917 6161 master.cpp:7341] Performing explicit task
> state reconciliation for 1 tasks of framework 97b3b110-e76b-482a-9908-d7741a917162-0000
> (default) at scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:
> 41803
> I1006 06:35:13.333231 6161 master.cpp:7470] Sending explicit
> reconciliation state TASK_UNKNOWN for task 34e1282c-fe08-460f-add3-e76802014fe0
> of framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
> I1006 06:35:13.333880 6168 sched.cpp:1041] Scheduler::statusUpdate took
> 59710ns
> I1006 06:35:13.334275 6142 sched.cpp:2021] Asked to stop the driver
> I1006 06:35:13.334552 6162 sched.cpp:1203] Stopping framework
> 97b3b110-e76b-482a-9908-d7741a917162-0000
> I1006 06:35:13.335064 6165 master.cpp:8343] Processing TEARDOWN call for
> framework 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
> I1006 06:35:13.335160 6165 master.cpp:8355] Removing framework
> 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
> I1006 06:35:13.335285 6165 master.cpp:3264] Deactivating framework
> 97b3b110-e76b-482a-9908-d7741a917162-0000 (default) at
> scheduler-8f0f1db3-5d1c-4036-beff-d9e77ea08df9@172.17.0.2:41803
> I1006 06:35:13.335538 6164 hierarchical.cpp:412] Deactivated framework
> 97b3b110-e76b-482a-9908-d7741a917162-0000
> I1006 06:35:13.336413 6162 hierarchical.cpp:355] Removed framework
> 97b3b110-e76b-482a-9908-d7741a917162-0000
> I1006 06:35:13.337273 6167 master.cpp:1160] Master terminating
> [ OK ] PartitionTest.RegistryGcByAge (215 ms)
> [ RUN ] PartitionTest.RegistryGcRace
> I1006 06:35:13.348095 6142 cluster.cpp:162] Creating default 'local'
> authorizer
> I1006 06:35:13.353413 6165 master.cpp:442] Master bbdfc6c4-fc0f-46c5-9265-d1bba925503b
> (594f4d6e6416) started on 172.17.0.2:41803
> I1006 06:35:13.353485 6165 master.cpp:444] Flags at startup: --acls=""
> --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate_agents="true" --authenticate_frameworks="true"
> --authenticate_http_frameworks="true" --authenticate_http_readonly="true"
> --authenticate_http_readwrite="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/eTNp8c/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="1" --registry_store_timeout="100secs"
> --registry_strict="false" --root_submissions="true" --user_sorter="drf"
> --version="false" --webui_dir="/mesos/mesos-1.4.1/_inst/share/mesos/webui"
> --work_dir="/tmp/eTNp8c/master" --zk_session_timeout="10secs"
> I1006 06:35:13.354131 6165 master.cpp:494] Master only allowing
> authenticated frameworks to register
> I1006 06:35:13.354233 6165 master.cpp:508] Master only allowing
> authenticated agents to register
> I1006 06:35:13.354393 6165 master.cpp:521] Master only allowing
> authenticated HTTP frameworks to register
> I1006 06:35:13.354457 6165 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/eTNp8c/credentials'
> I1006 06:35:13.354990 6165 master.cpp:566] Using default 'crammd5'
> authenticator
> I1006 06:35:13.355381 6165 http.cpp:1045] Creating default 'basic' HTTP
> authenticator for realm 'mesos-master-readonly'
> I1006 06:35:13.355710 6165 http.cpp:1045] Creating default 'basic' HTTP
> authenticator for realm 'mesos-master-readwrite'
> I1006 06:35:13.356060 6165 http.cpp:1045] Creating default 'basic' HTTP
> authenticator for realm 'mesos-master-scheduler'
> I1006 06:35:13.356334 6165 master.cpp:646] Authorization enabled
> I1006 06:35:13.356683 6161 hierarchical.cpp:171] Initialized hierarchical
> allocator process
> I1006 06:35:13.356719 6163 whitelist_watcher.cpp:77] No whitelist given
> I1006 06:35:13.360934 6162 master.cpp:2163] Elected as the leading master!
> I1006 06:35:13.360990 6162 master.cpp:1702] Recovering from registrar
> I1006 06:35:13.361346 6168 registrar.cpp:347] Recovering registrar
> I1006 06:35:13.362251 6168 registrar.cpp:391] Successfully fetched the
> registry (0B) in 0ns
> I1006 06:35:13.362467 6168 registrar.cpp:495] Applied 1 operations in
> 37625ns; attempting to update the registry
> I1006 06:35:13.363365 6163 registrar.cpp:552] Successfully updated the
> registry in 0ns
> I1006 06:35:13.363564 6163 registrar.cpp:424] Successfully recovered
> registrar
> I1006 06:35:13.364114 6165 master.cpp:1801] Recovered 0 agents from the
> registry (129B); allowing 10mins for agents to re-register
> I1006 06:35:13.364162 6164 hierarchical.cpp:209] Skipping recovery of
> hierarchical allocator: nothing to recover
> W1006 06:35:13.372690 6142 process.cpp:3196] Attempted to spawn already
> running process files@172.17.0.2:41803
> I1006 06:35:13.374418 6142 containerizer.cpp:246] Using isolation:
> posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
> W1006 06:35:13.375206 6142 backend.cpp:76] Failed to create 'overlay'
> backend: OverlayBackend requires root privileges
> W1006 06:35:13.375461 6142 backend.cpp:76] Failed to create 'bind'
> backend: BindBackend requires root privileges
> I1006 06:35:13.375509 6142 provisioner.cpp:255] Using default backend
> 'copy'
> I1006 06:35:13.378484 6142 cluster.cpp:448] Creating default 'local'
> authorizer
> I1006 06:35:13.381826 6163 slave.cpp:250] Mesos agent started on (338)@
> 172.17.0.2:41803
> I1006 06:35:13.381863 6163 slave.cpp:251] Flags at startup: --acls=""
> --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/
> PartitionTest_RegistryGcRace_2KNCbH/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/PartitionTest_RegistryGcRace_2KNCbH/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/PartitionTest_RegistryGcRace_2KNCbH/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/PartitionTest_RegistryGcRace_2KNCbH/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/PartitionTest_RegistryGcRace_2KNCbH/http_credentials"
> --http_heartbeat_interval="30secs" --initialize_driver_logging="true"
> --isolation="posix/cpu,posix/mem" --launcher="posix"
> --launcher_dir="/mesos/mesos-1.4.1/_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/PartitionTest_RegistryGcRace_2KNCbH"
> --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/PartitionTest_RegistryGcRace_Syu09r"
> I1006 06:35:13.382609 6163 credentials.hpp:86] Loading credential for
> authentication from '/tmp/PartitionTest_RegistryGcRace_2KNCbH/credential'
> I1006 06:35:13.382876 6163 slave.cpp:283] Agent using credential for:
> test-principal
> I1006 06:35:13.382905 6163 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/PartitionTest_RegistryGcRace_2KNCbH/http_
> credentials'
> I1006 06:35:13.383304 6163 http.cpp:1045] Creating default 'basic' HTTP
> authenticator for realm 'mesos-agent-readonly'
> I1006 06:35:13.383646 6163 http.cpp:1045] Creating default 'basic' HTTP
> authenticator for realm 'mesos-agent-readwrite'
> I1006 06:35:13.385949 6163 slave.cpp:565] 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"}]
> I1006 06:35:13.386394 6163 slave.cpp:573] Agent attributes: [ ]
> I1006 06:35:13.386416 6163 slave.cpp:582] Agent hostname: 594f4d6e6416
> I1006 06:35:13.386745 6165 status_update_manager.cpp:177] Pausing sending
> status updates
> I1006 06:35:13.388905 6164 state.cpp:64] Recovering state from
> '/tmp/PartitionTest_RegistryGcRace_Syu09r/meta'
> I1006 06:35:13.410207 6165 status_update_manager.cpp:203] Recovering
> status update manager
> I1006 06:35:13.410825 6168 containerizer.cpp:609] Recovering containerizer
> I1006 06:35:13.413388 6163 provisioner.cpp:416] Provisioner recovery
> complete
> I1006 06:35:13.413952 6163 slave.cpp:6295] Finished recovery
> I1006 06:35:13.414634 6163 slave.cpp:6477] Querying resource estimator
> for oversubscribable resources
> I1006 06:35:13.415042 6167 status_update_manager.cpp:177] Pausing sending
> status updates
> I1006 06:35:13.415082 6162 slave.cpp:971] New master detected at
> master@172.17.0.2:41803
> I1006 06:35:13.415357 6162 slave.cpp:1006] Detecting new master
> I1006 06:35:13.415689 6162 slave.cpp:6491] Received oversubscribable
> resources {} from the resource estimator
> I1006 06:35:13.416640 6165 slave.cpp:1033] Authenticating with master
> master@172.17.0.2:41803
> I1006 06:35:13.416880 6165 slave.cpp:1044] Using default CRAM-MD5
> authenticatee
> I1006 06:35:13.417382 6163 authenticatee.cpp:121] Creating new client
> SASL connection
> I1006 06:35:13.417912 6163 master.cpp:7832] Authenticating slave(338)@
> 172.17.0.2:41803
> I1006 06:35:13.418272 6161 authenticator.cpp:414] Starting authentication
> session for crammd5-authenticatee(729)@172.17.0.2:41803
> I1006 06:35:13.418856 6166 authenticator.cpp:98] Creating new server SASL
> connection
> I1006 06:35:13.419370 6167 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1006 06:35:13.419415 6167 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1006 06:35:13.419638 6167 authenticator.cpp:204] Received SASL
> authentication start
> I1006 06:35:13.419752 6167 authenticator.cpp:326] Authentication requires
> more steps
> I1006 06:35:13.420002 6162 authenticatee.cpp:259] Received SASL
> authentication step
> I1006 06:35:13.420261 6162 authenticator.cpp:232] Received SASL
> authentication step
> I1006 06:35:13.420302 6162 auxprop.cpp:109] Request to lookup properties
> for user: 'test-principal' realm: '594f4d6e6416' server FQDN:
> '594f4d6e6416' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I1006 06:35:13.420378 6162 auxprop.cpp:181] Looking up auxiliary property
> '*userPassword'
> I1006 06:35:13.420449 6162 auxprop.cpp:181] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I1006 06:35:13.420532 6162 auxprop.cpp:109] Request to lookup properties
> for user: 'test-principal' realm: '594f4d6e6416' server FQDN:
> '594f4d6e6416' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I1006 06:35:13.420553 6162 auxprop.cpp:131] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I1006 06:35:13.420572 6162 auxprop.cpp:131] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I1006 06:35:13.420599 6162 authenticator.cpp:318] Authentication success
> I1006 06:35:13.420960 6163 authenticatee.cpp:299] Authentication success
> I1006 06:35:13.421017 6161 master.cpp:7862] Successfully authenticated
> principal 'test-principal' at slave(338)@172.17.0.2:41803
> I1006 06:35:13.421124 6162 authenticator.cpp:432] Authentication session
> cleanup for crammd5-authenticatee(729)@172.17.0.2:41803
> I1006 06:35:13.421561 6166 slave.cpp:1128] Successfully authenticated
> with master master@172.17.0.2:41803
> I1006 06:35:13.421998 6166 slave.cpp:1607] Will retry registration in
> 15.417366ms if necessary
> I1006 06:35:13.422312 6164 master.cpp:5714] Received register agent
> message from slave(338)@172.17.0.2:41803 (594f4d6e6416)
> I1006 06:35:13.422503 6164 master.cpp:3803] Authorizing agent with
> principal 'test-principal'
> I1006 06:35:13.423179 6168 master.cpp:5774] Authorized registration of
> agent at slave(338)@172.17.0.2:41803 (594f4d6e6416)
> I1006 06:35:13.423399 6168 master.cpp:5867] Registering agent at
> slave(338)@172.17.0.2:41803 (594f4d6e6416) with id
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0
> I1006 06:35:13.424064 6165 registrar.cpp:495] Applied 1 operations in
> 101064ns; attempting to update the registry
> I1006 06:35:13.425149 6165 registrar.cpp:552] Successfully updated the
> registry in 0ns
> I1006 06:35:13.425624 6164 master.cpp:5914] Admitted agent
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 at slave(338)@172.17.0.2:41803
> (594f4d6e6416)
> I1006 06:35:13.426674 6167 slave.cpp:4970] Received ping from
> slave-observer(344)@172.17.0.2:41803
> I1006 06:35:13.427247 6163 hierarchical.cpp:593] Added agent
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 (594f4d6e6416) with cpus:2;
> mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
> I1006 06:35:13.427723 6163 hierarchical.cpp:1925] No allocations performed
> I1006 06:35:13.427798 6163 hierarchical.cpp:1468] Performed allocation
> for 1 agents in 276014ns
> I1006 06:35:13.428185 6162 slave.cpp:1174] Registered with master
> master@172.17.0.2:41803; given agent ID bbdfc6c4-fc0f-46c5-9265-
> d1bba925503b-S0
> I1006 06:35:13.427940 6164 master.cpp:5945] Registered agent
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 at slave(338)@172.17.0.2:41803
> (594f4d6e6416) 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"}]
> I1006 06:35:13.428568 6166 status_update_manager.cpp:184] Resuming
> sending status updates
> I1006 06:35:13.428890 6162 slave.cpp:1194] Checkpointing SlaveInfo to
> '/tmp/PartitionTest_RegistryGcRace_Syu09r/meta/slaves/bbdfc6c4-fc0f-46c5-
> 9265-d1bba925503b-S0/slave.info'
> I1006 06:35:13.429491 6162 slave.cpp:1243] Forwarding total
> oversubscribed resources {}
> I1006 06:35:13.429890 6165 master.cpp:6683] Received update of agent
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 at slave(338)@172.17.0.2:41803
> (594f4d6e6416) with total oversubscribed resources {}
> I1006 06:35:13.430629 6167 hierarchical.cpp:660] Agent
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-S0 (594f4d6e6416) updated with total
> resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
> W1006 06:35:13.431653 6142 process.cpp:3196] Attempted to spawn already
> running process version@172.17.0.2:41803
> I1006 06:35:13.433135 6142 sched.cpp:232] Version: 1.4.1
> I1006 06:35:13.434239 6162 sched.cpp:336] New master detected at
> master@172.17.0.2:41803
> I1006 06:35:13.434525 6162 sched.cpp:407] Authenticating with master
> master@172.17.0.2:41803
> I1006 06:35:13.434551 6162 sched.cpp:414] Using default CRAM-MD5
> authenticatee
> I1006 06:35:13.435107 6165 authenticatee.cpp:121] Creating new client
> SASL connection
> I1006 06:35:13.435746 6161 master.cpp:7832] Authenticating
> scheduler-925f744e-179e-44ae-a358-fcc313f2b43e@172.17.0.2:41803
> I1006 06:35:13.435986 6168 authenticator.cpp:414] Starting authentication
> session for crammd5-authenticatee(730)@172.17.0.2:41803
> I1006 06:35:13.436537 6163 authenticator.cpp:98] Creating new server SASL
> connection
> I1006 06:35:13.437063 6163 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> I1006 06:35:13.437111 6163 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> I1006 06:35:13.437273 6163 authenticator.cpp:204] Received SASL
> authentication start
> I1006 06:35:13.437345 6163 authenticator.cpp:326] Authentication requires
> more steps
> I1006 06:35:13.437598 6162 authenticatee.cpp:259] Received SASL
> authentication step
> I1006 06:35:13.437973 6167 authenticator.cpp:232] Received SASL
> authentication step
> I1006 06:35:13.438081 6167 auxprop.cpp:109] Request to lookup properties
> for user: 'test-principal' realm: '594f4d6e6416' server FQDN:
> '594f4d6e6416' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> I1006 06:35:13.438139 6167 auxprop.cpp:181] Looking up auxiliary property
> '*userPassword'
> I1006 06:35:13.438216 6167 auxprop.cpp:181] Looking up auxiliary property
> '*cmusaslsecretCRAM-MD5'
> I1006 06:35:13.438253 6167 auxprop.cpp:109] Request to lookup properties
> for user: 'test-principal' realm: '594f4d6e6416' server FQDN:
> '594f4d6e6416' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> I1006 06:35:13.438295 6167 auxprop.cpp:131] Skipping auxiliary property
> '*userPassword' since SASL_AUXPROP_AUTHZID == true
> I1006 06:35:13.438340 6167 auxprop.cpp:131] Skipping auxiliary property
> '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> I1006 06:35:13.438370 6167 authenticator.cpp:318] Authentication success
> I1006 06:35:13.438664 6165 authenticatee.cpp:299] Authentication success
> I1006 06:35:13.438710 6161 master.cpp:7862] Successfully authenticated
> principal 'test-principal' at scheduler-925f744e-179e-44ae-
> a358-fcc313f2b43e@172.17.0.2:41803
> I1006 06:35:13.438908 6168 authenticator.cpp:432] Authentication session
> cleanup for crammd5-authenticatee(730)@172.17.0.2:41803
> *** Aborted at 1507271713 (unix time) try "date -d @1507271713" if you are
> using GNU date ***
> I1006 06:35:13.439643 6166 sched.cpp:513] Successfully authenticated with
> master master@172.17.0.2:41803
> I1006 06:35:13.439745 6166 sched.cpp:836] Sending SUBSCRIBE call to
> master@172.17.0.2:41803
> I1006 06:35:13.440186 6166 sched.cpp:869] Will retry registration in
> 427.907251ms if necessary
> I1006 06:35:13.440557 6161 master.cpp:2894] Received SUBSCRIBE call for
> framework 'default' at scheduler-925f744e-179e-44ae-
> a358-fcc313f2b43e@172.17.0.2:41803
> I1006 06:35:13.440874 6161 master.cpp:2228] Authorizing framework
> principal 'test-principal' to receive offers for roles '{ * }'
> I1006 06:35:13.441699 6162 master.cpp:2974] Subscribing framework default
> with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT,
> PARTITION_AWARE ]
> I1006 06:35:13.442826 6162 sched.cpp:759] Framework registered with
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-0000
> I1006 06:35:13.442858 6164 hierarchical.cpp:303] Added framework
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-0000
> I1006 06:35:13.442903 6162 sched.cpp:773] Scheduler::registered took 28us
> I1006 06:35:13.444886 6164 hierarchical.cpp:2015] No inverse offers to
> send out!
> I1006 06:35:13.444964 6164 hierarchical.cpp:1468] Performed allocation
> for 1 agents in 1.889777ms
> I1006 06:35:13.446193 6166 master.cpp:7662] Sending 1 offers to framework
> bbdfc6c4-fc0f-46c5-9265-d1bba925503b-0000 (default) at
> scheduler-925f744e-179e-44ae-a358-fcc313f2b43e@172.17.0.2:41803
> I1006 06:35:13.447209 6161 sched.cpp:933] Scheduler::resourceOffers took
> 35920ns
> PC: @ 0x7fb3c214373a process::EventQueue::Consumer::empty()
> *** SIGSEGV (@0x8) received by PID 6142 (TID 0x7fb3afeae700) from PID 8;
> stack trace: ***
> @ 0x7fb3acd64455 (unknown)
> @ 0x7fb3acd69a71 (unknown)
> @ 0x7fb3acd5d2b8 (unknown)
> @ 0x7fb3bafec5e0 (unknown)
> @ 0x7fb3c214373a process::EventQueue::Consumer::empty()
> @ 0x7fb3c212f282 process::ProcessManager::resume()
> @ 0x7fb3c212b8ea _ZZN7process14ProcessManager12i
> nit_threadsEvENKUlvE_clEv
> @ 0x7fb3c213df94 _ZNSt12_Bind_simpleIFZN7process14ProcessMan
> ager12init_threadsEvEUlvE_vEE9_M_invokeIIEEEvSt12_Index_tupleIIXspT_EEE
> @ 0x7fb3c213ded8 _ZNSt12_Bind_simpleIFZN7process14ProcessMan
> ager12init_threadsEvEUlvE_vEEclEv
> @ 0x7fb3c213de62 _ZNSt6thread5_ImplISt12_Bind_
> simpleIFZN7process14ProcessManager12init_threadsEvEUlvE_vEEE6_M_runEv
> @ 0x7fb3ba97e2b0 (unknown)
> @ 0x7fb3bafe4e25 start_thread
> @ 0x7fb3ba0e634d __clone
> make[4]: *** [check-local] Segmentation fault (core dumped)
> make[4]: Leaving directory `/mesos/mesos-1.4.1/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-1.4.1/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-1.4.1/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-1.4.1/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1507267039-17894
> Untagged: mesos-1507267039-17894:latest
> Deleted: sha256:187e02475b4c9590934b6641c399c2
> 08cf8ec91eaf3312f6c0c1c51358ab09a4
> Deleted: sha256:67758dfc3e28b48fd132a52136e7a2
> 6d74e5ccd53d4ddbcbd49de30970d8d857
> Deleted: sha256:2dc08d31b0ac981ca77ad1a7fef1ac
> 0aaec9fdb4ade150d9a87229a457bf52ff
> Deleted: sha256:3c66f42e636317cfa35771da07e69f
> 996c5a5760906a275dfba4a312ef1de230
> Deleted: sha256:ea50cd159916e28b59a9243666b313
> 789189bc17b95f98b9c0748f29d5430a5b
> Deleted: sha256:a33560dccd9384fe0862faf8b73ca8
> 1c8c5411929ee08189ff29167dd591c8c7
> Deleted: sha256:a8873720be596216d9348ac008d51e
> 4ccc48868f3784d3842bfc5f4f84007176
> Deleted: sha256:4113536ff3594981355ec9fb3cca10
> a64dd9447ea871a641f70fda9bb98cb5bf
> Deleted: sha256:03166f0dafbd1a588d8e508d1deaaa
> 2fc54b8140cbd84a9cb24e025c8a8f6866
> Deleted: sha256:96a1a7556f94541899f0e44a5857fb
> 5cf2e25149600fd97a27b9e016e3ea58dc
> Deleted: sha256:a45fb07c85f14c6acd8fd0abdc40f3
> f7eaf71a0decd42164a2ee3adb3ac8dae9
> Deleted: sha256:28e5443e6c5aa41749aafdefaca81e
> a9789d5de3adf6c2b223bd83a55789e875
> Deleted: sha256:cad14753f8b4c27bd92d96bad8dbf5
> abc6811eaf557355ad3f30f2bae4acbd0c
> Deleted: sha256:3aa56910e01910d5e480ce37afd6cf
> e57bdbec487e8533a72ec35003fb115f7c
> Deleted: sha256:a8a3a85ada91bea4ec4713ff0fab12
> acd723511176234f9cc8700827dfedd8fd
> Deleted: sha256:71788490a2e0c9057da8b7472092c8
> cbf5a4609a0e9f11a0381ef8f00e73af03
> Deleted: sha256:d6d309e10dbe3e0e491e936f66b04c
> c53ddc8810b14aee9ff3ffa6a60d499aa4
> Deleted: sha256:2752b067ae0c9816531e450cc5f87f
> 9b5b8a04432256be4877155802cb4d663b
> Deleted: sha256:443b57e555a12f4a5e571d9876a3ff
> f63ddff05e9ca7ae61a3f5343c29e89298
> Deleted: sha256:f50e955c991f8eec979aea50b3c232
> bf7f7db165db19b8416d44c63429978b59
> Deleted: sha256:f287e8cfd96648948ad0a708b96ff7
> 7a23ff08d805787cbf95148808eb76549c
> Deleted: sha256:99b823b7901c7b14b72bbc1e359d6b
> ccd4c6643b4e70773eaf14ac2aecea4389
> Deleted: sha256:a5a237b277bf1dffd9cff5859dc313
> b4a765d4abee3f66f8a10de0d009b24196
> Deleted: sha256:9f5de8d69ae821582565a05ba20b23
> bd44e56cbf9c44d79372191af90e1622ac
> Deleted: sha256:5194125ccf307cec77e7a070cf20ca
> 9fc93c7c769894d80efc8f4b4bf9bcff29
> Build step 'Execute shell' marked build as failure
>