See <https://builds.apache.org/job/Mesos-Ubuntu-distcheck/238/changes>
Changes: [yujie.jay] Fixed a bug in port mapping test cleanup function. ------------------------------------------ [...truncated 60720 lines...] I0729 06:37:23.321805 24074 sched.cpp:139] Version: 0.20.0 I0729 06:37:23.325839 24105 sched.cpp:235] New master detected at [email protected]:35851 I0729 06:37:23.325866 24105 sched.cpp:285] Authenticating with master [email protected]:35851 I0729 06:37:23.325954 24105 authenticatee.hpp:128] Creating new client SASL connection I0729 06:37:23.326109 24105 master.cpp:3504] Authenticating [email protected]:35851 I0729 06:37:23.326192 24105 authenticator.hpp:156] Creating new server SASL connection I0729 06:37:23.326280 24105 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0729 06:37:23.326297 24105 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0729 06:37:23.326320 24105 authenticator.hpp:262] Received SASL authentication start I0729 06:37:23.326372 24105 authenticator.hpp:384] Authentication requires more steps I0729 06:37:23.326397 24105 authenticatee.hpp:265] Received SASL authentication step I0729 06:37:23.326429 24105 authenticator.hpp:290] Received SASL authentication step I0729 06:37:23.326442 24105 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0729 06:37:23.326449 24105 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0729 06:37:23.326459 24105 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0729 06:37:23.326468 24105 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0729 06:37:23.326474 24105 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0729 06:37:23.326480 24105 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0729 06:37:23.326491 24105 authenticator.hpp:376] Authentication success I0729 06:37:23.326516 24105 authenticatee.hpp:305] Authentication success I0729 06:37:23.326539 24105 master.cpp:3544] Successfully authenticated principal 'test-principal' at [email protected]:35851 I0729 06:37:23.326606 24105 sched.cpp:359] Successfully authenticated with master [email protected]:35851 I0729 06:37:23.326617 24105 sched.cpp:478] Sending registration request to [email protected]:35851 I0729 06:37:23.326658 24105 master.cpp:1248] Received registration request from [email protected]:35851 I0729 06:37:23.326675 24105 master.cpp:1208] Authorizing framework principal 'test-principal' to receive offers for role '*' I0729 06:37:23.326784 24105 master.cpp:1307] Registering framework 20140729-063723-453759884-35851-24074-0000 at [email protected]:35851 I0729 06:37:23.326874 24105 sched.cpp:409] Framework registered with 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.326900 24105 sched.cpp:423] Scheduler::registered took 15520ns I0729 06:37:23.327005 24105 hierarchical_allocator_process.hpp:331] Added framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.327014 24105 hierarchical_allocator_process.hpp:724] No resources available to allocate! I0729 06:37:23.327020 24105 hierarchical_allocator_process.hpp:686] Performed allocation for 0 slaves in 6070ns I0729 06:37:23.333621 24074 sched.cpp:139] Version: 0.20.0 I0729 06:37:23.337704 24119 sched.cpp:235] New master detected at [email protected]:35851 I0729 06:37:23.337725 24119 sched.cpp:285] Authenticating with master [email protected]:35851 I0729 06:37:23.337793 24119 authenticatee.hpp:128] Creating new client SASL connection I0729 06:37:23.337904 24119 master.cpp:3504] Authenticating [email protected]:35851 I0729 06:37:23.337978 24119 authenticator.hpp:156] Creating new server SASL connection I0729 06:37:23.338057 24119 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0729 06:37:23.338071 24119 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0729 06:37:23.338093 24119 authenticator.hpp:262] Received SASL authentication start I0729 06:37:23.338124 24119 authenticator.hpp:384] Authentication requires more steps I0729 06:37:23.338146 24119 authenticatee.hpp:265] Received SASL authentication step I0729 06:37:23.338176 24119 authenticator.hpp:290] Received SASL authentication step I0729 06:37:23.338186 24119 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0729 06:37:23.338193 24119 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0729 06:37:23.338201 24119 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0729 06:37:23.338210 24119 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0729 06:37:23.338217 24119 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0729 06:37:23.338222 24119 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0729 06:37:23.338232 24119 authenticator.hpp:376] Authentication success I0729 06:37:23.338254 24119 authenticatee.hpp:305] Authentication success I0729 06:37:23.338274 24119 master.cpp:3544] Successfully authenticated principal 'test-principal' at [email protected]:35851 I0729 06:37:23.338330 24119 sched.cpp:359] Successfully authenticated with master [email protected]:35851 I0729 06:37:23.338341 24119 sched.cpp:478] Sending registration request to [email protected]:35851 I0729 06:37:23.338377 24119 master.cpp:1248] Received registration request from [email protected]:35851 I0729 06:37:23.338392 24119 master.cpp:1208] Authorizing framework principal 'test-principal' to receive offers for role '*' I0729 06:37:23.338475 24119 master.cpp:1307] Registering framework 20140729-063723-453759884-35851-24074-0001 at [email protected]:35851 I0729 06:37:23.338531 24119 sched.cpp:409] Framework registered with 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.338551 24119 sched.cpp:423] Scheduler::registered took 11403ns I0729 06:37:23.338610 24119 hierarchical_allocator_process.hpp:331] Added framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.338618 24119 hierarchical_allocator_process.hpp:724] No resources available to allocate! I0729 06:37:23.338624 24119 hierarchical_allocator_process.hpp:686] Performed allocation for 0 slaves in 5720ns I0729 06:37:23.342171 24074 containerizer.cpp:124] Using isolation: posix/cpu,posix/mem I0729 06:37:23.345679 24098 slave.cpp:169] Slave started on 197)@140.211.11.27:35851 I0729 06:37:23.345701 24098 credentials.hpp:84] Loading credential for authentication from '/tmp/DRFAllocatorTest_SameShareAllocations_Bzc1Cr/credential' I0729 06:37:23.345799 24098 slave.cpp:267] Slave using credential for: test-principal I0729 06:37:23.345890 24098 slave.cpp:280] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0729 06:37:23.345948 24098 slave.cpp:325] Slave hostname: hemera.apache.org I0729 06:37:23.345957 24098 slave.cpp:326] Slave checkpoint: false I0729 06:37:23.346447 24098 state.cpp:33] Recovering state from '/tmp/DRFAllocatorTest_SameShareAllocations_Bzc1Cr/meta' I0729 06:37:23.357679 24123 hierarchical_allocator_process.hpp:724] No resources available to allocate! I0729 06:37:23.357694 24123 hierarchical_allocator_process.hpp:686] Performed allocation for 0 slaves in 30795ns I0729 06:37:23.361729 24097 status_update_manager.cpp:193] Recovering status update manager I0729 06:37:23.361806 24097 containerizer.cpp:287] Recovering containerizer I0729 06:37:23.362048 24097 slave.cpp:3128] Finished recovery I0729 06:37:23.362275 24097 slave.cpp:601] New master detected at [email protected]:35851 I0729 06:37:23.362298 24097 slave.cpp:677] Authenticating with master [email protected]:35851 I0729 06:37:23.362334 24097 slave.cpp:650] Detecting new master I0729 06:37:23.362365 24097 status_update_manager.cpp:167] New master detected at [email protected]:35851 I0729 06:37:23.362393 24097 authenticatee.hpp:128] Creating new client SASL connection I0729 06:37:23.362514 24097 master.cpp:3504] Authenticating slave(197)@140.211.11.27:35851 I0729 06:37:23.362583 24097 authenticator.hpp:156] Creating new server SASL connection I0729 06:37:23.362665 24097 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5 I0729 06:37:23.362680 24097 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5' I0729 06:37:23.362704 24097 authenticator.hpp:262] Received SASL authentication start I0729 06:37:23.362751 24097 authenticator.hpp:384] Authentication requires more steps I0729 06:37:23.362774 24097 authenticatee.hpp:265] Received SASL authentication step I0729 06:37:23.362804 24097 authenticator.hpp:290] Received SASL authentication step I0729 06:37:23.362818 24097 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I0729 06:37:23.362824 24097 auxprop.cpp:153] Looking up auxiliary property '*userPassword' I0729 06:37:23.362833 24097 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I0729 06:37:23.362841 24097 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'hemera.apache.org' server FQDN: 'hemera.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I0729 06:37:23.362848 24097 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I0729 06:37:23.362853 24097 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I0729 06:37:23.362864 24097 authenticator.hpp:376] Authentication success I0729 06:37:23.362889 24097 authenticatee.hpp:305] Authentication success I0729 06:37:23.362908 24097 master.cpp:3544] Successfully authenticated principal 'test-principal' at slave(197)@140.211.11.27:35851 I0729 06:37:23.362967 24097 slave.cpp:734] Successfully authenticated with master [email protected]:35851 I0729 06:37:23.362999 24097 slave.cpp:972] Will retry registration in 7.623332ms if necessary I0729 06:37:23.363057 24097 master.cpp:2762] Registering slave at slave(197)@140.211.11.27:35851 (hemera.apache.org) with id 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.363155 24097 registrar.cpp:422] Attempting to update the 'registry' I0729 06:37:23.365316 24097 log.cpp:680] Attempting to append 332 bytes to the log I0729 06:37:23.365365 24097 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I0729 06:37:23.365602 24097 replica.cpp:508] Replica received write request for position 3 I0729 06:37:23.365728 24102 slave.cpp:972] Will retry registration in 5.426525ms if necessary I0729 06:37:23.365784 24102 master.cpp:2750] Ignoring register slave message from slave(197)@140.211.11.27:35851 (hemera.apache.org) as admission is already in progress I0729 06:37:23.385679 24097 leveldb.cpp:343] Persisting action (351 bytes) to leveldb took 20.049522ms I0729 06:37:23.385735 24097 replica.cpp:676] Persisted action at 3 I0729 06:37:23.386018 24097 replica.cpp:655] Replica received learned notice for position 3 I0729 06:37:23.405658 24097 leveldb.cpp:343] Persisting action (353 bytes) to leveldb took 19.622593ms I0729 06:37:23.405679 24097 replica.cpp:676] Persisted action at 3 I0729 06:37:23.405689 24097 replica.cpp:661] Replica learned APPEND action at position 3 I0729 06:37:23.406052 24097 registrar.cpp:479] Successfully updated 'registry' I0729 06:37:23.406142 24097 log.cpp:699] Attempting to truncate the log to 3 I0729 06:37:23.406198 24097 master.cpp:2802] Registered slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) I0729 06:37:23.406211 24097 master.cpp:3972] Adding slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I0729 06:37:23.406329 24097 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I0729 06:37:23.406399 24097 slave.cpp:768] Registered with master [email protected]:35851; given slave ID 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.406509 24097 slave.cpp:2325] Received ping from slave-observer(166)@140.211.11.27:35851 I0729 06:37:23.406568 24097 hierarchical_allocator_process.hpp:444] Added slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I0729 06:37:23.406627 24097 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.406738 24097 hierarchical_allocator_process.hpp:706] Performed allocation for slave 20140729-063723-453759884-35851-24074-0 in 138624ns I0729 06:37:23.406822 24097 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.406864 24097 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.407019 24097 sched.cpp:546] Scheduler::resourceOffers took 30058ns I0729 06:37:23.407167 24097 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.407217 24097 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-0 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.407423 24097 replica.cpp:508] Replica received write request for position 4 I0729 06:37:23.409678 24102 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0000 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.425660 24097 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 18.217649ms I0729 06:37:23.425684 24097 replica.cpp:676] Persisted action at 4 I0729 06:37:23.425866 24097 replica.cpp:655] Replica received learned notice for position 4 I0729 06:37:23.445654 24097 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 19.77424ms I0729 06:37:23.445695 24097 leveldb.cpp:401] Deleting ~2 keys from leveldb took 18645ns I0729 06:37:23.445705 24097 replica.cpp:676] Persisted action at 4 I0729 06:37:23.445713 24097 replica.cpp:661] Replica learned TRUNCATE action at position 4 I0729 06:37:23.452121 24100 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.452237 24100 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 212467ns I0729 06:37:23.452292 24100 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.452333 24100 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.452446 24100 sched.cpp:546] Scheduler::resourceOffers took 28532ns I0729 06:37:23.452545 24100 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.452592 24100 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-1 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.452754 24100 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0001 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.473695 24121 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.473821 24121 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 156920ns I0729 06:37:23.473873 24121 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-2 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.473913 24121 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.474019 24121 sched.cpp:546] Scheduler::resourceOffers took 28768ns I0729 06:37:23.474117 24121 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-2 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.474164 24121 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-2 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.474320 24121 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0000 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.489717 24121 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.489820 24121 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 122655ns I0729 06:37:23.489866 24121 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-3 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.489902 24121 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.489990 24121 sched.cpp:546] Scheduler::resourceOffers took 23911ns I0729 06:37:23.490077 24121 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-3 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.490121 24121 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-3 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.490265 24121 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0001 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.505863 24121 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.506033 24121 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 191409ns I0729 06:37:23.506141 24121 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.506242 24121 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.506397 24121 sched.cpp:546] Scheduler::resourceOffers took 26888ns I0729 06:37:23.506551 24121 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-4 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.506656 24121 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-4 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.506865 24121 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0000 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.509702 24098 master.cpp:123] No whitelist given. Advertising offers for all slaves I0729 06:37:23.533718 24117 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.533859 24117 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 190626ns I0729 06:37:23.533918 24117 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-5 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.533969 24117 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.534101 24117 sched.cpp:546] Scheduler::resourceOffers took 28345ns I0729 06:37:23.534204 24117 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-5 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.534253 24117 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-5 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.534417 24117 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0001 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.557689 24117 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.557804 24117 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 140030ns I0729 06:37:23.557854 24117 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-6 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.557893 24117 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.557987 24117 sched.cpp:546] Scheduler::resourceOffers took 24602ns I0729 06:37:23.558079 24117 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-6 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.558125 24117 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-6 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.558276 24117 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0000 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.575275 24110 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.575392 24110 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 144284ns I0729 06:37:23.575441 24110 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-7 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.575480 24110 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.575577 24110 sched.cpp:546] Scheduler::resourceOffers took 24875ns I0729 06:37:23.575670 24110 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-7 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.575716 24110 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-7 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.575870 24110 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0001 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.593767 24114 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.593886 24114 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 219747ns I0729 06:37:23.593936 24114 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-8 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.593976 24114 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.594074 24114 sched.cpp:546] Scheduler::resourceOffers took 24950ns I0729 06:37:23.594166 24114 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-8 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.594212 24114 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-8 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0000 I0729 06:37:23.594364 24114 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0000 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.609673 24110 master.cpp:123] No whitelist given. Advertising offers for all slaves I0729 06:37:23.613687 24123 hierarchical_allocator_process.hpp:750] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.613806 24123 hierarchical_allocator_process.hpp:686] Performed allocation for 1 slaves in 146064ns I0729 06:37:23.613853 24123 master.hpp:816] Adding offer 20140729-063723-453759884-35851-24074-9 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.613893 24123 master.cpp:3451] Sending 1 offers to framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.613982 24123 sched.cpp:546] Scheduler::resourceOffers took 22916ns I0729 06:37:23.614070 24123 master.hpp:826] Removing offer 20140729-063723-453759884-35851-24074-9 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140729-063723-453759884-35851-24074-0 (hemera.apache.org) I0729 06:37:23.614115 24123 master.cpp:2125] Processing reply for offers: [ 20140729-063723-453759884-35851-24074-9 ] on slave 20140729-063723-453759884-35851-24074-0 at slave(197)@140.211.11.27:35851 (hemera.apache.org) for framework 20140729-063723-453759884-35851-24074-0001 I0729 06:37:23.614260 24123 hierarchical_allocator_process.hpp:546] Framework 20140729-063723-453759884-35851-24074-0001 left cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] unused on slave 20140729-063723-453759884-35851-24074-0 I0729 06:37:23.617745 24074 master.cpp:626] Master terminating I0729 06:37:23.621664 24102 sched.cpp:747] Stopping framework '20140729-063723-453759884-35851-24074-0000' I0729 06:37:23.625682 24116 sched.cpp:747] Stopping framework '20140729-063723-453759884-35851-24074-0001' I0729 06:37:23.629678 24106 slave.cpp:2332] [email protected]:35851 exited W0729 06:37:23.629696 24106 slave.cpp:2335] Master disconnected! Waiting for a new master to be elected I0729 06:37:23.641873 24074 slave.cpp:485] Slave terminating [ OK ] DRFAllocatorTest.SameShareAllocations (571 ms) [----------] 2 tests from DRFAllocatorTest (1385 ms total) [----------] 2 tests from CpuIsolatorTest/0, where TypeParam = mesos::internal::slave::PosixCpuIsolatorProcess [ RUN ] CpuIsolatorTest/0.SystemCpuUsage Using temporary directory '/tmp/CpuIsolatorTest_0_SystemCpuUsage_42gv3i' I0729 06:37:23.653084 24074 launcher.cpp:137] Forked child with pid '29532' for container 'system_cpu_usage' [ OK ] CpuIsolatorTest/0.SystemCpuUsage (739 ms) [ RUN ] CpuIsolatorTest/0.UserCpuUsage Using temporary directory '/tmp/CpuIsolatorTest_0_UserCpuUsage_y3hwi4' I0729 06:37:24.385808 24074 launcher.cpp:137] Forked child with pid '29541' for container 'user_cpu_usage' [ OK ] CpuIsolatorTest/0.UserCpuUsage (989 ms) [----------] 2 tests from CpuIsolatorTest/0 (1728 ms total) [----------] Global test environment tear-down [==========] 390 tests from 64 test cases ran. (464781 ms total) [ PASSED ] 389 tests. [ FAILED ] 1 test, listed below: [ FAILED ] SlaveRecoveryTest/0.MasterFailover, where TypeParam = mesos::internal::slave::MesosContainerizer 1 FAILED TEST YOU HAVE 4 DISABLED TESTS make[4]: *** [check-local] Error 1 make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src' make[3]: *** [check-am] Error 2 make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src' make[2]: *** [check] Error 2 make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build/src' make[1]: *** [check-recursive] Error 1 make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Ubuntu-distcheck/build/mesos-0.20.0/_build' make: *** [distcheck] Error 1 Build step 'Execute shell' marked build as failure
