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 665FA194EB for ; Thu, 31 Mar 2016 17:09:54 +0000 (UTC) Received: (qmail 51821 invoked by uid 500); 31 Mar 2016 17:09:54 -0000 Delivered-To: apmail-mesos-builds-archive@mesos.apache.org Received: (qmail 51796 invoked by uid 500); 31 Mar 2016 17:09:54 -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 51784 invoked by uid 99); 31 Mar 2016 17:09:54 -0000 Received: from crius.apache.org (HELO crius.apache.org) (140.211.11.14) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 31 Mar 2016 17:09:54 +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 607879C0220; Thu, 31 Mar 2016 17:09:48 +0000 (UTC) Date: Thu, 31 Mar 2016 17:09:48 +0000 (UTC) From: Apache Jenkins Server To: builds@mesos.apache.org, yujie.jay@gmail.com Message-ID: <317211587.1119.1459444188391.JavaMail.jenkins@crius> Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_Mesos_=C2=BB_clang?= =?UTF-8?Q?,--verbose_--enable-libevent_--enable-s?= =?UTF-8?Q?sl,GLOG=5Fv=3D1_MESOS=5FVERBOSE=3D1,ubuntu:14.0?= =?UTF-8?Q?4,(docker||Hadoop)&&(!ubuntu-us1)_#1914?= 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=clang,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] Add JSON::Object::at() helper API. [yujie.jay] Fix the local Docker puller for private registries. ------------------------------------------ [...truncated 136807 lines...] I0331 17:09:45.320572 1317 master.cpp:427] Master only allowing authentica= ted frameworks to register I0331 17:09:45.320585 1317 master.cpp:432] Master only allowing authentica= ted slaves to register I0331 17:09:45.320595 1317 credentials.hpp:37] Loading credentials for aut= hentication from '/tmp/HoiV0t/credentials' I0331 17:09:45.321111 1317 master.cpp:474] Using default 'crammd5' authent= icator I0331 17:09:45.321318 1317 master.cpp:545] Using default 'basic' HTTP auth= enticator I0331 17:09:45.321517 1317 master.cpp:583] Authorization enabled I0331 17:09:45.321770 1325 hierarchical.cpp:144] Initialized hierarchical = allocator process I0331 17:09:45.321846 1325 whitelist_watcher.cpp:77] No whitelist given I0331 17:09:45.325551 1317 master.cpp:1826] The newly elected leader is ma= ster@172.17.0.2:47264 with id 9b06f6db-72f1-42be-b262-98eb1ddb86e2 I0331 17:09:45.325592 1317 master.cpp:1839] Elected as the leading master! I0331 17:09:45.325604 1317 master.cpp:1526] Recovering from registrar I0331 17:09:45.325875 1331 registrar.cpp:307] Recovering registrar I0331 17:09:45.326637 1331 log.cpp:659] Attempting to start the writer I0331 17:09:45.328130 1324 replica.cpp:493] Replica received implicit prom= ise request from (12032)@172.17.0.2:47264 with proposal 1 I0331 17:09:45.328989 1324 leveldb.cpp:304] Persisting metadata (8 bytes) = to leveldb took 832457ns I0331 17:09:45.329020 1324 replica.cpp:342] Persisted promised to 1 I0331 17:09:45.329779 1329 coordinator.cpp:238] Coordinator attempting to = fill missing positions I0331 17:09:45.332118 1329 replica.cpp:388] Replica received explicit prom= ise request from (12033)@172.17.0.2:47264 for position 0 with proposal 2 I0331 17:09:45.332484 1329 leveldb.cpp:341] Persisting action (8 bytes) to= leveldb took 333132ns I0331 17:09:45.332510 1329 replica.cpp:712] Persisted action at 0 I0331 17:09:45.333724 1318 replica.cpp:537] Replica received write request= for position 0 from (12034)@172.17.0.2:47264 I0331 17:09:45.333792 1318 leveldb.cpp:436] Reading position from leveldb = took 45226ns I0331 17:09:45.334182 1318 leveldb.cpp:341] Persisting action (14 bytes) t= o leveldb took 358530ns I0331 17:09:45.334213 1318 replica.cpp:712] Persisted action at 0 I0331 17:09:45.335052 1318 replica.cpp:691] Replica received learned notic= e for position 0 from @0.0.0.0:0 I0331 17:09:45.335985 1318 leveldb.cpp:341] Persisting action (16 bytes) t= o leveldb took 920454ns I0331 17:09:45.336016 1318 replica.cpp:712] Persisted action at 0 I0331 17:09:45.336040 1318 replica.cpp:697] Replica learned NOP action at = position 0 I0331 17:09:45.336995 1327 log.cpp:675] Writer started with ending positio= n 0 I0331 17:09:45.338444 1319 leveldb.cpp:436] Reading position from leveldb = took 66714ns I0331 17:09:45.339751 1323 registrar.cpp:340] Successfully fetched the reg= istry (0B) in 13.831936ms I0331 17:09:45.339889 1323 registrar.cpp:439] Applied 1 operations in 4517= 8ns; attempting to update the 'registry' I0331 17:09:45.340608 1325 log.cpp:683] Attempting to append 170 bytes to = the log I0331 17:09:45.340701 1319 coordinator.cpp:348] Coordinator attempting to = write APPEND action at position 1 I0331 17:09:45.341581 1320 replica.cpp:537] Replica received write request= for position 1 from (12035)@172.17.0.2:47264 I0331 17:09:45.342003 1320 leveldb.cpp:341] Persisting action (189 bytes) = to leveldb took 395996ns I0331 17:09:45.342036 1320 replica.cpp:712] Persisted action at 1 I0331 17:09:45.343068 1320 replica.cpp:691] Replica received learned notic= e for position 1 from @0.0.0.0:0 I0331 17:09:45.343983 1320 leveldb.cpp:341] Persisting action (191 bytes) = to leveldb took 781249ns I0331 17:09:45.344081 1320 replica.cpp:712] Persisted action at 1 I0331 17:09:45.344189 1320 replica.cpp:697] Replica learned APPEND action = at position 1 I0331 17:09:45.345816 1320 registrar.cpp:484] Successfully updated the 're= gistry' in 5.862144ms I0331 17:09:45.346009 1319 log.cpp:702] Attempting to truncate the log to = 1 I0331 17:09:45.346058 1320 registrar.cpp:370] Successfully recovered regis= trar I0331 17:09:45.346191 1319 coordinator.cpp:348] Coordinator attempting to = write TRUNCATE action at position 2 I0331 17:09:45.347513 1317 replica.cpp:537] Replica received write request= for position 2 from (12036)@172.17.0.2:47264 I0331 17:09:45.347892 1320 master.cpp:1634] Recovered 0 slaves from the Re= gistry (131B) ; allowing 10mins for slaves to re-register I0331 17:09:45.347992 1317 leveldb.cpp:341] Persisting action (16 bytes) t= o leveldb took 434355ns I0331 17:09:45.348021 1317 replica.cpp:712] Persisted action at 2 I0331 17:09:45.348026 1323 hierarchical.cpp:171] Skipping recovery of hier= archical allocator: nothing to recover I0331 17:09:45.348672 1326 replica.cpp:691] Replica received learned notic= e for position 2 from @0.0.0.0:0 I0331 17:09:45.349053 1326 leveldb.cpp:341] Persisting action (18 bytes) t= o leveldb took 362152ns I0331 17:09:45.349113 1326 leveldb.cpp:399] Deleting ~1 keys from leveldb = took 44449ns I0331 17:09:45.349138 1326 replica.cpp:712] Persisted action at 2 I0331 17:09:45.349161 1326 replica.cpp:697] Replica learned TRUNCATE actio= n at position 2 I0331 17:09:45.364617 1331 slave.cpp:200] Slave started on 357)@172.17.0.2= :47264 I0331 17:09:45.364680 1331 slave.cpp:201] Flags at startup: --appc_simple_= discovery_uri_prefix=3D"http://" --appc_store_dir=3D"/tmp/mesos/store/appc"= --authenticate_http=3D"true" --authenticatee=3D"crammd5" --cgroups_cpu_ena= ble_pids_and_tids_count=3D"false" --cgroups_enable_cfs=3D"false" --cgroups_= hierarchy=3D"/sys/fs/cgroup" --cgroups_limit_swap=3D"false" --cgroups_root= =3D"mesos" --container_disk_watch_interval=3D"15secs" --containerizers=3D"m= esos" --credential=3D"/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r= 87Psi/credential" --default_role=3D"*" --disk_watch_interval=3D"1mins" --do= cker=3D"docker" --docker_kill_orphans=3D"true" --docker_registry=3D"https:/= /registry-1.docker.io" --docker_remove_delay=3D"6hrs" --docker_socket=3D"/v= ar/run/docker.sock" --docker_stop_timeout=3D"0ns" --docker_store_dir=3D"/tm= p/mesos/store/docker" --enforce_container_disk_quota=3D"false" --executor_r= egistration_timeout=3D"1mins" --executor_shutdown_grace_period=3D"5secs" --= fetcher_cache_dir=3D"/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r8= 7Psi/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_authenticators=3D"basic" --http_credentia= ls=3D"/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/http_crede= ntials" --image_provisioner_backend=3D"copy" --initialize_driver_logging=3D= "true" --isolation=3D"posix/cpu,posix/mem" --launcher_dir=3D"/mesos/mesos-0= .29.0/_build/src" --logbufsecs=3D"0" --logging_level=3D"INFO" --oversubscri= bed_resources_interval=3D"15secs" --perf_duration=3D"10secs" --perf_interva= l=3D"1mins" --qos_correction_interval_min=3D"0ns" --quiet=3D"false" --recov= er=3D"reconnect" --recovery_timeout=3D"15mins" --registration_backoff_facto= r=3D"10ms" --resources=3D"cpus:2;mem:1024;disk:1024;ports:[31000-32000]" --= revocable_cpu_low_priority=3D"true" --sandbox_directory=3D"/mnt/mesos/sandb= ox" --strict=3D"true" --switch_user=3D"true" --systemd_enable_support=3D"tr= ue" --systemd_runtime_directory=3D"/run/systemd/system" --version=3D"false"= --work_dir=3D"/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi" I0331 17:09:45.365267 1331 credentials.hpp:86] Loading credential for auth= entication from '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi= /credential' I0331 17:09:45.365537 1331 slave.cpp:338] Slave using credential for: test= -principal I0331 17:09:45.365581 1331 credentials.hpp:37] Loading credentials for aut= hentication from '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Ps= i/http_credentials' I0331 17:09:45.365871 1331 slave.cpp:390] Using default 'basic' HTTP authe= nticator I0331 17:09:45.366381 1331 resources.cpp:572] Parsing resources as JSON fa= iled: cpus:2;mem:1024;disk:1024;ports:[31000-32000] Trying semicolon-delimited string format instead I0331 17:09:45.366503 1299 sched.cpp:222] Version: 0.29.0 I0331 17:09:45.366998 1331 slave.cpp:589] Slave resources: cpus(*):2; mem(= *):1024; disk(*):1024; ports(*):[31000-32000] I0331 17:09:45.367086 1331 slave.cpp:597] Slave attributes: [ ] I0331 17:09:45.367101 1331 slave.cpp:602] Slave hostname: 29ba6f7aca97 I0331 17:09:45.367518 1321 sched.cpp:326] New master detected at master@17= 2.17.0.2:47264 I0331 17:09:45.367595 1321 sched.cpp:382] Authenticating with master maste= r@172.17.0.2:47264 I0331 17:09:45.367617 1321 sched.cpp:389] Using default CRAM-MD5 authentic= atee I0331 17:09:45.367931 1321 authenticatee.cpp:121] Creating new client SASL= connection I0331 17:09:45.368347 1321 master.cpp:5679] Authenticating scheduler-750da= fdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264 I0331 17:09:45.368533 1321 authenticator.cpp:413] Starting authentication = session for crammd5_authenticatee(753)@172.17.0.2:47264 I0331 17:09:45.368839 1321 authenticator.cpp:98] Creating new server SASL = connection I0331 17:09:45.369026 1317 state.cpp:57] Recovering state from '/tmp/Slave= Test_RemoveUnregisteredTerminatedExecutor_r87Psi/meta' I0331 17:09:45.369580 1317 authenticatee.cpp:212] Received SASL authentica= tion mechanisms: CRAM-MD5 I0331 17:09:45.369616 1317 authenticatee.cpp:238] Attempting to authentica= te with mechanism 'CRAM-MD5' I0331 17:09:45.369716 1321 authenticator.cpp:203] Received SASL authentica= tion start I0331 17:09:45.369781 1321 authenticator.cpp:325] Authentication requires = more steps I0331 17:09:45.369848 1320 status_update_manager.cpp:200] Recovering statu= s update manager I0331 17:09:45.369868 1321 authenticatee.cpp:258] Received SASL authentica= tion step I0331 17:09:45.370017 1320 authenticator.cpp:231] Received SASL authentica= tion step I0331 17:09:45.370057 1320 auxprop.cpp:107] Request to lookup properties f= or user: 'test-principal' realm: '29ba6f7aca97' server FQDN: '29ba6f7aca97'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: false=20 I0331 17:09:45.370074 1320 auxprop.cpp:179] Looking up auxiliary property = '*userPassword' I0331 17:09:45.370127 1320 auxprop.cpp:179] Looking up auxiliary property = '*cmusaslsecretCRAM-MD5' I0331 17:09:45.370151 1321 slave.cpp:4773] Finished recovery I0331 17:09:45.370156 1320 auxprop.cpp:107] Request to lookup properties f= or user: 'test-principal' realm: '29ba6f7aca97' server FQDN: '29ba6f7aca97'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: true=20 I0331 17:09:45.370182 1320 auxprop.cpp:129] Skipping auxiliary property '*= userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true I0331 17:09:45.370193 1320 auxprop.cpp:129] Skipping auxiliary property '*= cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true I0331 17:09:45.370211 1320 authenticator.cpp:317] Authentication success I0331 17:09:45.370369 1320 authenticatee.cpp:298] Authentication success I0331 17:09:45.370455 1320 master.cpp:5709] Successfully authenticated pri= ncipal 'test-principal' at scheduler-750dafdb-b99a-49b6-883c-3de70916a485@1= 72.17.0.2:47264 I0331 17:09:45.370546 1320 authenticator.cpp:431] Authentication session c= leanup for crammd5_authenticatee(753)@172.17.0.2:47264 I0331 17:09:45.370789 1321 slave.cpp:4945] Querying resource estimator for= oversubscribable resources I0331 17:09:45.371309 1321 status_update_manager.cpp:174] Pausing sending = status updates I0331 17:09:45.371325 1326 slave.cpp:928] New master detected at master@17= 2.17.0.2:47264 I0331 17:09:45.371408 1326 slave.cpp:991] Authenticating with master maste= r@172.17.0.2:47264 I0331 17:09:45.371428 1326 slave.cpp:996] Using default CRAM-MD5 authentic= atee I0331 17:09:45.371578 1326 slave.cpp:964] Detecting new master I0331 17:09:45.371677 1321 authenticatee.cpp:121] Creating new client SASL= connection I0331 17:09:45.372030 1317 master.cpp:5679] Authenticating slave(357)@172.= 17.0.2:47264 I0331 17:09:45.372042 1330 slave.cpp:4959] Received oversubscribable resou= rces from the resource estimator I0331 17:09:45.372134 1330 authenticator.cpp:413] Starting authentication = session for crammd5_authenticatee(754)@172.17.0.2:47264 I0331 17:09:45.372319 1322 authenticator.cpp:98] Creating new server SASL = connection I0331 17:09:45.372548 1321 authenticatee.cpp:212] Received SASL authentica= tion mechanisms: CRAM-MD5 I0331 17:09:45.372582 1321 authenticatee.cpp:238] Attempting to authentica= te with mechanism 'CRAM-MD5' I0331 17:09:45.372684 1322 authenticator.cpp:203] Received SASL authentica= tion start I0331 17:09:45.372746 1322 authenticator.cpp:325] Authentication requires = more steps I0331 17:09:45.372835 1322 authenticatee.cpp:258] Received SASL authentica= tion step I0331 17:09:45.372928 1322 authenticator.cpp:231] Received SASL authentica= tion step I0331 17:09:45.373037 1322 auxprop.cpp:107] Request to lookup properties f= or user: 'test-principal' realm: '29ba6f7aca97' server FQDN: '29ba6f7aca97'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: false=20 I0331 17:09:45.373059 1322 auxprop.cpp:179] Looking up auxiliary property = '*userPassword' I0331 17:09:45.373107 1322 auxprop.cpp:179] Looking up auxiliary property = '*cmusaslsecretCRAM-MD5' I0331 17:09:45.373134 1322 auxprop.cpp:107] Request to lookup properties f= or user: 'test-principal' realm: '29ba6f7aca97' server FQDN: '29ba6f7aca97'= SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_= AUXPROP_AUTHZID: true=20 I0331 17:09:45.373147 1322 auxprop.cpp:129] Skipping auxiliary property '*= userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true I0331 17:09:45.373155 1322 auxprop.cpp:129] Skipping auxiliary property '*= cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true I0331 17:09:45.373173 1322 authenticator.cpp:317] Authentication success I0331 17:09:45.373255 1320 authenticatee.cpp:298] Authentication success I0331 17:09:45.373327 1322 master.cpp:5709] Successfully authenticated pri= ncipal 'test-principal' at slave(357)@172.17.0.2:47264 I0331 17:09:45.373345 1320 authenticator.cpp:431] Authentication session c= leanup for crammd5_authenticatee(754)@172.17.0.2:47264 I0331 17:09:45.373495 1329 sched.cpp:472] Successfully authenticated with = master master@172.17.0.2:47264 I0331 17:09:45.373517 1329 sched.cpp:777] Sending SUBSCRIBE call to master= @172.17.0.2:47264 I0331 17:09:45.373615 1329 sched.cpp:810] Will retry registration in 1.487= 722594secs if necessary I0331 17:09:45.373731 1320 master.cpp:2346] Received SUBSCRIBE call for fr= amework 'default' at scheduler-750dafdb-b99a-49b6-883c-3de70916a485@172.17.= 0.2:47264 I0331 17:09:45.373754 1320 master.cpp:1865] Authorizing framework principa= l 'test-principal' to receive offers for role '*' I0331 17:09:45.374069 1320 master.cpp:2417] Subscribing framework default = with checkpointing disabled and capabilities [ ] I0331 17:09:45.374737 1320 hierarchical.cpp:266] Added framework 9b06f6db-= 72f1-42be-b262-98eb1ddb86e2-0000 I0331 17:09:45.374830 1320 hierarchical.cpp:1490] No resources available t= o allocate! I0331 17:09:45.374868 1320 hierarchical.cpp:1585] No inverse offers to sen= d out! I0331 17:09:45.374927 1320 hierarchical.cpp:1141] Performed allocation for= 0 slaves in 176144ns I0331 17:09:45.375205 1317 sched.cpp:704] Framework registered with 9b06f6= db-72f1-42be-b262-98eb1ddb86e2-0000 I0331 17:09:45.375283 1317 sched.cpp:718] Scheduler::registered took 32966= ns I0331 17:09:45.375393 1322 slave.cpp:1061] Successfully authenticated with= master master@172.17.0.2:47264 I0331 17:09:45.375533 1322 slave.cpp:1457] Will retry registration in 15.1= 82386ms if necessary I0331 17:09:45.375825 1322 master.cpp:4390] Registering slave at slave(357= )@172.17.0.2:47264 (29ba6f7aca97) with id 9b06f6db-72f1-42be-b262-98eb1ddb8= 6e2-S0 I0331 17:09:45.376332 1320 registrar.cpp:439] Applied 1 operations in 8111= 2ns; attempting to update the 'registry' I0331 17:09:45.377719 1322 log.cpp:683] Attempting to append 339 bytes to = the log I0331 17:09:45.377935 1322 coordinator.cpp:348] Coordinator attempting to = write APPEND action at position 3 I0331 17:09:45.379067 1330 replica.cpp:537] Replica received write request= for position 3 from (12042)@172.17.0.2:47264 I0331 17:09:45.379992 1330 leveldb.cpp:341] Persisting action (358 bytes) = to leveldb took 884750ns I0331 17:09:45.380023 1330 replica.cpp:712] Persisted action at 3 I0331 17:09:45.381299 1330 replica.cpp:691] Replica received learned notic= e for position 3 from @0.0.0.0:0 I0331 17:09:45.381980 1330 leveldb.cpp:341] Persisting action (360 bytes) = to leveldb took 604022ns I0331 17:09:45.382009 1330 replica.cpp:712] Persisted action at 3 I0331 17:09:45.382030 1330 replica.cpp:697] Replica learned APPEND action = at position 3 I0331 17:09:45.391926 1321 registrar.cpp:484] Successfully updated the 're= gistry' in 15520us I0331 17:09:45.392407 1322 log.cpp:702] Attempting to truncate the log to = 3 I0331 17:09:45.392673 1322 coordinator.cpp:348] Coordinator attempting to = write TRUNCATE action at position 4 I0331 17:09:45.392875 1321 master.cpp:4458] Registered slave 9b06f6db-72f1= -42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264 (29ba6f7aca97) wi= th cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0331 17:09:45.393252 1321 hierarchical.cpp:476] Added slave 9b06f6db-72f1= -42be-b262-98eb1ddb86e2-S0 (29ba6f7aca97) with cpus(*):2; mem(*):1024; disk= (*):1024; ports(*):[31000-32000] (allocated: ) I0331 17:09:45.393406 1317 slave.cpp:1105] Registered with master master@1= 72.17.0.2:47264; given slave ID 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 I0331 17:09:45.393548 1317 fetcher.cpp:81] Clearing fetcher cache I0331 17:09:45.394038 1319 status_update_manager.cpp:181] Resuming sending= status updates I0331 17:09:45.394340 1317 slave.cpp:1128] Checkpointing SlaveInfo to '/tm= p/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/meta/slaves/9b06f6d= b-72f1-42be-b262-98eb1ddb86e2-S0/slave.info' I0331 17:09:45.394834 1325 master.cpp:5508] Sending 1 offers to framework = 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 (default) at scheduler-750dafdb-b= 99a-49b6-883c-3de70916a485@172.17.0.2:47264 I0331 17:09:45.395146 1317 slave.cpp:1165] Forwarding total oversubscribed= resources=20 I0331 17:09:45.395349 1318 sched.cpp:874] Scheduler::resourceOffers took 1= 45774ns I0331 17:09:45.395462 1317 slave.cpp:3664] Received ping from slave-observ= er(340)@172.17.0.2:47264 I0331 17:09:45.395781 1325 master.cpp:4802] Received update of slave 9b06f= 6db-72f1-42be-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264 (29ba6f7a= ca97) with total oversubscribed resources=20 I0331 17:09:45.396335 1321 hierarchical.cpp:1585] No inverse offers to sen= d out! I0331 17:09:45.396401 1321 hierarchical.cpp:1164] Performed allocation for= slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 in 3.112603ms I0331 17:09:45.396800 1321 hierarchical.cpp:534] Slave 9b06f6db-72f1-42be-= b262-98eb1ddb86e2-S0 (29ba6f7aca97) updated with oversubscribed resources = (total: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000], alloc= ated: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) I0331 17:09:45.396965 1320 replica.cpp:537] Replica received write request= for position 4 from (12043)@172.17.0.2:47264 I0331 17:09:45.396978 1321 hierarchical.cpp:1490] No resources available t= o allocate! I0331 17:09:45.397666 1320 leveldb.cpp:341] Persisting action (16 bytes) t= o leveldb took 556870ns I0331 17:09:45.397699 1320 replica.cpp:712] Persisted action at 4 I0331 17:09:45.397799 1321 hierarchical.cpp:1585] No inverse offers to sen= d out! I0331 17:09:45.397964 1321 hierarchical.cpp:1164] Performed allocation for= slave 9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0 in 1.122823ms I0331 17:09:45.399468 1318 master.cpp:3288] Processing ACCEPT call for off= ers: [ 9b06f6db-72f1-42be-b262-98eb1ddb86e2-O0 ] on slave 9b06f6db-72f1-42b= e-b262-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264 (29ba6f7aca97) for fr= amework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 (default) at scheduler-75= 0dafdb-b99a-49b6-883c-3de70916a485@172.17.0.2:47264 I0331 17:09:45.399530 1318 master.cpp:2891] Authorizing framework principa= l 'test-principal' to launch task 1 as user 'mesos' W0331 17:09:45.401288 1318 validation.cpp:416] Executor default for task 1= uses less CPUs (None) than the minimum required (0.01). Please update your= executor, as this will be mandatory in future releases. W0331 17:09:45.401373 1318 validation.cpp:428] Executor default for task 1= uses less memory (None) than the minimum required (32MB). Please update yo= ur executor, as this will be mandatory in future releases. I0331 17:09:45.401914 1318 master.hpp:177] Adding task 1 with resources cp= us(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 9b06f6d= b-72f1-42be-b262-98eb1ddb86e2-S0 (29ba6f7aca97) I0331 17:09:45.403225 1318 master.cpp:3773] Launching task 1 of framework = 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 (default) at scheduler-750dafdb-b= 99a-49b6-883c-3de70916a485@172.17.0.2:47264 with resources cpus(*):2; mem(*= ):1024; disk(*):1024; ports(*):[31000-32000] on slave 9b06f6db-72f1-42be-b2= 62-98eb1ddb86e2-S0 at slave(357)@172.17.0.2:47264 (29ba6f7aca97) I0331 17:09:45.403780 1322 slave.cpp:1497] Got assigned task 1 for framewo= rk 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 I0331 17:09:45.404628 1322 slave.cpp:1616] Launching task 1 for framework = 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 I0331 17:09:45.404757 1318 replica.cpp:691] Replica received learned notic= e for position 4 from @0.0.0.0:0 I0331 17:09:45.405338 1318 leveldb.cpp:341] Persisting action (18 bytes) t= o leveldb took 557752ns I0331 17:09:45.405519 1318 leveldb.cpp:399] Deleting ~2 keys from leveldb = took 132165ns I0331 17:09:45.405654 1318 replica.cpp:712] Persisted action at 4 I0331 17:09:45.405788 1318 replica.cpp:697] Replica learned TRUNCATE actio= n at position 4 I0331 17:09:45.406699 1322 paths.cpp:528] Trying to chown '/tmp/SlaveTest_= RemoveUnregisteredTerminatedExecutor_r87Psi/slaves/9b06f6db-72f1-42be-b262-= 98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000/execut= ors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5c4f0' to user 'mesos' I0331 17:09:45.418769 1322 slave.cpp:5575] Launching executor default of f= ramework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 with resources in work = directory '/tmp/SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/slave= s/9b06f6db-72f1-42be-b262-98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b26= 2-98eb1ddb86e2-0000/executors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5= c4f0' I0331 17:09:45.422309 1322 exec.cpp:150] Version: 0.29.0 I0331 17:09:45.422802 1320 exec.cpp:200] Executor started at: executor(102= )@172.17.0.2:47264 with pid 1299 I0331 17:09:45.423281 1322 slave.cpp:1834] Queuing task '1' for executor '= default' of framework 9b06f6db-72f1-42be-b262-98eb1ddb86e2-0000 I0331 17:09:45.423583 1322 slave.cpp:881] Successfully attached file '/tmp= /SlaveTest_RemoveUnregisteredTerminatedExecutor_r87Psi/slaves/9b06f6db-72f1= -42be-b262-98eb1ddb86e2-S0/frameworks/9b06f6db-72f1-42be-b262-98eb1ddb86e2-= 0000/executors/default/runs/64ca6446-70ef-4912-98e0-fe9349b5c4f0' F0331 17:09:45.425576 1322 owned.hpp:110] Check failed: 'get()' Must be no= n NULL=20 *** Check failure stack trace: *** @ 0x2b84020b04bd google::LogMessage::Fail() @ 0x2b84020af89e google::LogMessage::SendToLog() @ 0x2b84020b017d google::LogMessage::Flush() @ 0x2b84020b35f8 google::LogMessageFatal::~LogMessageFatal() @ 0xa07364 google::CheckNotNull<>() @ 0x9f69a2 process::Owned<>::operator->() @ 0x9f4a6d mesos::internal::tests::TestContainerizer::_wait(= ) @ 0x9faf66 _ZN7testing8internal12InvokeHelperIN7process6Futu= reIN5mesos13containerizer11TerminationEEESt5tupleIJRKNS4_11ContainerIDEEEE1= 2InvokeMethodINS4_8internal5tests17TestContainerizerEMSH_FS7_SB_EEES7_PT_T0= _RKSC_ @ 0x9faeb4 _ZNK7testing8internal18InvokeMethodActionIN5mesos= 8internal5tests17TestContainerizerEMS5_FN7process6FutureINS2_13containerize= r11TerminationEEERKNS2_11ContainerIDEEE7PerformISA_St5tupleIJSD_EEEET_RKT0_ @ 0x9fae56 _ZN7testing17PolymorphicActionINS_8internal18Invo= keMethodActionIN5mesos8internal5tests17TestContainerizerEMS6_FN7process6Fut= ureINS3_13containerizer11TerminationEEERKNS3_11ContainerIDEEEEE15Monomorphi= cImplIFSB_SE_EE7PerformERKSt5tupleIJSE_EE @ 0xa30758 _ZNK7testing6ActionIFN7process6FutureIN5mesos13co= ntainerizer11TerminationEEERKNS3_11ContainerIDEEE7PerformERKSt5tupleIJS9_EE @ 0xa305ba testing::internal::ActionResultHolder<>::PerformA= ction<>() @ 0xa2f19c testing::internal::FunctionMockerBase<>::UntypedP= erformAction() @ 0x197a679 testing::internal::UntypedFunctionMockerBase::Unt= ypedInvokeWith() @ 0xa1d09b _ZN7testing8internal18FunctionMockerBaseIFN7proce= ss6FutureIN5mesos13containerizer11TerminationEEERKNS4_11ContainerIDEEE10Inv= okeWithERKSt5tupleIJSA_EE @ 0xa1d064 testing::internal::FunctionMocker<>::Invoke() @ 0x9f98c0 mesos::internal::tests::TestContainerizer::wait() @ 0x2b84012ec6a5 mesos::internal::slave::Slave::executorLaunched() @ 0x2b84013225ea _ZZN7process8dispatchIN5mesos8internal5slave5Slav= eERKNS1_11FrameworkIDERKNS1_10ExecutorIDERKNS1_11ContainerIDERKNS_6FutureIb= EES5_S8_SB_SF_EEvRKNS_3PIDIT_EEMSJ_FvT0_T1_T2_T3_ET4_T5_T6_T7_ENKUlPNS_11Pr= ocessBaseEE_clESY_ @ 0x2b8401322112 _ZNSt17_Function_handlerIFvPN7process11ProcessBas= eEEZNS0_8dispatchIN5mesos8internal5slave5SlaveERKNS5_11FrameworkIDERKNS5_10= ExecutorIDERKNS5_11ContainerIDERKNS0_6FutureIbEES9_SC_SF_SJ_EEvRKNS0_3PIDIT= _EEMSN_FvT0_T1_T2_T3_ET4_T5_T6_T7_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_ @ 0x2b8401fe72e8 std::function<>::operator()() @ 0x2b8401fd1534 process::ProcessBase::visit() @ 0x2b84020279ae process::DispatchEvent::visit() @ 0x86b4f1 process::ProcessBase::serve() @ 0x2b8401fcf254 process::ProcessManager::resume() @ 0x2b8401fd7375 process::ProcessManager::init_threads()::$_1::ope= rator()() @ 0x2b8401fd72b3 _ZNSt5_BindIFZN7process14ProcessManager12init_thr= eadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEE6__callIvJEJLm0EEEET_OS= t5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE @ 0x2b8401fd7266 _ZNSt5_BindIFZN7process14ProcessManager12init_thr= eadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEEclIJEvEET0_DpOT_ @ 0x2b8401fd7215 _ZNSt12_Bind_simpleIFSt5_BindIFZN7process14Proces= sManager12init_threadsEvE3$_1St17reference_wrapperIKSt11atomic_boolEEEvEE9_= M_invokeIJEEEvSt12_Index_tupleIJXspT_EEE @ 0x2b8401fd71e5 std::_Bind_simple<>::operator()() @ 0x2b8401fd71bc std::thread::_Impl<>::_M_run() @ 0x2b8403840a60 (unknown) make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src' make[4]: *** [check-local] Aborted make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src' make[3]: *** [check-am] Error 2 make[2]: *** [check] Error 2 make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/mesos/mesos-0.29.0/_build' make: *** [distcheck] Error 1 + docker rmi mesos-1459442182-6170 Untagged: mesos-1459442182-6170:latest Deleted: 2d66a00ec0049a3861429ddea6ad000ecee3cde2163dbfd00188809a1a4dccbc Deleted: 4f22ed5feeb67e6af34b3ca6e647425b7673f368f784fd2002a3d5d691a1984a Deleted: b738fe0fba889e16578ed5e4c91da22d32cc3dbee2a4bd6e79bd90a89ab84643 Deleted: 586be900ed5b534083e54b8111e242c8071a2b27b2354e86f4e59a076937c9d2 Deleted: 36a17040d363e8c1f1056c63120c4deef43ea9107b8566076780c7b6c2e0814b Deleted: 9dddb8f7a4e89a5c43a838920a62116550aa98063927ead99257e8d764a7568a Deleted: 26154f0eaffe623d376982559fb226602cc76479bfbb3b25a099f9affc2d1391 Deleted: 074341c72d94570bd67610567dfd64c082c43c37864089aa1b6893076a881a0e Deleted: af36b6d6fc35c9bae6adc732dc4216fce2bbd6d7397d39c290c6ff55c15f32a1 Deleted: 6e40d728ba2eb6886bca928e9ac9884fd923aa484c33035b7eff7b8a57c70be8 Deleted: 94f8edab37485281006f5f65690f4571b1ba5012e137b367b1ac0e1f4f733fea Deleted: d6955d17738051273ef8c18e66b944879130fabbc936f5f7c502890f93cfe2b3 Deleted: 13ba435ee0ad83459ba05018be306c55c0acd4211285521ff828db23bdef8528 Deleted: 89248d43439d536f7b7afa57ae625e859286451f9ef8e49f2d6e97daa6b9d1c6 Deleted: 841671f9606b1431f94603959fa87c3265735cf1546d3317ad08d1eccedd4401 Deleted: 7eb2dc32e199b207e33f2e2b658d720e31fba7bd6609078d94254585e3882140 Build step 'Execute shell' marked build as failure