You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/06/18 20:36:36 UTC

Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui #2187

See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2187/changes>

Changes:

[yujie.jay] Refactored subprocess to support more IO redirect modes.

[yujie.jay] Updated mesos to use the new Subprocess API.

------------------------------------------
[...truncated 50963 lines...]
I0618 18:36:30.160042 18856 master.hpp:785] Adding offer 20140618-183629-160088899-38847-18832-2 with resources cpus(*):3; mem(*):2048; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-2 (vesta.apache.org)
I0618 18:36:30.160086 18856 master.cpp:3404] Sending 1 offers to framework 20140618-183629-160088899-38847-18832-0001
I0618 18:36:30.160225 18857 sched.cpp:545] Scheduler::resourceOffers took 27243ns
I0618 18:36:30.162171 18832 sched.cpp:138] Version: 0.20.0
I0618 18:36:30.162359 18855 sched.cpp:234] New master detected at master@67.195.138.9:38847
I0618 18:36:30.162381 18855 sched.cpp:284] Authenticating with master master@67.195.138.9:38847
I0618 18:36:30.162628 18858 authenticatee.hpp:128] Creating new client SASL connection
I0618 18:36:30.162770 18856 master.cpp:3457] Authenticating scheduler-b6b34973-8b1d-406d-925d-c3e66421e068@67.195.138.9:38847
I0618 18:36:30.162868 18858 authenticator.hpp:156] Creating new server SASL connection
I0618 18:36:30.162972 18851 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0618 18:36:30.162998 18851 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0618 18:36:30.163032 18851 authenticator.hpp:262] Received SASL authentication start
I0618 18:36:30.163066 18851 authenticator.hpp:384] Authentication requires more steps
I0618 18:36:30.163095 18851 authenticatee.hpp:265] Received SASL authentication step
I0618 18:36:30.163141 18851 authenticator.hpp:290] Received SASL authentication step
I0618 18:36:30.163158 18851 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0618 18:36:30.163166 18851 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0618 18:36:30.163177 18851 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0618 18:36:30.163185 18851 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0618 18:36:30.163192 18851 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0618 18:36:30.163197 18851 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0618 18:36:30.163205 18851 authenticator.hpp:376] Authentication success
I0618 18:36:30.163254 18856 authenticatee.hpp:305] Authentication success
I0618 18:36:30.163269 18851 master.cpp:3497] Successfully authenticated principal 'test-principal' at scheduler-b6b34973-8b1d-406d-925d-c3e66421e068@67.195.138.9:38847
I0618 18:36:30.163350 18856 sched.cpp:358] Successfully authenticated with master master@67.195.138.9:38847
I0618 18:36:30.163375 18856 sched.cpp:477] Sending registration request to master@67.195.138.9:38847
I0618 18:36:30.163420 18857 master.cpp:1196] Received registration request from scheduler-b6b34973-8b1d-406d-925d-c3e66421e068@67.195.138.9:38847
I0618 18:36:30.163451 18857 master.cpp:1156] Authorizing framework principal 'test-principal' to receive offers for role 'role1'
I0618 18:36:30.163653 18858 master.cpp:1255] Registering framework 20140618-183629-160088899-38847-18832-0002 at scheduler-b6b34973-8b1d-406d-925d-c3e66421e068@67.195.138.9:38847
I0618 18:36:30.163753 18856 sched.cpp:408] Framework registered with 20140618-183629-160088899-38847-18832-0002
I0618 18:36:30.163785 18856 sched.cpp:422] Scheduler::registered took 11899ns
I0618 18:36:30.163851 18855 hierarchical_allocator_process.hpp:331] Added framework 20140618-183629-160088899-38847-18832-0002
I0618 18:36:30.163903 18855 hierarchical_allocator_process.hpp:686] Performed allocation for 3 slaves in 32374ns
I0618 18:36:30.164279 18852 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 4.323219ms
I0618 18:36:30.164299 18852 replica.cpp:676] Persisted action at 8
I0618 18:36:30.164537 18852 replica.cpp:655] Replica received learned notice for position 8
I0618 18:36:30.164623 18832 mesos_containerizer.cpp:125] Using isolation: posix/cpu,posix/mem
I0618 18:36:30.166002 18857 slave.cpp:143] Slave started on 180)@67.195.138.9:38847
I0618 18:36:30.166019 18857 credentials.hpp:35] Loading credentials for authentication from '/tmp/DRFAllocatorTest_DRFAllocatorProcess_bdYFfv/credential'
I0618 18:36:30.166072 18857 slave.cpp:243] Slave using credential for: test-principal
I0618 18:36:30.166172 18857 slave.cpp:256] Slave resources: cpus(*):4; mem(*):4096; disk(*):0; ports(*):[31000-32000]
I0618 18:36:30.166220 18857 slave.cpp:284] Slave hostname: vesta.apache.org
I0618 18:36:30.166231 18857 slave.cpp:285] Slave checkpoint: false
I0618 18:36:30.166595 18851 state.cpp:33] Recovering state from '/tmp/DRFAllocatorTest_DRFAllocatorProcess_bdYFfv/meta'
I0618 18:36:30.166723 18857 status_update_manager.cpp:193] Recovering status update manager
I0618 18:36:30.166806 18856 mesos_containerizer.cpp:283] Recovering containerizer
I0618 18:36:30.167007 18856 slave.cpp:3068] Finished recovery
I0618 18:36:30.167201 18851 slave.cpp:537] New master detected at master@67.195.138.9:38847
I0618 18:36:30.167229 18851 slave.cpp:613] Authenticating with master master@67.195.138.9:38847
I0618 18:36:30.167246 18856 status_update_manager.cpp:167] New master detected at master@67.195.138.9:38847
I0618 18:36:30.167270 18851 slave.cpp:586] Detecting new master
I0618 18:36:30.167280 18856 authenticatee.hpp:128] Creating new client SASL connection
I0618 18:36:30.167412 18858 master.cpp:3457] Authenticating slave(180)@67.195.138.9:38847
I0618 18:36:30.167544 18858 authenticator.hpp:156] Creating new server SASL connection
I0618 18:36:30.167610 18858 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0618 18:36:30.167632 18858 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0618 18:36:30.167656 18858 authenticator.hpp:262] Received SASL authentication start
I0618 18:36:30.167721 18858 authenticator.hpp:384] Authentication requires more steps
I0618 18:36:30.167752 18858 authenticatee.hpp:265] Received SASL authentication step
I0618 18:36:30.167790 18858 authenticator.hpp:290] Received SASL authentication step
I0618 18:36:30.167819 18858 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0618 18:36:30.167826 18858 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0618 18:36:30.167839 18858 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0618 18:36:30.167847 18858 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0618 18:36:30.167855 18858 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0618 18:36:30.167860 18858 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0618 18:36:30.167872 18858 authenticator.hpp:376] Authentication success
I0618 18:36:30.167899 18858 authenticatee.hpp:305] Authentication success
I0618 18:36:30.167927 18858 master.cpp:3497] Successfully authenticated principal 'test-principal' at slave(180)@67.195.138.9:38847
I0618 18:36:30.168016 18858 slave.cpp:670] Successfully authenticated with master master@67.195.138.9:38847
I0618 18:36:30.168071 18858 slave.cpp:908] Will retry registration in 11.826465ms if necessary
I0618 18:36:30.168216 18853 master.cpp:2736] Registering slave at slave(180)@67.195.138.9:38847 (vesta.apache.org) with id 20140618-183629-160088899-38847-18832-3
I0618 18:36:30.168267 18852 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 3.708616ms
I0618 18:36:30.168295 18852 leveldb.cpp:401] Deleting ~2 keys from leveldb took 12700ns
I0618 18:36:30.168306 18852 replica.cpp:676] Persisted action at 8
I0618 18:36:30.168315 18852 replica.cpp:661] Replica learned TRUNCATE action at position 8
I0618 18:36:30.168376 18851 registrar.cpp:422] Attempting to update the 'registry'
I0618 18:36:30.169534 18854 log.cpp:680] Attempting to append 891 bytes to the log
I0618 18:36:30.169602 18856 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 9
I0618 18:36:30.169976 18851 replica.cpp:508] Replica received write request for position 9
I0618 18:36:30.172253 18851 leveldb.cpp:343] Persisting action (910 bytes) to leveldb took 2.256453ms
I0618 18:36:30.172274 18851 replica.cpp:676] Persisted action at 9
I0618 18:36:30.172574 18857 replica.cpp:655] Replica received learned notice for position 9
I0618 18:36:30.180237 18857 leveldb.cpp:343] Persisting action (912 bytes) to leveldb took 7.641747ms
I0618 18:36:30.180253 18857 replica.cpp:676] Persisted action at 9
I0618 18:36:30.180261 18857 replica.cpp:661] Replica learned APPEND action at position 9
I0618 18:36:30.180544 18852 slave.cpp:908] Will retry registration in 15.140321ms if necessary
I0618 18:36:30.180675 18851 registrar.cpp:479] Successfully updated 'registry'
I0618 18:36:30.180682 18855 log.cpp:699] Attempting to truncate the log to 9
I0618 18:36:30.180738 18855 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 10
I0618 18:36:30.180714 18858 master.cpp:2724] Ignoring register slave message from slave(180)@67.195.138.9:38847 (vesta.apache.org) as admission is already in progress
I0618 18:36:30.180872 18858 master.cpp:2776] Registered slave 20140618-183629-160088899-38847-18832-3 at slave(180)@67.195.138.9:38847 (vesta.apache.org)
I0618 18:36:30.180893 18858 master.cpp:3923] Adding slave 20140618-183629-160088899-38847-18832-3 at slave(180)@67.195.138.9:38847 (vesta.apache.org) with cpus(*):4; mem(*):4096; disk(*):0; ports(*):[31000-32000]
I0618 18:36:30.180997 18851 slave.cpp:704] Registered with master master@67.195.138.9:38847; given slave ID 20140618-183629-160088899-38847-18832-3
I0618 18:36:30.181051 18851 replica.cpp:508] Replica received write request for position 10
I0618 18:36:30.181172 18855 hierarchical_allocator_process.hpp:444] Added slave 20140618-183629-160088899-38847-18832-3 (vesta.apache.org) with cpus(*):4; mem(*):4096; disk(*):0; ports(*):[31000-32000] (and cpus(*):4; mem(*):4096; disk(*):0; ports(*):[31000-32000] available)
I0618 18:36:30.181232 18855 hierarchical_allocator_process.hpp:750] Offering cpus(*):4; mem(*):4096; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-3 to framework 20140618-183629-160088899-38847-18832-0002
I0618 18:36:30.181380 18855 hierarchical_allocator_process.hpp:706] Performed allocation for slave 20140618-183629-160088899-38847-18832-3 in 174369ns
I0618 18:36:30.181423 18852 master.hpp:785] Adding offer 20140618-183629-160088899-38847-18832-3 with resources cpus(*):4; mem(*):4096; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-3 (vesta.apache.org)
I0618 18:36:30.181478 18852 master.cpp:3404] Sending 1 offers to framework 20140618-183629-160088899-38847-18832-0002
I0618 18:36:30.181581 18852 sched.cpp:545] Scheduler::resourceOffers took 20097ns
I0618 18:36:30.183437 18832 sched.cpp:138] Version: 0.20.0
I0618 18:36:30.183639 18853 sched.cpp:234] New master detected at master@67.195.138.9:38847
I0618 18:36:30.183656 18853 sched.cpp:284] Authenticating with master master@67.195.138.9:38847
I0618 18:36:30.183720 18852 authenticatee.hpp:128] Creating new client SASL connection
I0618 18:36:30.183809 18855 master.cpp:3457] Authenticating scheduler-47175652-711d-4cd7-90cb-ecc8dcc7ac0f@67.195.138.9:38847
I0618 18:36:30.183881 18857 authenticator.hpp:156] Creating new server SASL connection
I0618 18:36:30.183938 18857 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0618 18:36:30.183961 18857 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0618 18:36:30.183986 18857 authenticator.hpp:262] Received SASL authentication start
I0618 18:36:30.184010 18857 authenticator.hpp:384] Authentication requires more steps
I0618 18:36:30.184041 18857 authenticatee.hpp:265] Received SASL authentication step
I0618 18:36:30.184077 18857 authenticator.hpp:290] Received SASL authentication step
I0618 18:36:30.184092 18857 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0618 18:36:30.184099 18857 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0618 18:36:30.184108 18857 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0618 18:36:30.184116 18857 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0618 18:36:30.184123 18857 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0618 18:36:30.184128 18857 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0618 18:36:30.184137 18857 authenticator.hpp:376] Authentication success
I0618 18:36:30.184170 18857 authenticatee.hpp:305] Authentication success
I0618 18:36:30.184202 18851 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 3.126137ms
I0618 18:36:30.184209 18858 master.cpp:3497] Successfully authenticated principal 'test-principal' at scheduler-47175652-711d-4cd7-90cb-ecc8dcc7ac0f@67.195.138.9:38847
I0618 18:36:30.184236 18857 sched.cpp:358] Successfully authenticated with master master@67.195.138.9:38847
I0618 18:36:30.184218 18851 replica.cpp:676] Persisted action at 10
I0618 18:36:30.184248 18857 sched.cpp:477] Sending registration request to master@67.195.138.9:38847
I0618 18:36:30.184373 18858 master.cpp:1196] Received registration request from scheduler-47175652-711d-4cd7-90cb-ecc8dcc7ac0f@67.195.138.9:38847
I0618 18:36:30.184406 18858 master.cpp:1156] Authorizing framework principal 'test-principal' to receive offers for role 'role1'
I0618 18:36:30.184420 18855 replica.cpp:655] Replica received learned notice for position 10
I0618 18:36:30.184538 18853 master.cpp:1255] Registering framework 20140618-183629-160088899-38847-18832-0003 at scheduler-47175652-711d-4cd7-90cb-ecc8dcc7ac0f@67.195.138.9:38847
I0618 18:36:30.184653 18858 sched.cpp:408] Framework registered with 20140618-183629-160088899-38847-18832-0003
I0618 18:36:30.184703 18858 sched.cpp:422] Scheduler::registered took 30427ns
I0618 18:36:30.184725 18852 hierarchical_allocator_process.hpp:331] Added framework 20140618-183629-160088899-38847-18832-0003
I0618 18:36:30.184787 18852 hierarchical_allocator_process.hpp:686] Performed allocation for 4 slaves in 49942ns
I0618 18:36:30.185529 18832 mesos_containerizer.cpp:125] Using isolation: posix/cpu,posix/mem
I0618 18:36:30.187331 18854 slave.cpp:143] Slave started on 181)@67.195.138.9:38847
I0618 18:36:30.187357 18854 credentials.hpp:35] Loading credentials for authentication from '/tmp/DRFAllocatorTest_DRFAllocatorProcess_V0Fig6/credential'
I0618 18:36:30.187502 18854 slave.cpp:243] Slave using credential for: test-principal
I0618 18:36:30.187641 18854 slave.cpp:256] Slave resources: cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000]
I0618 18:36:30.187713 18854 slave.cpp:284] Slave hostname: vesta.apache.org
I0618 18:36:30.187734 18854 slave.cpp:285] Slave checkpoint: false
I0618 18:36:30.188180 18855 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 3.747052ms
I0618 18:36:30.188220 18855 leveldb.cpp:401] Deleting ~2 keys from leveldb took 15596ns
I0618 18:36:30.188230 18855 replica.cpp:676] Persisted action at 10
I0618 18:36:30.188236 18855 replica.cpp:661] Replica learned TRUNCATE action at position 10
I0618 18:36:30.188371 18855 state.cpp:33] Recovering state from '/tmp/DRFAllocatorTest_DRFAllocatorProcess_V0Fig6/meta'
I0618 18:36:30.188526 18851 status_update_manager.cpp:193] Recovering status update manager
I0618 18:36:30.188680 18854 mesos_containerizer.cpp:283] Recovering containerizer
I0618 18:36:30.188977 18852 slave.cpp:3068] Finished recovery
I0618 18:36:30.189472 18858 slave.cpp:537] New master detected at master@67.195.138.9:38847
I0618 18:36:30.189510 18858 slave.cpp:613] Authenticating with master master@67.195.138.9:38847
I0618 18:36:30.189565 18851 status_update_manager.cpp:167] New master detected at master@67.195.138.9:38847
I0618 18:36:30.189613 18858 slave.cpp:586] Detecting new master
I0618 18:36:30.189723 18857 authenticatee.hpp:128] Creating new client SASL connection
I0618 18:36:30.189823 18858 master.cpp:3457] Authenticating slave(181)@67.195.138.9:38847
I0618 18:36:30.189934 18858 authenticator.hpp:156] Creating new server SASL connection
I0618 18:36:30.190034 18858 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0618 18:36:30.190057 18858 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0618 18:36:30.190083 18858 authenticator.hpp:262] Received SASL authentication start
I0618 18:36:30.190136 18858 authenticator.hpp:384] Authentication requires more steps
I0618 18:36:30.190166 18858 authenticatee.hpp:265] Received SASL authentication step
I0618 18:36:30.190202 18858 authenticator.hpp:290] Received SASL authentication step
I0618 18:36:30.190220 18858 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0618 18:36:30.190228 18858 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0618 18:36:30.190239 18858 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0618 18:36:30.190249 18858 auxprop.cpp:81] Request to lookup properties for user: 'test-principal' realm: 'vesta.apache.org' server FQDN: 'vesta.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0618 18:36:30.190255 18858 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0618 18:36:30.190260 18858 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0618 18:36:30.190271 18858 authenticator.hpp:376] Authentication success
I0618 18:36:30.190297 18858 authenticatee.hpp:305] Authentication success
I0618 18:36:30.190322 18858 master.cpp:3497] Successfully authenticated principal 'test-principal' at slave(181)@67.195.138.9:38847
I0618 18:36:30.190395 18858 slave.cpp:670] Successfully authenticated with master master@67.195.138.9:38847
I0618 18:36:30.190438 18858 slave.cpp:908] Will retry registration in 4.254463ms if necessary
I0618 18:36:30.190551 18857 master.cpp:2736] Registering slave at slave(181)@67.195.138.9:38847 (vesta.apache.org) with id 20140618-183629-160088899-38847-18832-4
I0618 18:36:30.190706 18851 registrar.cpp:422] Attempting to update the 'registry'
I0618 18:36:30.191915 18857 log.cpp:680] Attempting to append 1079 bytes to the log
I0618 18:36:30.191992 18855 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 11
I0618 18:36:30.192384 18851 replica.cpp:508] Replica received write request for position 11
I0618 18:36:30.194872 18856 slave.cpp:908] Will retry registration in 37.465205ms if necessary
I0618 18:36:30.194914 18853 master.cpp:2724] Ignoring register slave message from slave(181)@67.195.138.9:38847 (vesta.apache.org) as admission is already in progress
I0618 18:36:30.196168 18851 leveldb.cpp:343] Persisting action (1098 bytes) to leveldb took 3.764834ms
I0618 18:36:30.196185 18851 replica.cpp:676] Persisted action at 11
I0618 18:36:30.196504 18854 replica.cpp:655] Replica received learned notice for position 11
I0618 18:36:30.233273 18853 slave.cpp:908] Will retry registration in 49.459777ms if necessary
I0618 18:36:30.233311 18855 master.cpp:2724] Ignoring register slave message from slave(181)@67.195.138.9:38847 (vesta.apache.org) as admission is already in progress
I0618 18:36:30.248739 18854 leveldb.cpp:343] Persisting action (1100 bytes) to leveldb took 52.192438ms
I0618 18:36:30.248821 18854 replica.cpp:676] Persisted action at 11
I0618 18:36:30.248833 18854 replica.cpp:661] Replica learned APPEND action at position 11
I0618 18:36:30.249552 18854 registrar.cpp:479] Successfully updated 'registry'
I0618 18:36:30.249644 18856 log.cpp:699] Attempting to truncate the log to 11
I0618 18:36:30.249719 18853 master.cpp:2776] Registered slave 20140618-183629-160088899-38847-18832-4 at slave(181)@67.195.138.9:38847 (vesta.apache.org)
I0618 18:36:30.249732 18853 master.cpp:3923] Adding slave 20140618-183629-160088899-38847-18832-4 at slave(181)@67.195.138.9:38847 (vesta.apache.org) with cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000]
I0618 18:36:30.249748 18855 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 12
I0618 18:36:30.249831 18855 slave.cpp:704] Registered with master master@67.195.138.9:38847; given slave ID 20140618-183629-160088899-38847-18832-4
I0618 18:36:30.250000 18855 hierarchical_allocator_process.hpp:444] Added slave 20140618-183629-160088899-38847-18832-4 (vesta.apache.org) with cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000] (and cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000] available)
I0618 18:36:30.250058 18855 hierarchical_allocator_process.hpp:750] Offering cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-4 to framework 20140618-183629-160088899-38847-18832-0001
I0618 18:36:30.250066 18853 replica.cpp:508] Replica received write request for position 12
I0618 18:36:30.250228 18855 hierarchical_allocator_process.hpp:706] Performed allocation for slave 20140618-183629-160088899-38847-18832-4 in 194719ns
I0618 18:36:30.250284 18858 master.hpp:785] Adding offer 20140618-183629-160088899-38847-18832-4 with resources cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-4 (vesta.apache.org)
I0618 18:36:30.250339 18858 master.cpp:3404] Sending 1 offers to framework 20140618-183629-160088899-38847-18832-0001
I0618 18:36:30.250450 18858 sched.cpp:545] Scheduler::resourceOffers took 29929ns
I0618 18:36:30.250731 18856 sched.cpp:746] Stopping framework '20140618-183629-160088899-38847-18832-0000'
I0618 18:36:30.250746 18855 sched.cpp:746] Stopping framework '20140618-183629-160088899-38847-18832-0003'
I0618 18:36:30.250798 18857 sched.cpp:746] Stopping framework '20140618-183629-160088899-38847-18832-0002'
I0618 18:36:30.250820 18851 sched.cpp:746] Stopping framework '20140618-183629-160088899-38847-18832-0001'
I0618 18:36:30.250900 18854 master.cpp:610] Master terminating
I0618 18:36:30.250964 18854 master.hpp:795] Removing offer 20140618-183629-160088899-38847-18832-3 with resources cpus(*):4; mem(*):4096; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-3 (vesta.apache.org)
I0618 18:36:30.251071 18854 master.hpp:795] Removing offer 20140618-183629-160088899-38847-18832-4 with resources cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-4 (vesta.apache.org)
I0618 18:36:30.251143 18854 master.hpp:795] Removing offer 20140618-183629-160088899-38847-18832-2 with resources cpus(*):3; mem(*):2048; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-2 (vesta.apache.org)
I0618 18:36:30.251204 18854 master.hpp:795] Removing offer 20140618-183629-160088899-38847-18832-1 with resources cpus(*):1; mem(*):512; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-1 (vesta.apache.org)
I0618 18:36:30.251297 18854 master.hpp:795] Removing offer 20140618-183629-160088899-38847-18832-0 with resources cpus(*):2; mem(*):1024; disk(*):0; ports(*):[31000-32000] on slave 20140618-183629-160088899-38847-18832-0 (vesta.apache.org)
I0618 18:36:30.251819 18855 slave.cpp:2267] master@67.195.138.9:38847 exited
W0618 18:36:30.251837 18855 slave.cpp:2270] Master disconnected! Waiting for a new master to be elected
I0618 18:36:30.251852 18852 slave.cpp:2267] master@67.195.138.9:38847 exited
W0618 18:36:30.251863 18852 slave.cpp:2270] Master disconnected! Waiting for a new master to be elected
I0618 18:36:30.251936 18856 slave.cpp:2267] master@67.195.138.9:38847 exited
W0618 18:36:30.251953 18856 slave.cpp:2270] Master disconnected! Waiting for a new master to be elected
I0618 18:36:30.252104 18854 slave.cpp:2267] master@67.195.138.9:38847 exited
I0618 18:36:30.252142 18857 slave.cpp:2267] master@67.195.138.9:38847 exited
W0618 18:36:30.253530 18857 slave.cpp:2270] Master disconnected! Waiting for a new master to be elected
W0618 18:36:30.253541 18854 slave.cpp:2270] Master disconnected! Waiting for a new master to be elected
I0618 18:36:30.255983 18853 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 5.86569ms
I0618 18:36:30.256002 18853 replica.cpp:676] Persisted action at 12
I0618 18:36:30.256852 18832 slave.cpp:426] Slave terminating
I0618 18:36:30.259455 18832 slave.cpp:426] Slave terminating
I0618 18:36:30.260615 18832 slave.cpp:426] Slave terminating
I0618 18:36:30.261800 18832 slave.cpp:426] Slave terminating
I0618 18:36:30.262892 18832 slave.cpp:426] Slave terminating
[       OK ] DRFAllocatorTest.DRFAllocatorProcess (508 ms)
[----------] 1 test from DRFAllocatorTest (508 ms total)

[----------] Global test environment tear-down
[==========] 335 tests from 54 test cases ran. (192530 ms total)
[  PASSED  ] 334 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] FaultToleranceTest.SlaveReregisterTerminatedExecutor

 1 FAILED TEST
  YOU HAVE 2 DISABLED TESTS

make[3]: *** [check-local] Error 1
make[3]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make[1]: *** [check] Error 2
make[1]: Leaving directory `/home/hudson/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/build/src'
make: *** [check-recursive] Error 1
I0618 18:36:34.755496 20701 exec.cpp:85] Committing suicide by killing the process group
Build step 'Execute shell' marked build as failure

Jenkins build is back to normal : Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui #2188

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2188/changes>