You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/11/18 15:57:46 UTC
Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1241
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1241/changes>
Changes:
[benjamin.mahler] Added const qualifiers to ProcessReference operators.
[benjamin.mahler] Introduced an Authenticator interface and an AuthenticationRouter in
------------------------------------------
[...truncated 139215 lines...]
I1118 14:57:25.819588 32393 slave.cpp:2009] Asked to shut down framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000 by @0.0.0.0:0
I1118 14:57:25.819697 32393 slave.cpp:2034] Shutting down framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000
I1118 14:57:25.819778 32393 slave.cpp:3657] Cleaning up executor 'default' of framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000 at executor(132)@172.17.18.215:47157
I1118 14:57:25.820080 32390 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_fCS0c8/slaves/78264a18-9077-45f5-ac83-cbfe1d116912-S0/frameworks/78264a18-9077-45f5-ac83-cbfe1d116912-0000/executors/default/runs/6ab20a0e-ca01-4484-ae9d-9bb441d8e41b' for gc 6.99999050949037days in the future
I1118 14:57:25.820340 32393 slave.cpp:3745] Cleaning up framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000
I1118 14:57:25.820395 32403 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_fCS0c8/slaves/78264a18-9077-45f5-ac83-cbfe1d116912-S0/frameworks/78264a18-9077-45f5-ac83-cbfe1d116912-0000/executors/default' for gc 6.99999050653926days in the future
I1118 14:57:25.820508 32389 status_update_manager.cpp:284] Closing status update streams for framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000
I1118 14:57:25.820569 32389 status_update_manager.cpp:530] Cleaning up status update stream for task 738e02f7-4a17-419a-81d1-532b2833bc2a of framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000
I1118 14:57:25.820667 32397 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_fCS0c8/slaves/78264a18-9077-45f5-ac83-cbfe1d116912-S0/frameworks/78264a18-9077-45f5-ac83-cbfe1d116912-0000' for gc 6.99999050328593days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (114 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1118 14:57:25.829965 32370 leveldb.cpp:176] Opened db in 3.862297ms
I1118 14:57:25.831382 32370 leveldb.cpp:183] Compacted db in 1.375064ms
I1118 14:57:25.831449 32370 leveldb.cpp:198] Created db iterator in 19287ns
I1118 14:57:25.831470 32370 leveldb.cpp:204] Seeked to beginning of db in 2270ns
I1118 14:57:25.831480 32370 leveldb.cpp:273] Iterated through 0 keys in the db in 335ns
I1118 14:57:25.831539 32370 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1118 14:57:25.832168 32401 recover.cpp:449] Starting replica recovery
I1118 14:57:25.832445 32401 recover.cpp:475] Replica is in EMPTY status
I1118 14:57:25.833976 32403 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10566)@172.17.18.215:47157
I1118 14:57:25.834455 32394 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1118 14:57:25.835110 32396 recover.cpp:566] Updating replica status to STARTING
I1118 14:57:25.835850 32403 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 557114ns
I1118 14:57:25.835888 32403 replica.cpp:323] Persisted replica status to STARTING
I1118 14:57:25.836108 32393 recover.cpp:475] Replica is in STARTING status
I1118 14:57:25.836205 32395 master.cpp:367] Master 93e512e6-bdfe-430b-b302-a4fee73ca378 (6ff0eaa4e60a) started on 172.17.18.215:47157
I1118 14:57:25.836235 32395 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/9cN8al/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/9cN8al/master" --zk_session_timeout="10secs"
I1118 14:57:25.836688 32395 master.cpp:416] Master allowing unauthenticated frameworks to register
I1118 14:57:25.836702 32395 master.cpp:419] Master only allowing authenticated slaves to register
I1118 14:57:25.836711 32395 credentials.hpp:37] Loading credentials for authentication from '/tmp/9cN8al/credentials'
I1118 14:57:25.837043 32395 master.cpp:458] Using default 'crammd5' authenticator
I1118 14:57:25.837203 32395 master.cpp:495] Authorization enabled
I1118 14:57:25.837332 32394 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10567)@172.17.18.215:47157
I1118 14:57:25.837626 32402 hierarchical.cpp:153] Initialized hierarchical allocator process
I1118 14:57:25.837699 32402 whitelist_watcher.cpp:79] No whitelist given
I1118 14:57:25.838245 32398 recover.cpp:195] Received a recover response from a replica in STARTING status
I1118 14:57:25.838841 32404 recover.cpp:566] Updating replica status to VOTING
I1118 14:57:25.839637 32401 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 510185ns
I1118 14:57:25.839668 32401 replica.cpp:323] Persisted replica status to VOTING
I1118 14:57:25.839787 32397 recover.cpp:580] Successfully joined the Paxos group
I1118 14:57:25.840132 32397 recover.cpp:464] Recover process terminated
I1118 14:57:25.840234 32396 master.cpp:1612] The newly elected leader is master@172.17.18.215:47157 with id 93e512e6-bdfe-430b-b302-a4fee73ca378
I1118 14:57:25.840277 32396 master.cpp:1625] Elected as the leading master!
I1118 14:57:25.840304 32396 master.cpp:1385] Recovering from registrar
I1118 14:57:25.840503 32399 registrar.cpp:309] Recovering registrar
I1118 14:57:25.841228 32404 log.cpp:661] Attempting to start the writer
I1118 14:57:25.843453 32393 replica.cpp:496] Replica received implicit promise request from (10568)@172.17.18.215:47157 with proposal 1
I1118 14:57:25.844174 32393 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 678591ns
I1118 14:57:25.844208 32393 replica.cpp:345] Persisted promised to 1
I1118 14:57:25.845754 32402 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1118 14:57:25.847532 32403 replica.cpp:391] Replica received explicit promise request from (10569)@172.17.18.215:47157 for position 0 with proposal 2
I1118 14:57:25.848218 32403 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 632829ns
I1118 14:57:25.848249 32403 replica.cpp:715] Persisted action at 0
I1118 14:57:25.850400 32390 replica.cpp:540] Replica received write request for position 0 from (10570)@172.17.18.215:47157
I1118 14:57:25.850487 32390 leveldb.cpp:438] Reading position from leveldb took 40797ns
I1118 14:57:25.851188 32390 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 636982ns
I1118 14:57:25.851238 32390 replica.cpp:715] Persisted action at 0
I1118 14:57:25.852253 32402 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1118 14:57:25.852844 32402 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 551574ns
I1118 14:57:25.852882 32402 replica.cpp:715] Persisted action at 0
I1118 14:57:25.852916 32402 replica.cpp:700] Replica learned NOP action at position 0
I1118 14:57:25.853726 32403 log.cpp:677] Writer started with ending position 0
I1118 14:57:25.855095 32393 leveldb.cpp:438] Reading position from leveldb took 39146ns
I1118 14:57:25.856219 32403 registrar.cpp:342] Successfully fetched the registry (0B) in 15.664128ms
I1118 14:57:25.856338 32403 registrar.cpp:441] Applied 1 operations in 28452ns; attempting to update the 'registry'
I1118 14:57:25.857318 32389 log.cpp:685] Attempting to append 178 bytes to the log
I1118 14:57:25.857499 32399 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1118 14:57:25.858671 32397 replica.cpp:540] Replica received write request for position 1 from (10571)@172.17.18.215:47157
I1118 14:57:25.859285 32397 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 568742ns
I1118 14:57:25.859323 32397 replica.cpp:715] Persisted action at 1
I1118 14:57:25.860077 32397 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1118 14:57:25.860714 32397 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 596758ns
I1118 14:57:25.860748 32397 replica.cpp:715] Persisted action at 1
I1118 14:57:25.860770 32397 replica.cpp:700] Replica learned APPEND action at position 1
I1118 14:57:25.861984 32399 registrar.cpp:486] Successfully updated the 'registry' in 5.577984ms
I1118 14:57:25.862151 32399 registrar.cpp:372] Successfully recovered registrar
I1118 14:57:25.862236 32401 log.cpp:704] Attempting to truncate the log to 1
I1118 14:57:25.862418 32395 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1118 14:57:25.862639 32403 master.cpp:1422] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1118 14:57:25.863729 32396 replica.cpp:540] Replica received write request for position 2 from (10572)@172.17.18.215:47157
I1118 14:57:25.864228 32396 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 452324ns
I1118 14:57:25.864264 32396 replica.cpp:715] Persisted action at 2
I1118 14:57:25.865064 32402 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1118 14:57:25.865463 32402 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 358859ns
I1118 14:57:25.865531 32402 leveldb.cpp:401] Deleting ~1 keys from leveldb took 34591ns
I1118 14:57:25.865557 32402 replica.cpp:715] Persisted action at 2
I1118 14:57:25.865581 32402 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1118 14:57:25.873694 32370 scheduler.cpp:156] Version: 0.26.0
I1118 14:57:25.874574 32389 scheduler.cpp:238] New master detected at master@172.17.18.215:47157
I1118 14:57:25.876019 32392 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.18.215:47157
I1118 14:57:25.877934 32399 process.cpp:3155] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1118 14:57:25.878000 32399 http.cpp:338] HTTP POST for /master/api/v1/scheduler from 172.17.18.215:42070
I1118 14:57:25.878228 32399 master.cpp:1874] Received subscription request for HTTP framework 'default'
I1118 14:57:25.878321 32399 master.cpp:1651] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1118 14:57:25.878875 32394 master.cpp:1966] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1118 14:57:25.879290 32389 hierarchical.cpp:197] Added framework 93e512e6-bdfe-430b-b302-a4fee73ca378-0000
I1118 14:57:25.879345 32389 hierarchical.cpp:979] No resources available to allocate!
I1118 14:57:25.879358 32392 master.hpp:1522] Sending heartbeat to 93e512e6-bdfe-430b-b302-a4fee73ca378-0000
I1118 14:57:25.879374 32389 hierarchical.cpp:1072] No inverse offers to send out!
I1118 14:57:25.879397 32389 hierarchical.cpp:878] Performed allocation for 0 slaves in 79401ns
I1118 14:57:25.880064 32390 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.18.215:47157
I1118 14:57:25.880630 32390 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.18.215:47157
I1118 14:57:25.881371 32389 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1118 14:57:25.881470 32390 scheduler.cpp:300] Sending REQUEST call to master@172.17.18.215:47157
I1118 14:57:25.883266 32403 process.cpp:3155] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1118 14:57:25.883318 32403 http.cpp:338] HTTP POST for /master/api/v1/scheduler from 172.17.18.215:42071
I1118 14:57:25.883424 32403 master.cpp:2621] Processing REQUEST call for framework 93e512e6-bdfe-430b-b302-a4fee73ca378-0000 (default)
I1118 14:57:25.883587 32396 hierarchical.cpp:460] Received resource request from framework 93e512e6-bdfe-430b-b302-a4fee73ca378-0000
I1118 14:57:25.884018 32397 master.cpp:928] Master terminating
I1118 14:57:25.884222 32403 hierarchical.cpp:232] Removed framework 93e512e6-bdfe-430b-b302-a4fee73ca378-0000
E1118 14:57:25.885417 32391 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (65 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1118 14:57:25.894351 32370 leveldb.cpp:176] Opened db in 3.21735ms
I1118 14:57:25.895536 32370 leveldb.cpp:183] Compacted db in 1.144274ms
I1118 14:57:25.895596 32370 leveldb.cpp:198] Created db iterator in 17127ns
I1118 14:57:25.895615 32370 leveldb.cpp:204] Seeked to beginning of db in 1722ns
I1118 14:57:25.895627 32370 leveldb.cpp:273] Iterated through 0 keys in the db in 257ns
I1118 14:57:25.895663 32370 replica.cpp:780] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1118 14:57:25.896224 32400 recover.cpp:449] Starting replica recovery
I1118 14:57:25.896478 32390 recover.cpp:475] Replica is in EMPTY status
I1118 14:57:25.897496 32390 replica.cpp:676] Replica in EMPTY status received a broadcasted recover request from (10577)@172.17.18.215:47157
I1118 14:57:25.897860 32400 recover.cpp:195] Received a recover response from a replica in EMPTY status
I1118 14:57:25.898435 32402 recover.cpp:566] Updating replica status to STARTING
I1118 14:57:25.899166 32403 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 524135ns
I1118 14:57:25.899196 32403 replica.cpp:323] Persisted replica status to STARTING
I1118 14:57:25.899415 32395 recover.cpp:475] Replica is in STARTING status
I1118 14:57:25.899914 32403 master.cpp:367] Master 1a73f83b-0ede-4040-94e2-128ab6639958 (6ff0eaa4e60a) started on 172.17.18.215:47157
I1118 14:57:25.899940 32403 master.cpp:369] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/j9KsrK/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="25secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui" --work_dir="/tmp/j9KsrK/master" --zk_session_timeout="10secs"
I1118 14:57:25.900171 32403 master.cpp:416] Master allowing unauthenticated frameworks to register
I1118 14:57:25.900187 32403 master.cpp:419] Master only allowing authenticated slaves to register
I1118 14:57:25.900198 32403 credentials.hpp:37] Loading credentials for authentication from '/tmp/j9KsrK/credentials'
I1118 14:57:25.900287 32404 replica.cpp:676] Replica in STARTING status received a broadcasted recover request from (10578)@172.17.18.215:47157
I1118 14:57:25.900449 32403 master.cpp:458] Using default 'crammd5' authenticator
I1118 14:57:25.900566 32403 master.cpp:495] Authorization enabled
I1118 14:57:25.900589 32393 recover.cpp:195] Received a recover response from a replica in STARTING status
I1118 14:57:25.900853 32399 hierarchical.cpp:153] Initialized hierarchical allocator process
I1118 14:57:25.900869 32397 whitelist_watcher.cpp:79] No whitelist given
I1118 14:57:25.901037 32391 recover.cpp:566] Updating replica status to VOTING
I1118 14:57:25.901593 32389 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 373738ns
I1118 14:57:25.901618 32389 replica.cpp:323] Persisted replica status to VOTING
I1118 14:57:25.901749 32395 recover.cpp:580] Successfully joined the Paxos group
I1118 14:57:25.901957 32395 recover.cpp:464] Recover process terminated
I1118 14:57:25.902403 32395 master.cpp:1612] The newly elected leader is master@172.17.18.215:47157 with id 1a73f83b-0ede-4040-94e2-128ab6639958
I1118 14:57:25.902431 32395 master.cpp:1625] Elected as the leading master!
I1118 14:57:25.902451 32395 master.cpp:1385] Recovering from registrar
I1118 14:57:25.902565 32398 registrar.cpp:309] Recovering registrar
I1118 14:57:25.903091 32390 log.cpp:661] Attempting to start the writer
I1118 14:57:25.904078 32399 replica.cpp:496] Replica received implicit promise request from (10579)@172.17.18.215:47157 with proposal 1
I1118 14:57:25.904649 32399 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 538280ns
I1118 14:57:25.904681 32399 replica.cpp:345] Persisted promised to 1
I1118 14:57:25.905484 32395 coordinator.cpp:240] Coordinator attempting to fill missing positions
I1118 14:57:25.907127 32395 replica.cpp:391] Replica received explicit promise request from (10580)@172.17.18.215:47157 for position 0 with proposal 2
I1118 14:57:25.907654 32395 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 426015ns
I1118 14:57:25.907685 32395 replica.cpp:715] Persisted action at 0
I1118 14:57:25.908995 32400 replica.cpp:540] Replica received write request for position 0 from (10581)@172.17.18.215:47157
I1118 14:57:25.909065 32400 leveldb.cpp:438] Reading position from leveldb took 29152ns
I1118 14:57:25.909543 32400 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 429960ns
I1118 14:57:25.909574 32400 replica.cpp:715] Persisted action at 0
I1118 14:57:25.910322 32401 replica.cpp:694] Replica received learned notice for position 0 from @0.0.0.0:0
I1118 14:57:25.910780 32401 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 416717ns
I1118 14:57:25.910852 32401 replica.cpp:715] Persisted action at 0
I1118 14:57:25.910892 32401 replica.cpp:700] Replica learned NOP action at position 0
I1118 14:57:25.911677 32401 log.cpp:677] Writer started with ending position 0
I1118 14:57:25.913002 32397 leveldb.cpp:438] Reading position from leveldb took 39474ns
I1118 14:57:25.914022 32391 registrar.cpp:342] Successfully fetched the registry (0B) in 11.405056ms
I1118 14:57:25.914140 32391 registrar.cpp:441] Applied 1 operations in 26380ns; attempting to update the 'registry'
I1118 14:57:25.914990 32394 log.cpp:685] Attempting to append 178 bytes to the log
I1118 14:57:25.915144 32389 coordinator.cpp:350] Coordinator attempting to write APPEND action at position 1
I1118 14:57:25.916051 32403 replica.cpp:540] Replica received write request for position 1 from (10582)@172.17.18.215:47157
I1118 14:57:25.916529 32403 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 432865ns
I1118 14:57:25.916560 32403 replica.cpp:715] Persisted action at 1
I1118 14:57:25.917306 32394 replica.cpp:694] Replica received learned notice for position 1 from @0.0.0.0:0
I1118 14:57:25.917783 32394 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 437520ns
I1118 14:57:25.917829 32394 replica.cpp:715] Persisted action at 1
I1118 14:57:25.917855 32394 replica.cpp:700] Replica learned APPEND action at position 1
I1118 14:57:25.919035 32402 registrar.cpp:486] Successfully updated the 'registry' in 4.804864ms
I1118 14:57:25.919241 32402 registrar.cpp:372] Successfully recovered registrar
I1118 14:57:25.919338 32404 log.cpp:704] Attempting to truncate the log to 1
I1118 14:57:25.919497 32392 coordinator.cpp:350] Coordinator attempting to write TRUNCATE action at position 2
I1118 14:57:25.919736 32391 master.cpp:1422] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1118 14:57:25.920508 32392 replica.cpp:540] Replica received write request for position 2 from (10583)@172.17.18.215:47157
I1118 14:57:25.921053 32392 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 503671ns
I1118 14:57:25.921085 32392 replica.cpp:715] Persisted action at 2
I1118 14:57:25.921880 32389 replica.cpp:694] Replica received learned notice for position 2 from @0.0.0.0:0
I1118 14:57:25.922329 32389 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 413918ns
I1118 14:57:25.922384 32389 leveldb.cpp:401] Deleting ~1 keys from leveldb took 28209ns
I1118 14:57:25.922404 32389 replica.cpp:715] Persisted action at 2
I1118 14:57:25.922422 32389 replica.cpp:700] Replica learned TRUNCATE action at position 2
I1118 14:57:25.930546 32370 scheduler.cpp:156] Version: 0.26.0
I1118 14:57:25.931267 32396 scheduler.cpp:238] New master detected at master@172.17.18.215:47157
I1118 14:57:25.932683 32396 scheduler.cpp:300] Sending SUBSCRIBE call to master@172.17.18.215:47157
I1118 14:57:25.934837 32389 process.cpp:3155] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1118 14:57:25.934917 32389 http.cpp:338] HTTP POST for /master/api/v1/scheduler from 172.17.18.215:42072
I1118 14:57:25.935349 32389 master.cpp:1874] Received subscription request for HTTP framework 'default'
I1118 14:57:25.935427 32389 master.cpp:1651] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1118 14:57:25.935747 32389 master.cpp:1966] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1118 14:57:25.936202 32400 hierarchical.cpp:197] Added framework 1a73f83b-0ede-4040-94e2-128ab6639958-0000
I1118 14:57:25.936255 32400 hierarchical.cpp:979] No resources available to allocate!
I1118 14:57:25.936290 32400 hierarchical.cpp:1072] No inverse offers to send out!
I1118 14:57:25.936313 32400 hierarchical.cpp:878] Performed allocation for 0 slaves in 83378ns
I1118 14:57:25.936398 32395 master.hpp:1522] Sending heartbeat to 1a73f83b-0ede-4040-94e2-128ab6639958-0000
I1118 14:57:25.937189 32389 scheduler.cpp:459] Enqueuing event SUBSCRIBED received from master@172.17.18.215:47157
I1118 14:57:25.937767 32389 scheduler.cpp:459] Enqueuing event HEARTBEAT received from master@172.17.18.215:47157
I1118 14:57:25.938503 32401 master_maintenance_tests.cpp:174] Ignoring HEARTBEAT event
I1118 14:57:25.938601 32389 scheduler.cpp:300] Sending REQUEST call to master@172.17.18.215:47157
I1118 14:57:25.940366 32395 process.cpp:3155] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1118 14:57:25.940436 32395 http.cpp:338] HTTP POST for /master/api/v1/scheduler from 172.17.18.215:42073
I1118 14:57:25.940726 32395 master.cpp:2621] Processing REQUEST call for framework 1a73f83b-0ede-4040-94e2-128ab6639958-0000 (default)
I1118 14:57:25.940927 32400 hierarchical.cpp:460] Received resource request from framework 1a73f83b-0ede-4040-94e2-128ab6639958-0000
I1118 14:57:25.941350 32396 master.cpp:928] Master terminating
I1118 14:57:25.941558 32404 hierarchical.cpp:232] Removed framework 1a73f83b-0ede-4040-94e2-128ab6639958-0000
E1118 14:57:25.942582 32389 scheduler.cpp:433] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (59 ms)
[----------] 22 tests from ContentType/SchedulerTest (4220 ms total)
[----------] Global test environment tear-down
[==========] 837 tests from 113 test cases ran. (337266 ms total)
[ PASSED ] 836 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] RegistryClientTest.SimpleGetManifest
1 FAILED TEST
YOU HAVE 7 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.26.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1447857102-16033
Untagged: mesos-1447857102-16033:latest
Deleted: 143e1e46c34a50f83137f99b6bcee468eea68b0700115149702fad3a4d211128
Deleted: 41133780fb6ab9fb83a6b98b6cf0eb075ccc6db57e5a880415610df5f833b892
Deleted: 271e1701e5f82615f91d9f7f05e50aa52971e7e68d1e8163d318d2cdf09657eb
Deleted: 6604bb49dd3636b72953f40dd1849a4cdd972d8b4cff692d02849bb6dec36381
Deleted: ee910cc28ec80355e6033db35866f34c96b16c738312c05d6579797b09a1dd4e
Deleted: 785a2eedb179f820e9788ad18e1c0f9049bf617dd41767bbbb3911f475c24521
Deleted: f7ca80ed3e34c779adaf0c690a59cabcf5cab9605ecb3606db258623d1899a16
Deleted: a71732d1dd890af80f1db308c7629662ff4e0550a6bfc9b61fc7c49f131050a1
Deleted: c1502d8f0f4199ad076397c3be81353191b94a801af2daf6d29783c2d06b8498
Deleted: 7b4d9c18cf4a337005b07fab005aea048c9d7af82f55df8ca929db000f223bd3
Deleted: 7f54fb69c3c77511e2089529e7e4ebef788061d530587e69f2fb0fdb5fa533a6
Deleted: 9fc70f1937be764668d81808c883a4b00e54d5ac2acb59e09a3194174addec2f
Build step 'Execute shell' marked build as failure
Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1241
Posted by Vinod Kone <vi...@apache.org>.
re-opened https://issues.apache.org/jira/browse/MESOS-3807
On Wed, Nov 18, 2015 at 6:57 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1241/changes
> >
>
> Changes:
>
> [benjamin.mahler] Added const qualifiers to ProcessReference operators.
>
> [benjamin.mahler] Introduced an Authenticator interface and an
> AuthenticationRouter in
>
> ------------------------------------------
> [...truncated 139215 lines...]
> I1118 14:57:25.819588 32393 slave.cpp:2009] Asked to shut down framework
> 78264a18-9077-45f5-ac83-cbfe1d116912-0000 by @0.0.0.0:0
> I1118 14:57:25.819697 32393 slave.cpp:2034] Shutting down framework
> 78264a18-9077-45f5-ac83-cbfe1d116912-0000
> I1118 14:57:25.819778 32393 slave.cpp:3657] Cleaning up executor 'default'
> of framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000 at executor(132)@
> 172.17.18.215:47157
> I1118 14:57:25.820080 32390 gc.cpp:56] Scheduling
> '/tmp/ContentType_SchedulerTest_Message_1_fCS0c8/slaves/78264a18-9077-45f5-ac83-cbfe1d116912-S0/frameworks/78264a18-9077-45f5-ac83-cbfe1d116912-0000/executors/default/runs/6ab20a0e-ca01-4484-ae9d-9bb441d8e41b'
> for gc 6.99999050949037days in the future
> I1118 14:57:25.820340 32393 slave.cpp:3745] Cleaning up framework
> 78264a18-9077-45f5-ac83-cbfe1d116912-0000
> I1118 14:57:25.820395 32403 gc.cpp:56] Scheduling
> '/tmp/ContentType_SchedulerTest_Message_1_fCS0c8/slaves/78264a18-9077-45f5-ac83-cbfe1d116912-S0/frameworks/78264a18-9077-45f5-ac83-cbfe1d116912-0000/executors/default'
> for gc 6.99999050653926days in the future
> I1118 14:57:25.820508 32389 status_update_manager.cpp:284] Closing status
> update streams for framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000
> I1118 14:57:25.820569 32389 status_update_manager.cpp:530] Cleaning up
> status update stream for task 738e02f7-4a17-419a-81d1-532b2833bc2a of
> framework 78264a18-9077-45f5-ac83-cbfe1d116912-0000
> I1118 14:57:25.820667 32397 gc.cpp:56] Scheduling
> '/tmp/ContentType_SchedulerTest_Message_1_fCS0c8/slaves/78264a18-9077-45f5-ac83-cbfe1d116912-S0/frameworks/78264a18-9077-45f5-ac83-cbfe1d116912-0000'
> for gc 6.99999050328593days in the future
> [ OK ] ContentType/SchedulerTest.Message/1 (114 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> I1118 14:57:25.829965 32370 leveldb.cpp:176] Opened db in 3.862297ms
> I1118 14:57:25.831382 32370 leveldb.cpp:183] Compacted db in 1.375064ms
> I1118 14:57:25.831449 32370 leveldb.cpp:198] Created db iterator in 19287ns
> I1118 14:57:25.831470 32370 leveldb.cpp:204] Seeked to beginning of db in
> 2270ns
> I1118 14:57:25.831480 32370 leveldb.cpp:273] Iterated through 0 keys in
> the db in 335ns
> I1118 14:57:25.831539 32370 replica.cpp:780] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1118 14:57:25.832168 32401 recover.cpp:449] Starting replica recovery
> I1118 14:57:25.832445 32401 recover.cpp:475] Replica is in EMPTY status
> I1118 14:57:25.833976 32403 replica.cpp:676] Replica in EMPTY status
> received a broadcasted recover request from (10566)@172.17.18.215:47157
> I1118 14:57:25.834455 32394 recover.cpp:195] Received a recover response
> from a replica in EMPTY status
> I1118 14:57:25.835110 32396 recover.cpp:566] Updating replica status to
> STARTING
> I1118 14:57:25.835850 32403 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 557114ns
> I1118 14:57:25.835888 32403 replica.cpp:323] Persisted replica status to
> STARTING
> I1118 14:57:25.836108 32393 recover.cpp:475] Replica is in STARTING status
> I1118 14:57:25.836205 32395 master.cpp:367] Master
> 93e512e6-bdfe-430b-b302-a4fee73ca378 (6ff0eaa4e60a) started on
> 172.17.18.215:47157
> I1118 14:57:25.836235 32395 master.cpp:369] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/9cN8al/credentials" --framework_sorter="drf"
> --help="false" --hostname_lookup="true" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --max_slave_ping_timeouts="5" --quiet="false"
> --recovery_slave_removal_limit="100%" --registry="replicated_log"
> --registry_fetch_timeout="1mins" --registry_store_timeout="25secs"
> --registry_strict="true" --root_submissions="true"
> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui"
> --work_dir="/tmp/9cN8al/master" --zk_session_timeout="10secs"
> I1118 14:57:25.836688 32395 master.cpp:416] Master allowing
> unauthenticated frameworks to register
> I1118 14:57:25.836702 32395 master.cpp:419] Master only allowing
> authenticated slaves to register
> I1118 14:57:25.836711 32395 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/9cN8al/credentials'
> I1118 14:57:25.837043 32395 master.cpp:458] Using default 'crammd5'
> authenticator
> I1118 14:57:25.837203 32395 master.cpp:495] Authorization enabled
> I1118 14:57:25.837332 32394 replica.cpp:676] Replica in STARTING status
> received a broadcasted recover request from (10567)@172.17.18.215:47157
> I1118 14:57:25.837626 32402 hierarchical.cpp:153] Initialized hierarchical
> allocator process
> I1118 14:57:25.837699 32402 whitelist_watcher.cpp:79] No whitelist given
> I1118 14:57:25.838245 32398 recover.cpp:195] Received a recover response
> from a replica in STARTING status
> I1118 14:57:25.838841 32404 recover.cpp:566] Updating replica status to
> VOTING
> I1118 14:57:25.839637 32401 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 510185ns
> I1118 14:57:25.839668 32401 replica.cpp:323] Persisted replica status to
> VOTING
> I1118 14:57:25.839787 32397 recover.cpp:580] Successfully joined the Paxos
> group
> I1118 14:57:25.840132 32397 recover.cpp:464] Recover process terminated
> I1118 14:57:25.840234 32396 master.cpp:1612] The newly elected leader is
> master@172.17.18.215:47157 with id 93e512e6-bdfe-430b-b302-a4fee73ca378
> I1118 14:57:25.840277 32396 master.cpp:1625] Elected as the leading master!
> I1118 14:57:25.840304 32396 master.cpp:1385] Recovering from registrar
> I1118 14:57:25.840503 32399 registrar.cpp:309] Recovering registrar
> I1118 14:57:25.841228 32404 log.cpp:661] Attempting to start the writer
> I1118 14:57:25.843453 32393 replica.cpp:496] Replica received implicit
> promise request from (10568)@172.17.18.215:47157 with proposal 1
> I1118 14:57:25.844174 32393 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 678591ns
> I1118 14:57:25.844208 32393 replica.cpp:345] Persisted promised to 1
> I1118 14:57:25.845754 32402 coordinator.cpp:240] Coordinator attempting to
> fill missing positions
> I1118 14:57:25.847532 32403 replica.cpp:391] Replica received explicit
> promise request from (10569)@172.17.18.215:47157 for position 0 with
> proposal 2
> I1118 14:57:25.848218 32403 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 632829ns
> I1118 14:57:25.848249 32403 replica.cpp:715] Persisted action at 0
> I1118 14:57:25.850400 32390 replica.cpp:540] Replica received write
> request for position 0 from (10570)@172.17.18.215:47157
> I1118 14:57:25.850487 32390 leveldb.cpp:438] Reading position from leveldb
> took 40797ns
> I1118 14:57:25.851188 32390 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 636982ns
> I1118 14:57:25.851238 32390 replica.cpp:715] Persisted action at 0
> I1118 14:57:25.852253 32402 replica.cpp:694] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1118 14:57:25.852844 32402 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 551574ns
> I1118 14:57:25.852882 32402 replica.cpp:715] Persisted action at 0
> I1118 14:57:25.852916 32402 replica.cpp:700] Replica learned NOP action at
> position 0
> I1118 14:57:25.853726 32403 log.cpp:677] Writer started with ending
> position 0
> I1118 14:57:25.855095 32393 leveldb.cpp:438] Reading position from leveldb
> took 39146ns
> I1118 14:57:25.856219 32403 registrar.cpp:342] Successfully fetched the
> registry (0B) in 15.664128ms
> I1118 14:57:25.856338 32403 registrar.cpp:441] Applied 1 operations in
> 28452ns; attempting to update the 'registry'
> I1118 14:57:25.857318 32389 log.cpp:685] Attempting to append 178 bytes to
> the log
> I1118 14:57:25.857499 32399 coordinator.cpp:350] Coordinator attempting to
> write APPEND action at position 1
> I1118 14:57:25.858671 32397 replica.cpp:540] Replica received write
> request for position 1 from (10571)@172.17.18.215:47157
> I1118 14:57:25.859285 32397 leveldb.cpp:343] Persisting action (197 bytes)
> to leveldb took 568742ns
> I1118 14:57:25.859323 32397 replica.cpp:715] Persisted action at 1
> I1118 14:57:25.860077 32397 replica.cpp:694] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1118 14:57:25.860714 32397 leveldb.cpp:343] Persisting action (199 bytes)
> to leveldb took 596758ns
> I1118 14:57:25.860748 32397 replica.cpp:715] Persisted action at 1
> I1118 14:57:25.860770 32397 replica.cpp:700] Replica learned APPEND action
> at position 1
> I1118 14:57:25.861984 32399 registrar.cpp:486] Successfully updated the
> 'registry' in 5.577984ms
> I1118 14:57:25.862151 32399 registrar.cpp:372] Successfully recovered
> registrar
> I1118 14:57:25.862236 32401 log.cpp:704] Attempting to truncate the log to
> 1
> I1118 14:57:25.862418 32395 coordinator.cpp:350] Coordinator attempting to
> write TRUNCATE action at position 2
> I1118 14:57:25.862639 32403 master.cpp:1422] Recovered 0 slaves from the
> Registry (139B) ; allowing 10mins for slaves to re-register
> I1118 14:57:25.863729 32396 replica.cpp:540] Replica received write
> request for position 2 from (10572)@172.17.18.215:47157
> I1118 14:57:25.864228 32396 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 452324ns
> I1118 14:57:25.864264 32396 replica.cpp:715] Persisted action at 2
> I1118 14:57:25.865064 32402 replica.cpp:694] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1118 14:57:25.865463 32402 leveldb.cpp:343] Persisting action (18 bytes)
> to leveldb took 358859ns
> I1118 14:57:25.865531 32402 leveldb.cpp:401] Deleting ~1 keys from leveldb
> took 34591ns
> I1118 14:57:25.865557 32402 replica.cpp:715] Persisted action at 2
> I1118 14:57:25.865581 32402 replica.cpp:700] Replica learned TRUNCATE
> action at position 2
> I1118 14:57:25.873694 32370 scheduler.cpp:156] Version: 0.26.0
> I1118 14:57:25.874574 32389 scheduler.cpp:238] New master detected at
> master@172.17.18.215:47157
> I1118 14:57:25.876019 32392 scheduler.cpp:300] Sending SUBSCRIBE call to
> master@172.17.18.215:47157
> I1118 14:57:25.877934 32399 process.cpp:3155] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1118 14:57:25.878000 32399 http.cpp:338] HTTP POST for
> /master/api/v1/scheduler from 172.17.18.215:42070
> I1118 14:57:25.878228 32399 master.cpp:1874] Received subscription request
> for HTTP framework 'default'
> I1118 14:57:25.878321 32399 master.cpp:1651] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1118 14:57:25.878875 32394 master.cpp:1966] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I1118 14:57:25.879290 32389 hierarchical.cpp:197] Added framework
> 93e512e6-bdfe-430b-b302-a4fee73ca378-0000
> I1118 14:57:25.879345 32389 hierarchical.cpp:979] No resources available
> to allocate!
> I1118 14:57:25.879358 32392 master.hpp:1522] Sending heartbeat to
> 93e512e6-bdfe-430b-b302-a4fee73ca378-0000
> I1118 14:57:25.879374 32389 hierarchical.cpp:1072] No inverse offers to
> send out!
> I1118 14:57:25.879397 32389 hierarchical.cpp:878] Performed allocation for
> 0 slaves in 79401ns
> I1118 14:57:25.880064 32390 scheduler.cpp:459] Enqueuing event SUBSCRIBED
> received from master@172.17.18.215:47157
> I1118 14:57:25.880630 32390 scheduler.cpp:459] Enqueuing event HEARTBEAT
> received from master@172.17.18.215:47157
> I1118 14:57:25.881371 32389 master_maintenance_tests.cpp:174] Ignoring
> HEARTBEAT event
> I1118 14:57:25.881470 32390 scheduler.cpp:300] Sending REQUEST call to
> master@172.17.18.215:47157
> I1118 14:57:25.883266 32403 process.cpp:3155] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1118 14:57:25.883318 32403 http.cpp:338] HTTP POST for
> /master/api/v1/scheduler from 172.17.18.215:42071
> I1118 14:57:25.883424 32403 master.cpp:2621] Processing REQUEST call for
> framework 93e512e6-bdfe-430b-b302-a4fee73ca378-0000 (default)
> I1118 14:57:25.883587 32396 hierarchical.cpp:460] Received resource
> request from framework 93e512e6-bdfe-430b-b302-a4fee73ca378-0000
> I1118 14:57:25.884018 32397 master.cpp:928] Master terminating
> I1118 14:57:25.884222 32403 hierarchical.cpp:232] Removed framework
> 93e512e6-bdfe-430b-b302-a4fee73ca378-0000
> E1118 14:57:25.885417 32391 scheduler.cpp:433] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/0 (65 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> I1118 14:57:25.894351 32370 leveldb.cpp:176] Opened db in 3.21735ms
> I1118 14:57:25.895536 32370 leveldb.cpp:183] Compacted db in 1.144274ms
> I1118 14:57:25.895596 32370 leveldb.cpp:198] Created db iterator in 17127ns
> I1118 14:57:25.895615 32370 leveldb.cpp:204] Seeked to beginning of db in
> 1722ns
> I1118 14:57:25.895627 32370 leveldb.cpp:273] Iterated through 0 keys in
> the db in 257ns
> I1118 14:57:25.895663 32370 replica.cpp:780] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1118 14:57:25.896224 32400 recover.cpp:449] Starting replica recovery
> I1118 14:57:25.896478 32390 recover.cpp:475] Replica is in EMPTY status
> I1118 14:57:25.897496 32390 replica.cpp:676] Replica in EMPTY status
> received a broadcasted recover request from (10577)@172.17.18.215:47157
> I1118 14:57:25.897860 32400 recover.cpp:195] Received a recover response
> from a replica in EMPTY status
> I1118 14:57:25.898435 32402 recover.cpp:566] Updating replica status to
> STARTING
> I1118 14:57:25.899166 32403 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 524135ns
> I1118 14:57:25.899196 32403 replica.cpp:323] Persisted replica status to
> STARTING
> I1118 14:57:25.899415 32395 recover.cpp:475] Replica is in STARTING status
> I1118 14:57:25.899914 32403 master.cpp:367] Master
> 1a73f83b-0ede-4040-94e2-128ab6639958 (6ff0eaa4e60a) started on
> 172.17.18.215:47157
> I1118 14:57:25.899940 32403 master.cpp:369] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/j9KsrK/credentials" --framework_sorter="drf"
> --help="false" --hostname_lookup="true" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --max_slave_ping_timeouts="5" --quiet="false"
> --recovery_slave_removal_limit="100%" --registry="replicated_log"
> --registry_fetch_timeout="1mins" --registry_store_timeout="25secs"
> --registry_strict="true" --root_submissions="true"
> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/mesos/mesos-0.26.0/_inst/share/mesos/webui"
> --work_dir="/tmp/j9KsrK/master" --zk_session_timeout="10secs"
> I1118 14:57:25.900171 32403 master.cpp:416] Master allowing
> unauthenticated frameworks to register
> I1118 14:57:25.900187 32403 master.cpp:419] Master only allowing
> authenticated slaves to register
> I1118 14:57:25.900198 32403 credentials.hpp:37] Loading credentials for
> authentication from '/tmp/j9KsrK/credentials'
> I1118 14:57:25.900287 32404 replica.cpp:676] Replica in STARTING status
> received a broadcasted recover request from (10578)@172.17.18.215:47157
> I1118 14:57:25.900449 32403 master.cpp:458] Using default 'crammd5'
> authenticator
> I1118 14:57:25.900566 32403 master.cpp:495] Authorization enabled
> I1118 14:57:25.900589 32393 recover.cpp:195] Received a recover response
> from a replica in STARTING status
> I1118 14:57:25.900853 32399 hierarchical.cpp:153] Initialized hierarchical
> allocator process
> I1118 14:57:25.900869 32397 whitelist_watcher.cpp:79] No whitelist given
> I1118 14:57:25.901037 32391 recover.cpp:566] Updating replica status to
> VOTING
> I1118 14:57:25.901593 32389 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 373738ns
> I1118 14:57:25.901618 32389 replica.cpp:323] Persisted replica status to
> VOTING
> I1118 14:57:25.901749 32395 recover.cpp:580] Successfully joined the Paxos
> group
> I1118 14:57:25.901957 32395 recover.cpp:464] Recover process terminated
> I1118 14:57:25.902403 32395 master.cpp:1612] The newly elected leader is
> master@172.17.18.215:47157 with id 1a73f83b-0ede-4040-94e2-128ab6639958
> I1118 14:57:25.902431 32395 master.cpp:1625] Elected as the leading master!
> I1118 14:57:25.902451 32395 master.cpp:1385] Recovering from registrar
> I1118 14:57:25.902565 32398 registrar.cpp:309] Recovering registrar
> I1118 14:57:25.903091 32390 log.cpp:661] Attempting to start the writer
> I1118 14:57:25.904078 32399 replica.cpp:496] Replica received implicit
> promise request from (10579)@172.17.18.215:47157 with proposal 1
> I1118 14:57:25.904649 32399 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 538280ns
> I1118 14:57:25.904681 32399 replica.cpp:345] Persisted promised to 1
> I1118 14:57:25.905484 32395 coordinator.cpp:240] Coordinator attempting to
> fill missing positions
> I1118 14:57:25.907127 32395 replica.cpp:391] Replica received explicit
> promise request from (10580)@172.17.18.215:47157 for position 0 with
> proposal 2
> I1118 14:57:25.907654 32395 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 426015ns
> I1118 14:57:25.907685 32395 replica.cpp:715] Persisted action at 0
> I1118 14:57:25.908995 32400 replica.cpp:540] Replica received write
> request for position 0 from (10581)@172.17.18.215:47157
> I1118 14:57:25.909065 32400 leveldb.cpp:438] Reading position from leveldb
> took 29152ns
> I1118 14:57:25.909543 32400 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 429960ns
> I1118 14:57:25.909574 32400 replica.cpp:715] Persisted action at 0
> I1118 14:57:25.910322 32401 replica.cpp:694] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1118 14:57:25.910780 32401 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 416717ns
> I1118 14:57:25.910852 32401 replica.cpp:715] Persisted action at 0
> I1118 14:57:25.910892 32401 replica.cpp:700] Replica learned NOP action at
> position 0
> I1118 14:57:25.911677 32401 log.cpp:677] Writer started with ending
> position 0
> I1118 14:57:25.913002 32397 leveldb.cpp:438] Reading position from leveldb
> took 39474ns
> I1118 14:57:25.914022 32391 registrar.cpp:342] Successfully fetched the
> registry (0B) in 11.405056ms
> I1118 14:57:25.914140 32391 registrar.cpp:441] Applied 1 operations in
> 26380ns; attempting to update the 'registry'
> I1118 14:57:25.914990 32394 log.cpp:685] Attempting to append 178 bytes to
> the log
> I1118 14:57:25.915144 32389 coordinator.cpp:350] Coordinator attempting to
> write APPEND action at position 1
> I1118 14:57:25.916051 32403 replica.cpp:540] Replica received write
> request for position 1 from (10582)@172.17.18.215:47157
> I1118 14:57:25.916529 32403 leveldb.cpp:343] Persisting action (197 bytes)
> to leveldb took 432865ns
> I1118 14:57:25.916560 32403 replica.cpp:715] Persisted action at 1
> I1118 14:57:25.917306 32394 replica.cpp:694] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1118 14:57:25.917783 32394 leveldb.cpp:343] Persisting action (199 bytes)
> to leveldb took 437520ns
> I1118 14:57:25.917829 32394 replica.cpp:715] Persisted action at 1
> I1118 14:57:25.917855 32394 replica.cpp:700] Replica learned APPEND action
> at position 1
> I1118 14:57:25.919035 32402 registrar.cpp:486] Successfully updated the
> 'registry' in 4.804864ms
> I1118 14:57:25.919241 32402 registrar.cpp:372] Successfully recovered
> registrar
> I1118 14:57:25.919338 32404 log.cpp:704] Attempting to truncate the log to
> 1
> I1118 14:57:25.919497 32392 coordinator.cpp:350] Coordinator attempting to
> write TRUNCATE action at position 2
> I1118 14:57:25.919736 32391 master.cpp:1422] Recovered 0 slaves from the
> Registry (139B) ; allowing 10mins for slaves to re-register
> I1118 14:57:25.920508 32392 replica.cpp:540] Replica received write
> request for position 2 from (10583)@172.17.18.215:47157
> I1118 14:57:25.921053 32392 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 503671ns
> I1118 14:57:25.921085 32392 replica.cpp:715] Persisted action at 2
> I1118 14:57:25.921880 32389 replica.cpp:694] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1118 14:57:25.922329 32389 leveldb.cpp:343] Persisting action (18 bytes)
> to leveldb took 413918ns
> I1118 14:57:25.922384 32389 leveldb.cpp:401] Deleting ~1 keys from leveldb
> took 28209ns
> I1118 14:57:25.922404 32389 replica.cpp:715] Persisted action at 2
> I1118 14:57:25.922422 32389 replica.cpp:700] Replica learned TRUNCATE
> action at position 2
> I1118 14:57:25.930546 32370 scheduler.cpp:156] Version: 0.26.0
> I1118 14:57:25.931267 32396 scheduler.cpp:238] New master detected at
> master@172.17.18.215:47157
> I1118 14:57:25.932683 32396 scheduler.cpp:300] Sending SUBSCRIBE call to
> master@172.17.18.215:47157
> I1118 14:57:25.934837 32389 process.cpp:3155] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1118 14:57:25.934917 32389 http.cpp:338] HTTP POST for
> /master/api/v1/scheduler from 172.17.18.215:42072
> I1118 14:57:25.935349 32389 master.cpp:1874] Received subscription request
> for HTTP framework 'default'
> I1118 14:57:25.935427 32389 master.cpp:1651] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1118 14:57:25.935747 32389 master.cpp:1966] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I1118 14:57:25.936202 32400 hierarchical.cpp:197] Added framework
> 1a73f83b-0ede-4040-94e2-128ab6639958-0000
> I1118 14:57:25.936255 32400 hierarchical.cpp:979] No resources available
> to allocate!
> I1118 14:57:25.936290 32400 hierarchical.cpp:1072] No inverse offers to
> send out!
> I1118 14:57:25.936313 32400 hierarchical.cpp:878] Performed allocation for
> 0 slaves in 83378ns
> I1118 14:57:25.936398 32395 master.hpp:1522] Sending heartbeat to
> 1a73f83b-0ede-4040-94e2-128ab6639958-0000
> I1118 14:57:25.937189 32389 scheduler.cpp:459] Enqueuing event SUBSCRIBED
> received from master@172.17.18.215:47157
> I1118 14:57:25.937767 32389 scheduler.cpp:459] Enqueuing event HEARTBEAT
> received from master@172.17.18.215:47157
> I1118 14:57:25.938503 32401 master_maintenance_tests.cpp:174] Ignoring
> HEARTBEAT event
> I1118 14:57:25.938601 32389 scheduler.cpp:300] Sending REQUEST call to
> master@172.17.18.215:47157
> I1118 14:57:25.940366 32395 process.cpp:3155] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1118 14:57:25.940436 32395 http.cpp:338] HTTP POST for
> /master/api/v1/scheduler from 172.17.18.215:42073
> I1118 14:57:25.940726 32395 master.cpp:2621] Processing REQUEST call for
> framework 1a73f83b-0ede-4040-94e2-128ab6639958-0000 (default)
> I1118 14:57:25.940927 32400 hierarchical.cpp:460] Received resource
> request from framework 1a73f83b-0ede-4040-94e2-128ab6639958-0000
> I1118 14:57:25.941350 32396 master.cpp:928] Master terminating
> I1118 14:57:25.941558 32404 hierarchical.cpp:232] Removed framework
> 1a73f83b-0ede-4040-94e2-128ab6639958-0000
> E1118 14:57:25.942582 32389 scheduler.cpp:433] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (59 ms)
> [----------] 22 tests from ContentType/SchedulerTest (4220 ms total)
>
> [----------] Global test environment tear-down
> [==========] 837 tests from 113 test cases ran. (337266 ms total)
> [ PASSED ] 836 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] RegistryClientTest.SimpleGetManifest
>
> 1 FAILED TEST
> YOU HAVE 7 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.26.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.26.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1447857102-16033
> Untagged: mesos-1447857102-16033:latest
> Deleted: 143e1e46c34a50f83137f99b6bcee468eea68b0700115149702fad3a4d211128
> Deleted: 41133780fb6ab9fb83a6b98b6cf0eb075ccc6db57e5a880415610df5f833b892
> Deleted: 271e1701e5f82615f91d9f7f05e50aa52971e7e68d1e8163d318d2cdf09657eb
> Deleted: 6604bb49dd3636b72953f40dd1849a4cdd972d8b4cff692d02849bb6dec36381
> Deleted: ee910cc28ec80355e6033db35866f34c96b16c738312c05d6579797b09a1dd4e
> Deleted: 785a2eedb179f820e9788ad18e1c0f9049bf617dd41767bbbb3911f475c24521
> Deleted: f7ca80ed3e34c779adaf0c690a59cabcf5cab9605ecb3606db258623d1899a16
> Deleted: a71732d1dd890af80f1db308c7629662ff4e0550a6bfc9b61fc7c49f131050a1
> Deleted: c1502d8f0f4199ad076397c3be81353191b94a801af2daf6d29783c2d06b8498
> Deleted: 7b4d9c18cf4a337005b07fab005aea048c9d7af82f55df8ca929db000f223bd3
> Deleted: 7f54fb69c3c77511e2089529e7e4ebef788061d530587e69f2fb0fdb5fa533a6
> Deleted: 9fc70f1937be764668d81808c883a4b00e54d5ac2acb59e09a3194174addec2f
> Build step 'Execute shell' marked build as failure
>
Jenkins build is back to normal : Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1242
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1242/changes>