You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/08/12 04:27:03 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4100
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4100/display/redirect?page=changes>
Changes:
[songzihao1990] Created staging dir only when needed.
[songzihao1990] Added MESOS-6950 to 1.3.2 CHANGELOG.
[songzihao1990] Added MESOS-6950 to 1.2.3 CHANGELOG.
[songzihao1990] Added MESOS-6950 to 1.1.3 CHANGELOG.
------------------------------------------
[...truncated 5.71 MB...]
I0812 00:15:35.753417 2775 hierarchical.cpp:660] Agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 (f79f9e0299d1) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0812 00:15:35.753479 2779 gc.cpp:59] Scheduling '/tmp/MasterSlaveReconciliationTest_SlaveReregisterFrameworks_Nn0MAW/slaves/05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0/frameworks/05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000/executors/default/runs/0b5de45d-5a84-469a-874f-5337210d80fb' for gc 6.9999912801837days in the future
I0812 00:15:35.753613 2774 slave.cpp:5525] Cleaning up framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000
I0812 00:15:35.753689 2780 status_update_manager.cpp:285] Closing status update streams for framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000
I0812 00:15:35.753718 2780 status_update_manager.cpp:531] Cleaning up status update stream for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000
I0812 00:15:35.753720 2779 gc.cpp:59] Scheduling '/tmp/MasterSlaveReconciliationTest_SlaveReregisterFrameworks_Nn0MAW/slaves/05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0/frameworks/05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000/executors/default' for gc 6.9999912783437days in the future
I0812 00:15:35.753842 2774 slave.cpp:3394] Ignoring new checkpointed resources identical to the current version: {}
I0812 00:15:35.753847 2779 gc.cpp:59] Scheduling '/tmp/MasterSlaveReconciliationTest_SlaveReregisterFrameworks_Nn0MAW/slaves/05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0/frameworks/05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000' for gc 6.99999127648days in the future
I0812 00:15:35.753934 2774 slave.cpp:4797] Forwarding the update TASK_RUNNING (UUID: 3a3069e1-483e-49fe-b587-692f015ee994) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 to master@172.17.0.6:46307
I0812 00:15:35.754204 2768 master.cpp:6846] Status update TASK_RUNNING (UUID: 3a3069e1-483e-49fe-b587-692f015ee994) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 from agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1)
W0812 00:15:35.754243 2768 master.cpp:6861] Received status update TASK_RUNNING (UUID: 3a3069e1-483e-49fe-b587-692f015ee994) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 from agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1) for an unknown framework
W0812 00:15:35.754284 2768 master.cpp:6876] Could not lookup task for status update TASK_RUNNING (UUID: 3a3069e1-483e-49fe-b587-692f015ee994) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 from agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1)
I0812 00:15:35.754484 2771 status_update_manager.cpp:323] Received status update TASK_FAILED (UUID: 174107b4-20b8-4ce6-939f-3824eb605f49) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000
I0812 00:15:35.754518 2771 status_update_manager.cpp:500] Creating StatusUpdate stream for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000
I0812 00:15:35.754824 2771 status_update_manager.cpp:377] Forwarding update TASK_FAILED (UUID: 174107b4-20b8-4ce6-939f-3824eb605f49) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 to the agent
I0812 00:15:35.754971 2773 slave.cpp:4797] Forwarding the update TASK_FAILED (UUID: 174107b4-20b8-4ce6-939f-3824eb605f49) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 to master@172.17.0.6:46307
I0812 00:15:35.755128 2773 slave.cpp:4691] Status update manager successfully handled status update TASK_FAILED (UUID: 174107b4-20b8-4ce6-939f-3824eb605f49) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000
I0812 00:15:35.755177 2773 slave.cpp:843] Agent terminating
I0812 00:15:35.755352 2762 master.cpp:6846] Status update TASK_FAILED (UUID: 174107b4-20b8-4ce6-939f-3824eb605f49) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 from agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1)
W0812 00:15:35.755403 2762 master.cpp:6861] Received status update TASK_FAILED (UUID: 174107b4-20b8-4ce6-939f-3824eb605f49) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 from agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1) for an unknown framework
W0812 00:15:35.755441 2762 master.cpp:6876] Could not lookup task for status update TASK_FAILED (UUID: 174107b4-20b8-4ce6-939f-3824eb605f49) for task 1 of framework 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-0000 from agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1)
I0812 00:15:35.755573 2762 master.cpp:1318] Agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1) disconnected
I0812 00:15:35.755596 2762 master.cpp:3301] Disconnecting agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1)
I0812 00:15:35.755666 2762 master.cpp:3320] Deactivating agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 at slave(102)@172.17.0.6:46307 (f79f9e0299d1)
I0812 00:15:35.755930 2762 hierarchical.cpp:690] Agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0 deactivated
I0812 00:15:35.757612 2775 master.cpp:1160] Master terminating
I0812 00:15:35.758059 2779 hierarchical.cpp:626] Removed agent 05aae7a0-1a25-43a8-bf6b-eaa33fdeb25c-S0
[ OK ] MasterSlaveReconciliationTest.SlaveReregisterFrameworks (71 ms)
[----------] 7 tests from MasterSlaveReconciliationTest (626 ms total)
[----------] 16 tests from PartitionTest
[ RUN ] PartitionTest.PartitionedSlave
I0812 00:15:35.766577 2756 cluster.cpp:162] Creating default 'local' authorizer
I0812 00:15:35.769404 2759 master.cpp:442] Master e6d6a218-f70d-425f-bfeb-d5e389c4f9e8 (f79f9e0299d1) started on 172.17.0.6:46307
I0812 00:15:35.769424 2759 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/vU2eJE/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/vU2eJE/master" --zk_session_timeout="10secs"
I0812 00:15:35.769642 2759 master.cpp:494] Master only allowing authenticated frameworks to register
I0812 00:15:35.769652 2759 master.cpp:508] Master only allowing authenticated agents to register
I0812 00:15:35.769661 2759 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0812 00:15:35.769671 2759 credentials.hpp:37] Loading credentials for authentication from '/tmp/vU2eJE/credentials'
I0812 00:15:35.769876 2759 master.cpp:566] Using default 'crammd5' authenticator
I0812 00:15:35.769994 2759 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0812 00:15:35.770120 2759 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0812 00:15:35.770292 2759 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0812 00:15:35.770426 2759 master.cpp:646] Authorization enabled
I0812 00:15:35.770567 2772 hierarchical.cpp:171] Initialized hierarchical allocator process
I0812 00:15:35.770668 2770 whitelist_watcher.cpp:77] No whitelist given
I0812 00:15:35.773182 2777 master.cpp:2163] Elected as the leading master!
I0812 00:15:35.773201 2777 master.cpp:1702] Recovering from registrar
I0812 00:15:35.773396 2763 registrar.cpp:347] Recovering registrar
I0812 00:15:35.773855 2763 registrar.cpp:391] Successfully fetched the registry (0B) in 430848ns
I0812 00:15:35.773941 2763 registrar.cpp:495] Applied 1 operations in 22836ns; attempting to update the registry
I0812 00:15:35.774415 2763 registrar.cpp:552] Successfully updated the registry in 433152ns
I0812 00:15:35.774516 2763 registrar.cpp:424] Successfully recovered registrar
I0812 00:15:35.774893 2779 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
I0812 00:15:35.775100 2780 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0812 00:15:35.779544 2756 process.cpp:3228] Attempting to spawn already spawned process files@172.17.0.6:46307
I0812 00:15:35.780791 2756 containerizer.cpp:246] Using isolation: posix/cpu,posix/mem,filesystem/posix,network/cni,environment_secret
W0812 00:15:35.781435 2756 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
W0812 00:15:35.781540 2756 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
I0812 00:15:35.781570 2756 provisioner.cpp:255] Using default backend 'copy'
I0812 00:15:35.783599 2756 cluster.cpp:448] Creating default 'local' authorizer
I0812 00:15:35.785511 2765 slave.cpp:250] Mesos agent started on (103)@172.17.0.6:46307
I0812 00:15:35.785975 2756 process.cpp:3228] Attempting to spawn already spawned process version@172.17.0.6:46307
I0812 00:15:35.785531 2765 slave.cpp:251] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/PartitionTest_PartitionedSlave_4Uil2v/store/appc" --authenticate_http_executors="true" --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_PartitionedSlave_4Uil2v/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/PartitionTest_PartitionedSlave_4Uil2v/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_secret_key="/tmp/PartitionTest_PartitionedSlave_4Uil2v/executor_secret_key" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/PartitionTest_PartitionedSlave_4Uil2v/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_PartitionedSlave_4Uil2v/http_credentials" --http_heartbeat_interval="30secs" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher="posix" --launcher_dir="/mesos/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_PartitionedSlave_4Uil2v" --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_PartitionedSlave_AILMn5"
I0812 00:15:35.786065 2765 credentials.hpp:86] Loading credential for authentication from '/tmp/PartitionTest_PartitionedSlave_4Uil2v/credential'
I0812 00:15:35.786306 2765 slave.cpp:283] Agent using credential for: test-principal
I0812 00:15:35.786331 2765 credentials.hpp:37] Loading credentials for authentication from '/tmp/PartitionTest_PartitionedSlave_4Uil2v/http_credentials'
I0812 00:15:35.786644 2765 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-executor'
I0812 00:15:35.786850 2765 http.cpp:1047] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-executor'
I0812 00:15:35.786962 2756 sched.cpp:232] Version: 1.4.0
I0812 00:15:35.787089 2765 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
I0812 00:15:35.787215 2765 http.cpp:1047] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readonly'
I0812 00:15:35.787417 2766 sched.cpp:336] New master detected at master@172.17.0.6:46307
I0812 00:15:35.787485 2765 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
I0812 00:15:35.787500 2766 sched.cpp:407] Authenticating with master master@172.17.0.6:46307
I0812 00:15:35.787516 2766 sched.cpp:414] Using default CRAM-MD5 authenticatee
I0812 00:15:35.787582 2765 http.cpp:1047] Creating default 'jwt' HTTP authenticator for realm 'mesos-agent-readwrite'
I0812 00:15:35.787751 2779 authenticatee.cpp:121] Creating new client SASL connection
I0812 00:15:35.788069 2761 master.cpp:7837] Authenticating scheduler-80189e17-026a-47f3-9424-04d6b75d144e@172.17.0.6:46307
I0812 00:15:35.788178 2770 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(208)@172.17.0.6:46307
I0812 00:15:35.788436 2761 authenticator.cpp:98] Creating new server SASL connection
I0812 00:15:35.788633 2757 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0812 00:15:35.788655 2757 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0812 00:15:35.788858 2761 authenticator.cpp:204] Received SASL authentication start
I0812 00:15:35.788909 2761 authenticator.cpp:326] Authentication requires more steps
I0812 00:15:35.789002 2774 authenticatee.cpp:259] Received SASL authentication step
I0812 00:15:35.789103 2775 authenticator.cpp:232] Received SASL authentication step
I0812 00:15:35.789126 2775 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f79f9e0299d1' server FQDN: 'f79f9e0299d1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0812 00:15:35.789136 2775 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0812 00:15:35.789160 2775 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0812 00:15:35.789175 2775 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f79f9e0299d1' server FQDN: 'f79f9e0299d1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0812 00:15:35.789182 2775 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0812 00:15:35.789188 2775 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0812 00:15:35.789201 2775 authenticator.cpp:318] Authentication success
I0812 00:15:35.789304 2771 authenticatee.cpp:299] Authentication success
I0812 00:15:35.789319 2776 master.cpp:7867] Successfully authenticated principal 'test-principal' at scheduler-80189e17-026a-47f3-9424-04d6b75d144e@172.17.0.6:46307
I0812 00:15:35.789362 2758 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(208)@172.17.0.6:46307
I0812 00:15:35.789609 2775 sched.cpp:513] Successfully authenticated with master master@172.17.0.6:46307
I0812 00:15:35.789624 2775 sched.cpp:836] Sending SUBSCRIBE call to master@172.17.0.6:46307
I0812 00:15:35.789839 2775 sched.cpp:869] Will retry registration in 1.554858715secs if necessary
I0812 00:15:35.789837 2765 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"}]
I0812 00:15:35.790074 2775 master.cpp:2894] Received SUBSCRIBE call for framework 'default' at scheduler-80189e17-026a-47f3-9424-04d6b75d144e@172.17.0.6:46307
I0812 00:15:35.790081 2765 slave.cpp:573] Agent attributes: [ ]
I0812 00:15:35.790138 2765 slave.cpp:582] Agent hostname: f79f9e0299d1
I0812 00:15:35.790161 2775 master.cpp:2228] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
I0812 00:15:35.790326 2772 status_update_manager.cpp:177] Pausing sending status updates
I0812 00:15:35.790657 2780 master.cpp:2974] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
I0812 00:15:35.791075 2780 sched.cpp:759] Framework registered with e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000
I0812 00:15:35.791108 2780 sched.cpp:773] Scheduler::registered took 15188ns
I0812 00:15:35.791312 2764 hierarchical.cpp:303] Added framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000
I0812 00:15:35.791478 2764 hierarchical.cpp:1925] No allocations performed
I0812 00:15:35.791505 2764 hierarchical.cpp:2015] No inverse offers to send out!
I0812 00:15:35.791532 2764 hierarchical.cpp:1468] Performed allocation for 0 agents in 84030ns
I0812 00:15:35.792348 2774 state.cpp:64] Recovering state from '/tmp/PartitionTest_PartitionedSlave_AILMn5/meta'
I0812 00:15:35.792600 2762 status_update_manager.cpp:203] Recovering status update manager
I0812 00:15:35.792748 2758 containerizer.cpp:598] Recovering containerizer
I0812 00:15:35.793902 2771 provisioner.cpp:416] Provisioner recovery complete
I0812 00:15:35.794224 2765 slave.cpp:6210] Finished recovery
I0812 00:15:35.794596 2765 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0812 00:15:35.794792 2765 slave.cpp:971] New master detected at master@172.17.0.6:46307
I0812 00:15:35.794855 2778 status_update_manager.cpp:177] Pausing sending status updates
I0812 00:15:35.794857 2765 slave.cpp:1006] Detecting new master
I0812 00:15:35.794968 2765 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0812 00:15:35.801064 2763 slave.cpp:1033] Authenticating with master master@172.17.0.6:46307
I0812 00:15:35.801118 2763 slave.cpp:1044] Using default CRAM-MD5 authenticatee
I0812 00:15:35.801340 2775 authenticatee.cpp:121] Creating new client SASL connection
I0812 00:15:35.801627 2772 master.cpp:7837] Authenticating slave(103)@172.17.0.6:46307
I0812 00:15:35.801723 2779 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(209)@172.17.0.6:46307
I0812 00:15:35.801945 2775 authenticator.cpp:98] Creating new server SASL connection
I0812 00:15:35.802120 2758 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
I0812 00:15:35.802142 2758 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
I0812 00:15:35.802317 2775 authenticator.cpp:204] Received SASL authentication start
I0812 00:15:35.802373 2775 authenticator.cpp:326] Authentication requires more steps
I0812 00:15:35.802475 2775 authenticatee.cpp:259] Received SASL authentication step
I0812 00:15:35.802594 2766 authenticator.cpp:232] Received SASL authentication step
I0812 00:15:35.802628 2766 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f79f9e0299d1' server FQDN: 'f79f9e0299d1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0812 00:15:35.802642 2766 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
I0812 00:15:35.802680 2766 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0812 00:15:35.802702 2766 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'f79f9e0299d1' server FQDN: 'f79f9e0299d1' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0812 00:15:35.802713 2766 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0812 00:15:35.802722 2766 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0812 00:15:35.802741 2766 authenticator.cpp:318] Authentication success
I0812 00:15:35.802918 2757 authenticatee.cpp:299] Authentication success
I0812 00:15:35.803026 2757 master.cpp:7867] Successfully authenticated principal 'test-principal' at slave(103)@172.17.0.6:46307
I0812 00:15:35.803064 2764 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(209)@172.17.0.6:46307
I0812 00:15:35.803261 2767 slave.cpp:1128] Successfully authenticated with master master@172.17.0.6:46307
I0812 00:15:35.803443 2767 slave.cpp:1572] Will retry registration in 16.889717ms if necessary
I0812 00:15:35.803576 2780 master.cpp:5712] Received register agent message from slave(103)@172.17.0.6:46307 (f79f9e0299d1)
I0812 00:15:35.803680 2780 master.cpp:3803] Authorizing agent with principal 'test-principal'
I0812 00:15:35.804014 2771 master.cpp:5772] Authorized registration of agent at slave(103)@172.17.0.6:46307 (f79f9e0299d1)
I0812 00:15:35.804096 2771 master.cpp:5865] Registering agent at slave(103)@172.17.0.6:46307 (f79f9e0299d1) with id e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0
I0812 00:15:35.804417 2773 registrar.cpp:495] Applied 1 operations in 39560ns; attempting to update the registry
I0812 00:15:35.804870 2773 registrar.cpp:552] Successfully updated the registry in 411136ns
I0812 00:15:35.805022 2763 master.cpp:5912] Admitted agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0 at slave(103)@172.17.0.6:46307 (f79f9e0299d1)
I0812 00:15:35.805496 2772 slave.cpp:4887] Received ping from slave-observer(96)@172.17.0.6:46307
I0812 00:15:35.805452 2763 master.cpp:5943] Registered agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0 at slave(103)@172.17.0.6:46307 (f79f9e0299d1) 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"}]
I0812 00:15:35.805655 2772 slave.cpp:1174] Registered with master master@172.17.0.6:46307; given agent ID e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0
I0812 00:15:35.805929 2760 hierarchical.cpp:593] Added agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0 (f79f9e0299d1) with cpus:2; mem:1024; disk:1024; ports:[31000-32000] (allocated: {})
I0812 00:15:35.806020 2772 slave.cpp:1194] Checkpointing SlaveInfo to '/tmp/PartitionTest_PartitionedSlave_AILMn5/meta/slaves/e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0/slave.info'
I0812 00:15:35.806247 2758 status_update_manager.cpp:184] Resuming sending status updates
I0812 00:15:35.806403 2772 slave.cpp:1232] Forwarding total oversubscribed resources {}
I0812 00:15:35.806566 2772 master.cpp:6688] Received update of agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0 at slave(103)@172.17.0.6:46307 (f79f9e0299d1) with total oversubscribed resources {}
I0812 00:15:35.806972 2760 hierarchical.cpp:2015] No inverse offers to send out!
I0812 00:15:35.807009 2760 hierarchical.cpp:1468] Performed allocation for 1 agents in 947110ns
I0812 00:15:35.807168 2760 hierarchical.cpp:660] Agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0 (f79f9e0299d1) updated with total resources cpus:2; mem:1024; disk:1024; ports:[31000-32000]
I0812 00:15:35.807533 2757 master.cpp:7667] Sending 1 offers to framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000 (default) at scheduler-80189e17-026a-47f3-9424-04d6b75d144e@172.17.0.6:46307
I0812 00:15:35.807989 2757 sched.cpp:933] Scheduler::resourceOffers took 62977ns
I0812 00:15:35.810210 2771 hierarchical.cpp:1925] No allocations performed
I0812 00:15:35.810245 2771 hierarchical.cpp:2015] No inverse offers to send out!
I0812 00:15:35.810276 2771 hierarchical.cpp:1468] Performed allocation for 1 agents in 156471ns
I0812 00:15:35.811650 2779 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0812 00:15:35.811810 2779 slave.cpp:4887] Received ping from slave-observer(96)@172.17.0.6:46307
I0812 00:15:35.811925 2779 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0812 00:15:35.812404 2764 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0812 00:15:35.812537 2764 slave.cpp:4887] Received ping from slave-observer(96)@172.17.0.6:46307
I0812 00:15:35.812625 2768 hierarchical.cpp:1925] No allocations performed
I0812 00:15:35.812646 2764 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0812 00:15:35.812680 2768 hierarchical.cpp:2015] No inverse offers to send out!
I0812 00:15:35.812718 2768 hierarchical.cpp:1468] Performed allocation for 1 agents in 225839ns
I0812 00:15:35.813269 2767 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0812 00:15:35.813344 2757 hierarchical.cpp:1925] No allocations performed
I0812 00:15:35.813386 2757 hierarchical.cpp:2015] No inverse offers to send out!
I0812 00:15:35.813436 2757 hierarchical.cpp:1468] Performed allocation for 1 agents in 194897ns
I0812 00:15:35.813386 2767 slave.cpp:4887] Received ping from slave-observer(96)@172.17.0.6:46307
I0812 00:15:35.813642 2767 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0812 00:15:35.814159 2771 hierarchical.cpp:1925] No allocations performed
I0812 00:15:35.814213 2771 hierarchical.cpp:2015] No inverse offers to send out!
I0812 00:15:35.814256 2771 hierarchical.cpp:1468] Performed allocation for 1 agents in 182027ns
I0812 00:15:35.814520 2771 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0812 00:15:35.814630 2771 slave.cpp:5835] Current disk usage 70.44%. Max allowed age: 1.369168351339421days
I0812 00:15:35.814741 2771 slave.cpp:4887] Received ping from slave-observer(96)@172.17.0.6:46307
I0812 00:15:35.814834 2771 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0812 00:15:35.815282 2763 hierarchical.cpp:1925] No allocations performed
I0812 00:15:35.815322 2763 hierarchical.cpp:2015] No inverse offers to send out!
I0812 00:15:35.815366 2763 hierarchical.cpp:1468] Performed allocation for 1 agents in 173322ns
I0812 00:15:35.815457 2772 slave.cpp:6392] Querying resource estimator for oversubscribable resources
I0812 00:15:35.815518 2763 master.cpp:7067] Marking agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0 at slave(103)@172.17.0.6:46307 (f79f9e0299d1) unreachable: health check timed out
I0812 00:15:35.815559 2772 slave.cpp:4887] Received ping from slave-observer(96)@172.17.0.6:46307
I0812 00:15:35.815765 2759 slave.cpp:6406] Received oversubscribable resources {} from the resource estimator
I0812 00:15:35.815881 2772 registrar.cpp:495] Applied 1 operations in 67262ns; attempting to update the registry
I0812 00:15:35.816378 2772 registrar.cpp:552] Successfully updated the registry in 0ns
I0812 00:15:35.816500 2762 master.cpp:7115] Marked agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0 at slave(103)@172.17.0.6:46307 (f79f9e0299d1) unreachable: health check timed out
I0812 00:15:35.816987 2762 master.cpp:9164] Removing offer e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-O0
I0812 00:15:35.817067 2778 sched.cpp:959] Rescinded offer e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-O0
I0812 00:15:35.817075 2779 hierarchical.cpp:626] Removed agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0
I0812 00:15:35.817136 2778 sched.cpp:970] Scheduler::offerRescinded took 21613ns
I0812 00:15:35.817250 2762 master.cpp:2080] Notifying framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000 (default) at scheduler-80189e17-026a-47f3-9424-04d6b75d144e@172.17.0.6:46307 of lost agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0 (f79f9e0299d1)
I0812 00:15:35.817389 2773 sched.cpp:1103] Lost agent e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-S0
I0812 00:15:35.817427 2773 sched.cpp:1114] Scheduler::slaveLost took 19448ns
I0812 00:15:35.817982 2774 slave.cpp:843] Agent terminating
I0812 00:15:35.824903 2763 process.cpp:3933] Handling HTTP event for process 'metrics' with path: '/metrics/snapshot'
I0812 00:15:35.844972 2756 sched.cpp:2021] Asked to stop the driver
I0812 00:15:35.845090 2771 sched.cpp:1203] Stopping framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000
I0812 00:15:35.845355 2777 master.cpp:8348] Processing TEARDOWN call for framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000 (default) at scheduler-80189e17-026a-47f3-9424-04d6b75d144e@172.17.0.6:46307
I0812 00:15:35.845381 2777 master.cpp:8360] Removing framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000 (default) at scheduler-80189e17-026a-47f3-9424-04d6b75d144e@172.17.0.6:46307
I0812 00:15:35.845402 2777 master.cpp:3264] Deactivating framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000 (default) at scheduler-80189e17-026a-47f3-9424-04d6b75d144e@172.17.0.6:46307
I0812 00:15:35.845510 2771 hierarchical.cpp:412] Deactivated framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000
I0812 00:15:35.846225 2777 hierarchical.cpp:355] Removed framework e6d6a218-f70d-425f-bfeb-d5e389c4f9e8-0000
I0812 00:15:35.846441 2756 master.cpp:1160] Master terminating
[ OK ] PartitionTest.PartitionedSlave (89 ms)
[ RUN ] PartitionTest.ReregisterSlavePartitionAware
I0812 00:15:35.857220 2756 cluster.cpp:162] Creating default 'local' authorizer
I0812 00:15:35.860685 2757 master.cpp:442] Master 77b67252-5acb-4be8-b131-db69a1ded866 (f79f9e0299d1) started on 172.17.0.6:46307
I0812 00:15:35.860703 2757 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/jRNjiO/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/jRNjiO/master" --zk_session_timeout="10secs"
I0812 00:15:35.860997 2757 master.cpp:494] Master only allowing authenticated frameworks to register
I0812 00:15:35.861008 2757 master.cpp:508] Master only allowing authenticated agents to register
I0812 00:15:35.861018 2757 master.cpp:521] Master only allowing authenticated HTTP frameworks to register
I0812 00:15:35.861027 2757 credentials.hpp:37] Loading credentials for authentication from '/tmp/jRNjiO/credentials'
I0812 00:15:35.861290 2757 master.cpp:566] Using default 'crammd5' authenticator
I0812 00:15:35.861439 2757 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
I0812 00:15:35.861600 2757 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
I0812 00:15:35.861738 2757 http.cpp:1026] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
I0812 00:15:35.861867 2757 master.cpp:646] Authorization enabled
I0812 00:15:35.862124 2773 hierarchical.cpp:171] Initialized hierarchical allocator process
I0812 00:15:35.862270 2772 whitelist_watcher.cpp:77] No whitelist given
I0812 00:15:35.865214 2770 master.cpp:2163] Elected as the leading master!
I0812 00:15:35.865332 2770 master.cpp:1702] Recovering from registrar
I0812 00:15:35.865496 2779 registrar.cpp:347] Recovering registrar
I0812 00:15:35.865965 2779 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
I0812 00:15:35.866240 2779 registrar.cpp:495] Applied 1 operations in 24255ns; attempting to update the registry
I0812 00:15:35.866804 2779 registrar.cpp:552] Successfully updated the registry in 0ns
I0812 00:15:35.866926 2779 registrar.cpp:424] Successfully recovered registrar
I0812 00:15:35.867611 2767 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
I0812 00:15:35.867703 2770 master.cpp:1801] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
Build timed out (after 300 minutes). Marking the build as failed.
Build was aborted
++ docker rmi mesos-1502494176-3388
Error response from daemon: conflict: unable to remove repository reference "mesos-1502494176-3388" (must force) - container f79f9e0299d1 is using its referenced image ba0ec5495865
Not sending mail to unregistered user songzihao1990@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2) #4101
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)/4101/display/redirect?page=changes>