See <https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui/2564/changes>
Changes: [niklas] Introduced mutex configure check in libprocess. ------------------------------------------ [...truncated 50336 lines...] I1115 01:51:03.364171 3781 recover.cpp:437] Starting replica recovery I1115 01:51:03.364497 3781 recover.cpp:463] Replica is in EMPTY status I1115 01:51:03.365865 3779 replica.cpp:638] Replica in EMPTY status received a broadcasted recover request I1115 01:51:03.366348 3780 recover.cpp:188] Received a recover response from a replica in EMPTY status I1115 01:51:03.366961 3777 recover.cpp:554] Updating replica status to STARTING I1115 01:51:03.367630 3787 master.cpp:318] Master 20141115-015103-3125920579-39596-3760 (penates.apache.org) started on 67.195.81.186:39596 I1115 01:51:03.367722 3783 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 531054ns I1115 01:51:03.367728 3787 master.cpp:364] Master only allowing authenticated frameworks to register I1115 01:51:03.367759 3783 replica.cpp:320] Persisted replica status to STARTING I1115 01:51:03.367769 3787 master.cpp:369] Master only allowing authenticated slaves to register I1115 01:51:03.367818 3787 credentials.hpp:36] Loading credentials for authentication from '/tmp/GarbageCollectorIntegrationTest_ExitedFramework_67ZD4J/credentials' I1115 01:51:03.368065 3789 recover.cpp:463] Replica is in STARTING status I1115 01:51:03.395867 3787 master.cpp:413] Authorization enabled I1115 01:51:03.396364 3780 master.cpp:126] No whitelist given. Advertising offers for all slaves I1115 01:51:03.396468 3789 hierarchical_allocator_process.hpp:299] Initializing hierarchical allocator process with master : [email protected]:39596 I1115 01:51:03.397018 3780 replica.cpp:638] Replica in STARTING status received a broadcasted recover request I1115 01:51:03.397498 3786 recover.cpp:188] Received a recover response from a replica in STARTING status I1115 01:51:03.397896 3787 master.cpp:1263] The newly elected leader is [email protected]:39596 with id 20141115-015103-3125920579-39596-3760 I1115 01:51:03.397933 3787 master.cpp:1276] Elected as the leading master! I1115 01:51:03.397959 3787 master.cpp:1094] Recovering from registrar I1115 01:51:03.397979 3777 recover.cpp:554] Updating replica status to VOTING I1115 01:51:03.398164 3785 registrar.cpp:313] Recovering registrar I1115 01:51:03.399166 3782 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 765797ns I1115 01:51:03.399210 3782 replica.cpp:320] Persisted replica status to VOTING I1115 01:51:03.399405 3782 recover.cpp:568] Successfully joined the Paxos group I1115 01:51:03.399662 3782 recover.cpp:452] Recover process terminated I1115 01:51:03.400338 3784 log.cpp:656] Attempting to start the writer I1115 01:51:03.401932 3776 replica.cpp:474] Replica received implicit promise request with proposal 1 I1115 01:51:03.402564 3776 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 599136ns I1115 01:51:03.402592 3776 replica.cpp:342] Persisted promised to 1 I1115 01:51:03.403293 3778 coordinator.cpp:230] Coordinator attemping to fill missing position I1115 01:51:03.405107 3779 replica.cpp:375] Replica received explicit promise request for position 0 with proposal 2 I1115 01:51:03.405762 3779 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 618101ns I1115 01:51:03.405792 3779 replica.cpp:676] Persisted action at 0 I1115 01:51:03.407712 3788 replica.cpp:508] Replica received write request for position 0 I1115 01:51:03.407769 3788 leveldb.cpp:438] Reading position from leveldb took 29234ns I1115 01:51:03.408265 3788 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 462546ns I1115 01:51:03.408290 3788 replica.cpp:676] Persisted action at 0 I1115 01:51:03.408838 3786 replica.cpp:655] Replica received learned notice for position 0 I1115 01:51:03.409487 3786 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 617993ns I1115 01:51:03.409538 3786 replica.cpp:676] Persisted action at 0 I1115 01:51:03.409565 3786 replica.cpp:661] Replica learned NOP action at position 0 I1115 01:51:03.410176 3781 log.cpp:672] Writer started with ending position 0 I1115 01:51:03.411552 3786 leveldb.cpp:438] Reading position from leveldb took 46088ns I1115 01:51:03.414705 3789 registrar.cpp:346] Successfully fetched the registry (0B) in 16.494848ms I1115 01:51:03.414818 3789 registrar.cpp:445] Applied 1 operations in 24057ns; attempting to update the 'registry' I1115 01:51:03.417726 3775 log.cpp:680] Attempting to append 139 bytes to the log I1115 01:51:03.417855 3778 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 1 I1115 01:51:03.418709 3776 replica.cpp:508] Replica received write request for position 1 I1115 01:51:03.419312 3776 leveldb.cpp:343] Persisting action (158 bytes) to leveldb took 570178ns I1115 01:51:03.419342 3776 replica.cpp:676] Persisted action at 1 I1115 01:51:03.420011 3788 replica.cpp:655] Replica received learned notice for position 1 I1115 01:51:03.420452 3788 leveldb.cpp:343] Persisting action (160 bytes) to leveldb took 411021ns I1115 01:51:03.420476 3788 replica.cpp:676] Persisted action at 1 I1115 01:51:03.420497 3788 replica.cpp:661] Replica learned APPEND action at position 1 I1115 01:51:03.421716 3779 registrar.cpp:490] Successfully updated the 'registry' in 6.849792ms I1115 01:51:03.421869 3779 registrar.cpp:376] Successfully recovered registrar I1115 01:51:03.422117 3774 log.cpp:699] Attempting to truncate the log to 1 I1115 01:51:03.422128 3779 master.cpp:1121] Recovered 0 slaves from the Registry (101B) ; allowing 10mins for slaves to re-register I1115 01:51:03.422296 3783 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 2 I1115 01:51:03.423460 3780 replica.cpp:508] Replica received write request for position 2 I1115 01:51:03.423938 3780 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 439832ns I1115 01:51:03.423967 3780 replica.cpp:676] Persisted action at 2 I1115 01:51:03.424697 3779 replica.cpp:655] Replica received learned notice for position 2 I1115 01:51:03.425132 3779 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 407240ns I1115 01:51:03.425194 3779 leveldb.cpp:401] Deleting ~1 keys from leveldb took 34261ns I1115 01:51:03.425215 3779 replica.cpp:676] Persisted action at 2 I1115 01:51:03.425237 3779 replica.cpp:661] Replica learned TRUNCATE action at position 2 I1115 01:51:03.438391 3778 slave.cpp:172] Slave started on 187)@67.195.81.186:39596 I1115 01:51:03.438432 3778 credentials.hpp:84] Loading credential for authentication from '/tmp/GarbageCollectorIntegrationTest_ExitedFramework_0HNpQK/credential' I1115 01:51:03.438604 3778 slave.cpp:281] Slave using credential for: test-principal I1115 01:51:03.438808 3778 slave.cpp:294] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1115 01:51:03.438892 3778 slave.cpp:323] Slave hostname: penates.apache.org I1115 01:51:03.438905 3778 slave.cpp:324] Slave checkpoint: false W1115 01:51:03.438912 3778 slave.cpp:326] Disabling checkpointing is deprecated and the --checkpoint flag will be removed in a future release. Please avoid using this flag I1115 01:51:03.439764 3783 state.cpp:33] Recovering state from '/tmp/GarbageCollectorIntegrationTest_ExitedFramework_0HNpQK/meta' I1115 01:51:03.440008 3786 status_update_manager.cpp:197] Recovering status update manager I1115 01:51:03.440326 3783 slave.cpp:3483] Finished recovery I1115 01:51:03.440951 3779 slave.cpp:607] New master detected at [email protected]:39596 I1115 01:51:03.440974 3785 status_update_manager.cpp:171] Pausing sending status updates I1115 01:51:03.441009 3779 slave.cpp:670] Authenticating with master [email protected]:39596 I1115 01:51:03.441026 3779 slave.cpp:675] Using default CRAM-MD5 authenticatee I1115 01:51:03.441174 3779 slave.cpp:643] Detecting new master I1115 01:51:03.441229 3787 authenticatee.hpp:138] Creating new client SASL connection I1115 01:51:03.441448 3779 master.cpp:3893] Authenticating slave(187)@67.195.81.186:39596 I1115 01:51:03.441490 3779 master.cpp:3904] Using default CRAM-MD5 authenticator I1115 01:51:03.441745 3786 authenticator.hpp:170] Creating new server SASL connection I1115 01:51:03.441929 3787 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I1115 01:51:03.441962 3787 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I1115 01:51:03.442111 3788 authenticator.hpp:276] Received SASL authentication start I1115 01:51:03.442180 3788 authenticator.hpp:398] Authentication requires more steps I1115 01:51:03.442278 3788 authenticatee.hpp:275] Received SASL authentication step I1115 01:51:03.442419 3787 authenticator.hpp:304] Received SASL authentication step I1115 01:51:03.442451 3787 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 I1115 01:51:03.442463 3787 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1115 01:51:03.442502 3787 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1115 01:51:03.481039 3787 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 I1115 01:51:03.481060 3787 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1115 01:51:03.481070 3787 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1115 01:51:03.481091 3787 authenticator.hpp:390] Authentication success I1115 01:51:03.481204 3787 authenticatee.hpp:315] Authentication success I1115 01:51:03.481288 3777 master.cpp:3951] Successfully authenticated principal 'test-principal' at slave(187)@67.195.81.186:39596 I1115 01:51:03.481448 3787 slave.cpp:741] Successfully authenticated with master [email protected]:39596 I1115 01:51:03.481600 3787 slave.cpp:1069] Will retry registration in 11.117295ms if necessary I1115 01:51:03.481950 3777 master.cpp:3060] Registering slave at slave(187)@67.195.81.186:39596 (penates.apache.org) with id 20141115-015103-3125920579-39596-3760-S0 I1115 01:51:03.482401 3777 registrar.cpp:445] Applied 1 operations in 55459ns; attempting to update the 'registry' I1115 01:51:03.484794 3782 log.cpp:680] Attempting to append 316 bytes to the log I1115 01:51:03.484949 3778 coordinator.cpp:340] Coordinator attempting to write APPEND action at position 3 I1115 01:51:03.485977 3789 replica.cpp:508] Replica received write request for position 3 I1115 01:51:03.486340 3789 leveldb.cpp:343] Persisting action (335 bytes) to leveldb took 327442ns I1115 01:51:03.486369 3789 replica.cpp:676] Persisted action at 3 I1115 01:51:03.487079 3778 replica.cpp:655] Replica received learned notice for position 3 I1115 01:51:03.487926 3778 leveldb.cpp:343] Persisting action (337 bytes) to leveldb took 824705ns I1115 01:51:03.487951 3778 replica.cpp:676] Persisted action at 3 I1115 01:51:03.487977 3778 replica.cpp:661] Replica learned APPEND action at position 3 I1115 01:51:03.489151 3783 registrar.cpp:490] Successfully updated the 'registry' in 6.70208ms I1115 01:51:03.489344 3775 log.cpp:699] Attempting to truncate the log to 3 I1115 01:51:03.489451 3785 coordinator.cpp:340] Coordinator attempting to write TRUNCATE action at position 4 I1115 01:51:03.489729 3786 master.cpp:3114] Registered slave 20141115-015103-3125920579-39596-3760-S0 at slave(187)@67.195.81.186:39596 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] I1115 01:51:03.489846 3785 slave.cpp:2555] Received ping from slave-observer(163)@67.195.81.186:39596 I1115 01:51:03.489920 3779 hierarchical_allocator_process.hpp:442] Added slave 20141115-015103-3125920579-39596-3760-S0 (penates.apache.org) with cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] (and cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] available) I1115 01:51:03.490023 3779 hierarchical_allocator_process.hpp:679] Performed allocation for slave 20141115-015103-3125920579-39596-3760-S0 in 38131ns I1115 01:51:03.490089 3785 slave.cpp:775] Registered with master [email protected]:39596; given slave ID 20141115-015103-3125920579-39596-3760-S0 I1115 01:51:03.490170 3776 status_update_manager.cpp:178] Resuming sending status updates I1115 01:51:03.491271 3783 replica.cpp:508] Replica received write request for position 4 I1115 01:51:03.491982 3783 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 683167ns I1115 01:51:03.492007 3783 replica.cpp:676] Persisted action at 4 I1115 01:51:03.492730 3787 replica.cpp:655] Replica received learned notice for position 4 I1115 01:51:03.493134 3787 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 348716ns I1115 01:51:03.493190 3787 leveldb.cpp:401] Deleting ~2 keys from leveldb took 32792ns I1115 01:51:03.493206 3787 replica.cpp:676] Persisted action at 4 I1115 01:51:03.493224 3787 replica.cpp:661] Replica learned TRUNCATE action at position 4 I1115 01:51:03.496301 3760 sched.cpp:148] Version: 0.22.0 I1115 01:51:03.496935 3780 sched.cpp:245] New master detected at [email protected]:39596 I1115 01:51:03.496984 3780 sched.cpp:301] Authenticating with master [email protected]:39596 I1115 01:51:03.497000 3780 sched.cpp:308] Using default CRAM-MD5 authenticatee I1115 01:51:03.497211 3788 authenticatee.hpp:138] Creating new client SASL connection I1115 01:51:03.497536 3781 master.cpp:3893] Authenticating [email protected]:39596 I1115 01:51:03.497581 3781 master.cpp:3904] Using default CRAM-MD5 authenticator I1115 01:51:03.497807 3788 authenticator.hpp:170] Creating new server SASL connection I1115 01:51:03.497970 3788 authenticatee.hpp:229] Received SASL authentication mechanisms: CRAM-MD5 I1115 01:51:03.498009 3788 authenticatee.hpp:255] Attempting to authenticate with mechanism 'CRAM-MD5' I1115 01:51:03.498106 3788 authenticator.hpp:276] Received SASL authentication start I1115 01:51:03.498157 3788 authenticator.hpp:398] Authentication requires more steps I1115 01:51:03.498255 3780 authenticatee.hpp:275] Received SASL authentication step I1115 01:51:03.498363 3780 authenticator.hpp:304] Received SASL authentication step I1115 01:51:03.498389 3780 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 I1115 01:51:03.498402 3780 auxprop.cpp:171] Looking up auxiliary property '*userPassword' I1115 01:51:03.498451 3780 auxprop.cpp:171] Looking up auxiliary property '*cmusaslsecretCRAM-MD5' I1115 01:51:03.498491 3780 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 I1115 01:51:03.498528 3780 auxprop.cpp:121] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true I1115 01:51:03.498540 3780 auxprop.cpp:121] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true I1115 01:51:03.498558 3780 authenticator.hpp:390] Authentication success I1115 01:51:03.498680 3782 authenticatee.hpp:315] Authentication success I1115 01:51:03.498713 3780 master.cpp:3951] Successfully authenticated principal 'test-principal' at [email protected]:39596 I1115 01:51:03.499055 3782 sched.cpp:389] Successfully authenticated with master [email protected]:39596 I1115 01:51:03.499081 3782 sched.cpp:512] Sending registration request to [email protected]:39596 I1115 01:51:03.499141 3782 sched.cpp:545] Will retry registration in 859.041983ms if necessary I1115 01:51:03.499301 3782 master.cpp:1383] Received registration request for framework 'default' at [email protected]:39596 I1115 01:51:03.499372 3782 master.cpp:1342] Authorizing framework principal 'test-principal' to receive offers for role '*' I1115 01:51:03.499888 3775 master.cpp:1447] Registering framework 20141115-015103-3125920579-39596-3760-0000 (default) at [email protected]:39596 I1115 01:51:03.500155 3789 hierarchical_allocator_process.hpp:329] Added framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.500293 3789 hierarchical_allocator_process.hpp:734] Offering cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000] on slave 20141115-015103-3125920579-39596-3760-S0 to framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.500381 3775 sched.cpp:439] Framework registered with 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.500434 3775 sched.cpp:453] Scheduler::registered took 27794ns I1115 01:51:03.500639 3789 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 460184ns I1115 01:51:03.500792 3787 master.cpp:3835] Sending 1 offers to framework 20141115-015103-3125920579-39596-3760-0000 (default) at [email protected]:39596 I1115 01:51:03.501370 3787 sched.cpp:602] Scheduler::resourceOffers took 311219ns I1115 01:51:03.502246 3788 master.cpp:2356] Processing reply for offers: [ 20141115-015103-3125920579-39596-3760-O0 ] on slave 20141115-015103-3125920579-39596-3760-S0 at slave(187)@67.195.81.186:39596 (penates.apache.org) for framework 20141115-015103-3125920579-39596-3760-0000 (default) at [email protected]:39596 I1115 01:51:03.502284 3788 master.cpp:2454] Authorizing framework principal 'test-principal' to launch task 0 as user 'jenkins' W1115 01:51:03.503134 3787 master.cpp:1991] 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. W1115 01:51:03.503164 3787 master.cpp:2003] 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. I1115 01:51:03.503412 3787 master.hpp:753] Adding task 0 with resources cpus(*):2; mem(*):1024 on slave 20141115-015103-3125920579-39596-3760-S0 (penates.apache.org) I1115 01:51:03.503461 3787 master.cpp:2522] Launching task 0 of framework 20141115-015103-3125920579-39596-3760-0000 (default) at [email protected]:39596 with resources cpus(*):2; mem(*):1024 on slave 20141115-015103-3125920579-39596-3760-S0 at slave(187)@67.195.81.186:39596 (penates.apache.org) I1115 01:51:03.503814 3776 slave.cpp:1100] Got assigned task 0 for framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.503960 3783 hierarchical_allocator_process.hpp:563] Recovered disk(*):1024; ports(*):[31000-32000] (total allocatable: disk(*):1024; ports(*):[31000-32000]) on slave 20141115-015103-3125920579-39596-3760-S0 from framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.504010 3783 hierarchical_allocator_process.hpp:599] Framework 20141115-015103-3125920579-39596-3760-0000 filtered slave 20141115-015103-3125920579-39596-3760-S0 for 5secs I1115 01:51:03.504343 3776 slave.cpp:1210] Launching task 0 for framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.506906 3776 slave.cpp:4014] Launching executor default of framework 20141115-015103-3125920579-39596-3760-0000 in work directory '/tmp/GarbageCollectorIntegrationTest_ExitedFramework_0HNpQK/slaves/20141115-015103-3125920579-39596-3760-S0/frameworks/20141115-015103-3125920579-39596-3760-0000/executors/default/runs/c2042087-50ed-49c8-9525-a471c89378ab' I1115 01:51:03.509753 3776 exec.cpp:132] Version: 0.22.0 I1115 01:51:03.510030 3787 exec.cpp:182] Executor started at: executor(57)@67.195.81.186:39596 with pid 3760 I1115 01:51:03.510175 3776 slave.cpp:1333] Queuing task '0' for executor default of framework '20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.510308 3776 slave.cpp:560] Successfully attached file '/tmp/GarbageCollectorIntegrationTest_ExitedFramework_0HNpQK/slaves/20141115-015103-3125920579-39596-3760-S0/frameworks/20141115-015103-3125920579-39596-3760-0000/executors/default/runs/c2042087-50ed-49c8-9525-a471c89378ab' I1115 01:51:03.510393 3776 slave.cpp:1877] Got registration for executor 'default' of framework 20141115-015103-3125920579-39596-3760-0000 from executor(57)@67.195.81.186:39596 I1115 01:51:03.510748 3776 slave.cpp:1996] Flushing queued task 0 for executor 'default' of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.510853 3780 exec.cpp:206] Executor registered on slave 20141115-015103-3125920579-39596-3760-S0 I1115 01:51:03.511060 3776 slave.cpp:2857] Monitoring executor 'default' of framework '20141115-015103-3125920579-39596-3760-0000' in container 'c2042087-50ed-49c8-9525-a471c89378ab' I1115 01:51:03.512411 3780 exec.cpp:218] Executor::registered took 22691ns I1115 01:51:03.512563 3780 exec.cpp:293] Executor asked to run task '0' I1115 01:51:03.512624 3780 exec.cpp:302] Executor::launchTask took 43462ns I1115 01:51:03.514075 3780 exec.cpp:525] Executor sending status update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.514266 3780 slave.cpp:2232] Handling status update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 from executor(57)@67.195.81.186:39596 I1115 01:51:03.514557 3777 status_update_manager.cpp:317] Received status update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.514607 3777 status_update_manager.cpp:494] Creating StatusUpdate stream for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.514837 3777 status_update_manager.cpp:371] Forwarding update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 to the slave I1115 01:51:03.515048 3783 slave.cpp:2475] Forwarding the update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 to [email protected]:39596 I1115 01:51:03.515213 3783 slave.cpp:2402] Status update manager successfully handled status update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.515245 3783 slave.cpp:2408] Sending acknowledgement for status update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 to executor(57)@67.195.81.186:39596 I1115 01:51:03.515391 3774 master.cpp:3438] Forwarding status update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.515416 3788 exec.cpp:339] Executor received status update acknowledgement 276b3ce8-e505-4253-9ed7-727a34c56b83 for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.515552 3774 master.cpp:3410] Status update TASK_RUNNING (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 from slave 20141115-015103-3125920579-39596-3760-S0 at slave(187)@67.195.81.186:39596 (penates.apache.org) I1115 01:51:03.515607 3774 master.cpp:4685] Updating the latest state of task 0 of framework 20141115-015103-3125920579-39596-3760-0000 to TASK_RUNNING I1115 01:51:03.515712 3779 sched.cpp:693] Scheduler::statusUpdate took 59549ns I1115 01:51:03.516082 3760 sched.cpp:1364] Asked to stop the driver I1115 01:51:03.516268 3776 master.cpp:2910] Forwarding status update acknowledgement 276b3ce8-e505-4253-9ed7-727a34c56b83 for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 (default) at [email protected]:39596 to slave 20141115-015103-3125920579-39596-3760-S0 at slave(187)@67.195.81.186:39596 (penates.apache.org) I1115 01:51:03.516360 3787 sched.cpp:805] Stopping framework '20141115-015103-3125920579-39596-3760-0000' I1115 01:51:03.516571 3775 master.cpp:1707] Asked to unregister framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.516600 3782 status_update_manager.cpp:389] Received status update acknowledgement (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.516602 3775 master.cpp:4263] Removing framework 20141115-015103-3125920579-39596-3760-0000 (default) at [email protected]:39596 I1115 01:51:03.516752 3784 hierarchical_allocator_process.hpp:405] Deactivated framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.516782 3781 slave.cpp:1550] Asked to shut down framework 20141115-015103-3125920579-39596-3760-0000 by [email protected]:39596 I1115 01:51:03.518841 3775 master.cpp:4685] Updating the latest state of task 0 of framework 20141115-015103-3125920579-39596-3760-0000 to TASK_KILLED I1115 01:51:03.658977 3781 slave.cpp:1575] Shutting down framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.659085 3781 slave.cpp:3163] Shutting down executor 'default' of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.659165 3775 master.cpp:4744] Removing task 0 with resources cpus(*):2; mem(*):1024 of framework 20141115-015103-3125920579-39596-3760-0000 on slave 20141115-015103-3125920579-39596-3760-S0 at slave(187)@67.195.81.186:39596 (penates.apache.org) I1115 01:51:03.659255 3777 exec.cpp:379] Executor asked to shutdown I1115 01:51:03.659253 3781 slave.cpp:1817] Status update manager successfully handled status update acknowledgement (UUID: 276b3ce8-e505-4253-9ed7-727a34c56b83) for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.659318 3777 exec.cpp:394] Executor::shutdown took 30459ns I1115 01:51:03.659297 3786 hierarchical_allocator_process.hpp:563] Recovered cpus(*):2; mem(*):1024 (total allocatable: disk(*):1024; ports(*):[31000-32000]; cpus(*):2; mem(*):1024) on slave 20141115-015103-3125920579-39596-3760-S0 from framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.659598 3775 master.cpp:4773] Removing executor 'default' with resources of framework 20141115-015103-3125920579-39596-3760-0000 on slave 20141115-015103-3125920579-39596-3760-S0 at slave(187)@67.195.81.186:39596 (penates.apache.org) I1115 01:51:03.660235 3785 hierarchical_allocator_process.hpp:360] Removed framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.678858 3783 master.cpp:126] No whitelist given. Advertising offers for all slaves *** Aborted at 1416016263 (unix time) try "date -d @1416016263" if you are using GNU date *** I1115 01:51:03.678863 3780 hierarchical_allocator_process.hpp:659] Performed allocation for 1 slaves in 90619ns I1115 01:51:03.679177 3779 slave.cpp:3233] Killing executor 'default' of framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.679394 3779 slave.cpp:2915] Executor 'default' of framework 20141115-015103-3125920579-39596-3760-0000 exited with status 0 I1115 01:51:03.679630 3779 slave.cpp:3024] Cleaning up executor 'default' of framework 20141115-015103-3125920579-39596-3760-0000 PC: @ 0x41782a0 (unknown) *** SIGSEGV (@0x41782a0) received by PID 3760 (TID 0x2ac2b0f14700) from PID 68649632; stack trace: *** W1115 01:51:03.679802 3777 master.cpp:3484] Ignoring unknown exited executor 'default' of framework 20141115-015103-3125920579-39596-3760-0000 on slave 20141115-015103-3125920579-39596-3760-S0 at slave(187)@67.195.81.186:39596 (penates.apache.org) I1115 01:51:03.679865 3785 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedFramework_0HNpQK/slaves/20141115-015103-3125920579-39596-3760-S0/frameworks/20141115-015103-3125920579-39596-3760-0000/executors/default/runs/c2042087-50ed-49c8-9525-a471c89378ab' for gc 6.99999402464days in the future I1115 01:51:03.680019 3779 slave.cpp:3101] Cleaning up framework 20141115-015103-3125920579-39596-3760-0000 @ 0x2ac2ace5a340 (unknown) I1115 01:51:03.680066 3785 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedFramework_0HNpQK/slaves/20141115-015103-3125920579-39596-3760-S0/frameworks/20141115-015103-3125920579-39596-3760-0000/executors/default' for gc 6.99999402464days in the future I1115 01:51:03.680130 3774 status_update_manager.cpp:279] Closing status update streams for framework 20141115-015103-3125920579-39596-3760-0000 I1115 01:51:03.680207 3785 gc.cpp:56] Scheduling '/tmp/GarbageCollectorIntegrationTest_ExitedFramework_0HNpQK/slaves/20141115-015103-3125920579-39596-3760-S0/frameworks/20141115-015103-3125920579-39596-3760-0000' for gc 6.99999402464days in the future I1115 01:51:03.680222 3774 status_update_manager.cpp:525] Cleaning up status update stream for task 0 of framework 20141115-015103-3125920579-39596-3760-0000 @ 0x41782a0 (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
