See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2539/changes>
Changes: [benjamin.hindman] Abstracted out invoking 'mesos-fetcher'. ------------------------------------------ [...truncated 51158 lines...] I1108 04:44:52.405315 28639 replica.cpp:676] Persisted action at 0 I1108 04:44:52.405799 28629 replica.cpp:655] Replica received learned notice for position 0 I1108 04:44:52.406230 28629 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 412560ns I1108 04:44:52.406249 28629 replica.cpp:676] Persisted action at 0 I1108 04:44:52.406262 28629 replica.cpp:661] Replica learned NOP action at position 0 I1108 04:44:52.406666 28638 log.cpp:672] Writer started with ending position 0 I1108 04:44:52.407613 28634 leveldb.cpp:438] Reading position from leveldb took 22363ns I1108 04:44:52.409680 28627 registrar.cpp:346] Successfully fetched the registry (0B) in 10.11584ms I1108 04:44:52.409771 28627 registrar.cpp:445] Applied 1 operations in 15655ns; attempting to update the 'registry' I1108 04:44:52.411700 28632 log.cpp:680] Attempting to append 143 bytes to the log I1108 04:44:52.411818 28624 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I1108 04:44:52.412412 28638 replica.cpp:508] Replica received write request for position 1 I1108 04:44:52.412874 28638 leveldb.cpp:343] Persisting action (162 bytes) to leveldb took 438499ns I1108 04:44:52.412892 28638 replica.cpp:676] Persisted action at 1 I1108 04:44:52.413326 28626 replica.cpp:655] Replica received learned notice for position 1 I1108 04:44:52.413765 28626 leveldb.cpp:343] Persisting action (164 bytes) to leveldb took 419350ns I1108 04:44:52.413784 28626 replica.cpp:676] Persisted action at 1 I1108 04:44:52.413799 28626 replica.cpp:661] Replica learned APPEND action at position 1 I1108 04:44:52.414469 28636 registrar.cpp:490] Successfully updated the 'registry' in 4.655104ms I1108 04:44:52.414584 28636 registrar.cpp:376] Successfully recovered registrar I1108 04:44:52.414657 28627 log.cpp:699] Attempting to truncate the log to 1 I1108 04:44:52.414768 28628 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2 I1108 04:44:52.414787 28632 master.cpp:1121] Recovered 0 slaves from the Registry (105B) ; allowing 10mins for slaves to re-register I1108 04:44:52.415380 28639 replica.cpp:508] Replica received write request for position 2 I1108 04:44:52.415827 28639 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 422800ns I1108 04:44:52.415845 28639 replica.cpp:676] Persisted action at 2 I1108 04:44:52.416314 28635 replica.cpp:655] Replica received learned notice for position 2 I1108 04:44:52.416710 28635 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 377788ns I1108 04:44:52.416750 28635 leveldb.cpp:401] Deleting ~1 keys from leveldb took 22716ns I1108 04:44:52.416764 28635 replica.cpp:676] Persisted action at 2 I1108 04:44:52.416779 28635 replica.cpp:661] Replica learned TRUNCATE action at position 2 I1108 04:44:52.428979 28637 slave.cpp:169] Slave started on 179)@67.195.81.189:38578 I1108 04:44:52.429020 28637 credentials.hpp:84] Loading credential for authentication from '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/credential' I1108 04:44:52.429195 28637 slave.cpp:276] Slave using credential for: test-principal I1108 04:44:52.429373 28637 slave.cpp:289] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1108 04:44:52.429440 28637 slave.cpp:318] Slave hostname: proserpina.apache.org I1108 04:44:52.429451 28637 slave.cpp:319] Slave checkpoint: false W1108 04:44:52.429457 28637 slave.cpp:321] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag I1108 04:44:52.430110 28634 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/meta' I1108 04:44:52.430361 28626 status_update_manager.cpp:197] Recovering status update manager I1108 04:44:52.430608 28631 slave.cpp:3464] Finished recovery I1108 04:44:52.430999 28634 status_update_manager.cpp:171] Pausing sending status updates I1108 04:44:52.431013 28638 slave.cpp:602] New master detected at [email protected]:38578 I1108 04:44:52.431051 28638 slave.cpp:665] Authenticating with master [email protected]:38578 I1108 04:44:52.431187 28638 slave.cpp:638] Detecting new master I1108 04:44:52.431207 28637 authenticatee.hpp:133] Creating new client SASL connection I1108 04:44:52.431361 28633 master.cpp:3901] Authenticating slave(179)@67.195.81.189:38578 I1108 04:44:52.431391 28633 master.cpp:3912] Using default CRAM-MD5 authenticator I1108 04:44:52.431519 28635 authenticator.hpp:166] Creating new server SASL connection I1108 04:44:52.431630 28637 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1108 04:44:52.431650 28637 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1108 04:44:52.431726 28625 authenticator.hpp:272] Received SASL authentication start I1108 04:44:52.431764 28625 authenticator.hpp:394] Authentication requires more steps I1108 04:44:52.431841 28637 authenticatee.hpp:270] Received SASL authentication step I1108 04:44:52.431926 28626 authenticator.hpp:300] Received SASL authentication step I1108 04:44:52.431948 28626 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1108 04:44:52.431957 28626 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1108 04:44:52.431987 28626 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1108 04:44:52.432003 28626 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1108 04:44:52.432009 28626 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1108 04:44:52.432015 28626 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1108 04:44:52.432027 28626 authenticator.hpp:386] Authentication success I1108 04:44:52.432127 28626 authenticatee.hpp:310] Authentication success I1108 04:44:52.432152 28637 master.cpp:3959] Successfully authenticated principal 'test-principal' at slave(179)@67.195.81.189:38578 I1108 04:44:52.432302 28624 slave.cpp:722] Successfully authenticated with master [email protected]:38578 I1108 04:44:52.432401 28624 slave.cpp:1050] Will retry registration in 248341ns if necessary I1108 04:44:52.432543 28632 master.cpp:3068] Registering slave at slave(179)@67.195.81.189:38578 (proserpina.apache.org) with id 20141108-044452-3176252227-38578-28610-S0 I1108 04:44:52.432847 28638 registrar.cpp:445] Applied 1 operations in 42087ns; attempting to update the 'registry' I1108 04:44:52.433658 28629 slave.cpp:1050] Will retry registration in 26.149965ms if necessary I1108 04:44:52.433745 28630 master.cpp:3056] Ignoring register slave message from slave(179)@67.195.81.189:38578 (proserpina.apache.org) as admission is already in progress I1108 04:44:52.434818 28634 log.cpp:680] Attempting to append 324 bytes to the log I1108 04:44:52.434931 28636 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I1108 04:44:52.435570 28626 replica.cpp:508] Replica received write request for position 3 I1108 04:44:52.435797 28626 leveldb.cpp:343] Persisting action (343 bytes) to leveldb took 204299ns I1108 04:44:52.435817 28626 replica.cpp:676] Persisted action at 3 I1108 04:44:52.436295 28633 replica.cpp:655] Replica received learned notice for position 3 I1108 04:44:52.436775 28633 leveldb.cpp:343] Persisting action (345 bytes) to leveldb took 461841ns I1108 04:44:52.436794 28633 replica.cpp:676] Persisted action at 3 I1108 04:44:52.436807 28633 replica.cpp:661] Replica learned APPEND action at position 3 I1108 04:44:52.437563 28628 registrar.cpp:490] Successfully updated the 'registry' in 4.670976ms I1108 04:44:52.437741 28639 log.cpp:699] Attempting to truncate the log to 3 I1108 04:44:52.437836 28633 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I1108 04:44:52.437952 28635 slave.cpp:2536] Received ping from slave-observer(156)@67.195.81.189:38578 I1108 04:44:52.438035 28624 master.cpp:3122] Registered slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1108 04:44:52.438165 28632 hierarchical_allocator_process.hpp:442] Added slave 20141108-044452-3176252227-38578-28610-S0 (proserpina.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I1108 04:44:52.438233 28635 slave.cpp:756] Registered with master [email protected]:38578; given slave ID 20141108-044452-3176252227-38578-28610-S0 I1108 04:44:52.438289 28632 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141108-044452-3176252227-38578-28610-S0 in 60692ns I1108 04:44:52.438371 28634 status_update_manager.cpp:178] Resuming sending status updates I1108 04:44:52.438556 28626 replica.cpp:508] Replica received write request for position 4 I1108 04:44:52.438941 28626 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 359075ns I1108 04:44:52.438958 28626 replica.cpp:676] Persisted action at 4 I1108 04:44:52.439426 28634 replica.cpp:655] Replica received learned notice for position 4 I1108 04:44:52.439821 28634 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 372567ns I1108 04:44:52.439865 28634 leveldb.cpp:401] Deleting ~2 keys from leveldb took 27350ns I1108 04:44:52.439878 28634 replica.cpp:676] Persisted action at 4 I1108 04:44:52.439893 28634 replica.cpp:661] Replica learned TRUNCATE action at position 4 I1108 04:44:52.442332 28610 sched.cpp:143] Version: 0.22.0 I1108 04:44:52.442729 28638 sched.cpp:239] New master detected at [email protected]:38578 I1108 04:44:52.442760 28638 sched.cpp:295] Authenticating with master [email protected]:38578 I1108 04:44:52.442875 28635 authenticatee.hpp:133] Creating new client SASL connection I1108 04:44:52.443037 28631 master.cpp:3901] Authenticating [email protected]:38578 I1108 04:44:52.443075 28631 master.cpp:3912] Using default CRAM-MD5 authenticator I1108 04:44:52.443200 28630 authenticator.hpp:166] Creating new server SASL connection I1108 04:44:52.443296 28637 authenticatee.hpp:224] Received SASL authentication mechanisms: CRAM-MD5 I1108 04:44:52.443320 28637 authenticatee.hpp:250] Attempting to authenticate with mechanism 'CRAM-MD5' I1108 04:44:52.478956 28634 authenticator.hpp:272] Received SASL authentication start I1108 04:44:52.479027 28634 authenticator.hpp:394] Authentication requires more steps I1108 04:44:52.479115 28634 authenticatee.hpp:270] Received SASL authentication step I1108 04:44:52.479207 28626 authenticator.hpp:300] Received SASL authentication step I1108 04:44:52.479236 28626 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false I1108 04:44:52.479245 28626 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1108 04:44:52.479286 28626 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1108 04:44:52.479301 28626 auxprop.cpp:99] Request to lookup properties for user: 'test-principal' realm: 'proserpina.apache.org' server FQDN: 'proserpina.apache.org' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true I1108 04:44:52.479308 28626 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1108 04:44:52.479313 28626 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1108 04:44:52.479326 28626 authenticator.hpp:386] Authentication success I1108 04:44:52.479442 28628 authenticatee.hpp:310] Authentication success I1108 04:44:52.479465 28634 master.cpp:3959] Successfully authenticated principal 'test-principal' at [email protected]:38578 I1108 04:44:52.479681 28628 sched.cpp:369] Successfully authenticated with master [email protected]:38578 I1108 04:44:52.479699 28628 sched.cpp:488] Sending registration request to [email protected]:38578 I1108 04:44:52.479743 28628 sched.cpp:521] Will retry registration in 55.159075ms if necessary I1108 04:44:52.479854 28634 master.cpp:1383] Received registration request for framework 'default' at [email protected]:38578 I1108 04:44:52.479912 28634 master.cpp:1342] Authorizing framework principal 'test-principal' to receive offers for role '*' I1108 04:44:52.480294 28636 master.cpp:1447] Registering framework 20141108-044452-3176252227-38578-28610-0000 (default) at [email protected]:38578 I1108 04:44:52.480546 28633 hierarchical_allocator_process.hpp:329] Added framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.480660 28624 sched.cpp:419] Framework registered with 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.480732 28624 sched.cpp:433] Scheduler::registered took 52127ns I1108 04:44:52.480732 28633 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141108-044452-3176252227-38578-28610-S0 to framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.480999 28633 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 428857ns I1108 04:44:52.481227 28634 master.cpp:3843] Sending 1 offers to framework 20141108-044452-3176252227-38578-28610-0000 (default) at [email protected]:38578 I1108 04:44:52.481792 28634 sched.cpp:578] Scheduler::resourceOffers took 301213ns I1108 04:44:52.482383 28634 master.cpp:2344] Processing reply for offers: [ 20141108-044452-3176252227-38578-28610-O0 ] on slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) for framework 20141108-044452-3176252227-38578-28610-0000 (default) at [email protected]:38578 W1108 04:44:52.482462 28634 master.cpp:1990] 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. W1108 04:44:52.482486 28634 master.cpp:2001] 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. I1108 04:44:52.482559 28634 master.cpp:2440] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins' I1108 04:44:52.483317 28624 master.hpp:877] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20141108-044452-3176252227-38578-28610-S0 (proserpina.apache.org) I1108 04:44:52.483367 28624 master.cpp:2503] Launching task 0 of framework 20141108-044452-3176252227-38578-28610-0000 (default) at [email protected]:38578 with resources cpus(*):2; mem(*):1024 on slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) I1108 04:44:52.483585 28627 slave.cpp:1081] Got assigned task 0 for framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.483785 28633 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20141108-044452-3176252227-38578-28610-S0 from framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.483829 28633 hierarchical_allocator_process.hpp:599] Framework 20141108-044452-3176252227-38578-28610-0000 filtered slave 20141108-044452-3176252227-38578-28610-S0 for 5secs I1108 04:44:52.484076 28627 slave.cpp:1191] Launching task 0 for framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.485821 28627 slave.cpp:3995] Launching executor default of framework 20141108-044452-3176252227-38578-28610-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000/executors/default/runs/ae08ba85-19d8-446b-9fc4-8bf976e8d611' I1108 04:44:52.487684 28627 exec.cpp:132] Version: 0.22.0 I1108 04:44:52.487900 28631 exec.cpp:182] Executor started at: executor(55)@67.195.81.189:38578 with pid 28610 I1108 04:44:52.488025 28627 slave.cpp:1314] Queuing task '0' for executor default of framework '20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.488157 28627 slave.cpp:555] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000/executors/default/runs/ae08ba85-19d8-446b-9fc4-8bf976e8d611' I1108 04:44:52.488214 28627 slave.cpp:1858] Got registration for executor 'default' of framework 20141108-044452-3176252227-38578-28610-0000 from executor(55)@67.195.81.189:38578 I1108 04:44:52.488453 28627 slave.cpp:1977] Flushing queued task 0 for executor 'default' of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.488520 28628 exec.cpp:206] Executor registered on slave 20141108-044452-3176252227-38578-28610-S0 I1108 04:44:52.488633 28627 slave.cpp:2838] Monitoring executor 'default' of framework '20141108-044452-3176252227-38578-28610-0000' in container 'ae08ba85-19d8-446b-9fc4-8bf976e8d611' I1108 04:44:52.489879 28628 exec.cpp:218] Executor::registered took 21994ns I1108 04:44:52.489994 28628 exec.cpp:293] Executor asked to run task '0' I1108 04:44:52.490048 28628 exec.cpp:302] Executor::launchTask took 40282ns I1108 04:44:52.491394 28628 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.491572 28628 slave.cpp:2213] Handling status update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 from executor(55)@67.195.81.189:38578 I1108 04:44:52.491776 28627 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.491816 28627 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.562410 28627 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 to the slave I1108 04:44:52.562630 28624 slave.cpp:2456] Forwarding the update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 to [email protected]:38578 I1108 04:44:52.562767 28624 slave.cpp:2383] Status update manager successfully handled status update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.562793 28624 slave.cpp:2389] Sending acknowledgement for status update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 to executor(55)@67.195.81.189:38578 I1108 04:44:52.562913 28624 exec.cpp:339] Executor received status update acknowledgement 3ade2736-0d1c-4677-9111-337098ea203c for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.562991 28625 master.cpp:3446] Forwarding status update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.563104 28625 master.cpp:3418] Status update TASK_RUNNING (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 from slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) I1108 04:44:52.563148 28625 master.cpp:4693] Updating the latest state of task 0 of framework 20141108-044452-3176252227-38578-28610-0000 to TASK_RUNNING I1108 04:44:52.563230 28626 sched.cpp:669] Scheduler::statusUpdate took 60543ns I1108 04:44:52.563495 28639 master.cpp:2918] Forwarding status update acknowledgement 3ade2736-0d1c-4677-9111-337098ea203c for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 (default) at [email protected]:38578 to slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) I1108 04:44:52.563760 28633 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.563789 28624 slave.cpp:2896] Executor 'default' of framework 20141108-044452-3176252227-38578-28610-0000 exited with status 0 I1108 04:44:52.565389 28624 slave.cpp:2213] Handling status update TASK_LOST (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 from @0.0.0.0:0 I1108 04:44:52.565448 28624 slave.cpp:4303] Terminating task 0 I1108 04:44:52.565760 28624 slave.cpp:1798] Status update manager successfully handled status update acknowledgement (UUID: 3ade2736-0d1c-4677-9111-337098ea203c) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.565853 28638 master.cpp:3498] Executor default of framework 20141108-044452-3176252227-38578-28610-0000 on slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) exited with status 0 I1108 04:44:52.565906 28638 master.cpp:4781] Removing executor 'default' with resources of framework 20141108-044452-3176252227-38578-28610-0000 on slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) I1108 04:44:52.565949 28633 status_update_manager.cpp:317] Received status update TASK_LOST (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.566035 28633 status_update_manager.cpp:371] Forwarding update TASK_LOST (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 to the slave I1108 04:44:52.566228 28639 slave.cpp:2456] Forwarding the update TASK_LOST (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 to [email protected]:38578 I1108 04:44:52.566367 28639 slave.cpp:2383] Status update manager successfully handled status update TASK_LOST (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.566431 28634 master.cpp:3446] Forwarding status update TASK_LOST (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.566530 28634 master.cpp:3418] Status update TASK_LOST (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 from slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) I1108 04:44:52.566567 28634 master.cpp:4693] Updating the latest state of task 0 of framework 20141108-044452-3176252227-38578-28610-0000 to TASK_LOST I1108 04:44:52.566613 28632 sched.cpp:669] Scheduler::statusUpdate took 12467ns I1108 04:44:52.566766 28636 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141108-044452-3176252227-38578-28610-S0 from framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.566788 28634 master.cpp:4752] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20141108-044452-3176252227-38578-28610-0000 on slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) I1108 04:44:52.566894 28634 master.cpp:2918] Forwarding status update acknowledgement fb405646-e4ab-4b6b-b402-18fba84d3ffd for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 (default) at [email protected]:38578 to slave 20141108-044452-3176252227-38578-28610-S0 at slave(179)@67.195.81.189:38578 (proserpina.apache.org) I1108 04:44:52.567129 28626 status_update_manager.cpp:389] Received status update acknowledgement (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.567194 28626 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.567356 28624 slave.cpp:1798] Status update manager successfully handled status update acknowledgement (UUID: fb405646-e4ab-4b6b-b402-18fba84d3ffd) for task 0 of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.567394 28624 slave.cpp:4342] Completing task 0 I1108 04:44:52.567417 28624 slave.cpp:3005] Cleaning up executor 'default' of framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.567570 28633 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000/executors/default/runs/ae08ba85-19d8-446b-9fc4-8bf976e8d611' for gc 6.99999347810074days in the future I1108 04:44:52.567687 28624 slave.cpp:3082] Cleaning up framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.567723 28633 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000/executors/default' for gc 6.99999347810074days in the future I1108 04:44:52.567757 28626 status_update_manager.cpp:279] Closing status update streams for framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.567813 28633 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000' for gc 6.99999347810074days in the future I1108 04:44:52.685833 28638 master.cpp:126] No whitelist given. Advertising offers for all slaves I1108 04:44:52.685940 28628 hierarchical_allocator_process.hpp:734] Offering disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024 on slave 20141108-044452-3176252227-38578-28610-S0 to framework 20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.686216 28630 slave.cpp:2536] Received ping from slave-observer(156)@67.195.81.189:38578 I1108 04:44:52.686254 28637 slave.cpp:3235] Framework 20141108-044449-3176252227-38578-28610-0000 seems to have exited. Ignoring registration timeout for executor 'default' I1108 04:44:52.686316 28628 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 618551ns I1108 04:44:52.686383 28637 slave.cpp:3319] Current usage 6.38%. Max allowed age: 5.853397189562581days I1108 04:44:52.686460 28628 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_3HQIk5/slaves/20141108-044449-3176252227-38578-28610-S0/frameworks/20141108-044449-3176252227-38578-28610-0000/executors/default/runs/d8a65de6-66ea-4bc2-ae33-2c36ad8681d2 I1108 04:44:52.686470 28634 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000/executors/default/runs/ae08ba85-19d8-446b-9fc4-8bf976e8d611 I1108 04:44:52.686477 28630 slave.cpp:3235] Framework 20141108-044452-3176252227-38578-28610-0000 seems to have exited. Ignoring registration timeout for executor 'default' I1108 04:44:52.686501 28637 slave.cpp:2611] No pings from master received within 75secs I1108 04:44:52.686547 28630 slave.cpp:3319] Current usage 6.38%. Max allowed age: 5.853397189562581days I1108 04:44:52.686605 28628 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_3HQIk5/slaves/20141108-044449-3176252227-38578-28610-S0/frameworks/20141108-044449-3176252227-38578-28610-0000/executors/default/runs/d8a65de6-66ea-4bc2-ae33-2c36ad8681d2' I1108 04:44:52.686606 28634 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000/executors/default/runs/ae08ba85-19d8-446b-9fc4-8bf976e8d611' I1108 04:44:52.686673 28637 slave.cpp:3319] Current usage 6.38%. Max allowed age: 5.853397189562581days *** Aborted at 1415421892 (unix time) try "date -d @1415421892" if you are using GNU date *** I1108 04:44:52.686699 28639 master.cpp:3843] Sending 1 offers to framework 20141108-044452-3176252227-38578-28610-0000 (default) at [email protected]:38578 I1108 04:44:52.686724 28628 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_3HQIk5/slaves/20141108-044449-3176252227-38578-28610-S0/frameworks/20141108-044449-3176252227-38578-28610-0000/executors/default I1108 04:44:52.686738 28634 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000/executors/default PC: @ 0x2b4bb16e12da (unknown) I1108 04:44:52.687100 28634 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000/executors/default' I1108 04:44:52.687136 28634 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000 I1108 04:44:52.687180 28628 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_3HQIk5/slaves/20141108-044449-3176252227-38578-28610-S0/frameworks/20141108-044449-3176252227-38578-28610-0000/executors/default' I1108 04:44:52.687206 28627 sched.cpp:578] Scheduler::resourceOffers took 22796ns I1108 04:44:52.687214 28628 gc.cpp:134] Deleting /tmp/GarbageCollectorIntegrationTest_DiskUsage_3HQIk5/slaves/20141108-044449-3176252227-38578-28610-S0/frameworks/20141108-044449-3176252227-38578-28610-0000 I1108 04:44:52.687229 28634 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_ExitedExecutor_zbfttx/slaves/20141108-044452-3176252227-38578-28610-S0/frameworks/20141108-044452-3176252227-38578-28610-0000' I1108 04:44:52.687299 28628 gc.cpp:143] Deleted '/tmp/GarbageCollectorIntegrationTest_DiskUsage_3HQIk5/slaves/20141108-044449-3176252227-38578-28610-S0/frameworks/20141108-044449-3176252227-38578-28610-0000' *** SIGSEGV (@0x0) received by PID 28610 (TID 0x2b4bb5851700) from PID 0; stack trace: *** @ 0x2b4bb1eb4340 (unknown) @ 0x2b4bb16e12da (unknown) @ 0x2b4baf1ff753 process::Process<>::self() @ 0x2b4baf1fd82c process::dispatch<>() @ 0x2b4baf1f904c mesos::internal::Files::detach() @ 0x2b4baf50827f mesos::internal::slave::Slave::detachFile() @ 0x2b4baf54fcb6 _ZZN7process8dispatchI7NothingN5mesos8internal5slave5SlaveERKSsSsEENS_6FutureIT_EERKNS_3PIDIT0_EEMSC_FS9_T1_ET2_ENKUlPNS_11ProcessBaseEE_clESL_ @ 0x2b4baf574469 _ZNSt17_Function_handlerIFvPN7process11ProcessBaseEEZNS0_8dispatchI7NothingN5mesos8internal5slave5SlaveERKSsSsEENS0_6FutureIT_EERKNS0_3PIDIT0_EEMSG_FSD_T1_ET2_EUlS2_E_E9_M_invokeERKSt9_Any_dataS2_ @ 0x2b4bafb72a25 std::function<>::operator()() @ 0x2b4bafb53ced process::ProcessBase::visit() @ 0x2b4bafb5e622 process::DispatchEvent::visit() @ 0x8f4636 process::ProcessBase::serve() @ 0x2b4bafb4ff62 process::ProcessManager::resume() @ 0x2b4bafb46627 process::schedule() @ 0x2b4bb1eac182 start_thread @ 0x2b4bb21bcfbd (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
