Mailing-List: contact dev-help@mesos.apache.org; run by ezmlm
Precedence: bulk
Reply-To: dev@mesos.apache.org
Date: Sat, 15 Nov 2014 01:53:22 +0000 (UTC)
From: Apache Jenkins Server  <jenkins@builds.apache.org>
To: dev@mesos.apache.org
Message-ID: <57159042.7133.1416016402359.JavaMail.jenkins@crius>
Subject: Build failed in Jenkins:
 Mesos-Trunk-Ubuntu-Build-Out-Of-Src-Disable-Java-Disable-Python-Disable-Webui
 #2564
MIME-Version: 1.0
Content-Type: text/plain; charset=UTF-8
Content-Transfer-Encoding: 7bit

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 : master@67.195.81.186: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 master@67.195.81.186: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 master@67.195.81.186: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 master@67.195.81.186: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 master@67.195.81.186: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 master@67.195.81.186: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 master@67.195.81.186:39596
I1115 01:51:03.496984  3780 sched.cpp:301] Authenticating with master master@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186:39596
I1115 01:51:03.499055  3782 sched.cpp:389] Successfully authenticated with master master@67.195.81.186:39596
I1115 01:51:03.499081  3782 sched.cpp:512] Sending registration request to master@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186: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 master@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186: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 scheduler-e59d07ae-c745-4899-9594-33a86cd5371a@67.195.81.186: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 master@67.195.81.186: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