@till: looks like a segfault in the auth code? can you triage? On Mon, Nov 3, 2014 at 7:38 PM, Apache Jenkins Server < jenk...@builds.apache.org> wrote:
> See < > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/2242/changes > > > > Changes: > > [toenshoff] Updated --isolation help message for slave. > > ------------------------------------------ > [...truncated 5243 lines...] > I1104 03:37:55.460032 28381 master.cpp:2337] Processing reply for offers: > [ 20141104-033755-3176252227-49988-28363-O0 ] on slave > 20141104-033755-3176252227-49988-28363-S0 at slave(1)@67.195.81.189:49988 > (proserpina.apache.org) for framework > 20141104-033755-3176252227-49988-28363-0000 (default) at > scheduler-612ac10f-0976-46e5-9df6-6d51c6c3b683@67.195.81.189:49988 > W1104 03:37:55.460283 28381 master.cpp:1985] 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. > W1104 03:37:55.460320 28381 master.cpp:1996] 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. > I1104 03:37:55.460546 28381 master.cpp:2433] Authorizing framework > principal 'test-principal' to launch task 0 as user 'jenkins' > I1104 03:37:55.462381 28392 master.hpp:877] Adding task 0 with resources > cpus(*):1; mem(*):64 on slave 20141104-033755-3176252227-49988-28363-S0 ( > proserpina.apache.org) > I1104 03:37:55.462442 28392 master.cpp:2496] Launching task 0 of framework > 20141104-033755-3176252227-49988-28363-0000 (default) at > scheduler-612ac10f-0976-46e5-9df6-6d51c6c3b683@67.195.81.189:49988 with > resources cpus(*):1; mem(*):64 on slave > 20141104-033755-3176252227-49988-28363-S0 at slave(1)@67.195.81.189:49988 > (proserpina.apache.org) > I1104 03:37:55.462740 28390 slave.cpp:1081] Got assigned task 0 for > framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.462961 28387 hierarchical_allocator_process.hpp:563] > Recovered cpus(*):1; mem(*):960; disk(*):1024; ports(*):[31000-32000] > (total allocatable: cpus(*):1; mem(*):960; disk(*):1024; > ports(*):[31000-32000]) on slave 20141104-033755-3176252227-49988-28363-S0 > from framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.463003 28387 hierarchical_allocator_process.hpp:599] > Framework 20141104-033755-3176252227-49988-28363-0000 filtered slave > 20141104-033755-3176252227-49988-28363-S0 for 5secs > I1104 03:37:55.463879 28390 slave.cpp:1191] Launching task 0 for framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.466716 28390 slave.cpp:3893] Launching executor default of > framework 20141104-033755-3176252227-49988-28363-0000 in work directory > '/tmp/MasterTest_OfferNotRescindedOnceUsed_IosgUN/slaves/20141104-033755-3176252227-49988-28363-S0/frameworks/20141104-033755-3176252227-49988-28363-0000/executors/default/runs/a25474f8-f457-4e6d-8d8b-45c41fde753b' > I1104 03:37:55.469238 28390 exec.cpp:132] Version: 0.21.0 > I1104 03:37:55.469936 28377 exec.cpp:182] Executor started at: executor(1)@ > 67.195.81.189:49988 with pid 28363 > I1104 03:37:55.470327 28390 slave.cpp:1317] Queuing task '0' for executor > default of framework '20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.470551 28390 slave.cpp:555] Successfully attached file > '/tmp/MasterTest_OfferNotRescindedOnceUsed_IosgUN/slaves/20141104-033755-3176252227-49988-28363-S0/frameworks/20141104-033755-3176252227-49988-28363-0000/executors/default/runs/a25474f8-f457-4e6d-8d8b-45c41fde753b' > I1104 03:37:55.470660 28390 slave.cpp:1849] Got registration for executor > 'default' of framework 20141104-033755-3176252227-49988-28363-0000 from > executor(1)@67.195.81.189:49988 > I1104 03:37:55.471159 28390 slave.cpp:1968] Flushing queued task 0 for > executor 'default' of framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.471324 28382 exec.cpp:206] Executor registered on slave > 20141104-033755-3176252227-49988-28363-S0 > I1104 03:37:55.471479 28390 slave.cpp:2824] Monitoring executor 'default' > of framework '20141104-033755-3176252227-49988-28363-0000' in container > 'a25474f8-f457-4e6d-8d8b-45c41fde753b' > I1104 03:37:55.473562 28382 exec.cpp:218] Executor::registered took 44754ns > I1104 03:37:55.473768 28382 exec.cpp:293] Executor asked to run task '0' > I1104 03:37:55.473961 28382 exec.cpp:302] Executor::launchTask took > 168197ns > I1104 03:37:55.476100 28382 exec.cpp:525] Executor sending status update > TASK_RUNNING (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.476656 28382 slave.cpp:2202] Handling status update > TASK_RUNNING (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 from executor(1)@ > 67.195.81.189:49988 > I1104 03:37:55.477147 28390 status_update_manager.cpp:317] Received status > update TASK_RUNNING (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 > of framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.477203 28390 status_update_manager.cpp:494] Creating > StatusUpdate stream for task 0 of framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.477670 28390 status_update_manager.cpp:371] Forwarding > update TASK_RUNNING (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 > of framework 20141104-033755-3176252227-49988-28363-0000 to the slave > I1104 03:37:55.477877 28386 slave.cpp:2442] Forwarding the update > TASK_RUNNING (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 to > master@67.195.81.189:49988 > I1104 03:37:55.478020 28386 slave.cpp:2369] Status update manager > successfully handled status update TASK_RUNNING (UUID: > 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 of framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.478049 28386 slave.cpp:2375] Sending acknowledgement for > status update TASK_RUNNING (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for > task 0 of framework 20141104-033755-3176252227-49988-28363-0000 to > executor(1)@67.195.81.189:49988 > I1104 03:37:55.478209 28390 master.cpp:3426] Forwarding status update > TASK_RUNNING (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.478281 28387 exec.cpp:339] Executor received status update > acknowledgement 38a55dfc-4392-4356-ad26-09fe19e72df7 for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.478322 28390 master.cpp:3398] Status update TASK_RUNNING > (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 of framework > 20141104-033755-3176252227-49988-28363-0000 from slave > 20141104-033755-3176252227-49988-28363-S0 at slave(1)@67.195.81.189:49988 > (proserpina.apache.org) > I1104 03:37:55.478382 28390 master.cpp:4654] Updating the latest state of > task 0 of framework 20141104-033755-3176252227-49988-28363-0000 to > TASK_RUNNING > I1104 03:37:55.478540 28378 sched.cpp:635] Scheduler::statusUpdate took > 79258ns > I1104 03:37:55.478910 28390 master.cpp:2898] Forwarding status update > acknowledgement 38a55dfc-4392-4356-ad26-09fe19e72df7 for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 (default) at > scheduler-612ac10f-0976-46e5-9df6-6d51c6c3b683@67.195.81.189:49988 to > slave 20141104-033755-3176252227-49988-28363-S0 at slave(1)@ > 67.195.81.189:49988 (proserpina.apache.org) > I1104 03:37:55.479517 28390 status_update_manager.cpp:389] Received status > update acknowledgement (UUID: 38a55dfc-4392-4356-ad26-09fe19e72df7) for > task 0 of framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.479719 28378 hierarchical_allocator_process.hpp:734] > Offering cpus(*):1; mem(*):960; disk(*):1024; ports(*):[31000-32000] on > slave 20141104-033755-3176252227-49988-28363-S0 to framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.479758 28379 slave.cpp:1789] Status update manager > successfully handled status update acknowledgement (UUID: > 38a55dfc-4392-4356-ad26-09fe19e72df7) for task 0 of framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.480165 28390 master.cpp:126] No whitelist given. > Advertising offers for all slaves > I1104 03:37:55.480191 28378 hierarchical_allocator_process.hpp:659] > Performed allocation for 1 slaves in 759575ns > I1104 03:37:55.480533 28385 slave.cpp:2522] Received ping from > slave-observer(1)@67.195.81.189:49988 > I1104 03:37:55.480849 28390 master.cpp:3811] Sending 1 offers to framework > 20141104-033755-3176252227-49988-28363-0000 (default) at > scheduler-612ac10f-0976-46e5-9df6-6d51c6c3b683@67.195.81.189:49988 > I1104 03:37:55.481184 28386 sched.cpp:544] Scheduler::resourceOffers took > 21814ns > I1104 03:37:55.489033 28388 sched.cpp:745] Stopping framework > '20141104-033755-3176252227-49988-28363-0000' > I1104 03:37:55.489090 28389 master.cpp:693] Master terminating > W1104 03:37:55.489441 28389 master.cpp:4699] Removing task 0 with > resources cpus(*):1; mem(*):64 of framework > 20141104-033755-3176252227-49988-28363-0000 on slave > 20141104-033755-3176252227-49988-28363-S0 at slave(1)@67.195.81.189:49988 > (proserpina.apache.org) in non-terminal state TASK_RUNNING > I1104 03:37:55.489784 28384 hierarchical_allocator_process.hpp:563] > Recovered cpus(*):1; mem(*):64 (total allocatable: cpus(*):1; mem(*):64) on > slave 20141104-033755-3176252227-49988-28363-S0 from framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.490154 28389 master.cpp:4742] Removing executor 'default' > with resources of framework 20141104-033755-3176252227-49988-28363-0000 on > slave 20141104-033755-3176252227-49988-28363-S0 at slave(1)@ > 67.195.81.189:49988 (proserpina.apache.org) > I1104 03:37:55.491279 28378 slave.cpp:2607] master@67.195.81.189:49988 > exited > W1104 03:37:55.491307 28378 slave.cpp:2610] Master disconnected! Waiting > for a new master to be elected > I1104 03:37:55.505779 28386 slave.cpp:2882] Executor 'default' of > framework 20141104-033755-3176252227-49988-28363-0000 exited with status 0 > I1104 03:37:55.507554 28386 slave.cpp:2202] Handling status update > TASK_LOST (UUID: 2c883a94-582f-44f0-831e-2b6a3d5e7c12) for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 from @0.0.0.0:0 > I1104 03:37:55.507659 28386 slave.cpp:4201] Terminating task 0 > I1104 03:37:55.508484 28392 status_update_manager.cpp:317] Received status > update TASK_LOST (UUID: 2c883a94-582f-44f0-831e-2b6a3d5e7c12) for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.508622 28392 status_update_manager.cpp:371] Forwarding > update TASK_LOST (UUID: 2c883a94-582f-44f0-831e-2b6a3d5e7c12) for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 to the slave > I1104 03:37:55.508870 28390 slave.cpp:2442] Forwarding the update > TASK_LOST (UUID: 2c883a94-582f-44f0-831e-2b6a3d5e7c12) for task 0 of > framework 20141104-033755-3176252227-49988-28363-0000 to > master@67.195.81.189:49988 > I1104 03:37:55.508998 28390 slave.cpp:2369] Status update manager > successfully handled status update TASK_LOST (UUID: > 2c883a94-582f-44f0-831e-2b6a3d5e7c12) for task 0 of framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.516192 28389 slave.cpp:484] Slave terminating > I1104 03:37:55.516494 28389 slave.cpp:1522] Asked to shut down framework > 20141104-033755-3176252227-49988-28363-0000 by @0.0.0.0:0 > I1104 03:37:55.516521 28389 slave.cpp:1547] Shutting down framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.516755 28389 slave.cpp:3019] Cleaning up executor 'default' > of framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.517096 28388 gc.cpp:56] Scheduling > '/tmp/MasterTest_OfferNotRescindedOnceUsed_IosgUN/slaves/20141104-033755-3176252227-49988-28363-S0/frameworks/20141104-033755-3176252227-49988-28363-0000/executors/default/runs/a25474f8-f457-4e6d-8d8b-45c41fde753b' > for gc 6.99999445880593days in the future > I1104 03:37:55.517292 28389 slave.cpp:3096] Cleaning up framework > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.517410 28377 status_update_manager.cpp:279] Closing status > update streams for framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.517462 28388 gc.cpp:56] Scheduling > '/tmp/MasterTest_OfferNotRescindedOnceUsed_IosgUN/slaves/20141104-033755-3176252227-49988-28363-S0/frameworks/20141104-033755-3176252227-49988-28363-0000/executors/default' > for gc 6.99999445880593days in the future > I1104 03:37:55.517576 28388 gc.cpp:56] Scheduling > '/tmp/MasterTest_OfferNotRescindedOnceUsed_IosgUN/slaves/20141104-033755-3176252227-49988-28363-S0/frameworks/20141104-033755-3176252227-49988-28363-0000' > for gc 6.99999445880593days in the future > I1104 03:37:55.517599 28377 status_update_manager.cpp:525] Cleaning up > status update stream for task 0 of framework > 20141104-033755-3176252227-49988-28363-0000 > [ OK ] MasterTest.OfferNotRescindedOnceUsed (154 ms) > [ RUN ] MasterTest.LaunchDuplicateOfferTest > Using temporary directory '/tmp/MasterTest_LaunchDuplicateOfferTest_XEBbvp' > I1104 03:37:55.523553 28363 leveldb.cpp:176] Opened db in 2.270387ms > I1104 03:37:55.524250 28363 leveldb.cpp:183] Compacted db in 662527ns > I1104 03:37:55.524276 28363 leveldb.cpp:198] Created db iterator in 4964ns > I1104 03:37:55.524284 28363 leveldb.cpp:204] Seeked to beginning of db in > 702ns > I1104 03:37:55.524291 28363 leveldb.cpp:273] Iterated through 0 keys in > the db in 450ns > I1104 03:37:55.524333 28363 replica.cpp:741] Replica recovered with log > positions 0 -> 0 with 1 holes and 0 unlearned > I1104 03:37:55.524852 28384 recover.cpp:437] Starting replica recovery > I1104 03:37:55.525188 28384 recover.cpp:463] Replica is in EMPTY status > I1104 03:37:55.526577 28378 replica.cpp:638] Replica in EMPTY status > received a broadcasted recover request > I1104 03:37:55.527135 28378 master.cpp:318] Master > 20141104-033755-3176252227-49988-28363 (proserpina.apache.org) started on > 67.195.81.189:49988 > I1104 03:37:55.527180 28378 master.cpp:364] Master only allowing > authenticated frameworks to register > I1104 03:37:55.527191 28378 master.cpp:369] Master only allowing > authenticated slaves to register > I1104 03:37:55.527217 28378 credentials.hpp:36] Loading credentials for > authentication from > '/tmp/MasterTest_LaunchDuplicateOfferTest_XEBbvp/credentials' > I1104 03:37:55.527451 28378 master.cpp:408] Authorization enabled > I1104 03:37:55.528081 28384 master.cpp:126] No whitelist given. > Advertising offers for all slaves > I1104 03:37:55.528548 28383 recover.cpp:188] Received a recover response > from a replica in EMPTY status > I1104 03:37:55.528645 28388 hierarchical_allocator_process.hpp:299] > Initializing hierarchical allocator process with master : > master@67.195.81.189:49988 > I1104 03:37:55.529233 28388 master.cpp:1258] The newly elected leader is > master@67.195.81.189:49988 with id 20141104-033755-3176252227-49988-28363 > I1104 03:37:55.529266 28388 master.cpp:1271] Elected as the leading master! > I1104 03:37:55.529289 28388 master.cpp:1089] Recovering from registrar > I1104 03:37:55.529311 28385 recover.cpp:554] Updating replica status to > STARTING > I1104 03:37:55.529500 28384 registrar.cpp:313] Recovering registrar > I1104 03:37:55.530037 28383 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 497965ns > I1104 03:37:55.530083 28383 replica.cpp:320] Persisted replica status to > STARTING > I1104 03:37:55.530335 28387 recover.cpp:463] Replica is in STARTING status > I1104 03:37:55.531343 28381 replica.cpp:638] Replica in STARTING status > received a broadcasted recover request > I1104 03:37:55.531739 28384 recover.cpp:188] Received a recover response > from a replica in STARTING status > I1104 03:37:55.532168 28379 recover.cpp:554] Updating replica status to > VOTING > I1104 03:37:55.532572 28381 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 293974ns > I1104 03:37:55.532594 28381 replica.cpp:320] Persisted replica status to > VOTING > I1104 03:37:55.532790 28390 recover.cpp:568] Successfully joined the Paxos > group > I1104 03:37:55.533107 28390 recover.cpp:452] Recover process terminated > I1104 03:37:55.533604 28382 log.cpp:656] Attempting to start the writer > I1104 03:37:55.534840 28381 replica.cpp:474] Replica received implicit > promise request with proposal 1 > I1104 03:37:55.535188 28381 leveldb.cpp:306] Persisting metadata (8 bytes) > to leveldb took 321021ns > I1104 03:37:55.535212 28381 replica.cpp:342] Persisted promised to 1 > I1104 03:37:55.535893 28378 coordinator.cpp:230] Coordinator attemping to > fill missing position > I1104 03:37:55.537318 28392 replica.cpp:375] Replica received explicit > promise request for position 0 with proposal 2 > I1104 03:37:55.537719 28392 leveldb.cpp:343] Persisting action (8 bytes) > to leveldb took 366858ns > I1104 03:37:55.537746 28392 replica.cpp:676] Persisted action at 0 > I1104 03:37:55.538872 28391 replica.cpp:508] Replica received write > request for position 0 > I1104 03:37:55.538952 28391 leveldb.cpp:438] Reading position from leveldb > took 36647ns > I1104 03:37:55.539482 28391 leveldb.cpp:343] Persisting action (14 bytes) > to leveldb took 483191ns > I1104 03:37:55.539510 28391 replica.cpp:676] Persisted action at 0 > I1104 03:37:55.540045 28379 replica.cpp:655] Replica received learned > notice for position 0 > I1104 03:37:55.540402 28379 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 299913ns > I1104 03:37:55.540429 28379 replica.cpp:676] Persisted action at 0 > I1104 03:37:55.540455 28379 replica.cpp:661] Replica learned NOP action at > position 0 > I1104 03:37:55.541205 28385 log.cpp:672] Writer started with ending > position 0 > I1104 03:37:55.542579 28378 leveldb.cpp:438] Reading position from leveldb > took 33358ns > I1104 03:37:55.545608 28377 registrar.cpp:346] Successfully fetched the > registry (0B) in 16.064768ms > I1104 03:37:55.545778 28377 registrar.cpp:445] Applied 1 operations in > 31511ns; attempting to update the 'registry' > I1104 03:37:55.548598 28382 log.cpp:680] Attempting to append 143 bytes to > the log > I1104 03:37:55.548821 28385 coordinator.cpp:340] Coordinator attempting to > write APPEND action at position 1 > I1104 03:37:55.549759 28385 replica.cpp:508] Replica received write > request for position 1 > I1104 03:37:55.550269 28385 leveldb.cpp:343] Persisting action (162 bytes) > to leveldb took 475014ns > I1104 03:37:55.550300 28385 replica.cpp:676] Persisted action at 1 > I1104 03:37:55.550984 28382 replica.cpp:655] Replica received learned > notice for position 1 > I1104 03:37:55.551311 28382 leveldb.cpp:343] Persisting action (164 bytes) > to leveldb took 304432ns > I1104 03:37:55.551332 28382 replica.cpp:676] Persisted action at 1 > I1104 03:37:55.551349 28382 replica.cpp:661] Replica learned APPEND action > at position 1 > I1104 03:37:55.552196 28392 registrar.cpp:490] Successfully updated the > 'registry' in 6.35904ms > I1104 03:37:55.552338 28392 registrar.cpp:376] Successfully recovered > registrar > I1104 03:37:55.552423 28385 log.cpp:699] Attempting to truncate the log to > 1 > I1104 03:37:55.552577 28382 master.cpp:1116] Recovered 0 slaves from the > Registry (105B) ; allowing 10mins for slaves to re-register > I1104 03:37:55.552647 28378 coordinator.cpp:340] Coordinator attempting to > write TRUNCATE action at position 2 > I1104 03:37:55.553383 28391 replica.cpp:508] Replica received write > request for position 2 > I1104 03:37:55.553772 28391 leveldb.cpp:343] Persisting action (16 bytes) > to leveldb took 357114ns > I1104 03:37:55.553797 28391 replica.cpp:676] Persisted action at 2 > I1104 03:37:55.554234 28379 replica.cpp:655] Replica received learned > notice for position 2 > I1104 03:37:55.554529 28379 leveldb.cpp:343] Persisting action (18 bytes) > to leveldb took 273827ns > I1104 03:37:55.554565 28379 leveldb.cpp:401] Deleting ~1 keys from leveldb > took 16455ns > I1104 03:37:55.554580 28379 replica.cpp:676] Persisted action at 2 > I1104 03:37:55.554596 28379 replica.cpp:661] Replica learned TRUNCATE > action at position 2 > I1104 03:37:55.566700 28385 slave.cpp:169] Slave started on 2)@ > 67.195.81.189:49988 > I1104 03:37:55.566766 28385 credentials.hpp:84] Loading credential for > authentication from > '/tmp/MasterTest_LaunchDuplicateOfferTest_He08c1/credential' > I1104 03:37:55.566946 28385 slave.cpp:276] Slave using credential for: > test-principal > I1104 03:37:55.567327 28385 slave.cpp:289] Slave resources: cpus(*):2; > mem(*):1024; disk(*):3.70122e+06; ports(*):[31000-32000] > I1104 03:37:55.567436 28385 slave.cpp:318] Slave hostname: > proserpina.apache.org > I1104 03:37:55.567458 28385 slave.cpp:319] Slave checkpoint: false > W1104 03:37:55.567471 28385 slave.cpp:321] Disabling checkpointing is > deprecated and the --checkpoint flag will be removed in a future release. > Please avoid using this flag > I1104 03:37:55.568488 28392 state.cpp:33] Recovering state from > '/tmp/MasterTest_LaunchDuplicateOfferTest_He08c1/meta' > I1104 03:37:55.568739 28380 status_update_manager.cpp:197] Recovering > status update manager > I1104 03:37:55.569128 28387 slave.cpp:3478] Finished recovery > I1104 03:37:55.569718 28388 status_update_manager.cpp:171] Pausing sending > status updates > I1104 03:37:55.569773 28384 slave.cpp:602] New master detected at > master@67.195.81.189:49988 > I1104 03:37:55.569820 28384 slave.cpp:665] Authenticating with master > master@67.195.81.189:49988 > I1104 03:37:55.570000 28386 authenticatee.hpp:133] Creating new client > SASL connection > I1104 03:37:55.570137 28384 slave.cpp:638] Detecting new master > I1104 03:37:55.570248 28385 master.cpp:3869] Authenticating slave(2)@ > 67.195.81.189:49988 > I1104 03:37:55.570291 28385 master.cpp:3880] Using default CRAM-MD5 > authenticator > I1104 03:37:55.570592 28377 authenticator.hpp:169] Creating new server > SASL connection > I1104 03:37:55.570808 28377 authenticatee.hpp:224] Received SASL > authentication mechanisms: CRAM-MD5 > I1104 03:37:55.570826 28363 sched.cpp:137] Version: 0.21.0 > I1104 03:37:55.570837 28377 authenticatee.hpp:250] Attempting to > authenticate with mechanism 'CRAM-MD5' > I1104 03:37:55.571281 28386 authenticator.hpp:275] Received SASL > authentication start > I1104 03:37:55.571321 28386 authenticator.hpp:397] Authentication requires > more steps > I1104 03:37:55.571599 28391 sched.cpp:233] New master detected at > master@67.195.81.189:49988 > I1104 03:37:55.571635 28391 sched.cpp:283] Authenticating with master > master@67.195.81.189:49988 > I1104 03:37:55.571771 28378 authenticatee.hpp:133] Creating new client > SASL connection > I1104 03:37:55.571974 28377 authenticatee.hpp:270] Received SASL > authentication step > I1104 03:37:55.572016 28380 master.cpp:3869] Authenticating > scheduler-3dc2b4d5-b421-4e73-9088-32ed103e743d@67.195.81.189:49988 > I1104 03:37:55.572082 28377 authenticator.hpp:303] Received SASL > authentication step > I1104 03:37:55.572103 28380 master.cpp:3880] Using default CRAM-MD5 > authenticator > I1104 03:37:55.572108 28377 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 > I1104 03:37:55.572119 28377 auxprop.cpp:171] Looking up auxiliary property > '*userPassword' > *** Aborted at 1415072275 (unix time) try "date -d @1415072275" if you are > using GNU date *** > I1104 03:37:55.572304 28387 authenticator.hpp:169] Creating new server > SASL connection > PC: @ 0x2aae9cc945cb (unknown) > I1104 03:37:55.572639 28380 authenticatee.hpp:224] Received SASL > authentication mechanisms: CRAM-MD5 > I1104 03:37:55.572667 28380 authenticatee.hpp:250] Attempting to > authenticate with mechanism 'CRAM-MD5' > I1104 03:37:55.572756 28391 authenticator.hpp:275] Received SASL > authentication start > I1104 03:37:55.572796 28391 authenticator.hpp:397] Authentication requires > more steps > I1104 03:37:55.572860 28391 authenticatee.hpp:270] Received SASL > authentication step > I1104 03:37:55.572939 28387 authenticator.hpp:303] Received SASL > authentication step > I1104 03:37:55.572958 28387 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 > I1104 03:37:55.572967 28387 auxprop.cpp:171] Looking up auxiliary property > '*userPassword' > I1104 03:37:55.572999 28387 auxprop.cpp:171] Looking up auxiliary property > '*cmusaslsecretCRAM-MD5' > I1104 03:37:55.573015 28387 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 > I1104 03:37:55.573027 28387 auxprop.cpp:121] Skipping auxiliary property > '*userPassword' since SASL_AUXPROP_AUTHZID == true > I1104 03:37:55.573034 28387 auxprop.cpp:121] Skipping auxiliary property > '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true > I1104 03:37:55.573045 28387 authenticator.hpp:389] Authentication success > I1104 03:37:55.573132 28390 authenticatee.hpp:310] Authentication success > I1104 03:37:55.573168 28388 master.cpp:3930] Successfully authenticated > principal 'test-principal' at > scheduler-3dc2b4d5-b421-4e73-9088-32ed103e743d@67.195.81.189:49988 > I1104 03:37:55.573325 28390 sched.cpp:357] Successfully authenticated with > master master@67.195.81.189:49988 > I1104 03:37:55.573348 28390 sched.cpp:476] Sending registration request to > master@67.195.81.189:49988 > I1104 03:37:55.573606 28388 master.cpp:1378] Received registration request > for framework 'default' at > scheduler-3dc2b4d5-b421-4e73-9088-32ed103e743d@67.195.81.189:49988 > I1104 03:37:55.573689 28388 master.cpp:1337] Authorizing framework > principal 'test-principal' to receive offers for role '*' > I1104 03:37:55.574188 28388 master.cpp:1442] Registering framework > 20141104-033755-3176252227-49988-28363-0000 (default) at > scheduler-3dc2b4d5-b421-4e73-9088-32ed103e743d@67.195.81.189:49988 > I1104 03:37:55.574515 28386 hierarchical_allocator_process.hpp:329] Added > framework 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.574547 28386 hierarchical_allocator_process.hpp:697] No > resources available to allocate! > I1104 03:37:55.574560 28386 hierarchical_allocator_process.hpp:659] > Performed allocation for 0 slaves in 19409ns > I1104 03:37:55.574631 28388 sched.cpp:407] Framework registered with > 20141104-033755-3176252227-49988-28363-0000 > I1104 03:37:55.574676 28388 sched.cpp:421] Scheduler::registered took > 20198ns > *** SIGSEGV (@0xfffffffffffffff8) received by PID 28363 (TID > 0x2aae9f399700) from PID 18446744073709551608; stack trace: *** > @ 0x2aae9d409340 (unknown) > @ 0x2aae9cc945cb (unknown) > @ 0x98e4f0 _ZNSt10_List_nodeISsEC2IJRKSsEEEDpOT_ > @ 0x9788c2 > _ZN9__gnu_cxx13new_allocatorISt10_List_nodeISsEE9constructIS2_JRKSsEEEvPT_DpOT0_ > @ 0x956696 std::list<>::_M_create_node<>() > @ 0x9376a4 std::list<>::_M_insert<>() > @ 0x978829 _ZNSt4listISsSaISsEE12emplace_backIJRKSsEEEvDpOT_ > @ 0x956534 std::list<>::_M_initialize_dispatch<>() > @ 0x9374e1 std::list<>::list() > @ 0xb0f80f mesos::internal::cram_md5::Property::Property() > @ 0x2aae9a6779f8 std::_List_node<>::_List_node<>() > @ 0x2aae9a67664e > _ZN9__gnu_cxx13new_allocatorISt10_List_nodeIN5mesos8internal8cram_md58PropertyEEE9constructIS6_JRKS5_EEEvPT_DpOT0_ > @ 0x2aae9a674876 > _ZNSt4listIN5mesos8internal8cram_md58PropertyESaIS3_EE14_M_create_nodeIJRKS3_EEEPSt10_List_nodeIS3_EDpOT_ > @ 0x2aae9a6721c6 > _ZNSt4listIN5mesos8internal8cram_md58PropertyESaIS3_EE9_M_insertIJRKS3_EEEvSt14_List_iteratorIS3_EDpOT_ > @ 0x2aae9a670326 std::list<>::push_back() > @ 0x2aae9a66e704 Multimap<>::get() > @ 0x2aae9a66b561 > mesos::internal::cram_md5::InMemoryAuxiliaryPropertyPlugin::lookup() > @ 0x2aae9a66aa3d > mesos::internal::cram_md5::InMemoryAuxiliaryPropertyPlugin::lookup() > @ 0x2aae9c7b9e29 (unknown) > @ 0x2aae9c7babe5 (unknown) > @ 0x2aaea2b8252e (unknown) > @ 0x2aaea2b82929 (unknown) > @ 0x2aae9c7c6628 (unknown) > @ 0xb0c482 > mesos::internal::cram_md5::CRAMMD5AuthenticatorProcess::step() > @ 0xb135e9 ProtobufProcess<>::_handler1<>() > @ 0xb25229 > _ZNSt5_BindIFPFvPN5mesos8internal8cram_md527CRAMMD5AuthenticatorProcessEMS3_FvRKSsEMNS1_25AuthenticationStepMessageEKFS6_vERKN7process4UPIDES6_ES4_S8_SB_St12_PlaceholderILi1EESI_ILi2EEEE6__callIvJSF_S6_EJLm0ELm1ELm2ELm3ELm4EEEET_OSt5tupleIJDpT0_EESt12_Index_tupleIJXspT1_EEE > @ 0xb2078c > _ZNSt5_BindIFPFvPN5mesos8internal8cram_md527CRAMMD5AuthenticatorProcessEMS3_FvRKSsEMNS1_25AuthenticationStepMessageEKFS6_vERKN7process4UPIDES6_ES4_S8_SB_St12_PlaceholderILi1EESI_ILi2EEEEclIJSF_S6_EvEET0_DpOT_ > @ 0xb1c33b std::_Function_handler<>::_M_invoke() > @ 0xb2bcc5 std::function<>::operator()() > @ 0xb2ba8e ProtobufProcess<>::visit() > @ 0x2aae9a84120a process::MessageEvent::visit() > @ 0x904766 process::ProcessBase::serve() > make[3]: *** [check-local] Segmentation fault > make[3]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src > '> > make[2]: *** [check-am] Error 2 > make[2]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src > '> > make[1]: *** [check] Error 2 > make[1]: Leaving directory `< > https://builds.apache.org/job/Mesos-Trunk-Ubuntu-Build-In-Src-Set-JAVA_HOME/ws/src > '> > make: *** [check-recursive] Error 1 > Test finished > Exit status: 2 > Build step 'Execute shell' marked build as failure > Recording test results >