mesos-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject Build failed in Jenkins: Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui #2880
Date Mon, 13 Apr 2015 17:58:35 GMT
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2880/changes>

Changes:

[vinodkone] Should install apr-util-devel in CentOS 6.5.

------------------------------------------
[...truncated 73720 lines...]
IRe-registered executor on proserpina.apache.org
0413 17:58:32.181589 31730 exec.cpp:241] Executor::reregistered took 91445ns
I0413 17:58:32.181962 20773 slave.cpp:2503] Handling status update TASK_LOST (UUID: 60f127b7-b58a-461e-95c7-8ea7b79b7912)
for task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2 of framework 20150413-175831-3176252227-60468-20747-0000
from @0.0.0.0:0
I0413 17:58:32.182065 20773 slave.cpp:4519] Terminating task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2
I0413 17:58:32.206502 20764 status_update_manager.cpp:317] Received status update TASK_LOST
(UUID: 60f127b7-b58a-461e-95c7-8ea7b79b7912) for task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2
of framework 20150413-175831-3176252227-60468-20747-0000
I0413 17:58:32.206568 20764 status_update_manager.cpp:494] Creating StatusUpdate stream for
task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2 of framework 20150413-175831-3176252227-60468-20747-0000
I0413 17:58:32.206914 20765 containerizer.cpp:950] Destroying container '95035a3f-79ce-453b-935f-a0e0b650f34e'
I0413 17:58:32.207056 20764 status_update_manager.hpp:346] Checkpointing UPDATE for status
update TASK_LOST (UUID: 60f127b7-b58a-461e-95c7-8ea7b79b7912) for task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2
of framework 20150413-175831-3176252227-60468-20747-0000
I0413 17:58:32.294944 20764 status_update_manager.cpp:371] Forwarding update TASK_LOST (UUID:
60f127b7-b58a-461e-95c7-8ea7b79b7912) for task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2 of framework
20150413-175831-3176252227-60468-20747-0000 to the slave
W0413 17:58:32.295214 20776 slave.cpp:2713] Dropping status update TASK_LOST (UUID: 60f127b7-b58a-461e-95c7-8ea7b79b7912)
for task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2 of framework 20150413-175831-3176252227-60468-20747-0000
sent by status update manager because the slave is in RECOVERING state
I0413 17:58:32.295289 20776 slave.cpp:2685] Status update manager successfully handled status
update TASK_LOST (UUID: 60f127b7-b58a-461e-95c7-8ea7b79b7912) for task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2
of framework 20150413-175831-3176252227-60468-20747-0000
I0413 17:58:32.350344 20767 containerizer.cpp:1159] Executor for container '95035a3f-79ce-453b-935f-a0e0b650f34e'
has exited
I0413 17:58:32.351619 20771 slave.cpp:3194] Executor 'f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2'
of framework 20150413-175831-3176252227-60468-20747-0000 terminated with signal Killed
I0413 17:58:32.351910 20747 sched.cpp:1589] Asked to stop the driver
I0413 17:58:32.352051 20747 master.cpp:788] Master terminating
I0413 17:58:32.352062 20763 sched.cpp:831] Stopping framework '20150413-175831-3176252227-60468-20747-0000'
I0413 17:58:32.352313 20771 hierarchical.hpp:470] Removed slave 20150413-175831-3176252227-60468-20747-S0
W0413 17:58:32.352293 20747 master.cpp:4707] Removing task f7f156f8-f9ba-4ee4-bcc2-9a434e9a65c2
with resources cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] of framework 20150413-175831-3176252227-60468-20747-0000
on slave 20150413-175831-3176252227-60468-20747-S0 at slave(231)@67.195.81.189:60468 (proserpina.apache.org)
in non-terminal state TASK_STAGING
I0413 17:58:32.353174 20776 hierarchical.hpp:354] Removed framework 20150413-175831-3176252227-60468-20747-0000
I0413 17:58:32.358019 20775 slave.cpp:509] Slave terminating
[       OK ] MesosContainerizerSlaveRecoveryTest.ResourceStatistics (846 ms)
[----------] 1 test from MesosContainerizerSlaveRecoveryTest (846 ms total)

[----------] 1 test from MasterContenderDetectorTest
[ RUN      ] MasterContenderDetectorTest.File
Using temporary directory '/tmp/MasterContenderDetectorTest_File_HXHDNc'
I0413 17:58:32.486649 20747 leveldb.cpp:176] Opened db in 122.163085ms
I0413 17:58:32.528734 20747 leveldb.cpp:183] Compacted db in 42.000124ms
I0413 17:58:32.528802 20747 leveldb.cpp:198] Created db iterator in 24914ns
I0413 17:58:32.528820 20747 leveldb.cpp:204] Seeked to beginning of db in 2504ns
I0413 17:58:32.528830 20747 leveldb.cpp:273] Iterated through 0 keys in the db in 393ns
I0413 17:58:32.528878 20747 replica.cpp:744] Replica recovered with log positions 0 ->
0 with 1 holes and 0 unlearned
I0413 17:58:32.529469 20770 recover.cpp:449] Starting replica recovery
I0413 17:58:32.529829 20770 recover.cpp:475] Replica is in EMPTY status
I0413 17:58:32.531082 20774 replica.cpp:641] Replica in EMPTY status received a broadcasted
recover request
I0413 17:58:32.531353 20769 recover.cpp:195] Received a recover response from a replica in
EMPTY status
I0413 17:58:32.532062 20772 recover.cpp:566] Updating replica status to STARTING
I0413 17:58:32.533360 20765 master.cpp:359] Master 20150413-175832-3176252227-60468-20747
(proserpina.apache.org) started on 67.195.81.189:60468
I0413 17:58:32.533390 20765 master.cpp:361] Flags at startup: --acls="" --allocation_interval="1secs"
--authenticate="true" --authenticate_slaves="true" --authenticators="crammd5" --credentials="/tmp/MasterContenderDetectorTest_File_HXHDNc/credentials"
--framework_sorter="drf" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0"
--logging_level="INFO" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log"
--registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true"
--root_submissions="true" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false"
--webui_dir="/usr/local/share/mesos/webui" --work_dir="/tmp/MasterContenderDetectorTest_File_HXHDNc/master"
--zk_session_timeout="10secs"
I0413 17:58:32.533808 20765 master.cpp:406] Master only allowing authenticated frameworks
to register
I0413 17:58:32.533823 20765 master.cpp:411] Master only allowing authenticated slaves to register
I0413 17:58:32.533830 20765 credentials.hpp:37] Loading credentials for authentication from
'/tmp/MasterContenderDetectorTest_File_HXHDNc/credentials'
I0413 17:58:32.534075 20765 master.cpp:450] Using default 'crammd5' authenticator
I0413 17:58:32.534219 20765 master.cpp:487] Authorization enabled
I0413 17:58:32.535002 20763 whitelist_watcher.cpp:79] No whitelist given
I0413 17:58:32.535074 20772 hierarchical.hpp:287] Initialized hierarchical allocator process
I0413 17:58:32.536412 20768 master.cpp:1419] The newly elected leader is master@67.195.81.189:60468
with id 20150413-175832-3176252227-60468-20747
I0413 17:58:32.536455 20768 master.cpp:1432] Elected as the leading master!
I0413 17:58:32.536485 20768 master.cpp:1205] Recovering from registrar
I0413 17:58:32.536625 20770 registrar.cpp:313] Recovering registrar
I0413 17:58:32.570184 20767 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took
37.935942ms
I0413 17:58:32.570252 20767 replica.cpp:323] Persisted replica status to STARTING
I0413 17:58:32.570576 20763 recover.cpp:475] Replica is in STARTING status
I0413 17:58:32.571846 20771 replica.cpp:641] Replica in STARTING status received a broadcasted
recover request
I0413 17:58:32.572268 20769 recover.cpp:195] Received a recover response from a replica in
STARTING status
I0413 17:58:32.572789 20767 recover.cpp:566] Updating replica status to VOTING
I0413 17:58:32.611865 20777 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took
38.838338ms
I0413 17:58:32.611915 20777 replica.cpp:323] Persisted replica status to VOTING
I0413 17:58:32.612074 20772 recover.cpp:580] Successfully joined the Paxos group
I0413 17:58:32.612311 20772 recover.cpp:464] Recover process terminated
I0413 17:58:32.612977 20776 log.cpp:660] Attempting to start the writer
I0413 17:58:32.614465 20767 replica.cpp:477] Replica received implicit promise request with
proposal 1
I0413 17:58:32.653548 20767 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took
39.040415ms
I0413 17:58:32.653597 20767 replica.cpp:345] Persisted promised to 1
I0413 17:58:32.654314 20773 coordinator.cpp:230] Coordinator attemping to fill missing position
I0413 17:58:32.655735 20764 replica.cpp:378] Replica received explicit promise request for
position 0 with proposal 2
I0413 17:58:32.687118 20764 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 31.339251ms
I0413 17:58:32.687163 20764 replica.cpp:679] Persisted action at 0
I0413 17:58:32.688657 20768 replica.cpp:511] Replica received write request for position 0
I0413 17:58:32.688747 20768 leveldb.cpp:438] Reading position from leveldb took 54318ns
I0413 17:58:32.720556 20768 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took
31.767308ms
I0413 17:58:32.720600 20768 replica.cpp:679] Persisted action at 0
I0413 17:58:32.721408 20769 replica.cpp:658] Replica received learned notice for position
0
I0413 17:58:32.753944 20769 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took
32.499029ms
I0413 17:58:32.753983 20769 replica.cpp:679] Persisted action at 0
I0413 17:58:32.754005 20769 replica.cpp:664] Replica learned NOP action at position 0
I0413 17:58:32.754655 20771 log.cpp:676] Writer started with ending position 0
I0413 17:58:32.755919 20772 leveldb.cpp:438] Reading position from leveldb took 32199ns
I0413 17:58:32.758930 20771 registrar.cpp:346] Successfully fetched the registry (0B) in 222.258944ms
I0413 17:58:32.759048 20771 registrar.cpp:445] Applied 1 operations in 28239ns; attempting
to update the 'registry'
I0413 17:58:32.761981 20774 log.cpp:684] Attempting to append 143 bytes to the log
I0413 17:58:32.762141 20764 coordinator.cpp:340] Coordinator attempting to write APPEND action
at position 1
I0413 17:58:32.763157 20768 replica.cpp:511] Replica received write request for position 1
I0413 17:58:32.795750 20768 leveldb.cpp:343] Persisting action (162 bytes) to leveldb took
32.550399ms
I0413 17:58:32.795804 20768 replica.cpp:679] Persisted action at 1
I0413 17:58:32.796527 20770 replica.cpp:658] Replica received learned notice for position
1
I0413 17:58:32.829246 20770 leveldb.cpp:343] Persisting action (164 bytes) to leveldb took
32.686488ms
I0413 17:58:32.829291 20770 replica.cpp:679] Persisted action at 1
I0413 17:58:32.829318 20770 replica.cpp:664] Replica learned APPEND action at position 1
I0413 17:58:32.830348 20770 registrar.cpp:490] Successfully updated the 'registry' in 71.234048ms
I0413 17:58:32.830536 20770 registrar.cpp:376] Successfully recovered registrar
I0413 17:58:32.830595 20774 log.cpp:703] Attempting to truncate the log to 1
I0413 17:58:32.830829 20771 coordinator.cpp:340] Coordinator attempting to write TRUNCATE
action at position 2
I0413 17:58:32.830888 20767 master.cpp:1232] Recovered 0 slaves from the Registry (105B) ;
allowing 10mins for slaves to re-register
I0413 17:58:32.831692 20776 replica.cpp:511] Replica received write request for position 2
I0413 17:58:32.868948 20776 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took
37.197874ms
I0413 17:58:32.868999 20776 replica.cpp:679] Persisted action at 2
I0413 17:58:32.869799 20764 replica.cpp:658] Replica received learned notice for position
2
I0413 17:58:32.904435 20764 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took
34.599291ms
I0413 17:58:32.904521 20764 leveldb.cpp:401] Deleting ~1 keys from leveldb took 43084ns
I0413 17:58:32.904546 20764 replica.cpp:679] Persisted action at 2
I0413 17:58:32.904567 20764 replica.cpp:664] Replica learned TRUNCATE action at position 2
W0413 17:58:32.913843 20747 detector.cpp:228] Specifying master detection mechanism / ZooKeeper
URL to be read out of a file via 'file://' is deprecated inside Mesos and will be removed
in a future release.
I0413 17:58:32.917676 20747 containerizer.cpp:113] Using isolation: posix/cpu,posix/mem
I0413 17:58:32.919158 20766 slave.cpp:176] Slave started on 233)@67.195.81.189:60468
I0413 17:58:32.919179 20766 slave.cpp:177] Flags at startup: --authenticatee="crammd5" --cgroups_cpu_enable_pids_and_tids_count="false"
--cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false"
--cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/MasterContenderDetectorTest_File_IEHCNA/credential"
--default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_remove_delay="6hrs"
--docker_sandbox_directory="/mnt/mesos/sandbox" --docker_stop_timeout="0ns" --enforce_container_disk_quota="false"
--executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --frameworks_home=""
--gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --initialize_driver_logging="true"
--isolation="posix/cpu,posix/mem" --launcher_dir="<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src">
--logbufsecs="0" --logging_level="INFO" --perf_duration="10secs" --perf_interval="1mins" --quiet="false"
--recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_monitoring_interval="1secs"
--resources="cpus:2;mem:1024;disk:1024;ports:[31000-32000]" --strict="true" --switch_user="true"
--version="false" --work_dir="/tmp/MasterContenderDetectorTest_File_IEHCNA"
I0413 17:58:32.919451 20766 credentials.hpp:85] Loading credential for authentication from
'/tmp/MasterContenderDetectorTest_File_IEHCNA/credential'
I0413 17:58:32.919564 20766 slave.cpp:298] Slave using credential for: test-principal
I0413 17:58:32.919769 20766 slave.cpp:316] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0413 17:58:32.919847 20766 slave.cpp:346] Slave hostname: proserpina.apache.org
I0413 17:58:32.919862 20766 slave.cpp:351] Slave checkpoint: true
I0413 17:58:32.920517 20765 state.cpp:35] Recovering state from '/tmp/MasterContenderDetectorTest_File_IEHCNA/meta'
I0413 17:58:32.920773 20769 status_update_manager.cpp:197] Recovering status update manager
I0413 17:58:32.920924 20777 containerizer.cpp:310] Recovering containerizer
I0413 17:58:32.921628 20765 slave.cpp:3806] Finished recovery
I0413 17:58:32.922309 20771 status_update_manager.cpp:171] Pausing sending status updates
I0413 17:58:32.922327 20777 slave.cpp:627] New master detected at master@67.195.81.189:60468
I0413 17:58:32.922472 20777 slave.cpp:690] Authenticating with master master@67.195.81.189:60468
I0413 17:58:32.922503 20777 slave.cpp:695] Using default CRAM-MD5 authenticatee
I0413 17:58:32.922667 20777 slave.cpp:663] Detecting new master
I0413 17:58:32.922695 20765 authenticatee.hpp:139] Creating new client SASL connection
I0413 17:58:32.923018 20764 master.cpp:3884] Authenticating slave(233)@67.195.81.189:60468
I0413 17:58:32.923228 20770 authenticator.cpp:406] Starting authentication session for crammd5_authenticatee(527)@67.195.81.189:60468
I0413 17:58:32.923475 20765 authenticator.cpp:92] Creating new server SASL connection
I0413 17:58:32.923846 20778 authenticatee.hpp:230] Received SASL authentication mechanisms:
CRAM-MD5
I0413 17:58:32.923889 20778 authenticatee.hpp:256] Attempting to authenticate with mechanism
'CRAM-MD5'
I0413 17:58:32.924008 20778 authenticator.cpp:197] Received SASL authentication start
I0413 17:58:32.924144 20778 authenticator.cpp:319] Authentication requires more steps
I0413 17:58:32.924270 20765 authenticatee.hpp:276] Received SASL authentication step
I0413 17:58:32.924432 20775 authenticator.cpp:225] Received SASL authentication step
I0413 17:58:32.924476 20775 auxprop.cpp:101] Request to lookup properties for user: 'test-principal'
realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0413 17:58:32.924494 20775 auxprop.cpp:173] Looking up auxiliary property '*userPassword'
I0413 17:58:32.924554 20775 auxprop.cpp:173] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0413 17:58:32.924587 20775 auxprop.cpp:101] Request to lookup properties for user: 'test-principal'
realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0413 17:58:32.924602 20775 auxprop.cpp:123] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0413 17:58:32.924612 20775 auxprop.cpp:123] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0413 17:58:32.924631 20775 authenticator.cpp:311] Authentication success
I0413 17:58:32.924757 20774 authenticatee.hpp:316] Authentication success
I0413 17:58:32.924779 20770 master.cpp:3914] Successfully authenticated principal 'test-principal'
at slave(233)@67.195.81.189:60468
I0413 17:58:32.924944 20747 sched.cpp:157] Version: 0.23.0
I0413 17:58:32.924969 20765 authenticator.cpp:424] Authentication session cleanup for crammd5_authenticatee(527)@67.195.81.189:60468
I0413 17:58:32.925184 20776 slave.cpp:761] Successfully authenticated with master master@67.195.81.189:60468
I0413 17:58:32.925561 20766 sched.cpp:254] New master detected at master@67.195.81.189:60468
I0413 17:58:32.925573 20776 slave.cpp:1088] Will retry registration in 5.64693ms if necessary
I0413 17:58:32.925668 20766 sched.cpp:310] Authenticating with master master@67.195.81.189:60468
I0413 17:58:32.925751 20766 sched.cpp:317] Using default CRAM-MD5 authenticatee
I0413 17:58:32.925811 20778 master.cpp:2991] Registering slave at slave(233)@67.195.81.189:60468
(proserpina.apache.org) with id 20150413-175832-3176252227-60468-20747-S0
I0413 17:58:32.925966 20769 authenticatee.hpp:139] Creating new client SASL connection
I0413 17:58:32.926213 20778 master.cpp:3884] Authenticating scheduler-a65031fc-b0c2-4df8-bba5-20c8fb961369@67.195.81.189:60468
I0413 17:58:32.926331 20766 authenticator.cpp:406] Starting authentication session for crammd5_authenticatee(528)@67.195.81.189:60468
I0413 17:58:32.926342 20777 registrar.cpp:445] Applied 1 operations in 88019ns; attempting
to update the 'registry'
I0413 17:58:32.926626 20769 authenticator.cpp:92] Creating new server SASL connection
I0413 17:58:32.926802 20773 authenticatee.hpp:230] Received SASL authentication mechanisms:
CRAM-MD5
I0413 17:58:32.926839 20773 authenticatee.hpp:256] Attempting to authenticate with mechanism
'CRAM-MD5'
I0413 17:58:32.926949 20774 authenticator.cpp:197] Received SASL authentication start
I0413 17:58:32.927008 20774 authenticator.cpp:319] Authentication requires more steps
I0413 17:58:32.927125 20773 authenticatee.hpp:276] Received SASL authentication step
I0413 17:58:32.927263 20774 authenticator.cpp:225] Received SASL authentication step
I0413 17:58:32.927309 20774 auxprop.cpp:101] Request to lookup properties for user: 'test-principal'
realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I0413 17:58:32.927331 20774 auxprop.cpp:173] Looking up auxiliary property '*userPassword'
I0413 17:58:32.927381 20774 auxprop.cpp:173] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0413 17:58:32.927431 20774 auxprop.cpp:101] Request to lookup properties for user: 'test-principal'
realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I0413 17:58:32.927458 20774 auxprop.cpp:123] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I0413 17:58:32.927474 20774 auxprop.cpp:123] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I0413 17:58:32.927502 20774 authenticator.cpp:311] Authentication success
I0413 17:58:32.927602 20766 authenticatee.hpp:316] Authentication success
I0413 17:58:32.927635 20773 master.cpp:3914] Successfully authenticated principal 'test-principal'
at scheduler-a65031fc-b0c2-4df8-bba5-20c8fb961369@67.195.81.189:60468
I0413 17:58:32.927690 20778 authenticator.cpp:424] Authentication session cleanup for crammd5_authenticatee(528)@67.195.81.189:60468
I0413 17:58:32.927983 20774 sched.cpp:398] Successfully authenticated with master master@67.195.81.189:60468
I0413 17:58:32.928010 20774 sched.cpp:521] Sending registration request to master@67.195.81.189:60468
I0413 17:58:32.928072 20774 sched.cpp:554] Will retry registration in 1.139051994secs if necessary
I0413 17:58:32.928170 20766 master.cpp:1637] Received registration request for framework 'default'
at scheduler-a65031fc-b0c2-4df8-bba5-20c8fb961369@67.195.81.189:60468
I0413 17:58:32.928266 20766 master.cpp:1498] Authorizing framework principal 'test-principal'
to receive offers for role '*'
I0413 17:58:32.928660 20776 master.cpp:1704] Registering framework 20150413-175832-3176252227-60468-20747-0000
(default) at scheduler-a65031fc-b0c2-4df8-bba5-20c8fb961369@67.195.81.189:60468
I0413 17:58:32.929060 20772 hierarchical.hpp:321] Added framework 20150413-175832-3176252227-60468-20747-0000
I0413 17:58:32.929101 20772 hierarchical.hpp:818] No resources available to allocate!
I0413 17:58:32.929123 20772 hierarchical.hpp:725] Performed allocation for 0 slaves in 32085ns
I0413 17:58:32.929218 20772 sched.cpp:448] Framework registered with 20150413-175832-3176252227-60468-20747-0000
I0413 17:58:32.929275 20772 sched.cpp:462] Scheduler::registered took 37118ns
I0413 17:58:32.929385 20770 log.cpp:684] Attempting to append 324 bytes to the log
I0413 17:58:32.929509 20767 coordinator.cpp:340] Coordinator attempting to write APPEND action
at position 3
I0413 17:58:32.930294 20773 replica.cpp:511] Replica received write request for position 3
I0413 17:58:32.932085 20767 slave.cpp:1088] Will retry registration in 17.574244ms if necessary
I0413 17:58:32.932209 20776 master.cpp:2979] Ignoring register slave message from slave(233)@67.195.81.189:60468
(proserpina.apache.org) as admission is already in progress
I0413 17:58:32.950625 20774 slave.cpp:1088] Will retry registration in 56.701364ms if necessary
I0413 17:58:32.950817 20777 master.cpp:2979] Ignoring register slave message from slave(233)@67.195.81.189:60468
(proserpina.apache.org) as admission is already in progress
I0413 17:58:32.963193 20773 leveldb.cpp:343] Persisting action (343 bytes) to leveldb took
32.85285ms
I0413 17:58:32.963244 20773 replica.cpp:679] Persisted action at 3
I0413 17:58:32.964040 20765 replica.cpp:658] Replica received learned notice for position
3
I0413 17:58:33.003015 20765 leveldb.cpp:343] Persisting action (345 bytes) to leveldb took
38.928349ms
I0413 17:58:33.003067 20765 replica.cpp:679] Persisted action at 3
I0413 17:58:33.003099 20765 replica.cpp:664] Replica learned APPEND action at position 3
I0413 17:58:33.004876 20773 registrar.cpp:490] Successfully updated the 'registry' in 78.436864ms
I0413 17:58:33.005249 20773 log.cpp:703] Attempting to truncate the log to 3
I0413 17:58:33.005396 20778 coordinator.cpp:340] Coordinator attempting to write TRUNCATE
action at position 4
I0413 17:58:33.005841 20771 master.cpp:3048] Registered slave 20150413-175832-3176252227-60468-20747-S0
at slave(233)@67.195.81.189:60468 (proserpina.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I0413 17:58:33.005897 20768 slave.cpp:2834] Received ping from slave-observer(205)@67.195.81.189:60468
I0413 17:58:33.005965 20778 hierarchical.hpp:439] Added slave 20150413-175832-3176252227-60468-20747-S0
(proserpina.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
(and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I0413 17:58:33.006142 20768 slave.cpp:795] Registered with master master@67.195.81.189:60468;
given slave ID 20150413-175832-3176252227-60468-20747-S0
I0413 17:58:33.006309 20767 status_update_manager.cpp:178] Resuming sending status updates
I0413 17:58:33.006463 20778 hierarchical.hpp:743] Performed allocation for slave 20150413-175832-3176252227-60468-20747-S0
in 444991ns
I0413 17:58:33.006490 20768 slave.cpp:810] Checkpointing SlaveInfo to '/tmp/MasterContenderDetectorTest_File_IEHCNA/meta/slaves/20150413-175832-3176252227-60468-20747-S0/slave.info'
I0413 17:58:33.006505 20776 replica.cpp:511] Replica received write request for position 4
I0413 17:58:33.007000 20765 master.cpp:3803] Sending 1 offers to framework 20150413-175832-3176252227-60468-20747-0000
(default) at scheduler-a65031fc-b0c2-4df8-bba5-20c8fb961369@67.195.81.189:60468
I0413 17:58:33.007438 20765 sched.cpp:611] Scheduler::resourceOffers took 79956ns
I0413 17:58:33.007753 20747 sched.cpp:1589] Asked to stop the driver
I0413 17:58:33.007865 20747 master.cpp:788] Master terminating
I0413 17:58:33.007906 20765 sched.cpp:831] Stopping framework '20150413-175832-3176252227-60468-20747-0000'
I0413 17:58:33.008077 20769 hierarchical.hpp:470] Removed slave 20150413-175832-3176252227-60468-20747-S0
I0413 17:58:33.008569 20767 hierarchical.hpp:354] Removed framework 20150413-175832-3176252227-60468-20747-0000
I0413 17:58:33.008887 20774 slave.cpp:2919] master@67.195.81.189:60468 exited
W0413 17:58:33.008924 20774 slave.cpp:2922] Master disconnected! Waiting for a new master
to be elected
I0413 17:58:33.045502 20776 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took
38.95226ms
I0413 17:58:33.045545 20776 replica.cpp:679] Persisted action at 4
I0413 17:58:33.047497 20747 slave.cpp:509] Slave terminating
[       OK ] MasterContenderDetectorTest.File (689 ms)
[----------] 1 test from MasterContenderDetectorTest (690 ms total)

[----------] 4 tests from SorterTest
[ RUN      ] SorterTest.WDRFSorter
[       OK ] SorterTest.WDRFSorter (1 ms)
[ RUN      ] SorterTest.Update
[       OK ] SorterTest.Update (1 ms)
[ RUN      ] SorterTest.DRFSorter
[       OK ] SorterTest.DRFSorter (1 ms)
[ RUN      ] SorterTest.SplitResourceShares
[       OK ] SorterTest.SplitResourceShares (1 ms)
[----------] 4 tests from SorterTest (4 ms total)

[----------] Global test environment tear-down
[==========] 507 tests from 78 test cases ran. (335305 ms total)
[  PASSED  ] 506 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] ExamplesTest.NoExecutorFramework

 1 FAILED TEST
  YOU HAVE 8 DISABLED TESTS

make[3]: *** [check-local] Error 1
make[3]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[2]: *** [check-am] Error 2
make[2]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make[1]: *** [check] Error 2
make[1]: Leaving directory `<https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/ws/build/src'>
make: *** [check-recursive] Error 1
Test finished
Exit status: 2
Build step 'Execute shell' marked build as failure
Recording test results

Mime
View raw message