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
