Mailing-List: contact builds-help@mesos.apache.org; run by ezmlm
Precedence: bulk
Reply-To: builds@mesos.apache.org
Date: Thu, 13 Aug 2015 19:38:32 +0000 (UTC)
From: Apache Jenkins Server  <jenkins@builds.apache.org>
To: builds@mesos.apache.org, vinodkone@gmail.com
Message-ID: <1033374021.3509.1439494712574.JavaMail.jenkins@crius>
Subject: =?UTF-8?Q?Build_failed_in_Jenkins:_Mesos_=C2=BB_clang,--verbose_?=
 =?UTF-8?Q?--enable-libevent,ubuntu:14.04,docker||Hadoop_#704?=
MIME-Version: 1.0
Content-Type: text/plain; charset=UTF-8
Content-Transfer-Encoding: quoted-printable

See <https://builds.apache.org/job/Mesos/COMPILER=3Dclang,CONFIGURATION=3D-=
-verbose%20--enable-libevent,OS=3Dubuntu%3A14.04,label_exp=3Ddocker%7C%7CHa=
doop/704/changes>

Changes:

[vinodkone] Added Heartbeater to master to send periodic heartbeats to HTTP=
 schedulers.

[vinodkone] Fixed style issue in master/main.cpp.

------------------------------------------
[...truncated 84999 lines...]
I0813 19:39:11.290153 25574 replica.cpp:511] Replica received write request=
 for position 0
I0813 19:39:11.290206 25574 leveldb.cpp:438] Reading position from leveldb =
took 32386ns
I0813 19:39:11.290707 25574 leveldb.cpp:343] Persisting action (14 bytes) t=
o leveldb took 483607ns
I0813 19:39:11.290743 25574 replica.cpp:679] Persisted action at 0
I0813 19:39:11.291465 25586 replica.cpp:658] Replica received learned notic=
e for position 0
I0813 19:39:11.291852 25586 leveldb.cpp:343] Persisting action (16 bytes) t=
o leveldb took 376227ns
I0813 19:39:11.291874 25586 replica.cpp:679] Persisted action at 0
I0813 19:39:11.291888 25586 replica.cpp:664] Replica learned NOP action at =
position 0
I0813 19:39:11.292363 25583 log.cpp:677] Writer started with ending positio=
n 0
I0813 19:39:11.293463 25582 leveldb.cpp:438] Reading position from leveldb =
took 55214ns
I0813 19:39:11.296563 25579 registrar.cpp:344] Successfully fetched the reg=
istry (0B) in 12.617984ms
I0813 19:39:11.296680 25579 registrar.cpp:443] Applied 1 operations in 3710=
9ns; attempting to update the 'registry'
I0813 19:39:11.299659 25577 log.cpp:685] Attempting to append 178 bytes to =
the log
I0813 19:39:11.299787 25585 coordinator.cpp:341] Coordinator attempting to =
write APPEND action at position 1
I0813 19:39:11.300559 25588 replica.cpp:511] Replica received write request=
 for position 1
I0813 19:39:11.301038 25588 leveldb.cpp:343] Persisting action (197 bytes) =
to leveldb took 470677ns
I0813 19:39:11.301090 25588 replica.cpp:679] Persisted action at 1
I0813 19:39:11.301820 25573 replica.cpp:658] Replica received learned notic=
e for position 1
I0813 19:39:11.302361 25573 leveldb.cpp:343] Persisting action (199 bytes) =
to leveldb took 526458ns
I0813 19:39:11.302392 25573 replica.cpp:679] Persisted action at 1
I0813 19:39:11.302412 25573 replica.cpp:664] Replica learned APPEND action =
at position 1
I0813 19:39:11.303418 25577 registrar.cpp:488] Successfully updated the 're=
gistry' in 6.668032ms
I0813 19:39:11.303585 25577 registrar.cpp:374] Successfully recovered regis=
trar
I0813 19:39:11.303735 25584 log.cpp:704] Attempting to truncate the log to =
1
I0813 19:39:11.303870 25579 coordinator.cpp:341] Coordinator attempting to =
write TRUNCATE action at position 2
I0813 19:39:11.304113 25583 master.cpp:1334] Recovered 0 slaves from the Re=
gistry (139B) ; allowing 10mins for slaves to re-register
I0813 19:39:11.304914 25581 replica.cpp:511] Replica received write request=
 for position 2
I0813 19:39:11.305512 25581 leveldb.cpp:343] Persisting action (16 bytes) t=
o leveldb took 564465ns
I0813 19:39:11.305544 25581 replica.cpp:679] Persisted action at 2
I0813 19:39:11.306294 25577 replica.cpp:658] Replica received learned notic=
e for position 2
I0813 19:39:11.306908 25577 leveldb.cpp:343] Persisting action (18 bytes) t=
o leveldb took 594714ns
I0813 19:39:11.306972 25577 leveldb.cpp:401] Deleting ~1 keys from leveldb =
took 47434ns
I0813 19:39:11.307004 25577 replica.cpp:679] Persisted action at 2
I0813 19:39:11.307032 25577 replica.cpp:664] Replica learned TRUNCATE actio=
n at position 2
I0813 19:39:11.314931 25555 scheduler.cpp:157] Version: 0.24.0
I0813 19:39:11.317999 25585 scheduler.cpp:272] New master detected at maste=
r@172.17.2.140:43269
I0813 19:39:11.321113 25574 process.cpp:3043] Handling HTTP event for proce=
ss 'master' with path: '/master/api/v1/scheduler'
I0813 19:39:11.321502 25574 master.cpp:1782] Received subscription request =
for HTTP framework 'default'
I0813 19:39:11.321538 25574 master.cpp:1563] Authorizing framework principa=
l 'test-principal' to receive offers for role '*'
I0813 19:39:11.322031 25574 master.cpp:1874] Subscribing framework 'default=
' with checkpointing disabled and capabilities [  ]
I0813 19:39:11.322541 25573 hierarchical.hpp:391] Added framework 20150813-=
193911-2348945836-43269-25555-0000
I0813 19:39:11.322581 25573 hierarchical.hpp:1008] No resources available t=
o allocate!
I0813 19:39:11.322602 25573 hierarchical.hpp:908] Performed allocation for =
0 slaves in 40651ns
I0813 19:39:11.322863 25585 master.hpp:1305] Sending heartbeat to 20150813-=
193911-2348945836-43269-25555-0000
../../src/tests/scheduler_tests.cpp:168: Failure
Value of: event.get().type()
  Actual: HEARTBEAT
Expected: Event::SUBSCRIBED
Which is: SUBSCRIBED
../../src/tests/scheduler_tests.cpp:169: Failure
Value of: event.get().subscribed().framework_id()
  Actual:=20
Expected: id
Which is: 20150813-193911-2348945836-43269-25555-0000
I0813 19:39:11.325372 25580 master.cpp:859] Master terminating
I0813 19:39:11.325575 25581 scheduler.cpp:381] Ignoring event from old stal=
e connection
I0813 19:39:11.325676 25575 hierarchical.hpp:428] Removed framework 2015081=
3-193911-2348945836-43269-25555-0000
I0813 19:39:11.327193 25589 process.cpp:2319] Returning '404 Not Found' for=
 '/master/api/v1/scheduler'
I0813 19:39:11.328357 25587 scheduler.cpp:421] Enqueuing locally injected e=
vent ERROR
[  FAILED  ] SchedulerTest.Subscribe (57 ms)
[ RUN      ] SchedulerTest.TaskRunning
Using temporary directory '/tmp/SchedulerTest_TaskRunning_4wIysX'
I0813 19:39:11.335824 25555 leveldb.cpp:176] Opened db in 3.415071ms
I0813 19:39:11.337149 25555 leveldb.cpp:183] Compacted db in 1.304082ms
I0813 19:39:11.337208 25555 leveldb.cpp:198] Created db iterator in 29154ns
I0813 19:39:11.337229 25555 leveldb.cpp:204] Seeked to beginning of db in 7=
580ns
I0813 19:39:11.337239 25555 leveldb.cpp:273] Iterated through 0 keys in the=
 db in 5743ns
I0813 19:39:11.337281 25555 replica.cpp:744] Replica recovered with log pos=
itions 0 -> 0 with 1 holes and 0 unlearned
I0813 19:39:11.337735 25575 recover.cpp:449] Starting replica recovery
I0813 19:39:11.338234 25575 recover.cpp:475] Replica is in EMPTY status
I0813 19:39:11.339174 25584 replica.cpp:641] Replica in EMPTY status receiv=
ed a broadcasted recover request
I0813 19:39:11.339646 25575 recover.cpp:195] Received a recover response fr=
om a replica in EMPTY status
I0813 19:39:11.340086 25588 recover.cpp:566] Updating replica status to STA=
RTING
I0813 19:39:11.340989 25579 master.cpp:378] Master 20150813-193911-23489458=
36-43269-25555 (cc2a5033b77b) started on 172.17.2.140:43269
I0813 19:39:11.341217 25581 leveldb.cpp:306] Persisting metadata (8 bytes) =
to leveldb took 830282ns
I0813 19:39:11.341248 25581 replica.cpp:323] Persisted replica status to ST=
ARTING
I0813 19:39:11.341032 25579 master.cpp:380] Flags at startup: --acls=3D"" -=
-allocation_interval=3D"1secs" --allocator=3D"HierarchicalDRF" --authentica=
te=3D"false" --authenticate_slaves=3D"true" --authenticators=3D"crammd5" --=
credentials=3D"/tmp/SchedulerTest_TaskRunning_4wIysX/credentials" --framewo=
rk_sorter=3D"drf" --help=3D"false" --initialize_driver_logging=3D"true" --l=
og_auto_initialize=3D"true" --logbufsecs=3D"0" --logging_level=3D"INFO" --m=
ax_slave_ping_timeouts=3D"5" --quiet=3D"false" --recovery_slave_removal_lim=
it=3D"100%" --registry=3D"replicated_log" --registry_fetch_timeout=3D"1mins=
" --registry_store_timeout=3D"25secs" --registry_strict=3D"true" --root_sub=
missions=3D"true" --slave_ping_timeout=3D"15secs" --slave_reregister_timeou=
t=3D"10mins" --user_sorter=3D"drf" --version=3D"false" --webui_dir=3D"/meso=
s/mesos-0.24.0/_inst/share/mesos/webui" --work_dir=3D"/tmp/SchedulerTest_Ta=
skRunning_4wIysX/master" --zk_session_timeout=3D"10secs"
I0813 19:39:11.341450 25579 master.cpp:427] Master allowing unauthenticated=
 frameworks to register
I0813 19:39:11.341470 25579 master.cpp:430] Master only allowing authentica=
ted slaves to register
I0813 19:39:11.341478 25579 credentials.hpp:37] Loading credentials for aut=
hentication from '/tmp/SchedulerTest_TaskRunning_4wIysX/credentials'
I0813 19:39:11.341563 25574 recover.cpp:475] Replica is in STARTING status
I0813 19:39:11.341802 25579 master.cpp:469] Using default 'crammd5' authent=
icator
I0813 19:39:11.341995 25579 master.cpp:506] Authorization enabled
I0813 19:39:11.342221 25587 whitelist_watcher.cpp:79] No whitelist given
I0813 19:39:11.342356 25573 hierarchical.hpp:346] Initialized hierarchical =
allocator process
I0813 19:39:11.342927 25581 replica.cpp:641] Replica in STARTING status rec=
eived a broadcasted recover request
I0813 19:39:11.343325 25573 recover.cpp:195] Received a recover response fr=
om a replica in STARTING status
I0813 19:39:11.343549 25585 master.cpp:1524] The newly elected leader is ma=
ster@172.17.2.140:43269 with id 20150813-193911-2348945836-43269-25555
I0813 19:39:11.343590 25585 master.cpp:1537] Elected as the leading master!
I0813 19:39:11.343611 25585 master.cpp:1307] Recovering from registrar
I0813 19:39:11.343737 25581 registrar.cpp:311] Recovering registrar
I0813 19:39:11.343967 25574 recover.cpp:566] Updating replica status to VOT=
ING
I0813 19:39:11.344450 25588 leveldb.cpp:306] Persisting metadata (8 bytes) =
to leveldb took 355761ns
I0813 19:39:11.344476 25588 replica.cpp:323] Persisted replica status to VO=
TING
I0813 19:39:11.344558 25584 recover.cpp:580] Successfully joined the Paxos =
group
I0813 19:39:11.344751 25584 recover.cpp:464] Recover process terminated
I0813 19:39:11.345187 25574 log.cpp:661] Attempting to start the writer
I0813 19:39:11.346258 25585 replica.cpp:477] Replica received implicit prom=
ise request with proposal 1
I0813 19:39:11.346653 25585 leveldb.cpp:306] Persisting metadata (8 bytes) =
to leveldb took 381114ns
I0813 19:39:11.346678 25585 replica.cpp:345] Persisted promised to 1
I0813 19:39:11.347175 25588 coordinator.cpp:231] Coordinator attemping to f=
ill missing position
I0813 19:39:11.348291 25585 replica.cpp:378] Replica received explicit prom=
ise request for position 0 with proposal 2
I0813 19:39:11.348716 25585 leveldb.cpp:343] Persisting action (8 bytes) to=
 leveldb took 403744ns
I0813 19:39:11.348740 25585 replica.cpp:679] Persisted action at 0
I0813 19:39:11.349889 25584 replica.cpp:511] Replica received write request=
 for position 0
I0813 19:39:11.349954 25584 leveldb.cpp:438] Reading position from leveldb =
took 41931ns
I0813 19:39:11.350421 25584 leveldb.cpp:343] Persisting action (14 bytes) t=
o leveldb took 433976ns
I0813 19:39:11.350453 25584 replica.cpp:679] Persisted action at 0
I0813 19:39:11.351116 25573 replica.cpp:658] Replica received learned notic=
e for position 0
I0813 19:39:11.351637 25573 leveldb.cpp:343] Persisting action (16 bytes) t=
o leveldb took 492709ns
I0813 19:39:11.351665 25573 replica.cpp:679] Persisted action at 0
I0813 19:39:11.351682 25573 replica.cpp:664] Replica learned NOP action at =
position 0
I0813 19:39:11.352242 25573 log.cpp:677] Writer started with ending positio=
n 0
I0813 19:39:11.353255 25583 leveldb.cpp:438] Reading position from leveldb =
took 59635ns
I0813 19:39:11.356402 25574 registrar.cpp:344] Successfully fetched the reg=
istry (0B) in 12.617984ms
I0813 19:39:11.356521 25574 registrar.cpp:443] Applied 1 operations in 3535=
4ns; attempting to update the 'registry'
I0813 19:39:11.359467 25576 log.cpp:685] Attempting to append 178 bytes to =
the log
I0813 19:39:11.359570 25581 coordinator.cpp:341] Coordinator attempting to =
write APPEND action at position 1
I0813 19:39:11.360397 25582 replica.cpp:511] Replica received write request=
 for position 1
I0813 19:39:11.360970 25582 leveldb.cpp:343] Persisting action (197 bytes) =
to leveldb took 549571ns
I0813 19:39:11.361001 25582 replica.cpp:679] Persisted action at 1
I0813 19:39:11.361763 25580 replica.cpp:658] Replica received learned notic=
e for position 1
I0813 19:39:11.362355 25580 leveldb.cpp:343] Persisting action (199 bytes) =
to leveldb took 507827ns
I0813 19:39:11.362386 25580 replica.cpp:679] Persisted action at 1
I0813 19:39:11.362406 25580 replica.cpp:664] Replica learned APPEND action =
at position 1
I0813 19:39:11.363592 25585 registrar.cpp:488] Successfully updated the 're=
gistry' in 7008us
I0813 19:39:11.363739 25585 registrar.cpp:374] Successfully recovered regis=
trar
I0813 19:39:11.363872 25583 log.cpp:704] Attempting to truncate the log to =
1
I0813 19:39:11.364034 25575 coordinator.cpp:341] Coordinator attempting to =
write TRUNCATE action at position 2
I0813 19:39:11.364222 25584 master.cpp:1334] Recovered 0 slaves from the Re=
gistry (139B) ; allowing 10mins for slaves to re-register
I0813 19:39:11.365118 25575 replica.cpp:511] Replica received write request=
 for position 2
I0813 19:39:11.365631 25575 leveldb.cpp:343] Persisting action (16 bytes) t=
o leveldb took 493844ns
I0813 19:39:11.365664 25575 replica.cpp:679] Persisted action at 2
I0813 19:39:11.366436 25574 replica.cpp:658] Replica received learned notic=
e for position 2
I0813 19:39:11.367002 25574 leveldb.cpp:343] Persisting action (18 bytes) t=
o leveldb took 490242ns
I0813 19:39:11.367089 25574 leveldb.cpp:401] Deleting ~1 keys from leveldb =
took 68785ns
I0813 19:39:11.367117 25574 replica.cpp:679] Persisted action at 2
I0813 19:39:11.367138 25574 replica.cpp:664] Replica learned TRUNCATE actio=
n at position 2
I0813 19:39:11.380969 25578 slave.cpp:190] Slave started on 211)@172.17.2.1=
40:43269
I0813 19:39:11.381017 25578 slave.cpp:191] Flags at startup: --authenticate=
e=3D"crammd5" --cgroups_cpu_enable_pids_and_tids_count=3D"false" --cgroups_=
enable_cfs=3D"false" --cgroups_hierarchy=3D"/sys/fs/cgroup" --cgroups_limit=
_swap=3D"false" --cgroups_root=3D"mesos" --container_disk_watch_interval=3D=
"15secs" --containerizers=3D"mesos" --credential=3D"/tmp/SchedulerTest_Task=
Running_JwLUYa/credential" --default_role=3D"*" --disk_watch_interval=3D"1m=
ins" --docker=3D"docker" --docker_kill_orphans=3D"true" --docker_remove_del=
ay=3D"6hrs" --docker_socket=3D"/var/run/docker.sock" --docker_stop_timeout=
=3D"0ns" --enforce_container_disk_quota=3D"false" --executor_registration_t=
imeout=3D"1mins" --executor_shutdown_grace_period=3D"5secs" --fetcher_cache=
_dir=3D"/tmp/SchedulerTest_TaskRunning_JwLUYa/fetch" --fetcher_cache_size=
=3D"2GB" --frameworks_home=3D"" --gc_delay=3D"1weeks" --gc_disk_headroom=3D=
"0.1" --hadoop_home=3D"" --help=3D"false" --initialize_driver_logging=3D"tr=
ue" --isolation=3D"posix/cpu,posix/mem" --launcher_dir=3D"/mesos/mesos-0.24=
.0/_build/src" --logbufsecs=3D"0" --logging_level=3D"INFO" --oversubscribed=
_resources_interval=3D"15secs" --perf_duration=3D"10secs" --perf_interval=
=3D"1mins" --qos_correction_interval_min=3D"0ns" --quiet=3D"false" --recove=
r=3D"reconnect" --recovery_timeout=3D"15mins" --registration_backoff_factor=
=3D"10ms" --resource_monitoring_interval=3D"1secs" --resources=3D"cpus:2;me=
m:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority=3D"true"=
 --sandbox_directory=3D"/mnt/mesos/sandbox" --strict=3D"true" --switch_user=
=3D"true" --version=3D"false" --work_dir=3D"/tmp/SchedulerTest_TaskRunning_=
JwLUYa"
I0813 19:39:11.381707 25578 credentials.hpp:85] Loading credential for auth=
entication from '/tmp/SchedulerTest_TaskRunning_JwLUYa/credential'
I0813 19:39:11.381927 25578 slave.cpp:321] Slave using credential for: test=
-principal
I0813 19:39:11.382661 25578 slave.cpp:354] Slave resources: cpus(*):2; mem(=
*):1024; disk(*):1024; ports(*):[31000-32000]
I0813 19:39:11.382802 25578 slave.cpp:384] Slave hostname: cc2a5033b77b
I0813 19:39:11.382829 25578 slave.cpp:389] Slave checkpoint: true
I0813 19:39:11.383169 25555 scheduler.cpp:157] Version: 0.24.0
I0813 19:39:11.383623 25574 state.cpp:54] Recovering state from '/tmp/Sched=
ulerTest_TaskRunning_JwLUYa/meta'
I0813 19:39:11.383965 25580 status_update_manager.cpp:202] Recovering statu=
s update manager
I0813 19:39:11.384846 25583 slave.cpp:4069] Finished recovery
I0813 19:39:11.385458 25583 slave.cpp:4226] Querying resource estimator for=
 oversubscribable resources
I0813 19:39:11.385506 25579 scheduler.cpp:272] New master detected at maste=
r@172.17.2.140:43269
I0813 19:39:11.385727 25583 slave.cpp:684] New master detected at master@17=
2.17.2.140:43269
I0813 19:39:11.385776 25586 status_update_manager.cpp:176] Pausing sending =
status updates
I0813 19:39:11.385831 25583 slave.cpp:747] Authenticating with master maste=
r@172.17.2.140:43269
I0813 19:39:11.385857 25583 slave.cpp:752] Using default CRAM-MD5 authentic=
atee
I0813 19:39:11.386102 25583 slave.cpp:720] Detecting new master
I0813 19:39:11.386152 25584 authenticatee.cpp:115] Creating new client SASL=
 connection
I0813 19:39:11.386267 25583 slave.cpp:4240] Received oversubscribable resou=
rces  from the resource estimator
I0813 19:39:11.386531 25583 master.cpp:4693] Authenticating slave(211)@172.=
17.2.140:43269
I0813 19:39:11.386626 25577 authenticator.cpp:407] Starting authentication =
session for crammd5_authenticatee(516)@172.17.2.140:43269
I0813 19:39:11.386880 25575 authenticator.cpp:92] Creating new server SASL =
connection
I0813 19:39:11.387121 25587 authenticatee.cpp:206] Received SASL authentica=
tion mechanisms: CRAM-MD5
I0813 19:39:11.387166 25587 authenticatee.cpp:232] Attempting to authentica=
te with mechanism 'CRAM-MD5'
I0813 19:39:11.387261 25583 authenticator.cpp:197] Received SASL authentica=
tion start
I0813 19:39:11.387361 25583 authenticator.cpp:319] Authentication requires =
more steps
I0813 19:39:11.387460 25583 authenticatee.cpp:252] Received SASL authentica=
tion step
I0813 19:39:11.387553 25583 authenticator.cpp:225] Received SASL authentica=
tion step
I0813 19:39:11.387583 25583 auxprop.cpp:102] Request to lookup properties f=
or user: 'test-principal' realm: 'cc2a5033b77b' server FQDN: 'cc2a5033b77b'=
 SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_=
AUXPROP_AUTHZID: false=20
I0813 19:39:11.387598 25583 auxprop.cpp:174] Looking up auxiliary property =
'*userPassword'
I0813 19:39:11.387636 25583 auxprop.cpp:174] Looking up auxiliary property =
'*cmusaslsecretCRAM-MD5'
I0813 19:39:11.387666 25583 auxprop.cpp:102] Request to lookup properties f=
or user: 'test-principal' realm: 'cc2a5033b77b' server FQDN: 'cc2a5033b77b'=
 SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_=
AUXPROP_AUTHZID: true=20
I0813 19:39:11.387677 25583 auxprop.cpp:124] Skipping auxiliary property '*=
userPassword' since SASL_AUXPROP_AUTHZID =3D=3D true
I0813 19:39:11.387682 25583 auxprop.cpp:124] Skipping auxiliary property '*=
cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID =3D=3D true
I0813 19:39:11.387694 25583 authenticator.cpp:311] Authentication success
I0813 19:39:11.387857 25587 authenticatee.cpp:292] Authentication success
I0813 19:39:11.387878 25584 master.cpp:4723] Successfully authenticated pri=
ncipal 'test-principal' at slave(211)@172.17.2.140:43269
I0813 19:39:11.387909 25580 authenticator.cpp:425] Authentication session c=
leanup for crammd5_authenticatee(516)@172.17.2.140:43269
I0813 19:39:11.388181 25588 slave.cpp:815] Successfully authenticated with =
master master@172.17.2.140:43269
I0813 19:39:11.388239 25587 process.cpp:3043] Handling HTTP event for proce=
ss 'master' with path: '/master/api/v1/scheduler'
I0813 19:39:11.388281 25588 slave.cpp:1209] Will retry registration in 13.1=
92467ms if necessary
I0813 19:39:11.388561 25587 master.cpp:1782] Received subscription request =
for HTTP framework 'default'
I0813 19:39:11.388586 25587 master.cpp:1563] Authorizing framework principa=
l 'test-principal' to receive offers for role '*'
I0813 19:39:11.388898 25587 master.cpp:3635] Registering slave at slave(211=
)@172.17.2.140:43269 (cc2a5033b77b) with id 20150813-193911-2348945836-4326=
9-25555-S0
I0813 19:39:11.389118 25587 master.cpp:1874] Subscribing framework 'default=
' with checkpointing disabled and capabilities [  ]
I0813 19:39:11.389288 25585 registrar.cpp:443] Applied 1 operations in 7225=
8ns; attempting to update the 'registry'
I0813 19:39:11.389379 25580 hierarchical.hpp:391] Added framework 20150813-=
193911-2348945836-43269-25555-0000
I0813 19:39:11.389405 25580 hierarchical.hpp:1008] No resources available t=
o allocate!
I0813 19:39:11.389418 25580 hierarchical.hpp:908] Performed allocation for =
0 slaves in 27342ns
I0813 19:39:11.389472 25587 master.hpp:1305] Sending heartbeat to 20150813-=
193911-2348945836-43269-25555-0000
../../src/tests/scheduler_tests.cpp:227: Failure
Value of: event.get().type()
  Actual: HEARTBEAT
Expected: Event::OFFERS
Which is: OFFERS
../../src/tests/scheduler_tests.cpp:228: Failure
Expected: (0) !=3D (event.get().offers().offers().size()), actual: 0 vs 0
[libprotobuf FATAL ../3rdparty/libprocess/3rdparty/protobuf-2.5.0/src/googl=
e/protobuf/repeated_field.h:824] CHECK failed: (index) < (size()):=20
../../src/tests/scheduler_tests.cpp:237: Failure
Actual function call count doesn't match EXPECT_CALL(containerizer, update(=
_, _))...
         Expected: to be called at least once
           Actual: never called - unsatisfied and active
../../src/tests/scheduler_tests.cpp:233: Failure
Actual function call count doesn't match EXPECT_CALL(exec, launchTask(_, _)=
)...
         Expected: to be called once
           Actual: never called - unsatisfied and active
../../src/tests/scheduler_tests.cpp:230: Failure
Actual function call count doesn't match EXPECT_CALL(exec, registered(_, _,=
 _, _))...
         Expected: to be called once
           Actual: never called - unsatisfied and active
I0813 19:39:11.391996 25578 log.cpp:685] Attempting to append 349 bytes to =
the log
unknown file: Failure
C++ exception with description "CHECK failed: (index) < (size()): " thrown =
in the test body.
I0813 19:39:11.392107 25575 coordinator.cpp:341] Coordinator attempting to =
write APPEND action at position 3
I0813 19:39:11.392549 25574 master.cpp:859] Master terminating
I0813 19:39:11.392828 25580 replica.cpp:511] Replica received write request=
 for position 3
I0813 19:39:11.392843 25586 hierarchical.hpp:428] Removed framework 2015081=
3-193911-2348945836-43269-25555-0000
I0813 19:39:11.393401 25578 slave.cpp:3143] master@172.17.2.140:43269 exite=
d
W0813 19:39:11.393443 25578 slave.cpp:3146] Master disconnected! Waiting fo=
r a new master to be elected
I0813 19:39:11.393638 25580 leveldb.cpp:343] Persisting action (368 bytes) =
to leveldb took 783859ns
I0813 19:39:11.393734 25580 replica.cpp:679] Persisted action at 3
I0813 19:39:11.394592 25575 replica.cpp:658] Replica received learned notic=
e for position 3
I0813 19:39:11.395037 25575 leveldb.cpp:343] Persisting action (370 bytes) =
to leveldb took 443541ns
I0813 19:39:11.395084 25575 replica.cpp:679] Persisted action at 3
I0813 19:39:11.395105 25575 replica.cpp:664] Replica learned APPEND action =
at position 3
*** Aborted at 1439494751 (unix time) try "date -d @1439494751" if you are =
using GNU date ***
PC: @                0x0 (unknown)
I0813 19:39:11.402402 25578 slave.cpp:1209] Will retry registration in 28.1=
31843ms if necessary
I0813 19:39:11.431833 25584 slave.cpp:1209] Will retry registration in 12.3=
2856ms if necessary
*** SIGSEGV (@0x0) received by PID 25555 (TID 0x2b4c9475fb40) from PID 0; s=
tack trace: ***
I0813 19:39:11.445384 25587 slave.cpp:1209] Will retry registration in 136.=
479046ms if necessary
    @     0x2b4cb63a1867 os::Linux::chained_handler()
    @     0x2b4cb63a5af9 JVM_handle_linux_signal
    @     0x2b4c99d54340 (unknown)
make[4]: *** [check-local] Segmentation fault
make[4]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.24.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1439493699-30811
Untagged: mesos-1439493699-30811:latest
Deleted: dc92e614574eaa0677b4f6a84a4b6686137a8ba7c8bfb51b8a4e7ebb7899170e
Deleted: 6cfdc684abc73ba5ecf5649bbb506ebe89133cf4f442e017c9630f6a3a9bc151
Deleted: 811651dc1e7efc8041fce303ce05a684696537835ab31edc15aa5272028719ce
Deleted: ff14df6b613503e9f9c2df1c9e279f1f72b88432c62d2e7391e7100beb6edc7e
Build step 'Execute shell' marked build as failure