Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 2478A200D34 for ; Fri, 20 Oct 2017 04:05:25 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 22C0B160BEC; Fri, 20 Oct 2017 02:05:25 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 6F2621609EE for ; Fri, 20 Oct 2017 04:05:23 +0200 (CEST) Received: (qmail 96965 invoked by uid 500); 20 Oct 2017 02:05:22 -0000 Mailing-List: contact builds-help@mesos.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: builds@mesos.apache.org Delivered-To: mailing list builds@mesos.apache.org Received: (qmail 96953 invoked by uid 99); 20 Oct 2017 02:05:21 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd2-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 20 Oct 2017 02:05:21 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd2-us-west.apache.org (ASF Mail Server at spamd2-us-west.apache.org) with ESMTP id 0EA851A109A for ; Fri, 20 Oct 2017 02:05:21 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd2-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 1.751 X-Spam-Level: * X-Spam-Status: No, score=1.751 tagged_above=-999 required=6.31 tests=[KAM_LAZY_DOMAIN_SECURITY=1, KAM_LOTSOFHASH=0.25, KAM_NUMSUBJECT=0.5, NORMAL_HTTP_TO_IP=0.001, RP_MATCHES_RCVD=-0.001, URIBL_BLOCKED=0.001] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd2-us-west.apache.org [10.40.0.9]) (amavisd-new, port 10024) with ESMTP id zHs8RyprzZLU for ; Fri, 20 Oct 2017 02:05:13 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id 9CB5B5FCE5 for ; Fri, 20 Oct 2017 02:05:12 +0000 (UTC) Received: from jenkins-master.apache.org (unknown [62.210.60.235]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTP id 7EBA5E041C; Fri, 20 Oct 2017 02:05:11 +0000 (UTC) Received: from jenkins-master.apache.org (localhost [127.0.0.1]) by jenkins-master.apache.org (ASF Mail Server at jenkins-master.apache.org) with ESMTP id 5750FA0179; Fri, 20 Oct 2017 02:05:05 +0000 (UTC) Date: Fri, 20 Oct 2017 02:03:37 +0000 (UTC) From: Apache Jenkins Server To: builds@mesos.apache.org, jpeach@apache.org Message-ID: <608330755.4982.1508465091537.JavaMail.jenkins@jenkins-master.apache.org> Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_Mesos-Buildbot_=C2=BB_cmake,gcc,--v?= =?UTF-8?Q?erbose_--disable-libtool-wrappers_--enable-libevent_--e?= =?UTF-8?Q?nable-ssl,GLOG=5Fv=3D1_MESOS=5FVERBOSE=3D1,ubuntu:14.04,(ubuntu?= =?UTF-8?Q?)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)_#4346?= MIME-Version: 1.0 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Instance-Identity: MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAkqVKZPv7YyHBB3FvWfV7XQehwe/Ga3aadzSNknt8g382X3uN8A3SOQ+Ixq9HxS+ZlN6XR4TECySmSRy2JN5Rx8svxAD0TjtSF9LuU98dD+LniNDP7Lq6gvRFuJhbMHoS0nuTizDZLsK4X8TW5MyV9w+jFbdoZfRE5O/Mse0fkOeL5uoIS/3Vvu/W+x9QSjDkB7CaU56bPFlQjqqJBl3Cn9r34CkXQZYnLb/NjW4vcpw0+TgMUAPTIVEr5BTPZRshz19g7huwg3zANT5HBIZnzV4hsVY9w4JHkceFdKi/ibNnjPjsFs9pm0HSGJ/RDxjIvSTYT02eH4+m1RAYaj2E9QIDAQAB X-Jenkins-Job: Mesos-Buildbot/BUILDTOOL=cmake,COMPILER=gcc,CONFIGURATION=--verbose --disable-libtool-wrappers --enable-libevent --enable-ssl,ENVIRONMENT=GLOG_v=1 MESOS_VERBOSE=1,OS=ubuntu:14.04,label_exp=(ubuntu)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23) X-Jenkins-Result: FAILURE archived-at: Fri, 20 Oct 2017 02:05:25 -0000 See Changes: [jpeach] Added a `network/ports` isolator skeleton. [jpeach] Added network ports isolator listen socket utilities. [jpeach] Added network ports isolator socket utilities tests. [jpeach] Added socket checking to the network ports isolator. [jpeach] Configure the `network/ports` isolator watch interval. [jpeach] Optionally isolate only the agent network ports. [jpeach] Ignored containers that join CNI networks. [jpeach] Added the `network/ports` isolator to the Mesos containerizer. [jpeach] Added basic `network/ports` isolator tests. [jpeach] Added `network/ports` isolator recovery tests. [jpeach] Added `network/ports` isolator nested container tests. ------------------------------------------ [...truncated 31.03 MB...] 3: } 3: } 3: " --agent_ping_timeout=3D"15secs" --agent_reregister_timeout=3D"10mins" = --allocation_interval=3D"1secs" --allocator=3D"HierarchicalDRF" --authentic= ate_agents=3D"true" --authenticate_frameworks=3D"false" --authenticate_http= _frameworks=3D"true" --authenticate_http_readonly=3D"true" --authenticate_h= ttp_readwrite=3D"true" --authenticators=3D"crammd5" --authorizers=3D"local"= --credentials=3D"/tmp/ujewNY/credentials" --filter_gpu_resources=3D"true" = --framework_sorter=3D"drf" --help=3D"false" --hostname_lookup=3D"true" --ht= tp_authenticators=3D"basic" --http_framework_authenticators=3D"basic" --ini= tialize_driver_logging=3D"true" --log_auto_initialize=3D"true" --logbufsecs= =3D"0" --logging_level=3D"INFO" --max_agent_ping_timeouts=3D"5" --max_compl= eted_frameworks=3D"50" --max_completed_tasks_per_framework=3D"1000" --max_u= nreachable_tasks_per_framework=3D"1000" --port=3D"5050" --quiet=3D"false" -= -recovery_agent_removal_limit=3D"100%" --registry=3D"in_memory" --registry_= fetch_timeout=3D"1mins" --registry_gc_interval=3D"15mins" --registry_max_ag= ent_age=3D"2weeks" --registry_max_agent_count=3D"102400" --registry_store_t= imeout=3D"100secs" --registry_strict=3D"false" --roles=3D"default-role" --r= oot_submissions=3D"true" --user_sorter=3D"drf" --version=3D"false" --webui_= dir=3D"/usr/local/share/mesos/webui" --work_dir=3D"/tmp/ujewNY/master" --zk= _session_timeout=3D"10secs" 3: I1020 02:00:35.301120 27987 master.cpp:498] Master allowing unauthentica= ted frameworks to register 3: I1020 02:00:35.301132 27987 master.cpp:502] Master only allowing authent= icated agents to register 3: I1020 02:00:35.301141 27987 master.cpp:508] Master only allowing authent= icated HTTP frameworks to register 3: I1020 02:00:35.301161 27987 credentials.hpp:37] Loading credentials for = authentication from '/tmp/ujewNY/credentials' 3: I1020 02:00:35.301501 27987 master.cpp:552] Using default 'crammd5' auth= enticator 3: I1020 02:00:35.301704 27987 http.cpp:1045] Creating default 'basic' HTTP= authenticator for realm 'mesos-master-readonly' 3: I1020 02:00:35.301910 27987 http.cpp:1045] Creating default 'basic' HTTP= authenticator for realm 'mesos-master-readwrite' 3: I1020 02:00:35.302135 27987 http.cpp:1045] Creating default 'basic' HTTP= authenticator for realm 'mesos-master-scheduler' 3: I1020 02:00:35.302317 27987 master.cpp:631] Authorization enabled 3: W1020 02:00:35.302340 27987 master.cpp:694] The '--roles' flag is deprec= ated. This flag will be removed in the future. See the Mesos 0.27 upgrade n= otes for more information 3: I1020 02:00:35.302554 28004 whitelist_watcher.cpp:77] No whitelist given 3: I1020 02:00:35.302603 27992 hierarchical.cpp:171] Initialized hierarchic= al allocator process 3: I1020 02:00:35.305477 27986 master.cpp:2198] Elected as the leading mast= er! 3: I1020 02:00:35.305505 27986 master.cpp:1687] Recovering from registrar 3: I1020 02:00:35.305668 27991 registrar.cpp:347] Recovering registrar 3: I1020 02:00:35.306188 27991 registrar.cpp:391] Successfully fetched the = registry (0B) in 0ns 3: I1020 02:00:35.306272 27991 registrar.cpp:495] Applied 1 operations in 2= 1703ns; attempting to update the registry 3: I1020 02:00:35.306751 27988 registrar.cpp:552] Successfully updated the = registry in 0ns 3: I1020 02:00:35.306859 27988 registrar.cpp:424] Successfully recovered re= gistrar 3: I1020 02:00:35.307394 27994 master.cpp:1791] Recovered 0 agents from the= registry (129B); allowing 10mins for agents to re-register 3: I1020 02:00:35.307411 27985 hierarchical.cpp:209] Skipping recovery of h= ierarchical allocator: nothing to recover 3: W1020 02:00:35.312603 19871 process.cpp:3193] Attempted to spawn already= running process files@172.17.0.4:44808 3: I1020 02:00:35.313479 19871 containerizer.cpp:301] Using isolation { env= ironment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } 3: W1020 02:00:35.313953 19871 backend.cpp:76] Failed to create 'aufs' back= end: AufsBackend requires root privileges 3: W1020 02:00:35.314072 19871 backend.cpp:76] Failed to create 'bind' back= end: BindBackend requires root privileges 3: I1020 02:00:35.314101 19871 provisioner.cpp:255] Using default backend '= copy' 3: I1020 02:00:35.315945 19871 cluster.cpp:448] Creating default 'local' au= thorizer 3: I1020 02:00:35.318063 27985 slave.cpp:254] Mesos agent started on (831)@= 172.17.0.4:44808 3: W1020 02:00:35.318497 19871 process.cpp:3193] Attempted to spawn already= running process version@172.17.0.4:44808 3: I1020 02:00:35.318086 27985 slave.cpp:255] Flags at startup: --acls=3D""= --appc_simple_discovery_uri_prefix=3D"http://" --appc_store_dir=3D"/tmp/Di= skResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_l7nauF/store/a= ppc" --authenticate_http_executors=3D"true" --authenticate_http_readonly=3D= "true" --authenticate_http_readwrite=3D"true" --authenticatee=3D"crammd5" -= -authentication_backoff_factor=3D"1secs" --authorizer=3D"local" --cgroups_c= pu_enable_pids_and_tids_count=3D"false" --cgroups_enable_cfs=3D"false" --cg= roups_hierarchy=3D"/sys/fs/cgroup" --cgroups_limit_swap=3D"false" --cgroups= _root=3D"mesos" --container_disk_watch_interval=3D"15secs" --containerizers= =3D"mesos" --credential=3D"/tmp/DiskResource_PersistentVolumeTest_BadACLDro= pCreateAndDestroy_1_l7nauF/credential" --default_role=3D"*" --disallow_shar= ing_agent_pid_namespace=3D"false" --disk_watch_interval=3D"1mins" --docker= =3D"docker" --docker_kill_orphans=3D"true" --docker_registry=3D"https://reg= istry-1.docker.io" --docker_remove_delay=3D"6hrs" --docker_socket=3D"/var/r= un/docker.sock" --docker_stop_timeout=3D"0ns" --docker_store_dir=3D"/tmp/Di= skResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_l7nauF/store/d= ocker" --docker_volume_checkpoint_dir=3D"/var/run/mesos/isolators/docker/vo= lume" --enforce_container_disk_quota=3D"false" --executor_registration_time= out=3D"1mins" --executor_reregistration_timeout=3D"2secs" --executor_secret= _key=3D"/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1= _l7nauF/executor_secret_key" --executor_shutdown_grace_period=3D"5secs" --f= etcher_cache_dir=3D"/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreate= AndDestroy_1_l7nauF/fetch" --fetcher_cache_size=3D"2GB" --frameworks_home= =3D"" --gc_delay=3D"1weeks" --gc_disk_headroom=3D"0.1" --hadoop_home=3D"" -= -help=3D"false" --hostname_lookup=3D"true" --http_command_executor=3D"false= " --http_credentials=3D"/tmp/DiskResource_PersistentVolumeTest_BadACLDropCr= eateAndDestroy_1_l7nauF/http_credentials" --http_heartbeat_interval=3D"30se= cs" --initialize_driver_logging=3D"true" --isolation=3D"posix/cpu,posix/mem= " --launcher=3D"posix" --launcher_dir=3D"/mesos/build/src" --logbufsecs=3D"= 0" --logging_level=3D"INFO" --max_completed_executors_per_framework=3D"150"= --oversubscribed_resources_interval=3D"15secs" --perf_duration=3D"10secs" = --perf_interval=3D"1mins" --port=3D"5051" --qos_correction_interval_min=3D"= 0ns" --quiet=3D"false" --recover=3D"reconnect" --recovery_timeout=3D"15mins= " --registration_backoff_factor=3D"10ms" --resources=3D"[{"name":"cpus","sc= alar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":2048.0= },"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskResource_P= ersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c\/disk1"},"type":"PA= TH"}},"name":"disk","reservations":[{"role":"default-role","type":"STATIC"}= ],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"ro= ot":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_= vF1g0c\/disk2"},"type":"PATH"}},"name":"disk","reservations":[{"role":"defa= ult-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]" --= revocable_cpu_low_priority=3D"true" --runtime_dir=3D"/tmp/DiskResource_Pers= istentVolumeTest_BadACLDropCreateAndDestroy_1_l7nauF" --sandbox_directory= =3D"/mnt/mesos/sandbox" --strict=3D"true" --switch_user=3D"true" --systemd_= enable_support=3D"true" --systemd_runtime_directory=3D"/run/systemd/system"= --version=3D"false" --work_dir=3D"/tmp/DiskResource_PersistentVolumeTest_B= adACLDropCreateAndDestroy_1_st6cfr" --zk_session_timeout=3D"10secs" 3: I1020 02:00:35.318532 27985 credentials.hpp:86] Loading credential for a= uthentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreate= AndDestroy_1_l7nauF/credential' 3: I1020 02:00:35.318697 27985 slave.cpp:287] Agent using credential for: t= est-principal 3: I1020 02:00:35.318717 27985 credentials.hpp:37] Loading credentials for = authentication from '/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreat= eAndDestroy_1_l7nauF/http_credentials' 3: I1020 02:00:35.319025 27985 http.cpp:1045] Creating default 'basic' HTTP= authenticator for realm 'mesos-agent-executor' 3: I1020 02:00:35.319165 27985 http.cpp:1066] Creating default 'jwt' HTTP a= uthenticator for realm 'mesos-agent-executor' 3: I1020 02:00:35.319337 19871 sched.cpp:232] Version: 1.5.0 3: I1020 02:00:35.319437 27985 http.cpp:1045] Creating default 'basic' HTTP= authenticator for realm 'mesos-agent-readonly' 3: I1020 02:00:35.319573 27985 http.cpp:1066] Creating default 'jwt' HTTP a= uthenticator for realm 'mesos-agent-readonly' 3: I1020 02:00:35.319910 27985 http.cpp:1045] Creating default 'basic' HTTP= authenticator for realm 'mesos-agent-readwrite' 3: I1020 02:00:35.319973 27984 sched.cpp:336] New master detected at master= @172.17.0.4:44808 3: I1020 02:00:35.320049 27985 http.cpp:1066] Creating default 'jwt' HTTP a= uthenticator for realm 'mesos-agent-readwrite' 3: I1020 02:00:35.320089 27984 sched.cpp:351] No credentials provided. Atte= mpting to register without authentication 3: I1020 02:00:35.320101 27984 sched.cpp:820] Sending SUBSCRIBE call to mas= ter@172.17.0.4:44808 3: I1020 02:00:35.320219 27984 sched.cpp:853] Will retry registration in 1.= 955896264secs if necessary 3: I1020 02:00:35.320401 27991 master.cpp:2929] Received SUBSCRIBE call for= framework 'default' at scheduler-3ac92834-a7a2-4dd9-a7d6-cc32f3e66697@172.= 17.0.4:44808 3: I1020 02:00:35.320477 27991 master.cpp:2263] Authorizing framework princ= ipal 'test-principal' to receive offers for roles '{ default-role }' 3: I1020 02:00:35.320894 27988 master.cpp:3009] Subscribing framework defau= lt with checkpointing disabled and capabilities [ RESERVATION_REFINEMENT ] 3: I1020 02:00:35.321560 27988 sched.cpp:747] Framework registered with 01c= be334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.321602 27988 sched.cpp:761] Scheduler::registered took 16= 672ns 3: I1020 02:00:35.321681 27999 hierarchical.cpp:303] Added framework 01cbe3= 34-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.321990 27999 hierarchical.cpp:1488] Performed allocation = for 0 agents in 107026ns 3: I1020 02:00:35.325496 27985 slave.cpp:585] Agent resources: [{"name":"cp= us","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value"= :2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"root":"\/tmp\/DiskRes= ource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c\/disk1"},"ty= pe":"PATH"}},"name":"disk","reservations":[{"role":"default-role","type":"S= TATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"pat= h":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDes= troy_1_vF1g0c\/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: I1020 02:00:35.326228 27985 slave.cpp:593] Agent attributes: [ ] 3: I1020 02:00:35.326244 27985 slave.cpp:602] Agent hostname: 561d479a968e 3: I1020 02:00:35.326469 28007 status_update_manager.cpp:177] Pausing sendi= ng status updates 3: I1020 02:00:35.328421 27995 state.cpp:64] Recovering state from '/tmp/Di= skResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_st6cfr/meta' 3: I1020 02:00:35.328974 27998 status_update_manager.cpp:203] Recovering st= atus update manager 3: I1020 02:00:35.329277 28000 containerizer.cpp:662] Recovering containeri= zer 3: I1020 02:00:35.331022 27990 provisioner.cpp:416] Provisioner recovery co= mplete 3: I1020 02:00:35.331612 27987 slave.cpp:6310] Finished recovery 3: I1020 02:00:35.332540 28004 slave.cpp:993] New master detected at master= @172.17.0.4:44808 3: I1020 02:00:35.332554 27985 status_update_manager.cpp:177] Pausing sendi= ng status updates 3: I1020 02:00:35.332623 28004 slave.cpp:1028] Detecting new master 3: I1020 02:00:35.333247 27995 slave.cpp:1055] Authenticating with master m= aster@172.17.0.4:44808 3: I1020 02:00:35.333313 27995 slave.cpp:1064] Using default CRAM-MD5 authe= nticatee 3: I1020 02:00:35.333621 27998 hierarchical.cpp:1488] Performed allocation = for 0 agents in 120822ns 3: I1020 02:00:35.333683 27984 authenticatee.cpp:121] Creating new client S= ASL connection 3: I1020 02:00:35.334131 27989 master.cpp:7936] Authenticating slave(831)@1= 72.17.0.4:44808 3: I1020 02:00:35.334348 28001 authenticator.cpp:414] Starting authenticati= on session for crammd5-authenticatee(1499)@172.17.0.4:44808 3: I1020 02:00:35.334826 27994 authenticator.cpp:98] Creating new server SA= SL connection 3: I1020 02:00:35.335209 28003 authenticatee.cpp:213] Received SASL authent= ication mechanisms: CRAM-MD5 3: I1020 02:00:35.335239 28003 authenticatee.cpp:239] Attempting to authent= icate with mechanism 'CRAM-MD5' 3: I1020 02:00:35.335360 28003 authenticator.cpp:204] Received SASL authent= ication start 3: I1020 02:00:35.335420 28003 authenticator.cpp:326] Authentication requir= es more steps 3: I1020 02:00:35.335541 28003 authenticatee.cpp:259] Received SASL authent= ication step 3: I1020 02:00:35.335671 28003 authenticator.cpp:232] Received SASL authent= ication step 3: I1020 02:00:35.335700 28003 auxprop.cpp:109] Request to lookup propertie= s for user: 'test-principal' realm: '561d479a968e' server FQDN: '561d479a96= 8e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SA= SL_AUXPROP_AUTHZID: false=20 3: I1020 02:00:35.335710 28003 auxprop.cpp:181] Looking up auxiliary proper= ty '*userPassword' 3: I1020 02:00:35.335737 28003 auxprop.cpp:181] Looking up auxiliary proper= ty '*cmusaslsecretCRAM-MD5' 3: I1020 02:00:35.335758 28003 auxprop.cpp:109] Request to lookup propertie= s for user: 'test-principal' realm: '561d479a968e' server FQDN: '561d479a96= 8e' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SA= SL_AUXPROP_AUTHZID: true=20 3: I1020 02:00:35.335767 28003 auxprop.cpp:131] Skipping auxiliary property= '*userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true 3: I1020 02:00:35.335773 28003 auxprop.cpp:131] Skipping auxiliary property= '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true 3: I1020 02:00:35.335800 28003 authenticator.cpp:318] Authentication succes= s 3: I1020 02:00:35.335952 28005 authenticatee.cpp:299] Authentication succes= s 3: I1020 02:00:35.335999 27988 master.cpp:7966] Successfully authenticated = principal 'test-principal' at slave(831)@172.17.0.4:44808 3: I1020 02:00:35.336035 28003 authenticator.cpp:432] Authentication sessio= n cleanup for crammd5-authenticatee(1499)@172.17.0.4:44808 3: I1020 02:00:35.336326 27990 slave.cpp:1147] Successfully authenticated w= ith master master@172.17.0.4:44808 3: I1020 02:00:35.336730 27990 slave.cpp:1626] Will retry registration in 1= 7.734336ms if necessary 3: I1020 02:00:35.336962 27996 master.cpp:5801] Received register agent mes= sage from slave(831)@172.17.0.4:44808 (561d479a968e) 3: I1020 02:00:35.337098 27996 master.cpp:3838] Authorizing agent with prin= cipal 'test-principal' 3: I1020 02:00:35.337498 27985 master.cpp:5861] Authorized registration of = agent at slave(831)@172.17.0.4:44808 (561d479a968e) 3: I1020 02:00:35.337644 27985 master.cpp:5954] Registering agent at slave(= 831)@172.17.0.4:44808 (561d479a968e) with id 01cbe334-ef9c-411e-8e1d-9d92a1= cb1ef8-S0 3: I1020 02:00:35.338181 28004 registrar.cpp:495] Applied 1 operations in 6= 9653ns; attempting to update the registry 3: I1020 02:00:35.338724 28004 registrar.cpp:552] Successfully updated the = registry in 0ns 3: I1020 02:00:35.338949 27998 master.cpp:6001] Admitted agent 01cbe334-ef9= c-411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d479a968e) 3: I1020 02:00:35.339838 27989 slave.cpp:1193] Registered with master maste= r@172.17.0.4:44808; given agent ID 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-S0 3: I1020 02:00:35.339985 28006 status_update_manager.cpp:184] Resuming send= ing status updates 3: I1020 02:00:35.339706 27998 master.cpp:6032] Registered agent 01cbe334-e= f9c-411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d479a968e)= with [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem"= ,"scalar":{"value":2048.0},"type":"SCALAR"},{"disk":{"source":{"path":{"roo= t":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_v= F1g0c\/disk1"},"type":"PATH"}},"name":"disk","reservations":[{"role":"defau= lt-role","type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"},{"dis= k":{"source":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadA= CLDropCreateAndDestroy_1_vF1g0c\/disk2"},"type":"PATH"}},"name":"disk","res= ervations":[{"role":"default-role","type":"STATIC"}],"scalar":{"value":2048= .0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end= ":32000}]},"type":"RANGES"}] 3: I1020 02:00:35.340240 27989 slave.cpp:1213] Checkpointing SlaveInfo to '= /tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_st6cfr/= meta/slaves/01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-S0/slave.info' 3: I1020 02:00:35.340258 28001 hierarchical.cpp:593] Added agent 01cbe334-e= f9c-411e-8e1d-9d92a1cb1ef8-S0 (561d479a968e) with cpus:2; mem:2048; disk(re= servations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolum= eTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1]:2048; disk(reservations: [= (STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLD= ropCreateAndDestroy_1_vF1g0c/disk2]:2048; ports:[31000-32000] (allocated: {= }) 3: I1020 02:00:35.340688 27989 slave.cpp:1262] Forwarding total oversubscri= bed resources {} 3: I1020 02:00:35.340880 27989 master.cpp:6817] Received update of agent 01= cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d4= 79a968e) with total oversubscribed resources {} 3: I1020 02:00:35.341131 27989 master.cpp:6828] Ignoring update on agent 01= cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d4= 79a968e) as it reports no changes 3: I1020 02:00:35.342022 28001 hierarchical.cpp:1488] Performed allocation = for 1 agents in 1.596297ms 3: I1020 02:00:35.342768 27991 master.cpp:7766] Sending 1 offers to framewo= rk 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler-3ac9283= 4-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.343295 27991 sched.cpp:917] Scheduler::resourceOffers too= k 97391ns 3: I1020 02:00:35.346069 27988 master.cpp:9389] Removing offer 01cbe334-ef9= c-411e-8e1d-9d92a1cb1ef8-O0 3: I1020 02:00:35.346313 27988 master.cpp:4196] Processing ACCEPT call for = offers: [ 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-O0 ] on agent 01cbe334-ef9c-= 411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d479a968e) for= framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler= -3ac92834-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.346547 27988 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_BadACLDropCreateAnd= Destroy_1_vF1g0c\/disk1"},"type":"PATH"},"volume":{"container_path":"path1"= ,"mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","type":= "STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' 3: W1020 02:00:35.347995 27988 master.cpp:2348] Dropping CREATE offer opera= tion from framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at = scheduler-3ac92834-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808: Not author= ized to create persistent volumes as 'test-principal' 3: I1020 02:00:35.349858 27996 hierarchical.cpp:1163] Recovered cpus(alloca= ted: default-role):2; mem(allocated: default-role):2048; disk(allocated: de= fault-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_P= ersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1]:2048; disk(a= llocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/Di= skResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk2]:= 2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048= ; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_Persis= tentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1]:2048; disk(reserv= ations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTes= t_BadACLDropCreateAndDestroy_1_vF1g0c/disk2]:2048; ports:[31000-32000], all= ocated: {}) on agent 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-S0 from framework= 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.351948 28002 hierarchical.cpp:1488] Performed allocation = for 1 agents in 1.472813ms 3: I1020 02:00:35.352691 27986 master.cpp:7766] Sending 1 offers to framewo= rk 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler-3ac9283= 4-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.353282 27995 sched.cpp:917] Scheduler::resourceOffers too= k 107711ns 3: W1020 02:00:35.353901 19871 process.cpp:3193] Attempted to spawn already= running process version@172.17.0.4:44808 3: I1020 02:00:35.353948 28004 master.cpp:5207] Processing DECLINE call for= offers: [ 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-O1 ] for framework 01cbe334= -ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler-3ac92834-a7a2-4dd9= -a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.354534 19871 sched.cpp:232] Version: 1.5.0 3: I1020 02:00:35.354820 28004 master.cpp:9389] Removing offer 01cbe334-ef9= c-411e-8e1d-9d92a1cb1ef8-O1 3: I1020 02:00:35.354997 28004 master.cpp:3439] Processing SUPPRESS call fo= r framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at schedule= r-3ac92834-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.355062 27989 sched.cpp:336] New master detected at master= @172.17.0.4:44808 3: I1020 02:00:35.355162 27989 sched.cpp:351] No credentials provided. Atte= mpting to register without authentication 3: I1020 02:00:35.355182 27989 sched.cpp:820] Sending SUBSCRIBE call to mas= ter@172.17.0.4:44808 3: I1020 02:00:35.355293 27989 sched.cpp:853] Will retry registration in 74= 1.247548ms if necessary 3: I1020 02:00:35.355479 28001 master.cpp:2929] Received SUBSCRIBE call for= framework 'creator-framework' at scheduler-bd471cdc-130e-43a9-aa03-dea158c= 832fd@172.17.0.4:44808 3: I1020 02:00:35.355545 28001 master.cpp:2263] Authorizing framework princ= ipal 'creator-principal' to receive offers for roles '{ default-role }' 3: I1020 02:00:35.355856 27994 hierarchical.cpp:1163] Recovered cpus(alloca= ted: default-role):2; mem(allocated: default-role):2048; disk(allocated: de= fault-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_P= ersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1]:2048; disk(a= llocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/Di= skResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk2]:= 2048; ports(allocated: default-role):[31000-32000] (total: cpus:2; mem:2048= ; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_Persis= tentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1]:2048; disk(reserv= ations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTes= t_BadACLDropCreateAndDestroy_1_vF1g0c/disk2]:2048; ports:[31000-32000], all= ocated: {}) on agent 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-S0 from framework= 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.355978 27991 master.cpp:3009] Subscribing framework creat= or-framework with checkpointing disabled and capabilities [ RESERVATION_REF= INEMENT ] 3: I1020 02:00:35.356026 27994 hierarchical.cpp:1275] Suppressed offers for= roles { default-role } of framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0= 000 3: I1020 02:00:35.356432 28007 hierarchical.cpp:303] Added framework 01cbe3= 34-ef9c-411e-8e1d-9d92a1cb1ef8-0001 3: I1020 02:00:35.356498 28003 sched.cpp:747] Framework registered with 01c= be334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 3: I1020 02:00:35.356555 28003 sched.cpp:761] Scheduler::registered took 25= 295ns 3: I1020 02:00:35.358767 28007 hierarchical.cpp:1488] Performed allocation = for 1 agents in 2.129118ms 3: I1020 02:00:35.359791 27987 master.cpp:7766] Sending 1 offers to framewo= rk 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 (creator-framework) at schedul= er-bd471cdc-130e-43a9-aa03-dea158c832fd@172.17.0.4:44808 3: I1020 02:00:35.360481 27990 sched.cpp:917] Scheduler::resourceOffers too= k 123712ns 3: I1020 02:00:35.361285 27988 hierarchical.cpp:1488] Performed allocation = for 1 agents in 220088ns 3: I1020 02:00:35.363265 28002 master.cpp:9389] Removing offer 01cbe334-ef9= c-411e-8e1d-9d92a1cb1ef8-O2 3: I1020 02:00:35.363389 28002 master.cpp:4196] Processing ACCEPT call for = offers: [ 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-O2 ] on agent 01cbe334-ef9c-= 411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d479a968e) for= framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 (creator-framework) at= scheduler-bd471cdc-130e-43a9-aa03-dea158c832fd@172.17.0.4:44808 3: I1020 02:00:35.363538 28002 master.cpp:3758] Authorizing principal 'crea= tor-principal' to create volumes '[{"allocation_info":{"role":"default-role= "},"disk":{"persistence":{"id":"id1","principal":"creator-principal"},"sour= ce":{"path":{"root":"\/tmp\/DiskResource_PersistentVolumeTest_BadACLDropCre= ateAndDestroy_1_vF1g0c\/disk1"},"type":"PATH"},"volume":{"container_path":"= path1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","= type":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' 3: I1020 02:00:35.365069 28006 master.cpp:4639] Applying CREATE operation f= or volumes [{"allocation_info":{"role":"default-role"},"disk":{"persistence= ":{"id":"id1","principal":"creator-principal"},"source":{"path":{"root":"\/= tmp\/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c\= /disk1"},"type":"PATH"},"volume":{"container_path":"path1","mode":"RW"}},"n= ame":"disk","reservations":[{"role":"default-role","type":"STATIC"}],"scala= r":{"value":2048.0},"type":"SCALAR"}] from framework 01cbe334-ef9c-411e-8e1= d-9d92a1cb1ef8-0001 (creator-framework) at scheduler-bd471cdc-130e-43a9-aa0= 3-dea158c832fd@172.17.0.4:44808 to agent 01cbe334-ef9c-411e-8e1d-9d92a1cb1e= f8-S0 at slave(831)@172.17.0.4:44808 (561d479a968e) 3: I1020 02:00:35.365937 28006 master.cpp:9335] Sending updated checkpointe= d resources disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResou= rce_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,id1:path= 1]:2048 to agent 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.= 17.0.4:44808 (561d479a968e) 3: I1020 02:00:35.367158 27998 slave.cpp:3519] Updated checkpointed resourc= es from {} to disk(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskRes= ource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,id1:pa= th1]:2048 3: I1020 02:00:35.369964 28004 hierarchical.cpp:887] Updated allocation of = framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 on agent 01cbe334-ef9c-= 411e-8e1d-9d92a1cb1ef8-S0 from cpus(allocated: default-role):2; mem(allocat= ed: default-role):2048; disk(allocated: default-role)(reservations: [(STATI= C,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCre= ateAndDestroy_1_vF1g0c/disk1]:2048; disk(allocated: default-role)(reservati= ons: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_B= adACLDropCreateAndDestroy_1_vF1g0c/disk2]:2048; ports(allocated: default-ro= le):[31000-32000] to cpus(allocated: default-role):2; mem(allocated: defaul= t-role):2048; ports(allocated: default-role):[31000-32000]; disk(allocated:= default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp/DiskResourc= e_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk2]:2048; dis= k(allocated: default-role)(reservations: [(STATIC,default-role)])[PATH:/tmp= /DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk= 1,id1:path1]:2048 3: I1020 02:00:35.371480 28004 hierarchical.cpp:1163] Recovered cpus(alloca= ted: default-role):2; mem(allocated: default-role):2048; ports(allocated: d= efault-role):[31000-32000]; disk(allocated: default-role)(reservations: [(S= TATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDro= pCreateAndDestroy_1_vF1g0c/disk2]:2048; disk(allocated: default-role)(reser= vations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTe= st_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,id1:path1]:2048 (total: cpus:2= ; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)]= )[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_= vF1g0c/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/D= iskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,= id1:path1]:2048, allocated: {}) on agent 01cbe334-ef9c-411e-8e1d-9d92a1cb1e= f8-S0 from framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 3: I1020 02:00:35.374105 27992 hierarchical.cpp:1488] Performed allocation = for 1 agents in 1.906104ms 3: I1020 02:00:35.375149 28001 master.cpp:7766] Sending 1 offers to framewo= rk 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 (creator-framework) at schedul= er-bd471cdc-130e-43a9-aa03-dea158c832fd@172.17.0.4:44808 3: I1020 02:00:35.375965 28005 sched.cpp:917] Scheduler::resourceOffers too= k 140148ns 3: I1020 02:00:35.376724 27991 master.cpp:5207] Processing DECLINE call for= offers: [ 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-O3 ] for framework 01cbe334= -ef9c-411e-8e1d-9d92a1cb1ef8-0001 (creator-framework) at scheduler-bd471cdc= -130e-43a9-aa03-dea158c832fd@172.17.0.4:44808 3: I1020 02:00:35.377683 27991 master.cpp:9389] Removing offer 01cbe334-ef9= c-411e-8e1d-9d92a1cb1ef8-O3 3: I1020 02:00:35.377823 27991 master.cpp:3439] Processing SUPPRESS call fo= r framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 (creator-framework) a= t scheduler-bd471cdc-130e-43a9-aa03-dea158c832fd@172.17.0.4:44808 3: I1020 02:00:35.378868 27999 hierarchical.cpp:1163] Recovered cpus(alloca= ted: default-role):2; mem(allocated: default-role):2048; ports(allocated: d= efault-role):[31000-32000]; disk(allocated: default-role)(reservations: [(S= TATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDro= pCreateAndDestroy_1_vF1g0c/disk2]:2048; disk(allocated: default-role)(reser= vations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTe= st_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,id1:path1]:2048 (total: cpus:2= ; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)]= )[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_= vF1g0c/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/D= iskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,= id1:path1]:2048, allocated: {}) on agent 01cbe334-ef9c-411e-8e1d-9d92a1cb1e= f8-S0 from framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 3: I1020 02:00:35.379079 27999 hierarchical.cpp:1275] Suppressed offers for= roles { default-role } of framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0= 001 3: I1020 02:00:35.379552 28003 master.cpp:5312] Processing REVIVE call for = framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler-= 3ac92834-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.379758 27987 hierarchical.cpp:1310] Revived offers for ro= les { default-role } of framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.381857 27987 hierarchical.cpp:1488] Performed allocation = for 1 agents in 1.964957ms 3: I1020 02:00:35.382766 27990 master.cpp:7766] Sending 1 offers to framewo= rk 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler-3ac9283= 4-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.383522 27996 sched.cpp:917] Scheduler::resourceOffers too= k 144821ns 3: I1020 02:00:35.385284 27997 master.cpp:9389] Removing offer 01cbe334-ef9= c-411e-8e1d-9d92a1cb1ef8-O4 3: I1020 02:00:35.385412 27997 master.cpp:4196] Processing ACCEPT call for = offers: [ 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-O4 ] on agent 01cbe334-ef9c-= 411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d479a968e) for= framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler= -3ac92834-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.385548 27997 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_BadACLDropCreat= eAndDestroy_1_vF1g0c\/disk1"},"type":"PATH"},"volume":{"container_path":"pa= th1","mode":"RW"}},"name":"disk","reservations":[{"role":"default-role","ty= pe":"STATIC"}],"scalar":{"value":2048.0},"type":"SCALAR"}]' 3: W1020 02:00:35.386507 27997 master.cpp:2348] Dropping DESTROY offer oper= ation from framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at= scheduler-3ac92834-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808: Not autho= rized to destroy persistent volumes as 'test-principal' 3: I1020 02:00:35.388101 27984 hierarchical.cpp:1163] Recovered cpus(alloca= ted: default-role):2; mem(allocated: default-role):2048; ports(allocated: d= efault-role):[31000-32000]; disk(allocated: default-role)(reservations: [(S= TATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDro= pCreateAndDestroy_1_vF1g0c/disk2]:2048; disk(allocated: default-role)(reser= vations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTe= st_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,id1:path1]:2048 (total: cpus:2= ; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)]= )[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_= vF1g0c/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/D= iskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,= id1:path1]:2048, allocated: {}) on agent 01cbe334-ef9c-411e-8e1d-9d92a1cb1e= f8-S0 from framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.390631 28006 hierarchical.cpp:1488] Performed allocation = for 1 agents in 1.878999ms 3: I1020 02:00:35.391355 27998 master.cpp:7766] Sending 1 offers to framewo= rk 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler-3ac9283= 4-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.391911 28004 sched.cpp:917] Scheduler::resourceOffers too= k 100959ns 3: I1020 02:00:35.392222 19871 sched.cpp:2005] Asked to stop the driver 3: I1020 02:00:35.392339 27989 sched.cpp:1187] Stopping framework 01cbe334-= ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.392666 27992 master.cpp:8447] Processing TEARDOWN call fo= r framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at schedule= r-3ac92834-a7a2-4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.392704 27992 master.cpp:8459] Removing framework 01cbe334= -ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler-3ac92834-a7a2-4dd9= -a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.392715 27992 master.cpp:3299] Deactivating framework 01cb= e334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 (default) at scheduler-3ac92834-a7a2-= 4dd9-a7d6-cc32f3e66697@172.17.0.4:44808 3: I1020 02:00:35.392729 19871 sched.cpp:2005] Asked to stop the driver 3: I1020 02:00:35.392817 27994 hierarchical.cpp:412] Deactivated framework = 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.392846 27999 sched.cpp:1187] Stopping framework 01cbe334-= ef9c-411e-8e1d-9d92a1cb1ef8-0001 3: I1020 02:00:35.393878 27992 master.cpp:9389] Removing offer 01cbe334-ef9= c-411e-8e1d-9d92a1cb1ef8-O5 3: I1020 02:00:35.394083 27997 slave.cpp:3211] Asked to shut down framework= 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 by master@172.17.0.4:44808 3: I1020 02:00:35.394110 27997 slave.cpp:3226] Cannot shut down unknown fra= mework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.394429 27992 master.cpp:8447] Processing TEARDOWN call fo= r framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 (creator-framework) a= t scheduler-bd471cdc-130e-43a9-aa03-dea158c832fd@172.17.0.4:44808 3: I1020 02:00:35.394461 27992 master.cpp:8459] Removing framework 01cbe334= -ef9c-411e-8e1d-9d92a1cb1ef8-0001 (creator-framework) at scheduler-bd471cdc= -130e-43a9-aa03-dea158c832fd@172.17.0.4:44808 3: I1020 02:00:35.394474 27992 master.cpp:3299] Deactivating framework 01cb= e334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 (creator-framework) at scheduler-bd47= 1cdc-130e-43a9-aa03-dea158c832fd@172.17.0.4:44808 3: I1020 02:00:35.394670 27994 slave.cpp:3211] Asked to shut down framework= 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 by master@172.17.0.4:44808 3: I1020 02:00:35.394701 27994 slave.cpp:3226] Cannot shut down unknown fra= mework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 3: I1020 02:00:35.394784 28003 hierarchical.cpp:1163] Recovered cpus(alloca= ted: default-role):2; mem(allocated: default-role):2048; ports(allocated: d= efault-role):[31000-32000]; disk(allocated: default-role)(reservations: [(S= TATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDro= pCreateAndDestroy_1_vF1g0c/disk2]:2048; disk(allocated: default-role)(reser= vations: [(STATIC,default-role)])[PATH:/tmp/DiskResource_PersistentVolumeTe= st_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,id1:path1]:2048 (total: cpus:2= ; mem:2048; ports:[31000-32000]; disk(reservations: [(STATIC,default-role)]= )[PATH:/tmp/DiskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_= vF1g0c/disk2]:2048; disk(reservations: [(STATIC,default-role)])[PATH:/tmp/D= iskResource_PersistentVolumeTest_BadACLDropCreateAndDestroy_1_vF1g0c/disk1,= id1:path1]:2048, allocated: {}) on agent 01cbe334-ef9c-411e-8e1d-9d92a1cb1e= f8-S0 from framework 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.395125 28003 hierarchical.cpp:355] Removed framework 01cb= e334-ef9c-411e-8e1d-9d92a1cb1ef8-0000 3: I1020 02:00:35.395218 28003 hierarchical.cpp:412] Deactivated framework = 01cbe334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 3: I1020 02:00:35.395562 28003 hierarchical.cpp:355] Removed framework 01cb= e334-ef9c-411e-8e1d-9d92a1cb1ef8-0001 3: I1020 02:00:35.395808 19871 slave.cpp:869] Agent terminating 3: I1020 02:00:35.396003 27984 master.cpp:1303] Agent 01cbe334-ef9c-411e-8e= 1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d479a968e) disconnect= ed 3: I1020 02:00:35.396039 27984 master.cpp:3336] Disconnecting agent 01cbe33= 4-ef9c-411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d479a96= 8e) 3: I1020 02:00:35.396096 27984 master.cpp:3355] Deactivating agent 01cbe334= -ef9c-411e-8e1d-9d92a1cb1ef8-S0 at slave(831)@172.17.0.4:44808 (561d479a968= e) 3: I1020 02:00:35.396194 28005 hierarchical.cpp:690] Agent 01cbe334-ef9c-41= 1e-8e1d-9d92a1cb1ef8-S0 deactivated 3: I1020 02:00:35.401772 19871 master.cpp:1145] Master terminating 3: I1020 02:00:35.403401 28007 hierarchical.cpp:626] Removed agent 01cbe334= -ef9c-411e-8e1d-9d92a1cb1ef8-S0 3: [ OK ] DiskResource/PersistentVolumeTest.BadACLDropCreateAndDestro= y/1 (111 ms) 3: [----------] 34 tests from DiskResource/PersistentVolumeTest (12323 ms t= otal) 3:=20 3: [----------] Global test environment tear-down 3: [=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D] 1657 tests from 180 test cases ran. (53= 6957 ms total) 3: [ PASSED ] 1656 tests. 3: [ FAILED ] 1 test, listed below: 3: [ FAILED ] MasterSlaveReconciliationTest.ReconcileRace 3:=20 3: 1 FAILED TEST 3: YOU HAVE 21 DISABLED TESTS 3:=20 3: I1020 02:00:35.473898 28008 process.cpp:1067] Failed to accept socket: f= uture discarded 3/3 Test #3: MesosTests .......................***Failed 541.76 sec 67% tests passed, 1 tests failed out of 3 Total Test time (real) =3D 581.83 sec The following tests FAILED: =09 3 - MesosTests (Failed) make[3]: Leaving directory `/mesos/build' make[2]: Leaving directory `/mesos/build' make[1]: Leaving directory `/mesos/build' + docker rmi mesos-1508462080-17741 Untagged: mesos-1508462080-17741:latest Deleted: sha256:2f8c69604457e917f310f20d6388db77a7bbbd88686b7cffab5e11111d8= 13f9b Deleted: sha256:a20e02678c10ea1475502338189fc513aa2018db6530224a1936b246d01= a0dab Deleted: sha256:06cf49c6888a06214605753bb37b8286fb6a260608ee81fcd6b0c2270dd= 89ecb Deleted: sha256:bd74306bd7b6b30746293ab2e69b1a93ae24ddf343bae1aeab832fe65ac= 2c922 Deleted: sha256:a823de9cb7e5f0eca4ffbe67dfa0ffc0d07fac2d913f3cb83f4fb5f0bd7= fc388 Deleted: sha256:9444adeb1bd177e51c334fd709d8c7303bf63373f65c385446e5eacf505= 150ba Deleted: sha256:7e4c207490694095626e93edb6f0ab37d1503d3faa82dacf396e33fe8c5= fc300 Deleted: sha256:105cf3a41b0c22938f5ef93366a3eb3a047a0a3eb6760c313715e0fa0ef= 7d0e9 Deleted: sha256:d1d05412965b86f83e9c186033dc63164eaa4b64afe2046ddaae95dcb19= d1ef6 Deleted: sha256:5535f010c4f5bf54cbc3a82f259880a0fcc73d34cac3f52e7435c369ae6= 09512 Deleted: sha256:6d025fe704444a43d9418ce2f5b1982066198efa621b581d8429ba18fab= 7db4b Deleted: sha256:62cdc312a67f9e5f65ce9e40d448a841882317a40ea121871360b326755= 602bf Deleted: sha256:846b94ab4e146aaaf92dc04bf2aad4c83a360e35d4e6d15e0d387825271= 7dea9 Deleted: sha256:f75e8ac3746f0cc5a2db7f08c002d20f66ae4d27153bccaee4b4e2b8a06= 4ea66 Deleted: sha256:ec0c1762e7c33e687a5660a44f3c3bbf0561144aa7a74b9dd8f87f07112= 619c8 Deleted: sha256:c4c3400eeaad05d9565d91e6184b177ab4d68d7a3cae462c286ef3fcf08= 7d147 Deleted: sha256:594d66579bb1480f4cf8c431ee257de5a7dc7b6bd6b37ee8ba70ff60897= 903d5 Deleted: sha256:83ff63bf039b4f58aec2d05b1cc41c1575ffc4fa1db4ab5207754f041b3= 1e53e Deleted: sha256:4af023d8403bbcb2fbb929594795f7da04415c70d21bb0b072e786700fd= 686bb Deleted: sha256:5568d4977af089ea3f28283760ff90ff6c2e6170564127c0c7289920032= 2a127 Deleted: sha256:50959e8d63bdd45c89abebd83cb7bf4ea87d3fbb482688bb38132bdd444= 974f5 Deleted: sha256:8ff86013e397560f94269c49f28e7b408377395e9afe01fd095af2bb0a3= 1ceea Deleted: sha256:f3a2dd3a069691efe98ee7e6ff8113bce85e348024e876ded4920fdc820= ecd9a Deleted: sha256:20971d47d738ebf6977c7b295b7ee433969ee1f5569b0067a4b47714d53= 2bf2f Deleted: sha256:ffceed57a748ef366e423db6af63050d2b6c9449da06ee2f77b1e2d9461= 61378 Deleted: sha256:0d132608ad25ff686d1b9cccd818005d3701942dfb16f71020611318c20= 70964 Deleted: sha256:8edc71683cd274c0c57a83b22f92bdb4bfd7403d4d752969a571ab9d4d0= 7063a Deleted: sha256:5b31a30b618975dcc5a016a2193720eb3be8d1a6da99d87e2024f525cb0= fd450 Build step 'Execute shell' marked build as failure