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/04/30 09:14:50 UTC
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #2100
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2100/changes>
Changes:
[vinod] Fixed distclean break during maven clean up.
[idownes] Fixed some indentation.
[benjamin.hindman] Better error message for protobuf::write.
[benjamin.hindman] Added 'mesos-usage' for use by external containerizers.
[benjamin.hindman] Added test for slave stopping before containerizer launches.
[benjamin.hindman] Allowed passing headers to http::get/post.
[benjamin.hindman] Updated uses of http::post.
[benjamin.hindman] Used memory::shared_ptr instead of boost::shared_ptr.
[benjamin.hindman] Added 'cref' and 'ref' to lambda:: namespace.
[benjamin.hindman] Added process::async overloads for void functions.
[benjamin.hindman] Fix URL decoding of '+' as space.
------------------------------------------
[...truncated 12167 lines...]
I0430 07:14:45.095927 20159 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 14.693982ms
I0430 07:14:45.095958 20159 replica.cpp:664] Persisted action at 0
I0430 07:14:45.096354 20156 replica.cpp:643] Replica received learned notice for position 0
I0430 07:14:45.112054 20156 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 15.675985ms
I0430 07:14:45.112085 20156 replica.cpp:664] Persisted action at 0
I0430 07:14:45.112097 20156 replica.cpp:649] Replica learned NOP action at position 0
I0430 07:14:45.112575 20173 log.cpp:672] Writer started with ending position 0
I0430 07:14:45.114032 20164 leveldb.cpp:436] Reading position from leveldb took 15974ns
I0430 07:14:45.117079 20172 registrar.cpp:308] Successfully recovered registrar
I0430 07:14:45.117110 20172 registrar.cpp:379] Attempting to update the 'registry'
I0430 07:14:45.119696 20170 log.cpp:680] Attempting to append 138 bytes to the log
I0430 07:14:45.119909 20165 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 1
I0430 07:14:45.121414 20169 replica.cpp:508] Replica received write request for position 1
I0430 07:14:45.140153 20169 leveldb.cpp:341] Persisting action (157 bytes) to leveldb took 18.711857ms
I0430 07:14:45.140185 20169 replica.cpp:664] Persisted action at 1
I0430 07:14:45.140605 20151 replica.cpp:643] Replica received learned notice for position 1
I0430 07:14:45.156277 20151 leveldb.cpp:341] Persisting action (159 bytes) to leveldb took 15.574151ms
I0430 07:14:45.156309 20151 replica.cpp:664] Persisted action at 1
I0430 07:14:45.156321 20151 replica.cpp:649] Replica learned APPEND action at position 1
I0430 07:14:45.156697 20151 log.cpp:699] Attempting to truncate the log to 1
I0430 07:14:45.156749 20161 registrar.cpp:427] Successfully updated 'registry'
I0430 07:14:45.157124 20161 master.cpp:780] Recovered 0 slaves from the Registry (100B) ; allowing 10mins for slaves to re-register
I0430 07:14:45.157249 20168 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 2
I0430 07:14:45.158311 20153 replica.cpp:508] Replica received write request for position 2
I0430 07:14:45.161258 20161 slave.cpp:140] Slave started on 18)@140.211.11.27:34679
I0430 07:14:45.161368 20161 credentials.hpp:35] Loading credentials for authentication
W0430 07:14:45.161427 20161 credentials.hpp:48] Failed to stat credentials file 'file:///tmp/ExternalContainerizerTest_Launch_EpuFum/credential': No such file or directory
I0430 07:14:45.161450 20161 slave.cpp:231] Slave using credential for: test-principal
I0430 07:14:45.161546 20161 slave.cpp:244] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0430 07:14:45.161610 20161 slave.cpp:272] Slave hostname: hemera.apache.org
I0430 07:14:45.161623 20161 slave.cpp:273] Slave checkpoint: false
I0430 07:14:45.162005 20161 state.cpp:33] Recovering state from '/tmp/ExternalContainerizerTest_Launch_EpuFum/meta'
I0430 07:14:45.162148 20168 status_update_manager.cpp:193] Recovering status update manager
I0430 07:14:45.162564 20170 slave.cpp:2943] Finished recovery
I0430 07:14:45.164058 20132 sched.cpp:121] Version: 0.19.0
I0430 07:14:45.164284 20174 sched.cpp:217] New master detected at master@140.211.11.27:34679
I0430 07:14:45.164312 20174 sched.cpp:268] Authenticating with master master@140.211.11.27:34679
I0430 07:14:45.164554 20174 authenticatee.hpp:128] Creating new client SASL connection
I0430 07:14:45.164816 20174 master.cpp:2795] Authenticating scheduler(15)@140.211.11.27:34679
I0430 07:14:45.164914 20154 authenticator.hpp:148] Creating new server SASL connection
I0430 07:14:45.165529 20157 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0430 07:14:45.165555 20157 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0430 07:14:45.165595 20157 authenticator.hpp:254] Received SASL authentication start
I0430 07:14:45.165647 20157 authenticator.hpp:342] Authentication requires more steps
I0430 07:14:45.165722 20157 authenticatee.hpp:265] Received SASL authentication step
I0430 07:14:45.165889 20174 authenticator.hpp:282] Received SASL authentication step
I0430 07:14:45.165920 20174 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
I0430 07:14:45.165930 20174 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0430 07:14:45.165940 20174 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0430 07:14:45.165951 20174 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
I0430 07:14:45.165957 20174 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0430 07:14:45.165962 20174 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0430 07:14:45.165977 20174 authenticator.hpp:334] Authentication success
I0430 07:14:45.166046 20174 authenticatee.hpp:305] Authentication success
I0430 07:14:45.166085 20154 master.cpp:2835] Successfully authenticated scheduler(15)@140.211.11.27:34679
I0430 07:14:45.166658 20174 sched.cpp:342] Successfully authenticated with master master@140.211.11.27:34679
I0430 07:14:45.166679 20174 sched.cpp:461] Sending registration request to master@140.211.11.27:34679
I0430 07:14:45.166820 20158 master.cpp:981] Received registration request from scheduler(15)@140.211.11.27:34679
I0430 07:14:45.166910 20158 master.cpp:999] Registering framework 20140430-071445-453759884-34679-20132-0000 at scheduler(15)@140.211.11.27:34679
I0430 07:14:45.167436 20155 sched.cpp:392] Framework registered with 20140430-071445-453759884-34679-20132-0000
I0430 07:14:45.167618 20155 sched.cpp:406] Scheduler::registered took 142411ns
I0430 07:14:45.167680 20163 hierarchical_allocator_process.hpp:332] Added framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:45.167695 20163 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0430 07:14:45.167702 20163 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 7477ns
I0430 07:14:45.168773 20163 slave.cpp:525] New master detected at master@140.211.11.27:34679
I0430 07:14:45.168927 20163 slave.cpp:585] Authenticating with master master@140.211.11.27:34679
I0430 07:14:45.168952 20168 status_update_manager.cpp:167] New master detected at master@140.211.11.27:34679
I0430 07:14:45.169455 20163 slave.cpp:558] Detecting new master
I0430 07:14:45.169468 20159 authenticatee.hpp:128] Creating new client SASL connection
I0430 07:14:45.170161 20159 master.cpp:2795] Authenticating slave(18)@140.211.11.27:34679
I0430 07:14:45.170263 20154 authenticator.hpp:148] Creating new server SASL connection
I0430 07:14:45.170413 20154 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0430 07:14:45.170440 20154 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0430 07:14:45.170763 20174 authenticator.hpp:254] Received SASL authentication start
I0430 07:14:45.170874 20174 authenticator.hpp:342] Authentication requires more steps
I0430 07:14:45.171197 20155 authenticatee.hpp:265] Received SASL authentication step
I0430 07:14:45.171363 20168 authenticator.hpp:282] Received SASL authentication step
I0430 07:14:45.171392 20168 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
I0430 07:14:45.171401 20168 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0430 07:14:45.171412 20168 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0430 07:14:45.171422 20168 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
I0430 07:14:45.171429 20168 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0430 07:14:45.171434 20168 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0430 07:14:45.171447 20168 authenticator.hpp:334] Authentication success
I0430 07:14:45.171484 20168 authenticatee.hpp:305] Authentication success
I0430 07:14:45.171511 20158 master.cpp:2835] Successfully authenticated slave(18)@140.211.11.27:34679
I0430 07:14:45.172602 20153 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 14.090268ms
I0430 07:14:45.172627 20153 replica.cpp:664] Persisted action at 2
I0430 07:14:45.172777 20155 slave.cpp:642] Successfully authenticated with master master@140.211.11.27:34679
I0430 07:14:45.173617 20155 slave.cpp:871] Will retry registration in 6.210883842secs if necessary
I0430 07:14:45.175292 20172 registrar.cpp:379] Attempting to update the 'registry'
I0430 07:14:45.175423 20166 replica.cpp:643] Replica received learned notice for position 2
I0430 07:14:45.188819 20166 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 13.375024ms
I0430 07:14:45.188906 20166 leveldb.cpp:399] Deleting ~1 keys from leveldb took 56264ns
I0430 07:14:45.188921 20166 replica.cpp:664] Persisted action at 2
I0430 07:14:45.188930 20166 replica.cpp:649] Replica learned TRUNCATE action at position 2
I0430 07:14:45.189460 20155 log.cpp:680] Attempting to append 332 bytes to the log
I0430 07:14:45.189753 20171 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3
I0430 07:14:45.191615 20161 replica.cpp:508] Replica received write request for position 3
I0430 07:14:45.204910 20161 leveldb.cpp:341] Persisting action (351 bytes) to leveldb took 13.269444ms
I0430 07:14:45.204939 20161 replica.cpp:664] Persisted action at 3
I0430 07:14:45.205428 20165 replica.cpp:643] Replica received learned notice for position 3
I0430 07:14:45.217073 20165 leveldb.cpp:341] Persisting action (353 bytes) to leveldb took 11.620776ms
I0430 07:14:45.217105 20165 replica.cpp:664] Persisted action at 3
I0430 07:14:45.217116 20165 replica.cpp:649] Replica learned APPEND action at position 3
I0430 07:14:45.217731 20157 registrar.cpp:427] Successfully updated 'registry'
I0430 07:14:45.217835 20157 master.cpp:2169] Admitted slave on hemera.apache.org at slave(18)@140.211.11.27:34679
I0430 07:14:45.217850 20157 master.cpp:3283] Adding slave 20140430-071445-453759884-34679-20132-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0430 07:14:45.218056 20168 slave.cpp:675] Registered with master master@140.211.11.27:34679; given slave ID 20140430-071445-453759884-34679-20132-0
I0430 07:14:45.218135 20162 log.cpp:699] Attempting to truncate the log to 3
I0430 07:14:45.218255 20157 hierarchical_allocator_process.hpp:445] Added slave 20140430-071445-453759884-34679-20132-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)
I0430 07:14:45.218659 20159 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4
I0430 07:14:45.218742 20157 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-071445-453759884-34679-20132-0 to framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:45.219079 20157 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140430-071445-453759884-34679-20132-0 in 368873ns
I0430 07:14:45.219281 20157 master.hpp:586] Adding offer 20140430-071445-453759884-34679-20132-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-071445-453759884-34679-20132-0 (hemera.apache.org)
I0430 07:14:45.219336 20157 master.cpp:2744] Sending 1 offers to framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:45.219938 20158 replica.cpp:508] Replica received write request for position 4
I0430 07:14:45.220268 20167 sched.cpp:529] Scheduler::resourceOffers took 586095ns
I0430 07:14:45.221384 20154 master.hpp:596] Removing offer 20140430-071445-453759884-34679-20132-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-071445-453759884-34679-20132-0 (hemera.apache.org)
I0430 07:14:45.221451 20154 master.cpp:1806] Processing reply for offers: [ 20140430-071445-453759884-34679-20132-0 ] on slave 20140430-071445-453759884-34679-20132-0 (hemera.apache.org) for framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:45.221530 20154 master.hpp:558] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-071445-453759884-34679-20132-0 (hemera.apache.org)
I0430 07:14:45.221596 20154 master.cpp:2919] Launching task 1 of framework 20140430-071445-453759884-34679-20132-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-071445-453759884-34679-20132-0 (hemera.apache.org)
I0430 07:14:45.221796 20161 slave.cpp:905] Got assigned task 1 for framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:45.222100 20161 slave.cpp:1015] Launching task 1 for framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:45.224656 20161 slave.cpp:1125] Queuing task '1' for executor 1 of framework '20140430-071445-453759884-34679-20132-0000
I0430 07:14:45.224709 20161 slave.cpp:486] Successfully attached file '/tmp/ExternalContainerizerTest_Launch_EpuFum/slaves/20140430-071445-453759884-34679-20132-0/frameworks/20140430-071445-453759884-34679-20132-0000/executors/1/runs/c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:45.224736 20167 external_containerizer.cpp:266] Launching container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:45.224830 20167 external_containerizer.cpp:804] Invoking external containerizer for method 'launch'
I0430 07:14:45.224930 20167 external_containerizer.cpp:818] user: jenkins
I0430 07:14:45.233916 20158 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 13.87842ms
I0430 07:14:45.234233 20158 replica.cpp:664] Persisted action at 4
I0430 07:14:45.239241 20151 replica.cpp:643] Replica received learned notice for position 4
I0430 07:14:45.240955 20167 external_containerizer.cpp:378] Wait triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:45.265941 20151 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 26.664443ms
I0430 07:14:45.266000 20151 leveldb.cpp:399] Deleting ~2 keys from leveldb took 26931ns
I0430 07:14:45.266013 20151 replica.cpp:664] Persisted action at 4
I0430 07:14:45.266022 20151 replica.cpp:649] Replica learned TRUNCATE action at position 4
I0430 07:14:46.005877 20173 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 85221ns
2014-04-30 07:14:46,684:20132(0x2b0618d35700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:41518] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0430 07:14:47.006727 20174 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 37120ns
I0430 07:14:47.494434 20154 slave.cpp:1598] Got registration for executor '1' of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:47.495302 20154 slave.cpp:1717] Flushing queued task 1 for executor '1' of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:47.495360 20170 external_containerizer.cpp:476] Update triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:47.499454 20156 slave.cpp:1953] Handling status update TASK_RUNNING (UUID: e194d707-5e52-469f-9b1d-8f63d4cca17b) for task 1 of framework 20140430-071445-453759884-34679-20132-0000 from executor(1)@140.211.11.27:34700
I0430 07:14:47.499652 20156 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: e194d707-5e52-469f-9b1d-8f63d4cca17b) for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:47.499673 20156 status_update_manager.cpp:499] Creating StatusUpdate stream for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:47.500179 20156 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: e194d707-5e52-469f-9b1d-8f63d4cca17b) for task 1 of framework 20140430-071445-453759884-34679-20132-0000 to master@140.211.11.27:34679
I0430 07:14:47.500692 20174 slave.cpp:2070] Status update manager successfully handled status update TASK_RUNNING (UUID: e194d707-5e52-469f-9b1d-8f63d4cca17b) for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:47.500716 20174 slave.cpp:2076] Sending acknowledgement for status update TASK_RUNNING (UUID: e194d707-5e52-469f-9b1d-8f63d4cca17b) for task 1 of framework 20140430-071445-453759884-34679-20132-0000 to executor(1)@140.211.11.27:34700
I0430 07:14:47.500833 20164 master.cpp:2444] Status update TASK_RUNNING (UUID: e194d707-5e52-469f-9b1d-8f63d4cca17b) for task 1 of framework 20140430-071445-453759884-34679-20132-0000 from slave(18)@140.211.11.27:34679
I0430 07:14:47.501085 20170 sched.cpp:620] Scheduler::statusUpdate took 333869ns
I0430 07:14:47.502003 20168 status_update_manager.cpp:398] Received status update acknowledgement (UUID: e194d707-5e52-469f-9b1d-8f63d4cca17b) for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:47.502421 20167 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: e194d707-5e52-469f-9b1d-8f63d4cca17b) for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:47.514267 20159 external_containerizer.cpp:550] Usage triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:48.007387 20169 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 66424ns
I0430 07:14:48.188813 20169 external_containerizer.cpp:341] Launch validation callback triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:48.188833 20169 external_containerizer.cpp:350] Launch finishing up for container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:48.189400 20169 external_containerizer.cpp:396] Wait continuation triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:48.189424 20169 external_containerizer.cpp:804] Invoking external containerizer for method 'wait'
I0430 07:14:48.189438 20169 external_containerizer.cpp:818] user: jenkins
I0430 07:14:48.189805 20157 slave.cpp:2282] Monitoring executor '1' of framework '20140430-071445-453759884-34679-20132-0000' in container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:48.206432 20169 external_containerizer.cpp:496] Update continuation triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:48.206492 20169 external_containerizer.cpp:804] Invoking external containerizer for method 'update'
I0430 07:14:48.206508 20169 external_containerizer.cpp:818] user: jenkins
I0430 07:14:48.221786 20169 external_containerizer.cpp:568] Usage continuation on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:48.221815 20169 external_containerizer.cpp:804] Invoking external containerizer for method 'usage'
I0430 07:14:48.221828 20169 external_containerizer.cpp:818] user: jenkins
I0430 07:14:48.236387 20169 external_containerizer.cpp:364] Launch confirmation callback triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.007865 20165 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 81290ns
I0430 07:14:49.189896 20168 external_containerizer.cpp:532] Update callback triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.190199 20151 external_containerizer.cpp:611] Usage callback triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.192558 20161 master.cpp:1912] Asked to kill task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.192589 20161 master.cpp:1995] Telling slave 20140430-071445-453759884-34679-20132-0 (hemera.apache.org) to kill task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.192630 20161 slave.cpp:1181] Asked to kill task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.193553 20167 external_containerizer.cpp:550] Usage triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.193631 20167 external_containerizer.cpp:568] Usage continuation on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.193645 20167 external_containerizer.cpp:804] Invoking external containerizer for method 'usage'
I0430 07:14:49.193655 20167 external_containerizer.cpp:818] user: jenkins
I0430 07:14:49.501615 20163 slave.cpp:1953] Handling status update TASK_KILLED (UUID: 7567ece3-1cc5-4433-b397-78dea46a2d9a) for task 1 of framework 20140430-071445-453759884-34679-20132-0000 from executor(1)@140.211.11.27:34700
I0430 07:14:49.501816 20163 slave.cpp:3444] Terminating task 1
I0430 07:14:49.502852 20153 status_update_manager.cpp:320] Received status update TASK_KILLED (UUID: 7567ece3-1cc5-4433-b397-78dea46a2d9a) for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.502864 20173 external_containerizer.cpp:476] Update triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.502949 20153 status_update_manager.cpp:373] Forwarding status update TASK_KILLED (UUID: 7567ece3-1cc5-4433-b397-78dea46a2d9a) for task 1 of framework 20140430-071445-453759884-34679-20132-0000 to master@140.211.11.27:34679
I0430 07:14:49.503053 20173 external_containerizer.cpp:496] Update continuation triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.503460 20163 master.cpp:2444] Status update TASK_KILLED (UUID: 7567ece3-1cc5-4433-b397-78dea46a2d9a) for task 1 of framework 20140430-071445-453759884-34679-20132-0000 from slave(18)@140.211.11.27:34679
I0430 07:14:49.503559 20173 external_containerizer.cpp:804] Invoking external containerizer for method 'update'
I0430 07:14:49.503922 20173 external_containerizer.cpp:818] user: jenkins
I0430 07:14:49.504276 20174 slave.cpp:2070] Status update manager successfully handled status update TASK_KILLED (UUID: 7567ece3-1cc5-4433-b397-78dea46a2d9a) for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.504305 20174 slave.cpp:2076] Sending acknowledgement for status update TASK_KILLED (UUID: 7567ece3-1cc5-4433-b397-78dea46a2d9a) for task 1 of framework 20140430-071445-453759884-34679-20132-0000 to executor(1)@140.211.11.27:34700
I0430 07:14:49.506994 20163 master.hpp:576] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-071445-453759884-34679-20132-0 (hemera.apache.org)
I0430 07:14:49.507316 20161 sched.cpp:620] Scheduler::statusUpdate took 525600ns
I0430 07:14:49.508543 20161 sched.cpp:731] Stopping framework '20140430-071445-453759884-34679-20132-0000'
I0430 07:14:49.508687 20171 hierarchical_allocator_process.hpp:637] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20140430-071445-453759884-34679-20132-0 from framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.508822 20160 master.cpp:551] Master terminating
I0430 07:14:49.509269 20166 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 7567ece3-1cc5-4433-b397-78dea46a2d9a) for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.509392 20166 status_update_manager.cpp:530] Cleaning up status update stream for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.510393 20158 slave.cpp:2151] master@140.211.11.27:34679 exited
W0430 07:14:49.510465 20158 slave.cpp:2154] Master disconnected! Waiting for a new master to be elected
I0430 07:14:49.510941 20157 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: 7567ece3-1cc5-4433-b397-78dea46a2d9a) for task 1 of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:49.510969 20157 slave.cpp:3468] Completing task 1
I0430 07:14:49.523191 20152 external_containerizer.cpp:378] Wait triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.523298 20152 external_containerizer.cpp:637] Destroy triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.523362 20152 external_containerizer.cpp:396] Wait continuation triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.523376 20152 external_containerizer.cpp:804] Invoking external containerizer for method 'wait'
I0430 07:14:49.523387 20152 external_containerizer.cpp:818] user: jenkins
I0430 07:14:49.539129 20152 external_containerizer.cpp:655] Destroy continuation on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
I0430 07:14:49.539161 20152 external_containerizer.cpp:804] Invoking external containerizer for method 'destroy'
I0430 07:14:49.539190 20152 external_containerizer.cpp:818] user: jenkins
2014-04-30 07:14:50,020:20132(0x2b0618d35700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:41518] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0430 07:14:50.191496 20172 external_containerizer.cpp:448] Wait callback triggered on container 'c4df3cfc-3a53-4283-ae14-596a2b8de9d2'
E0430 07:14:50.193096 20165 slave.cpp:2324] Termination of executor '1' of framework '20140430-071445-453759884-34679-20132-0000' failed: External containerizer failed (status: 1)
I0430 07:14:50.194201 20165 slave.cpp:2479] Cleaning up executor '1' of framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:50.195521 20165 slave.cpp:2554] Cleaning up framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:50.195538 20160 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_EpuFum/slaves/20140430-071445-453759884-34679-20132-0/frameworks/20140430-071445-453759884-34679-20132-0000/executors/1/runs/c4df3cfc-3a53-4283-ae14-596a2b8de9d2' for gc 6.99999774381926days in the future
I0430 07:14:50.195688 20160 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_EpuFum/slaves/20140430-071445-453759884-34679-20132-0/frameworks/20140430-071445-453759884-34679-20132-0000/executors/1' for gc 6.99999774260444days in the future
I0430 07:14:50.195731 20173 status_update_manager.cpp:282] Closing status update streams for framework 20140430-071445-453759884-34679-20132-0000
I0430 07:14:50.195739 20160 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_EpuFum/slaves/20140430-071445-453759884-34679-20132-0/frameworks/20140430-071445-453759884-34679-20132-0000' for gc 6.99999773615704days in the future
../../src/tests/cluster.hpp:462: Failure
(wait).failure(): External containerizer failed (status: 1)
I0430 07:14:50.211710 20172 external_containerizer.cpp:762] Killed the following process tree/s:
[
]
I0430 07:14:50.211751 20172 external_containerizer.cpp:717] Callback performing final cleanup of running state
F0430 07:14:50.211751 20132 logging.cpp:55] RAW: Pure virtual method called
@ 0x2b03838419ad google::LogMessage::Fail()
@ 0x2b038384951a google::RawLog__()
@ 0x51dd69 mesos::internal::tests::Cluster::Slaves::shutdown()
@ 0x740309 mesos::internal::tests::MesosTest::ShutdownSlaves()
@ 0x8bf4cd testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x8b7ab3 testing::Test::Run()
@ 0x8b7be6 testing::TestInfo::Run()
@ 0x8b7d27 testing::TestCase::Run()
@ 0x8b808e testing::internal::UnitTestImpl::RunAllTests()
@ 0x8bf04d testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x8b715e testing::UnitTest::Run()
@ 0x4a54a0 main
@ 0x2b0384c8d76d (unknown)
@ 0x4b08a1 (unknown)
make[4]: *** [check-local] Aborted (core dumped)
make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build'
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal :
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #2103
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2103/changes>
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #2102
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2102/changes>
Changes:
[bmahler] Improved timing accuracy in the Registrar.
[dlester] Adds Netflix to #PoweredByMesos list.
[yujie.jay] Updated the 'contains' method in stout IntervalSet to support more
[yujie.jay] Added stream functions for stout Interval and IntervalSet.
[benjamin.hindman] Introduced base64::encode/decode in stout.
[benjamin.hindman] Decode percent-encoded paths.
[yujie.jay] Fixed gcc-4.8 compilation error for stout interval set.
------------------------------------------
[...truncated 25198 lines...]
I0501 07:42:05.184708 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 23987ns
I0501 07:42:06.184794 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:06.184834 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 50668ns
I0501 07:42:07.185907 31972 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:07.185920 31972 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15254ns
I0501 07:42:08.186988 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:08.187003 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 19272ns
I0501 07:42:09.188068 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:09.188081 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16344ns
I0501 07:42:09.697610 31971 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:10.189165 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:10.189180 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 19632ns
I0501 07:42:11.190234 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:11.190248 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15810ns
I0501 07:42:12.191321 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:12.191340 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 21224ns
I0501 07:42:13.192404 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:13.192419 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17408ns
I0501 07:42:14.193490 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:14.193503 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18989ns
I0501 07:42:14.697989 31972 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:15.194555 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:15.194569 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17961ns
I0501 07:42:16.195636 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:16.195648 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15162ns
I0501 07:42:17.196722 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:17.196737 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18482ns
I0501 07:42:18.197806 31972 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:18.197819 31972 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14121ns
I0501 07:42:19.198890 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:19.198906 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17414ns
I0501 07:42:19.698391 31970 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:20.199962 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:20.199977 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20264ns
I0501 07:42:21.201040 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:21.201056 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18675ns
I0501 07:42:22.202123 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:22.202142 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 19592ns
I0501 07:42:23.203209 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:23.203223 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18003ns
I0501 07:42:24.204289 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:24.204304 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16226ns
I0501 07:42:24.698781 31976 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:25.205421 31972 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:25.205454 31972 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 47545ns
I0501 07:42:26.206442 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:26.206459 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18641ns
I0501 07:42:27.206522 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:27.206534 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 13086ns
I0501 07:42:28.206598 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:28.206614 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20121ns
I0501 07:42:29.206678 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:29.206691 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16325ns
I0501 07:42:29.699257 31977 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:30.206770 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:30.206782 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14180ns
I0501 07:42:31.207849 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:31.207866 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20108ns
I0501 07:42:32.208976 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:32.208998 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 27446ns
I0501 07:42:33.210106 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:33.210119 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20387ns
I0501 07:42:34.211189 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:34.211202 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16208ns
I0501 07:42:34.699674 31977 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:35.212255 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:35.212267 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17498ns
I0501 07:42:36.213393 31972 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:36.213425 31972 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 41902ns
I0501 07:42:37.214359 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:37.214375 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16898ns
I0501 07:42:38.214447 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:38.214460 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 19616ns
I0501 07:42:39.214524 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:39.214534 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 13997ns
I0501 07:42:39.700057 31973 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:40.214586 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:40.214599 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14888ns
I0501 07:42:41.214663 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:41.214680 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 19631ns
I0501 07:42:42.214747 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:42.214761 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 19529ns
I0501 07:42:43.214818 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:43.214830 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15152ns
I0501 07:42:44.214893 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:44.214905 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14672ns
I0501 07:42:44.700439 31970 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:45.214965 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:45.214989 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 30569ns
I0501 07:42:46.215042 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:46.215059 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20265ns
I0501 07:42:47.215122 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:47.215137 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16064ns
I0501 07:42:48.215205 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:48.215217 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15116ns
I0501 07:42:49.215287 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:49.215301 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15707ns
I0501 07:42:49.700855 31971 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:50.215384 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:50.215399 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18338ns
I0501 07:42:51.215458 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:51.215473 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18098ns
I0501 07:42:52.215535 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:52.215548 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 12879ns
I0501 07:42:53.215610 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:53.215622 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15269ns
I0501 07:42:54.215690 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:54.215703 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16625ns
I0501 07:42:54.701227 31970 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:42:55.215751 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:55.215764 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17108ns
I0501 07:42:56.215834 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:56.215847 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18008ns
I0501 07:42:57.215904 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:57.215917 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16209ns
I0501 07:42:58.215981 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:58.215992 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15710ns
I0501 07:42:59.216053 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:42:59.216065 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 13365ns
I0501 07:42:59.701555 31970 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:00.217142 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:00.217159 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20921ns
I0501 07:43:01.217213 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:01.217224 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 13616ns
I0501 07:43:02.217288 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:02.217301 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15077ns
I0501 07:43:03.217367 31972 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:03.217381 31972 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16152ns
I0501 07:43:04.217449 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:04.217465 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 30161ns
I0501 07:43:04.701964 31970 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:05.218540 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:05.218551 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15944ns
I0501 07:43:06.219622 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:06.219636 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17667ns
I0501 07:43:07.220698 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:07.220715 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 22128ns
I0501 07:43:08.221757 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:08.221771 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14225ns
I0501 07:43:09.222839 31972 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:09.222853 31972 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17592ns
I0501 07:43:09.702332 31976 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:10.223914 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:10.223927 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 13271ns
I0501 07:43:11.224992 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:11.225005 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18294ns
I0501 07:43:12.226058 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:12.226069 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14361ns
I0501 07:43:13.227159 31972 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:13.227183 31972 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 30794ns
I0501 07:43:14.228148 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:14.228162 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15194ns
I0501 07:43:14.702671 31976 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:15.228199 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:15.228221 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 23264ns
I0501 07:43:16.228281 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:16.228296 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20426ns
I0501 07:43:17.228341 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:17.228377 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 37940ns
I0501 07:43:18.228420 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:18.228430 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 12849ns
I0501 07:43:19.228497 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:19.228512 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17076ns
I0501 07:43:19.703037 31972 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:20.228560 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:20.228572 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 13421ns
I0501 07:43:21.228633 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:21.228644 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14154ns
I0501 07:43:22.228713 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:22.228724 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14633ns
I0501 07:43:23.228792 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:23.228806 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 12330ns
I0501 07:43:24.228880 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:24.228899 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 22664ns
I0501 07:43:24.703405 31970 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:25.229992 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:25.230005 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14997ns
I0501 07:43:26.231117 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:26.231133 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 22805ns
I0501 07:43:27.231194 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:27.231209 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17862ns
I0501 07:43:28.231273 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:28.231287 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15866ns
I0501 07:43:29.231355 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:29.231369 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17478ns
I0501 07:43:29.703961 31972 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:30.231508 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:30.231541 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 36628ns
I0501 07:43:31.232614 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:31.232642 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 37999ns
I0501 07:43:32.233477 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:32.233495 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 24143ns
I0501 07:43:33.233554 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:33.233568 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17480ns
I0501 07:43:34.233634 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:34.233647 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15854ns
I0501 07:43:34.704157 31971 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:35.233705 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:35.233717 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17333ns
I0501 07:43:36.233784 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:36.233810 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 38683ns
I0501 07:43:37.234912 31972 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:37.234926 31972 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20813ns
I0501 07:43:38.235985 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:38.235996 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14042ns
I0501 07:43:39.237071 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:39.237082 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 14129ns
I0501 07:43:39.704535 31971 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:40.238133 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:40.238152 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20643ns
I0501 07:43:41.239209 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:41.239224 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 20036ns
I0501 07:43:42.240278 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:42.240293 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18060ns
I0501 07:43:43.241345 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:43.241363 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18788ns
I0501 07:43:44.242451 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:44.242465 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16589ns
I0501 07:43:44.705016 31972 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:45.242563 31973 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:45.242578 31973 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 18864ns
I0501 07:43:46.243639 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:46.243654 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 19586ns
I0501 07:43:47.244710 31977 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:47.244726 31977 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 21383ns
I0501 07:43:48.245779 31974 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:48.245791 31974 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17438ns
I0501 07:43:49.246858 31970 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:49.246872 31970 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 17786ns
I0501 07:43:49.705335 31975 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0501 07:43:50.247934 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:50.247947 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15192ns
I0501 07:43:51.249042 31971 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:51.249063 31971 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 28867ns
I0501 07:43:52.250025 31975 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:52.250038 31975 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 15425ns
I0501 07:43:53.250092 31976 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0501 07:43:53.250104 31976 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 16409ns
Build timed out (after 120 minutes). Marking the build as failed.
Build was aborted
make[4]: *** [check-local] Terminated
make[4]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/mesos-0.19.0/_build/src'>
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/mesos-0.19.0/_build/src'>
make[2]: *** [check] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/mesos-0.19.0/_build/src'>
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/ws/build/mesos-0.19.0/_build'>
make: *** [distcheck] Error 1
Build failed in Jenkins:
Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME #2101
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/2101/changes>
Changes:
[vinod] Used security group names to look up clusters instead of security group
[bmahler] Fixed a flaky use of FUTURE_DISPATCH.
[bmahler] Allowed timing of Future completions in Timer.
------------------------------------------
[...truncated 23546 lines...]
I0430 17:34:47.697932 23809 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 15.779387ms
I0430 17:34:47.697963 23809 replica.cpp:664] Persisted action at 0
I0430 17:34:47.697975 23809 replica.cpp:649] Replica learned NOP action at position 0
I0430 17:34:47.699738 23793 log.cpp:672] Writer started with ending position 0
I0430 17:34:47.701735 23815 leveldb.cpp:436] Reading position from leveldb took 11431ns
I0430 17:34:47.704406 23794 registrar.cpp:308] Successfully recovered registrar
I0430 17:34:47.704435 23794 registrar.cpp:379] Attempting to update the 'registry'
I0430 17:34:47.706820 23814 log.cpp:680] Attempting to append 138 bytes to the log
I0430 17:34:47.706949 23814 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 1
I0430 17:34:47.707844 23800 replica.cpp:508] Replica received write request for position 1
I0430 17:34:47.722025 23800 leveldb.cpp:341] Persisting action (157 bytes) to leveldb took 14.1573ms
I0430 17:34:47.722057 23800 replica.cpp:664] Persisted action at 1
I0430 17:34:47.722661 23802 replica.cpp:643] Replica received learned notice for position 1
I0430 17:34:47.738065 23802 leveldb.cpp:341] Persisting action (159 bytes) to leveldb took 15.187333ms
I0430 17:34:47.738096 23802 replica.cpp:664] Persisted action at 1
I0430 17:34:47.738108 23802 replica.cpp:649] Replica learned APPEND action at position 1
I0430 17:34:47.738582 23813 registrar.cpp:427] Successfully updated 'registry'
I0430 17:34:47.738726 23793 log.cpp:699] Attempting to truncate the log to 1
I0430 17:34:47.739212 23796 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 2
I0430 17:34:47.739686 23799 master.cpp:780] Recovered 0 slaves from the Registry (100B) ; allowing 10mins for slaves to re-register
I0430 17:34:47.742480 23804 replica.cpp:508] Replica received write request for position 2
I0430 17:34:47.745002 23801 slave.cpp:140] Slave started on 68)@140.211.11.27:55610
I0430 17:34:47.745157 23801 credentials.hpp:35] Loading credentials for authentication
W0430 17:34:47.745621 23801 credentials.hpp:48] Failed to stat credentials file 'file:///tmp/ExternalContainerizerTest_Launch_gUjOZ6/credential': No such file or directory
I0430 17:34:47.745650 23801 slave.cpp:231] Slave using credential for: test-principal
I0430 17:34:47.745751 23801 slave.cpp:244] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0430 17:34:47.745826 23801 slave.cpp:272] Slave hostname: hemera.apache.org
I0430 17:34:47.745836 23801 slave.cpp:273] Slave checkpoint: false
I0430 17:34:47.746580 23806 state.cpp:33] Recovering state from '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/meta'
I0430 17:34:47.747006 23808 status_update_manager.cpp:193] Recovering status update manager
I0430 17:34:47.747320 23815 slave.cpp:2943] Finished recovery
I0430 17:34:47.748061 23810 slave.cpp:525] New master detected at master@140.211.11.27:55610
I0430 17:34:47.748155 23810 slave.cpp:585] Authenticating with master master@140.211.11.27:55610
I0430 17:34:47.748180 23803 status_update_manager.cpp:167] New master detected at master@140.211.11.27:55610
I0430 17:34:47.748216 23810 slave.cpp:558] Detecting new master
I0430 17:34:47.748244 23797 authenticatee.hpp:128] Creating new client SASL connection
I0430 17:34:47.748450 23803 master.cpp:2795] Authenticating slave(68)@140.211.11.27:55610
I0430 17:34:47.749156 23800 authenticator.hpp:148] Creating new server SASL connection
I0430 17:34:47.749620 23795 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0430 17:34:47.749706 23795 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0430 17:34:47.749745 23795 authenticator.hpp:254] Received SASL authentication start
I0430 17:34:47.749846 23795 authenticator.hpp:342] Authentication requires more steps
I0430 17:34:47.750031 23795 authenticatee.hpp:265] Received SASL authentication step
I0430 17:34:47.750159 23796 authenticator.hpp:282] Received SASL authentication step
I0430 17:34:47.750186 23796 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
I0430 17:34:47.750195 23796 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0430 17:34:47.750206 23796 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0430 17:34:47.750216 23796 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
I0430 17:34:47.750222 23796 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0430 17:34:47.750228 23796 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0430 17:34:47.750241 23796 authenticator.hpp:334] Authentication success
I0430 17:34:47.750278 23796 authenticatee.hpp:305] Authentication success
I0430 17:34:47.750356 23814 master.cpp:2835] Successfully authenticated slave(68)@140.211.11.27:55610
I0430 17:34:47.750797 23793 slave.cpp:642] Successfully authenticated with master master@140.211.11.27:55610
I0430 17:34:47.750856 23793 slave.cpp:871] Will retry registration in 5.749806799secs if necessary
I0430 17:34:47.751086 23808 registrar.cpp:379] Attempting to update the 'registry'
I0430 17:34:47.752308 23773 sched.cpp:121] Version: 0.19.0
I0430 17:34:47.752837 23812 sched.cpp:217] New master detected at master@140.211.11.27:55610
I0430 17:34:47.752864 23812 sched.cpp:268] Authenticating with master master@140.211.11.27:55610
I0430 17:34:47.752943 23799 authenticatee.hpp:128] Creating new client SASL connection
I0430 17:34:47.753176 23799 master.cpp:2795] Authenticating scheduler(78)@140.211.11.27:55610
I0430 17:34:47.753326 23797 authenticator.hpp:148] Creating new server SASL connection
I0430 17:34:47.753536 23797 authenticatee.hpp:219] Received SASL authentication mechanisms: CRAM-MD5
I0430 17:34:47.753700 23797 authenticatee.hpp:245] Attempting to authenticate with mechanism 'CRAM-MD5'
I0430 17:34:47.753880 23807 authenticator.hpp:254] Received SASL authentication start
I0430 17:34:47.753983 23807 authenticator.hpp:342] Authentication requires more steps
I0430 17:34:47.754142 23799 authenticatee.hpp:265] Received SASL authentication step
I0430 17:34:47.754215 23799 authenticator.hpp:282] Received SASL authentication step
I0430 17:34:47.754238 23799 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
I0430 17:34:47.754246 23799 auxprop.cpp:153] Looking up auxiliary property '*userPassword'
I0430 17:34:47.754256 23799 auxprop.cpp:153] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0430 17:34:47.754266 23799 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
I0430 17:34:47.754272 23799 auxprop.cpp:103] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0430 17:34:47.754277 23799 auxprop.cpp:103] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0430 17:34:47.754289 23799 authenticator.hpp:334] Authentication success
I0430 17:34:47.754706 23807 master.cpp:2835] Successfully authenticated scheduler(78)@140.211.11.27:55610
I0430 17:34:47.754750 23795 authenticatee.hpp:305] Authentication success
I0430 17:34:47.755344 23797 sched.cpp:342] Successfully authenticated with master master@140.211.11.27:55610
I0430 17:34:47.755367 23797 sched.cpp:461] Sending registration request to master@140.211.11.27:55610
I0430 17:34:47.755465 23800 master.cpp:981] Received registration request from scheduler(78)@140.211.11.27:55610
I0430 17:34:47.755707 23800 master.cpp:999] Registering framework 20140430-173447-453759884-55610-23773-0000 at scheduler(78)@140.211.11.27:55610
I0430 17:34:47.755936 23813 sched.cpp:392] Framework registered with 20140430-173447-453759884-55610-23773-0000
I0430 17:34:47.755985 23813 sched.cpp:406] Scheduler::registered took 25411ns
I0430 17:34:47.756237 23807 hierarchical_allocator_process.hpp:332] Added framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:47.756259 23807 hierarchical_allocator_process.hpp:726] No resources available to allocate!
I0430 17:34:47.756271 23807 hierarchical_allocator_process.hpp:688] Performed allocation for 0 slaves in 13604ns
I0430 17:34:47.758844 23804 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 16.340156ms
I0430 17:34:47.758872 23804 replica.cpp:664] Persisted action at 2
I0430 17:34:47.759532 23813 replica.cpp:643] Replica received learned notice for position 2
I0430 17:34:47.774960 23813 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.404479ms
I0430 17:34:47.775022 23813 leveldb.cpp:399] Deleting ~1 keys from leveldb took 34769ns
I0430 17:34:47.775038 23813 replica.cpp:664] Persisted action at 2
I0430 17:34:47.775046 23813 replica.cpp:649] Replica learned TRUNCATE action at position 2
I0430 17:34:47.775553 23814 log.cpp:680] Attempting to append 332 bytes to the log
I0430 17:34:47.776111 23801 coordinator.cpp:339] Coordinator attempting to write APPEND action at position 3
I0430 17:34:47.777572 23814 replica.cpp:508] Replica received write request for position 3
I0430 17:34:47.795042 23814 leveldb.cpp:341] Persisting action (351 bytes) to leveldb took 17.359961ms
I0430 17:34:47.795071 23814 replica.cpp:664] Persisted action at 3
I0430 17:34:47.796146 23807 replica.cpp:643] Replica received learned notice for position 3
I0430 17:34:47.811107 23807 leveldb.cpp:341] Persisting action (353 bytes) to leveldb took 14.935802ms
I0430 17:34:47.811139 23807 replica.cpp:664] Persisted action at 3
I0430 17:34:47.811151 23807 replica.cpp:649] Replica learned APPEND action at position 3
I0430 17:34:47.811533 23802 registrar.cpp:427] Successfully updated 'registry'
I0430 17:34:47.811877 23813 log.cpp:699] Attempting to truncate the log to 3
I0430 17:34:47.812146 23800 coordinator.cpp:339] Coordinator attempting to write TRUNCATE action at position 4
I0430 17:34:47.812217 23807 master.cpp:2169] Admitted slave on hemera.apache.org at slave(68)@140.211.11.27:55610
I0430 17:34:47.812240 23807 master.cpp:3283] Adding slave 20140430-173447-453759884-55610-23773-0 at hemera.apache.org with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0430 17:34:47.812356 23801 slave.cpp:675] Registered with master master@140.211.11.27:55610; given slave ID 20140430-173447-453759884-55610-23773-0
I0430 17:34:47.812572 23802 hierarchical_allocator_process.hpp:445] Added slave 20140430-173447-453759884-55610-23773-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)
I0430 17:34:47.812644 23802 hierarchical_allocator_process.hpp:752] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 to framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:47.813200 23802 hierarchical_allocator_process.hpp:708] Performed allocation for slave 20140430-173447-453759884-55610-23773-0 in 461307ns
I0430 17:34:47.813387 23805 master.hpp:586] Adding offer 20140430-173447-453759884-55610-23773-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org)
I0430 17:34:47.813451 23805 master.cpp:2744] Sending 1 offers to framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:47.813621 23805 sched.cpp:529] Scheduler::resourceOffers took 52681ns
I0430 17:34:47.814942 23806 replica.cpp:508] Replica received write request for position 4
I0430 17:34:47.815641 23801 master.hpp:596] Removing offer 20140430-173447-453759884-55610-23773-0 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org)
I0430 17:34:47.815709 23801 master.cpp:1806] Processing reply for offers: [ 20140430-173447-453759884-55610-23773-0 ] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) for framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:47.815788 23801 master.hpp:558] Adding task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org)
I0430 17:34:47.815840 23801 master.cpp:2919] Launching task 1 of framework 20140430-173447-453759884-55610-23773-0000 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org)
I0430 17:34:47.815979 23814 slave.cpp:905] Got assigned task 1 for framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:47.816241 23814 slave.cpp:1015] Launching task 1 for framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:47.818881 23814 slave.cpp:1125] Queuing task '1' for executor 1 of framework '20140430-173447-453759884-55610-23773-0000
I0430 17:34:47.818878 23799 external_containerizer.cpp:266] Launching container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:47.818953 23814 slave.cpp:486] Successfully attached file '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/slaves/20140430-173447-453759884-55610-23773-0/frameworks/20140430-173447-453759884-55610-23773-0000/executors/1/runs/d7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:47.818992 23799 external_containerizer.cpp:804] Invoking external containerizer for method 'launch'
I0430 17:34:47.819042 23799 external_containerizer.cpp:818] user: jenkins
I0430 17:34:47.833279 23806 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 18.173754ms
I0430 17:34:47.833389 23806 replica.cpp:664] Persisted action at 4
I0430 17:34:47.836529 23796 replica.cpp:643] Replica received learned notice for position 4
I0430 17:34:47.837968 23799 external_containerizer.cpp:378] Wait triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:47.851956 23796 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.394267ms
I0430 17:34:47.852027 23796 leveldb.cpp:399] Deleting ~2 keys from leveldb took 37035ns
I0430 17:34:47.852042 23796 replica.cpp:664] Persisted action at 4
I0430 17:34:47.852052 23796 replica.cpp:649] Replica learned TRUNCATE action at position 4
I0430 17:34:48.593334 23801 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 176709ns
I0430 17:34:49.594342 23796 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 18749ns
I0430 17:34:49.964293 23796 slave.cpp:1598] Got registration for executor '1' of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:49.964897 23796 slave.cpp:1717] Flushing queued task 1 for executor '1' of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:49.965246 23795 external_containerizer.cpp:476] Update triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:49.970732 23815 slave.cpp:1953] Handling status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 from executor(1)@140.211.11.27:46320
I0430 17:34:49.971582 23811 status_update_manager.cpp:320] Received status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:49.971606 23811 status_update_manager.cpp:499] Creating StatusUpdate stream for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:49.971721 23811 status_update_manager.cpp:373] Forwarding status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 to master@140.211.11.27:55610
I0430 17:34:49.971968 23815 master.cpp:2444] Status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 from slave(68)@140.211.11.27:55610
I0430 17:34:49.972627 23804 slave.cpp:2070] Status update manager successfully handled status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:49.972669 23804 slave.cpp:2076] Sending acknowledgement for status update TASK_RUNNING (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 to executor(1)@140.211.11.27:46320
I0430 17:34:49.973084 23803 sched.cpp:620] Scheduler::statusUpdate took 279844ns
I0430 17:34:49.973749 23795 status_update_manager.cpp:398] Received status update acknowledgement (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:49.974501 23804 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: d7ec60ad-35cf-40ed-b39e-e4b686daf0c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:49.975860 23809 external_containerizer.cpp:550] Usage triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:50.594594 23803 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 34763ns
2014-04-30 17:34:50,699:23773(0x2b27a943b700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:34943] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I0430 17:34:50.915688 23813 external_containerizer.cpp:341] Launch validation callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:50.915700 23813 external_containerizer.cpp:350] Launch finishing up for container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:50.916580 23813 external_containerizer.cpp:396] Wait continuation triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:50.916636 23813 external_containerizer.cpp:804] Invoking external containerizer for method 'wait'
I0430 17:34:50.916642 23798 slave.cpp:2282] Monitoring executor '1' of framework '20140430-173447-453759884-55610-23773-0000' in container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:50.916647 23813 external_containerizer.cpp:818] user: jenkins
I0430 17:34:50.933562 23813 external_containerizer.cpp:496] Update continuation triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:50.933627 23813 external_containerizer.cpp:804] Invoking external containerizer for method 'update'
I0430 17:34:50.933640 23813 external_containerizer.cpp:818] user: jenkins
I0430 17:34:50.947533 23813 external_containerizer.cpp:568] Usage continuation on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:50.947564 23813 external_containerizer.cpp:804] Invoking external containerizer for method 'usage'
I0430 17:34:50.947582 23813 external_containerizer.cpp:818] user: jenkins
I0430 17:34:50.960595 23813 external_containerizer.cpp:364] Launch confirmation callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:51.595144 23803 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 200297ns
I0430 17:34:51.917400 23809 external_containerizer.cpp:532] Update callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:51.918601 23809 external_containerizer.cpp:611] Usage callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:51.921807 23796 master.cpp:1912] Asked to kill task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:51.921900 23796 master.cpp:1995] Telling slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org) to kill task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:51.922068 23812 external_containerizer.cpp:550] Usage triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:51.922088 23796 slave.cpp:1181] Asked to kill task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:51.922320 23812 external_containerizer.cpp:568] Usage continuation on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:51.922333 23812 external_containerizer.cpp:804] Invoking external containerizer for method 'usage'
I0430 17:34:51.922430 23812 external_containerizer.cpp:818] user: jenkins
I0430 17:34:52.593157 23799 master.cpp:104] No whitelist given. Advertising offers for all slaves
I0430 17:34:52.596314 23804 hierarchical_allocator_process.hpp:688] Performed allocation for 1 slaves in 101623ns
I0430 17:34:52.918819 23804 external_containerizer.cpp:611] Usage callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:52.973181 23794 slave.cpp:1953] Handling status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 from executor(1)@140.211.11.27:46320
I0430 17:34:52.973309 23794 slave.cpp:3444] Terminating task 1
I0430 17:34:52.975381 23814 external_containerizer.cpp:476] Update triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:52.975376 23803 status_update_manager.cpp:320] Received status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:52.975548 23814 external_containerizer.cpp:496] Update continuation triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:52.975594 23814 external_containerizer.cpp:804] Invoking external containerizer for method 'update'
I0430 17:34:52.975594 23803 status_update_manager.cpp:373] Forwarding status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 to master@140.211.11.27:55610
I0430 17:34:52.975628 23814 external_containerizer.cpp:818] user: jenkins
I0430 17:34:52.980075 23797 master.cpp:2444] Status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 from slave(68)@140.211.11.27:55610
I0430 17:34:52.980255 23800 slave.cpp:2070] Status update manager successfully handled status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:52.980366 23800 slave.cpp:2076] Sending acknowledgement for status update TASK_KILLED (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000 to executor(1)@140.211.11.27:46320
I0430 17:34:52.981459 23797 master.hpp:576] Removing task 1 with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20140430-173447-453759884-55610-23773-0 (hemera.apache.org)
I0430 17:34:52.981820 23805 sched.cpp:620] Scheduler::statusUpdate took 227997ns
I0430 17:34:52.983749 23805 status_update_manager.cpp:398] Received status update acknowledgement (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:52.983983 23805 status_update_manager.cpp:530] Cleaning up status update stream for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:52.984702 23810 sched.cpp:731] Stopping framework '20140430-173447-453759884-55610-23773-0000'
I0430 17:34:52.984524 23806 hierarchical_allocator_process.hpp:637] Recovered cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (total allocatable: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]) on slave 20140430-173447-453759884-55610-23773-0 from framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:52.984719 23773 master.cpp:551] Master terminating
I0430 17:34:52.985010 23805 slave.cpp:1538] Status update manager successfully handled status update acknowledgement (UUID: 1eb2512e-cc68-43d9-978d-e8eb4b23d8c1) for task 1 of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:52.985031 23805 slave.cpp:3468] Completing task 1
I0430 17:34:52.986140 23811 slave.cpp:2151] master@140.211.11.27:55610 exited
W0430 17:34:52.986165 23811 slave.cpp:2154] Master disconnected! Waiting for a new master to be elected
I0430 17:34:53.001719 23800 external_containerizer.cpp:378] Wait triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:53.001806 23800 external_containerizer.cpp:637] Destroy triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:53.001842 23800 external_containerizer.cpp:396] Wait continuation triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:53.001853 23800 external_containerizer.cpp:804] Invoking external containerizer for method 'wait'
I0430 17:34:53.001863 23800 external_containerizer.cpp:818] user: jenkins
I0430 17:34:53.017118 23800 external_containerizer.cpp:655] Destroy continuation on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:53.017211 23800 external_containerizer.cpp:804] Invoking external containerizer for method 'destroy'
I0430 17:34:53.017243 23800 external_containerizer.cpp:818] user: jenkins
I0430 17:34:53.922402 23800 external_containerizer.cpp:550] Usage triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
I0430 17:34:53.923333 23800 external_containerizer.cpp:448] Wait callback triggered on container 'd7d27ddf-9f25-404f-bd4c-82ba6d562181'
E0430 17:34:53.925525 23809 slave.cpp:2324] Termination of executor '1' of framework '20140430-173447-453759884-55610-23773-0000' failed: External containerizer failed (status: 1)
I0430 17:34:53.927026 23809 slave.cpp:2479] Cleaning up executor '1' of framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:53.929193 23809 slave.cpp:2554] Cleaning up framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:53.930148 23801 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/slaves/20140430-173447-453759884-55610-23773-0/frameworks/20140430-173447-453759884-55610-23773-0000/executors/1/runs/d7d27ddf-9f25-404f-bd4c-82ba6d562181' for gc 6.99998926056889days in the future
I0430 17:34:53.930593 23801 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/slaves/20140430-173447-453759884-55610-23773-0/frameworks/20140430-173447-453759884-55610-23773-0000/executors/1' for gc 6.99998925448days in the future
I0430 17:34:53.930598 23803 status_update_manager.cpp:282] Closing status update streams for framework 20140430-173447-453759884-55610-23773-0000
I0430 17:34:53.930671 23801 gc.cpp:56] Scheduling '/tmp/ExternalContainerizerTest_Launch_gUjOZ6/slaves/20140430-173447-453759884-55610-23773-0/frameworks/20140430-173447-453759884-55610-23773-0000' for gc 6.99998924264889days in the future
../../src/tests/cluster.hpp:462: Failure
(wait).failure(): External containerizer failed (status: 1)
I0430 17:34:53.946579 23800 external_containerizer.cpp:762] Killed the following process tree/s:
[
]
I0430 17:34:53.946650 23800 external_containerizer.cpp:717] Callback performing final cleanup of running state
F0430 17:34:53.946650 23773 logging.cpp:55] RAW: Pure virtual method called
@ 0x2b259137a9ad google::LogMessage::Fail()
@ 0x2b259138251a google::RawLog__()
@ 0x51e009 mesos::internal::tests::Cluster::Slaves::shutdown()
@ 0x740519 mesos::internal::tests::MesosTest::ShutdownSlaves()
@ 0x8bf70d testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x8b7cf3 testing::Test::Run()
@ 0x8b7e26 testing::TestInfo::Run()
@ 0x8b7f67 testing::TestCase::Run()
@ 0x8b82ce testing::internal::UnitTestImpl::RunAllTests()
@ 0x8bf28d testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x8b739e testing::UnitTest::Run()
@ 0x4a5550 main
@ 0x2b25927c676d (unknown)
@ 0x4b0951 (unknown)
make[4]: *** [check-local] Aborted (core dumped)
make[4]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/x1/jenkins/jenkins-slave/workspace/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Set-JAVA_HOME/build/mesos-0.19.0/_build'
make: *** [distcheck] Error 1
Build step 'Execute shell' marked build as failure