filed https://issues.apache.org/jira/browse/MESOS-2143
On Wed, Nov 19, 2014 at 12:58 PM, Apache Jenkins Server < [email protected]> wrote: > 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 > [email protected]:57639 > I1119 20:55:51.660011 25143 sched.cpp:389] Successfully authenticated with > master [email protected]:57639 > I1119 20:55:51.660035 25143 sched.cpp:512] Sending registration request to > [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]:57639 > I1119 20:55:52.014560 25134 slave.cpp:668] Authenticating with master > [email protected]: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 [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]: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 > [email protected]: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 [email protected]: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 > [email protected]: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 >
