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/12/20 04:19:43 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4632
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4632/display/redirect?page=changes>
Changes:
[yujie.jay] Moved files related to volume profiles into `resource_provider/storage`.
[yujie.jay] Made volume secret isolator not standalone container aware.
------------------------------------------
[...truncated 13.26 MB...]
3: I1220 04:19:26.766213 15996 master.cpp:456] Master 725cd1b3-e3f5-4fc2-9956-0087cc8434f8 (d440a027eed8) started on 172.17.0.6:38776
3: I1220 04:19:26.766253 15996 master.cpp:458] 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/7Rai9x/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/7Rai9x/master" --zk_session_timeout="10secs"
3: I1220 04:19:26.766623 15996 master.cpp:507] Master only allowing authenticated frameworks to register
3: I1220 04:19:26.766638 15996 master.cpp:513] Master only allowing authenticated agents to register
3: I1220 04:19:26.766647 15996 master.cpp:519] Master only allowing authenticated HTTP frameworks to register
3: I1220 04:19:26.766657 15996 credentials.hpp:37] Loading credentials for authentication from '/tmp/7Rai9x/credentials'
3: I1220 04:19:26.766964 15996 master.cpp:563] Using default 'crammd5' authenticator
3: I1220 04:19:26.767143 15996 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1220 04:19:26.767319 15996 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1220 04:19:26.767488 15996 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1220 04:19:26.767642 15996 master.cpp:642] Authorization enabled
3: I1220 04:19:26.767884 16001 whitelist_watcher.cpp:77] No whitelist given
3: I1220 04:19:26.767904 16008 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1220 04:19:26.771116 15996 master.cpp:2209] Elected as the leading master!
3: I1220 04:19:26.771142 15996 master.cpp:1689] Recovering from registrar
3: I1220 04:19:26.771327 16000 registrar.cpp:347] Recovering registrar
3: I1220 04:19:26.772078 16000 registrar.cpp:391] Successfully fetched the registry (0B) in 713984ns
3: I1220 04:19:26.772223 16000 registrar.cpp:495] Applied 1 operations in 51391ns; attempting to update the registry
3: I1220 04:19:26.772855 16000 registrar.cpp:552] Successfully updated the registry in 566016ns
3: I1220 04:19:26.773007 16000 registrar.cpp:424] Successfully recovered registrar
3: I1220 04:19:26.773669 16009 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register
3: I1220 04:19:26.773717 15996 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover
3: W1220 04:19:26.779069 15994 process.cpp:2756] Attempted to spawn already running process files@172.17.0.6:38776
3: I1220 04:19:26.780174 15994 containerizer.cpp:304] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1220 04:19:26.780771 15994 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1220 04:19:26.780897 15994 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1220 04:19:26.780954 15994 provisioner.cpp:299] Using default backend 'copy'
3: I1220 04:19:26.783133 15994 cluster.cpp:460] Creating default 'local' authorizer
3: I1220 04:19:26.785742 16001 slave.cpp:262] Mesos agent started on (301)@172.17.0.6:38776
3: I1220 04:19:26.785784 16001 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterAllocatorTest_1_SingleFramework_NSu7eT/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --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/MasterAllocatorTest_1_SingleFramework_NSu7eT/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/MasterAllocatorTest_1_SingleFramework_NSu7eT/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/MasterAllocatorTest_1_SingleFramework_NSu7eT/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/MasterAllocatorTest_1_SingleFramework_NSu7eT/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" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;mem:1024;disk:0" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterAllocatorTest_1_SingleFramework_NSu7eT" --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/MasterAllocatorTest_1_SingleFramework_psgcHd" --zk_session_timeout="10secs"
3: I1220 04:19:26.786303 16001 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterAllocatorTest_1_SingleFramework_NSu7eT/credential'
3: W1220 04:19:26.786334 15994 process.cpp:2756] Attempted to spawn already running process version@172.17.0.6:38776
3: I1220 04:19:26.786438 16001 slave.cpp:295] Agent using credential for: test-principal
3: I1220 04:19:26.786455 16001 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterAllocatorTest_1_SingleFramework_NSu7eT/http_credentials'
3: I1220 04:19:26.786651 16001 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1220 04:19:26.787228 15994 sched.cpp:232] Version: 1.5.0
3: I1220 04:19:26.787824 16001 slave.cpp:612] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1220 04:19:26.788051 16001 slave.cpp:620] Agent attributes: [ ]
3: I1220 04:19:26.788064 16001 slave.cpp:629] Agent hostname: d440a027eed8
3: I1220 04:19:26.788069 16003 sched.cpp:336] New master detected at master@172.17.0.6:38776
3: I1220 04:19:26.788182 16003 sched.cpp:396] Authenticating with master master@172.17.0.6:38776
3: I1220 04:19:26.788204 16003 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1220 04:19:26.788322 15995 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1220 04:19:26.788513 16004 authenticatee.cpp:121] Creating new client SASL connection
3: I1220 04:19:26.788938 15998 master.cpp:8932] Authenticating scheduler-9035af5e-28bb-45c0-a17a-a81a21508584@172.17.0.6:38776
3: I1220 04:19:26.789119 16008 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(589)@172.17.0.6:38776
3: I1220 04:19:26.789480 16005 authenticator.cpp:98] Creating new server SASL connection
3: I1220 04:19:26.789782 16007 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1220 04:19:26.789816 16007 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1220 04:19:26.789958 16007 authenticator.cpp:204] Received SASL authentication start
3: I1220 04:19:26.790036 16007 authenticator.cpp:326] Authentication requires more steps
3: I1220 04:19:26.790069 15999 state.cpp:66] Recovering state from '/tmp/MasterAllocatorTest_1_SingleFramework_psgcHd/meta'
3: I1220 04:19:26.790235 16002 authenticatee.cpp:259] Received SASL authentication step
3: I1220 04:19:26.790395 15995 authenticator.cpp:232] Received SASL authentication step
3: I1220 04:19:26.790436 15995 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd440a027eed8' server FQDN: 'd440a027eed8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1220 04:19:26.790444 16003 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1220 04:19:26.790463 15995 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1220 04:19:26.790509 15995 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1220 04:19:26.790534 15995 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd440a027eed8' server FQDN: 'd440a027eed8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1220 04:19:26.790544 15995 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1220 04:19:26.790551 15995 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1220 04:19:26.790567 15995 authenticator.cpp:318] Authentication success
3: I1220 04:19:26.790688 15998 authenticatee.cpp:299] Authentication success
3: I1220 04:19:26.790755 16000 containerizer.cpp:674] Recovering containerizer
3: I1220 04:19:26.790798 16008 master.cpp:8962] Successfully authenticated principal 'test-principal' at scheduler-9035af5e-28bb-45c0-a17a-a81a21508584@172.17.0.6:38776
3: I1220 04:19:26.790825 15997 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(589)@172.17.0.6:38776
3: I1220 04:19:26.791061 15995 sched.cpp:502] Successfully authenticated with master master@172.17.0.6:38776
3: I1220 04:19:26.791083 15995 sched.cpp:824] Sending SUBSCRIBE call to master@172.17.0.6:38776
3: I1220 04:19:26.791209 15995 sched.cpp:857] Will retry registration in 233.202815ms if necessary
3: I1220 04:19:26.791463 16007 master.cpp:2957] Received SUBSCRIBE call for framework 'default' at scheduler-9035af5e-28bb-45c0-a17a-a81a21508584@172.17.0.6:38776
3: I1220 04:19:26.791493 16007 master.cpp:2274] Authorizing framework principal 'test-principal' to receive offers for roles '{ * }'
3: I1220 04:19:26.792105 16008 master.cpp:3037] Subscribing framework default with checkpointing disabled and capabilities [ MULTI_ROLE, RESERVATION_REFINEMENT ]
3: I1220 04:19:26.792297 16008 master.cpp:9153] Adding framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000 (default) at scheduler-9035af5e-28bb-45c0-a17a-a81a21508584@172.17.0.6:38776 with roles { } suppressed
3: I1220 04:19:26.792824 15996 provisioner.cpp:495] Provisioner recovery complete
3: I1220 04:19:26.792837 16010 sched.cpp:751] Framework registered with 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000
3: I1220 04:19:26.792884 16010 sched.cpp:765] Scheduler::registered took 28776ns
3: I1220 04:19:26.793012 15997 hierarchical.cpp:297] Added framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000
3: I1220 04:19:26.793251 15995 slave.cpp:6777] Finished recovery
3: I1220 04:19:26.793292 15997 hierarchical.cpp:1517] Performed allocation for 0 agents in 97160ns
3: I1220 04:19:26.794231 16009 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1220 04:19:26.794265 16007 slave.cpp:1055] New master detected at master@172.17.0.6:38776
3: I1220 04:19:26.794329 16007 slave.cpp:1110] Detecting new master
3: I1220 04:19:26.804642 16006 slave.cpp:1137] Authenticating with master master@172.17.0.6:38776
3: I1220 04:19:26.804709 16006 slave.cpp:1146] Using default CRAM-MD5 authenticatee
3: I1220 04:19:26.804972 16008 authenticatee.cpp:121] Creating new client SASL connection
3: I1220 04:19:26.805264 16008 master.cpp:8932] Authenticating slave(301)@172.17.0.6:38776
3: I1220 04:19:26.805418 16002 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(590)@172.17.0.6:38776
3: I1220 04:19:26.805690 16010 authenticator.cpp:98] Creating new server SASL connection
3: I1220 04:19:26.805958 16004 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1220 04:19:26.805991 16004 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1220 04:19:26.806108 15999 authenticator.cpp:204] Received SASL authentication start
3: I1220 04:19:26.806157 15999 authenticator.cpp:326] Authentication requires more steps
3: I1220 04:19:26.806314 15999 authenticatee.cpp:259] Received SASL authentication step
3: I1220 04:19:26.806430 15999 authenticator.cpp:232] Received SASL authentication step
3: I1220 04:19:26.806458 15999 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd440a027eed8' server FQDN: 'd440a027eed8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1220 04:19:26.806468 15999 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1220 04:19:26.806496 15999 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1220 04:19:26.806514 15999 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd440a027eed8' server FQDN: 'd440a027eed8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1220 04:19:26.806524 15999 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1220 04:19:26.806530 15999 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1220 04:19:26.806545 15999 authenticator.cpp:318] Authentication success
3: I1220 04:19:26.806635 15997 authenticatee.cpp:299] Authentication success
3: I1220 04:19:26.806696 15995 master.cpp:8962] Successfully authenticated principal 'test-principal' at slave(301)@172.17.0.6:38776
3: I1220 04:19:26.806790 16001 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(590)@172.17.0.6:38776
3: I1220 04:19:26.807067 16000 slave.cpp:1229] Successfully authenticated with master master@172.17.0.6:38776
3: I1220 04:19:26.807307 16000 slave.cpp:1708] Will retry registration in 13.341202ms if necessary
3: I1220 04:19:26.807485 16006 master.cpp:6077] Received register agent message from slave(301)@172.17.0.6:38776 (d440a027eed8)
3: I1220 04:19:26.807531 16006 master.cpp:3866] Authorizing agent with principal 'test-principal'
3: I1220 04:19:26.807971 15998 master.cpp:6147] Authorized registration of agent at slave(301)@172.17.0.6:38776 (d440a027eed8)
3: I1220 04:19:26.808063 15998 master.cpp:6240] Registering agent at slave(301)@172.17.0.6:38776 (d440a027eed8) with id 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0
3: I1220 04:19:26.808631 16002 registrar.cpp:495] Applied 1 operations in 118577ns; attempting to update the registry
3: I1220 04:19:26.809342 16002 registrar.cpp:552] Successfully updated the registry in 641024ns
3: I1220 04:19:26.809551 15997 master.cpp:6288] Admitted agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 at slave(301)@172.17.0.6:38776 (d440a027eed8)
3: I1220 04:19:26.810312 15997 master.cpp:6337] Registered agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 at slave(301)@172.17.0.6:38776 (d440a027eed8) with cpus:2; mem:1024; ports:[31000-32000]
3: I1220 04:19:26.810482 15995 slave.cpp:1275] Registered with master master@172.17.0.6:38776; given agent ID 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0
3: I1220 04:19:26.810681 16001 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1220 04:19:26.810937 15995 slave.cpp:1295] Checkpointing SlaveInfo to '/tmp/MasterAllocatorTest_1_SingleFramework_psgcHd/meta/slaves/725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0/slave.info'
3: I1220 04:19:26.810964 15999 hierarchical.cpp:574] Added agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 (d440a027eed8) with cpus:2; mem:1024; ports:[31000-32000] (allocated: {})
3: I1220 04:19:26.811552 15995 slave.cpp:1352] Forwarding agent update {"offer_operations":{},"resource_version_uuid":{"value":"3W35fXEKT5+bGSmvjllbMg=="},"slave_id":{"value":"725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0"},"update_oversubscribed_resources":true}
3: I1220 04:19:26.811895 15995 master.cpp:7266] Received update of agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 at slave(301)@172.17.0.6:38776 (d440a027eed8) with total oversubscribed resources {}
3: I1220 04:19:26.812268 15995 master.cpp:7362] Ignoring update on agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 at slave(301)@172.17.0.6:38776 (d440a027eed8) as it reports no changes
3: I1220 04:19:26.812882 15999 hierarchical.cpp:1517] Performed allocation for 1 agents in 1.715917ms
3: I1220 04:19:26.813410 16006 master.cpp:8762] Sending 1 offers to framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000 (default) at scheduler-9035af5e-28bb-45c0-a17a-a81a21508584@172.17.0.6:38776
3: I1220 04:19:26.813915 16006 sched.cpp:921] Scheduler::resourceOffers took 65864ns
3: I1220 04:19:26.814332 15994 sched.cpp:2009] Asked to stop the driver
3: I1220 04:19:26.814517 15996 sched.cpp:1191] Stopping framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000
3: I1220 04:19:26.814800 16009 master.cpp:9442] Processing TEARDOWN call for framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000 (default) at scheduler-9035af5e-28bb-45c0-a17a-a81a21508584@172.17.0.6:38776
3: I1220 04:19:26.814834 16009 master.cpp:9454] Removing framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000 (default) at scheduler-9035af5e-28bb-45c0-a17a-a81a21508584@172.17.0.6:38776
3: I1220 04:19:26.814849 16009 master.cpp:3327] Deactivating framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000 (default) at scheduler-9035af5e-28bb-45c0-a17a-a81a21508584@172.17.0.6:38776
3: I1220 04:19:26.815050 16001 hierarchical.cpp:405] Deactivated framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000
3: I1220 04:19:26.815423 16008 slave.cpp:931] Agent terminating
3: I1220 04:19:26.815450 16009 master.cpp:10678] Removing offer 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-O0
3: I1220 04:19:26.816262 16009 master.cpp:1305] Agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 at slave(301)@172.17.0.6:38776 (d440a027eed8) disconnected
3: I1220 04:19:26.816283 16009 master.cpp:3364] Disconnecting agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 at slave(301)@172.17.0.6:38776 (d440a027eed8)
3: I1220 04:19:26.816331 16009 master.cpp:3383] Deactivating agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 at slave(301)@172.17.0.6:38776 (d440a027eed8)
3: I1220 04:19:26.816382 16001 hierarchical.cpp:1192] Recovered cpus(allocated: *):2; mem(allocated: *):1024; ports(allocated: *):[31000-32000] (total: cpus:2; mem:1024; ports:[31000-32000], allocated: {}) on agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 from framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000
3: I1220 04:19:26.816764 16001 hierarchical.cpp:344] Removed framework 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-0000
3: I1220 04:19:26.816841 16001 hierarchical.cpp:766] Agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0 deactivated
3: I1220 04:19:26.824622 15994 master.cpp:1147] Master terminating
3: I1220 04:19:26.825346 16004 hierarchical.cpp:609] Removed agent 725cd1b3-e3f5-4fc2-9956-0087cc8434f8-S0
3: [ OK ] MasterAllocatorTest/1.SingleFramework (69 ms)
3: [ RUN ] MasterAllocatorTest/1.ResourcesUnused
3: I1220 04:19:26.834065 15994 cluster.cpp:172] Creating default 'local' authorizer
3: I1220 04:19:26.837249 16006 master.cpp:456] Master 43793dc9-fb6a-4745-8e74-f5792cc7deb6 (d440a027eed8) started on 172.17.0.6:38776
3: I1220 04:19:26.837314 16006 master.cpp:458] 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/9sXeaU/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/9sXeaU/master" --zk_session_timeout="10secs"
3: I1220 04:19:26.837683 16006 master.cpp:507] Master only allowing authenticated frameworks to register
3: I1220 04:19:26.837697 16006 master.cpp:513] Master only allowing authenticated agents to register
3: I1220 04:19:26.837707 16006 master.cpp:519] Master only allowing authenticated HTTP frameworks to register
3: I1220 04:19:26.837718 16006 credentials.hpp:37] Loading credentials for authentication from '/tmp/9sXeaU/credentials'
3: I1220 04:19:26.838045 16006 master.cpp:563] Using default 'crammd5' authenticator
3: I1220 04:19:26.838241 16006 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1220 04:19:26.838456 16006 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1220 04:19:26.838649 16006 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1220 04:19:26.838827 16006 master.cpp:642] Authorization enabled
3: I1220 04:19:26.839119 16007 whitelist_watcher.cpp:77] No whitelist given
3: I1220 04:19:26.839167 15996 hierarchical.cpp:175] Initialized hierarchical allocator process
3: I1220 04:19:26.842319 16005 master.cpp:2209] Elected as the leading master!
3: I1220 04:19:26.842337 16005 master.cpp:1689] Recovering from registrar
3: I1220 04:19:26.842483 15995 registrar.cpp:347] Recovering registrar
3: I1220 04:19:26.843171 15995 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1220 04:19:26.843276 15995 registrar.cpp:495] Applied 1 operations in 37041ns; attempting to update the registry
3: I1220 04:19:26.843816 15995 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1220 04:19:26.843926 15995 registrar.cpp:424] Successfully recovered registrar
3: I1220 04:19:26.844313 16007 master.cpp:1802] Recovered 0 agents from the registry (135B); allowing 10mins for agents to re-register
3: I1220 04:19:26.844365 15996 hierarchical.cpp:213] Skipping recovery of hierarchical allocator: nothing to recover
3: W1220 04:19:26.848738 15994 process.cpp:2756] Attempted to spawn already running process files@172.17.0.6:38776
3: I1220 04:19:26.848999 15994 cluster.cpp:460] Creating default 'local' authorizer
3: I1220 04:19:26.851146 16004 slave.cpp:262] Mesos agent started on (302)@172.17.0.6:38776
3: I1220 04:19:26.851171 16004 slave.cpp:263] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/MasterAllocatorTest_1_ResourcesUnused_HYWXCf/store/appc" --authenticate_http_readonly="true" --authenticate_http_readwrite="false" --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/MasterAllocatorTest_1_ResourcesUnused_HYWXCf/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/MasterAllocatorTest_1_ResourcesUnused_HYWXCf/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/MasterAllocatorTest_1_ResourcesUnused_HYWXCf/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/MasterAllocatorTest_1_ResourcesUnused_HYWXCf/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" --reconfiguration_policy="equal" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resources="cpus:2;mem:1024" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/MasterAllocatorTest_1_ResourcesUnused_HYWXCf" --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/MasterAllocatorTest_1_ResourcesUnused_nwVATz" --zk_session_timeout="10secs"
3: I1220 04:19:26.851570 16004 credentials.hpp:86] Loading credential for authentication from '/tmp/MasterAllocatorTest_1_ResourcesUnused_HYWXCf/credential'
3: I1220 04:19:26.851758 16004 slave.cpp:295] Agent using credential for: test-principal
3: I1220 04:19:26.851778 16004 credentials.hpp:37] Loading credentials for authentication from '/tmp/MasterAllocatorTest_1_ResourcesUnused_HYWXCf/http_credentials'
3: I1220 04:19:26.852015 16004 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1220 04:19:26.853258 16009 hierarchical.cpp:1517] Performed allocation for 0 agents in 59881ns
3: I1220 04:19:26.853216 16004 slave.cpp:612] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":3701220.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1220 04:19:26.853456 16004 slave.cpp:620] Agent attributes: [ ]
3: I1220 04:19:26.853469 16004 slave.cpp:629] Agent hostname: d440a027eed8
3: I1220 04:19:26.853657 16002 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1220 04:19:26.855315 15999 state.cpp:66] Recovering state from '/tmp/MasterAllocatorTest_1_ResourcesUnused_nwVATz/meta'
3: I1220 04:19:26.855645 16001 task_status_update_manager.cpp:207] Recovering task status update manager
3: I1220 04:19:26.856009 16007 slave.cpp:6777] Finished recovery
3: I1220 04:19:26.856811 15998 slave.cpp:1055] New master detected at master@172.17.0.6:38776
3: I1220 04:19:26.856815 16009 task_status_update_manager.cpp:181] Pausing sending task status updates
3: I1220 04:19:26.856887 15998 slave.cpp:1110] Detecting new master
3: I1220 04:19:26.858263 16010 slave.cpp:1137] Authenticating with master master@172.17.0.6:38776
3: I1220 04:19:26.858363 16010 slave.cpp:1146] Using default CRAM-MD5 authenticatee
3: I1220 04:19:26.858587 15997 authenticatee.cpp:121] Creating new client SASL connection
3: I1220 04:19:26.858907 16005 master.cpp:8932] Authenticating slave(302)@172.17.0.6:38776
3: I1220 04:19:26.859028 16004 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(591)@172.17.0.6:38776
3: I1220 04:19:26.859269 16008 authenticator.cpp:98] Creating new server SASL connection
3: I1220 04:19:26.859602 16000 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1220 04:19:26.859637 16000 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1220 04:19:26.859761 16000 authenticator.cpp:204] Received SASL authentication start
3: I1220 04:19:26.859822 16000 authenticator.cpp:326] Authentication requires more steps
3: I1220 04:19:26.859946 16001 authenticatee.cpp:259] Received SASL authentication step
3: I1220 04:19:26.860061 15995 authenticator.cpp:232] Received SASL authentication step
3: I1220 04:19:26.860090 15995 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd440a027eed8' server FQDN: 'd440a027eed8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1220 04:19:26.860107 15995 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1220 04:19:26.860146 15995 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1220 04:19:26.860172 15995 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd440a027eed8' server FQDN: 'd440a027eed8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1220 04:19:26.860183 15995 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1220 04:19:26.860193 15995 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1220 04:19:26.860209 15995 authenticator.cpp:318] Authentication success
3: I1220 04:19:26.860304 16003 authenticatee.cpp:299] Authentication success
3: I1220 04:19:26.860430 16007 master.cpp:8962] Successfully authenticated principal 'test-principal' at slave(302)@172.17.0.6:38776
3: I1220 04:19:26.860502 16003 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(591)@172.17.0.6:38776
3: I1220 04:19:26.860662 16006 slave.cpp:1229] Successfully authenticated with master master@172.17.0.6:38776
3: I1220 04:19:26.860870 16006 slave.cpp:1708] Will retry registration in 8.14243ms if necessary
3: I1220 04:19:26.861001 15997 master.cpp:6077] Received register agent message from slave(302)@172.17.0.6:38776 (d440a027eed8)
3: I1220 04:19:26.861039 15997 master.cpp:3866] Authorizing agent with principal 'test-principal'
3: I1220 04:19:26.861407 16005 master.cpp:6147] Authorized registration of agent at slave(302)@172.17.0.6:38776 (d440a027eed8)
3: I1220 04:19:26.861475 16005 master.cpp:6240] Registering agent at slave(302)@172.17.0.6:38776 (d440a027eed8) with id 43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0
3: I1220 04:19:26.861835 16008 registrar.cpp:495] Applied 1 operations in 86536ns; attempting to update the registry
3: I1220 04:19:26.862407 16001 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1220 04:19:26.862576 16007 master.cpp:6288] Admitted agent 43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0 at slave(302)@172.17.0.6:38776 (d440a027eed8)
3: I1220 04:19:26.863212 16009 slave.cpp:1275] Registered with master master@172.17.0.6:38776; given agent ID 43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0
3: I1220 04:19:26.863157 16007 master.cpp:6337] Registered agent 43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0 at slave(302)@172.17.0.6:38776 (d440a027eed8) with cpus:2; mem:1024; disk:3701220; ports:[31000-32000]
3: I1220 04:19:26.863312 16003 task_status_update_manager.cpp:188] Resuming sending task status updates
3: I1220 04:19:26.863503 16009 slave.cpp:1295] Checkpointing SlaveInfo to '/tmp/MasterAllocatorTest_1_ResourcesUnused_nwVATz/meta/slaves/43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0/slave.info'
3: I1220 04:19:26.863510 16002 hierarchical.cpp:574] Added agent 43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0 (d440a027eed8) with cpus:2; mem:1024; disk:3701220; ports:[31000-32000] (allocated: {})
3: I1220 04:19:26.863744 16002 hierarchical.cpp:1517] Performed allocation for 1 agents in 121488ns
3: I1220 04:19:26.863867 16009 slave.cpp:1352] Forwarding agent update {"offer_operations":{},"resource_version_uuid":{"value":"Ined6vRZR\/OSpWDymSmp+Q=="},"slave_id":{"value":"43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0"},"update_oversubscribed_resources":true}
3: I1220 04:19:26.864082 15997 master.cpp:7266] Received update of agent 43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0 at slave(302)@172.17.0.6:38776 (d440a027eed8) with total oversubscribed resources {}
3: I1220 04:19:26.864295 15997 master.cpp:7362] Ignoring update on agent 43793dc9-fb6a-4745-8e74-f5792cc7deb6-S0 at slave(302)@172.17.0.6:38776 (d440a027eed8) as it reports no changes
3: W1220 04:19:26.864729 15994 process.cpp:2756] Attempted to spawn already running process version@172.17.0.6:38776
3: I1220 04:19:26.865661 15994 sched.cpp:232] Version: 1.5.0
3: I1220 04:19:26.866212 15995 sched.cpp:336] New master detected at master@172.17.0.6:38776
3: I1220 04:19:26.866302 15995 sched.cpp:396] Authenticating with master master@172.17.0.6:38776
3: I1220 04:19:26.866317 15995 sched.cpp:403] Using default CRAM-MD5 authenticatee
3: I1220 04:19:26.866545 15999 authenticatee.cpp:121] Creating new client SASL connection
3: I1220 04:19:26.866753 15998 master.cpp:8932] Authenticating scheduler-e682aedd-b8c7-424d-9b49-cea5d8afb242@172.17.0.6:38776
3: I1220 04:19:26.866854 16010 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(592)@172.17.0.6:38776
3: I1220 04:19:26.867046 16007 authenticator.cpp:98] Creating new server SASL connection
3: I1220 04:19:26.867218 16003 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1220 04:19:26.867242 16003 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1220 04:19:26.867339 16002 authenticator.cpp:204] Received SASL authentication start
3: I1220 04:19:26.867386 16002 authenticator.cpp:326] Authentication requires more steps
3: I1220 04:19:26.867485 16002 authenticatee.cpp:259] Received SASL authentication step
3: I1220 04:19:26.867588 16002 authenticator.cpp:232] Received SASL authentication step
3: I1220 04:19:26.867614 16002 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd440a027eed8' server FQDN: 'd440a027eed8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1220 04:19:26.867626 16002 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1220 04:19:26.867656 16002 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1220 04:19:26.867684 16002 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: 'd440a027eed8' server FQDN: 'd440a027eed8' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1220 04:19:26.867697 16002 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1220 04:19:26.867707 16002 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1220 04:19:26.867722 16002 authenticator.cpp:318] Authentication success
Process leaked file descriptors. See https://jenkins.io/redirect/troubleshooting/process-leaked-file-descriptors for more information
Build step 'Execute shell' marked build as failure
Not sending mail to unregistered user yujie.jay@gmail.com
Jenkins build is back to normal : Mesos-Buildbot » cmake,clang,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4633
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=clang,CONFIGURATION=--verbose%20--disable-libtool-wrappers,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)/4633/display/redirect>