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/24 04:13:46 UTC
Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4359
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,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)/4359/display/redirect?page=changes>
Changes:
[bmahler] Fixed the flaky MasterTest.IgnoreOldAgentReregistration.
------------------------------------------
[...truncated 29.86 MB...]
3: creator_principals {
3: type: NONE
3: }
3: }
3: " --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate_agents="true" --authenticate_frameworks="false" --authenticate_http_frameworks="true" --authenticate_http_readonly="true" --authenticate_http_readwrite="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/E8oHWm/credentials" --filter_gpu_resources="true" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_unreachable_tasks_per_framework="1000" --port="5050" --quiet="false" --recovery_agent_removal_limit="100%" --registry="in_memory" --registry_fetch_timeout="1mins" --registry_gc_interval="15mins" --registry_max_agent_age="2weeks" --registry_max_agent_count="102400" --registry_store_timeout="100secs" --registry_strict="false" --roles="default-role" --root_submissions="true" --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/E8oHWm/master" --zk_session_timeout="10secs"
3: I1024 04:11:08.909395 15898 master.cpp:498] Master allowing unauthenticated frameworks to register
3: I1024 04:11:08.909404 15898 master.cpp:502] Master only allowing authenticated agents to register
3: I1024 04:11:08.909407 15898 master.cpp:508] Master only allowing authenticated HTTP frameworks to register
3: I1024 04:11:08.909413 15898 credentials.hpp:37] Loading credentials for authentication from '/tmp/E8oHWm/credentials'
3: I1024 04:11:08.909760 15898 master.cpp:552] Using default 'crammd5' authenticator
3: I1024 04:11:08.909956 15898 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readonly'
3: I1024 04:11:08.910174 15898 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-readwrite'
3: I1024 04:11:08.910356 15898 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-master-scheduler'
3: I1024 04:11:08.910513 15898 master.cpp:631] Authorization enabled
3: W1024 04:11:08.910527 15898 master.cpp:694] The '--roles' flag is deprecated. This flag will be removed in the future. See the Mesos 0.27 upgrade notes for more information
3: I1024 04:11:08.910727 15887 hierarchical.cpp:171] Initialized hierarchical allocator process
3: I1024 04:11:08.910782 15888 whitelist_watcher.cpp:77] No whitelist given
3: I1024 04:11:08.914042 15906 master.cpp:2198] Elected as the leading master!
3: I1024 04:11:08.914069 15906 master.cpp:1687] Recovering from registrar
3: I1024 04:11:08.914283 15892 registrar.cpp:347] Recovering registrar
3: I1024 04:11:08.915030 15892 registrar.cpp:391] Successfully fetched the registry (0B) in 0ns
3: I1024 04:11:08.915171 15892 registrar.cpp:495] Applied 1 operations in 25118ns; attempting to update the registry
3: I1024 04:11:08.915908 15892 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1024 04:11:08.916079 15892 registrar.cpp:424] Successfully recovered registrar
3: I1024 04:11:08.916563 15896 hierarchical.cpp:209] Skipping recovery of hierarchical allocator: nothing to recover
3: I1024 04:11:08.916564 15894 master.cpp:1791] Recovered 0 agents from the registry (129B); allowing 10mins for agents to re-register
3: W1024 04:11:08.923128 15886 process.cpp:3193] Attempted to spawn already running process files@172.17.0.4:38770
3: I1024 04:11:08.924201 15886 containerizer.cpp:301] Using isolation { environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
3: W1024 04:11:08.924837 15886 backend.cpp:76] Failed to create 'aufs' backend: AufsBackend requires root privileges
3: W1024 04:11:08.924991 15886 backend.cpp:76] Failed to create 'bind' backend: BindBackend requires root privileges
3: I1024 04:11:08.925025 15886 provisioner.cpp:255] Using default backend 'copy'
3: I1024 04:11:08.927402 15886 cluster.cpp:448] Creating default 'local' authorizer
3: I1024 04:11:08.930121 15894 slave.cpp:254] Mesos agent started on (822)@172.17.0.4:38770
3: I1024 04:11:08.930153 15894 slave.cpp:255] Flags at startup: --acls="" --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/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="[{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB" --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/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_z5ZOOb" --zk_session_timeout="10secs"
3: I1024 04:11:08.930595 15894 credentials.hpp:86] Loading credential for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/credential'
3: I1024 04:11:08.930748 15894 slave.cpp:287] Agent using credential for: test-principal
3: I1024 04:11:08.930765 15894 credentials.hpp:37] Loading credentials for authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/http_credentials'
3: W1024 04:11:08.930830 15886 process.cpp:3193] Attempted to spawn already running process version@172.17.0.4:38770
3: I1024 04:11:08.930989 15894 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readonly'
3: I1024 04:11:08.931143 15894 http.cpp:1045] Creating default 'basic' HTTP authenticator for realm 'mesos-agent-readwrite'
3: I1024 04:11:08.932003 15886 sched.cpp:232] Version: 1.5.0
3: I1024 04:11:08.932505 15897 sched.cpp:336] New master detected at master@172.17.0.4:38770
3: I1024 04:11:08.932641 15897 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1024 04:11:08.932657 15897 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.4:38770
3: I1024 04:11:08.932763 15897 sched.cpp:853] Will retry registration in 576.150212ms if necessary
3: I1024 04:11:08.932945 15905 master.cpp:2929] Received SUBSCRIBE call for framework 'default' at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.933017 15905 master.cpp:2263] Authorizing framework principal 'test-principal' to receive offers for roles '{ default-role }'
3: I1024 04:11:08.933459 15909 master.cpp:3009] Subscribing framework default with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1024 04:11:08.934033 15890 sched.cpp:747] Framework registered with 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:08.934139 15890 sched.cpp:761] Scheduler::registered took 76607ns
3: I1024 04:11:08.934159 15891 hierarchical.cpp:303] Added framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:08.934320 15891 hierarchical.cpp:1488] Performed allocation for 0 agents in 53111ns
3: I1024 04:11:08.934747 15894 slave.cpp:585] Agent resources: [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1024 04:11:08.935180 15894 slave.cpp:593] Agent attributes: [ ]
3: I1024 04:11:08.935190 15894 slave.cpp:602] Agent hostname: 4d972d0ce409
3: I1024 04:11:08.935341 15896 status_update_manager.cpp:177] Pausing sending status updates
3: I1024 04:11:08.936926 15903 state.cpp:64] Recovering state from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_z5ZOOb/meta'
3: I1024 04:11:08.937274 15893 status_update_manager.cpp:203] Recovering status update manager
3: I1024 04:11:08.937506 15893 containerizer.cpp:662] Recovering containerizer
3: I1024 04:11:08.939023 15910 provisioner.cpp:416] Provisioner recovery complete
3: I1024 04:11:08.939424 15910 slave.cpp:6310] Finished recovery
3: I1024 04:11:08.940152 15889 slave.cpp:993] New master detected at master@172.17.0.4:38770
3: I1024 04:11:08.940222 15889 slave.cpp:1028] Detecting new master
3: I1024 04:11:08.940222 15894 status_update_manager.cpp:177] Pausing sending status updates
3: I1024 04:11:08.941874 15903 slave.cpp:1055] Authenticating with master master@172.17.0.4:38770
3: I1024 04:11:08.941953 15903 slave.cpp:1064] Using default CRAM-MD5 authenticatee
3: I1024 04:11:08.942239 15898 authenticatee.cpp:121] Creating new client SASL connection
3: I1024 04:11:08.942376 15897 hierarchical.cpp:1488] Performed allocation for 0 agents in 70718ns
3: I1024 04:11:08.942559 15906 master.cpp:7947] Authenticating slave(822)@172.17.0.4:38770
3: I1024 04:11:08.942734 15905 authenticator.cpp:414] Starting authentication session for crammd5-authenticatee(1483)@172.17.0.4:38770
3: I1024 04:11:08.943078 15902 authenticator.cpp:98] Creating new server SASL connection
3: I1024 04:11:08.943351 15907 authenticatee.cpp:213] Received SASL authentication mechanisms: CRAM-MD5
3: I1024 04:11:08.943379 15907 authenticatee.cpp:239] Attempting to authenticate with mechanism 'CRAM-MD5'
3: I1024 04:11:08.943528 15893 authenticator.cpp:204] Received SASL authentication start
3: I1024 04:11:08.943603 15893 authenticator.cpp:326] Authentication requires more steps
3: I1024 04:11:08.943722 15893 authenticatee.cpp:259] Received SASL authentication step
3: I1024 04:11:08.943871 15890 authenticator.cpp:232] Received SASL authentication step
3: I1024 04:11:08.943902 15890 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4d972d0ce409' server FQDN: '4d972d0ce409' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
3: I1024 04:11:08.943912 15890 auxprop.cpp:181] Looking up auxiliary property '*userPassword'
3: I1024 04:11:08.943944 15890 auxprop.cpp:181] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
3: I1024 04:11:08.943964 15890 auxprop.cpp:109] Request to lookup properties for user: 'test-principal' realm: '4d972d0ce409' server FQDN: '4d972d0ce409' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
3: I1024 04:11:08.943971 15890 auxprop.cpp:131] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
3: I1024 04:11:08.943979 15890 auxprop.cpp:131] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
3: I1024 04:11:08.943991 15890 authenticator.cpp:318] Authentication success
3: I1024 04:11:08.944172 15908 authenticatee.cpp:299] Authentication success
3: I1024 04:11:08.944242 15909 master.cpp:7977] Successfully authenticated principal 'test-principal' at slave(822)@172.17.0.4:38770
3: I1024 04:11:08.944272 15891 authenticator.cpp:432] Authentication session cleanup for crammd5-authenticatee(1483)@172.17.0.4:38770
3: I1024 04:11:08.944520 15908 slave.cpp:1147] Successfully authenticated with master master@172.17.0.4:38770
3: I1024 04:11:08.944804 15908 slave.cpp:1626] Will retry registration in 9.315168ms if necessary
3: I1024 04:11:08.945008 15894 master.cpp:5801] Received register agent message from slave(822)@172.17.0.4:38770 (4d972d0ce409)
3: I1024 04:11:08.945138 15894 master.cpp:3838] Authorizing agent with principal 'test-principal'
3: I1024 04:11:08.945519 15887 master.cpp:5861] Authorized registration of agent at slave(822)@172.17.0.4:38770 (4d972d0ce409)
3: I1024 04:11:08.945629 15887 master.cpp:5954] Registering agent at slave(822)@172.17.0.4:38770 (4d972d0ce409) with id 18396bd2-dac6-4060-9b49-1f220c21343a-S0
3: I1024 04:11:08.946077 15892 registrar.cpp:495] Applied 1 operations in 74926ns; attempting to update the registry
3: I1024 04:11:08.946666 15898 registrar.cpp:552] Successfully updated the registry in 0ns
3: I1024 04:11:08.946887 15906 master.cpp:6001] Admitted agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409)
3: I1024 04:11:08.947679 15905 slave.cpp:1193] Registered with master master@172.17.0.4:38770; given agent ID 18396bd2-dac6-4060-9b49-1f220c21343a-S0
3: I1024 04:11:08.947841 15893 status_update_manager.cpp:184] Resuming sending status updates
3: I1024 04:11:08.947976 15905 slave.cpp:1213] Checkpointing SlaveInfo to '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_z5ZOOb/meta/slaves/18396bd2-dac6-4060-9b49-1f220c21343a-S0/slave.info'
3: I1024 04:11:08.947605 15906 master.cpp:6032] Registered agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409) with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
3: I1024 04:11:08.948372 15905 slave.cpp:1262] Forwarding total oversubscribed resources {}
3: I1024 04:11:08.948344 15907 hierarchical.cpp:593] Added agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 (4d972d0ce409) with cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; ports:[31000-32000] (allocated: {})
3: I1024 04:11:08.948647 15890 master.cpp:6828] Received update of agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409) with total oversubscribed resources {}
3: I1024 04:11:08.948891 15890 master.cpp:6839] Ignoring update on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409) as it reports no changes
3: I1024 04:11:08.950250 15907 hierarchical.cpp:1488] Performed allocation for 1 agents in 1.68346ms
3: I1024 04:11:08.951030 15896 master.cpp:7777] Sending 1 offers to framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.951601 15901 sched.cpp:917] Scheduler::resourceOffers took 98695ns
3: I1024 04:11:08.953522 15904 master.cpp:9400] Removing offer 18396bd2-dac6-4060-9b49-1f220c21343a-O0
3: I1024 04:11:08.953652 15904 master.cpp:4196] Processing ACCEPT call for offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O0 ] on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409) for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.953804 15904 master.cpp:3758] Authorizing principal 'test-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"test-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1024 04:11:08.954843 15904 master.cpp:2348] Dropping CREATE offer operation from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770: Not authorized to create persistent volumes as 'test-principal'
3: I1024 04:11:08.956342 15889 hierarchical.cpp:1163] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:08.959540 15899 hierarchical.cpp:1488] Performed allocation for 1 agents in 1.532509ms
3: I1024 04:11:08.960273 15903 master.cpp:7777] Sending 1 offers to framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.960852 15892 sched.cpp:917] Scheduler::resourceOffers took 102962ns
3: W1024 04:11:08.961515 15886 process.cpp:3193] Attempted to spawn already running process version@172.17.0.4:38770
3: I1024 04:11:08.961583 15900 master.cpp:5207] Processing DECLINE call for offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O1 ] for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.962237 15886 sched.cpp:232] Version: 1.5.0
3: I1024 04:11:08.962491 15900 master.cpp:9400] Removing offer 18396bd2-dac6-4060-9b49-1f220c21343a-O1
3: I1024 04:11:08.962641 15900 master.cpp:3439] Processing SUPPRESS call for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.962750 15900 sched.cpp:336] New master detected at master@172.17.0.4:38770
3: I1024 04:11:08.962831 15900 sched.cpp:351] No credentials provided. Attempting to register without authentication
3: I1024 04:11:08.962844 15900 sched.cpp:820] Sending SUBSCRIBE call to master@172.17.0.4:38770
3: I1024 04:11:08.962934 15900 sched.cpp:853] Will retry registration in 751.662492ms if necessary
3: I1024 04:11:08.963114 15890 master.cpp:2929] Received SUBSCRIBE call for framework 'creator-framework' at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:08.963189 15890 master.cpp:2263] Authorizing framework principal 'creator-principal' to receive offers for roles '{ default-role }'
3: I1024 04:11:08.963198 15902 hierarchical.cpp:1163] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; ports:[31000-32000], allocated: {}) on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:08.963369 15902 hierarchical.cpp:1275] Suppressed offers for roles { default-role } of framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:08.963600 15896 master.cpp:3009] Subscribing framework creator-framework with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ]
3: I1024 04:11:08.964077 15888 hierarchical.cpp:303] Added framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:08.964102 15891 sched.cpp:747] Framework registered with 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:08.964151 15891 sched.cpp:761] Scheduler::registered took 16475ns
3: I1024 04:11:08.965756 15888 hierarchical.cpp:1488] Performed allocation for 1 agents in 1.516217ms
3: I1024 04:11:08.966486 15908 master.cpp:7777] Sending 1 offers to framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:08.967046 15894 sched.cpp:917] Scheduler::resourceOffers took 99952ns
3: I1024 04:11:08.968725 15904 hierarchical.cpp:1488] Performed allocation for 1 agents in 148335ns
3: I1024 04:11:08.970499 15899 master.cpp:9400] Removing offer 18396bd2-dac6-4060-9b49-1f220c21343a-O2
3: I1024 04:11:08.970631 15899 master.cpp:4196] Processing ACCEPT call for offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O2 ] on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409) for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:08.970788 15899 master.cpp:3758] Authorizing principal 'creator-principal' to create volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: I1024 04:11:08.972287 15893 master.cpp:4639] Applying CREATE operation for volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770 to agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409)
3: I1024 04:11:08.972900 15893 master.cpp:9346] Sending updated checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048 to agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409)
3: I1024 04:11:08.974191 15895 slave.cpp:3519] Updated checkpointed resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048
3: I1024 04:11:08.978729 15905 hierarchical.cpp:887] Updated allocation of framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from cpus(allocated: default-role):2; mem(allocated: default-role):2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; ports(allocated: default-role):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048
3: I1024 04:11:08.981479 15905 hierarchical.cpp:1163] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048, allocated: {}) on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:08.984879 15900 hierarchical.cpp:1488] Performed allocation for 1 agents in 1.648066ms
3: I1024 04:11:08.985703 15890 master.cpp:7777] Sending 1 offers to framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:08.986312 15902 sched.cpp:917] Scheduler::resourceOffers took 103864ns
3: I1024 04:11:08.987006 15896 master.cpp:5207] Processing DECLINE call for offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O3 ] for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:08.987954 15896 master.cpp:9400] Removing offer 18396bd2-dac6-4060-9b49-1f220c21343a-O3
3: I1024 04:11:08.988101 15896 master.cpp:3439] Processing SUPPRESS call for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:08.989820 15909 hierarchical.cpp:1163] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048, allocated: {}) on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:08.990104 15909 hierarchical.cpp:1275] Suppressed offers for roles { default-role } of framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:08.990451 15891 master.cpp:5312] Processing REVIVE call for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.990615 15891 hierarchical.cpp:1310] Revived offers for roles { default-role } of framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:08.992228 15891 hierarchical.cpp:1488] Performed allocation for 1 agents in 1.504427ms
3: I1024 04:11:08.993010 15894 master.cpp:7777] Sending 1 offers to framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.993590 15910 sched.cpp:917] Scheduler::resourceOffers took 109910ns
3: I1024 04:11:08.995249 15889 master.cpp:9400] Removing offer 18396bd2-dac6-4060-9b49-1f220c21343a-O4
3: I1024 04:11:08.995373 15889 master.cpp:4196] Processing ACCEPT call for offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O4 ] on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409) for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:08.995507 15889 master.cpp:3810] Authorizing principal 'test-principal' to destroy volumes '[{"allocation_info":{"role":"default-role"},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]'
3: W1024 04:11:08.996527 15899 master.cpp:2348] Dropping DESTROY offer operation from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770: Not authorized to destroy persistent volumes as 'test-principal'
3: I1024 04:11:08.998054 15906 hierarchical.cpp:1163] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048, allocated: {}) on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:09.002147 15895 hierarchical.cpp:1488] Performed allocation for 1 agents in 2.275546ms
3: I1024 04:11:09.003396 15905 master.cpp:7777] Sending 1 offers to framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:09.004021 15907 sched.cpp:917] Scheduler::resourceOffers took 101830ns
3: I1024 04:11:09.004353 15886 sched.cpp:2005] Asked to stop the driver
3: I1024 04:11:09.004525 15900 sched.cpp:1187] Stopping framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:09.004839 15902 master.cpp:8458] Processing TEARDOWN call for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:09.004868 15902 master.cpp:8470] Removing framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:09.004879 15902 master.cpp:3299] Deactivating framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
3: I1024 04:11:09.004909 15886 sched.cpp:2005] Asked to stop the driver
3: I1024 04:11:09.005000 15909 sched.cpp:1187] Stopping framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:09.005022 15890 hierarchical.cpp:412] Deactivated framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:09.005800 15902 master.cpp:9400] Removing offer 18396bd2-dac6-4060-9b49-1f220c21343a-O5
3: I1024 04:11:09.006009 15898 slave.cpp:3211] Asked to shut down framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 by master@172.17.0.4:38770
3: I1024 04:11:09.006047 15898 slave.cpp:3226] Cannot shut down unknown framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:09.006271 15902 master.cpp:8458] Processing TEARDOWN call for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:09.006299 15902 master.cpp:8470] Removing framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:09.006309 15902 master.cpp:3299] Deactivating framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
3: I1024 04:11:09.006497 15895 slave.cpp:3211] Asked to shut down framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 by master@172.17.0.4:38770
3: I1024 04:11:09.006527 15895 slave.cpp:3226] Cannot shut down unknown framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:09.006599 15908 hierarchical.cpp:1163] Recovered cpus(allocated: default-role):2; mem(allocated: default-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048 (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048, allocated: {}) on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:09.006803 15908 hierarchical.cpp:355] Removed framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
3: I1024 04:11:09.006853 15908 hierarchical.cpp:412] Deactivated framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:09.007043 15908 hierarchical.cpp:355] Removed framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
3: I1024 04:11:09.007345 15886 slave.cpp:869] Agent terminating
3: I1024 04:11:09.007544 15896 master.cpp:1303] Agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409) disconnected
3: I1024 04:11:09.007580 15896 master.cpp:3336] Disconnecting agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409)
3: I1024 04:11:09.007638 15896 master.cpp:3355] Deactivating agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770 (4d972d0ce409)
3: I1024 04:11:09.007733 15910 hierarchical.cpp:690] Agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 deactivated
3: I1024 04:11:09.013173 15886 master.cpp:1145] Master terminating
3: I1024 04:11:09.014163 15889 hierarchical.cpp:626] Removed agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0
3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1 (115 ms)
3: [----------] 34 tests from DiskResource/PersistentVolumeTest (11887 ms total)
3:
3: [----------] Global test environment tear-down
3: [==========] 1646 tests from 178 test cases ran. (526090 ms total)
3: [ PASSED ] 1645 tests.
3: [ FAILED ] 1 test, listed below:
3: [ FAILED ] ContentType/AgentAPIStreamingTest.AttachInputToNestedContainerSession/1, where GetParam() = application/json
3:
3: 1 FAILED TEST
3: YOU HAVE 21 DISABLED TESTS
3:
3: I1024 04:11:09.080514 15911 process.cpp:1067] Failed to accept socket: future discarded
3/3 Test #3: MesosTests .......................***Failed 530.24 sec
67% tests passed, 1 tests failed out of 3
Total Test time (real) = 539.88 sec
The following tests FAILED:
3 - MesosTests (Failed)
make[3]: Leaving directory `/mesos/build'
make[2]: Leaving directory `/mesos/build'
make[1]: Leaving directory `/mesos/build'
+ docker rmi mesos-1508815861-17030
Untagged: mesos-1508815861-17030:latest
Deleted: sha256:1cc3de362523fd78264509c635098ee1dd3b98cff22d9857b12f015ad169cb49
Deleted: sha256:3775ffcab0f8369402de442158a8d3c8223f97ed0be65e9cca7086e5aad0d8c1
Deleted: sha256:942a3eb795be0d056f7b0f8ad14cb168ecbfde219f94ec9120e29af730ef800b
Deleted: sha256:14079e3642b11de4a6e6078dff4944948c91133b234c83a7d391c0483e0aca56
Deleted: sha256:77b6b49052e912fdbcc5ee7b19dcfe869537a33a01131099226c2a65ea5bab2c
Deleted: sha256:750b6418dad329105638d6c2f04ea23cde9aa991fb995b9e14c4853165180ab1
Deleted: sha256:5a1da7ca3fa7db7f3d8130dc55c50a01b1394b3ec409705c5187b15a6f5e78e8
Deleted: sha256:7999637698b6d2db1fa356a4d51801d85046899411c50a0fae2a8c3cef5a25d3
Deleted: sha256:c65d71cbbcb1c7f78106f8d4f2f69705dea9973b5682400f8b0bbc79bf2480c3
Deleted: sha256:142b0e02d522e568645848dbb25d22e2520e6b3737d3ebd44f8e8426d45ec6b8
Deleted: sha256:7c0d2ea922e6d6f80b19ad80cedb40cde7d861794415036956363312fc9acbc9
Deleted: sha256:8ff582f89d02b6cf1e4df924cf96b48663fc6335948ce55382934f79bd3be6c6
Deleted: sha256:977e520821b95d22e0e6ec71593c9ee94b3aa1fdb235a73b1cf0cad121f2e25e
Deleted: sha256:41110809d8277daaeda105a5e569d8c10a773224aaf00c31eab618c7c19d8c84
Deleted: sha256:9108925e2a4d41a4c964a5059060a392197bc707e7d66ce57e5b62fc2626ca75
Deleted: sha256:56f57acbb5145469e4d6ccf8274653bae7b908b7d271a83f9968415af7ccca9b
Deleted: sha256:aa4565ec4291eb2f869de386adfa8b0fcb0815d15de8d68d6366d9b7ef63c046
Deleted: sha256:5eb330cf3a4d48051754b45a4c2ed588a66e7d77cb626bec9a1329f281ef1fec
Deleted: sha256:d7b08884ab333ec8c6d887a240f82ad0a790cded6243bc440bd136b9502a9cc7
Deleted: sha256:461a5f81f3801acce6fa785b326119630765ad6c22ae6c76194108a1d35774e9
Deleted: sha256:1cce5aef9d6a8dbfe4d8049debdebfb2f3102293364a639aa1fab9f9c17dda16
Deleted: sha256:844896faeb5d0a20458d2b8d0586d4824b105b864624734bf6fe106618d429f9
Deleted: sha256:ab0b6e06a5729685d69f83b0bdb73ecaf615054694d75980edfc5ace6b5d1669
Deleted: sha256:1695af1c65bfcb3b526b03cfda882f7ba9f103862d54b25ea86fa108aa2ace45
Deleted: sha256:60fd9dd905fd01ed231e0922d5b2b7bba9329b2d869a02772e92fd6d5e90abc5
Deleted: sha256:7739fdedef5b765ba02177b900ea7d54a35a449decdf974698d7414e0808a0f1
Deleted: sha256:a95a9026a3745c7d010ea1e7462b4a00f7285e7f501f08fa1046992e96efea43
Deleted: sha256:2d5c114ccbd6b8f834a41863856a3bae78a7b60965581da4f38b3fdca6bf7aef
Deleted: sha256:e8182fe869eb7f9f8785e67d61079ab75c10651ae13d379ba159f6ecbc6785d3
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
Re: Build failed in Jenkins: Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4359
Posted by Benjamin Mahler <bm...@apache.org>.
+gaston, you're assigned to this ticket? Are you still planning to look
into this?
https://issues.apache.org/jira/browse/MESOS-7742
On Mon, Oct 23, 2017 at 9:13 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=
> cmake,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)/4359/display/redirect?page=changes>
>
> Changes:
>
> [bmahler] Fixed the flaky MasterTest.IgnoreOldAgentReregistration.
>
> ------------------------------------------
> [...truncated 29.86 MB...]
> 3: creator_principals {
> 3: type: NONE
> 3: }
> 3: }
> 3: " --agent_ping_timeout="15secs" --agent_reregister_timeout="10mins"
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate_agents="true" --authenticate_frameworks="false"
> --authenticate_http_frameworks="true" --authenticate_http_readonly="true"
> --authenticate_http_readwrite="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/E8oHWm/credentials"
> --filter_gpu_resources="true" --framework_sorter="drf" --help="false"
> --hostname_lookup="true" --http_authenticators="basic" --http_framework_authenticators="basic"
> --initialize_driver_logging="true" --log_auto_initialize="true"
> --logbufsecs="0" --logging_level="INFO" --max_agent_ping_timeouts="5"
> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
> --max_unreachable_tasks_per_framework="1000" --port="5050"
> --quiet="false" --recovery_agent_removal_limit="100%"
> --registry="in_memory" --registry_fetch_timeout="1mins"
> --registry_gc_interval="15mins" --registry_max_agent_age="2weeks"
> --registry_max_agent_count="102400" --registry_store_timeout="100secs"
> --registry_strict="false" --roles="default-role" --root_submissions="true"
> --user_sorter="drf" --version="false" --webui_dir="/usr/local/share/mesos/webui"
> --work_dir="/tmp/E8oHWm/master" --zk_session_timeout="10secs"
> 3: I1024 04:11:08.909395 15898 master.cpp:498] Master allowing
> unauthenticated frameworks to register
> 3: I1024 04:11:08.909404 15898 master.cpp:502] Master only allowing
> authenticated agents to register
> 3: I1024 04:11:08.909407 15898 master.cpp:508] Master only allowing
> authenticated HTTP frameworks to register
> 3: I1024 04:11:08.909413 15898 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/E8oHWm/credentials'
> 3: I1024 04:11:08.909760 15898 master.cpp:552] Using default 'crammd5'
> authenticator
> 3: I1024 04:11:08.909956 15898 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-readonly'
> 3: I1024 04:11:08.910174 15898 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-readwrite'
> 3: I1024 04:11:08.910356 15898 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-master-scheduler'
> 3: I1024 04:11:08.910513 15898 master.cpp:631] Authorization enabled
> 3: W1024 04:11:08.910527 15898 master.cpp:694] The '--roles' flag is
> deprecated. This flag will be removed in the future. See the Mesos 0.27
> upgrade notes for more information
> 3: I1024 04:11:08.910727 15887 hierarchical.cpp:171] Initialized
> hierarchical allocator process
> 3: I1024 04:11:08.910782 15888 whitelist_watcher.cpp:77] No whitelist given
> 3: I1024 04:11:08.914042 15906 master.cpp:2198] Elected as the leading
> master!
> 3: I1024 04:11:08.914069 15906 master.cpp:1687] Recovering from registrar
> 3: I1024 04:11:08.914283 15892 registrar.cpp:347] Recovering registrar
> 3: I1024 04:11:08.915030 15892 registrar.cpp:391] Successfully fetched the
> registry (0B) in 0ns
> 3: I1024 04:11:08.915171 15892 registrar.cpp:495] Applied 1 operations in
> 25118ns; attempting to update the registry
> 3: I1024 04:11:08.915908 15892 registrar.cpp:552] Successfully updated the
> registry in 0ns
> 3: I1024 04:11:08.916079 15892 registrar.cpp:424] Successfully recovered
> registrar
> 3: I1024 04:11:08.916563 15896 hierarchical.cpp:209] Skipping recovery of
> hierarchical allocator: nothing to recover
> 3: I1024 04:11:08.916564 15894 master.cpp:1791] Recovered 0 agents from
> the registry (129B); allowing 10mins for agents to re-register
> 3: W1024 04:11:08.923128 15886 process.cpp:3193] Attempted to spawn
> already running process files@172.17.0.4:38770
> 3: I1024 04:11:08.924201 15886 containerizer.cpp:301] Using isolation {
> environment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni }
> 3: W1024 04:11:08.924837 15886 backend.cpp:76] Failed to create 'aufs'
> backend: AufsBackend requires root privileges
> 3: W1024 04:11:08.924991 15886 backend.cpp:76] Failed to create 'bind'
> backend: BindBackend requires root privileges
> 3: I1024 04:11:08.925025 15886 provisioner.cpp:255] Using default backend
> 'copy'
> 3: I1024 04:11:08.927402 15886 cluster.cpp:448] Creating default 'local'
> authorizer
> 3: I1024 04:11:08.930121 15894 slave.cpp:254] Mesos agent started on (822)@
> 172.17.0.4:38770
> 3: I1024 04:11:08.930153 15894 slave.cpp:255] Flags at startup: --acls=""
> --appc_simple_discovery_uri_prefix="http://" --appc_store_dir="/tmp/
> DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/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/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/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/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_UkrehB/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/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_UkrehB/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/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB/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="[{"name":"cpus","scalar":{"value":2.0},"type":"
> SCALAR"},{"name":"mem","scalar":{"value":2048.0},"
> type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> EiQqmM\/disk1"},"type":"PATH"}},"name":"disk","reservations"
> :[{"role":"default-role","type":"STATIC"}],"scalar":{"
> value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"
> root":"\/tmp\/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM\/disk2"},"type":"PATH"}
> },"name":"disk","reservations":[{"role":"default-role","
> type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]"
> --revocable_cpu_low_priority="true" --runtime_dir="/tmp/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_UkrehB"
> --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/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_z5ZOOb" --zk_session_timeout="10secs"
> 3: I1024 04:11:08.930595 15894 credentials.hpp:86] Loading credential for
> authentication from '/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_UkrehB/credential'
> 3: I1024 04:11:08.930748 15894 slave.cpp:287] Agent using credential for:
> test-principal
> 3: I1024 04:11:08.930765 15894 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_UkrehB/http_credentials'
> 3: W1024 04:11:08.930830 15886 process.cpp:3193] Attempted to spawn
> already running process version@172.17.0.4:38770
> 3: I1024 04:11:08.930989 15894 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-agent-readonly'
> 3: I1024 04:11:08.931143 15894 http.cpp:1045] Creating default 'basic'
> HTTP authenticator for realm 'mesos-agent-readwrite'
> 3: I1024 04:11:08.932003 15886 sched.cpp:232] Version: 1.5.0
> 3: I1024 04:11:08.932505 15897 sched.cpp:336] New master detected at
> master@172.17.0.4:38770
> 3: I1024 04:11:08.932641 15897 sched.cpp:351] No credentials provided.
> Attempting to register without authentication
> 3: I1024 04:11:08.932657 15897 sched.cpp:820] Sending SUBSCRIBE call to
> master@172.17.0.4:38770
> 3: I1024 04:11:08.932763 15897 sched.cpp:853] Will retry registration in
> 576.150212ms if necessary
> 3: I1024 04:11:08.932945 15905 master.cpp:2929] Received SUBSCRIBE call
> for framework 'default' at scheduler-3b4d3400-08d9-41f9-
> ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:08.933017 15905 master.cpp:2263] Authorizing framework
> principal 'test-principal' to receive offers for roles '{ default-role }'
> 3: I1024 04:11:08.933459 15909 master.cpp:3009] Subscribing framework
> default with checkpointing disabled and capabilities [
> RESERVATION_REFINEMENT ]
> 3: I1024 04:11:08.934033 15890 sched.cpp:747] Framework registered with
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:08.934139 15890 sched.cpp:761] Scheduler::registered took
> 76607ns
> 3: I1024 04:11:08.934159 15891 hierarchical.cpp:303] Added framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:08.934320 15891 hierarchical.cpp:1488] Performed allocation
> for 0 agents in 53111ns
> 3: I1024 04:11:08.934747 15894 slave.cpp:585] Agent resources:
> [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{
> "name":"mem","scalar":{"value":2048.0},"type":"SCALAR"},{"
> disk":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"}
> },"name":"disk","reservations":[{"role":"default-role","
> type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"
> },{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> EiQqmM\/disk2"},"type":"PATH"}},"name":"disk","reservations"
> :[{"role":"default-role","type":"STATIC"}],"scalar":{"
> value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"
> range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
> 3: I1024 04:11:08.935180 15894 slave.cpp:593] Agent attributes: [ ]
> 3: I1024 04:11:08.935190 15894 slave.cpp:602] Agent hostname: 4d972d0ce409
> 3: I1024 04:11:08.935341 15896 status_update_manager.cpp:177] Pausing
> sending status updates
> 3: I1024 04:11:08.936926 15903 state.cpp:64] Recovering state from
> '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> z5ZOOb/meta'
> 3: I1024 04:11:08.937274 15893 status_update_manager.cpp:203] Recovering
> status update manager
> 3: I1024 04:11:08.937506 15893 containerizer.cpp:662] Recovering
> containerizer
> 3: I1024 04:11:08.939023 15910 provisioner.cpp:416] Provisioner recovery
> complete
> 3: I1024 04:11:08.939424 15910 slave.cpp:6310] Finished recovery
> 3: I1024 04:11:08.940152 15889 slave.cpp:993] New master detected at
> master@172.17.0.4:38770
> 3: I1024 04:11:08.940222 15889 slave.cpp:1028] Detecting new master
> 3: I1024 04:11:08.940222 15894 status_update_manager.cpp:177] Pausing
> sending status updates
> 3: I1024 04:11:08.941874 15903 slave.cpp:1055] Authenticating with master
> master@172.17.0.4:38770
> 3: I1024 04:11:08.941953 15903 slave.cpp:1064] Using default CRAM-MD5
> authenticatee
> 3: I1024 04:11:08.942239 15898 authenticatee.cpp:121] Creating new client
> SASL connection
> 3: I1024 04:11:08.942376 15897 hierarchical.cpp:1488] Performed allocation
> for 0 agents in 70718ns
> 3: I1024 04:11:08.942559 15906 master.cpp:7947] Authenticating slave(822)@
> 172.17.0.4:38770
> 3: I1024 04:11:08.942734 15905 authenticator.cpp:414] Starting
> authentication session for crammd5-authenticatee(1483)@172.17.0.4:38770
> 3: I1024 04:11:08.943078 15902 authenticator.cpp:98] Creating new server
> SASL connection
> 3: I1024 04:11:08.943351 15907 authenticatee.cpp:213] Received SASL
> authentication mechanisms: CRAM-MD5
> 3: I1024 04:11:08.943379 15907 authenticatee.cpp:239] Attempting to
> authenticate with mechanism 'CRAM-MD5'
> 3: I1024 04:11:08.943528 15893 authenticator.cpp:204] Received SASL
> authentication start
> 3: I1024 04:11:08.943603 15893 authenticator.cpp:326] Authentication
> requires more steps
> 3: I1024 04:11:08.943722 15893 authenticatee.cpp:259] Received SASL
> authentication step
> 3: I1024 04:11:08.943871 15890 authenticator.cpp:232] Received SASL
> authentication step
> 3: I1024 04:11:08.943902 15890 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4d972d0ce409' server FQDN:
> '4d972d0ce409' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
> 3: I1024 04:11:08.943912 15890 auxprop.cpp:181] Looking up auxiliary
> property '*userPassword'
> 3: I1024 04:11:08.943944 15890 auxprop.cpp:181] Looking up auxiliary
> property '*cmusaslsecretCRAM-MD5'
> 3: I1024 04:11:08.943964 15890 auxprop.cpp:109] Request to lookup
> properties for user: 'test-principal' realm: '4d972d0ce409' server FQDN:
> '4d972d0ce409' SASL_AUXPROP_VERIFY_AGAINST_HASH: false
> SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
> 3: I1024 04:11:08.943971 15890 auxprop.cpp:131] Skipping auxiliary
> property '*userPassword' since SASL_AUXPROP_AUTHZID == true
> 3: I1024 04:11:08.943979 15890 auxprop.cpp:131] Skipping auxiliary
> property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
> 3: I1024 04:11:08.943991 15890 authenticator.cpp:318] Authentication
> success
> 3: I1024 04:11:08.944172 15908 authenticatee.cpp:299] Authentication
> success
> 3: I1024 04:11:08.944242 15909 master.cpp:7977] Successfully authenticated
> principal 'test-principal' at slave(822)@172.17.0.4:38770
> 3: I1024 04:11:08.944272 15891 authenticator.cpp:432] Authentication
> session cleanup for crammd5-authenticatee(1483)@172.17.0.4:38770
> 3: I1024 04:11:08.944520 15908 slave.cpp:1147] Successfully authenticated
> with master master@172.17.0.4:38770
> 3: I1024 04:11:08.944804 15908 slave.cpp:1626] Will retry registration in
> 9.315168ms if necessary
> 3: I1024 04:11:08.945008 15894 master.cpp:5801] Received register agent
> message from slave(822)@172.17.0.4:38770 (4d972d0ce409)
> 3: I1024 04:11:08.945138 15894 master.cpp:3838] Authorizing agent with
> principal 'test-principal'
> 3: I1024 04:11:08.945519 15887 master.cpp:5861] Authorized registration of
> agent at slave(822)@172.17.0.4:38770 (4d972d0ce409)
> 3: I1024 04:11:08.945629 15887 master.cpp:5954] Registering agent at
> slave(822)@172.17.0.4:38770 (4d972d0ce409) with id
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0
> 3: I1024 04:11:08.946077 15892 registrar.cpp:495] Applied 1 operations in
> 74926ns; attempting to update the registry
> 3: I1024 04:11:08.946666 15898 registrar.cpp:552] Successfully updated the
> registry in 0ns
> 3: I1024 04:11:08.946887 15906 master.cpp:6001] Admitted agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409)
> 3: I1024 04:11:08.947679 15905 slave.cpp:1193] Registered with master
> master@172.17.0.4:38770; given agent ID 18396bd2-dac6-4060-9b49-
> 1f220c21343a-S0
> 3: I1024 04:11:08.947841 15893 status_update_manager.cpp:184] Resuming
> sending status updates
> 3: I1024 04:11:08.947976 15905 slave.cpp:1213] Checkpointing SlaveInfo to
> '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> z5ZOOb/meta/slaves/18396bd2-dac6-4060-9b49-1f220c21343a-S0/slave.info'
> 3: I1024 04:11:08.947605 15906 master.cpp:6032] Registered agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409) with [{"name":"cpus","scalar":{"
> value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value"
> :2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"
> root":"\/tmp\/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"}
> },"name":"disk","reservations":[{"role":"default-role","
> type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"
> },{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> EiQqmM\/disk2"},"type":"PATH"}},"name":"disk","reservations"
> :[{"role":"default-role","type":"STATIC"}],"scalar":{"
> value":2048.0},"type":"SCALAR"},{"name":"ports","ranges":{"
> range":[{"begin":31000,"end":32000}]},"type":"RANGES"}]
> 3: I1024 04:11:08.948372 15905 slave.cpp:1262] Forwarding total
> oversubscribed resources {}
> 3: I1024 04:11:08.948344 15907 hierarchical.cpp:593] Added agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 (4d972d0ce409) with cpus:2;
> mem:2048; disk(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048;
> disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> ports:[31000-32000] (allocated: {})
> 3: I1024 04:11:08.948647 15890 master.cpp:6828] Received update of agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409) with total oversubscribed resources {}
> 3: I1024 04:11:08.948891 15890 master.cpp:6839] Ignoring update on agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409) as it reports no changes
> 3: I1024 04:11:08.950250 15907 hierarchical.cpp:1488] Performed allocation
> for 1 agents in 1.68346ms
> 3: I1024 04:11:08.951030 15896 master.cpp:7777] Sending 1 offers to
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:08.951601 15901 sched.cpp:917] Scheduler::resourceOffers
> took 98695ns
> 3: I1024 04:11:08.953522 15904 master.cpp:9400] Removing offer
> 18396bd2-dac6-4060-9b49-1f220c21343a-O0
> 3: I1024 04:11:08.953652 15904 master.cpp:4196] Processing ACCEPT call for
> offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O0 ] on agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409) for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:
> 38770
> 3: I1024 04:11:08.953804 15904 master.cpp:3758] Authorizing principal
> 'test-principal' to create volumes '[{"allocation_info":{"role":"
> default-role"},"disk":{"persistence":{"id":"id1","
> principal":"test-principal"},"source":{"path":{"root":"\/
> tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> EiQqmM\/disk1"},"type":"PATH"},"volume":{"container_path":"
> path1","mode":"RW"}},"name":"disk","reservations":[{"role":
> "default-role","type":"STATIC"}],"scalar":{"value":2048.0},"
> type":"SCALAR"}]'
> 3: W1024 04:11:08.954843 15904 master.cpp:2348] Dropping CREATE offer
> operation from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:
> 38770: Not authorized to create persistent volumes as 'test-principal'
> 3: I1024 04:11:08.956342 15889 hierarchical.cpp:1163] Recovered
> cpus(allocated: default-role):2; mem(allocated: default-role):2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048;
> disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048;
> disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> ports:[31000-32000], allocated: {}) on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0
> from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:08.959540 15899 hierarchical.cpp:1488] Performed allocation
> for 1 agents in 1.532509ms
> 3: I1024 04:11:08.960273 15903 master.cpp:7777] Sending 1 offers to
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:08.960852 15892 sched.cpp:917] Scheduler::resourceOffers
> took 102962ns
> 3: W1024 04:11:08.961515 15886 process.cpp:3193] Attempted to spawn
> already running process version@172.17.0.4:38770
> 3: I1024 04:11:08.961583 15900 master.cpp:5207] Processing DECLINE call
> for offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O1 ] for framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:08.962237 15886 sched.cpp:232] Version: 1.5.0
> 3: I1024 04:11:08.962491 15900 master.cpp:9400] Removing offer
> 18396bd2-dac6-4060-9b49-1f220c21343a-O1
> 3: I1024 04:11:08.962641 15900 master.cpp:3439] Processing SUPPRESS call
> for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:08.962750 15900 sched.cpp:336] New master detected at
> master@172.17.0.4:38770
> 3: I1024 04:11:08.962831 15900 sched.cpp:351] No credentials provided.
> Attempting to register without authentication
> 3: I1024 04:11:08.962844 15900 sched.cpp:820] Sending SUBSCRIBE call to
> master@172.17.0.4:38770
> 3: I1024 04:11:08.962934 15900 sched.cpp:853] Will retry registration in
> 751.662492ms if necessary
> 3: I1024 04:11:08.963114 15890 master.cpp:2929] Received SUBSCRIBE call
> for framework 'creator-framework' at scheduler-439ed120-1ee1-4630-
> b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:08.963189 15890 master.cpp:2263] Authorizing framework
> principal 'creator-principal' to receive offers for roles '{ default-role }'
> 3: I1024 04:11:08.963198 15902 hierarchical.cpp:1163] Recovered
> cpus(allocated: default-role):2; mem(allocated: default-role):2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048;
> disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048;
> disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_
> PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> ports:[31000-32000], allocated: {}) on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0
> from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:08.963369 15902 hierarchical.cpp:1275] Suppressed offers
> for roles { default-role } of framework 18396bd2-dac6-4060-9b49-
> 1f220c21343a-0000
> 3: I1024 04:11:08.963600 15896 master.cpp:3009] Subscribing framework
> creator-framework with checkpointing disabled and capabilities [
> RESERVATION_REFINEMENT ]
> 3: I1024 04:11:08.964077 15888 hierarchical.cpp:303] Added framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> 3: I1024 04:11:08.964102 15891 sched.cpp:747] Framework registered with
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> 3: I1024 04:11:08.964151 15891 sched.cpp:761] Scheduler::registered took
> 16475ns
> 3: I1024 04:11:08.965756 15888 hierarchical.cpp:1488] Performed allocation
> for 1 agents in 1.516217ms
> 3: I1024 04:11:08.966486 15908 master.cpp:7777] Sending 1 offers to
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework)
> at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:08.967046 15894 sched.cpp:917] Scheduler::resourceOffers
> took 99952ns
> 3: I1024 04:11:08.968725 15904 hierarchical.cpp:1488] Performed allocation
> for 1 agents in 148335ns
> 3: I1024 04:11:08.970499 15899 master.cpp:9400] Removing offer
> 18396bd2-dac6-4060-9b49-1f220c21343a-O2
> 3: I1024 04:11:08.970631 15899 master.cpp:4196] Processing ACCEPT call for
> offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O2 ] on agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409) for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> (creator-framework) at scheduler-439ed120-1ee1-4630-
> b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:08.970788 15899 master.cpp:3758] Authorizing principal
> 'creator-principal' to create volumes '[{"allocation_info":{"role":"
> default-role"},"disk":{"persistence":{"id":"id1","
> principal":"creator-principal"},"source":{"path":{"root":"\/
> tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> EiQqmM\/disk1"},"type":"PATH"},"volume":{"container_path":"
> path1","mode":"RW"}},"name":"disk","reservations":[{"role":
> "default-role","type":"STATIC"}],"scalar":{"value":2048.0},"
> type":"SCALAR"}]'
> 3: I1024 04:11:08.972287 15893 master.cpp:4639] Applying CREATE operation
> for volumes [{"allocation_info":{"role":"default-role"},"disk":{"
> persistence":{"id":"id1","principal":"creator-principal"
> },"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM\/disk1"},"type":"PATH"}
> ,"volume":{"container_path":"path1","mode":"RW"}},"name":"
> disk","reservations":[{"role":"default-role","type":"STATIC"
> }],"scalar":{"value":2048.0},"type":"SCALAR"}] from framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at
> scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770 to agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409)
> 3: I1024 04:11:08.972900 15893 master.cpp:9346] Sending updated
> checkpointed resources disk(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048
> to agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@
> 172.17.0.4:38770 (4d972d0ce409)
> 3: I1024 04:11:08.974191 15895 slave.cpp:3519] Updated checkpointed
> resources from {} to disk(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> EiQqmM/disk1,id1:path1]:2048
> 3: I1024 04:11:08.978729 15905 hierarchical.cpp:887] Updated allocation of
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 on agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from cpus(allocated:
> default-role):2; mem(allocated: default-role):2048; disk(allocated:
> default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1]:2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> ports(allocated: default-role):[31000-32000] to cpus(allocated:
> default-role):2; mem(allocated: default-role):2048; ports(allocated:
> default-role):[31000-32000]; disk(allocated: default-role)(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(allocated:
> default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> EiQqmM/disk1,id1:path1]:2048
> 3: I1024 04:11:08.981479 15905 hierarchical.cpp:1163] Recovered
> cpus(allocated: default-role):2; mem(allocated: default-role):2048;
> ports(allocated: default-role):[31000-32000]; disk(allocated:
> default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048
> (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048, allocated: {})
> on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> 3: I1024 04:11:08.984879 15900 hierarchical.cpp:1488] Performed allocation
> for 1 agents in 1.648066ms
> 3: I1024 04:11:08.985703 15890 master.cpp:7777] Sending 1 offers to
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework)
> at scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:08.986312 15902 sched.cpp:917] Scheduler::resourceOffers
> took 103864ns
> 3: I1024 04:11:08.987006 15896 master.cpp:5207] Processing DECLINE call
> for offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O3 ] for framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at
> scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:08.987954 15896 master.cpp:9400] Removing offer
> 18396bd2-dac6-4060-9b49-1f220c21343a-O3
> 3: I1024 04:11:08.988101 15896 master.cpp:3439] Processing SUPPRESS call
> for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> (creator-framework) at scheduler-439ed120-1ee1-4630-
> b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:08.989820 15909 hierarchical.cpp:1163] Recovered
> cpus(allocated: default-role):2; mem(allocated: default-role):2048;
> ports(allocated: default-role):[31000-32000]; disk(allocated:
> default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048
> (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048, allocated: {})
> on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> 3: I1024 04:11:08.990104 15909 hierarchical.cpp:1275] Suppressed offers
> for roles { default-role } of framework 18396bd2-dac6-4060-9b49-
> 1f220c21343a-0001
> 3: I1024 04:11:08.990451 15891 master.cpp:5312] Processing REVIVE call for
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:08.990615 15891 hierarchical.cpp:1310] Revived offers for
> roles { default-role } of framework 18396bd2-dac6-4060-9b49-
> 1f220c21343a-0000
> 3: I1024 04:11:08.992228 15891 hierarchical.cpp:1488] Performed allocation
> for 1 agents in 1.504427ms
> 3: I1024 04:11:08.993010 15894 master.cpp:7777] Sending 1 offers to
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:08.993590 15910 sched.cpp:917] Scheduler::resourceOffers
> took 109910ns
> 3: I1024 04:11:08.995249 15889 master.cpp:9400] Removing offer
> 18396bd2-dac6-4060-9b49-1f220c21343a-O4
> 3: I1024 04:11:08.995373 15889 master.cpp:4196] Processing ACCEPT call for
> offers: [ 18396bd2-dac6-4060-9b49-1f220c21343a-O4 ] on agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409) for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:
> 38770
> 3: I1024 04:11:08.995507 15889 master.cpp:3810] Authorizing principal
> 'test-principal' to destroy volumes '[{"allocation_info":{"role":"
> default-role"},"disk":{"persistence":{"id":"id1","
> principal":"creator-principal"},"source":{"path":{"root":"\/
> tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_
> EiQqmM\/disk1"},"type":"PATH"},"volume":{"container_path":"
> path1","mode":"RW"}},"name":"disk","reservations":[{"role":
> "default-role","type":"STATIC"}],"scalar":{"value":2048.0},"
> type":"SCALAR"}]'
> 3: W1024 04:11:08.996527 15899 master.cpp:2348] Dropping DESTROY offer
> operation from framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> (default) at scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:
> 38770: Not authorized to destroy persistent volumes as 'test-principal'
> 3: I1024 04:11:08.998054 15906 hierarchical.cpp:1163] Recovered
> cpus(allocated: default-role):2; mem(allocated: default-role):2048;
> ports(allocated: default-role):[31000-32000]; disk(allocated:
> default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048
> (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048, allocated: {})
> on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:09.002147 15895 hierarchical.cpp:1488] Performed allocation
> for 1 agents in 2.275546ms
> 3: I1024 04:11:09.003396 15905 master.cpp:7777] Sending 1 offers to
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:09.004021 15907 sched.cpp:917] Scheduler::resourceOffers
> took 101830ns
> 3: I1024 04:11:09.004353 15886 sched.cpp:2005] Asked to stop the driver
> 3: I1024 04:11:09.004525 15900 sched.cpp:1187] Stopping framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:09.004839 15902 master.cpp:8458] Processing TEARDOWN call
> for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:09.004868 15902 master.cpp:8470] Removing framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:09.004879 15902 master.cpp:3299] Deactivating framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000 (default) at
> scheduler-3b4d3400-08d9-41f9-ae8e-cf79a5357e4b@172.17.0.4:38770
> 3: I1024 04:11:09.004909 15886 sched.cpp:2005] Asked to stop the driver
> 3: I1024 04:11:09.005000 15909 sched.cpp:1187] Stopping framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> 3: I1024 04:11:09.005022 15890 hierarchical.cpp:412] Deactivated framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:09.005800 15902 master.cpp:9400] Removing offer
> 18396bd2-dac6-4060-9b49-1f220c21343a-O5
> 3: I1024 04:11:09.006009 15898 slave.cpp:3211] Asked to shut down
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000 by
> master@172.17.0.4:38770
> 3: I1024 04:11:09.006047 15898 slave.cpp:3226] Cannot shut down unknown
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:09.006271 15902 master.cpp:8458] Processing TEARDOWN call
> for framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> (creator-framework) at scheduler-439ed120-1ee1-4630-
> b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:09.006299 15902 master.cpp:8470] Removing framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at
> scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:09.006309 15902 master.cpp:3299] Deactivating framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001 (creator-framework) at
> scheduler-439ed120-1ee1-4630-b24e-8586554960c3@172.17.0.4:38770
> 3: I1024 04:11:09.006497 15895 slave.cpp:3211] Asked to shut down
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001 by
> master@172.17.0.4:38770
> 3: I1024 04:11:09.006527 15895 slave.cpp:3226] Cannot shut down unknown
> framework 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> 3: I1024 04:11:09.006599 15908 hierarchical.cpp:1163] Recovered
> cpus(allocated: default-role):2; mem(allocated: default-role):2048;
> ports(allocated: default-role):[31000-32000]; disk(allocated:
> default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048;
> disk(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:
> /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048
> (total: cpus:2; mem:2048; ports:[31000-32000]; disk(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk2]:2048; disk(reservations:
> [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_
> BadACLDropCreateAndDestroy_1_EiQqmM/disk1,id1:path1]:2048, allocated: {})
> on agent 18396bd2-dac6-4060-9b49-1f220c21343a-S0 from framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:09.006803 15908 hierarchical.cpp:355] Removed framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0000
> 3: I1024 04:11:09.006853 15908 hierarchical.cpp:412] Deactivated framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> 3: I1024 04:11:09.007043 15908 hierarchical.cpp:355] Removed framework
> 18396bd2-dac6-4060-9b49-1f220c21343a-0001
> 3: I1024 04:11:09.007345 15886 slave.cpp:869] Agent terminating
> 3: I1024 04:11:09.007544 15896 master.cpp:1303] Agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409) disconnected
> 3: I1024 04:11:09.007580 15896 master.cpp:3336] Disconnecting agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409)
> 3: I1024 04:11:09.007638 15896 master.cpp:3355] Deactivating agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 at slave(822)@172.17.0.4:38770
> (4d972d0ce409)
> 3: I1024 04:11:09.007733 15910 hierarchical.cpp:690] Agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0 deactivated
> 3: I1024 04:11:09.013173 15886 master.cpp:1145] Master terminating
> 3: I1024 04:11:09.014163 15889 hierarchical.cpp:626] Removed agent
> 18396bd2-dac6-4060-9b49-1f220c21343a-S0
> 3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestroy/1
> (115 ms)
> 3: [----------] 34 tests from DiskResource/PersistentVolumeTest (11887 ms
> total)
> 3:
> 3: [----------] Global test environment tear-down
> 3: [==========] 1646 tests from 178 test cases ran. (526090 ms total)
> 3: [ PASSED ] 1645 tests.
> 3: [ FAILED ] 1 test, listed below:
> 3: [ FAILED ] ContentType/AgentAPIStreamingTest.
> AttachInputToNestedContainerSession/1, where GetParam() = application/json
> 3:
> 3: 1 FAILED TEST
> 3: YOU HAVE 21 DISABLED TESTS
> 3:
> 3: I1024 04:11:09.080514 15911 process.cpp:1067] Failed to accept socket:
> future discarded
> 3/3 Test #3: MesosTests .......................***Failed 530.24 sec
>
> 67% tests passed, 1 tests failed out of 3
>
> Total Test time (real) = 539.88 sec
>
> The following tests FAILED:
> 3 - MesosTests (Failed)
> make[3]: Leaving directory `/mesos/build'
> make[2]: Leaving directory `/mesos/build'
> make[1]: Leaving directory `/mesos/build'
> + docker rmi mesos-1508815861-17030
> Untagged: mesos-1508815861-17030:latest
> Deleted: sha256:1cc3de362523fd78264509c635098e
> e1dd3b98cff22d9857b12f015ad169cb49
> Deleted: sha256:3775ffcab0f8369402de442158a8d3
> c8223f97ed0be65e9cca7086e5aad0d8c1
> Deleted: sha256:942a3eb795be0d056f7b0f8ad14cb1
> 68ecbfde219f94ec9120e29af730ef800b
> Deleted: sha256:14079e3642b11de4a6e6078dff4944
> 948c91133b234c83a7d391c0483e0aca56
> Deleted: sha256:77b6b49052e912fdbcc5ee7b19dcfe
> 869537a33a01131099226c2a65ea5bab2c
> Deleted: sha256:750b6418dad329105638d6c2f04ea2
> 3cde9aa991fb995b9e14c4853165180ab1
> Deleted: sha256:5a1da7ca3fa7db7f3d8130dc55c50a
> 01b1394b3ec409705c5187b15a6f5e78e8
> Deleted: sha256:7999637698b6d2db1fa356a4d51801
> d85046899411c50a0fae2a8c3cef5a25d3
> Deleted: sha256:c65d71cbbcb1c7f78106f8d4f2f697
> 05dea9973b5682400f8b0bbc79bf2480c3
> Deleted: sha256:142b0e02d522e568645848dbb25d22
> e2520e6b3737d3ebd44f8e8426d45ec6b8
> Deleted: sha256:7c0d2ea922e6d6f80b19ad80cedb40
> cde7d861794415036956363312fc9acbc9
> Deleted: sha256:8ff582f89d02b6cf1e4df924cf96b4
> 8663fc6335948ce55382934f79bd3be6c6
> Deleted: sha256:977e520821b95d22e0e6ec71593c9e
> e94b3aa1fdb235a73b1cf0cad121f2e25e
> Deleted: sha256:41110809d8277daaeda105a5e569d8
> c10a773224aaf00c31eab618c7c19d8c84
> Deleted: sha256:9108925e2a4d41a4c964a5059060a3
> 92197bc707e7d66ce57e5b62fc2626ca75
> Deleted: sha256:56f57acbb5145469e4d6ccf8274653
> bae7b908b7d271a83f9968415af7ccca9b
> Deleted: sha256:aa4565ec4291eb2f869de386adfa8b
> 0fcb0815d15de8d68d6366d9b7ef63c046
> Deleted: sha256:5eb330cf3a4d48051754b45a4c2ed5
> 88a66e7d77cb626bec9a1329f281ef1fec
> Deleted: sha256:d7b08884ab333ec8c6d887a240f82a
> d0a790cded6243bc440bd136b9502a9cc7
> Deleted: sha256:461a5f81f3801acce6fa785b326119
> 630765ad6c22ae6c76194108a1d35774e9
> Deleted: sha256:1cce5aef9d6a8dbfe4d8049debdebf
> b2f3102293364a639aa1fab9f9c17dda16
> Deleted: sha256:844896faeb5d0a20458d2b8d0586d4
> 824b105b864624734bf6fe106618d429f9
> Deleted: sha256:ab0b6e06a5729685d69f83b0bdb73e
> caf615054694d75980edfc5ace6b5d1669
> Deleted: sha256:1695af1c65bfcb3b526b03cfda882f
> 7ba9f103862d54b25ea86fa108aa2ace45
> Deleted: sha256:60fd9dd905fd01ed231e0922d5b2b7
> bba9329b2d869a02772e92fd6d5e90abc5
> Deleted: sha256:7739fdedef5b765ba02177b900ea7d
> 54a35a449decdf974698d7414e0808a0f1
> Deleted: sha256:a95a9026a3745c7d010ea1e7462b4a
> 00f7285e7f501f08fa1046992e96efea43
> Deleted: sha256:2d5c114ccbd6b8f834a41863856a3b
> ae78a7b60965581da4f38b3fdca6bf7aef
> Deleted: sha256:e8182fe869eb7f9f8785e67d61079a
> b75c10651ae13d379ba159f6ecbc6785d3
> 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
>
Jenkins build is back to normal : Mesos-Buildbot » cmake,gcc,--verbose --disable-libtool-wrappers,GLOG_v=1 MESOS_VERBOSE=1,centos:7,(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) #4360
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Buildbot/BUILDTOOL=cmake,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)/4360/display/redirect>