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/09/01 03:33:07 UTC
Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #755
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/755/changes>
Changes:
[benjamin.mahler] Fixed a non-plural variable name in perf.hpp.
------------------------------------------
[...truncated 121858 lines...]
I0901 01:34:13.515564 30509 hierarchical.hpp:428] Removed framework 20150901-013413-1996493228-35897-30488-0000
E0901 01:34:13.516106 30509 scheduler.cpp:435] End-Of-File received from master. The master closed the event stream
I0901 01:34:13.516243 30515 slave.cpp:3143] master@172.17.0.119:35897 exited
W0901 01:34:13.516481 30515 slave.cpp:3146] Master disconnected! Waiting for a new master to be elected
I0901 01:34:13.519942 30508 slave.cpp:3399] Executor 'default' of framework 20150901-013413-1996493228-35897-30488-0000 exited with status 0
I0901 01:34:13.521723 30508 slave.cpp:2696] Handling status update TASK_LOST (UUID: 80a7ec7c-4558-4c42-8a68-9b282052b4a5) for task d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c of framework 20150901-013413-1996493228-35897-30488-0000 from @0.0.0.0:0
I0901 01:34:13.521848 30508 slave.cpp:5094] Terminating task d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c
I0901 01:34:13.522336 30508 slave.cpp:564] Slave terminating
I0901 01:34:13.522413 30508 slave.cpp:1959] Asked to shut down framework 20150901-013413-1996493228-35897-30488-0000 by @0.0.0.0:0
I0901 01:34:13.522534 30508 slave.cpp:1984] Shutting down framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.522689 30508 slave.cpp:3503] Cleaning up executor 'default' of framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.523030 30509 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000/executors/default/runs/fd926952-f7cf-40c8-be62-bc34289b89cf' for gc 6.99999394757037days in the future
I0901 01:34:13.523133 30508 slave.cpp:3592] Cleaning up framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.523282 30509 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000/executors/default' for gc 6.99999394579852days in the future
I0901 01:34:13.523396 30518 status_update_manager.cpp:284] Closing status update streams for framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.524374 30518 status_update_manager.cpp:530] Cleaning up status update stream for task d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c of framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.523474 30509 gc.cpp:56] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000' for gc 6.99999394276741days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (125 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
Using temporary directory '/tmp/ContentType_SchedulerTest_Request_0_vR0XnP'
I0901 01:34:13.531173 30488 leveldb.cpp:176] Opened db in 2.561692ms
I0901 01:34:13.532003 30488 leveldb.cpp:183] Compacted db in 783277ns
I0901 01:34:13.532119 30488 leveldb.cpp:198] Created db iterator in 22761ns
I0901 01:34:13.532143 30488 leveldb.cpp:204] Seeked to beginning of db in 2055ns
I0901 01:34:13.532160 30488 leveldb.cpp:273] Iterated through 0 keys in the db in 472ns
I0901 01:34:13.532212 30488 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0901 01:34:13.532742 30511 recover.cpp:449] Starting replica recovery
I0901 01:34:13.532968 30511 recover.cpp:475] Replica is in EMPTY status
I0901 01:34:13.534502 30508 replica.cpp:641] Replica in EMPTY status received a broadcasted recover request
I0901 01:34:13.535284 30508 recover.cpp:195] Received a recover response from a replica in EMPTY status
I0901 01:34:13.537603 30516 recover.cpp:566] Updating replica status to STARTING
I0901 01:34:13.538120 30518 master.cpp:378] Master 20150901-013413-1996493228-35897-30488 (998e845ced4a) started on 172.17.0.119:35897
I0901 01:34:13.538146 30518 master.cpp:380] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/credentials" --framework_sorter="drf" --help="false" --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.25.0/_inst/share/mesos/webui" --work_dir="/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/master" --zk_session_timeout="10secs"
I0901 01:34:13.538532 30518 master.cpp:427] Master allowing unauthenticated frameworks to register
I0901 01:34:13.538547 30518 master.cpp:430] Master only allowing authenticated slaves to register
I0901 01:34:13.538558 30518 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/credentials'
I0901 01:34:13.538666 30522 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 568647ns
I0901 01:34:13.538703 30522 replica.cpp:323] Persisted replica status to STARTING
I0901 01:34:13.538883 30518 master.cpp:469] Using default 'crammd5' authenticator
I0901 01:34:13.538979 30510 recover.cpp:475] Replica is in STARTING status
I0901 01:34:13.539052 30518 master.cpp:506] Authorization enabled
I0901 01:34:13.540369 30508 replica.cpp:641] Replica in STARTING status received a broadcasted recover request
I0901 01:34:13.540448 30510 hierarchical.hpp:346] Initialized hierarchical allocator process
I0901 01:34:13.540478 30522 whitelist_watcher.cpp:79] No whitelist given
I0901 01:34:13.541385 30508 recover.cpp:195] Received a recover response from a replica in STARTING status
I0901 01:34:13.542026 30522 recover.cpp:566] Updating replica status to VOTING
I0901 01:34:13.542232 30518 master.cpp:1559] The newly elected leader is master@172.17.0.119:35897 with id 20150901-013413-1996493228-35897-30488
I0901 01:34:13.542335 30518 master.cpp:1572] Elected as the leading master!
I0901 01:34:13.542466 30518 master.cpp:1332] Recovering from registrar
I0901 01:34:13.542826 30518 registrar.cpp:311] Recovering registrar
I0901 01:34:13.543068 30508 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 365312ns
I0901 01:34:13.543367 30508 replica.cpp:323] Persisted replica status to VOTING
I0901 01:34:13.543473 30522 recover.cpp:580] Successfully joined the Paxos group
I0901 01:34:13.543685 30522 recover.cpp:464] Recover process terminated
I0901 01:34:13.544334 30516 log.cpp:661] Attempting to start the writer
I0901 01:34:13.545722 30510 replica.cpp:477] Replica received implicit promise request with proposal 1
I0901 01:34:13.546131 30510 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 382715ns
I0901 01:34:13.546164 30510 replica.cpp:345] Persisted promised to 1
I0901 01:34:13.547056 30510 coordinator.cpp:231] Coordinator attemping to fill missing position
I0901 01:34:13.548393 30517 replica.cpp:378] Replica received explicit promise request for position 0 with proposal 2
I0901 01:34:13.549365 30517 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 508185ns
I0901 01:34:13.549592 30517 replica.cpp:679] Persisted action at 0
I0901 01:34:13.553926 30522 replica.cpp:511] Replica received write request for position 0
I0901 01:34:13.554023 30522 leveldb.cpp:438] Reading position from leveldb took 34026ns
I0901 01:34:13.554674 30522 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 523847ns
I0901 01:34:13.554711 30522 replica.cpp:679] Persisted action at 0
I0901 01:34:13.555552 30522 replica.cpp:658] Replica received learned notice for position 0
I0901 01:34:13.555915 30522 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 333834ns
I0901 01:34:13.555944 30522 replica.cpp:679] Persisted action at 0
I0901 01:34:13.555968 30522 replica.cpp:664] Replica learned NOP action at position 0
I0901 01:34:13.557031 30522 log.cpp:677] Writer started with ending position 0
I0901 01:34:13.558575 30522 leveldb.cpp:438] Reading position from leveldb took 35255ns
I0901 01:34:13.562242 30522 registrar.cpp:344] Successfully fetched the registry (0B) in 19.28192ms
I0901 01:34:13.562387 30522 registrar.cpp:443] Applied 1 operations in 33477ns; attempting to update the 'registry'
I0901 01:34:13.565824 30510 log.cpp:685] Attempting to append 178 bytes to the log
I0901 01:34:13.566048 30513 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 1
I0901 01:34:13.566949 30512 replica.cpp:511] Replica received write request for position 1
I0901 01:34:13.567483 30512 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 492374ns
I0901 01:34:13.567523 30512 replica.cpp:679] Persisted action at 1
I0901 01:34:13.568199 30512 replica.cpp:658] Replica received learned notice for position 1
I0901 01:34:13.568665 30512 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 343360ns
I0901 01:34:13.568699 30512 replica.cpp:679] Persisted action at 1
I0901 01:34:13.568729 30512 replica.cpp:664] Replica learned APPEND action at position 1
I0901 01:34:13.569790 30520 registrar.cpp:488] Successfully updated the 'registry' in 7.33184ms
I0901 01:34:13.569968 30520 registrar.cpp:374] Successfully recovered registrar
I0901 01:34:13.570150 30515 log.cpp:704] Attempting to truncate the log to 1
I0901 01:34:13.570272 30520 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
I0901 01:34:13.570386 30507 master.cpp:1369] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I0901 01:34:13.571435 30510 replica.cpp:511] Replica received write request for position 2
I0901 01:34:13.571858 30510 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 371692ns
I0901 01:34:13.571985 30510 replica.cpp:679] Persisted action at 2
I0901 01:34:13.573128 30507 replica.cpp:658] Replica received learned notice for position 2
I0901 01:34:13.573748 30507 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 399399ns
I0901 01:34:13.573860 30507 leveldb.cpp:401] Deleting ~1 keys from leveldb took 80758ns
I0901 01:34:13.573890 30507 replica.cpp:679] Persisted action at 2
I0901 01:34:13.573920 30507 replica.cpp:664] Replica learned TRUNCATE action at position 2
I0901 01:34:13.581409 30488 scheduler.cpp:157] Version: 0.25.0
I0901 01:34:13.584280 30520 scheduler.cpp:240] New master detected at master@172.17.0.119:35897
I0901 01:34:13.586750 30522 scheduler.cpp:302] Sending SUBSCRIBE call to master@172.17.0.119:35897
I0901 01:34:13.588770 30510 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 01:34:13.588932 30510 http.cpp:333] HTTP POST for /master/api/v1/scheduler from 172.17.0.119:38028
I0901 01:34:13.589198 30510 master.cpp:1817] Received subscription request for HTTP framework 'default'
I0901 01:34:13.589267 30510 master.cpp:1598] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0901 01:34:13.589565 30514 master.cpp:1909] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0901 01:34:13.590019 30513 hierarchical.hpp:391] Added framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.590059 30513 hierarchical.hpp:1010] No resources available to allocate!
I0901 01:34:13.590093 30513 hierarchical.hpp:910] Performed allocation for 0 slaves in 30381ns
I0901 01:34:13.590368 30507 master.hpp:1339] Sending heartbeat to 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.591143 30514 scheduler.cpp:461] Enqueuing event SUBSCRIBED received from master@172.17.0.119:35897
I0901 01:34:13.592531 30509 scheduler.cpp:302] Sending REQUEST call to master@172.17.0.119:35897
I0901 01:34:13.593093 30509 scheduler.cpp:461] Enqueuing event HEARTBEAT received from master@172.17.0.119:35897
I0901 01:34:13.593616 30515 scheduler_tests.cpp:113] Ignoring HEARTBEAT event
I0901 01:34:13.598319 30517 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 01:34:13.598618 30517 http.cpp:333] HTTP POST for /master/api/v1/scheduler from 172.17.0.119:38029
I0901 01:34:13.598848 30517 master.cpp:2520] Processing REQUEST call for framework 20150901-013413-1996493228-35897-30488-0000 (default)
I0901 01:34:13.599093 30508 hierarchical.hpp:668] Received resource request from framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.599490 30515 master.cpp:884] Master terminating
I0901 01:34:13.600097 30512 hierarchical.hpp:428] Removed framework 20150901-013413-1996493228-35897-30488-0000
E0901 01:34:13.602247 30520 scheduler.cpp:435] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (80 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
Using temporary directory '/tmp/ContentType_SchedulerTest_Request_1_ccrAwf'
I0901 01:34:13.612321 30488 leveldb.cpp:176] Opened db in 2.732436ms
I0901 01:34:13.613260 30488 leveldb.cpp:183] Compacted db in 757637ns
I0901 01:34:13.613317 30488 leveldb.cpp:198] Created db iterator in 22964ns
I0901 01:34:13.613451 30488 leveldb.cpp:204] Seeked to beginning of db in 2606ns
I0901 01:34:13.613534 30488 leveldb.cpp:273] Iterated through 0 keys in the db in 548ns
I0901 01:34:13.613595 30488 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0901 01:34:13.614243 30514 recover.cpp:449] Starting replica recovery
I0901 01:34:13.614574 30514 recover.cpp:475] Replica is in EMPTY status
I0901 01:34:13.615953 30515 replica.cpp:641] Replica in EMPTY status received a broadcasted recover request
I0901 01:34:13.616627 30514 recover.cpp:195] Received a recover response from a replica in EMPTY status
I0901 01:34:13.617187 30512 master.cpp:378] Master 20150901-013413-1996493228-35897-30488 (998e845ced4a) started on 172.17.0.119:35897
I0901 01:34:13.617529 30519 recover.cpp:566] Updating replica status to STARTING
I0901 01:34:13.617300 30512 master.cpp:380] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/credentials" --framework_sorter="drf" --help="false" --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.25.0/_inst/share/mesos/webui" --work_dir="/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/master" --zk_session_timeout="10secs"
I0901 01:34:13.617902 30512 master.cpp:427] Master allowing unauthenticated frameworks to register
I0901 01:34:13.617919 30512 master.cpp:430] Master only allowing authenticated slaves to register
I0901 01:34:13.617931 30512 credentials.hpp:37] Loading credentials for authentication from '/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/credentials'
I0901 01:34:13.618306 30512 master.cpp:469] Using default 'crammd5' authenticator
I0901 01:34:13.618398 30514 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 600368ns
I0901 01:34:13.618505 30512 master.cpp:506] Authorization enabled
I0901 01:34:13.619098 30519 hierarchical.hpp:346] Initialized hierarchical allocator process
I0901 01:34:13.619163 30519 whitelist_watcher.cpp:79] No whitelist given
I0901 01:34:13.618569 30514 replica.cpp:323] Persisted replica status to STARTING
I0901 01:34:13.620694 30521 master.cpp:1559] The newly elected leader is master@172.17.0.119:35897 with id 20150901-013413-1996493228-35897-30488
I0901 01:34:13.620810 30521 master.cpp:1572] Elected as the leading master!
I0901 01:34:13.620961 30521 master.cpp:1332] Recovering from registrar
I0901 01:34:13.621230 30519 registrar.cpp:311] Recovering registrar
I0901 01:34:13.621961 30514 recover.cpp:475] Replica is in STARTING status
I0901 01:34:13.622889 30517 replica.cpp:641] Replica in STARTING status received a broadcasted recover request
I0901 01:34:13.623294 30515 recover.cpp:195] Received a recover response from a replica in STARTING status
I0901 01:34:13.623807 30512 recover.cpp:566] Updating replica status to VOTING
I0901 01:34:13.624295 30515 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 327184ns
I0901 01:34:13.624326 30515 replica.cpp:323] Persisted replica status to VOTING
I0901 01:34:13.624436 30514 recover.cpp:580] Successfully joined the Paxos group
I0901 01:34:13.624774 30514 recover.cpp:464] Recover process terminated
I0901 01:34:13.625172 30508 log.cpp:661] Attempting to start the writer
I0901 01:34:13.626436 30517 replica.cpp:477] Replica received implicit promise request with proposal 1
I0901 01:34:13.626927 30517 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 298893ns
I0901 01:34:13.626951 30517 replica.cpp:345] Persisted promised to 1
I0901 01:34:13.627614 30507 coordinator.cpp:231] Coordinator attemping to fill missing position
I0901 01:34:13.628764 30518 replica.cpp:378] Replica received explicit promise request for position 0 with proposal 2
I0901 01:34:13.629114 30518 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 303373ns
I0901 01:34:13.629140 30518 replica.cpp:679] Persisted action at 0
I0901 01:34:13.630182 30517 replica.cpp:511] Replica received write request for position 0
I0901 01:34:13.630272 30517 leveldb.cpp:438] Reading position from leveldb took 58804ns
I0901 01:34:13.630722 30517 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 280506ns
I0901 01:34:13.630748 30517 replica.cpp:679] Persisted action at 0
I0901 01:34:13.632084 30516 replica.cpp:658] Replica received learned notice for position 0
I0901 01:34:13.632535 30516 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 395677ns
I0901 01:34:13.633059 30516 replica.cpp:679] Persisted action at 0
I0901 01:34:13.633344 30516 replica.cpp:664] Replica learned NOP action at position 0
I0901 01:34:13.634232 30521 log.cpp:677] Writer started with ending position 0
I0901 01:34:13.635602 30521 leveldb.cpp:438] Reading position from leveldb took 36365ns
I0901 01:34:13.639372 30521 registrar.cpp:344] Successfully fetched the registry (0B) in 17.931008ms
I0901 01:34:13.640036 30521 registrar.cpp:443] Applied 1 operations in 62767ns; attempting to update the 'registry'
I0901 01:34:13.642554 30521 log.cpp:685] Attempting to append 178 bytes to the log
I0901 01:34:13.642717 30508 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 1
I0901 01:34:13.643582 30514 replica.cpp:511] Replica received write request for position 1
I0901 01:34:13.644204 30514 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 470599ns
I0901 01:34:13.644233 30514 replica.cpp:679] Persisted action at 1
I0901 01:34:13.645012 30512 replica.cpp:658] Replica received learned notice for position 1
I0901 01:34:13.645385 30512 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 347723ns
I0901 01:34:13.645421 30512 replica.cpp:679] Persisted action at 1
I0901 01:34:13.645450 30512 replica.cpp:664] Replica learned APPEND action at position 1
I0901 01:34:13.646477 30507 registrar.cpp:488] Successfully updated the 'registry' in 6.305024ms
I0901 01:34:13.646771 30512 log.cpp:704] Attempting to truncate the log to 1
I0901 01:34:13.646960 30512 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
I0901 01:34:13.646975 30507 registrar.cpp:374] Successfully recovered registrar
I0901 01:34:13.647505 30521 master.cpp:1369] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I0901 01:34:13.648180 30518 replica.cpp:511] Replica received write request for position 2
I0901 01:34:13.648666 30518 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 326369ns
I0901 01:34:13.648694 30518 replica.cpp:679] Persisted action at 2
I0901 01:34:13.649610 30518 replica.cpp:658] Replica received learned notice for position 2
I0901 01:34:13.649953 30518 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 318537ns
I0901 01:34:13.650037 30518 leveldb.cpp:401] Deleting ~1 keys from leveldb took 57361ns
I0901 01:34:13.650075 30518 replica.cpp:679] Persisted action at 2
I0901 01:34:13.650104 30518 replica.cpp:664] Replica learned TRUNCATE action at position 2
I0901 01:34:13.658303 30488 scheduler.cpp:157] Version: 0.25.0
I0901 01:34:13.660554 30519 scheduler.cpp:240] New master detected at master@172.17.0.119:35897
I0901 01:34:13.662181 30518 scheduler.cpp:302] Sending SUBSCRIBE call to master@172.17.0.119:35897
I0901 01:34:13.664407 30514 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 01:34:13.664616 30514 http.cpp:333] HTTP POST for /master/api/v1/scheduler from 172.17.0.119:38030
I0901 01:34:13.664958 30514 master.cpp:1817] Received subscription request for HTTP framework 'default'
I0901 01:34:13.665046 30514 master.cpp:1598] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0901 01:34:13.665314 30514 master.cpp:1909] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0901 01:34:13.665791 30508 hierarchical.hpp:391] Added framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.665828 30508 hierarchical.hpp:1010] No resources available to allocate!
I0901 01:34:13.665844 30508 hierarchical.hpp:910] Performed allocation for 0 slaves in 29650ns
I0901 01:34:13.665873 30508 master.hpp:1339] Sending heartbeat to 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.666849 30521 scheduler.cpp:461] Enqueuing event SUBSCRIBED received from master@172.17.0.119:35897
I0901 01:34:13.667315 30521 scheduler.cpp:461] Enqueuing event HEARTBEAT received from master@172.17.0.119:35897
I0901 01:34:13.667949 30508 scheduler_tests.cpp:113] Ignoring HEARTBEAT event
I0901 01:34:13.668045 30518 scheduler.cpp:302] Sending REQUEST call to master@172.17.0.119:35897
I0901 01:34:13.669878 30522 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0901 01:34:13.670075 30522 http.cpp:333] HTTP POST for /master/api/v1/scheduler from 172.17.0.119:38031
I0901 01:34:13.670334 30522 master.cpp:2520] Processing REQUEST call for framework 20150901-013413-1996493228-35897-30488-0000 (default)
I0901 01:34:13.670500 30512 hierarchical.hpp:668] Received resource request from framework 20150901-013413-1996493228-35897-30488-0000
I0901 01:34:13.670824 30508 master.cpp:884] Master terminating
I0901 01:34:13.671052 30522 hierarchical.hpp:428] Removed framework 20150901-013413-1996493228-35897-30488-0000
E0901 01:34:13.673291 30515 scheduler.cpp:435] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (69 ms)
[----------] 20 tests from ContentType/SchedulerTest (4160 ms total)
[----------] Global test environment tear-down
[==========] 750 tests from 102 test cases ran. (315444 ms total)
[ PASSED ] 749 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] FetcherCacheHttpTest.HttpMixed
1 FAILED TEST
YOU HAVE 8 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.25.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.25.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.25.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.25.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1441069876-15440
Untagged: mesos-1441069876-15440:latest
Deleted: fb82ea47314ebcb7532036a117c5bf2fb3f387c351c3388b589c8eef849fbbcb
Deleted: 3a6b23d38f2ec006a11631ca3f654512fd974d75a9643e96dd34f9c3f7340fb8
Deleted: bad7754fb1b9adb33da8efe6647760dd1db582b7f0c0caa902ac8bb3fac84b32
Deleted: 9bb423c03745fd22630cfa4c2fed2c0088bbb0ad09bd6a4403a6c7464e32b597
Build step 'Execute shell' marked build as failure
Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #755
Posted by Benjamin Mahler <be...@gmail.com>.
+bernd
Re-opened https://issues.apache.org/jira/browse/MESOS-2858
On Mon, Aug 31, 2015 at 6:33 PM, 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/755/changes
> >
>
> Changes:
>
> [benjamin.mahler] Fixed a non-plural variable name in perf.hpp.
>
> ------------------------------------------
> [...truncated 121858 lines...]
> I0901 01:34:13.515564 30509 hierarchical.hpp:428] Removed framework
> 20150901-013413-1996493228-35897-30488-0000
> E0901 01:34:13.516106 30509 scheduler.cpp:435] End-Of-File received from
> master. The master closed the event stream
> I0901 01:34:13.516243 30515 slave.cpp:3143] master@172.17.0.119:35897
> exited
> W0901 01:34:13.516481 30515 slave.cpp:3146] Master disconnected! Waiting
> for a new master to be elected
> I0901 01:34:13.519942 30508 slave.cpp:3399] Executor 'default' of
> framework 20150901-013413-1996493228-35897-30488-0000 exited with status 0
> I0901 01:34:13.521723 30508 slave.cpp:2696] Handling status update
> TASK_LOST (UUID: 80a7ec7c-4558-4c42-8a68-9b282052b4a5) for task
> d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c of framework
> 20150901-013413-1996493228-35897-30488-0000 from @0.0.0.0:0
> I0901 01:34:13.521848 30508 slave.cpp:5094] Terminating task
> d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c
> I0901 01:34:13.522336 30508 slave.cpp:564] Slave terminating
> I0901 01:34:13.522413 30508 slave.cpp:1959] Asked to shut down framework
> 20150901-013413-1996493228-35897-30488-0000 by @0.0.0.0:0
> I0901 01:34:13.522534 30508 slave.cpp:1984] Shutting down framework
> 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.522689 30508 slave.cpp:3503] Cleaning up executor 'default'
> of framework 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.523030 30509 gc.cpp:56] Scheduling
> '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000/executors/default/runs/fd926952-f7cf-40c8-be62-bc34289b89cf'
> for gc 6.99999394757037days in the future
> I0901 01:34:13.523133 30508 slave.cpp:3592] Cleaning up framework
> 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.523282 30509 gc.cpp:56] Scheduling
> '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000/executors/default'
> for gc 6.99999394579852days in the future
> I0901 01:34:13.523396 30518 status_update_manager.cpp:284] Closing status
> update streams for framework 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.524374 30518 status_update_manager.cpp:530] Cleaning up
> status update stream for task d5dad94b-5a73-4b32-9d3f-5432e6b6ad3c of
> framework 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.523474 30509 gc.cpp:56] Scheduling
> '/tmp/ContentType_SchedulerTest_Message_1_R2AKVz/slaves/20150901-013413-1996493228-35897-30488-S0/frameworks/20150901-013413-1996493228-35897-30488-0000'
> for gc 6.99999394276741days in the future
> [ OK ] ContentType/SchedulerTest.Message/1 (125 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> Using temporary directory '/tmp/ContentType_SchedulerTest_Request_0_vR0XnP'
> I0901 01:34:13.531173 30488 leveldb.cpp:176] Opened db in 2.561692ms
> I0901 01:34:13.532003 30488 leveldb.cpp:183] Compacted db in 783277ns
> I0901 01:34:13.532119 30488 leveldb.cpp:198] Created db iterator in 22761ns
> I0901 01:34:13.532143 30488 leveldb.cpp:204] Seeked to beginning of db in
> 2055ns
> I0901 01:34:13.532160 30488 leveldb.cpp:273] Iterated through 0 keys in
> the db in 472ns
> I0901 01:34:13.532212 30488 replica.cpp:744] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0901 01:34:13.532742 30511 recover.cpp:449] Starting replica recovery
> I0901 01:34:13.532968 30511 recover.cpp:475] Replica is in EMPTY status
> I0901 01:34:13.534502 30508 replica.cpp:641] Replica in EMPTY status
> received a broadcasted recover request
> I0901 01:34:13.535284 30508 recover.cpp:195] Received a recover response
> from a replica in EMPTY status
> I0901 01:34:13.537603 30516 recover.cpp:566] Updating replica status to
> STARTING
> I0901 01:34:13.538120 30518 master.cpp:378] Master
> 20150901-013413-1996493228-35897-30488 (998e845ced4a) started on
> 172.17.0.119:35897
> I0901 01:34:13.538146 30518 master.cpp:380] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/credentials"
> --framework_sorter="drf" --help="false" --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.25.0/_inst/share/mesos/webui"
> --work_dir="/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/master"
> --zk_session_timeout="10secs"
> I0901 01:34:13.538532 30518 master.cpp:427] Master allowing
> unauthenticated frameworks to register
> I0901 01:34:13.538547 30518 master.cpp:430] Master only allowing
> authenticated slaves to register
> I0901 01:34:13.538558 30518 credentials.hpp:37] Loading credentials for
> authentication from
> '/tmp/ContentType_SchedulerTest_Request_0_vR0XnP/credentials'
> I0901 01:34:13.538666 30522 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 568647ns
> I0901 01:34:13.538703 30522 replica.cpp:323] Persisted replica status to
> STARTING
> I0901 01:34:13.538883 30518 master.cpp:469] Using default 'crammd5'
> authenticator
> I0901 01:34:13.538979 30510 recover.cpp:475] Replica is in STARTING status
> I0901 01:34:13.539052 30518 master.cpp:506] Authorization enabled
> I0901 01:34:13.540369 30508 replica.cpp:641] Replica in STARTING status
> received a broadcasted recover request
> I0901 01:34:13.540448 30510 hierarchical.hpp:346] Initialized hierarchical
> allocator process
> I0901 01:34:13.540478 30522 whitelist_watcher.cpp:79] No whitelist given
> I0901 01:34:13.541385 30508 recover.cpp:195] Received a recover response
> from a replica in STARTING status
> I0901 01:34:13.542026 30522 recover.cpp:566] Updating replica status to
> VOTING
> I0901 01:34:13.542232 30518 master.cpp:1559] The newly elected leader is
> master@172.17.0.119:35897 with id 20150901-013413-1996493228-35897-30488
> I0901 01:34:13.542335 30518 master.cpp:1572] Elected as the leading master!
> I0901 01:34:13.542466 30518 master.cpp:1332] Recovering from registrar
> I0901 01:34:13.542826 30518 registrar.cpp:311] Recovering registrar
> I0901 01:34:13.543068 30508 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 365312ns
> I0901 01:34:13.543367 30508 replica.cpp:323] Persisted replica status to
> VOTING
> I0901 01:34:13.543473 30522 recover.cpp:580] Successfully joined the Paxos
> group
> I0901 01:34:13.543685 30522 recover.cpp:464] Recover process terminated
> I0901 01:34:13.544334 30516 log.cpp:661] Attempting to start the writer
> I0901 01:34:13.545722 30510 replica.cpp:477] Replica received implicit
> promise request with proposal 1
> I0901 01:34:13.546131 30510 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 382715ns
> I0901 01:34:13.546164 30510 replica.cpp:345] Persisted promised to 1
> I0901 01:34:13.547056 30510 coordinator.cpp:231] Coordinator attemping to
> fill missing position
> I0901 01:34:13.548393 30517 replica.cpp:378] Replica received explicit
> promise request for position 0 with proposal 2
> I0901 01:34:13.549365 30517 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 508185ns
> I0901 01:34:13.549592 30517 replica.cpp:679] Persisted action at 0
> I0901 01:34:13.553926 30522 replica.cpp:511] Replica received write
> request for position 0
> I0901 01:34:13.554023 30522 leveldb.cpp:438] Reading position from leveldb
> took 34026ns
> I0901 01:34:13.554674 30522 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 523847ns
> I0901 01:34:13.554711 30522 replica.cpp:679] Persisted action at 0
> I0901 01:34:13.555552 30522 replica.cpp:658] Replica received learned
> notice for position 0
> I0901 01:34:13.555915 30522 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 333834ns
> I0901 01:34:13.555944 30522 replica.cpp:679] Persisted action at 0
> I0901 01:34:13.555968 30522 replica.cpp:664] Replica learned NOP action at
> position 0
> I0901 01:34:13.557031 30522 log.cpp:677] Writer started with ending
> position 0
> I0901 01:34:13.558575 30522 leveldb.cpp:438] Reading position from leveldb
> took 35255ns
> I0901 01:34:13.562242 30522 registrar.cpp:344] Successfully fetched the
> registry (0B) in 19.28192ms
> I0901 01:34:13.562387 30522 registrar.cpp:443] Applied 1 operations in
> 33477ns; attempting to update the 'registry'
> I0901 01:34:13.565824 30510 log.cpp:685] Attempting to append 178 bytes to
> the log
> I0901 01:34:13.566048 30513 coordinator.cpp:341] Coordinator attempting to
> write APPEND action at position 1
> I0901 01:34:13.566949 30512 replica.cpp:511] Replica received write
> request for position 1
> I0901 01:34:13.567483 30512 leveldb.cpp:343] Persisting action (197 bytes)
> to leveldb took 492374ns
> I0901 01:34:13.567523 30512 replica.cpp:679] Persisted action at 1
> I0901 01:34:13.568199 30512 replica.cpp:658] Replica received learned
> notice for position 1
> I0901 01:34:13.568665 30512 leveldb.cpp:343] Persisting action (199 bytes)
> to leveldb took 343360ns
> I0901 01:34:13.568699 30512 replica.cpp:679] Persisted action at 1
> I0901 01:34:13.568729 30512 replica.cpp:664] Replica learned APPEND action
> at position 1
> I0901 01:34:13.569790 30520 registrar.cpp:488] Successfully updated the
> 'registry' in 7.33184ms
> I0901 01:34:13.569968 30520 registrar.cpp:374] Successfully recovered
> registrar
> I0901 01:34:13.570150 30515 log.cpp:704] Attempting to truncate the log to
> 1
> I0901 01:34:13.570272 30520 coordinator.cpp:341] Coordinator attempting to
> write TRUNCATE action at position 2
> I0901 01:34:13.570386 30507 master.cpp:1369] Recovered 0 slaves from the
> Registry (139B) ; allowing 10mins for slaves to re-register
> I0901 01:34:13.571435 30510 replica.cpp:511] Replica received write
> request for position 2
> I0901 01:34:13.571858 30510 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 371692ns
> I0901 01:34:13.571985 30510 replica.cpp:679] Persisted action at 2
> I0901 01:34:13.573128 30507 replica.cpp:658] Replica received learned
> notice for position 2
> I0901 01:34:13.573748 30507 leveldb.cpp:343] Persisting action (18 bytes)
> to leveldb took 399399ns
> I0901 01:34:13.573860 30507 leveldb.cpp:401] Deleting ~1 keys from leveldb
> took 80758ns
> I0901 01:34:13.573890 30507 replica.cpp:679] Persisted action at 2
> I0901 01:34:13.573920 30507 replica.cpp:664] Replica learned TRUNCATE
> action at position 2
> I0901 01:34:13.581409 30488 scheduler.cpp:157] Version: 0.25.0
> I0901 01:34:13.584280 30520 scheduler.cpp:240] New master detected at
> master@172.17.0.119:35897
> I0901 01:34:13.586750 30522 scheduler.cpp:302] Sending SUBSCRIBE call to
> master@172.17.0.119:35897
> I0901 01:34:13.588770 30510 process.cpp:3043] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0901 01:34:13.588932 30510 http.cpp:333] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.119:38028
> I0901 01:34:13.589198 30510 master.cpp:1817] Received subscription request
> for HTTP framework 'default'
> I0901 01:34:13.589267 30510 master.cpp:1598] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0901 01:34:13.589565 30514 master.cpp:1909] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0901 01:34:13.590019 30513 hierarchical.hpp:391] Added framework
> 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.590059 30513 hierarchical.hpp:1010] No resources available
> to allocate!
> I0901 01:34:13.590093 30513 hierarchical.hpp:910] Performed allocation for
> 0 slaves in 30381ns
> I0901 01:34:13.590368 30507 master.hpp:1339] Sending heartbeat to
> 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.591143 30514 scheduler.cpp:461] Enqueuing event SUBSCRIBED
> received from master@172.17.0.119:35897
> I0901 01:34:13.592531 30509 scheduler.cpp:302] Sending REQUEST call to
> master@172.17.0.119:35897
> I0901 01:34:13.593093 30509 scheduler.cpp:461] Enqueuing event HEARTBEAT
> received from master@172.17.0.119:35897
> I0901 01:34:13.593616 30515 scheduler_tests.cpp:113] Ignoring HEARTBEAT
> event
> I0901 01:34:13.598319 30517 process.cpp:3043] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0901 01:34:13.598618 30517 http.cpp:333] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.119:38029
> I0901 01:34:13.598848 30517 master.cpp:2520] Processing REQUEST call for
> framework 20150901-013413-1996493228-35897-30488-0000 (default)
> I0901 01:34:13.599093 30508 hierarchical.hpp:668] Received resource
> request from framework 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.599490 30515 master.cpp:884] Master terminating
> I0901 01:34:13.600097 30512 hierarchical.hpp:428] Removed framework
> 20150901-013413-1996493228-35897-30488-0000
> E0901 01:34:13.602247 30520 scheduler.cpp:435] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/0 (80 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> Using temporary directory '/tmp/ContentType_SchedulerTest_Request_1_ccrAwf'
> I0901 01:34:13.612321 30488 leveldb.cpp:176] Opened db in 2.732436ms
> I0901 01:34:13.613260 30488 leveldb.cpp:183] Compacted db in 757637ns
> I0901 01:34:13.613317 30488 leveldb.cpp:198] Created db iterator in 22964ns
> I0901 01:34:13.613451 30488 leveldb.cpp:204] Seeked to beginning of db in
> 2606ns
> I0901 01:34:13.613534 30488 leveldb.cpp:273] Iterated through 0 keys in
> the db in 548ns
> I0901 01:34:13.613595 30488 replica.cpp:744] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0901 01:34:13.614243 30514 recover.cpp:449] Starting replica recovery
> I0901 01:34:13.614574 30514 recover.cpp:475] Replica is in EMPTY status
> I0901 01:34:13.615953 30515 replica.cpp:641] Replica in EMPTY status
> received a broadcasted recover request
> I0901 01:34:13.616627 30514 recover.cpp:195] Received a recover response
> from a replica in EMPTY status
> I0901 01:34:13.617187 30512 master.cpp:378] Master
> 20150901-013413-1996493228-35897-30488 (998e845ced4a) started on
> 172.17.0.119:35897
> I0901 01:34:13.617529 30519 recover.cpp:566] Updating replica status to
> STARTING
> I0901 01:34:13.617300 30512 master.cpp:380] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/credentials"
> --framework_sorter="drf" --help="false" --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.25.0/_inst/share/mesos/webui"
> --work_dir="/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/master"
> --zk_session_timeout="10secs"
> I0901 01:34:13.617902 30512 master.cpp:427] Master allowing
> unauthenticated frameworks to register
> I0901 01:34:13.617919 30512 master.cpp:430] Master only allowing
> authenticated slaves to register
> I0901 01:34:13.617931 30512 credentials.hpp:37] Loading credentials for
> authentication from
> '/tmp/ContentType_SchedulerTest_Request_1_ccrAwf/credentials'
> I0901 01:34:13.618306 30512 master.cpp:469] Using default 'crammd5'
> authenticator
> I0901 01:34:13.618398 30514 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 600368ns
> I0901 01:34:13.618505 30512 master.cpp:506] Authorization enabled
> I0901 01:34:13.619098 30519 hierarchical.hpp:346] Initialized hierarchical
> allocator process
> I0901 01:34:13.619163 30519 whitelist_watcher.cpp:79] No whitelist given
> I0901 01:34:13.618569 30514 replica.cpp:323] Persisted replica status to
> STARTING
> I0901 01:34:13.620694 30521 master.cpp:1559] The newly elected leader is
> master@172.17.0.119:35897 with id 20150901-013413-1996493228-35897-30488
> I0901 01:34:13.620810 30521 master.cpp:1572] Elected as the leading master!
> I0901 01:34:13.620961 30521 master.cpp:1332] Recovering from registrar
> I0901 01:34:13.621230 30519 registrar.cpp:311] Recovering registrar
> I0901 01:34:13.621961 30514 recover.cpp:475] Replica is in STARTING status
> I0901 01:34:13.622889 30517 replica.cpp:641] Replica in STARTING status
> received a broadcasted recover request
> I0901 01:34:13.623294 30515 recover.cpp:195] Received a recover response
> from a replica in STARTING status
> I0901 01:34:13.623807 30512 recover.cpp:566] Updating replica status to
> VOTING
> I0901 01:34:13.624295 30515 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 327184ns
> I0901 01:34:13.624326 30515 replica.cpp:323] Persisted replica status to
> VOTING
> I0901 01:34:13.624436 30514 recover.cpp:580] Successfully joined the Paxos
> group
> I0901 01:34:13.624774 30514 recover.cpp:464] Recover process terminated
> I0901 01:34:13.625172 30508 log.cpp:661] Attempting to start the writer
> I0901 01:34:13.626436 30517 replica.cpp:477] Replica received implicit
> promise request with proposal 1
> I0901 01:34:13.626927 30517 leveldb.cpp:306] Persisting metadata (8 bytes)
> to leveldb took 298893ns
> I0901 01:34:13.626951 30517 replica.cpp:345] Persisted promised to 1
> I0901 01:34:13.627614 30507 coordinator.cpp:231] Coordinator attemping to
> fill missing position
> I0901 01:34:13.628764 30518 replica.cpp:378] Replica received explicit
> promise request for position 0 with proposal 2
> I0901 01:34:13.629114 30518 leveldb.cpp:343] Persisting action (8 bytes)
> to leveldb took 303373ns
> I0901 01:34:13.629140 30518 replica.cpp:679] Persisted action at 0
> I0901 01:34:13.630182 30517 replica.cpp:511] Replica received write
> request for position 0
> I0901 01:34:13.630272 30517 leveldb.cpp:438] Reading position from leveldb
> took 58804ns
> I0901 01:34:13.630722 30517 leveldb.cpp:343] Persisting action (14 bytes)
> to leveldb took 280506ns
> I0901 01:34:13.630748 30517 replica.cpp:679] Persisted action at 0
> I0901 01:34:13.632084 30516 replica.cpp:658] Replica received learned
> notice for position 0
> I0901 01:34:13.632535 30516 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 395677ns
> I0901 01:34:13.633059 30516 replica.cpp:679] Persisted action at 0
> I0901 01:34:13.633344 30516 replica.cpp:664] Replica learned NOP action at
> position 0
> I0901 01:34:13.634232 30521 log.cpp:677] Writer started with ending
> position 0
> I0901 01:34:13.635602 30521 leveldb.cpp:438] Reading position from leveldb
> took 36365ns
> I0901 01:34:13.639372 30521 registrar.cpp:344] Successfully fetched the
> registry (0B) in 17.931008ms
> I0901 01:34:13.640036 30521 registrar.cpp:443] Applied 1 operations in
> 62767ns; attempting to update the 'registry'
> I0901 01:34:13.642554 30521 log.cpp:685] Attempting to append 178 bytes to
> the log
> I0901 01:34:13.642717 30508 coordinator.cpp:341] Coordinator attempting to
> write APPEND action at position 1
> I0901 01:34:13.643582 30514 replica.cpp:511] Replica received write
> request for position 1
> I0901 01:34:13.644204 30514 leveldb.cpp:343] Persisting action (197 bytes)
> to leveldb took 470599ns
> I0901 01:34:13.644233 30514 replica.cpp:679] Persisted action at 1
> I0901 01:34:13.645012 30512 replica.cpp:658] Replica received learned
> notice for position 1
> I0901 01:34:13.645385 30512 leveldb.cpp:343] Persisting action (199 bytes)
> to leveldb took 347723ns
> I0901 01:34:13.645421 30512 replica.cpp:679] Persisted action at 1
> I0901 01:34:13.645450 30512 replica.cpp:664] Replica learned APPEND action
> at position 1
> I0901 01:34:13.646477 30507 registrar.cpp:488] Successfully updated the
> 'registry' in 6.305024ms
> I0901 01:34:13.646771 30512 log.cpp:704] Attempting to truncate the log to
> 1
> I0901 01:34:13.646960 30512 coordinator.cpp:341] Coordinator attempting to
> write TRUNCATE action at position 2
> I0901 01:34:13.646975 30507 registrar.cpp:374] Successfully recovered
> registrar
> I0901 01:34:13.647505 30521 master.cpp:1369] Recovered 0 slaves from the
> Registry (139B) ; allowing 10mins for slaves to re-register
> I0901 01:34:13.648180 30518 replica.cpp:511] Replica received write
> request for position 2
> I0901 01:34:13.648666 30518 leveldb.cpp:343] Persisting action (16 bytes)
> to leveldb took 326369ns
> I0901 01:34:13.648694 30518 replica.cpp:679] Persisted action at 2
> I0901 01:34:13.649610 30518 replica.cpp:658] Replica received learned
> notice for position 2
> I0901 01:34:13.649953 30518 leveldb.cpp:343] Persisting action (18 bytes)
> to leveldb took 318537ns
> I0901 01:34:13.650037 30518 leveldb.cpp:401] Deleting ~1 keys from leveldb
> took 57361ns
> I0901 01:34:13.650075 30518 replica.cpp:679] Persisted action at 2
> I0901 01:34:13.650104 30518 replica.cpp:664] Replica learned TRUNCATE
> action at position 2
> I0901 01:34:13.658303 30488 scheduler.cpp:157] Version: 0.25.0
> I0901 01:34:13.660554 30519 scheduler.cpp:240] New master detected at
> master@172.17.0.119:35897
> I0901 01:34:13.662181 30518 scheduler.cpp:302] Sending SUBSCRIBE call to
> master@172.17.0.119:35897
> I0901 01:34:13.664407 30514 process.cpp:3043] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0901 01:34:13.664616 30514 http.cpp:333] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.119:38030
> I0901 01:34:13.664958 30514 master.cpp:1817] Received subscription request
> for HTTP framework 'default'
> I0901 01:34:13.665046 30514 master.cpp:1598] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0901 01:34:13.665314 30514 master.cpp:1909] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0901 01:34:13.665791 30508 hierarchical.hpp:391] Added framework
> 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.665828 30508 hierarchical.hpp:1010] No resources available
> to allocate!
> I0901 01:34:13.665844 30508 hierarchical.hpp:910] Performed allocation for
> 0 slaves in 29650ns
> I0901 01:34:13.665873 30508 master.hpp:1339] Sending heartbeat to
> 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.666849 30521 scheduler.cpp:461] Enqueuing event SUBSCRIBED
> received from master@172.17.0.119:35897
> I0901 01:34:13.667315 30521 scheduler.cpp:461] Enqueuing event HEARTBEAT
> received from master@172.17.0.119:35897
> I0901 01:34:13.667949 30508 scheduler_tests.cpp:113] Ignoring HEARTBEAT
> event
> I0901 01:34:13.668045 30518 scheduler.cpp:302] Sending REQUEST call to
> master@172.17.0.119:35897
> I0901 01:34:13.669878 30522 process.cpp:3043] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0901 01:34:13.670075 30522 http.cpp:333] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.119:38031
> I0901 01:34:13.670334 30522 master.cpp:2520] Processing REQUEST call for
> framework 20150901-013413-1996493228-35897-30488-0000 (default)
> I0901 01:34:13.670500 30512 hierarchical.hpp:668] Received resource
> request from framework 20150901-013413-1996493228-35897-30488-0000
> I0901 01:34:13.670824 30508 master.cpp:884] Master terminating
> I0901 01:34:13.671052 30522 hierarchical.hpp:428] Removed framework
> 20150901-013413-1996493228-35897-30488-0000
> E0901 01:34:13.673291 30515 scheduler.cpp:435] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (69 ms)
> [----------] 20 tests from ContentType/SchedulerTest (4160 ms total)
>
> [----------] Global test environment tear-down
> [==========] 750 tests from 102 test cases ran. (315444 ms total)
> [ PASSED ] 749 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] FetcherCacheHttpTest.HttpMixed
>
> 1 FAILED TEST
> YOU HAVE 8 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.25.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.25.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.25.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.25.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1441069876-15440
> Untagged: mesos-1441069876-15440:latest
> Deleted: fb82ea47314ebcb7532036a117c5bf2fb3f387c351c3388b589c8eef849fbbcb
> Deleted: 3a6b23d38f2ec006a11631ca3f654512fd974d75a9643e96dd34f9c3f7340fb8
> Deleted: bad7754fb1b9adb33da8efe6647760dd1db582b7f0c0caa902ac8bb3fac84b32
> Deleted: 9bb423c03745fd22630cfa4c2fed2c0088bbb0ad09bd6a4403a6c7464e32b597
> 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 #756
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/756/changes>