Return-Path: X-Original-To: apmail-mesos-builds-archive@minotaur.apache.org Delivered-To: apmail-mesos-builds-archive@minotaur.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 4083B1849B for ; Mon, 29 Feb 2016 02:16:10 +0000 (UTC) Received: (qmail 91301 invoked by uid 500); 29 Feb 2016 02:16:05 -0000 Delivered-To: apmail-mesos-builds-archive@mesos.apache.org Received: (qmail 91269 invoked by uid 500); 29 Feb 2016 02:16:05 -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 91251 invoked by uid 99); 29 Feb 2016 02:16:04 -0000 Received: from crius.apache.org (HELO crius.apache.org) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 29 Feb 2016 02:16:04 +0000 Received: from crius.apache.org (localhost [127.0.0.1]) by crius.apache.org (ASF Mail Server at crius.apache.org) with ESMTP id B07C09C013F; Mon, 29 Feb 2016 02:16:03 +0000 (UTC) Date: Mon, 29 Feb 2016 02:16:03 +0000 (UTC) From: Apache Jenkins Server To: builds@mesos.apache.org, yujie.jay@gmail.com Message-ID: <1265729449.947.1456712163569.JavaMail.jenkins@crius> In-Reply-To: <1541630318.919.1456705484808.JavaMail.jenkins@crius> References: <1541630318.919.1456705484808.JavaMail.jenkins@crius> Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_Mesos_=C2=BB_gcc,-?= =?UTF-8?Q?-verbose_--enable-libevent_--enable-ss?= =?UTF-8?Q?l,GLOG=5Fv=3D1_MESOS=5FVERBOSE=3D1,ubuntu:14.0?= =?UTF-8?Q?4,(docker||Hadoop)&&(!ubuntu-us1)_#1743?= 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/COMPILER=gcc,CONFIGURATION=--verbose --enable-libevent --enable-ssl,ENVIRONMENT=GLOG_v=1 MESOS_VERBOSE=1,OS=ubuntu:14.04,label_exp=(docker||Hadoop)&&(!ubuntu-us1) X-Jenkins-Result: FAILURE See Changes: [yujie.jay] Added overlayfs provisioning backend. ------------------------------------------ [...truncated 170663 lines...] I0229 02:15:56.107776 2152 recover.cpp:447] Starting replica recovery I0229 02:15:56.108957 2152 recover.cpp:473] Replica is in EMPTY status I0229 02:15:56.110283 2143 replica.cpp:673] Replica in EMPTY status receiv= ed a broadcasted recover request from (14982)@172.17.0.3:36786 I0229 02:15:56.110687 2152 recover.cpp:193] Received a recover response fr= om a replica in EMPTY status I0229 02:15:56.111274 2143 recover.cpp:564] Updating replica status to STA= RTING I0229 02:15:56.114994 2147 master.cpp:375] Master 0c40dfa6-9846-4328-b50c-= c73c3f5eca42 (3036a6611147) started on 172.17.0.3:36786 I0229 02:15:56.115144 2147 master.cpp:377] Flags at startup: --acls=3D"" -= -allocation_interval=3D"1secs" --allocator=3D"HierarchicalDRF" --authentica= te=3D"false" --authenticate_http=3D"true" --authenticate_slaves=3D"true" --= authenticators=3D"crammd5" --authorizers=3D"local" --credentials=3D"/tmp/YA= Dhh3/credentials" --framework_sorter=3D"drf" --help=3D"false" --hostname_lo= okup=3D"true" --http_authenticators=3D"basic" --initialize_driver_logging= =3D"true" --log_auto_initialize=3D"true" --logbufsecs=3D"0" --logging_level= =3D"INFO" --max_completed_frameworks=3D"50" --max_completed_tasks_per_frame= work=3D"1000" --max_slave_ping_timeouts=3D"5" --quiet=3D"false" --recovery_= slave_removal_limit=3D"100%" --registry=3D"replicated_log" --registry_fetch= _timeout=3D"1mins" --registry_store_timeout=3D"100secs" --registry_strict= =3D"true" --root_submissions=3D"true" --slave_ping_timeout=3D"15secs" --sla= ve_reregister_timeout=3D"10mins" --user_sorter=3D"drf" --version=3D"false" = --webui_dir=3D"/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir=3D"/= tmp/YADhh3/master" --zk_session_timeout=3D"10secs" I0229 02:15:56.115700 2147 master.cpp:424] Master allowing unauthenticated= frameworks to register I0229 02:15:56.115768 2147 master.cpp:427] Master only allowing authentica= ted slaves to register I0229 02:15:56.115854 2147 credentials.hpp:35] Loading credentials for aut= hentication from '/tmp/YADhh3/credentials' I0229 02:15:56.116323 2147 master.cpp:467] Using default 'crammd5' authent= icator I0229 02:15:56.116578 2147 master.cpp:536] Using default 'basic' HTTP auth= enticator I0229 02:15:56.116839 2147 master.cpp:570] Authorization enabled I0229 02:15:56.117327 2153 hierarchical.cpp:144] Initialized hierarchical = allocator process I0229 02:15:56.117478 2153 whitelist_watcher.cpp:77] No whitelist given I0229 02:15:56.120146 2147 master.cpp:1711] The newly elected leader is ma= ster@172.17.0.3:36786 with id 0c40dfa6-9846-4328-b50c-c73c3f5eca42 I0229 02:15:56.122109 2147 master.cpp:1724] Elected as the leading master! I0229 02:15:56.122205 2147 master.cpp:1469] Recovering from registrar I0229 02:15:56.122541 2147 registrar.cpp:307] Recovering registrar I0229 02:15:56.146335 2156 leveldb.cpp:304] Persisting metadata (8 bytes) = to leveldb took 34.030381ms I0229 02:15:56.146423 2156 replica.cpp:320] Persisted replica status to ST= ARTING I0229 02:15:56.146739 2143 recover.cpp:473] Replica is in STARTING status I0229 02:15:56.148308 2147 replica.cpp:673] Replica in STARTING status rec= eived a broadcasted recover request from (14984)@172.17.0.3:36786 I0229 02:15:56.148645 2143 recover.cpp:193] Received a recover response fr= om a replica in STARTING status I0229 02:15:56.149184 2146 recover.cpp:564] Updating replica status to VOT= ING I0229 02:15:56.185286 2146 leveldb.cpp:304] Persisting metadata (8 bytes) = to leveldb took 35.831466ms I0229 02:15:56.185371 2146 replica.cpp:320] Persisted replica status to VO= TING I0229 02:15:56.185585 2146 recover.cpp:578] Successfully joined the Paxos = group I0229 02:15:56.185789 2146 recover.cpp:462] Recover process terminated I0229 02:15:56.186553 2146 log.cpp:659] Attempting to start the writer I0229 02:15:56.188200 2146 replica.cpp:493] Replica received implicit prom= ise request from (14985)@172.17.0.3:36786 with proposal 1 I0229 02:15:56.227095 2146 leveldb.cpp:304] Persisting metadata (8 bytes) = to leveldb took 38.742551ms I0229 02:15:56.227187 2146 replica.cpp:342] Persisted promised to 1 I0229 02:15:56.228185 2146 coordinator.cpp:238] Coordinator attempting to = fill missing positions I0229 02:15:56.229981 2143 replica.cpp:388] Replica received explicit prom= ise request from (14986)@172.17.0.3:36786 for position 0 with proposal 2 I0229 02:15:56.277256 2143 leveldb.cpp:341] Persisting action (8 bytes) to= leveldb took 47.042383ms I0229 02:15:56.277346 2143 replica.cpp:712] Persisted action at 0 I0229 02:15:56.279191 2143 replica.cpp:537] Replica received write request= for position 0 from (14987)@172.17.0.3:36786 I0229 02:15:56.279315 2143 leveldb.cpp:436] Reading position from leveldb = took 51156ns I0229 02:15:56.319020 2143 leveldb.cpp:341] Persisting action (14 bytes) t= o leveldb took 39.626848ms I0229 02:15:56.319109 2143 replica.cpp:712] Persisted action at 0 I0229 02:15:56.332270 2151 replica.cpp:691] Replica received learned notic= e for position 0 from @0.0.0.0:0 I0229 02:15:56.369140 2151 leveldb.cpp:341] Persisting action (16 bytes) t= o leveldb took 36.677916ms I0229 02:15:56.369245 2151 replica.cpp:712] Persisted action at 0 I0229 02:15:56.369283 2151 replica.cpp:697] Replica learned NOP action at = position 0 I0229 02:15:56.370585 2151 log.cpp:675] Writer started with ending positio= n 0 I0229 02:15:56.372695 2148 leveldb.cpp:436] Reading position from leveldb = took 84986ns I0229 02:15:56.374394 2148 registrar.cpp:340] Successfully fetched the reg= istry (0B) in 251.7888ms I0229 02:15:56.374871 2148 registrar.cpp:439] Applied 1 operations in 1075= 37ns; attempting to update the 'registry' I0229 02:15:56.375970 2148 log.cpp:683] Attempting to append 170 bytes to = the log I0229 02:15:56.376313 2157 coordinator.cpp:348] Coordinator attempting to = write APPEND action at position 1 I0229 02:15:56.378511 2147 replica.cpp:537] Replica received write request= for position 1 from (14988)@172.17.0.3:36786 I0229 02:15:56.419292 2147 leveldb.cpp:341] Persisting action (189 bytes) = to leveldb took 40.709499ms I0229 02:15:56.419378 2147 replica.cpp:712] Persisted action at 1 I0229 02:15:56.420531 2147 replica.cpp:691] Replica received learned notic= e for position 1 from @0.0.0.0:0 I0229 02:15:56.454614 2147 leveldb.cpp:341] Persisting action (191 bytes) = to leveldb took 33.965705ms I0229 02:15:56.454699 2147 replica.cpp:712] Persisted action at 1 I0229 02:15:56.454731 2147 replica.cpp:697] Replica learned APPEND action = at position 1 I0229 02:15:56.456099 2145 registrar.cpp:484] Successfully updated the 're= gistry' in 81.080064ms I0229 02:15:56.456251 2145 registrar.cpp:370] Successfully recovered regis= trar I0229 02:15:56.456346 2147 log.cpp:702] Attempting to truncate the log to = 1 I0229 02:15:56.456657 2145 master.cpp:1521] Recovered 0 slaves from the Re= gistry (131B) ; allowing 10mins for slaves to re-register I0229 02:15:56.456748 2147 coordinator.cpp:348] Coordinator attempting to = write TRUNCATE action at position 2 I0229 02:15:56.456841 2145 hierarchical.cpp:171] Skipping recovery of hier= archical allocator: nothing to recover I0229 02:15:56.457914 2157 replica.cpp:537] Replica received write request= for position 2 from (14989)@172.17.0.3:36786 I0229 02:15:56.496408 2157 leveldb.cpp:341] Persisting action (16 bytes) t= o leveldb took 38.428996ms I0229 02:15:56.496493 2157 replica.cpp:712] Persisted action at 2 I0229 02:15:56.497864 2157 replica.cpp:691] Replica received learned notic= e for position 2 from @0.0.0.0:0 I0229 02:15:56.529799 2157 leveldb.cpp:341] Persisting action (18 bytes) t= o leveldb took 31.888648ms I0229 02:15:56.529942 2157 leveldb.cpp:399] Deleting ~1 keys from leveldb = took 67688ns I0229 02:15:56.529966 2157 replica.cpp:712] Persisted action at 2 I0229 02:15:56.529997 2157 replica.cpp:697] Replica learned TRUNCATE actio= n at position 2 I0229 02:15:56.539132 2124 scheduler.cpp:172] Version: 0.28.0 I0229 02:15:56.540165 2145 scheduler.cpp:432] New master detected at maste= r@172.17.0.3:36786 I0229 02:15:56.542012 2157 scheduler.cpp:332] Connected with the master at= http://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:56.543699 2146 scheduler.cpp:230] Sending SUBSCRIBE call to ht= tp://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:56.544775 2146 process.cpp:3141] Handling HTTP event for proce= ss 'master' with path: '/master/api/v1/scheduler' I0229 02:15:56.545267 2157 http.cpp:311] HTTP POST for /master/api/v1/sche= duler from 172.17.0.3:33996 I0229 02:15:56.545460 2157 master.cpp:1973] Received subscription request = for HTTP framework 'default' I0229 02:15:56.545526 2157 master.cpp:1750] Authorizing framework principa= l 'test-principal' to receive offers for role '*' I0229 02:15:56.545902 2154 master.cpp:2064] Subscribing framework 'default= ' with checkpointing disabled and capabilities [ ] I0229 02:15:56.546641 2154 hierarchical.cpp:265] Added framework 0c40dfa6-= 9846-4328-b50c-c73c3f5eca42-0000 I0229 02:15:56.546746 2154 hierarchical.cpp:1437] No resources available t= o allocate! I0229 02:15:56.546790 2154 hierarchical.cpp:1532] No inverse offers to sen= d out! I0229 02:15:56.546818 2154 hierarchical.cpp:1130] Performed allocation for= 0 slaves in 148599ns I0229 02:15:56.547363 2157 master.hpp:1657] Sending heartbeat to 0c40dfa6-= 9846-4328-b50c-c73c3f5eca42-0000 I0229 02:15:56.548665 2157 scheduler.cpp:602] Enqueuing event SUBSCRIBED r= eceived from http://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:56.549109 2157 scheduler.cpp:602] Enqueuing event HEARTBEAT re= ceived from http://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:56.551059 2157 master_maintenance_tests.cpp:177] Ignoring HEAR= TBEAT event I0229 02:15:56.552098 2156 scheduler.cpp:230] Sending REQUEST call to http= ://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:56.553340 2157 process.cpp:3141] Handling HTTP event for proce= ss 'master' with path: '/master/api/v1/scheduler' I0229 02:15:56.554039 2157 http.cpp:311] HTTP POST for /master/api/v1/sche= duler from 172.17.0.3:33997 I0229 02:15:56.554322 2157 master.cpp:2718] Processing REQUEST call for fr= amework 0c40dfa6-9846-4328-b50c-c73c3f5eca42-0000 (default) I0229 02:15:56.554713 2157 hierarchical.cpp:589] Received resource request= from framework 0c40dfa6-9846-4328-b50c-c73c3f5eca42-0000 I0229 02:15:56.555743 2124 master.cpp:1026] Master terminating I0229 02:15:56.557487 2157 hierarchical.cpp:326] Removed framework 0c40dfa= 6-9846-4328-b50c-c73c3f5eca42-0000 E0229 02:15:56.558192 2157 scheduler.cpp:575] End-Of-File received from ma= ster. The master closed the event stream I0229 02:15:56.561866 2157 scheduler.cpp:414] Re-detecting master I0229 02:15:56.562177 2157 scheduler.cpp:365] Ignoring disconnection attem= pt from stale connection I0229 02:15:56.562233 2157 scheduler.cpp:365] Ignoring disconnection attem= pt from stale connection I0229 02:15:56.562350 2157 scheduler.cpp:432] New master detected at maste= r@172.17.0.3:36786 [ OK ] ContentType/SchedulerTest.Request/0 (582 ms) [ RUN ] ContentType/SchedulerTest.Request/1 I0229 02:15:56.687436 2124 leveldb.cpp:174] Opened db in 120.062306ms I0229 02:15:56.712635 2124 leveldb.cpp:181] Compacted db in 25.119414ms I0229 02:15:56.712725 2124 leveldb.cpp:196] Created db iterator in 25078ns I0229 02:15:56.712743 2124 leveldb.cpp:202] Seeked to beginning of db in 2= 847ns I0229 02:15:56.712754 2124 leveldb.cpp:271] Iterated through 0 keys in the= db in 312ns I0229 02:15:56.712805 2124 replica.cpp:779] Replica recovered with log pos= itions 0 -> 0 with 1 holes and 0 unlearned I0229 02:15:56.713627 2154 recover.cpp:447] Starting replica recovery I0229 02:15:56.713923 2154 recover.cpp:473] Replica is in EMPTY status I0229 02:15:56.716002 2154 replica.cpp:673] Replica in EMPTY status receiv= ed a broadcasted recover request from (14997)@172.17.0.3:36786 I0229 02:15:56.716269 2150 recover.cpp:193] Received a recover response fr= om a replica in EMPTY status I0229 02:15:56.716969 2150 recover.cpp:564] Updating replica status to STA= RTING I0229 02:15:56.719297 2148 master.cpp:375] Master 4dc1c07c-7566-4414-922d-= 75fe3c4943a8 (3036a6611147) started on 172.17.0.3:36786 I0229 02:15:56.719329 2148 master.cpp:377] Flags at startup: --acls=3D"" -= -allocation_interval=3D"1secs" --allocator=3D"HierarchicalDRF" --authentica= te=3D"false" --authenticate_http=3D"true" --authenticate_slaves=3D"true" --= authenticators=3D"crammd5" --authorizers=3D"local" --credentials=3D"/tmp/EW= Qyd9/credentials" --framework_sorter=3D"drf" --help=3D"false" --hostname_lo= okup=3D"true" --http_authenticators=3D"basic" --initialize_driver_logging= =3D"true" --log_auto_initialize=3D"true" --logbufsecs=3D"0" --logging_level= =3D"INFO" --max_completed_frameworks=3D"50" --max_completed_tasks_per_frame= work=3D"1000" --max_slave_ping_timeouts=3D"5" --quiet=3D"false" --recovery_= slave_removal_limit=3D"100%" --registry=3D"replicated_log" --registry_fetch= _timeout=3D"1mins" --registry_store_timeout=3D"100secs" --registry_strict= =3D"true" --root_submissions=3D"true" --slave_ping_timeout=3D"15secs" --sla= ve_reregister_timeout=3D"10mins" --user_sorter=3D"drf" --version=3D"false" = --webui_dir=3D"/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir=3D"/= tmp/EWQyd9/master" --zk_session_timeout=3D"10secs" I0229 02:15:56.719744 2148 master.cpp:424] Master allowing unauthenticated= frameworks to register I0229 02:15:56.719758 2148 master.cpp:427] Master only allowing authentica= ted slaves to register I0229 02:15:56.719766 2148 credentials.hpp:35] Loading credentials for aut= hentication from '/tmp/EWQyd9/credentials' I0229 02:15:56.720166 2148 master.cpp:467] Using default 'crammd5' authent= icator I0229 02:15:56.720355 2148 master.cpp:536] Using default 'basic' HTTP auth= enticator I0229 02:15:56.720502 2148 master.cpp:570] Authorization enabled I0229 02:15:56.721393 2154 hierarchical.cpp:144] Initialized hierarchical = allocator process I0229 02:15:56.721470 2154 whitelist_watcher.cpp:77] No whitelist given I0229 02:15:56.723130 2148 master.cpp:1711] The newly elected leader is ma= ster@172.17.0.3:36786 with id 4dc1c07c-7566-4414-922d-75fe3c4943a8 I0229 02:15:56.723165 2148 master.cpp:1724] Elected as the leading master! I0229 02:15:56.723186 2148 master.cpp:1469] Recovering from registrar I0229 02:15:56.723338 2154 registrar.cpp:307] Recovering registrar I0229 02:15:56.754322 2156 leveldb.cpp:304] Persisting metadata (8 bytes) = to leveldb took 37.026888ms I0229 02:15:56.754415 2156 replica.cpp:320] Persisted replica status to ST= ARTING I0229 02:15:56.754830 2156 recover.cpp:473] Replica is in STARTING status I0229 02:15:56.757160 2156 replica.cpp:673] Replica in STARTING status rec= eived a broadcasted recover request from (14999)@172.17.0.3:36786 I0229 02:15:56.757570 2155 recover.cpp:193] Received a recover response fr= om a replica in STARTING status I0229 02:15:56.758131 2155 recover.cpp:564] Updating replica status to VOT= ING I0229 02:15:56.796151 2155 leveldb.cpp:304] Persisting metadata (8 bytes) = to leveldb took 37.813514ms I0229 02:15:56.796249 2155 replica.cpp:320] Persisted replica status to VO= TING I0229 02:15:56.796541 2152 recover.cpp:578] Successfully joined the Paxos = group I0229 02:15:56.796758 2152 recover.cpp:462] Recover process terminated I0229 02:15:56.797389 2151 log.cpp:659] Attempting to start the writer I0229 02:15:56.798993 2152 replica.cpp:493] Replica received implicit prom= ise request from (15000)@172.17.0.3:36786 with proposal 1 I0229 02:15:56.837910 2152 leveldb.cpp:304] Persisting metadata (8 bytes) = to leveldb took 38.855827ms I0229 02:15:56.837998 2152 replica.cpp:342] Persisted promised to 1 I0229 02:15:56.838883 2153 coordinator.cpp:238] Coordinator attempting to = fill missing positions I0229 02:15:56.840648 2150 replica.cpp:388] Replica received explicit prom= ise request from (15001)@172.17.0.3:36786 for position 0 with proposal 2 I0229 02:15:56.879678 2150 leveldb.cpp:341] Persisting action (8 bytes) to= leveldb took 38.959655ms I0229 02:15:56.879776 2150 replica.cpp:712] Persisted action at 0 I0229 02:15:56.881788 2150 replica.cpp:537] Replica received write request= for position 0 from (15002)@172.17.0.3:36786 I0229 02:15:56.881875 2150 leveldb.cpp:436] Reading position from leveldb = took 47057ns I0229 02:15:56.921463 2150 leveldb.cpp:341] Persisting action (14 bytes) t= o leveldb took 39.515154ms I0229 02:15:56.921561 2150 replica.cpp:712] Persisted action at 0 I0229 02:15:56.922840 2150 replica.cpp:691] Replica received learned notic= e for position 0 from @0.0.0.0:0 I0229 02:15:56.963253 2150 leveldb.cpp:341] Persisting action (16 bytes) t= o leveldb took 40.344929ms I0229 02:15:56.963351 2150 replica.cpp:712] Persisted action at 0 I0229 02:15:56.963392 2150 replica.cpp:697] Replica learned NOP action at = position 0 I0229 02:15:56.964365 2144 log.cpp:675] Writer started with ending positio= n 0 I0229 02:15:56.966162 2152 leveldb.cpp:436] Reading position from leveldb = took 67930ns I0229 02:15:56.967757 2152 registrar.cpp:340] Successfully fetched the reg= istry (0B) in 244.367872ms I0229 02:15:56.967985 2152 registrar.cpp:439] Applied 1 operations in 4526= 0ns; attempting to update the 'registry' I0229 02:15:56.969004 2152 log.cpp:683] Attempting to append 170 bytes to = the log I0229 02:15:56.969205 2144 coordinator.cpp:348] Coordinator attempting to = write APPEND action at position 1 I0229 02:15:56.970360 2144 replica.cpp:537] Replica received write request= for position 1 from (15003)@172.17.0.3:36786 I0229 02:15:57.014338 2144 leveldb.cpp:341] Persisting action (189 bytes) = to leveldb took 43.905773ms I0229 02:15:57.014447 2144 replica.cpp:712] Persisted action at 1 I0229 02:15:57.015732 2157 replica.cpp:691] Replica received learned notic= e for position 1 from @0.0.0.0:0 I0229 02:15:57.055166 2157 leveldb.cpp:341] Persisting action (191 bytes) = to leveldb took 39.369504ms I0229 02:15:57.055281 2157 replica.cpp:712] Persisted action at 1 I0229 02:15:57.055326 2157 replica.cpp:697] Replica learned APPEND action = at position 1 I0229 02:15:57.057488 2143 registrar.cpp:484] Successfully updated the 're= gistry' in 89.366784ms I0229 02:15:57.057651 2143 registrar.cpp:370] Successfully recovered regis= trar I0229 02:15:57.058130 2143 master.cpp:1521] Recovered 0 slaves from the Re= gistry (131B) ; allowing 10mins for slaves to re-register I0229 02:15:57.058296 2154 hierarchical.cpp:171] Skipping recovery of hier= archical allocator: nothing to recover I0229 02:15:57.058799 2144 log.cpp:702] Attempting to truncate the log to = 1 I0229 02:15:57.059187 2148 coordinator.cpp:348] Coordinator attempting to = write TRUNCATE action at position 2 I0229 02:15:57.060894 2148 replica.cpp:537] Replica received write request= for position 2 from (15004)@172.17.0.3:36786 I0229 02:15:57.106336 2148 leveldb.cpp:341] Persisting action (16 bytes) t= o leveldb took 45.29642ms I0229 02:15:57.106438 2148 replica.cpp:712] Persisted action at 2 I0229 02:15:57.113812 2143 replica.cpp:691] Replica received learned notic= e for position 2 from @0.0.0.0:0 I0229 02:15:57.148335 2143 leveldb.cpp:341] Persisting action (18 bytes) t= o leveldb took 34.423604ms I0229 02:15:57.148520 2143 leveldb.cpp:399] Deleting ~1 keys from leveldb = took 91136ns I0229 02:15:57.148550 2143 replica.cpp:712] Persisted action at 2 I0229 02:15:57.148591 2143 replica.cpp:697] Replica learned TRUNCATE actio= n at position 2 I0229 02:15:57.157214 2124 scheduler.cpp:172] Version: 0.28.0 I0229 02:15:57.158874 2143 scheduler.cpp:432] New master detected at maste= r@172.17.0.3:36786 I0229 02:15:57.161413 2154 scheduler.cpp:332] Connected with the master at= http://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:57.163365 2154 scheduler.cpp:230] Sending SUBSCRIBE call to ht= tp://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:57.164963 2149 process.cpp:3141] Handling HTTP event for proce= ss 'master' with path: '/master/api/v1/scheduler' I0229 02:15:57.165591 2149 http.cpp:311] HTTP POST for /master/api/v1/sche= duler from 172.17.0.3:34011 I0229 02:15:57.166049 2149 master.cpp:1973] Received subscription request = for HTTP framework 'default' I0229 02:15:57.166127 2149 master.cpp:1750] Authorizing framework principa= l 'test-principal' to receive offers for role '*' I0229 02:15:57.166829 2149 master.cpp:2064] Subscribing framework 'default= ' with checkpointing disabled and capabilities [ ] I0229 02:15:57.168263 2150 master.hpp:1657] Sending heartbeat to 4dc1c07c-= 7566-4414-922d-75fe3c4943a8-0000 I0229 02:15:57.169301 2149 hierarchical.cpp:265] Added framework 4dc1c07c-= 7566-4414-922d-75fe3c4943a8-0000 I0229 02:15:57.169495 2149 hierarchical.cpp:1437] No resources available t= o allocate! I0229 02:15:57.169647 2149 hierarchical.cpp:1532] No inverse offers to sen= d out! I0229 02:15:57.169767 2149 hierarchical.cpp:1130] Performed allocation for= 0 slaves in 369412ns I0229 02:15:57.170194 2153 scheduler.cpp:602] Enqueuing event SUBSCRIBED r= eceived from http://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:57.170770 2153 scheduler.cpp:602] Enqueuing event HEARTBEAT re= ceived from http://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:57.171830 2143 master_maintenance_tests.cpp:177] Ignoring HEAR= TBEAT event I0229 02:15:57.172364 2155 scheduler.cpp:230] Sending REQUEST call to http= ://172.17.0.3:36786/master/api/v1/scheduler I0229 02:15:57.175287 2158 process.cpp:3141] Handling HTTP event for proce= ss 'master' with path: '/master/api/v1/scheduler' I0229 02:15:57.175928 2158 http.cpp:311] HTTP POST for /master/api/v1/sche= duler from 172.17.0.3:34012 I0229 02:15:57.176409 2158 master.cpp:2718] Processing REQUEST call for fr= amework 4dc1c07c-7566-4414-922d-75fe3c4943a8-0000 (default) I0229 02:15:57.176756 2158 hierarchical.cpp:589] Received resource request= from framework 4dc1c07c-7566-4414-922d-75fe3c4943a8-0000 I0229 02:15:57.178057 2124 master.cpp:1026] Master terminating I0229 02:15:57.178347 2155 hierarchical.cpp:326] Removed framework 4dc1c07= c-7566-4414-922d-75fe3c4943a8-0000 E0229 02:15:57.183257 2151 scheduler.cpp:575] End-Of-File received from ma= ster. The master closed the event stream I0229 02:15:57.195824 2151 scheduler.cpp:414] Re-detecting master I0229 02:15:57.196198 2151 scheduler.cpp:365] Ignoring disconnection attem= pt from stale connection I0229 02:15:57.196741 2151 scheduler.cpp:365] Ignoring disconnection attem= pt from stale connection [ OK ] ContentType/SchedulerTest.Request/1 (635 ms) [----------] 26 tests from ContentType/SchedulerTest (20990 ms total) [----------] Global test environment tear-down [=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D] 990 tests from 126 test cases ran. (851354= ms total) [ PASSED ] 989 tests. [ FAILED ] 1 test, listed below: [ FAILED ] SlaveRecoveryTest/0.RecoverTerminatedExecutor, where TypeParam= =3D mesos::internal::slave::MesosContainerizer 1 FAILED TEST YOU HAVE 6 DISABLED TESTS make[4]: *** [check-local] Error 1 make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-0.28.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1456708251-24367 Untagged: mesos-1456708251-24367:latest Deleted: 6ad3cd1428675136aac9132323c38fdd8c1717b82bbf05d3723162722a9d0878 Deleted: 009cfff16217e070340102fb971578fe7c1a381ffdd84e2d7e376d7e3bb4c112 Deleted: e5cad6dc702f41b11b6a61e4224890bf6c64c26bac96bb8dbbe862aacf63dd82 Deleted: 8a4b16c133b4dee4dd255a80b4c920a7bf5730aa6907b0002b948dc241f0c97c Deleted: 68b3f4b6fe512bb21f29895fc0a9d9dbcff48deb585edaf8cfa0d59ad35f078e Deleted: 2c68dd892f07bf23b23c86559631cb8605ecbbb2aadebb2738295cd7b94b2ded Deleted: a338f0d728320cc6afb8d908a94c9008a4036b196b2be7573836a36bd4d6d564 Deleted: 4929e3828261ee246598c25515433f03366896e7e5615a56494d5292bc6f2ea7 Deleted: 8865d71a15bc555b3ccb477ce63e98219ef3102600983eb2603c04fe04343b4d Deleted: 2eda82b6c60ce4dd1b88abb7e0cc910f9cc3f10af6e20f35a0e366e0f8b8413f Deleted: f35d7ff5ec8e8a309f87c79bcc5fcb6bb4eef4f08bd6d204c997bae29efeb884 Deleted: 1308659f6982c1569f1f7fd40fc108a0ac4f49d99e09165fae6eef41b229cbc7 Deleted: 4c0f50dcf2a0090d78c43d254b91f70f2c3985b1b81d244e3d016264a041d66c Deleted: 9ee206daf313aed0e0972b57dcefbc04852b2e4c5f4c551d49fda8aa06ef835b Deleted: 62be144226b897082c26d99de0aacc7afc67b0bdf63ca2557a2c2cd7ab245c45 Deleted: 983f572045f937f87cc79224b314f7d432463b100318f41ab92ea1a2090a2336 Build step 'Execute shell' marked build as failure