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 64BFC200D2A for ; Sat, 14 Oct 2017 01:52:13 +0200 (CEST) Received: by cust-asf.ponee.io (Postfix) id 5E2D5160BE5; Fri, 13 Oct 2017 23:52:13 +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 D1D181609E9 for ; Sat, 14 Oct 2017 01:52:11 +0200 (CEST) Received: (qmail 82701 invoked by uid 500); 13 Oct 2017 23:52:11 -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 82690 invoked by uid 99); 13 Oct 2017 23:52:10 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd3-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 13 Oct 2017 23:52:10 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd3-us-west.apache.org (ASF Mail Server at spamd3-us-west.apache.org) with ESMTP id DE52F180920 for ; Fri, 13 Oct 2017 23:52:09 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd3-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-us.apache.org ([10.40.0.8]) by localhost (spamd3-us-west.apache.org [10.40.0.10]) (amavisd-new, port 10024) with ESMTP id 1eCMCa5ifSw4 for ; Fri, 13 Oct 2017 23:52:04 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-us.apache.org (ASF Mail Server at mx1-lw-us.apache.org) with ESMTP id 2C0715F3CC for ; Fri, 13 Oct 2017 23:52:04 +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 213DFE02BE; Fri, 13 Oct 2017 23:52:03 +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 BED84A0032; Fri, 13 Oct 2017 23:51:58 +0000 (UTC) Date: Fri, 13 Oct 2017 23:51:56 +0000 (UTC) From: Apache Jenkins Server To: builds@mesos.apache.org, jpeach@apache.org Message-ID: <1723625538.3077.1507938716482.JavaMail.jenkins@jenkins-master.apache.org> Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_Mesos-Buildbot_=C2=BB_autotools,clan?= =?UTF-8?Q?g,--verbose_--disable-libtool-wrappers_--enable-libevent_?= =?UTF-8?Q?--enable-ssl,GLOG=5Fv=3D1_MESOS=5F?= =?UTF-8?Q?VERBOSE=3D1,ubuntu:14.04,(ubunt?= =?UTF-8?Q?u)&&(!ubuntu-us1)&&(!ubuntu-eu2)&&(!qnode3)&&(!H23)_#4324?= 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=autotools,COMPILER=clang,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, 13 Oct 2017 23:52:13 -0000 See Changes: [jpeach] Added a benchmark test for allocating shared resources. ------------------------------------------ [...truncated 19.11 MB...] I1013 23:51:44.853505 6771 slave.cpp:6506] Received oversubscribable resou= rces {} from the resource estimator I1013 23:51:44.864217 6762 slave.cpp:1055] Authenticating with master mast= er@172.17.0.2:46604 I1013 23:51:44.864317 6762 slave.cpp:1064] Using default CRAM-MD5 authenti= catee I1013 23:51:44.864644 6767 authenticatee.cpp:121] Creating new client SASL= connection I1013 23:51:44.865123 6774 master.cpp:7936] Authenticating slave(392)@172.= 17.0.2:46604 I1013 23:51:44.865224 6781 authenticator.cpp:414] Starting authentication = session for crammd5-authenticatee(836)@172.17.0.2:46604 I1013 23:51:44.865525 6768 authenticator.cpp:98] Creating new server SASL = connection I1013 23:51:44.865767 6770 authenticatee.cpp:213] Received SASL authentica= tion mechanisms: CRAM-MD5 I1013 23:51:44.865798 6770 authenticatee.cpp:239] Attempting to authentica= te with mechanism 'CRAM-MD5' I1013 23:51:44.865952 6768 authenticator.cpp:204] Received SASL authentica= tion start I1013 23:51:44.866011 6768 authenticator.cpp:326] Authentication requires = more steps I1013 23:51:44.866137 6775 authenticatee.cpp:259] Received SASL authentica= tion step I1013 23:51:44.866300 6761 authenticator.cpp:232] Received SASL authentica= tion step I1013 23:51:44.866336 6761 auxprop.cpp:109] Request to lookup properties f= or user: 'test-principal' realm: '080f3c58946f' server FQDN: '080f3c58946f'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: false=20 I1013 23:51:44.866356 6761 auxprop.cpp:181] Looking up auxiliary property = '*userPassword' I1013 23:51:44.866408 6761 auxprop.cpp:181] Looking up auxiliary property = '*cmusaslsecretCRAM-MD5' I1013 23:51:44.866432 6761 auxprop.cpp:109] Request to lookup properties f= or user: 'test-principal' realm: '080f3c58946f' server FQDN: '080f3c58946f'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: true=20 I1013 23:51:44.866443 6761 auxprop.cpp:131] Skipping auxiliary property '*= userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true I1013 23:51:44.866452 6761 auxprop.cpp:131] Skipping auxiliary property '*= cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true I1013 23:51:44.866468 6761 authenticator.cpp:318] Authentication success I1013 23:51:44.866561 6773 authenticatee.cpp:299] Authentication success I1013 23:51:44.866659 6761 master.cpp:7966] Successfully authenticated pri= ncipal 'test-principal' at slave(392)@172.17.0.2:46604 I1013 23:51:44.866688 6773 authenticator.cpp:432] Authentication session c= leanup for crammd5-authenticatee(836)@172.17.0.2:46604 I1013 23:51:44.867077 6766 slave.cpp:1147] Successfully authenticated with= master master@172.17.0.2:46604 I1013 23:51:44.867318 6766 slave.cpp:1626] Will retry registration in 7.24= 7958ms if necessary I1013 23:51:44.867620 6777 master.cpp:5801] Received register agent messag= e from slave(392)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.867686 6777 master.cpp:3838] Authorizing agent with princip= al 'test-principal' I1013 23:51:44.868329 6777 master.cpp:5861] Authorized registration of age= nt at slave(392)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.868458 6777 master.cpp:5954] Registering agent at slave(392= )@172.17.0.2:46604 (080f3c58946f) with id ce5bcce8-7821-47cb-a7b0-79d947909= c38-S0 I1013 23:51:44.868827 6763 registrar.cpp:495] Applied 1 operations in 1006= 87ns; attempting to update the registry I1013 23:51:44.869575 6763 registrar.cpp:552] Successfully updated the reg= istry in 674816ns I1013 23:51:44.869781 6771 master.cpp:6001] Admitted agent ce5bcce8-7821-4= 7cb-a7b0-79d947909c38-S0 at slave(392)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.870461 6767 slave.cpp:4966] Received ping from slave-observ= er(398)@172.17.0.2:46604 I1013 23:51:44.870743 6767 slave.cpp:1193] Registered with master master@1= 72.17.0.2:46604; given agent ID ce5bcce8-7821-47cb-a7b0-79d947909c38-S0 I1013 23:51:44.870496 6771 master.cpp:6032] Registered agent ce5bcce8-7821= -47cb-a7b0-79d947909c38-S0 at slave(392)@172.17.0.2:46604 (080f3c58946f) wi= th [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","s= calar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1= 024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"= end":32000}]},"type":"RANGES"}] I1013 23:51:44.870847 6775 status_update_manager.cpp:184] Resuming sending= status updates I1013 23:51:44.870831 6781 hierarchical.cpp:593] Added agent ce5bcce8-7821= -47cb-a7b0-79d947909c38-S0 (080f3c58946f) with cpus:2; mem:1024; disk:1024;= ports:[31000-32000] (allocated: {}) I1013 23:51:44.871044 6767 slave.cpp:1213] Checkpointing SlaveInfo to '/tm= p/ReservationEndpointsTest_InsufficientResources_L0rieD/meta/slaves/ce5bcce= 8-7821-47cb-a7b0-79d947909c38-S0/slave.info' I1013 23:51:44.871212 6781 hierarchical.cpp:1943] No allocations performed I1013 23:51:44.871276 6781 hierarchical.cpp:1486] Performed allocation for= 1 agents in 189658ns I1013 23:51:44.871387 6767 slave.cpp:1262] Forwarding total oversubscribed= resources {} I1013 23:51:44.871593 6769 master.cpp:6817] Received update of agent ce5bc= ce8-7821-47cb-a7b0-79d947909c38-S0 at slave(392)@172.17.0.2:46604 (080f3c58= 946f) with total oversubscribed resources {} I1013 23:51:44.871763 6769 master.cpp:6828] Ignoring update on agent ce5bc= ce8-7821-47cb-a7b0-79d947909c38-S0 at slave(392)@172.17.0.2:46604 (080f3c58= 946f) as it reports no changes I1013 23:51:44.874330 6783 process.cpp:3929] Handling HTTP event for proce= ss 'master' with path: '/master/reserve' I1013 23:51:44.875799 6765 hierarchical.cpp:1943] No allocations performed I1013 23:51:44.875881 6765 hierarchical.cpp:1486] Performed allocation for= 1 agents in 242582ns I1013 23:51:44.876092 6765 http.cpp:1185] HTTP POST for /master/reserve fr= om 172.17.0.2:42984 I1013 23:51:44.876890 6765 master.cpp:3623] Authorizing principal 'test-pr= incipal' to reserve resources '[{"name":"cpus","reservations":[{"principal"= :"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":4.0},"= type":"SCALAR"},{"name":"mem","reservations":[{"principal":"test-principal"= ,"role":"role","type":"DYNAMIC"}],"scalar":{"value":4096.0},"type":"SCALAR"= }]' I1013 23:51:44.882134 6778 process.cpp:3929] Handling HTTP event for proce= ss 'master' with path: '/master/unreserve' I1013 23:51:44.883354 6775 http.cpp:1185] HTTP POST for /master/unreserve = from 172.17.0.2:42986 I1013 23:51:44.884030 6775 master.cpp:3691] Authorizing principal 'test-pr= incipal' to unreserve resources '[{"name":"cpus","reservations":[{"principa= l":"test-principal","role":"role","type":"DYNAMIC"}],"scalar":{"value":4.0}= ,"type":"SCALAR"},{"name":"mem","reservations":[{"principal":"test-principa= l","role":"role","type":"DYNAMIC"}],"scalar":{"value":4096.0},"type":"SCALA= R"}]' I1013 23:51:44.887377 6772 slave.cpp:869] Agent terminating I1013 23:51:44.887630 6772 master.cpp:1303] Agent ce5bcce8-7821-47cb-a7b0-= 79d947909c38-S0 at slave(392)@172.17.0.2:46604 (080f3c58946f) disconnected I1013 23:51:44.887665 6772 master.cpp:3336] Disconnecting agent ce5bcce8-7= 821-47cb-a7b0-79d947909c38-S0 at slave(392)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.887724 6772 master.cpp:3355] Deactivating agent ce5bcce8-78= 21-47cb-a7b0-79d947909c38-S0 at slave(392)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.887895 6778 hierarchical.cpp:690] Agent ce5bcce8-7821-47cb-= a7b0-79d947909c38-S0 deactivated I1013 23:51:44.900769 6760 master.cpp:1145] Master terminating I1013 23:51:44.901687 6766 hierarchical.cpp:626] Removed agent ce5bcce8-78= 21-47cb-a7b0-79d947909c38-S0 [ OK ] ReservationEndpointsTest.InsufficientResources (90 ms) [ RUN ] ReservationEndpointsTest.NoHeader I1013 23:51:44.909339 6760 cluster.cpp:162] Creating default 'local' autho= rizer I1013 23:51:44.912811 6774 master.cpp:445] Master 93acd52d-cfec-4c64-a368-= d07dfebea5f2 (080f3c58946f) started on 172.17.0.2:46604 I1013 23:51:44.912889 6774 master.cpp:447] Flags at startup: --acls=3D"" -= -agent_ping_timeout=3D"15secs" --agent_reregister_timeout=3D"10mins" --allo= cation_interval=3D"50ms" --allocator=3D"HierarchicalDRF" --authenticate_age= nts=3D"true" --authenticate_frameworks=3D"true" --authenticate_http_framewo= rks=3D"true" --authenticate_http_readonly=3D"true" --authenticate_http_read= write=3D"true" --authenticators=3D"crammd5" --authorizers=3D"local" --crede= ntials=3D"/tmp/WS8JrI/credentials" --filter_gpu_resources=3D"true" --framew= ork_sorter=3D"drf" --help=3D"false" --hostname_lookup=3D"true" --http_authe= nticators=3D"basic" --http_framework_authenticators=3D"basic" --initialize_= driver_logging=3D"true" --log_auto_initialize=3D"true" --logbufsecs=3D"0" -= -logging_level=3D"INFO" --max_agent_ping_timeouts=3D"5" --max_completed_fra= meworks=3D"50" --max_completed_tasks_per_framework=3D"1000" --max_unreachab= le_tasks_per_framework=3D"1000" --port=3D"5050" --quiet=3D"false" --recover= y_agent_removal_limit=3D"100%" --registry=3D"in_memory" --registry_fetch_ti= meout=3D"1mins" --registry_gc_interval=3D"15mins" --registry_max_agent_age= =3D"2weeks" --registry_max_agent_count=3D"102400" --registry_store_timeout= =3D"100secs" --registry_strict=3D"false" --roles=3D"role" --root_submission= s=3D"true" --user_sorter=3D"drf" --version=3D"false" --webui_dir=3D"/mesos/= mesos-1.5.0/_inst/share/mesos/webui" --work_dir=3D"/tmp/WS8JrI/master" --zk= _session_timeout=3D"10secs" I1013 23:51:44.913228 6774 master.cpp:496] Master only allowing authentica= ted frameworks to register I1013 23:51:44.913244 6774 master.cpp:502] Master only allowing authentica= ted agents to register I1013 23:51:44.913254 6774 master.cpp:508] Master only allowing authentica= ted HTTP frameworks to register I1013 23:51:44.913267 6774 credentials.hpp:37] Loading credentials for aut= hentication from '/tmp/WS8JrI/credentials' I1013 23:51:44.913619 6774 master.cpp:552] Using default 'crammd5' authent= icator I1013 23:51:44.913827 6774 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-readonly' I1013 23:51:44.914017 6774 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-readwrite' I1013 23:51:44.914155 6774 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-scheduler' I1013 23:51:44.914315 6774 master.cpp:631] Authorization enabled W1013 23:51:44.914337 6774 master.cpp:694] The '--roles' flag is deprecate= d. This flag will be removed in the future. See the Mesos 0.27 upgrade note= s for more information I1013 23:51:44.914554 6773 whitelist_watcher.cpp:77] No whitelist given I1013 23:51:44.914567 6777 hierarchical.cpp:171] Initialized hierarchical = allocator process I1013 23:51:44.917316 6780 master.cpp:2198] Elected as the leading master! I1013 23:51:44.917372 6780 master.cpp:1687] Recovering from registrar I1013 23:51:44.917650 6778 registrar.cpp:347] Recovering registrar I1013 23:51:44.918306 6778 registrar.cpp:391] Successfully fetched the reg= istry (0B) in 614912ns I1013 23:51:44.918454 6778 registrar.cpp:495] Applied 1 operations in 5363= 4ns; attempting to update the registry I1013 23:51:44.919122 6778 registrar.cpp:552] Successfully updated the reg= istry in 605952ns I1013 23:51:44.919240 6778 registrar.cpp:424] Successfully recovered regis= trar I1013 23:51:44.919714 6761 master.cpp:1791] Recovered 0 agents from the re= gistry (129B); allowing 10mins for agents to re-register I1013 23:51:44.919781 6764 hierarchical.cpp:209] Skipping recovery of hier= archical allocator: nothing to recover W1013 23:51:44.925555 6760 process.cpp:3194] Attempted to spawn already ru= nning process files@172.17.0.2:46604 I1013 23:51:44.926741 6760 containerizer.cpp:292] Using isolation { enviro= nment_secret, posix/cpu, posix/mem, filesystem/posix, network/cni } W1013 23:51:44.927407 6760 backend.cpp:76] Failed to create 'aufs' backend= : AufsBackend requires root privileges W1013 23:51:44.927531 6760 backend.cpp:76] Failed to create 'bind' backend= : BindBackend requires root privileges I1013 23:51:44.927572 6760 provisioner.cpp:255] Using default backend 'cop= y' I1013 23:51:44.929745 6760 cluster.cpp:448] Creating default 'local' autho= rizer I1013 23:51:44.932106 6764 slave.cpp:254] Mesos agent started on (393)@172= .17.0.2:46604 I1013 23:51:44.932163 6764 slave.cpp:255] Flags at startup: --acls=3D"" --= appc_simple_discovery_uri_prefix=3D"http://" --appc_store_dir=3D"/tmp/Reser= vationEndpointsTest_NoHeader_msslkg/store/appc" --authenticate_http_executo= rs=3D"true" --authenticate_http_readonly=3D"true" --authenticate_http_readw= rite=3D"true" --authenticatee=3D"crammd5" --authentication_backoff_factor= =3D"1secs" --authorizer=3D"local" --cgroups_cpu_enable_pids_and_tids_count= =3D"false" --cgroups_enable_cfs=3D"false" --cgroups_hierarchy=3D"/sys/fs/cg= roup" --cgroups_limit_swap=3D"false" --cgroups_root=3D"mesos" --container_d= isk_watch_interval=3D"15secs" --containerizers=3D"mesos" --credential=3D"/t= mp/ReservationEndpointsTest_NoHeader_msslkg/credential" --default_role=3D"*= " --disallow_sharing_agent_pid_namespace=3D"false" --disk_watch_interval=3D= "1mins" --docker=3D"docker" --docker_kill_orphans=3D"true" --docker_registr= y=3D"https://registry-1.docker.io" --docker_remove_delay=3D"6hrs" --docker_= socket=3D"/var/run/docker.sock" --docker_stop_timeout=3D"0ns" --docker_stor= e_dir=3D"/tmp/ReservationEndpointsTest_NoHeader_msslkg/store/docker" --dock= er_volume_checkpoint_dir=3D"/var/run/mesos/isolators/docker/volume" --enfor= ce_container_disk_quota=3D"false" --executor_registration_timeout=3D"1mins"= --executor_reregistration_timeout=3D"2secs" --executor_secret_key=3D"/tmp/= ReservationEndpointsTest_NoHeader_msslkg/executor_secret_key" --executor_sh= utdown_grace_period=3D"5secs" --fetcher_cache_dir=3D"/tmp/ReservationEndpoi= ntsTest_NoHeader_msslkg/fetch" --fetcher_cache_size=3D"2GB" --frameworks_ho= me=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"fal= se" --http_credentials=3D"/tmp/ReservationEndpointsTest_NoHeader_msslkg/htt= p_credentials" --http_heartbeat_interval=3D"30secs" --initialize_driver_log= ging=3D"true" --isolation=3D"posix/cpu,posix/mem" --launcher=3D"posix" --la= uncher_dir=3D"/mesos/mesos-1.5.0/_build/src" --logbufsecs=3D"0" --logging_l= evel=3D"INFO" --max_completed_executors_per_framework=3D"150" --oversubscri= bed_resources_interval=3D"15secs" --perf_duration=3D"10secs" --perf_interva= l=3D"1mins" --port=3D"5051" --qos_correction_interval_min=3D"0ns" --quiet= =3D"false" --recover=3D"reconnect" --recovery_timeout=3D"15mins" --registra= tion_backoff_factor=3D"10ms" --resources=3D"cpus:2;gpus:0;mem:1024;disk:102= 4;ports:[31000-32000]" --revocable_cpu_low_priority=3D"true" --runtime_dir= =3D"/tmp/ReservationEndpointsTest_NoHeader_msslkg" --sandbox_directory=3D"/= mnt/mesos/sandbox" --strict=3D"true" --switch_user=3D"true" --systemd_enabl= e_support=3D"true" --systemd_runtime_directory=3D"/run/systemd/system" --ve= rsion=3D"false" --work_dir=3D"/tmp/ReservationEndpointsTest_NoHeader_0sO1nu= " --zk_session_timeout=3D"10secs" I1013 23:51:44.932663 6764 credentials.hpp:86] Loading credential for auth= entication from '/tmp/ReservationEndpointsTest_NoHeader_msslkg/credential' I1013 23:51:44.932929 6764 slave.cpp:287] Agent using credential for: test= -principal I1013 23:51:44.932958 6764 credentials.hpp:37] Loading credentials for aut= hentication from '/tmp/ReservationEndpointsTest_NoHeader_msslkg/http_creden= tials' I1013 23:51:44.933286 6764 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-agent-executor' I1013 23:51:44.933439 6764 http.cpp:1066] Creating default 'jwt' HTTP auth= enticator for realm 'mesos-agent-executor' I1013 23:51:44.933672 6764 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-agent-readonly' I1013 23:51:44.933784 6764 http.cpp:1066] Creating default 'jwt' HTTP auth= enticator for realm 'mesos-agent-readonly' I1013 23:51:44.933976 6764 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-agent-readwrite' I1013 23:51:44.934077 6764 http.cpp:1066] Creating default 'jwt' HTTP auth= enticator for realm 'mesos-agent-readwrite' I1013 23:51:44.936125 6764 slave.cpp:585] Agent resources: [{"name":"cpus"= ,"scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","scalar":{"value":10= 24.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1024.0},"type":"SCA= LAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"end":32000}]},"typ= e":"RANGES"}] I1013 23:51:44.936452 6764 slave.cpp:593] Agent attributes: [ ] I1013 23:51:44.936568 6764 slave.cpp:602] Agent hostname: 080f3c58946f I1013 23:51:44.936805 6779 status_update_manager.cpp:177] Pausing sending = status updates I1013 23:51:44.938691 6763 state.cpp:64] Recovering state from '/tmp/Reser= vationEndpointsTest_NoHeader_0sO1nu/meta' I1013 23:51:44.939010 6773 status_update_manager.cpp:203] Recovering statu= s update manager I1013 23:51:44.939265 6777 containerizer.cpp:648] Recovering containerizer I1013 23:51:44.940685 6783 provisioner.cpp:416] Provisioner recovery compl= ete I1013 23:51:44.941107 6779 slave.cpp:6310] Finished recovery I1013 23:51:44.941654 6779 slave.cpp:6492] Querying resource estimator for= oversubscribable resources I1013 23:51:44.941874 6775 status_update_manager.cpp:177] Pausing sending = status updates I1013 23:51:44.941913 6779 slave.cpp:993] New master detected at master@17= 2.17.0.2:46604 I1013 23:51:44.942049 6779 slave.cpp:1028] Detecting new master I1013 23:51:44.942188 6779 slave.cpp:6506] Received oversubscribable resou= rces {} from the resource estimator I1013 23:51:44.946336 6763 slave.cpp:1055] Authenticating with master mast= er@172.17.0.2:46604 I1013 23:51:44.946490 6763 slave.cpp:1064] Using default CRAM-MD5 authenti= catee I1013 23:51:44.946836 6778 authenticatee.cpp:121] Creating new client SASL= connection I1013 23:51:44.947222 6773 master.cpp:7936] Authenticating slave(393)@172.= 17.0.2:46604 I1013 23:51:44.947335 6784 authenticator.cpp:414] Starting authentication = session for crammd5-authenticatee(837)@172.17.0.2:46604 I1013 23:51:44.947573 6762 authenticator.cpp:98] Creating new server SASL = connection I1013 23:51:44.947834 6762 authenticatee.cpp:213] Received SASL authentica= tion mechanisms: CRAM-MD5 I1013 23:51:44.947866 6762 authenticatee.cpp:239] Attempting to authentica= te with mechanism 'CRAM-MD5' I1013 23:51:44.948017 6774 authenticator.cpp:204] Received SASL authentica= tion start I1013 23:51:44.948077 6774 authenticator.cpp:326] Authentication requires = more steps I1013 23:51:44.948181 6772 authenticatee.cpp:259] Received SASL authentica= tion step I1013 23:51:44.948318 6780 authenticator.cpp:232] Received SASL authentica= tion step I1013 23:51:44.948356 6780 auxprop.cpp:109] Request to lookup properties f= or user: 'test-principal' realm: '080f3c58946f' server FQDN: '080f3c58946f'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: false=20 I1013 23:51:44.948382 6780 auxprop.cpp:181] Looking up auxiliary property = '*userPassword' I1013 23:51:44.948439 6780 auxprop.cpp:181] Looking up auxiliary property = '*cmusaslsecretCRAM-MD5' I1013 23:51:44.948511 6780 auxprop.cpp:109] Request to lookup properties f= or user: 'test-principal' realm: '080f3c58946f' server FQDN: '080f3c58946f'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: true=20 I1013 23:51:44.948529 6780 auxprop.cpp:131] Skipping auxiliary property '*= userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true I1013 23:51:44.948580 6780 auxprop.cpp:131] Skipping auxiliary property '*= cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true I1013 23:51:44.948606 6780 authenticator.cpp:318] Authentication success I1013 23:51:44.948712 6768 authenticatee.cpp:299] Authentication success I1013 23:51:44.948781 6776 master.cpp:7966] Successfully authenticated pri= ncipal 'test-principal' at slave(393)@172.17.0.2:46604 I1013 23:51:44.948822 6777 authenticator.cpp:432] Authentication session c= leanup for crammd5-authenticatee(837)@172.17.0.2:46604 I1013 23:51:44.949009 6770 slave.cpp:1147] Successfully authenticated with= master master@172.17.0.2:46604 *** Aborted at 1507938704 (unix time) try "date -d @1507938704" if you are = using GNU date *** I1013 23:51:44.949247 6770 slave.cpp:1626] Will retry registration in 18.2= 92144ms if necessary I1013 23:51:44.949527 6770 master.cpp:5801] Received register agent messag= e from slave(393)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.949563 6770 master.cpp:3838] Authorizing agent with princip= al 'test-principal' I1013 23:51:44.950091 6767 master.cpp:5861] Authorized registration of age= nt at slave(393)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.950207 6767 master.cpp:5954] Registering agent at slave(393= )@172.17.0.2:46604 (080f3c58946f) with id 93acd52d-cfec-4c64-a368-d07dfebea= 5f2-S0 I1013 23:51:44.950558 6775 registrar.cpp:495] Applied 1 operations in 8202= 8ns; attempting to update the registry I1013 23:51:44.951200 6779 registrar.cpp:552] Successfully updated the reg= istry in 551168ns I1013 23:51:44.951483 6763 master.cpp:6001] Admitted agent 93acd52d-cfec-4= c64-a368-d07dfebea5f2-S0 at slave(393)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.952137 6773 slave.cpp:4966] Received ping from slave-observ= er(399)@172.17.0.2:46604 I1013 23:51:44.952407 6773 slave.cpp:1193] Registered with master master@1= 72.17.0.2:46604; given agent ID 93acd52d-cfec-4c64-a368-d07dfebea5f2-S0 I1013 23:51:44.952188 6763 master.cpp:6032] Registered agent 93acd52d-cfec= -4c64-a368-d07dfebea5f2-S0 at slave(393)@172.17.0.2:46604 (080f3c58946f) wi= th [{"name":"cpus","scalar":{"value":2.0},"type":"SCALAR"},{"name":"mem","s= calar":{"value":1024.0},"type":"SCALAR"},{"name":"disk","scalar":{"value":1= 024.0},"type":"SCALAR"},{"name":"ports","ranges":{"range":[{"begin":31000,"= end":32000}]},"type":"RANGES"}] I1013 23:51:44.952540 6772 status_update_manager.cpp:184] Resuming sending= status updates I1013 23:51:44.952641 6784 hierarchical.cpp:593] Added agent 93acd52d-cfec= -4c64-a368-d07dfebea5f2-S0 (080f3c58946f) with cpus:2; mem:1024; disk:1024;= ports:[31000-32000] (allocated: {}) I1013 23:51:44.952793 6773 slave.cpp:1213] Checkpointing SlaveInfo to '/tm= p/ReservationEndpointsTest_NoHeader_0sO1nu/meta/slaves/93acd52d-cfec-4c64-a= 368-d07dfebea5f2-S0/slave.info' I1013 23:51:44.952898 6784 hierarchical.cpp:1943] No allocations performed I1013 23:51:44.952961 6784 hierarchical.cpp:1486] Performed allocation for= 1 agents in 187802ns I1013 23:51:44.953217 6773 slave.cpp:1262] Forwarding total oversubscribed= resources {} I1013 23:51:44.953413 6761 master.cpp:6817] Received update of agent 93acd= 52d-cfec-4c64-a368-d07dfebea5f2-S0 at slave(393)@172.17.0.2:46604 (080f3c58= 946f) with total oversubscribed resources {} I1013 23:51:44.953598 6761 master.cpp:6828] Ignoring update on agent 93acd= 52d-cfec-4c64-a368-d07dfebea5f2-S0 at slave(393)@172.17.0.2:46604 (080f3c58= 946f) as it reports no changes I1013 23:51:44.956408 6767 process.cpp:3929] Handling HTTP event for proce= ss 'master' with path: '/master/reserve' I1013 23:51:44.960872 6783 process.cpp:3929] Handling HTTP event for proce= ss 'master' with path: '/master/unreserve' I1013 23:51:44.963680 6784 slave.cpp:869] Agent terminating I1013 23:51:44.963943 6777 master.cpp:1303] Agent 93acd52d-cfec-4c64-a368-= d07dfebea5f2-S0 at slave(393)@172.17.0.2:46604 (080f3c58946f) disconnected I1013 23:51:44.963977 6777 master.cpp:3336] Disconnecting agent 93acd52d-c= fec-4c64-a368-d07dfebea5f2-S0 at slave(393)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.964139 6777 master.cpp:3355] Deactivating agent 93acd52d-cf= ec-4c64-a368-d07dfebea5f2-S0 at slave(393)@172.17.0.2:46604 (080f3c58946f) I1013 23:51:44.964375 6771 hierarchical.cpp:690] Agent 93acd52d-cfec-4c64-= a368-d07dfebea5f2-S0 deactivated I1013 23:51:44.965267 6772 hierarchical.cpp:1943] No allocations performed I1013 23:51:44.965327 6772 hierarchical.cpp:1486] Performed allocation for= 1 agents in 201569ns PC: @ 0x2b5a998b3bc0 process::EventQueue::Consumer::empty() *** SIGSEGV (@0x8) received by PID 6760 (TID 0x2b5aa6422700) from PID 8; st= ack trace: *** @ 0x2b5aaaa21af7 (unknown) @ 0x2b5aaaa26449 (unknown) @ 0x2b5a9c686330 (unknown) I1013 23:51:44.978029 6765 master.cpp:1145] Master terminating I1013 23:51:44.978888 6779 hierarchical.cpp:626] Removed agent 93acd52d-cf= ec-4c64-a368-d07dfebea5f2-S0 [ OK ] ReservationEndpointsTest.NoHeader (78 ms) [ RUN ] ReservationEndpointsTest.BadCredentials I1013 23:51:44.988415 6760 cluster.cpp:162] Creating default 'local' autho= rizer I1013 23:51:44.991685 6767 master.cpp:445] Master 4c90ee68-8301-4d40-87a4-= 31d4fdac8152 (080f3c58946f) started on 172.17.0.2:46604 I1013 23:51:44.991749 6767 master.cpp:447] Flags at startup: --acls=3D"" -= -agent_ping_timeout=3D"15secs" --agent_reregister_timeout=3D"10mins" --allo= cation_interval=3D"50ms" --allocator=3D"HierarchicalDRF" --authenticate_age= nts=3D"true" --authenticate_frameworks=3D"true" --authenticate_http_framewo= rks=3D"true" --authenticate_http_readonly=3D"true" --authenticate_http_read= write=3D"true" --authenticators=3D"crammd5" --authorizers=3D"local" --crede= ntials=3D"/tmp/mFzOpA/credentials" --filter_gpu_resources=3D"true" --framew= ork_sorter=3D"drf" --help=3D"false" --hostname_lookup=3D"true" --http_authe= nticators=3D"basic" --http_framework_authenticators=3D"basic" --initialize_= driver_logging=3D"true" --log_auto_initialize=3D"true" --logbufsecs=3D"0" -= -logging_level=3D"INFO" --max_agent_ping_timeouts=3D"5" --max_completed_fra= meworks=3D"50" --max_completed_tasks_per_framework=3D"1000" --max_unreachab= le_tasks_per_framework=3D"1000" --port=3D"5050" --quiet=3D"false" --recover= y_agent_removal_limit=3D"100%" --registry=3D"in_memory" --registry_fetch_ti= meout=3D"1mins" --registry_gc_interval=3D"15mins" --registry_max_agent_age= =3D"2weeks" --registry_max_agent_count=3D"102400" --registry_store_timeout= =3D"100secs" --registry_strict=3D"false" --roles=3D"role" --root_submission= s=3D"true" --user_sorter=3D"drf" --version=3D"false" --webui_dir=3D"/mesos/= mesos-1.5.0/_inst/share/mesos/webui" --work_dir=3D"/tmp/mFzOpA/master" --zk= _session_timeout=3D"10secs" I1013 23:51:44.992168 6767 master.cpp:496] Master only allowing authentica= ted frameworks to register I1013 23:51:44.992184 6767 master.cpp:502] Master only allowing authentica= ted agents to register I1013 23:51:44.992195 6767 master.cpp:508] Master only allowing authentica= ted HTTP frameworks to register I1013 23:51:44.992208 6767 credentials.hpp:37] Loading credentials for aut= hentication from '/tmp/mFzOpA/credentials' I1013 23:51:44.992601 6767 master.cpp:552] Using default 'crammd5' authent= icator I1013 23:51:44.992825 6767 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-readonly' I1013 23:51:44.993018 6767 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-readwrite' I1013 23:51:44.993167 6767 http.cpp:1045] Creating default 'basic' HTTP au= thenticator for realm 'mesos-master-scheduler' I1013 23:51:44.993330 6767 master.cpp:631] Authorization enabled W1013 23:51:44.993356 6767 master.cpp:694] The '--roles' flag is deprecate= d. This flag will be removed in the future. See the Mesos 0.27 upgrade note= s for more information I1013 23:51:44.993580 6783 hierarchical.cpp:171] Initialized hierarchical = allocator process I1013 23:51:44.993702 6773 whitelist_watcher.cpp:77] No whitelist given I1013 23:51:44.996886 6766 master.cpp:2198] Elected as the leading master! I1013 23:51:44.996927 6766 master.cpp:1687] Recovering from registrar I1013 23:51:44.997068 6775 registrar.cpp:347] Recovering registrar I1013 23:51:44.997629 6775 registrar.cpp:391] Successfully fetched the reg= istry (0B) in 519168ns I1013 23:51:44.997750 6775 registrar.cpp:495] Applied 1 operations in 4374= 2ns; attempting to update the registry I1013 23:51:44.998252 6775 registrar.cpp:552] Successfully updated the reg= istry in 442880ns I1013 23:51:44.998375 6775 registrar.cpp:424] Successfully recovered regis= trar I1013 23:51:44.998695 6780 master.cpp:1791] Recovered 0 agents from the re= gistry (129B); allowing 10mins for agents to re-register I1013 23:51:44.998767 6774 hierarchical.cpp:209] Skipping recovery of hier= archical allocator: nothing to recover @ 0x2b5a998b3bc0 process::EventQueue::Consumer::empty() @ 0x2b5a998975d9 process::ProcessManager::resume() @ 0x2b5a998a75a1 process::ProcessManager::init_threads()::$_9::oper= ator()() @ 0x2b5a998a74e5 _ZNSt12_Bind_simpleIFZN7process14ProcessManager12i= nit_threadsEvE3$_9vEE9_M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE @ 0x2b5a998a74b5 std::_Bind_simple<>::operator()() @ 0x2b5a998a748c std::thread::_Impl<>::_M_run() @ 0x2b5a9bf07a60 (unknown) @ 0x2b5a9c67e184 start_thread @ 0x2b5a9c991ffd (unknown) make[4]: *** [check-local] Segmentation fault (core dumped) make[4]: Leaving directory `/mesos/mesos-1.5.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-1.5.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-1.5.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-1.5.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1507935822-28430 Untagged: mesos-1507935822-28430:latest Deleted: sha256:e3648af78b3e6ae26ffc3a266602e67b3f121d44252fe477e98f22e941c= bea22 Deleted: sha256:67502ccce924895955501bf522d308e7b8a5b5ed3c2e098b769ddb0973c= 00da2 Deleted: sha256:cda1b97132213c19abf4e36d655b08c0a5977ce1687af8937aad1a75e97= 39dea Deleted: sha256:70cfb41bfcd41237a1b90dd607b92f4e8ab93dd5fbbffb67c4b80993266= e3044 Deleted: sha256:2d85a6c5326bb016b871d4c31cba136c3b3d37e5d1064e45cd569c750d6= e4763 Deleted: sha256:947923828be8850bb9e77a3a75d0785b3ff69aa6c791f7221155cb4bbbd= 0e59f Deleted: sha256:37dc5c6d71792b1f7fe5ba43fdc35235ecab10e8a2b1c81e0a139cdcc08= aec8f Deleted: sha256:809fb6a61bfa8344467b978dce391d7ed289458867962008a445f06ccf9= c0fd2 Deleted: sha256:660ed53c0e1c2095dfe0fdea683f4b608d611e84503169d527022edbda8= 19056 Deleted: sha256:dba9107e59ffb2c89b5eeb2063b6f94a23ece00eb3fcf2ef9008ab4169a= 82819 Deleted: sha256:894dee45caa61d03951a882ebc20017ab6773c782029a9445453e85d34e= d46ab Deleted: sha256:8d38c686d807f328f2e4e2e8767e672a9d81d90362e4696542d720cf266= 071ce Deleted: sha256:8b713c9ccf97ab36fa018fca7e65564f5c3f674b17f5c2fc3af3928372f= bbb64 Deleted: sha256:5d6012cbea2918e6eabd46e5ad3604f3f16c999b7fec56f9d98ffab01e8= 6ac0d Deleted: sha256:5460be57e9ed899bbfa9529ae6646d3339743e50139366a41686b24d147= aa4c8 Deleted: sha256:89a91845cad7e48476595505e0b86bea21023961b7ff2111cba15ff0260= f6ae4 Deleted: sha256:0ccda72d3206c6909e5caa5b1d3d6e1f687181303a5b4e6df2c9f93f9f1= eae4b Deleted: sha256:164ede1d611f70958f0edfd9083b9d9442c510fb31f8c1362d0b334c100= 90326 Deleted: sha256:bcbc419250468ebc4da555f003d55f99f9a6b780338a8213c854b7eee44= 5b8de Deleted: sha256:2d516c80f202b24fa6e0470bc3780f95c6c6a8e812826c53de6af400e02= 4da9d Deleted: sha256:4fcfbb967b85ad0b4baddedaead1edb94e53baab873f7f9febc6753bff2= 9d68c Deleted: sha256:5f838efb36a520edcfe5580dcc32e70db7d521f2f3fbb00a2598a7bb768= c36ac Deleted: sha256:eded8f8bb2951b9edee99f1b0eecb6e0240d0485a7ccbaf738f9b4d2d65= e9c6a Deleted: sha256:5d69a5a4a2948c85d5af94db7de0f123521b6a8b13812a4985a39b0a7dc= 4a3e6 Deleted: sha256:04982242e2df871d105d41f72f5f89a4b56f005b92f191f7506211379b3= 47b1d Deleted: sha256:d94f4e78a9bda4f38e97c3c1623ea7ae8f3475a8c4f0764ca58f1156ffd= 5ebdf Deleted: sha256:3ffbdd49852c7de75acfd28b7b159ad875d1cbe7412690774b07e3c89b7= 0bf2d Deleted: sha256:099d167d1d65d2d79750169fc329d43cffa723c41ddeed8df77feb25297= a420e Build step 'Execute shell' marked build as failure