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 #2576
Date Wed, 19 Nov 2014 20:58:04 GMT
See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2576/changes>

Changes:

[dhamon] Pull Metrics struct out of Slave class.

------------------------------------------
[...truncated 44841 lines...]
I1119 20:55:51.659664 25148 auxprop.cpp:171] Looking up auxiliary property '*userPassword'
I1119 20:55:51.659699 25148 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1119 20:55:51.659721 25148 auxprop.cpp:99] Request to lookup properties for user: 'test-principal'
realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I1119 20:55:51.659736 25148 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I1119 20:55:51.659742 25148 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I1119 20:55:51.659754 25148 authenticator.hpp:390] Authentication success
I1119 20:55:51.659847 25148 authenticatee.hpp:315] Authentication success
I1119 20:55:51.659881 25139 master.cpp:3960] Successfully authenticated principal 'test-principal'
at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639
I1119 20:55:51.660011 25143 sched.cpp:389] Successfully authenticated with master master@67.195.81.186:57639
I1119 20:55:51.660035 25143 sched.cpp:512] Sending registration request to master@67.195.81.186:57639
I1119 20:55:51.660159 25143 sched.cpp:545] Will retry registration in 944.513209ms if necessary
I1119 20:55:51.660300 25139 master.cpp:1383] Received registration request for framework 'default'
at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639
I1119 20:55:51.838100 25139 master.cpp:1342] Authorizing framework principal 'test-principal'
to receive offers for role '*'
I1119 20:55:51.838531 25141 master.cpp:1447] Registering framework 20141119-205551-3125920579-57639-25120-0000
(default) at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639
I1119 20:55:51.838961 25138 hierarchical_allocator_process.hpp:329] Added framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.839155 25138 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] on slave 20141119-205551-3125920579-57639-25120-S0 to
framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.839249 25149 sched.cpp:439] Framework registered with 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.839293 25149 sched.cpp:453] Scheduler::registered took 17715ns
I1119 20:55:51.839381 25138 hierarchical_allocator_process.hpp:659] Performed allocation for
1 slaves in 370594ns
I1119 20:55:51.839718 25142 master.cpp:3844] Sending 1 offers to framework 20141119-205551-3125920579-57639-25120-0000
(default) at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639
I1119 20:55:51.840572 25142 sched.cpp:602] Scheduler::resourceOffers took 575156ns
I1119 20:55:51.841292 25142 master.cpp:2363] Processing reply for offers: [ 20141119-205551-3125920579-57639-25120-O0
] on slave 20141119-205551-3125920579-57639-25120-S0 at slave(170)@67.195.81.186:57639 (penates.apache.org)
for framework 20141119-205551-3125920579-57639-25120-0000 (default) at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639
I1119 20:55:51.841332 25142 master.cpp:2461] Authorizing framework principal 'test-principal'
to launch task 0 as user 'jenkins'
W1119 20:55:51.842247 25136 master.cpp:1998] Executor default for task 0 uses less CPUs (None)
than the minimum required (0.01). Please update your executor, as this will be mandatory in
future releases.
W1119 20:55:51.842288 25136 master.cpp:2010] Executor default for task 0 uses less memory
(None) than the minimum required (32MB). Please update your executor, as this will be mandatory
in future releases.
I1119 20:55:51.842574 25136 master.hpp:753] Adding task 0 with resources cpus(*):2; mem(*):1024
on slave 20141119-205551-3125920579-57639-25120-S0 (penates.apache.org)
I1119 20:55:51.842655 25136 master.cpp:2531] Launching task 0 of framework 20141119-205551-3125920579-57639-25120-0000
(default) at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639 with resources
cpus(*):2; mem(*):1024 on slave 20141119-205551-3125920579-57639-25120-S0 at slave(170)@67.195.81.186:57639
(penates.apache.org)
I1119 20:55:51.842952 25137 slave.cpp:1098] Got assigned task 0 for framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.843076 25140 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024;
ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave
20141119-205551-3125920579-57639-25120-S0 from framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.843169 25140 hierarchical_allocator_process.hpp:599] Framework 20141119-205551-3125920579-57639-25120-0000
filtered slave 20141119-205551-3125920579-57639-25120-S0 for 5secs
I1119 20:55:51.843490 25137 slave.cpp:1208] Launching task 0 for framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.846076 25137 slave.cpp:3863] Launching executor default of framework 20141119-205551-3125920579-57639-25120-0000
in work directory '/tmp/GarbageCollectorIntegrationTest_Restart_NfqYfw/slaves/20141119-205551-3125920579-57639-25120-S0/frameworks/20141119-205551-3125920579-57639-25120-0000/executors/default/runs/c5990ed1-f83f-4860-87d8-eccb146bc79b'
I1119 20:55:51.848954 25137 exec.cpp:132] Version: 0.22.0
I1119 20:55:51.849208 25145 exec.cpp:182] Executor started at: executor(54)@67.195.81.186:57639
with pid 25120
I1119 20:55:51.849371 25137 slave.cpp:1331] Queuing task '0' for executor default of framework
'20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.849491 25137 slave.cpp:558] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_Restart_NfqYfw/slaves/20141119-205551-3125920579-57639-25120-S0/frameworks/20141119-205551-3125920579-57639-25120-0000/executors/default/runs/c5990ed1-f83f-4860-87d8-eccb146bc79b'
I1119 20:55:51.849593 25137 slave.cpp:1875] Got registration for executor 'default' of framework
20141119-205551-3125920579-57639-25120-0000 from executor(54)@67.195.81.186:57639
I1119 20:55:51.849922 25137 slave.cpp:1994] Flushing queued task 0 for executor 'default'
of framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.849998 25149 exec.cpp:206] Executor registered on slave 20141119-205551-3125920579-57639-25120-S0
I1119 20:55:51.850167 25137 slave.cpp:2855] Monitoring executor 'default' of framework '20141119-205551-3125920579-57639-25120-0000'
in container 'c5990ed1-f83f-4860-87d8-eccb146bc79b'
I1119 20:55:51.852202 25149 exec.cpp:218] Executor::registered took 24230ns
I1119 20:55:51.852372 25149 exec.cpp:293] Executor asked to run task '0'
I1119 20:55:51.852447 25149 exec.cpp:302] Executor::launchTask took 51403ns
I1119 20:55:51.854575 25149 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID:
2ca01883-21c2-4dbc-8ac4-d249c616db57) for task 0 of framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.854799 25149 slave.cpp:2230] Handling status update TASK_RUNNING (UUID: 2ca01883-21c2-4dbc-8ac4-d249c616db57)
for task 0 of framework 20141119-205551-3125920579-57639-25120-0000 from executor(54)@67.195.81.186:57639
I1119 20:55:51.855067 25137 status_update_manager.cpp:317] Received status update TASK_RUNNING
(UUID: 2ca01883-21c2-4dbc-8ac4-d249c616db57) for task 0 of framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.855108 25137 status_update_manager.cpp:494] Creating StatusUpdate stream for
task 0 of framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.855295 25137 status_update_manager.cpp:371] Forwarding update TASK_RUNNING
(UUID: 2ca01883-21c2-4dbc-8ac4-d249c616db57) for task 0 of framework 20141119-205551-3125920579-57639-25120-0000
to the slave
I1119 20:55:51.855590 25138 slave.cpp:2473] Forwarding the update TASK_RUNNING (UUID: 2ca01883-21c2-4dbc-8ac4-d249c616db57)
for task 0 of framework 20141119-205551-3125920579-57639-25120-0000 to master@67.195.81.186:57639
I1119 20:55:51.855765 25138 slave.cpp:2400] Status update manager successfully handled status
update TASK_RUNNING (UUID: 2ca01883-21c2-4dbc-8ac4-d249c616db57) for task 0 of framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.855788 25138 slave.cpp:2406] Sending acknowledgement for status update TASK_RUNNING
(UUID: 2ca01883-21c2-4dbc-8ac4-d249c616db57) for task 0 of framework 20141119-205551-3125920579-57639-25120-0000
to executor(54)@67.195.81.186:57639
I1119 20:55:51.855937 25149 master.cpp:3447] Forwarding status update TASK_RUNNING (UUID:
2ca01883-21c2-4dbc-8ac4-d249c616db57) for task 0 of framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.855962 25144 exec.cpp:339] Executor received status update acknowledgement
2ca01883-21c2-4dbc-8ac4-d249c616db57 for task 0 of framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.856088 25149 master.cpp:3419] Status update TASK_RUNNING (UUID: 2ca01883-21c2-4dbc-8ac4-d249c616db57)
for task 0 of framework 20141119-205551-3125920579-57639-25120-0000 from slave 20141119-205551-3125920579-57639-25120-S0
at slave(170)@67.195.81.186:57639 (penates.apache.org)
I1119 20:55:51.856142 25149 master.cpp:4694] Updating the latest state of task 0 of framework
20141119-205551-3125920579-57639-25120-0000 to TASK_RUNNING
I1119 20:55:51.856267 25134 sched.cpp:693] Scheduler::statusUpdate took 60023ns
I1119 20:55:51.856701 25141 master.cpp:2919] Forwarding status update acknowledgement 2ca01883-21c2-4dbc-8ac4-d249c616db57
for task 0 of framework 20141119-205551-3125920579-57639-25120-0000 (default) at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639
to slave 20141119-205551-3125920579-57639-25120-S0 at slave(170)@67.195.81.186:57639 (penates.apache.org)
I1119 20:55:51.857004 25120 slave.cpp:487] Slave terminating
I1119 20:55:51.988471 25120 slave.cpp:1548] Asked to shut down framework 20141119-205551-3125920579-57639-25120-0000
by @0.0.0.0:0
I1119 20:55:51.988502 25120 slave.cpp:1573] Shutting down framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.988595 25120 slave.cpp:3161] Shutting down executor 'default' of framework
20141119-205551-3125920579-57639-25120-0000
I1119 20:55:51.988844 25138 exec.cpp:379] Executor asked to shutdown
I1119 20:55:51.988886 25147 master.cpp:839] Slave 20141119-205551-3125920579-57639-25120-S0
at slave(170)@67.195.81.186:57639 (penates.apache.org) disconnected
I1119 20:55:51.988906 25138 exec.cpp:394] Executor::shutdown took 17627ns
I1119 20:55:51.988914 25147 master.cpp:843] Removing disconnected slave 20141119-205551-3125920579-57639-25120-S0
at slave(170)@67.195.81.186:57639 (penates.apache.org) because it is not checkpointing!
I1119 20:55:51.988924 25147 master.cpp:4510] Removing slave 20141119-205551-3125920579-57639-25120-S0
at slave(170)@67.195.81.186:57639 (penates.apache.org)
I1119 20:55:51.989198 25134 hierarchical_allocator_process.hpp:467] Removed slave 20141119-205551-3125920579-57639-25120-S0
I1119 20:55:51.990890 25147 master.cpp:4694] Updating the latest state of task 0 of framework
20141119-205551-3125920579-57639-25120-0000 to TASK_LOST
I1119 20:55:51.991261 25147 master.cpp:4753] Removing task 0 with resources cpus(*):2; mem(*):1024
of framework 20141119-205551-3125920579-57639-25120-0000 on slave 20141119-205551-3125920579-57639-25120-S0
at slave(170)@67.195.81.186:57639 (penates.apache.org)
I1119 20:55:51.991596 25147 master.cpp:4782] Removing executor 'default' with resources  of
framework 20141119-205551-3125920579-57639-25120-0000 on slave 20141119-205551-3125920579-57639-25120-S0
at slave(170)@67.195.81.186:57639 (penates.apache.org)
I1119 20:55:51.992651 25137 registrar.cpp:445] Applied 1 operations in 79281ns; attempting
to update the 'registry'
I1119 20:55:51.994978 25135 log.cpp:680] Attempting to append 142 bytes to the log
I1119 20:55:51.995136 25147 coordinator.cpp:340] Coordinator attempting to write APPEND action
at position 5
I1119 20:55:51.995918 25142 replica.cpp:508] Replica received write request for position 5
I1119 20:55:51.996655 25142 leveldb.cpp:343] Persisting action (161 bytes) to leveldb took
710930ns
I1119 20:55:51.996675 25142 replica.cpp:676] Persisted action at 5
I1119 20:55:51.997179 25143 replica.cpp:655] Replica received learned notice for position
5
I1119 20:55:51.997761 25143 leveldb.cpp:343] Persisting action (163 bytes) to leveldb took
561213ns
I1119 20:55:51.997781 25143 replica.cpp:676] Persisted action at 5
I1119 20:55:51.997797 25143 replica.cpp:661] Replica learned APPEND action at position 5
I1119 20:55:51.998579 25140 registrar.cpp:490] Successfully updated the 'registry' in 0ns
I1119 20:55:51.998796 25145 master.cpp:4604] Removed slave 20141119-205551-3125920579-57639-25120-S0
(penates.apache.org)
I1119 20:55:51.998824 25138 log.cpp:699] Attempting to truncate the log to 5
I1119 20:55:51.998834 25145 master.cpp:3442] Sending status update TASK_LOST (UUID: df8816c7-b5f1-4148-8e3f-eb233450e9fd)
for task 0 of framework 20141119-205551-3125920579-57639-25120-0000 'Slave penates.apache.org
removed'
I1119 20:55:51.998934 25147 coordinator.cpp:340] Coordinator attempting to write TRUNCATE
action at position 6
I1119 20:55:51.998945 25145 master.cpp:4622] Notifying framework 20141119-205551-3125920579-57639-25120-0000
(default) at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639 of lost slave
20141119-205551-3125920579-57639-25120-S0 (penates.apache.org) after recovering
I1119 20:55:51.999068 25148 sched.cpp:693] Scheduler::statusUpdate took 16960ns
I1119 20:55:51.999125 25148 sched.cpp:745] Lost slave 20141119-205551-3125920579-57639-25120-S0
I1119 20:55:51.999171 25148 sched.cpp:756] Scheduler::slaveLost took 22586ns
I1119 20:55:51.999542 25145 replica.cpp:508] Replica received write request for position 6
I1119 20:55:51.999994 25145 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took
429105ns
I1119 20:55:52.000011 25145 replica.cpp:676] Persisted action at 6
I1119 20:55:52.000478 25145 replica.cpp:655] Replica received learned notice for position
6
I1119 20:55:52.001101 25145 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took
603409ns
I1119 20:55:52.001149 25145 leveldb.cpp:401] Deleting ~2 keys from leveldb took 28351ns
I1119 20:55:52.001163 25145 replica.cpp:676] Persisted action at 6
I1119 20:55:52.001178 25145 replica.cpp:661] Replica learned TRUNCATE action at position 6
I1119 20:55:52.004945 25120 containerizer.cpp:101] Using isolation: posix/cpu,posix/mem
I1119 20:55:52.011484 25134 slave.cpp:170] Slave started on 171)@67.195.81.186:57639
I1119 20:55:52.011546 25134 credentials.hpp:84] Loading credential for authentication from
'/tmp/GarbageCollectorIntegrationTest_Restart_NfqYfw/credential'
I1119 20:55:52.011695 25134 slave.cpp:279] Slave using credential for: test-principal
I1119 20:55:52.011860 25134 slave.cpp:292] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I1119 20:55:52.011932 25134 slave.cpp:321] Slave hostname: penates.apache.org
I1119 20:55:52.011945 25134 slave.cpp:322] Slave checkpoint: false
W1119 20:55:52.011950 25134 slave.cpp:324] Disabling checkpointing is deprecated and the --checkpoint
flag will be removed in a future release. Please avoid using this flag
I1119 20:55:52.012670 25140 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_Restart_NfqYfw/meta'
I1119 20:55:52.012778 25140 state.cpp:62] Failed to find the latest slave from '/tmp/GarbageCollectorIntegrationTest_Restart_NfqYfw/meta'
I1119 20:55:52.012987 25143 status_update_manager.cpp:197] Recovering status update manager
I1119 20:55:52.013170 25147 containerizer.cpp:282] Recovering containerizer
I1119 20:55:52.013921 25145 slave.cpp:3481] Finished recovery
I1119 20:55:52.014137 25145 slave.cpp:3514] Garbage collecting old slave 20141119-205551-3125920579-57639-25120-S0
I1119 20:55:52.014256 25142 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_Restart_NfqYfw/slaves/20141119-205551-3125920579-57639-25120-S0'
for gc 1.00000023690624weeks in the future
I1119 20:55:52.014497 25145 status_update_manager.cpp:171] Pausing sending status updates
I1119 20:55:52.014499 25134 slave.cpp:605] New master detected at master@67.195.81.186:57639
I1119 20:55:52.014560 25134 slave.cpp:668] Authenticating with master master@67.195.81.186:57639
I1119 20:55:52.014572 25134 slave.cpp:673] Using default CRAM-MD5 authenticatee
I1119 20:55:52.014690 25134 slave.cpp:641] Detecting new master
I1119 20:55:52.014803 25135 authenticatee.hpp:138] Creating new client SASL connection
I1119 20:55:52.014958 25134 master.cpp:3902] Authenticating slave(171)@67.195.81.186:57639
I1119 20:55:52.014986 25134 master.cpp:3913] Using default CRAM-MD5 authenticator
I1119 20:55:52.015208 25146 authenticator.hpp:170] Creating new server SASL connection
I1119 20:55:52.015374 25137 authenticatee.hpp:229] Received SASL authentication mechanisms:
CRAM-MD5
I1119 20:55:52.015396 25137 authenticatee.hpp:255] Attempting to authenticate with mechanism
'CRAM-MD5'
I1119 20:55:52.015482 25139 authenticator.hpp:276] Received SASL authentication start
I1119 20:55:52.015537 25139 authenticator.hpp:398] Authentication requires more steps
I1119 20:55:52.015626 25135 authenticatee.hpp:275] Received SASL authentication step
I1119 20:55:52.015718 25139 authenticator.hpp:304] Received SASL authentication step
I1119 20:55:52.015745 25139 auxprop.cpp:99] Request to lookup properties for user: 'test-principal'
realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I1119 20:55:52.141175 25139 auxprop.cpp:171] Looking up auxiliary property '*userPassword'
I1119 20:55:52.141226 25139 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1119 20:55:52.141263 25139 auxprop.cpp:99] Request to lookup properties for user: 'test-principal'
realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I1119 20:55:52.141276 25139 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I1119 20:55:52.141284 25139 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I1119 20:55:52.141304 25139 authenticator.hpp:390] Authentication success
I1119 20:55:52.141443 25135 authenticatee.hpp:315] Authentication success
I1119 20:55:52.141463 25139 master.cpp:3960] Successfully authenticated principal 'test-principal'
at slave(171)@67.195.81.186:57639
I1119 20:55:52.141909 25140 slave.cpp:739] Successfully authenticated with master master@67.195.81.186:57639
I1119 20:55:52.142099 25140 slave.cpp:1067] Will retry registration in 17.332436ms if necessary
I1119 20:55:52.142302 25138 master.cpp:3069] Registering slave at slave(171)@67.195.81.186:57639
(penates.apache.org) with id 20141119-205551-3125920579-57639-25120-S1
I1119 20:55:52.142891 25137 registrar.cpp:445] Applied 1 operations in 80189ns; attempting
to update the 'registry'
I1119 20:55:52.145234 25140 log.cpp:680] Attempting to append 318 bytes to the log
I1119 20:55:52.145550 25146 coordinator.cpp:340] Coordinator attempting to write APPEND action
at position 7
I1119 20:55:52.146803 25147 replica.cpp:508] Replica received write request for position 7
I1119 20:55:52.147094 25147 leveldb.cpp:343] Persisting action (337 bytes) to leveldb took
254443ns
I1119 20:55:52.147125 25147 replica.cpp:676] Persisted action at 7
I1119 20:55:52.148007 25134 replica.cpp:655] Replica received learned notice for position
7
I1119 20:55:52.148839 25134 leveldb.cpp:343] Persisting action (339 bytes) to leveldb took
802036ns
I1119 20:55:52.148866 25134 replica.cpp:676] Persisted action at 7
I1119 20:55:52.148888 25134 replica.cpp:661] Replica learned APPEND action at position 7
I1119 20:55:52.150070 25149 registrar.cpp:490] Successfully updated the 'registry' in 0ns
I1119 20:55:52.150444 25149 log.cpp:699] Attempting to truncate the log to 7
I1119 20:55:52.150643 25139 coordinator.cpp:340] Coordinator attempting to write TRUNCATE
action at position 8
I1119 20:55:52.150822 25134 slave.cpp:2553] Received ping from slave-observer(153)@67.195.81.186:57639
I1119 20:55:52.150784 25138 master.cpp:3123] Registered slave 20141119-205551-3125920579-57639-25120-S1
at slave(171)@67.195.81.186:57639 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024;
ports(*):[31000-32000]
I1119 20:55:52.151083 25144 hierarchical_allocator_process.hpp:442] Added slave 20141119-205551-3125920579-57639-25120-S1
(penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and
cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available)
I1119 20:55:52.151203 25134 slave.cpp:773] Registered with master master@67.195.81.186:57639;
given slave ID 20141119-205551-3125920579-57639-25120-S1
I1119 20:55:52.151306 25140 status_update_manager.cpp:178] Resuming sending status updates
I1119 20:55:52.151396 25144 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024;
disk(*):1024; ports(*):[31000-32000] on slave 20141119-205551-3125920579-57639-25120-S1 to
framework 20141119-205551-3125920579-57639-25120-0000
I1119 20:55:52.151685 25144 hierarchical_allocator_process.hpp:679] Performed allocation for
slave 20141119-205551-3125920579-57639-25120-S1 in 437297ns
I1119 20:55:52.151710 25135 replica.cpp:508] Replica received write request for position 8
I1119 20:55:52.152009 25137 master.cpp:3844] Sending 1 offers to framework 20141119-205551-3125920579-57639-25120-0000
(default) at scheduler-71d4de3d-d16a-443e-888c-3ad984c2993e@67.195.81.186:57639
I1119 20:55:52.152302 25135 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took
560685ns
I1119 20:55:52.152309 25137 sched.cpp:602] Scheduler::resourceOffers took 18501ns
I1119 20:55:52.152333 25135 replica.cpp:676] Persisted action at 8
I1119 20:55:52.152984 25146 replica.cpp:655] Replica received learned notice for position
8
I1119 20:55:52.153604 25146 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took
592552ns
I1119 20:55:52.153681 25146 leveldb.cpp:401] Deleting ~2 keys from leveldb took 48696ns
I1119 20:55:52.153704 25146 replica.cpp:676] Persisted action at 8
I1119 20:55:52.153728 25146 replica.cpp:661] Replica learned TRUNCATE action at position 8
I1119 20:55:52.174667 25145 hierarchical_allocator_process.hpp:659] Performed allocation for
1 slaves in 129261ns
I1119 20:55:52.174723 25135 master.cpp:126] No whitelist given. Advertising offers for all
slaves
I1119 20:55:52.175037 25134 slave.cpp:3252] Framework 20141119-205549-3125920579-57639-25120-0000
seems to have exited. Ignoring registration timeout for executor 'default'
I1119 20:55:52.175127 25141 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_4kBMA9/slaves/20141119-205549-3125920579-57639-25120-S0/frameworks/20141119-205549-3125920579-57639-25120-0000/executors/default/runs/938f4723-ba7b-4738-89db-ac755f638e08
I1119 20:55:52.175256 25141 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_4kBMA9/slaves/20141119-205549-3125920579-57639-25120-S0/frameworks/20141119-205549-3125920579-57639-25120-0000/executors/default/runs/938f4723-ba7b-4738-89db-ac755f638e08'
I1119 20:55:52.175313 25142 slave.cpp:2628] No pings from master received within 75secs
I1119 20:55:52.175366 25134 slave.cpp:2628] No pings from master received within 75secs
I1119 20:55:52.175393 25141 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_4kBMA9/slaves/20141119-205549-3125920579-57639-25120-S0/frameworks/20141119-205549-3125920579-57639-25120-0000/executors/default
I1119 20:55:52.175464 25142 slave.cpp:2553] Received ping from slave-observer(153)@67.195.81.186:57639
I1119 20:55:52.175606 25134 slave.cpp:3336] Current usage 7.68%. Max allowed age: 5.762099051717870days
I1119 20:55:52.175626 25141 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_4kBMA9/slaves/20141119-205549-3125920579-57639-25120-S0/frameworks/20141119-205549-3125920579-57639-25120-0000/executors/default'
I1119 20:55:52.175667 25141 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_4kBMA9/slaves/20141119-205549-3125920579-57639-25120-S0/frameworks/20141119-205549-3125920579-57639-25120-0000
I1119 20:55:52.175700 25142 slave.cpp:3336] Current usage 7.68%. Max allowed age: 5.762099051717870days
I1119 20:55:52.175768 25141 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_4kBMA9/slaves/20141119-205549-3125920579-57639-25120-S0/frameworks/20141119-205549-3125920579-57639-25120-0000'
I1119 20:55:52.175776 25134 slave.cpp:3336] Current usage 7.68%. Max allowed age: 5.762099059095440days
I1119 20:55:52.175850 25149 gc.cpp:167] Pruning directories with remaining removal time 143.280896ms
I1119 20:55:52.175886 25142 slave.cpp:594] Re-detecting master
I1119 20:55:52.175914 25142 slave.cpp:641] Detecting new master
I1119 20:55:52.175941 25135 status_update_manager.cpp:171] Pausing sending status updates
I1119 20:55:52.176003 25149 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_Restart_NfqYfw/slaves/20141119-205551-3125920579-57639-25120-S0
*** Aborted at 1416430552 (unix time) try "date -d @1416430552" if you are using GNU date
***
I1119 20:55:52.176296 25142 slave.cpp:605] New master detected at master@67.195.81.186:57639
I1119 20:55:52.176309 25143 status_update_manager.cpp:171] Pausing sending status updates
PC: @     0x2b37874132d2 (unknown)
I1119 20:55:52.176342 25142 slave.cpp:668] Authenticating with master master@67.195.81.186:57639
I1119 20:55:52.292456 25142 slave.cpp:673] Using default CRAM-MD5 authenticatee
I1119 20:55:52.176353 25149 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_Restart_NfqYfw/slaves/20141119-205551-3125920579-57639-25120-S0'
*** SIGSEGV (@0x1d) received by PID 25120 (TID 0x2b3789e91700) from PID 29; stack trace: ***
I1119 20:55:52.292702 25142 slave.cpp:641] Detecting new master
I1119 20:55:52.292875 25141 authenticatee.hpp:138] Creating new client SASL connection
I1119 20:55:52.293140 25148 master.cpp:3902] Authenticating slave(171)@67.195.81.186:57639
I1119 20:55:52.293181 25148 master.cpp:3913] Using default CRAM-MD5 authenticator
I1119 20:55:52.293591 25147 authenticator.hpp:170] Creating new server SASL connection
I1119 20:55:52.293779 25143 authenticatee.hpp:229] Received SASL authentication mechanisms:
CRAM-MD5
I1119 20:55:52.293805 25143 authenticatee.hpp:255] Attempting to authenticate with mechanism
'CRAM-MD5'
I1119 20:55:52.293903 25148 authenticator.hpp:276] Received SASL authentication start
I1119 20:55:52.293957 25148 authenticator.hpp:398] Authentication requires more steps
I1119 20:55:52.294081 25137 authenticatee.hpp:275] Received SASL authentication step
I1119 20:55:52.294214 25146 authenticator.hpp:304] Received SASL authentication step
I1119 20:55:52.294245 25146 auxprop.cpp:99] Request to lookup properties for user: 'test-principal'
realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false 
I1119 20:55:52.294256 25146 auxprop.cpp:171] Looking up auxiliary property '*userPassword'
I1119 20:55:52.294288 25146 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I1119 20:55:52.294312 25146 auxprop.cpp:99] Request to lookup properties for user: 'test-principal'
realm: 'penates.apache.org' server FQDN: 'penates.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH:
false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true 
I1119 20:55:52.294323 25146 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since
SASL_AUXPROP_AUTHZID == true
I1119 20:55:52.294332 25146 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5'
since SASL_AUXPROP_AUTHZID == true
I1119 20:55:52.294348 25146 authenticator.hpp:390] Authentication success
I1119 20:55:52.294441 25137 authenticatee.hpp:315] Authentication success
I1119 20:55:52.294474 25146 master.cpp:3960] Successfully authenticated principal 'test-principal'
at slave(171)@67.195.81.186:57639
I1119 20:55:52.294723 25137 slave.cpp:739] Successfully authenticated with master master@67.195.81.186:57639
I1119 20:55:52.294875 25137 slave.cpp:1067] Will retry registration in 4.008242ms if necessary
I1119 20:55:52.295094 25135 master.cpp:3196] Re-registering slave 20141119-205551-3125920579-57639-25120-S1
at slave(171)@67.195.81.186:57639 (penates.apache.org)
I1119 20:55:52.295258 25137 slave.cpp:841] Re-registered with master master@67.195.81.186:57639
I1119 20:55:52.295364 25138 status_update_manager.cpp:178] Resuming sending status updates
    @     0x2b3787be6340 (unknown)
    @     0x2b37874132d2 (unknown)
    @     0x2b3784ef0651 process::Process<>::self()
    @     0x2b3784eee72a process::dispatch<>()
    @     0x2b3784ee9ec6 mesos::internal::Files::detach()
    @     0x2b3785202db3 mesos::internal::slave::Slave::detachFile()
    @     0x2b3785247f26 _ZZN7process8dispatchI7NothingN5mesos8internal5slave5SlaveERKSsSsEENS_6FutureIT_EERKNS_3PIDIT0_EEMSC_FS9_T1_ET2_ENKUlPNS_11ProcessBaseEE_clESL_
    @     0x2b378526bb07 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingN5mesos8internal5slave5SlaveERKSsSsEENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSG_FSD_T1_ET2_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_
    @     0x2b378587a78f std::function<>::operator()()
    @     0x2b3785861b0d process::ProcessBase::visit()
    @     0x2b3785866338 process::DispatchEvent::visit()
    @           0x8fb972 process::ProcessBase::serve()
    @     0x2b378585e5a6 process::ProcessManager::resume()
    @     0x2b3785852d65 process::schedule()
    @     0x2b3787bde182 start_thread
    @     0x2b3787eeefbd (unknown)
make[3]: *** [check-local] Segmentation fault
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