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/30 22:08:37 UTC

Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1294

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/1294/changes>

Changes:

[vinodkone] Added Executor->Framework message handler for the Executor HTTP API.

------------------------------------------
[...truncated 149286 lines...]
I1130 21:08:27.330451   355 replica.cpp:713] Persisted action at 4
I1130 21:08:27.330485   355 replica.cpp:698] Replica learned TRUNCATE action at position 4
E1130 21:08:27.331228   345 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
I1130 21:08:27.347769   324 slave.cpp:599] Slave terminating
I1130 21:08:27.347898   324 slave.cpp:2010] Asked to shut down framework bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 by @0.0.0.0:0
I1130 21:08:27.347939   324 slave.cpp:2035] Shutting down framework bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000
I1130 21:08:27.348021   324 slave.cpp:3891] Shutting down executor 'default' of framework bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 at executor(132)@172.17.20.211:49853
[       OK ] ContentType/SchedulerTest.Message/1 (683 ms)
[ RUN      ] ContentType/SchedulerTest.Request/0
I1130 21:08:27.479187   324 leveldb.cpp:174] Opened db in 115.296913ms
I1130 21:08:27.511601   324 leveldb.cpp:181] Compacted db in 32.327319ms
I1130 21:08:27.511701   324 leveldb.cpp:196] Created db iterator in 29426ns
I1130 21:08:27.511721   324 leveldb.cpp:202] Seeked to beginning of db in 3382ns
I1130 21:08:27.511734   324 leveldb.cpp:271] Iterated through 0 keys in the db in 384ns
I1130 21:08:27.511795   324 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1130 21:08:27.512843   355 recover.cpp:447] Starting replica recovery
I1130 21:08:27.513139   355 recover.cpp:473] Replica is in EMPTY status
I1130 21:08:27.514932   355 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11277)@172.17.20.211:49853
I1130 21:08:27.517248   352 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1130 21:08:27.517969   352 recover.cpp:564] Updating replica status to STARTING
I1130 21:08:27.523208   344 master.cpp:365] Master 18a00433-18e1-476f-82d1-5f507c7ba81d (aea03c1ca24e) started on 172.17.20.211:49853
I1130 21:08:27.523257   344 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/RbAcoE/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.27.0/_inst/share/mesos/webui" --work_dir="/tmp/RbAcoE/master" --zk_session_timeout="10secs"
I1130 21:08:27.523615   344 master.cpp:414] Master allowing unauthenticated frameworks to register
I1130 21:08:27.523629   344 master.cpp:417] Master only allowing authenticated slaves to register
I1130 21:08:27.523638   344 credentials.hpp:35] Loading credentials for authentication from '/tmp/RbAcoE/credentials'
I1130 21:08:27.523980   344 master.cpp:456] Using default 'crammd5' authenticator
I1130 21:08:27.524160   344 master.cpp:493] Authorization enabled
I1130 21:08:27.525506   350 hierarchical.cpp:162] Initialized hierarchical allocator process
I1130 21:08:27.525606   350 whitelist_watcher.cpp:77] No whitelist given
I1130 21:08:27.527474   350 master.cpp:1637] The newly elected leader is master@172.17.20.211:49853 with id 18a00433-18e1-476f-82d1-5f507c7ba81d
I1130 21:08:27.527525   350 master.cpp:1650] Elected as the leading master!
I1130 21:08:27.527546   350 master.cpp:1395] Recovering from registrar
I1130 21:08:27.527820   350 registrar.cpp:307] Recovering registrar
I1130 21:08:27.546037   352 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 27.807666ms
I1130 21:08:27.546118   352 replica.cpp:321] Persisted replica status to STARTING
I1130 21:08:27.546471   352 recover.cpp:473] Replica is in STARTING status
I1130 21:08:27.548519   352 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11278)@172.17.20.211:49853
I1130 21:08:27.548904   352 recover.cpp:193] Received a recover response from a replica in STARTING status
I1130 21:08:27.549509   350 recover.cpp:564] Updating replica status to VOTING
I1130 21:08:27.571252   350 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.409051ms
I1130 21:08:27.571336   350 replica.cpp:321] Persisted replica status to VOTING
I1130 21:08:27.571596   350 recover.cpp:578] Successfully joined the Paxos group
I1130 21:08:27.571888   350 recover.cpp:462] Recover process terminated
I1130 21:08:27.572474   358 log.cpp:659] Attempting to start the writer
I1130 21:08:27.573999   358 replica.cpp:494] Replica received implicit promise request from (11279)@172.17.20.211:49853 with proposal 1
I1130 21:08:27.596279   358 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.224107ms
I1130 21:08:27.596366   358 replica.cpp:343] Persisted promised to 1
I1130 21:08:27.597810   343 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1130 21:08:27.600751   343 replica.cpp:389] Replica received explicit promise request from (11280)@172.17.20.211:49853 for position 0 with proposal 2
I1130 21:08:27.622263   343 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 21.312087ms
I1130 21:08:27.622346   343 replica.cpp:713] Persisted action at 0
I1130 21:08:27.624104   346 replica.cpp:538] Replica received write request for position 0 from (11281)@172.17.20.211:49853
I1130 21:08:27.624258   346 leveldb.cpp:436] Reading position from leveldb took 107729ns
I1130 21:08:27.646852   346 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 22.452736ms
I1130 21:08:27.646936   346 replica.cpp:713] Persisted action at 0
I1130 21:08:27.648002   346 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1130 21:08:27.678287   346 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 30.230306ms
I1130 21:08:27.678381   346 replica.cpp:713] Persisted action at 0
I1130 21:08:27.678421   346 replica.cpp:698] Replica learned NOP action at position 0
I1130 21:08:27.679606   347 log.cpp:675] Writer started with ending position 0
I1130 21:08:27.681371   347 leveldb.cpp:436] Reading position from leveldb took 70404ns
I1130 21:08:27.682898   347 registrar.cpp:340] Successfully fetched the registry (0B) in 155.027968ms
I1130 21:08:27.683078   347 registrar.cpp:439] Applied 1 operations in 54140ns; attempting to update the 'registry'
I1130 21:08:27.684787   344 log.cpp:683] Attempting to append 178 bytes to the log
I1130 21:08:27.685065   347 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1130 21:08:27.686206   348 replica.cpp:538] Replica received write request for position 1 from (11282)@172.17.20.211:49853
I1130 21:08:27.722992   348 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 36.707093ms
I1130 21:08:27.723076   348 replica.cpp:713] Persisted action at 1
I1130 21:08:27.724438   345 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1130 21:08:27.770239   345 leveldb.cpp:341] Persisting action (199 bytes) to leveldb took 45.745553ms
I1130 21:08:27.770336   345 replica.cpp:713] Persisted action at 1
I1130 21:08:27.770376   345 replica.cpp:698] Replica learned APPEND action at position 1
I1130 21:08:27.772614   356 registrar.cpp:484] Successfully updated the 'registry' in 89.427968ms
I1130 21:08:27.772789   356 registrar.cpp:370] Successfully recovered registrar
I1130 21:08:27.773059   356 log.cpp:702] Attempting to truncate the log to 1
I1130 21:08:27.773519   358 master.cpp:1447] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1130 21:08:27.773712   358 hierarchical.cpp:174] Allocator recovery is not supported yet
I1130 21:08:27.774319   349 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1130 21:08:27.776000   356 replica.cpp:538] Replica received write request for position 2 from (11283)@172.17.20.211:49853
I1130 21:08:27.806820   356 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 30.743154ms
I1130 21:08:27.806915   356 replica.cpp:713] Persisted action at 2
I1130 21:08:27.808435   349 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1130 21:08:27.840296   349 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 31.73806ms
I1130 21:08:27.840463   349 leveldb.cpp:399] Deleting ~1 keys from leveldb took 77442ns
I1130 21:08:27.840493   349 replica.cpp:713] Persisted action at 2
I1130 21:08:27.840531   349 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1130 21:08:27.845356   324 scheduler.cpp:154] Version: 0.27.0
I1130 21:08:27.846367   349 scheduler.cpp:236] New master detected at master@172.17.20.211:49853
I1130 21:08:27.847790   356 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.20.211:49853
I1130 21:08:27.849529   356 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1130 21:08:27.849588   356 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.20.211:54626
I1130 21:08:27.849788   356 master.cpp:1899] Received subscription request for HTTP framework 'default'
I1130 21:08:27.849864   356 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1130 21:08:27.850175   357 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I1130 21:08:27.850682   344 hierarchical.cpp:220] Added framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
I1130 21:08:27.850739   344 hierarchical.cpp:1064] No resources available to allocate!
I1130 21:08:27.850770   344 hierarchical.cpp:1157] No inverse offers to send out!
I1130 21:08:27.850793   344 hierarchical.cpp:959] Performed allocation for 0 slaves in 86690ns
I1130 21:08:27.850900   357 master.hpp:1542] Sending heartbeat to 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
I1130 21:08:27.852912   347 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.20.211:49853
I1130 21:08:27.853420   347 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.20.211:49853
I1130 21:08:27.853988   350 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1130 21:08:27.854672   358 scheduler.cpp:298] Sending REQUEST call to master@172.17.20.211:49853
I1130 21:08:27.856593   353 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1130 21:08:27.856719   353 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.20.211:54627
I1130 21:08:27.856889   353 master.cpp:2646] Processing REQUEST call for framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000 (default)
I1130 21:08:27.857241   353 hierarchical.cpp:494] Received resource request from framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
I1130 21:08:27.857846   324 master.cpp:938] Master terminating
I1130 21:08:27.858505   349 hierarchical.cpp:260] Removed framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
E1130 21:08:27.862787   353 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[       OK ] ContentType/SchedulerTest.Request/0 (525 ms)
[ RUN      ] ContentType/SchedulerTest.Request/1
I1130 21:08:27.981688   324 leveldb.cpp:174] Opened db in 96.943089ms
I1130 21:08:28.008204   324 leveldb.cpp:181] Compacted db in 26.429482ms
I1130 21:08:28.008306   324 leveldb.cpp:196] Created db iterator in 25911ns
I1130 21:08:28.008329   324 leveldb.cpp:202] Seeked to beginning of db in 3856ns
I1130 21:08:28.008342   324 leveldb.cpp:271] Iterated through 0 keys in the db in 341ns
I1130 21:08:28.008400   324 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1130 21:08:28.009362   345 recover.cpp:447] Starting replica recovery
I1130 21:08:28.009794   345 recover.cpp:473] Replica is in EMPTY status
I1130 21:08:28.011524   348 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11287)@172.17.20.211:49853
I1130 21:08:28.012024   345 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1130 21:08:28.012697   345 recover.cpp:564] Updating replica status to STARTING
I1130 21:08:28.028069   353 master.cpp:365] Master cf6bff68-54b0-4223-a1f0-cef4ceeb004d (aea03c1ca24e) started on 172.17.20.211:49853
I1130 21:08:28.028102   353 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/RQ8lBH/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.27.0/_inst/share/mesos/webui" --work_dir="/tmp/RQ8lBH/master" --zk_session_timeout="10secs"
I1130 21:08:28.028492   353 master.cpp:414] Master allowing unauthenticated frameworks to register
I1130 21:08:28.028504   353 master.cpp:417] Master only allowing authenticated slaves to register
I1130 21:08:28.028513   353 credentials.hpp:35] Loading credentials for authentication from '/tmp/RQ8lBH/credentials'
I1130 21:08:28.038077   353 master.cpp:456] Using default 'crammd5' authenticator
I1130 21:08:28.038632   353 master.cpp:493] Authorization enabled
I1130 21:08:28.039350   346 hierarchical.cpp:162] Initialized hierarchical allocator process
I1130 21:08:28.039631   346 whitelist_watcher.cpp:77] No whitelist given
I1130 21:08:28.044158   353 master.cpp:1637] The newly elected leader is master@172.17.20.211:49853 with id cf6bff68-54b0-4223-a1f0-cef4ceeb004d
I1130 21:08:28.044719   353 master.cpp:1650] Elected as the leading master!
I1130 21:08:28.044865   353 master.cpp:1395] Recovering from registrar
I1130 21:08:28.045243   357 registrar.cpp:307] Recovering registrar
I1130 21:08:28.046226   345 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 33.160654ms
I1130 21:08:28.046283   345 replica.cpp:321] Persisted replica status to STARTING
I1130 21:08:28.046551   345 recover.cpp:473] Replica is in STARTING status
I1130 21:08:28.048651   345 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11289)@172.17.20.211:49853
I1130 21:08:28.049242   355 recover.cpp:193] Received a recover response from a replica in STARTING status
I1130 21:08:28.050812   355 recover.cpp:564] Updating replica status to VOTING
I1130 21:08:28.082262   357 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.981671ms
I1130 21:08:28.082348   357 replica.cpp:321] Persisted replica status to VOTING
I1130 21:08:28.082595   357 recover.cpp:578] Successfully joined the Paxos group
I1130 21:08:28.082778   357 recover.cpp:462] Recover process terminated
I1130 21:08:28.083708   348 log.cpp:659] Attempting to start the writer
I1130 21:08:28.085435   348 replica.cpp:494] Replica received implicit promise request from (11290)@172.17.20.211:49853 with proposal 1
I1130 21:08:28.133262   348 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 47.708066ms
I1130 21:08:28.133359   348 replica.cpp:343] Persisted promised to 1
I1130 21:08:28.134845   348 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1130 21:08:28.136824   348 replica.cpp:389] Replica received explicit promise request from (11291)@172.17.20.211:49853 for position 0 with proposal 2
I1130 21:08:28.166779   348 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 29.742663ms
I1130 21:08:28.166870   348 replica.cpp:713] Persisted action at 0
I1130 21:08:28.168622   352 replica.cpp:538] Replica received write request for position 0 from (11292)@172.17.20.211:49853
I1130 21:08:28.168822   352 leveldb.cpp:436] Reading position from leveldb took 40320ns
I1130 21:08:28.191473   352 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 22.491006ms
I1130 21:08:28.191576   352 replica.cpp:713] Persisted action at 0
I1130 21:08:28.192988   344 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1130 21:08:28.216675   344 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.06125ms
I1130 21:08:28.216773   344 replica.cpp:713] Persisted action at 0
I1130 21:08:28.216810   344 replica.cpp:698] Replica learned NOP action at position 0
I1130 21:08:28.217905   355 log.cpp:675] Writer started with ending position 0
I1130 21:08:28.219653   355 leveldb.cpp:436] Reading position from leveldb took 52180ns
I1130 21:08:28.221421   344 registrar.cpp:340] Successfully fetched the registry (0B) in 176.124928ms
I1130 21:08:28.221719   344 registrar.cpp:439] Applied 1 operations in 39055ns; attempting to update the 'registry'
I1130 21:08:28.222777   350 log.cpp:683] Attempting to append 178 bytes to the log
I1130 21:08:28.223114   343 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1130 21:08:28.224201   344 replica.cpp:538] Replica received write request for position 1 from (11293)@172.17.20.211:49853
I1130 21:08:28.250054   344 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 25.784879ms
I1130 21:08:28.250156   344 replica.cpp:713] Persisted action at 1
I1130 21:08:28.258332   354 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1130 21:08:28.283534   354 leveldb.cpp:341] Persisting action (199 bytes) to leveldb took 25.027205ms
I1130 21:08:28.283632   354 replica.cpp:713] Persisted action at 1
I1130 21:08:28.283669   354 replica.cpp:698] Replica learned APPEND action at position 1
I1130 21:08:28.285953   354 registrar.cpp:484] Successfully updated the 'registry' in 64.059904ms
I1130 21:08:28.286386   354 registrar.cpp:370] Successfully recovered registrar
I1130 21:08:28.286895   354 master.cpp:1447] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I1130 21:08:28.287101   351 hierarchical.cpp:174] Allocator recovery is not supported yet
I1130 21:08:28.286087   345 log.cpp:702] Attempting to truncate the log to 1
I1130 21:08:28.288830   345 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1130 21:08:28.290005   350 replica.cpp:538] Replica received write request for position 2 from (11294)@172.17.20.211:49853
I1130 21:08:28.316994   350 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 25.725069ms
I1130 21:08:28.317087   350 replica.cpp:713] Persisted action at 2
I1130 21:08:28.318534   358 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1130 21:08:28.342185   358 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.521347ms
I1130 21:08:28.342352   358 leveldb.cpp:399] Deleting ~1 keys from leveldb took 66119ns
I1130 21:08:28.342381   358 replica.cpp:713] Persisted action at 2
I1130 21:08:28.342417   358 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1130 21:08:28.349741   324 scheduler.cpp:154] Version: 0.27.0
I1130 21:08:28.350648   356 scheduler.cpp:236] New master detected at master@172.17.20.211:49853
I1130 21:08:28.363270   351 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.20.211:49853
I1130 21:08:28.366402   351 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1130 21:08:28.366574   351 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.20.211:54631
I1130 21:08:28.367025   351 master.cpp:1899] Received subscription request for HTTP framework 'default'
I1130 21:08:28.367185   351 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1130 21:08:28.367882   351 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I1130 21:08:28.369591   351 hierarchical.cpp:220] Added framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
I1130 21:08:28.369653   351 hierarchical.cpp:1064] No resources available to allocate!
I1130 21:08:28.369691   351 hierarchical.cpp:1157] No inverse offers to send out!
I1130 21:08:28.369715   351 hierarchical.cpp:959] Performed allocation for 0 slaves in 97414ns
I1130 21:08:28.369966   354 master.hpp:1542] Sending heartbeat to cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
I1130 21:08:28.371633   354 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.20.211:49853
I1130 21:08:28.372498   358 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.20.211:49853
I1130 21:08:28.373020   358 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1130 21:08:28.373692   353 scheduler.cpp:298] Sending REQUEST call to master@172.17.20.211:49853
I1130 21:08:28.375929   352 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1130 21:08:28.375993   352 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.20.211:54632
I1130 21:08:28.376310   352 master.cpp:2646] Processing REQUEST call for framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000 (default)
I1130 21:08:28.376541   347 hierarchical.cpp:494] Received resource request from framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
I1130 21:08:28.377280   324 master.cpp:938] Master terminating
I1130 21:08:28.377461   353 hierarchical.cpp:260] Removed framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
E1130 21:08:28.378293   343 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[       OK ] ContentType/SchedulerTest.Request/1 (521 ms)
[----------] 22 tests from ContentType/SchedulerTest (16744 ms total)

[----------] Global test environment tear-down
[==========] 870 tests from 117 test cases ran. (791709 ms total)
[  PASSED  ] 869 tests.
[  FAILED  ] 1 test, listed below:
[  FAILED  ] MasterQuotaTest.AvailableResourcesAfterRescinding

 1 FAILED TEST
  YOU HAVE 7 DISABLED TESTS

make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1448914320-17469
Untagged: mesos-1448914320-17469:latest
Deleted: da529dd4edda7833eac2b5da57783b3f2e7375a404c6a4fca455a47a56d04a41
Deleted: bbda95a5006e24047ed48fd2387163a3158cc6080e978b97bb0c4538f2359019
Deleted: 3f4ce9d12df094a2ca1e075487610073e6488b64ce72087dbab8c30705e9f094
Deleted: 33a50a7ac53e4ccbb915a987666fa1ee25fd1cc9a61e7bf05170074841b603b3
Deleted: c5d1dc8b4f484cd84b2eba7455843b3d89f9ceea2cdf67c65214aa37c816f530
Deleted: 732b7e0b2b214c15e1e824c69d13bcdc19ea80d6507beec41925a9919b148fc2
Deleted: 77533cc5f4a714a800b9c943273a212fd934ac1ae2d0de6be31eb085832def65
Deleted: c1857e128beff6a7d52c8636159969734ddfa5ae6f24a46f902f9751b0e3845e
Deleted: 663e544e4e1729dfd29f36448991233dc69491ea1958107d431720951b89cf51
Deleted: 03524d3a5ec49d86b43fab3f013937fbf88980b4ff9ff1836d9cf481f5feb83d
Deleted: cae2f74aee84aa95e111f5d458d5e6426cc302c660212332170d4026245cf446
Deleted: 8d5f7938d6cc8a66e3611c319d98a6413f2386ba9878bd245109b73ea98e24e0
Build step 'Execute shell' marked build as failure

Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1294

Posted by Alex Rukletsov <al...@mesosphere.com>.

Looking into it

On Mon, Nov 30, 2015 at 10:44 PM, Vinod Kone <vi...@apache.org> wrote:

> AlexR?
>
> On Mon, Nov 30, 2015 at 1:08 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/1294/changes
> > >
> >
> > Changes:
> >
> > [vinodkone] Added Executor->Framework message handler for the Executor
> > HTTP API.
> >
> > ------------------------------------------
> > [...truncated 149286 lines...]
> > I1130 21:08:27.330451   355 replica.cpp:713] Persisted action at 4
> > I1130 21:08:27.330485   355 replica.cpp:698] Replica learned TRUNCATE
> > action at position 4
> > E1130 21:08:27.331228   345 scheduler.cpp:431] End-Of-File received from
> > master. The master closed the event stream
> > I1130 21:08:27.347769   324 slave.cpp:599] Slave terminating
> > I1130 21:08:27.347898   324 slave.cpp:2010] Asked to shut down framework
> > bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 by @0.0.0.0:0
> > I1130 21:08:27.347939   324 slave.cpp:2035] Shutting down framework
> > bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000
> > I1130 21:08:27.348021   324 slave.cpp:3891] Shutting down executor
> > 'default' of framework bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 at
> > executor(132)@172.17.20.211:49853
> > [       OK ] ContentType/SchedulerTest.Message/1 (683 ms)
> > [ RUN      ] ContentType/SchedulerTest.Request/0
> > I1130 21:08:27.479187   324 leveldb.cpp:174] Opened db in 115.296913ms
> > I1130 21:08:27.511601   324 leveldb.cpp:181] Compacted db in 32.327319ms
> > I1130 21:08:27.511701   324 leveldb.cpp:196] Created db iterator in
> 29426ns
> > I1130 21:08:27.511721   324 leveldb.cpp:202] Seeked to beginning of db in
> > 3382ns
> > I1130 21:08:27.511734   324 leveldb.cpp:271] Iterated through 0 keys in
> > the db in 384ns
> > I1130 21:08:27.511795   324 replica.cpp:778] Replica recovered with log
> > positions 0 -> 0 with 1 holes and 0 unlearned
> > I1130 21:08:27.512843   355 recover.cpp:447] Starting replica recovery
> > I1130 21:08:27.513139   355 recover.cpp:473] Replica is in EMPTY status
> > I1130 21:08:27.514932   355 replica.cpp:674] Replica in EMPTY status
> > received a broadcasted recover request from (11277)@172.17.20.211:49853
> > I1130 21:08:27.517248   352 recover.cpp:193] Received a recover response
> > from a replica in EMPTY status
> > I1130 21:08:27.517969   352 recover.cpp:564] Updating replica status to
> > STARTING
> > I1130 21:08:27.523208   344 master.cpp:365] Master
> > 18a00433-18e1-476f-82d1-5f507c7ba81d (aea03c1ca24e) started on
> > 172.17.20.211:49853
> > I1130 21:08:27.523257   344 master.cpp:367] Flags at startup: --acls=""
> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > --authenticate="false" --authenticate_slaves="true"
> > --authenticators="crammd5" --authorizers="local"
> > --credentials="/tmp/RbAcoE/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.27.0/_inst/share/mesos/webui"
> > --work_dir="/tmp/RbAcoE/master" --zk_session_timeout="10secs"
> > I1130 21:08:27.523615   344 master.cpp:414] Master allowing
> > unauthenticated frameworks to register
> > I1130 21:08:27.523629   344 master.cpp:417] Master only allowing
> > authenticated slaves to register
> > I1130 21:08:27.523638   344 credentials.hpp:35] Loading credentials for
> > authentication from '/tmp/RbAcoE/credentials'
> > I1130 21:08:27.523980   344 master.cpp:456] Using default 'crammd5'
> > authenticator
> > I1130 21:08:27.524160   344 master.cpp:493] Authorization enabled
> > I1130 21:08:27.525506   350 hierarchical.cpp:162] Initialized
> hierarchical
> > allocator process
> > I1130 21:08:27.525606   350 whitelist_watcher.cpp:77] No whitelist given
> > I1130 21:08:27.527474   350 master.cpp:1637] The newly elected leader is
> > master@172.17.20.211:49853 with id 18a00433-18e1-476f-82d1-5f507c7ba81d
> > I1130 21:08:27.527525   350 master.cpp:1650] Elected as the leading
> master!
> > I1130 21:08:27.527546   350 master.cpp:1395] Recovering from registrar
> > I1130 21:08:27.527820   350 registrar.cpp:307] Recovering registrar
> > I1130 21:08:27.546037   352 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 27.807666ms
> > I1130 21:08:27.546118   352 replica.cpp:321] Persisted replica status to
> > STARTING
> > I1130 21:08:27.546471   352 recover.cpp:473] Replica is in STARTING
> status
> > I1130 21:08:27.548519   352 replica.cpp:674] Replica in STARTING status
> > received a broadcasted recover request from (11278)@172.17.20.211:49853
> > I1130 21:08:27.548904   352 recover.cpp:193] Received a recover response
> > from a replica in STARTING status
> > I1130 21:08:27.549509   350 recover.cpp:564] Updating replica status to
> > VOTING
> > I1130 21:08:27.571252   350 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 21.409051ms
> > I1130 21:08:27.571336   350 replica.cpp:321] Persisted replica status to
> > VOTING
> > I1130 21:08:27.571596   350 recover.cpp:578] Successfully joined the
> Paxos
> > group
> > I1130 21:08:27.571888   350 recover.cpp:462] Recover process terminated
> > I1130 21:08:27.572474   358 log.cpp:659] Attempting to start the writer
> > I1130 21:08:27.573999   358 replica.cpp:494] Replica received implicit
> > promise request from (11279)@172.17.20.211:49853 with proposal 1
> > I1130 21:08:27.596279   358 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 22.224107ms
> > I1130 21:08:27.596366   358 replica.cpp:343] Persisted promised to 1
> > I1130 21:08:27.597810   343 coordinator.cpp:238] Coordinator attempting
> to
> > fill missing positions
> > I1130 21:08:27.600751   343 replica.cpp:389] Replica received explicit
> > promise request from (11280)@172.17.20.211:49853 for position 0 with
> > proposal 2
> > I1130 21:08:27.622263   343 leveldb.cpp:341] Persisting action (8 bytes)
> > to leveldb took 21.312087ms
> > I1130 21:08:27.622346   343 replica.cpp:713] Persisted action at 0
> > I1130 21:08:27.624104   346 replica.cpp:538] Replica received write
> > request for position 0 from (11281)@172.17.20.211:49853
> > I1130 21:08:27.624258   346 leveldb.cpp:436] Reading position from
> leveldb
> > took 107729ns
> > I1130 21:08:27.646852   346 leveldb.cpp:341] Persisting action (14 bytes)
> > to leveldb took 22.452736ms
> > I1130 21:08:27.646936   346 replica.cpp:713] Persisted action at 0
> > I1130 21:08:27.648002   346 replica.cpp:692] Replica received learned
> > notice for position 0 from @0.0.0.0:0
> > I1130 21:08:27.678287   346 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 30.230306ms
> > I1130 21:08:27.678381   346 replica.cpp:713] Persisted action at 0
> > I1130 21:08:27.678421   346 replica.cpp:698] Replica learned NOP action
> at
> > position 0
> > I1130 21:08:27.679606   347 log.cpp:675] Writer started with ending
> > position 0
> > I1130 21:08:27.681371   347 leveldb.cpp:436] Reading position from
> leveldb
> > took 70404ns
> > I1130 21:08:27.682898   347 registrar.cpp:340] Successfully fetched the
> > registry (0B) in 155.027968ms
> > I1130 21:08:27.683078   347 registrar.cpp:439] Applied 1 operations in
> > 54140ns; attempting to update the 'registry'
> > I1130 21:08:27.684787   344 log.cpp:683] Attempting to append 178 bytes
> to
> > the log
> > I1130 21:08:27.685065   347 coordinator.cpp:348] Coordinator attempting
> to
> > write APPEND action at position 1
> > I1130 21:08:27.686206   348 replica.cpp:538] Replica received write
> > request for position 1 from (11282)@172.17.20.211:49853
> > I1130 21:08:27.722992   348 leveldb.cpp:341] Persisting action (197
> bytes)
> > to leveldb took 36.707093ms
> > I1130 21:08:27.723076   348 replica.cpp:713] Persisted action at 1
> > I1130 21:08:27.724438   345 replica.cpp:692] Replica received learned
> > notice for position 1 from @0.0.0.0:0
> > I1130 21:08:27.770239   345 leveldb.cpp:341] Persisting action (199
> bytes)
> > to leveldb took 45.745553ms
> > I1130 21:08:27.770336   345 replica.cpp:713] Persisted action at 1
> > I1130 21:08:27.770376   345 replica.cpp:698] Replica learned APPEND
> action
> > at position 1
> > I1130 21:08:27.772614   356 registrar.cpp:484] Successfully updated the
> > 'registry' in 89.427968ms
> > I1130 21:08:27.772789   356 registrar.cpp:370] Successfully recovered
> > registrar
> > I1130 21:08:27.773059   356 log.cpp:702] Attempting to truncate the log
> to
> > 1
> > I1130 21:08:27.773519   358 master.cpp:1447] Recovered 0 slaves from the
> > Registry (139B) ; allowing 10mins for slaves to re-register
> > I1130 21:08:27.773712   358 hierarchical.cpp:174] Allocator recovery is
> > not supported yet
> > I1130 21:08:27.774319   349 coordinator.cpp:348] Coordinator attempting
> to
> > write TRUNCATE action at position 2
> > I1130 21:08:27.776000   356 replica.cpp:538] Replica received write
> > request for position 2 from (11283)@172.17.20.211:49853
> > I1130 21:08:27.806820   356 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 30.743154ms
> > I1130 21:08:27.806915   356 replica.cpp:713] Persisted action at 2
> > I1130 21:08:27.808435   349 replica.cpp:692] Replica received learned
> > notice for position 2 from @0.0.0.0:0
> > I1130 21:08:27.840296   349 leveldb.cpp:341] Persisting action (18 bytes)
> > to leveldb took 31.73806ms
> > I1130 21:08:27.840463   349 leveldb.cpp:399] Deleting ~1 keys from
> leveldb
> > took 77442ns
> > I1130 21:08:27.840493   349 replica.cpp:713] Persisted action at 2
> > I1130 21:08:27.840531   349 replica.cpp:698] Replica learned TRUNCATE
> > action at position 2
> > I1130 21:08:27.845356   324 scheduler.cpp:154] Version: 0.27.0
> > I1130 21:08:27.846367   349 scheduler.cpp:236] New master detected at
> > master@172.17.20.211:49853
> > I1130 21:08:27.847790   356 scheduler.cpp:298] Sending SUBSCRIBE call to
> > master@172.17.20.211:49853
> > I1130 21:08:27.849529   356 process.cpp:3067] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1130 21:08:27.849588   356 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.20.211:54626
> > I1130 21:08:27.849788   356 master.cpp:1899] Received subscription
> request
> > for HTTP framework 'default'
> > I1130 21:08:27.849864   356 master.cpp:1676] Authorizing framework
> > principal 'test-principal' to receive offers for role '*'
> > I1130 21:08:27.850175   357 master.cpp:1991] Subscribing framework
> > 'default' with checkpointing disabled and capabilities [  ]
> > I1130 21:08:27.850682   344 hierarchical.cpp:220] Added framework
> > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > I1130 21:08:27.850739   344 hierarchical.cpp:1064] No resources available
> > to allocate!
> > I1130 21:08:27.850770   344 hierarchical.cpp:1157] No inverse offers to
> > send out!
> > I1130 21:08:27.850793   344 hierarchical.cpp:959] Performed allocation
> for
> > 0 slaves in 86690ns
> > I1130 21:08:27.850900   357 master.hpp:1542] Sending heartbeat to
> > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > I1130 21:08:27.852912   347 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> > received from master@172.17.20.211:49853
> > I1130 21:08:27.853420   347 scheduler.cpp:457] Enqueuing event HEARTBEAT
> > received from master@172.17.20.211:49853
> > I1130 21:08:27.853988   350 master_maintenance_tests.cpp:172] Ignoring
> > HEARTBEAT event
> > I1130 21:08:27.854672   358 scheduler.cpp:298] Sending REQUEST call to
> > master@172.17.20.211:49853
> > I1130 21:08:27.856593   353 process.cpp:3067] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1130 21:08:27.856719   353 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.20.211:54627
> > I1130 21:08:27.856889   353 master.cpp:2646] Processing REQUEST call for
> > framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000 (default)
> > I1130 21:08:27.857241   353 hierarchical.cpp:494] Received resource
> > request from framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > I1130 21:08:27.857846   324 master.cpp:938] Master terminating
> > I1130 21:08:27.858505   349 hierarchical.cpp:260] Removed framework
> > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > E1130 21:08:27.862787   353 scheduler.cpp:431] End-Of-File received from
> > master. The master closed the event stream
> > [       OK ] ContentType/SchedulerTest.Request/0 (525 ms)
> > [ RUN      ] ContentType/SchedulerTest.Request/1
> > I1130 21:08:27.981688   324 leveldb.cpp:174] Opened db in 96.943089ms
> > I1130 21:08:28.008204   324 leveldb.cpp:181] Compacted db in 26.429482ms
> > I1130 21:08:28.008306   324 leveldb.cpp:196] Created db iterator in
> 25911ns
> > I1130 21:08:28.008329   324 leveldb.cpp:202] Seeked to beginning of db in
> > 3856ns
> > I1130 21:08:28.008342   324 leveldb.cpp:271] Iterated through 0 keys in
> > the db in 341ns
> > I1130 21:08:28.008400   324 replica.cpp:778] Replica recovered with log
> > positions 0 -> 0 with 1 holes and 0 unlearned
> > I1130 21:08:28.009362   345 recover.cpp:447] Starting replica recovery
> > I1130 21:08:28.009794   345 recover.cpp:473] Replica is in EMPTY status
> > I1130 21:08:28.011524   348 replica.cpp:674] Replica in EMPTY status
> > received a broadcasted recover request from (11287)@172.17.20.211:49853
> > I1130 21:08:28.012024   345 recover.cpp:193] Received a recover response
> > from a replica in EMPTY status
> > I1130 21:08:28.012697   345 recover.cpp:564] Updating replica status to
> > STARTING
> > I1130 21:08:28.028069   353 master.cpp:365] Master
> > cf6bff68-54b0-4223-a1f0-cef4ceeb004d (aea03c1ca24e) started on
> > 172.17.20.211:49853
> > I1130 21:08:28.028102   353 master.cpp:367] Flags at startup: --acls=""
> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > --authenticate="false" --authenticate_slaves="true"
> > --authenticators="crammd5" --authorizers="local"
> > --credentials="/tmp/RQ8lBH/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.27.0/_inst/share/mesos/webui"
> > --work_dir="/tmp/RQ8lBH/master" --zk_session_timeout="10secs"
> > I1130 21:08:28.028492   353 master.cpp:414] Master allowing
> > unauthenticated frameworks to register
> > I1130 21:08:28.028504   353 master.cpp:417] Master only allowing
> > authenticated slaves to register
> > I1130 21:08:28.028513   353 credentials.hpp:35] Loading credentials for
> > authentication from '/tmp/RQ8lBH/credentials'
> > I1130 21:08:28.038077   353 master.cpp:456] Using default 'crammd5'
> > authenticator
> > I1130 21:08:28.038632   353 master.cpp:493] Authorization enabled
> > I1130 21:08:28.039350   346 hierarchical.cpp:162] Initialized
> hierarchical
> > allocator process
> > I1130 21:08:28.039631   346 whitelist_watcher.cpp:77] No whitelist given
> > I1130 21:08:28.044158   353 master.cpp:1637] The newly elected leader is
> > master@172.17.20.211:49853 with id cf6bff68-54b0-4223-a1f0-cef4ceeb004d
> > I1130 21:08:28.044719   353 master.cpp:1650] Elected as the leading
> master!
> > I1130 21:08:28.044865   353 master.cpp:1395] Recovering from registrar
> > I1130 21:08:28.045243   357 registrar.cpp:307] Recovering registrar
> > I1130 21:08:28.046226   345 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 33.160654ms
> > I1130 21:08:28.046283   345 replica.cpp:321] Persisted replica status to
> > STARTING
> > I1130 21:08:28.046551   345 recover.cpp:473] Replica is in STARTING
> status
> > I1130 21:08:28.048651   345 replica.cpp:674] Replica in STARTING status
> > received a broadcasted recover request from (11289)@172.17.20.211:49853
> > I1130 21:08:28.049242   355 recover.cpp:193] Received a recover response
> > from a replica in STARTING status
> > I1130 21:08:28.050812   355 recover.cpp:564] Updating replica status to
> > VOTING
> > I1130 21:08:28.082262   357 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 30.981671ms
> > I1130 21:08:28.082348   357 replica.cpp:321] Persisted replica status to
> > VOTING
> > I1130 21:08:28.082595   357 recover.cpp:578] Successfully joined the
> Paxos
> > group
> > I1130 21:08:28.082778   357 recover.cpp:462] Recover process terminated
> > I1130 21:08:28.083708   348 log.cpp:659] Attempting to start the writer
> > I1130 21:08:28.085435   348 replica.cpp:494] Replica received implicit
> > promise request from (11290)@172.17.20.211:49853 with proposal 1
> > I1130 21:08:28.133262   348 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 47.708066ms
> > I1130 21:08:28.133359   348 replica.cpp:343] Persisted promised to 1
> > I1130 21:08:28.134845   348 coordinator.cpp:238] Coordinator attempting
> to
> > fill missing positions
> > I1130 21:08:28.136824   348 replica.cpp:389] Replica received explicit
> > promise request from (11291)@172.17.20.211:49853 for position 0 with
> > proposal 2
> > I1130 21:08:28.166779   348 leveldb.cpp:341] Persisting action (8 bytes)
> > to leveldb took 29.742663ms
> > I1130 21:08:28.166870   348 replica.cpp:713] Persisted action at 0
> > I1130 21:08:28.168622   352 replica.cpp:538] Replica received write
> > request for position 0 from (11292)@172.17.20.211:49853
> > I1130 21:08:28.168822   352 leveldb.cpp:436] Reading position from
> leveldb
> > took 40320ns
> > I1130 21:08:28.191473   352 leveldb.cpp:341] Persisting action (14 bytes)
> > to leveldb took 22.491006ms
> > I1130 21:08:28.191576   352 replica.cpp:713] Persisted action at 0
> > I1130 21:08:28.192988   344 replica.cpp:692] Replica received learned
> > notice for position 0 from @0.0.0.0:0
> > I1130 21:08:28.216675   344 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 23.06125ms
> > I1130 21:08:28.216773   344 replica.cpp:713] Persisted action at 0
> > I1130 21:08:28.216810   344 replica.cpp:698] Replica learned NOP action
> at
> > position 0
> > I1130 21:08:28.217905   355 log.cpp:675] Writer started with ending
> > position 0
> > I1130 21:08:28.219653   355 leveldb.cpp:436] Reading position from
> leveldb
> > took 52180ns
> > I1130 21:08:28.221421   344 registrar.cpp:340] Successfully fetched the
> > registry (0B) in 176.124928ms
> > I1130 21:08:28.221719   344 registrar.cpp:439] Applied 1 operations in
> > 39055ns; attempting to update the 'registry'
> > I1130 21:08:28.222777   350 log.cpp:683] Attempting to append 178 bytes
> to
> > the log
> > I1130 21:08:28.223114   343 coordinator.cpp:348] Coordinator attempting
> to
> > write APPEND action at position 1
> > I1130 21:08:28.224201   344 replica.cpp:538] Replica received write
> > request for position 1 from (11293)@172.17.20.211:49853
> > I1130 21:08:28.250054   344 leveldb.cpp:341] Persisting action (197
> bytes)
> > to leveldb took 25.784879ms
> > I1130 21:08:28.250156   344 replica.cpp:713] Persisted action at 1
> > I1130 21:08:28.258332   354 replica.cpp:692] Replica received learned
> > notice for position 1 from @0.0.0.0:0
> > I1130 21:08:28.283534   354 leveldb.cpp:341] Persisting action (199
> bytes)
> > to leveldb took 25.027205ms
> > I1130 21:08:28.283632   354 replica.cpp:713] Persisted action at 1
> > I1130 21:08:28.283669   354 replica.cpp:698] Replica learned APPEND
> action
> > at position 1
> > I1130 21:08:28.285953   354 registrar.cpp:484] Successfully updated the
> > 'registry' in 64.059904ms
> > I1130 21:08:28.286386   354 registrar.cpp:370] Successfully recovered
> > registrar
> > I1130 21:08:28.286895   354 master.cpp:1447] Recovered 0 slaves from the
> > Registry (139B) ; allowing 10mins for slaves to re-register
> > I1130 21:08:28.287101   351 hierarchical.cpp:174] Allocator recovery is
> > not supported yet
> > I1130 21:08:28.286087   345 log.cpp:702] Attempting to truncate the log
> to
> > 1
> > I1130 21:08:28.288830   345 coordinator.cpp:348] Coordinator attempting
> to
> > write TRUNCATE action at position 2
> > I1130 21:08:28.290005   350 replica.cpp:538] Replica received write
> > request for position 2 from (11294)@172.17.20.211:49853
> > I1130 21:08:28.316994   350 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 25.725069ms
> > I1130 21:08:28.317087   350 replica.cpp:713] Persisted action at 2
> > I1130 21:08:28.318534   358 replica.cpp:692] Replica received learned
> > notice for position 2 from @0.0.0.0:0
> > I1130 21:08:28.342185   358 leveldb.cpp:341] Persisting action (18 bytes)
> > to leveldb took 23.521347ms
> > I1130 21:08:28.342352   358 leveldb.cpp:399] Deleting ~1 keys from
> leveldb
> > took 66119ns
> > I1130 21:08:28.342381   358 replica.cpp:713] Persisted action at 2
> > I1130 21:08:28.342417   358 replica.cpp:698] Replica learned TRUNCATE
> > action at position 2
> > I1130 21:08:28.349741   324 scheduler.cpp:154] Version: 0.27.0
> > I1130 21:08:28.350648   356 scheduler.cpp:236] New master detected at
> > master@172.17.20.211:49853
> > I1130 21:08:28.363270   351 scheduler.cpp:298] Sending SUBSCRIBE call to
> > master@172.17.20.211:49853
> > I1130 21:08:28.366402   351 process.cpp:3067] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1130 21:08:28.366574   351 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.20.211:54631
> > I1130 21:08:28.367025   351 master.cpp:1899] Received subscription
> request
> > for HTTP framework 'default'
> > I1130 21:08:28.367185   351 master.cpp:1676] Authorizing framework
> > principal 'test-principal' to receive offers for role '*'
> > I1130 21:08:28.367882   351 master.cpp:1991] Subscribing framework
> > 'default' with checkpointing disabled and capabilities [  ]
> > I1130 21:08:28.369591   351 hierarchical.cpp:220] Added framework
> > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > I1130 21:08:28.369653   351 hierarchical.cpp:1064] No resources available
> > to allocate!
> > I1130 21:08:28.369691   351 hierarchical.cpp:1157] No inverse offers to
> > send out!
> > I1130 21:08:28.369715   351 hierarchical.cpp:959] Performed allocation
> for
> > 0 slaves in 97414ns
> > I1130 21:08:28.369966   354 master.hpp:1542] Sending heartbeat to
> > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > I1130 21:08:28.371633   354 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> > received from master@172.17.20.211:49853
> > I1130 21:08:28.372498   358 scheduler.cpp:457] Enqueuing event HEARTBEAT
> > received from master@172.17.20.211:49853
> > I1130 21:08:28.373020   358 master_maintenance_tests.cpp:172] Ignoring
> > HEARTBEAT event
> > I1130 21:08:28.373692   353 scheduler.cpp:298] Sending REQUEST call to
> > master@172.17.20.211:49853
> > I1130 21:08:28.375929   352 process.cpp:3067] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1130 21:08:28.375993   352 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.20.211:54632
> > I1130 21:08:28.376310   352 master.cpp:2646] Processing REQUEST call for
> > framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000 (default)
> > I1130 21:08:28.376541   347 hierarchical.cpp:494] Received resource
> > request from framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > I1130 21:08:28.377280   324 master.cpp:938] Master terminating
> > I1130 21:08:28.377461   353 hierarchical.cpp:260] Removed framework
> > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > E1130 21:08:28.378293   343 scheduler.cpp:431] End-Of-File received from
> > master. The master closed the event stream
> > [       OK ] ContentType/SchedulerTest.Request/1 (521 ms)
> > [----------] 22 tests from ContentType/SchedulerTest (16744 ms total)
> >
> > [----------] Global test environment tear-down
> > [==========] 870 tests from 117 test cases ran. (791709 ms total)
> > [  PASSED  ] 869 tests.
> > [  FAILED  ] 1 test, listed below:
> > [  FAILED  ] MasterQuotaTest.AvailableResourcesAfterRescinding
> >
> >  1 FAILED TEST
> >   YOU HAVE 7 DISABLED TESTS
> >
> > make[4]: *** [check-local] Error 1
> > make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[3]: *** [check-am] Error 2
> > make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[2]: *** [check] Error 2
> > make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[1]: *** [check-recursive] Error 1
> > make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> > make: *** [distcheck] Error 1
> > + docker rmi mesos-1448914320-17469
> > Untagged: mesos-1448914320-17469:latest
> > Deleted: da529dd4edda7833eac2b5da57783b3f2e7375a404c6a4fca455a47a56d04a41
> > Deleted: bbda95a5006e24047ed48fd2387163a3158cc6080e978b97bb0c4538f2359019
> > Deleted: 3f4ce9d12df094a2ca1e075487610073e6488b64ce72087dbab8c30705e9f094
> > Deleted: 33a50a7ac53e4ccbb915a987666fa1ee25fd1cc9a61e7bf05170074841b603b3
> > Deleted: c5d1dc8b4f484cd84b2eba7455843b3d89f9ceea2cdf67c65214aa37c816f530
> > Deleted: 732b7e0b2b214c15e1e824c69d13bcdc19ea80d6507beec41925a9919b148fc2
> > Deleted: 77533cc5f4a714a800b9c943273a212fd934ac1ae2d0de6be31eb085832def65
> > Deleted: c1857e128beff6a7d52c8636159969734ddfa5ae6f24a46f902f9751b0e3845e
> > Deleted: 663e544e4e1729dfd29f36448991233dc69491ea1958107d431720951b89cf51
> > Deleted: 03524d3a5ec49d86b43fab3f013937fbf88980b4ff9ff1836d9cf481f5feb83d
> > Deleted: cae2f74aee84aa95e111f5d458d5e6426cc302c660212332170d4026245cf446
> > Deleted: 8d5f7938d6cc8a66e3611c319d98a6413f2386ba9878bd245109b73ea98e24e0
> > Build step 'Execute shell' marked build as failure
> >
>

Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1294

Posted by Benjamin Mahler <be...@gmail.com>.

Thanks for the accountability and fast follow up Alex!

On Tue, Dec 1, 2015 at 3:48 AM, Alex Rukletsov <al...@mesosphere.com> wrote:

> Posted https://reviews.apache.org/r/40818/
>
> On Mon, Nov 30, 2015 at 10:44 PM, Vinod Kone <vi...@apache.org> wrote:
>
> > AlexR?
> >
> > On Mon, Nov 30, 2015 at 1:08 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/1294/changes
> > > >
> > >
> > > Changes:
> > >
> > > [vinodkone] Added Executor->Framework message handler for the Executor
> > > HTTP API.
> > >
> > > ------------------------------------------
> > > [...truncated 149286 lines...]
> > > I1130 21:08:27.330451   355 replica.cpp:713] Persisted action at 4
> > > I1130 21:08:27.330485   355 replica.cpp:698] Replica learned TRUNCATE
> > > action at position 4
> > > E1130 21:08:27.331228   345 scheduler.cpp:431] End-Of-File received
> from
> > > master. The master closed the event stream
> > > I1130 21:08:27.347769   324 slave.cpp:599] Slave terminating
> > > I1130 21:08:27.347898   324 slave.cpp:2010] Asked to shut down
> framework
> > > bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 by @0.0.0.0:0
> > > I1130 21:08:27.347939   324 slave.cpp:2035] Shutting down framework
> > > bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000
> > > I1130 21:08:27.348021   324 slave.cpp:3891] Shutting down executor
> > > 'default' of framework bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 at
> > > executor(132)@172.17.20.211:49853
> > > [       OK ] ContentType/SchedulerTest.Message/1 (683 ms)
> > > [ RUN      ] ContentType/SchedulerTest.Request/0
> > > I1130 21:08:27.479187   324 leveldb.cpp:174] Opened db in 115.296913ms
> > > I1130 21:08:27.511601   324 leveldb.cpp:181] Compacted db in
> 32.327319ms
> > > I1130 21:08:27.511701   324 leveldb.cpp:196] Created db iterator in
> > 29426ns
> > > I1130 21:08:27.511721   324 leveldb.cpp:202] Seeked to beginning of db
> in
> > > 3382ns
> > > I1130 21:08:27.511734   324 leveldb.cpp:271] Iterated through 0 keys in
> > > the db in 384ns
> > > I1130 21:08:27.511795   324 replica.cpp:778] Replica recovered with log
> > > positions 0 -> 0 with 1 holes and 0 unlearned
> > > I1130 21:08:27.512843   355 recover.cpp:447] Starting replica recovery
> > > I1130 21:08:27.513139   355 recover.cpp:473] Replica is in EMPTY status
> > > I1130 21:08:27.514932   355 replica.cpp:674] Replica in EMPTY status
> > > received a broadcasted recover request from (11277)@
> 172.17.20.211:49853
> > > I1130 21:08:27.517248   352 recover.cpp:193] Received a recover
> response
> > > from a replica in EMPTY status
> > > I1130 21:08:27.517969   352 recover.cpp:564] Updating replica status to
> > > STARTING
> > > I1130 21:08:27.523208   344 master.cpp:365] Master
> > > 18a00433-18e1-476f-82d1-5f507c7ba81d (aea03c1ca24e) started on
> > > 172.17.20.211:49853
> > > I1130 21:08:27.523257   344 master.cpp:367] Flags at startup: --acls=""
> > > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > > --authenticate="false" --authenticate_slaves="true"
> > > --authenticators="crammd5" --authorizers="local"
> > > --credentials="/tmp/RbAcoE/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.27.0/_inst/share/mesos/webui"
> > > --work_dir="/tmp/RbAcoE/master" --zk_session_timeout="10secs"
> > > I1130 21:08:27.523615   344 master.cpp:414] Master allowing
> > > unauthenticated frameworks to register
> > > I1130 21:08:27.523629   344 master.cpp:417] Master only allowing
> > > authenticated slaves to register
> > > I1130 21:08:27.523638   344 credentials.hpp:35] Loading credentials for
> > > authentication from '/tmp/RbAcoE/credentials'
> > > I1130 21:08:27.523980   344 master.cpp:456] Using default 'crammd5'
> > > authenticator
> > > I1130 21:08:27.524160   344 master.cpp:493] Authorization enabled
> > > I1130 21:08:27.525506   350 hierarchical.cpp:162] Initialized
> > hierarchical
> > > allocator process
> > > I1130 21:08:27.525606   350 whitelist_watcher.cpp:77] No whitelist
> given
> > > I1130 21:08:27.527474   350 master.cpp:1637] The newly elected leader
> is
> > > master@172.17.20.211:49853 with id
> 18a00433-18e1-476f-82d1-5f507c7ba81d
> > > I1130 21:08:27.527525   350 master.cpp:1650] Elected as the leading
> > master!
> > > I1130 21:08:27.527546   350 master.cpp:1395] Recovering from registrar
> > > I1130 21:08:27.527820   350 registrar.cpp:307] Recovering registrar
> > > I1130 21:08:27.546037   352 leveldb.cpp:304] Persisting metadata (8
> > bytes)
> > > to leveldb took 27.807666ms
> > > I1130 21:08:27.546118   352 replica.cpp:321] Persisted replica status
> to
> > > STARTING
> > > I1130 21:08:27.546471   352 recover.cpp:473] Replica is in STARTING
> > status
> > > I1130 21:08:27.548519   352 replica.cpp:674] Replica in STARTING status
> > > received a broadcasted recover request from (11278)@
> 172.17.20.211:49853
> > > I1130 21:08:27.548904   352 recover.cpp:193] Received a recover
> response
> > > from a replica in STARTING status
> > > I1130 21:08:27.549509   350 recover.cpp:564] Updating replica status to
> > > VOTING
> > > I1130 21:08:27.571252   350 leveldb.cpp:304] Persisting metadata (8
> > bytes)
> > > to leveldb took 21.409051ms
> > > I1130 21:08:27.571336   350 replica.cpp:321] Persisted replica status
> to
> > > VOTING
> > > I1130 21:08:27.571596   350 recover.cpp:578] Successfully joined the
> > Paxos
> > > group
> > > I1130 21:08:27.571888   350 recover.cpp:462] Recover process terminated
> > > I1130 21:08:27.572474   358 log.cpp:659] Attempting to start the writer
> > > I1130 21:08:27.573999   358 replica.cpp:494] Replica received implicit
> > > promise request from (11279)@172.17.20.211:49853 with proposal 1
> > > I1130 21:08:27.596279   358 leveldb.cpp:304] Persisting metadata (8
> > bytes)
> > > to leveldb took 22.224107ms
> > > I1130 21:08:27.596366   358 replica.cpp:343] Persisted promised to 1
> > > I1130 21:08:27.597810   343 coordinator.cpp:238] Coordinator attempting
> > to
> > > fill missing positions
> > > I1130 21:08:27.600751   343 replica.cpp:389] Replica received explicit
> > > promise request from (11280)@172.17.20.211:49853 for position 0 with
> > > proposal 2
> > > I1130 21:08:27.622263   343 leveldb.cpp:341] Persisting action (8
> bytes)
> > > to leveldb took 21.312087ms
> > > I1130 21:08:27.622346   343 replica.cpp:713] Persisted action at 0
> > > I1130 21:08:27.624104   346 replica.cpp:538] Replica received write
> > > request for position 0 from (11281)@172.17.20.211:49853
> > > I1130 21:08:27.624258   346 leveldb.cpp:436] Reading position from
> > leveldb
> > > took 107729ns
> > > I1130 21:08:27.646852   346 leveldb.cpp:341] Persisting action (14
> bytes)
> > > to leveldb took 22.452736ms
> > > I1130 21:08:27.646936   346 replica.cpp:713] Persisted action at 0
> > > I1130 21:08:27.648002   346 replica.cpp:692] Replica received learned
> > > notice for position 0 from @0.0.0.0:0
> > > I1130 21:08:27.678287   346 leveldb.cpp:341] Persisting action (16
> bytes)
> > > to leveldb took 30.230306ms
> > > I1130 21:08:27.678381   346 replica.cpp:713] Persisted action at 0
> > > I1130 21:08:27.678421   346 replica.cpp:698] Replica learned NOP action
> > at
> > > position 0
> > > I1130 21:08:27.679606   347 log.cpp:675] Writer started with ending
> > > position 0
> > > I1130 21:08:27.681371   347 leveldb.cpp:436] Reading position from
> > leveldb
> > > took 70404ns
> > > I1130 21:08:27.682898   347 registrar.cpp:340] Successfully fetched the
> > > registry (0B) in 155.027968ms
> > > I1130 21:08:27.683078   347 registrar.cpp:439] Applied 1 operations in
> > > 54140ns; attempting to update the 'registry'
> > > I1130 21:08:27.684787   344 log.cpp:683] Attempting to append 178 bytes
> > to
> > > the log
> > > I1130 21:08:27.685065   347 coordinator.cpp:348] Coordinator attempting
> > to
> > > write APPEND action at position 1
> > > I1130 21:08:27.686206   348 replica.cpp:538] Replica received write
> > > request for position 1 from (11282)@172.17.20.211:49853
> > > I1130 21:08:27.722992   348 leveldb.cpp:341] Persisting action (197
> > bytes)
> > > to leveldb took 36.707093ms
> > > I1130 21:08:27.723076   348 replica.cpp:713] Persisted action at 1
> > > I1130 21:08:27.724438   345 replica.cpp:692] Replica received learned
> > > notice for position 1 from @0.0.0.0:0
> > > I1130 21:08:27.770239   345 leveldb.cpp:341] Persisting action (199
> > bytes)
> > > to leveldb took 45.745553ms
> > > I1130 21:08:27.770336   345 replica.cpp:713] Persisted action at 1
> > > I1130 21:08:27.770376   345 replica.cpp:698] Replica learned APPEND
> > action
> > > at position 1
> > > I1130 21:08:27.772614   356 registrar.cpp:484] Successfully updated the
> > > 'registry' in 89.427968ms
> > > I1130 21:08:27.772789   356 registrar.cpp:370] Successfully recovered
> > > registrar
> > > I1130 21:08:27.773059   356 log.cpp:702] Attempting to truncate the log
> > to
> > > 1
> > > I1130 21:08:27.773519   358 master.cpp:1447] Recovered 0 slaves from
> the
> > > Registry (139B) ; allowing 10mins for slaves to re-register
> > > I1130 21:08:27.773712   358 hierarchical.cpp:174] Allocator recovery is
> > > not supported yet
> > > I1130 21:08:27.774319   349 coordinator.cpp:348] Coordinator attempting
> > to
> > > write TRUNCATE action at position 2
> > > I1130 21:08:27.776000   356 replica.cpp:538] Replica received write
> > > request for position 2 from (11283)@172.17.20.211:49853
> > > I1130 21:08:27.806820   356 leveldb.cpp:341] Persisting action (16
> bytes)
> > > to leveldb took 30.743154ms
> > > I1130 21:08:27.806915   356 replica.cpp:713] Persisted action at 2
> > > I1130 21:08:27.808435   349 replica.cpp:692] Replica received learned
> > > notice for position 2 from @0.0.0.0:0
> > > I1130 21:08:27.840296   349 leveldb.cpp:341] Persisting action (18
> bytes)
> > > to leveldb took 31.73806ms
> > > I1130 21:08:27.840463   349 leveldb.cpp:399] Deleting ~1 keys from
> > leveldb
> > > took 77442ns
> > > I1130 21:08:27.840493   349 replica.cpp:713] Persisted action at 2
> > > I1130 21:08:27.840531   349 replica.cpp:698] Replica learned TRUNCATE
> > > action at position 2
> > > I1130 21:08:27.845356   324 scheduler.cpp:154] Version: 0.27.0
> > > I1130 21:08:27.846367   349 scheduler.cpp:236] New master detected at
> > > master@172.17.20.211:49853
> > > I1130 21:08:27.847790   356 scheduler.cpp:298] Sending SUBSCRIBE call
> to
> > > master@172.17.20.211:49853
> > > I1130 21:08:27.849529   356 process.cpp:3067] Handling HTTP event for
> > > process 'master' with path: '/master/api/v1/scheduler'
> > > I1130 21:08:27.849588   356 http.cpp:336] HTTP POST for
> > > /master/api/v1/scheduler from 172.17.20.211:54626
> > > I1130 21:08:27.849788   356 master.cpp:1899] Received subscription
> > request
> > > for HTTP framework 'default'
> > > I1130 21:08:27.849864   356 master.cpp:1676] Authorizing framework
> > > principal 'test-principal' to receive offers for role '*'
> > > I1130 21:08:27.850175   357 master.cpp:1991] Subscribing framework
> > > 'default' with checkpointing disabled and capabilities [  ]
> > > I1130 21:08:27.850682   344 hierarchical.cpp:220] Added framework
> > > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > > I1130 21:08:27.850739   344 hierarchical.cpp:1064] No resources
> available
> > > to allocate!
> > > I1130 21:08:27.850770   344 hierarchical.cpp:1157] No inverse offers to
> > > send out!
> > > I1130 21:08:27.850793   344 hierarchical.cpp:959] Performed allocation
> > for
> > > 0 slaves in 86690ns
> > > I1130 21:08:27.850900   357 master.hpp:1542] Sending heartbeat to
> > > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > > I1130 21:08:27.852912   347 scheduler.cpp:457] Enqueuing event
> SUBSCRIBED
> > > received from master@172.17.20.211:49853
> > > I1130 21:08:27.853420   347 scheduler.cpp:457] Enqueuing event
> HEARTBEAT
> > > received from master@172.17.20.211:49853
> > > I1130 21:08:27.853988   350 master_maintenance_tests.cpp:172] Ignoring
> > > HEARTBEAT event
> > > I1130 21:08:27.854672   358 scheduler.cpp:298] Sending REQUEST call to
> > > master@172.17.20.211:49853
> > > I1130 21:08:27.856593   353 process.cpp:3067] Handling HTTP event for
> > > process 'master' with path: '/master/api/v1/scheduler'
> > > I1130 21:08:27.856719   353 http.cpp:336] HTTP POST for
> > > /master/api/v1/scheduler from 172.17.20.211:54627
> > > I1130 21:08:27.856889   353 master.cpp:2646] Processing REQUEST call
> for
> > > framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000 (default)
> > > I1130 21:08:27.857241   353 hierarchical.cpp:494] Received resource
> > > request from framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > > I1130 21:08:27.857846   324 master.cpp:938] Master terminating
> > > I1130 21:08:27.858505   349 hierarchical.cpp:260] Removed framework
> > > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > > E1130 21:08:27.862787   353 scheduler.cpp:431] End-Of-File received
> from
> > > master. The master closed the event stream
> > > [       OK ] ContentType/SchedulerTest.Request/0 (525 ms)
> > > [ RUN      ] ContentType/SchedulerTest.Request/1
> > > I1130 21:08:27.981688   324 leveldb.cpp:174] Opened db in 96.943089ms
> > > I1130 21:08:28.008204   324 leveldb.cpp:181] Compacted db in
> 26.429482ms
> > > I1130 21:08:28.008306   324 leveldb.cpp:196] Created db iterator in
> > 25911ns
> > > I1130 21:08:28.008329   324 leveldb.cpp:202] Seeked to beginning of db
> in
> > > 3856ns
> > > I1130 21:08:28.008342   324 leveldb.cpp:271] Iterated through 0 keys in
> > > the db in 341ns
> > > I1130 21:08:28.008400   324 replica.cpp:778] Replica recovered with log
> > > positions 0 -> 0 with 1 holes and 0 unlearned
> > > I1130 21:08:28.009362   345 recover.cpp:447] Starting replica recovery
> > > I1130 21:08:28.009794   345 recover.cpp:473] Replica is in EMPTY status
> > > I1130 21:08:28.011524   348 replica.cpp:674] Replica in EMPTY status
> > > received a broadcasted recover request from (11287)@
> 172.17.20.211:49853
> > > I1130 21:08:28.012024   345 recover.cpp:193] Received a recover
> response
> > > from a replica in EMPTY status
> > > I1130 21:08:28.012697   345 recover.cpp:564] Updating replica status to
> > > STARTING
> > > I1130 21:08:28.028069   353 master.cpp:365] Master
> > > cf6bff68-54b0-4223-a1f0-cef4ceeb004d (aea03c1ca24e) started on
> > > 172.17.20.211:49853
> > > I1130 21:08:28.028102   353 master.cpp:367] Flags at startup: --acls=""
> > > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > > --authenticate="false" --authenticate_slaves="true"
> > > --authenticators="crammd5" --authorizers="local"
> > > --credentials="/tmp/RQ8lBH/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.27.0/_inst/share/mesos/webui"
> > > --work_dir="/tmp/RQ8lBH/master" --zk_session_timeout="10secs"
> > > I1130 21:08:28.028492   353 master.cpp:414] Master allowing
> > > unauthenticated frameworks to register
> > > I1130 21:08:28.028504   353 master.cpp:417] Master only allowing
> > > authenticated slaves to register
> > > I1130 21:08:28.028513   353 credentials.hpp:35] Loading credentials for
> > > authentication from '/tmp/RQ8lBH/credentials'
> > > I1130 21:08:28.038077   353 master.cpp:456] Using default 'crammd5'
> > > authenticator
> > > I1130 21:08:28.038632   353 master.cpp:493] Authorization enabled
> > > I1130 21:08:28.039350   346 hierarchical.cpp:162] Initialized
> > hierarchical
> > > allocator process
> > > I1130 21:08:28.039631   346 whitelist_watcher.cpp:77] No whitelist
> given
> > > I1130 21:08:28.044158   353 master.cpp:1637] The newly elected leader
> is
> > > master@172.17.20.211:49853 with id
> cf6bff68-54b0-4223-a1f0-cef4ceeb004d
> > > I1130 21:08:28.044719   353 master.cpp:1650] Elected as the leading
> > master!
> > > I1130 21:08:28.044865   353 master.cpp:1395] Recovering from registrar
> > > I1130 21:08:28.045243   357 registrar.cpp:307] Recovering registrar
> > > I1130 21:08:28.046226   345 leveldb.cpp:304] Persisting metadata (8
> > bytes)
> > > to leveldb took 33.160654ms
> > > I1130 21:08:28.046283   345 replica.cpp:321] Persisted replica status
> to
> > > STARTING
> > > I1130 21:08:28.046551   345 recover.cpp:473] Replica is in STARTING
> > status
> > > I1130 21:08:28.048651   345 replica.cpp:674] Replica in STARTING status
> > > received a broadcasted recover request from (11289)@
> 172.17.20.211:49853
> > > I1130 21:08:28.049242   355 recover.cpp:193] Received a recover
> response
> > > from a replica in STARTING status
> > > I1130 21:08:28.050812   355 recover.cpp:564] Updating replica status to
> > > VOTING
> > > I1130 21:08:28.082262   357 leveldb.cpp:304] Persisting metadata (8
> > bytes)
> > > to leveldb took 30.981671ms
> > > I1130 21:08:28.082348   357 replica.cpp:321] Persisted replica status
> to
> > > VOTING
> > > I1130 21:08:28.082595   357 recover.cpp:578] Successfully joined the
> > Paxos
> > > group
> > > I1130 21:08:28.082778   357 recover.cpp:462] Recover process terminated
> > > I1130 21:08:28.083708   348 log.cpp:659] Attempting to start the writer
> > > I1130 21:08:28.085435   348 replica.cpp:494] Replica received implicit
> > > promise request from (11290)@172.17.20.211:49853 with proposal 1
> > > I1130 21:08:28.133262   348 leveldb.cpp:304] Persisting metadata (8
> > bytes)
> > > to leveldb took 47.708066ms
> > > I1130 21:08:28.133359   348 replica.cpp:343] Persisted promised to 1
> > > I1130 21:08:28.134845   348 coordinator.cpp:238] Coordinator attempting
> > to
> > > fill missing positions
> > > I1130 21:08:28.136824   348 replica.cpp:389] Replica received explicit
> > > promise request from (11291)@172.17.20.211:49853 for position 0 with
> > > proposal 2
> > > I1130 21:08:28.166779   348 leveldb.cpp:341] Persisting action (8
> bytes)
> > > to leveldb took 29.742663ms
> > > I1130 21:08:28.166870   348 replica.cpp:713] Persisted action at 0
> > > I1130 21:08:28.168622   352 replica.cpp:538] Replica received write
> > > request for position 0 from (11292)@172.17.20.211:49853
> > > I1130 21:08:28.168822   352 leveldb.cpp:436] Reading position from
> > leveldb
> > > took 40320ns
> > > I1130 21:08:28.191473   352 leveldb.cpp:341] Persisting action (14
> bytes)
> > > to leveldb took 22.491006ms
> > > I1130 21:08:28.191576   352 replica.cpp:713] Persisted action at 0
> > > I1130 21:08:28.192988   344 replica.cpp:692] Replica received learned
> > > notice for position 0 from @0.0.0.0:0
> > > I1130 21:08:28.216675   344 leveldb.cpp:341] Persisting action (16
> bytes)
> > > to leveldb took 23.06125ms
> > > I1130 21:08:28.216773   344 replica.cpp:713] Persisted action at 0
> > > I1130 21:08:28.216810   344 replica.cpp:698] Replica learned NOP action
> > at
> > > position 0
> > > I1130 21:08:28.217905   355 log.cpp:675] Writer started with ending
> > > position 0
> > > I1130 21:08:28.219653   355 leveldb.cpp:436] Reading position from
> > leveldb
> > > took 52180ns
> > > I1130 21:08:28.221421   344 registrar.cpp:340] Successfully fetched the
> > > registry (0B) in 176.124928ms
> > > I1130 21:08:28.221719   344 registrar.cpp:439] Applied 1 operations in
> > > 39055ns; attempting to update the 'registry'
> > > I1130 21:08:28.222777   350 log.cpp:683] Attempting to append 178 bytes
> > to
> > > the log
> > > I1130 21:08:28.223114   343 coordinator.cpp:348] Coordinator attempting
> > to
> > > write APPEND action at position 1
> > > I1130 21:08:28.224201   344 replica.cpp:538] Replica received write
> > > request for position 1 from (11293)@172.17.20.211:49853
> > > I1130 21:08:28.250054   344 leveldb.cpp:341] Persisting action (197
> > bytes)
> > > to leveldb took 25.784879ms
> > > I1130 21:08:28.250156   344 replica.cpp:713] Persisted action at 1
> > > I1130 21:08:28.258332   354 replica.cpp:692] Replica received learned
> > > notice for position 1 from @0.0.0.0:0
> > > I1130 21:08:28.283534   354 leveldb.cpp:341] Persisting action (199
> > bytes)
> > > to leveldb took 25.027205ms
> > > I1130 21:08:28.283632   354 replica.cpp:713] Persisted action at 1
> > > I1130 21:08:28.283669   354 replica.cpp:698] Replica learned APPEND
> > action
> > > at position 1
> > > I1130 21:08:28.285953   354 registrar.cpp:484] Successfully updated the
> > > 'registry' in 64.059904ms
> > > I1130 21:08:28.286386   354 registrar.cpp:370] Successfully recovered
> > > registrar
> > > I1130 21:08:28.286895   354 master.cpp:1447] Recovered 0 slaves from
> the
> > > Registry (139B) ; allowing 10mins for slaves to re-register
> > > I1130 21:08:28.287101   351 hierarchical.cpp:174] Allocator recovery is
> > > not supported yet
> > > I1130 21:08:28.286087   345 log.cpp:702] Attempting to truncate the log
> > to
> > > 1
> > > I1130 21:08:28.288830   345 coordinator.cpp:348] Coordinator attempting
> > to
> > > write TRUNCATE action at position 2
> > > I1130 21:08:28.290005   350 replica.cpp:538] Replica received write
> > > request for position 2 from (11294)@172.17.20.211:49853
> > > I1130 21:08:28.316994   350 leveldb.cpp:341] Persisting action (16
> bytes)
> > > to leveldb took 25.725069ms
> > > I1130 21:08:28.317087   350 replica.cpp:713] Persisted action at 2
> > > I1130 21:08:28.318534   358 replica.cpp:692] Replica received learned
> > > notice for position 2 from @0.0.0.0:0
> > > I1130 21:08:28.342185   358 leveldb.cpp:341] Persisting action (18
> bytes)
> > > to leveldb took 23.521347ms
> > > I1130 21:08:28.342352   358 leveldb.cpp:399] Deleting ~1 keys from
> > leveldb
> > > took 66119ns
> > > I1130 21:08:28.342381   358 replica.cpp:713] Persisted action at 2
> > > I1130 21:08:28.342417   358 replica.cpp:698] Replica learned TRUNCATE
> > > action at position 2
> > > I1130 21:08:28.349741   324 scheduler.cpp:154] Version: 0.27.0
> > > I1130 21:08:28.350648   356 scheduler.cpp:236] New master detected at
> > > master@172.17.20.211:49853
> > > I1130 21:08:28.363270   351 scheduler.cpp:298] Sending SUBSCRIBE call
> to
> > > master@172.17.20.211:49853
> > > I1130 21:08:28.366402   351 process.cpp:3067] Handling HTTP event for
> > > process 'master' with path: '/master/api/v1/scheduler'
> > > I1130 21:08:28.366574   351 http.cpp:336] HTTP POST for
> > > /master/api/v1/scheduler from 172.17.20.211:54631
> > > I1130 21:08:28.367025   351 master.cpp:1899] Received subscription
> > request
> > > for HTTP framework 'default'
> > > I1130 21:08:28.367185   351 master.cpp:1676] Authorizing framework
> > > principal 'test-principal' to receive offers for role '*'
> > > I1130 21:08:28.367882   351 master.cpp:1991] Subscribing framework
> > > 'default' with checkpointing disabled and capabilities [  ]
> > > I1130 21:08:28.369591   351 hierarchical.cpp:220] Added framework
> > > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > > I1130 21:08:28.369653   351 hierarchical.cpp:1064] No resources
> available
> > > to allocate!
> > > I1130 21:08:28.369691   351 hierarchical.cpp:1157] No inverse offers to
> > > send out!
> > > I1130 21:08:28.369715   351 hierarchical.cpp:959] Performed allocation
> > for
> > > 0 slaves in 97414ns
> > > I1130 21:08:28.369966   354 master.hpp:1542] Sending heartbeat to
> > > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > > I1130 21:08:28.371633   354 scheduler.cpp:457] Enqueuing event
> SUBSCRIBED
> > > received from master@172.17.20.211:49853
> > > I1130 21:08:28.372498   358 scheduler.cpp:457] Enqueuing event
> HEARTBEAT
> > > received from master@172.17.20.211:49853
> > > I1130 21:08:28.373020   358 master_maintenance_tests.cpp:172] Ignoring
> > > HEARTBEAT event
> > > I1130 21:08:28.373692   353 scheduler.cpp:298] Sending REQUEST call to
> > > master@172.17.20.211:49853
> > > I1130 21:08:28.375929   352 process.cpp:3067] Handling HTTP event for
> > > process 'master' with path: '/master/api/v1/scheduler'
> > > I1130 21:08:28.375993   352 http.cpp:336] HTTP POST for
> > > /master/api/v1/scheduler from 172.17.20.211:54632
> > > I1130 21:08:28.376310   352 master.cpp:2646] Processing REQUEST call
> for
> > > framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000 (default)
> > > I1130 21:08:28.376541   347 hierarchical.cpp:494] Received resource
> > > request from framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > > I1130 21:08:28.377280   324 master.cpp:938] Master terminating
> > > I1130 21:08:28.377461   353 hierarchical.cpp:260] Removed framework
> > > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > > E1130 21:08:28.378293   343 scheduler.cpp:431] End-Of-File received
> from
> > > master. The master closed the event stream
> > > [       OK ] ContentType/SchedulerTest.Request/1 (521 ms)
> > > [----------] 22 tests from ContentType/SchedulerTest (16744 ms total)
> > >
> > > [----------] Global test environment tear-down
> > > [==========] 870 tests from 117 test cases ran. (791709 ms total)
> > > [  PASSED  ] 869 tests.
> > > [  FAILED  ] 1 test, listed below:
> > > [  FAILED  ] MasterQuotaTest.AvailableResourcesAfterRescinding
> > >
> > >  1 FAILED TEST
> > >   YOU HAVE 7 DISABLED TESTS
> > >
> > > make[4]: *** [check-local] Error 1
> > > make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > > make[3]: *** [check-am] Error 2
> > > make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > > make[2]: *** [check] Error 2
> > > make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > > make[1]: *** [check-recursive] Error 1
> > > make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> > > make: *** [distcheck] Error 1
> > > + docker rmi mesos-1448914320-17469
> > > Untagged: mesos-1448914320-17469:latest
> > > Deleted:
> da529dd4edda7833eac2b5da57783b3f2e7375a404c6a4fca455a47a56d04a41
> > > Deleted:
> bbda95a5006e24047ed48fd2387163a3158cc6080e978b97bb0c4538f2359019
> > > Deleted:
> 3f4ce9d12df094a2ca1e075487610073e6488b64ce72087dbab8c30705e9f094
> > > Deleted:
> 33a50a7ac53e4ccbb915a987666fa1ee25fd1cc9a61e7bf05170074841b603b3
> > > Deleted:
> c5d1dc8b4f484cd84b2eba7455843b3d89f9ceea2cdf67c65214aa37c816f530
> > > Deleted:
> 732b7e0b2b214c15e1e824c69d13bcdc19ea80d6507beec41925a9919b148fc2
> > > Deleted:
> 77533cc5f4a714a800b9c943273a212fd934ac1ae2d0de6be31eb085832def65
> > > Deleted:
> c1857e128beff6a7d52c8636159969734ddfa5ae6f24a46f902f9751b0e3845e
> > > Deleted:
> 663e544e4e1729dfd29f36448991233dc69491ea1958107d431720951b89cf51
> > > Deleted:
> 03524d3a5ec49d86b43fab3f013937fbf88980b4ff9ff1836d9cf481f5feb83d
> > > Deleted:
> cae2f74aee84aa95e111f5d458d5e6426cc302c660212332170d4026245cf446
> > > Deleted:
> 8d5f7938d6cc8a66e3611c319d98a6413f2386ba9878bd245109b73ea98e24e0
> > > Build step 'Execute shell' marked build as failure
> > >
> >
>

Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1294

Posted by Alex Rukletsov <al...@mesosphere.com>.

Posted https://reviews.apache.org/r/40818/

On Mon, Nov 30, 2015 at 10:44 PM, Vinod Kone <vi...@apache.org> wrote:

> AlexR?
>
> On Mon, Nov 30, 2015 at 1:08 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/1294/changes
> > >
> >
> > Changes:
> >
> > [vinodkone] Added Executor->Framework message handler for the Executor
> > HTTP API.
> >
> > ------------------------------------------
> > [...truncated 149286 lines...]
> > I1130 21:08:27.330451   355 replica.cpp:713] Persisted action at 4
> > I1130 21:08:27.330485   355 replica.cpp:698] Replica learned TRUNCATE
> > action at position 4
> > E1130 21:08:27.331228   345 scheduler.cpp:431] End-Of-File received from
> > master. The master closed the event stream
> > I1130 21:08:27.347769   324 slave.cpp:599] Slave terminating
> > I1130 21:08:27.347898   324 slave.cpp:2010] Asked to shut down framework
> > bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 by @0.0.0.0:0
> > I1130 21:08:27.347939   324 slave.cpp:2035] Shutting down framework
> > bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000
> > I1130 21:08:27.348021   324 slave.cpp:3891] Shutting down executor
> > 'default' of framework bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 at
> > executor(132)@172.17.20.211:49853
> > [       OK ] ContentType/SchedulerTest.Message/1 (683 ms)
> > [ RUN      ] ContentType/SchedulerTest.Request/0
> > I1130 21:08:27.479187   324 leveldb.cpp:174] Opened db in 115.296913ms
> > I1130 21:08:27.511601   324 leveldb.cpp:181] Compacted db in 32.327319ms
> > I1130 21:08:27.511701   324 leveldb.cpp:196] Created db iterator in
> 29426ns
> > I1130 21:08:27.511721   324 leveldb.cpp:202] Seeked to beginning of db in
> > 3382ns
> > I1130 21:08:27.511734   324 leveldb.cpp:271] Iterated through 0 keys in
> > the db in 384ns
> > I1130 21:08:27.511795   324 replica.cpp:778] Replica recovered with log
> > positions 0 -> 0 with 1 holes and 0 unlearned
> > I1130 21:08:27.512843   355 recover.cpp:447] Starting replica recovery
> > I1130 21:08:27.513139   355 recover.cpp:473] Replica is in EMPTY status
> > I1130 21:08:27.514932   355 replica.cpp:674] Replica in EMPTY status
> > received a broadcasted recover request from (11277)@172.17.20.211:49853
> > I1130 21:08:27.517248   352 recover.cpp:193] Received a recover response
> > from a replica in EMPTY status
> > I1130 21:08:27.517969   352 recover.cpp:564] Updating replica status to
> > STARTING
> > I1130 21:08:27.523208   344 master.cpp:365] Master
> > 18a00433-18e1-476f-82d1-5f507c7ba81d (aea03c1ca24e) started on
> > 172.17.20.211:49853
> > I1130 21:08:27.523257   344 master.cpp:367] Flags at startup: --acls=""
> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > --authenticate="false" --authenticate_slaves="true"
> > --authenticators="crammd5" --authorizers="local"
> > --credentials="/tmp/RbAcoE/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.27.0/_inst/share/mesos/webui"
> > --work_dir="/tmp/RbAcoE/master" --zk_session_timeout="10secs"
> > I1130 21:08:27.523615   344 master.cpp:414] Master allowing
> > unauthenticated frameworks to register
> > I1130 21:08:27.523629   344 master.cpp:417] Master only allowing
> > authenticated slaves to register
> > I1130 21:08:27.523638   344 credentials.hpp:35] Loading credentials for
> > authentication from '/tmp/RbAcoE/credentials'
> > I1130 21:08:27.523980   344 master.cpp:456] Using default 'crammd5'
> > authenticator
> > I1130 21:08:27.524160   344 master.cpp:493] Authorization enabled
> > I1130 21:08:27.525506   350 hierarchical.cpp:162] Initialized
> hierarchical
> > allocator process
> > I1130 21:08:27.525606   350 whitelist_watcher.cpp:77] No whitelist given
> > I1130 21:08:27.527474   350 master.cpp:1637] The newly elected leader is
> > master@172.17.20.211:49853 with id 18a00433-18e1-476f-82d1-5f507c7ba81d
> > I1130 21:08:27.527525   350 master.cpp:1650] Elected as the leading
> master!
> > I1130 21:08:27.527546   350 master.cpp:1395] Recovering from registrar
> > I1130 21:08:27.527820   350 registrar.cpp:307] Recovering registrar
> > I1130 21:08:27.546037   352 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 27.807666ms
> > I1130 21:08:27.546118   352 replica.cpp:321] Persisted replica status to
> > STARTING
> > I1130 21:08:27.546471   352 recover.cpp:473] Replica is in STARTING
> status
> > I1130 21:08:27.548519   352 replica.cpp:674] Replica in STARTING status
> > received a broadcasted recover request from (11278)@172.17.20.211:49853
> > I1130 21:08:27.548904   352 recover.cpp:193] Received a recover response
> > from a replica in STARTING status
> > I1130 21:08:27.549509   350 recover.cpp:564] Updating replica status to
> > VOTING
> > I1130 21:08:27.571252   350 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 21.409051ms
> > I1130 21:08:27.571336   350 replica.cpp:321] Persisted replica status to
> > VOTING
> > I1130 21:08:27.571596   350 recover.cpp:578] Successfully joined the
> Paxos
> > group
> > I1130 21:08:27.571888   350 recover.cpp:462] Recover process terminated
> > I1130 21:08:27.572474   358 log.cpp:659] Attempting to start the writer
> > I1130 21:08:27.573999   358 replica.cpp:494] Replica received implicit
> > promise request from (11279)@172.17.20.211:49853 with proposal 1
> > I1130 21:08:27.596279   358 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 22.224107ms
> > I1130 21:08:27.596366   358 replica.cpp:343] Persisted promised to 1
> > I1130 21:08:27.597810   343 coordinator.cpp:238] Coordinator attempting
> to
> > fill missing positions
> > I1130 21:08:27.600751   343 replica.cpp:389] Replica received explicit
> > promise request from (11280)@172.17.20.211:49853 for position 0 with
> > proposal 2
> > I1130 21:08:27.622263   343 leveldb.cpp:341] Persisting action (8 bytes)
> > to leveldb took 21.312087ms
> > I1130 21:08:27.622346   343 replica.cpp:713] Persisted action at 0
> > I1130 21:08:27.624104   346 replica.cpp:538] Replica received write
> > request for position 0 from (11281)@172.17.20.211:49853
> > I1130 21:08:27.624258   346 leveldb.cpp:436] Reading position from
> leveldb
> > took 107729ns
> > I1130 21:08:27.646852   346 leveldb.cpp:341] Persisting action (14 bytes)
> > to leveldb took 22.452736ms
> > I1130 21:08:27.646936   346 replica.cpp:713] Persisted action at 0
> > I1130 21:08:27.648002   346 replica.cpp:692] Replica received learned
> > notice for position 0 from @0.0.0.0:0
> > I1130 21:08:27.678287   346 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 30.230306ms
> > I1130 21:08:27.678381   346 replica.cpp:713] Persisted action at 0
> > I1130 21:08:27.678421   346 replica.cpp:698] Replica learned NOP action
> at
> > position 0
> > I1130 21:08:27.679606   347 log.cpp:675] Writer started with ending
> > position 0
> > I1130 21:08:27.681371   347 leveldb.cpp:436] Reading position from
> leveldb
> > took 70404ns
> > I1130 21:08:27.682898   347 registrar.cpp:340] Successfully fetched the
> > registry (0B) in 155.027968ms
> > I1130 21:08:27.683078   347 registrar.cpp:439] Applied 1 operations in
> > 54140ns; attempting to update the 'registry'
> > I1130 21:08:27.684787   344 log.cpp:683] Attempting to append 178 bytes
> to
> > the log
> > I1130 21:08:27.685065   347 coordinator.cpp:348] Coordinator attempting
> to
> > write APPEND action at position 1
> > I1130 21:08:27.686206   348 replica.cpp:538] Replica received write
> > request for position 1 from (11282)@172.17.20.211:49853
> > I1130 21:08:27.722992   348 leveldb.cpp:341] Persisting action (197
> bytes)
> > to leveldb took 36.707093ms
> > I1130 21:08:27.723076   348 replica.cpp:713] Persisted action at 1
> > I1130 21:08:27.724438   345 replica.cpp:692] Replica received learned
> > notice for position 1 from @0.0.0.0:0
> > I1130 21:08:27.770239   345 leveldb.cpp:341] Persisting action (199
> bytes)
> > to leveldb took 45.745553ms
> > I1130 21:08:27.770336   345 replica.cpp:713] Persisted action at 1
> > I1130 21:08:27.770376   345 replica.cpp:698] Replica learned APPEND
> action
> > at position 1
> > I1130 21:08:27.772614   356 registrar.cpp:484] Successfully updated the
> > 'registry' in 89.427968ms
> > I1130 21:08:27.772789   356 registrar.cpp:370] Successfully recovered
> > registrar
> > I1130 21:08:27.773059   356 log.cpp:702] Attempting to truncate the log
> to
> > 1
> > I1130 21:08:27.773519   358 master.cpp:1447] Recovered 0 slaves from the
> > Registry (139B) ; allowing 10mins for slaves to re-register
> > I1130 21:08:27.773712   358 hierarchical.cpp:174] Allocator recovery is
> > not supported yet
> > I1130 21:08:27.774319   349 coordinator.cpp:348] Coordinator attempting
> to
> > write TRUNCATE action at position 2
> > I1130 21:08:27.776000   356 replica.cpp:538] Replica received write
> > request for position 2 from (11283)@172.17.20.211:49853
> > I1130 21:08:27.806820   356 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 30.743154ms
> > I1130 21:08:27.806915   356 replica.cpp:713] Persisted action at 2
> > I1130 21:08:27.808435   349 replica.cpp:692] Replica received learned
> > notice for position 2 from @0.0.0.0:0
> > I1130 21:08:27.840296   349 leveldb.cpp:341] Persisting action (18 bytes)
> > to leveldb took 31.73806ms
> > I1130 21:08:27.840463   349 leveldb.cpp:399] Deleting ~1 keys from
> leveldb
> > took 77442ns
> > I1130 21:08:27.840493   349 replica.cpp:713] Persisted action at 2
> > I1130 21:08:27.840531   349 replica.cpp:698] Replica learned TRUNCATE
> > action at position 2
> > I1130 21:08:27.845356   324 scheduler.cpp:154] Version: 0.27.0
> > I1130 21:08:27.846367   349 scheduler.cpp:236] New master detected at
> > master@172.17.20.211:49853
> > I1130 21:08:27.847790   356 scheduler.cpp:298] Sending SUBSCRIBE call to
> > master@172.17.20.211:49853
> > I1130 21:08:27.849529   356 process.cpp:3067] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1130 21:08:27.849588   356 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.20.211:54626
> > I1130 21:08:27.849788   356 master.cpp:1899] Received subscription
> request
> > for HTTP framework 'default'
> > I1130 21:08:27.849864   356 master.cpp:1676] Authorizing framework
> > principal 'test-principal' to receive offers for role '*'
> > I1130 21:08:27.850175   357 master.cpp:1991] Subscribing framework
> > 'default' with checkpointing disabled and capabilities [  ]
> > I1130 21:08:27.850682   344 hierarchical.cpp:220] Added framework
> > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > I1130 21:08:27.850739   344 hierarchical.cpp:1064] No resources available
> > to allocate!
> > I1130 21:08:27.850770   344 hierarchical.cpp:1157] No inverse offers to
> > send out!
> > I1130 21:08:27.850793   344 hierarchical.cpp:959] Performed allocation
> for
> > 0 slaves in 86690ns
> > I1130 21:08:27.850900   357 master.hpp:1542] Sending heartbeat to
> > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > I1130 21:08:27.852912   347 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> > received from master@172.17.20.211:49853
> > I1130 21:08:27.853420   347 scheduler.cpp:457] Enqueuing event HEARTBEAT
> > received from master@172.17.20.211:49853
> > I1130 21:08:27.853988   350 master_maintenance_tests.cpp:172] Ignoring
> > HEARTBEAT event
> > I1130 21:08:27.854672   358 scheduler.cpp:298] Sending REQUEST call to
> > master@172.17.20.211:49853
> > I1130 21:08:27.856593   353 process.cpp:3067] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1130 21:08:27.856719   353 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.20.211:54627
> > I1130 21:08:27.856889   353 master.cpp:2646] Processing REQUEST call for
> > framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000 (default)
> > I1130 21:08:27.857241   353 hierarchical.cpp:494] Received resource
> > request from framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > I1130 21:08:27.857846   324 master.cpp:938] Master terminating
> > I1130 21:08:27.858505   349 hierarchical.cpp:260] Removed framework
> > 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> > E1130 21:08:27.862787   353 scheduler.cpp:431] End-Of-File received from
> > master. The master closed the event stream
> > [       OK ] ContentType/SchedulerTest.Request/0 (525 ms)
> > [ RUN      ] ContentType/SchedulerTest.Request/1
> > I1130 21:08:27.981688   324 leveldb.cpp:174] Opened db in 96.943089ms
> > I1130 21:08:28.008204   324 leveldb.cpp:181] Compacted db in 26.429482ms
> > I1130 21:08:28.008306   324 leveldb.cpp:196] Created db iterator in
> 25911ns
> > I1130 21:08:28.008329   324 leveldb.cpp:202] Seeked to beginning of db in
> > 3856ns
> > I1130 21:08:28.008342   324 leveldb.cpp:271] Iterated through 0 keys in
> > the db in 341ns
> > I1130 21:08:28.008400   324 replica.cpp:778] Replica recovered with log
> > positions 0 -> 0 with 1 holes and 0 unlearned
> > I1130 21:08:28.009362   345 recover.cpp:447] Starting replica recovery
> > I1130 21:08:28.009794   345 recover.cpp:473] Replica is in EMPTY status
> > I1130 21:08:28.011524   348 replica.cpp:674] Replica in EMPTY status
> > received a broadcasted recover request from (11287)@172.17.20.211:49853
> > I1130 21:08:28.012024   345 recover.cpp:193] Received a recover response
> > from a replica in EMPTY status
> > I1130 21:08:28.012697   345 recover.cpp:564] Updating replica status to
> > STARTING
> > I1130 21:08:28.028069   353 master.cpp:365] Master
> > cf6bff68-54b0-4223-a1f0-cef4ceeb004d (aea03c1ca24e) started on
> > 172.17.20.211:49853
> > I1130 21:08:28.028102   353 master.cpp:367] Flags at startup: --acls=""
> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > --authenticate="false" --authenticate_slaves="true"
> > --authenticators="crammd5" --authorizers="local"
> > --credentials="/tmp/RQ8lBH/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.27.0/_inst/share/mesos/webui"
> > --work_dir="/tmp/RQ8lBH/master" --zk_session_timeout="10secs"
> > I1130 21:08:28.028492   353 master.cpp:414] Master allowing
> > unauthenticated frameworks to register
> > I1130 21:08:28.028504   353 master.cpp:417] Master only allowing
> > authenticated slaves to register
> > I1130 21:08:28.028513   353 credentials.hpp:35] Loading credentials for
> > authentication from '/tmp/RQ8lBH/credentials'
> > I1130 21:08:28.038077   353 master.cpp:456] Using default 'crammd5'
> > authenticator
> > I1130 21:08:28.038632   353 master.cpp:493] Authorization enabled
> > I1130 21:08:28.039350   346 hierarchical.cpp:162] Initialized
> hierarchical
> > allocator process
> > I1130 21:08:28.039631   346 whitelist_watcher.cpp:77] No whitelist given
> > I1130 21:08:28.044158   353 master.cpp:1637] The newly elected leader is
> > master@172.17.20.211:49853 with id cf6bff68-54b0-4223-a1f0-cef4ceeb004d
> > I1130 21:08:28.044719   353 master.cpp:1650] Elected as the leading
> master!
> > I1130 21:08:28.044865   353 master.cpp:1395] Recovering from registrar
> > I1130 21:08:28.045243   357 registrar.cpp:307] Recovering registrar
> > I1130 21:08:28.046226   345 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 33.160654ms
> > I1130 21:08:28.046283   345 replica.cpp:321] Persisted replica status to
> > STARTING
> > I1130 21:08:28.046551   345 recover.cpp:473] Replica is in STARTING
> status
> > I1130 21:08:28.048651   345 replica.cpp:674] Replica in STARTING status
> > received a broadcasted recover request from (11289)@172.17.20.211:49853
> > I1130 21:08:28.049242   355 recover.cpp:193] Received a recover response
> > from a replica in STARTING status
> > I1130 21:08:28.050812   355 recover.cpp:564] Updating replica status to
> > VOTING
> > I1130 21:08:28.082262   357 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 30.981671ms
> > I1130 21:08:28.082348   357 replica.cpp:321] Persisted replica status to
> > VOTING
> > I1130 21:08:28.082595   357 recover.cpp:578] Successfully joined the
> Paxos
> > group
> > I1130 21:08:28.082778   357 recover.cpp:462] Recover process terminated
> > I1130 21:08:28.083708   348 log.cpp:659] Attempting to start the writer
> > I1130 21:08:28.085435   348 replica.cpp:494] Replica received implicit
> > promise request from (11290)@172.17.20.211:49853 with proposal 1
> > I1130 21:08:28.133262   348 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 47.708066ms
> > I1130 21:08:28.133359   348 replica.cpp:343] Persisted promised to 1
> > I1130 21:08:28.134845   348 coordinator.cpp:238] Coordinator attempting
> to
> > fill missing positions
> > I1130 21:08:28.136824   348 replica.cpp:389] Replica received explicit
> > promise request from (11291)@172.17.20.211:49853 for position 0 with
> > proposal 2
> > I1130 21:08:28.166779   348 leveldb.cpp:341] Persisting action (8 bytes)
> > to leveldb took 29.742663ms
> > I1130 21:08:28.166870   348 replica.cpp:713] Persisted action at 0
> > I1130 21:08:28.168622   352 replica.cpp:538] Replica received write
> > request for position 0 from (11292)@172.17.20.211:49853
> > I1130 21:08:28.168822   352 leveldb.cpp:436] Reading position from
> leveldb
> > took 40320ns
> > I1130 21:08:28.191473   352 leveldb.cpp:341] Persisting action (14 bytes)
> > to leveldb took 22.491006ms
> > I1130 21:08:28.191576   352 replica.cpp:713] Persisted action at 0
> > I1130 21:08:28.192988   344 replica.cpp:692] Replica received learned
> > notice for position 0 from @0.0.0.0:0
> > I1130 21:08:28.216675   344 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 23.06125ms
> > I1130 21:08:28.216773   344 replica.cpp:713] Persisted action at 0
> > I1130 21:08:28.216810   344 replica.cpp:698] Replica learned NOP action
> at
> > position 0
> > I1130 21:08:28.217905   355 log.cpp:675] Writer started with ending
> > position 0
> > I1130 21:08:28.219653   355 leveldb.cpp:436] Reading position from
> leveldb
> > took 52180ns
> > I1130 21:08:28.221421   344 registrar.cpp:340] Successfully fetched the
> > registry (0B) in 176.124928ms
> > I1130 21:08:28.221719   344 registrar.cpp:439] Applied 1 operations in
> > 39055ns; attempting to update the 'registry'
> > I1130 21:08:28.222777   350 log.cpp:683] Attempting to append 178 bytes
> to
> > the log
> > I1130 21:08:28.223114   343 coordinator.cpp:348] Coordinator attempting
> to
> > write APPEND action at position 1
> > I1130 21:08:28.224201   344 replica.cpp:538] Replica received write
> > request for position 1 from (11293)@172.17.20.211:49853
> > I1130 21:08:28.250054   344 leveldb.cpp:341] Persisting action (197
> bytes)
> > to leveldb took 25.784879ms
> > I1130 21:08:28.250156   344 replica.cpp:713] Persisted action at 1
> > I1130 21:08:28.258332   354 replica.cpp:692] Replica received learned
> > notice for position 1 from @0.0.0.0:0
> > I1130 21:08:28.283534   354 leveldb.cpp:341] Persisting action (199
> bytes)
> > to leveldb took 25.027205ms
> > I1130 21:08:28.283632   354 replica.cpp:713] Persisted action at 1
> > I1130 21:08:28.283669   354 replica.cpp:698] Replica learned APPEND
> action
> > at position 1
> > I1130 21:08:28.285953   354 registrar.cpp:484] Successfully updated the
> > 'registry' in 64.059904ms
> > I1130 21:08:28.286386   354 registrar.cpp:370] Successfully recovered
> > registrar
> > I1130 21:08:28.286895   354 master.cpp:1447] Recovered 0 slaves from the
> > Registry (139B) ; allowing 10mins for slaves to re-register
> > I1130 21:08:28.287101   351 hierarchical.cpp:174] Allocator recovery is
> > not supported yet
> > I1130 21:08:28.286087   345 log.cpp:702] Attempting to truncate the log
> to
> > 1
> > I1130 21:08:28.288830   345 coordinator.cpp:348] Coordinator attempting
> to
> > write TRUNCATE action at position 2
> > I1130 21:08:28.290005   350 replica.cpp:538] Replica received write
> > request for position 2 from (11294)@172.17.20.211:49853
> > I1130 21:08:28.316994   350 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 25.725069ms
> > I1130 21:08:28.317087   350 replica.cpp:713] Persisted action at 2
> > I1130 21:08:28.318534   358 replica.cpp:692] Replica received learned
> > notice for position 2 from @0.0.0.0:0
> > I1130 21:08:28.342185   358 leveldb.cpp:341] Persisting action (18 bytes)
> > to leveldb took 23.521347ms
> > I1130 21:08:28.342352   358 leveldb.cpp:399] Deleting ~1 keys from
> leveldb
> > took 66119ns
> > I1130 21:08:28.342381   358 replica.cpp:713] Persisted action at 2
> > I1130 21:08:28.342417   358 replica.cpp:698] Replica learned TRUNCATE
> > action at position 2
> > I1130 21:08:28.349741   324 scheduler.cpp:154] Version: 0.27.0
> > I1130 21:08:28.350648   356 scheduler.cpp:236] New master detected at
> > master@172.17.20.211:49853
> > I1130 21:08:28.363270   351 scheduler.cpp:298] Sending SUBSCRIBE call to
> > master@172.17.20.211:49853
> > I1130 21:08:28.366402   351 process.cpp:3067] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1130 21:08:28.366574   351 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.20.211:54631
> > I1130 21:08:28.367025   351 master.cpp:1899] Received subscription
> request
> > for HTTP framework 'default'
> > I1130 21:08:28.367185   351 master.cpp:1676] Authorizing framework
> > principal 'test-principal' to receive offers for role '*'
> > I1130 21:08:28.367882   351 master.cpp:1991] Subscribing framework
> > 'default' with checkpointing disabled and capabilities [  ]
> > I1130 21:08:28.369591   351 hierarchical.cpp:220] Added framework
> > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > I1130 21:08:28.369653   351 hierarchical.cpp:1064] No resources available
> > to allocate!
> > I1130 21:08:28.369691   351 hierarchical.cpp:1157] No inverse offers to
> > send out!
> > I1130 21:08:28.369715   351 hierarchical.cpp:959] Performed allocation
> for
> > 0 slaves in 97414ns
> > I1130 21:08:28.369966   354 master.hpp:1542] Sending heartbeat to
> > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > I1130 21:08:28.371633   354 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> > received from master@172.17.20.211:49853
> > I1130 21:08:28.372498   358 scheduler.cpp:457] Enqueuing event HEARTBEAT
> > received from master@172.17.20.211:49853
> > I1130 21:08:28.373020   358 master_maintenance_tests.cpp:172] Ignoring
> > HEARTBEAT event
> > I1130 21:08:28.373692   353 scheduler.cpp:298] Sending REQUEST call to
> > master@172.17.20.211:49853
> > I1130 21:08:28.375929   352 process.cpp:3067] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1130 21:08:28.375993   352 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.20.211:54632
> > I1130 21:08:28.376310   352 master.cpp:2646] Processing REQUEST call for
> > framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000 (default)
> > I1130 21:08:28.376541   347 hierarchical.cpp:494] Received resource
> > request from framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > I1130 21:08:28.377280   324 master.cpp:938] Master terminating
> > I1130 21:08:28.377461   353 hierarchical.cpp:260] Removed framework
> > cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> > E1130 21:08:28.378293   343 scheduler.cpp:431] End-Of-File received from
> > master. The master closed the event stream
> > [       OK ] ContentType/SchedulerTest.Request/1 (521 ms)
> > [----------] 22 tests from ContentType/SchedulerTest (16744 ms total)
> >
> > [----------] Global test environment tear-down
> > [==========] 870 tests from 117 test cases ran. (791709 ms total)
> > [  PASSED  ] 869 tests.
> > [  FAILED  ] 1 test, listed below:
> > [  FAILED  ] MasterQuotaTest.AvailableResourcesAfterRescinding
> >
> >  1 FAILED TEST
> >   YOU HAVE 7 DISABLED TESTS
> >
> > make[4]: *** [check-local] Error 1
> > make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[3]: *** [check-am] Error 2
> > make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[2]: *** [check] Error 2
> > make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[1]: *** [check-recursive] Error 1
> > make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> > make: *** [distcheck] Error 1
> > + docker rmi mesos-1448914320-17469
> > Untagged: mesos-1448914320-17469:latest
> > Deleted: da529dd4edda7833eac2b5da57783b3f2e7375a404c6a4fca455a47a56d04a41
> > Deleted: bbda95a5006e24047ed48fd2387163a3158cc6080e978b97bb0c4538f2359019
> > Deleted: 3f4ce9d12df094a2ca1e075487610073e6488b64ce72087dbab8c30705e9f094
> > Deleted: 33a50a7ac53e4ccbb915a987666fa1ee25fd1cc9a61e7bf05170074841b603b3
> > Deleted: c5d1dc8b4f484cd84b2eba7455843b3d89f9ceea2cdf67c65214aa37c816f530
> > Deleted: 732b7e0b2b214c15e1e824c69d13bcdc19ea80d6507beec41925a9919b148fc2
> > Deleted: 77533cc5f4a714a800b9c943273a212fd934ac1ae2d0de6be31eb085832def65
> > Deleted: c1857e128beff6a7d52c8636159969734ddfa5ae6f24a46f902f9751b0e3845e
> > Deleted: 663e544e4e1729dfd29f36448991233dc69491ea1958107d431720951b89cf51
> > Deleted: 03524d3a5ec49d86b43fab3f013937fbf88980b4ff9ff1836d9cf481f5feb83d
> > Deleted: cae2f74aee84aa95e111f5d458d5e6426cc302c660212332170d4026245cf446
> > Deleted: 8d5f7938d6cc8a66e3611c319d98a6413f2386ba9878bd245109b73ea98e24e0
> > Build step 'Execute shell' marked build as failure
> >
>

Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,centos:7,docker||Hadoop #1294

Posted by Vinod Kone <vi...@apache.org>.

AlexR?

On Mon, Nov 30, 2015 at 1:08 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/1294/changes
> >
>
> Changes:
>
> [vinodkone] Added Executor->Framework message handler for the Executor
> HTTP API.
>
> ------------------------------------------
> [...truncated 149286 lines...]
> I1130 21:08:27.330451   355 replica.cpp:713] Persisted action at 4
> I1130 21:08:27.330485   355 replica.cpp:698] Replica learned TRUNCATE
> action at position 4
> E1130 21:08:27.331228   345 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> I1130 21:08:27.347769   324 slave.cpp:599] Slave terminating
> I1130 21:08:27.347898   324 slave.cpp:2010] Asked to shut down framework
> bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 by @0.0.0.0:0
> I1130 21:08:27.347939   324 slave.cpp:2035] Shutting down framework
> bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000
> I1130 21:08:27.348021   324 slave.cpp:3891] Shutting down executor
> 'default' of framework bf6744d9-8ee6-46c5-89a5-e97bcde63000-0000 at
> executor(132)@172.17.20.211:49853
> [       OK ] ContentType/SchedulerTest.Message/1 (683 ms)
> [ RUN      ] ContentType/SchedulerTest.Request/0
> I1130 21:08:27.479187   324 leveldb.cpp:174] Opened db in 115.296913ms
> I1130 21:08:27.511601   324 leveldb.cpp:181] Compacted db in 32.327319ms
> I1130 21:08:27.511701   324 leveldb.cpp:196] Created db iterator in 29426ns
> I1130 21:08:27.511721   324 leveldb.cpp:202] Seeked to beginning of db in
> 3382ns
> I1130 21:08:27.511734   324 leveldb.cpp:271] Iterated through 0 keys in
> the db in 384ns
> I1130 21:08:27.511795   324 replica.cpp:778] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1130 21:08:27.512843   355 recover.cpp:447] Starting replica recovery
> I1130 21:08:27.513139   355 recover.cpp:473] Replica is in EMPTY status
> I1130 21:08:27.514932   355 replica.cpp:674] Replica in EMPTY status
> received a broadcasted recover request from (11277)@172.17.20.211:49853
> I1130 21:08:27.517248   352 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I1130 21:08:27.517969   352 recover.cpp:564] Updating replica status to
> STARTING
> I1130 21:08:27.523208   344 master.cpp:365] Master
> 18a00433-18e1-476f-82d1-5f507c7ba81d (aea03c1ca24e) started on
> 172.17.20.211:49853
> I1130 21:08:27.523257   344 master.cpp:367] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/RbAcoE/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.27.0/_inst/share/mesos/webui"
> --work_dir="/tmp/RbAcoE/master" --zk_session_timeout="10secs"
> I1130 21:08:27.523615   344 master.cpp:414] Master allowing
> unauthenticated frameworks to register
> I1130 21:08:27.523629   344 master.cpp:417] Master only allowing
> authenticated slaves to register
> I1130 21:08:27.523638   344 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/RbAcoE/credentials'
> I1130 21:08:27.523980   344 master.cpp:456] Using default 'crammd5'
> authenticator
> I1130 21:08:27.524160   344 master.cpp:493] Authorization enabled
> I1130 21:08:27.525506   350 hierarchical.cpp:162] Initialized hierarchical
> allocator process
> I1130 21:08:27.525606   350 whitelist_watcher.cpp:77] No whitelist given
> I1130 21:08:27.527474   350 master.cpp:1637] The newly elected leader is
> master@172.17.20.211:49853 with id 18a00433-18e1-476f-82d1-5f507c7ba81d
> I1130 21:08:27.527525   350 master.cpp:1650] Elected as the leading master!
> I1130 21:08:27.527546   350 master.cpp:1395] Recovering from registrar
> I1130 21:08:27.527820   350 registrar.cpp:307] Recovering registrar
> I1130 21:08:27.546037   352 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 27.807666ms
> I1130 21:08:27.546118   352 replica.cpp:321] Persisted replica status to
> STARTING
> I1130 21:08:27.546471   352 recover.cpp:473] Replica is in STARTING status
> I1130 21:08:27.548519   352 replica.cpp:674] Replica in STARTING status
> received a broadcasted recover request from (11278)@172.17.20.211:49853
> I1130 21:08:27.548904   352 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I1130 21:08:27.549509   350 recover.cpp:564] Updating replica status to
> VOTING
> I1130 21:08:27.571252   350 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 21.409051ms
> I1130 21:08:27.571336   350 replica.cpp:321] Persisted replica status to
> VOTING
> I1130 21:08:27.571596   350 recover.cpp:578] Successfully joined the Paxos
> group
> I1130 21:08:27.571888   350 recover.cpp:462] Recover process terminated
> I1130 21:08:27.572474   358 log.cpp:659] Attempting to start the writer
> I1130 21:08:27.573999   358 replica.cpp:494] Replica received implicit
> promise request from (11279)@172.17.20.211:49853 with proposal 1
> I1130 21:08:27.596279   358 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 22.224107ms
> I1130 21:08:27.596366   358 replica.cpp:343] Persisted promised to 1
> I1130 21:08:27.597810   343 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I1130 21:08:27.600751   343 replica.cpp:389] Replica received explicit
> promise request from (11280)@172.17.20.211:49853 for position 0 with
> proposal 2
> I1130 21:08:27.622263   343 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 21.312087ms
> I1130 21:08:27.622346   343 replica.cpp:713] Persisted action at 0
> I1130 21:08:27.624104   346 replica.cpp:538] Replica received write
> request for position 0 from (11281)@172.17.20.211:49853
> I1130 21:08:27.624258   346 leveldb.cpp:436] Reading position from leveldb
> took 107729ns
> I1130 21:08:27.646852   346 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 22.452736ms
> I1130 21:08:27.646936   346 replica.cpp:713] Persisted action at 0
> I1130 21:08:27.648002   346 replica.cpp:692] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1130 21:08:27.678287   346 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 30.230306ms
> I1130 21:08:27.678381   346 replica.cpp:713] Persisted action at 0
> I1130 21:08:27.678421   346 replica.cpp:698] Replica learned NOP action at
> position 0
> I1130 21:08:27.679606   347 log.cpp:675] Writer started with ending
> position 0
> I1130 21:08:27.681371   347 leveldb.cpp:436] Reading position from leveldb
> took 70404ns
> I1130 21:08:27.682898   347 registrar.cpp:340] Successfully fetched the
> registry (0B) in 155.027968ms
> I1130 21:08:27.683078   347 registrar.cpp:439] Applied 1 operations in
> 54140ns; attempting to update the 'registry'
> I1130 21:08:27.684787   344 log.cpp:683] Attempting to append 178 bytes to
> the log
> I1130 21:08:27.685065   347 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I1130 21:08:27.686206   348 replica.cpp:538] Replica received write
> request for position 1 from (11282)@172.17.20.211:49853
> I1130 21:08:27.722992   348 leveldb.cpp:341] Persisting action (197 bytes)
> to leveldb took 36.707093ms
> I1130 21:08:27.723076   348 replica.cpp:713] Persisted action at 1
> I1130 21:08:27.724438   345 replica.cpp:692] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1130 21:08:27.770239   345 leveldb.cpp:341] Persisting action (199 bytes)
> to leveldb took 45.745553ms
> I1130 21:08:27.770336   345 replica.cpp:713] Persisted action at 1
> I1130 21:08:27.770376   345 replica.cpp:698] Replica learned APPEND action
> at position 1
> I1130 21:08:27.772614   356 registrar.cpp:484] Successfully updated the
> 'registry' in 89.427968ms
> I1130 21:08:27.772789   356 registrar.cpp:370] Successfully recovered
> registrar
> I1130 21:08:27.773059   356 log.cpp:702] Attempting to truncate the log to
> 1
> I1130 21:08:27.773519   358 master.cpp:1447] Recovered 0 slaves from the
> Registry (139B) ; allowing 10mins for slaves to re-register
> I1130 21:08:27.773712   358 hierarchical.cpp:174] Allocator recovery is
> not supported yet
> I1130 21:08:27.774319   349 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I1130 21:08:27.776000   356 replica.cpp:538] Replica received write
> request for position 2 from (11283)@172.17.20.211:49853
> I1130 21:08:27.806820   356 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 30.743154ms
> I1130 21:08:27.806915   356 replica.cpp:713] Persisted action at 2
> I1130 21:08:27.808435   349 replica.cpp:692] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1130 21:08:27.840296   349 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 31.73806ms
> I1130 21:08:27.840463   349 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 77442ns
> I1130 21:08:27.840493   349 replica.cpp:713] Persisted action at 2
> I1130 21:08:27.840531   349 replica.cpp:698] Replica learned TRUNCATE
> action at position 2
> I1130 21:08:27.845356   324 scheduler.cpp:154] Version: 0.27.0
> I1130 21:08:27.846367   349 scheduler.cpp:236] New master detected at
> master@172.17.20.211:49853
> I1130 21:08:27.847790   356 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.20.211:49853
> I1130 21:08:27.849529   356 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1130 21:08:27.849588   356 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.20.211:54626
> I1130 21:08:27.849788   356 master.cpp:1899] Received subscription request
> for HTTP framework 'default'
> I1130 21:08:27.849864   356 master.cpp:1676] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1130 21:08:27.850175   357 master.cpp:1991] Subscribing framework
> 'default' with checkpointing disabled and capabilities [  ]
> I1130 21:08:27.850682   344 hierarchical.cpp:220] Added framework
> 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> I1130 21:08:27.850739   344 hierarchical.cpp:1064] No resources available
> to allocate!
> I1130 21:08:27.850770   344 hierarchical.cpp:1157] No inverse offers to
> send out!
> I1130 21:08:27.850793   344 hierarchical.cpp:959] Performed allocation for
> 0 slaves in 86690ns
> I1130 21:08:27.850900   357 master.hpp:1542] Sending heartbeat to
> 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> I1130 21:08:27.852912   347 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.20.211:49853
> I1130 21:08:27.853420   347 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.20.211:49853
> I1130 21:08:27.853988   350 master_maintenance_tests.cpp:172] Ignoring
> HEARTBEAT event
> I1130 21:08:27.854672   358 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.20.211:49853
> I1130 21:08:27.856593   353 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1130 21:08:27.856719   353 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.20.211:54627
> I1130 21:08:27.856889   353 master.cpp:2646] Processing REQUEST call for
> framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000 (default)
> I1130 21:08:27.857241   353 hierarchical.cpp:494] Received resource
> request from framework 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> I1130 21:08:27.857846   324 master.cpp:938] Master terminating
> I1130 21:08:27.858505   349 hierarchical.cpp:260] Removed framework
> 18a00433-18e1-476f-82d1-5f507c7ba81d-0000
> E1130 21:08:27.862787   353 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [       OK ] ContentType/SchedulerTest.Request/0 (525 ms)
> [ RUN      ] ContentType/SchedulerTest.Request/1
> I1130 21:08:27.981688   324 leveldb.cpp:174] Opened db in 96.943089ms
> I1130 21:08:28.008204   324 leveldb.cpp:181] Compacted db in 26.429482ms
> I1130 21:08:28.008306   324 leveldb.cpp:196] Created db iterator in 25911ns
> I1130 21:08:28.008329   324 leveldb.cpp:202] Seeked to beginning of db in
> 3856ns
> I1130 21:08:28.008342   324 leveldb.cpp:271] Iterated through 0 keys in
> the db in 341ns
> I1130 21:08:28.008400   324 replica.cpp:778] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1130 21:08:28.009362   345 recover.cpp:447] Starting replica recovery
> I1130 21:08:28.009794   345 recover.cpp:473] Replica is in EMPTY status
> I1130 21:08:28.011524   348 replica.cpp:674] Replica in EMPTY status
> received a broadcasted recover request from (11287)@172.17.20.211:49853
> I1130 21:08:28.012024   345 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I1130 21:08:28.012697   345 recover.cpp:564] Updating replica status to
> STARTING
> I1130 21:08:28.028069   353 master.cpp:365] Master
> cf6bff68-54b0-4223-a1f0-cef4ceeb004d (aea03c1ca24e) started on
> 172.17.20.211:49853
> I1130 21:08:28.028102   353 master.cpp:367] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/RQ8lBH/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.27.0/_inst/share/mesos/webui"
> --work_dir="/tmp/RQ8lBH/master" --zk_session_timeout="10secs"
> I1130 21:08:28.028492   353 master.cpp:414] Master allowing
> unauthenticated frameworks to register
> I1130 21:08:28.028504   353 master.cpp:417] Master only allowing
> authenticated slaves to register
> I1130 21:08:28.028513   353 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/RQ8lBH/credentials'
> I1130 21:08:28.038077   353 master.cpp:456] Using default 'crammd5'
> authenticator
> I1130 21:08:28.038632   353 master.cpp:493] Authorization enabled
> I1130 21:08:28.039350   346 hierarchical.cpp:162] Initialized hierarchical
> allocator process
> I1130 21:08:28.039631   346 whitelist_watcher.cpp:77] No whitelist given
> I1130 21:08:28.044158   353 master.cpp:1637] The newly elected leader is
> master@172.17.20.211:49853 with id cf6bff68-54b0-4223-a1f0-cef4ceeb004d
> I1130 21:08:28.044719   353 master.cpp:1650] Elected as the leading master!
> I1130 21:08:28.044865   353 master.cpp:1395] Recovering from registrar
> I1130 21:08:28.045243   357 registrar.cpp:307] Recovering registrar
> I1130 21:08:28.046226   345 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 33.160654ms
> I1130 21:08:28.046283   345 replica.cpp:321] Persisted replica status to
> STARTING
> I1130 21:08:28.046551   345 recover.cpp:473] Replica is in STARTING status
> I1130 21:08:28.048651   345 replica.cpp:674] Replica in STARTING status
> received a broadcasted recover request from (11289)@172.17.20.211:49853
> I1130 21:08:28.049242   355 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I1130 21:08:28.050812   355 recover.cpp:564] Updating replica status to
> VOTING
> I1130 21:08:28.082262   357 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.981671ms
> I1130 21:08:28.082348   357 replica.cpp:321] Persisted replica status to
> VOTING
> I1130 21:08:28.082595   357 recover.cpp:578] Successfully joined the Paxos
> group
> I1130 21:08:28.082778   357 recover.cpp:462] Recover process terminated
> I1130 21:08:28.083708   348 log.cpp:659] Attempting to start the writer
> I1130 21:08:28.085435   348 replica.cpp:494] Replica received implicit
> promise request from (11290)@172.17.20.211:49853 with proposal 1
> I1130 21:08:28.133262   348 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 47.708066ms
> I1130 21:08:28.133359   348 replica.cpp:343] Persisted promised to 1
> I1130 21:08:28.134845   348 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I1130 21:08:28.136824   348 replica.cpp:389] Replica received explicit
> promise request from (11291)@172.17.20.211:49853 for position 0 with
> proposal 2
> I1130 21:08:28.166779   348 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 29.742663ms
> I1130 21:08:28.166870   348 replica.cpp:713] Persisted action at 0
> I1130 21:08:28.168622   352 replica.cpp:538] Replica received write
> request for position 0 from (11292)@172.17.20.211:49853
> I1130 21:08:28.168822   352 leveldb.cpp:436] Reading position from leveldb
> took 40320ns
> I1130 21:08:28.191473   352 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 22.491006ms
> I1130 21:08:28.191576   352 replica.cpp:713] Persisted action at 0
> I1130 21:08:28.192988   344 replica.cpp:692] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1130 21:08:28.216675   344 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 23.06125ms
> I1130 21:08:28.216773   344 replica.cpp:713] Persisted action at 0
> I1130 21:08:28.216810   344 replica.cpp:698] Replica learned NOP action at
> position 0
> I1130 21:08:28.217905   355 log.cpp:675] Writer started with ending
> position 0
> I1130 21:08:28.219653   355 leveldb.cpp:436] Reading position from leveldb
> took 52180ns
> I1130 21:08:28.221421   344 registrar.cpp:340] Successfully fetched the
> registry (0B) in 176.124928ms
> I1130 21:08:28.221719   344 registrar.cpp:439] Applied 1 operations in
> 39055ns; attempting to update the 'registry'
> I1130 21:08:28.222777   350 log.cpp:683] Attempting to append 178 bytes to
> the log
> I1130 21:08:28.223114   343 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I1130 21:08:28.224201   344 replica.cpp:538] Replica received write
> request for position 1 from (11293)@172.17.20.211:49853
> I1130 21:08:28.250054   344 leveldb.cpp:341] Persisting action (197 bytes)
> to leveldb took 25.784879ms
> I1130 21:08:28.250156   344 replica.cpp:713] Persisted action at 1
> I1130 21:08:28.258332   354 replica.cpp:692] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1130 21:08:28.283534   354 leveldb.cpp:341] Persisting action (199 bytes)
> to leveldb took 25.027205ms
> I1130 21:08:28.283632   354 replica.cpp:713] Persisted action at 1
> I1130 21:08:28.283669   354 replica.cpp:698] Replica learned APPEND action
> at position 1
> I1130 21:08:28.285953   354 registrar.cpp:484] Successfully updated the
> 'registry' in 64.059904ms
> I1130 21:08:28.286386   354 registrar.cpp:370] Successfully recovered
> registrar
> I1130 21:08:28.286895   354 master.cpp:1447] Recovered 0 slaves from the
> Registry (139B) ; allowing 10mins for slaves to re-register
> I1130 21:08:28.287101   351 hierarchical.cpp:174] Allocator recovery is
> not supported yet
> I1130 21:08:28.286087   345 log.cpp:702] Attempting to truncate the log to
> 1
> I1130 21:08:28.288830   345 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I1130 21:08:28.290005   350 replica.cpp:538] Replica received write
> request for position 2 from (11294)@172.17.20.211:49853
> I1130 21:08:28.316994   350 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 25.725069ms
> I1130 21:08:28.317087   350 replica.cpp:713] Persisted action at 2
> I1130 21:08:28.318534   358 replica.cpp:692] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1130 21:08:28.342185   358 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 23.521347ms
> I1130 21:08:28.342352   358 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 66119ns
> I1130 21:08:28.342381   358 replica.cpp:713] Persisted action at 2
> I1130 21:08:28.342417   358 replica.cpp:698] Replica learned TRUNCATE
> action at position 2
> I1130 21:08:28.349741   324 scheduler.cpp:154] Version: 0.27.0
> I1130 21:08:28.350648   356 scheduler.cpp:236] New master detected at
> master@172.17.20.211:49853
> I1130 21:08:28.363270   351 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.20.211:49853
> I1130 21:08:28.366402   351 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1130 21:08:28.366574   351 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.20.211:54631
> I1130 21:08:28.367025   351 master.cpp:1899] Received subscription request
> for HTTP framework 'default'
> I1130 21:08:28.367185   351 master.cpp:1676] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1130 21:08:28.367882   351 master.cpp:1991] Subscribing framework
> 'default' with checkpointing disabled and capabilities [  ]
> I1130 21:08:28.369591   351 hierarchical.cpp:220] Added framework
> cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> I1130 21:08:28.369653   351 hierarchical.cpp:1064] No resources available
> to allocate!
> I1130 21:08:28.369691   351 hierarchical.cpp:1157] No inverse offers to
> send out!
> I1130 21:08:28.369715   351 hierarchical.cpp:959] Performed allocation for
> 0 slaves in 97414ns
> I1130 21:08:28.369966   354 master.hpp:1542] Sending heartbeat to
> cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> I1130 21:08:28.371633   354 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.20.211:49853
> I1130 21:08:28.372498   358 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.20.211:49853
> I1130 21:08:28.373020   358 master_maintenance_tests.cpp:172] Ignoring
> HEARTBEAT event
> I1130 21:08:28.373692   353 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.20.211:49853
> I1130 21:08:28.375929   352 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1130 21:08:28.375993   352 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.20.211:54632
> I1130 21:08:28.376310   352 master.cpp:2646] Processing REQUEST call for
> framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000 (default)
> I1130 21:08:28.376541   347 hierarchical.cpp:494] Received resource
> request from framework cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> I1130 21:08:28.377280   324 master.cpp:938] Master terminating
> I1130 21:08:28.377461   353 hierarchical.cpp:260] Removed framework
> cf6bff68-54b0-4223-a1f0-cef4ceeb004d-0000
> E1130 21:08:28.378293   343 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [       OK ] ContentType/SchedulerTest.Request/1 (521 ms)
> [----------] 22 tests from ContentType/SchedulerTest (16744 ms total)
>
> [----------] Global test environment tear-down
> [==========] 870 tests from 117 test cases ran. (791709 ms total)
> [  PASSED  ] 869 tests.
> [  FAILED  ] 1 test, listed below:
> [  FAILED  ] MasterQuotaTest.AvailableResourcesAfterRescinding
>
>  1 FAILED TEST
>   YOU HAVE 7 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1448914320-17469
> Untagged: mesos-1448914320-17469:latest
> Deleted: da529dd4edda7833eac2b5da57783b3f2e7375a404c6a4fca455a47a56d04a41
> Deleted: bbda95a5006e24047ed48fd2387163a3158cc6080e978b97bb0c4538f2359019
> Deleted: 3f4ce9d12df094a2ca1e075487610073e6488b64ce72087dbab8c30705e9f094
> Deleted: 33a50a7ac53e4ccbb915a987666fa1ee25fd1cc9a61e7bf05170074841b603b3
> Deleted: c5d1dc8b4f484cd84b2eba7455843b3d89f9ceea2cdf67c65214aa37c816f530
> Deleted: 732b7e0b2b214c15e1e824c69d13bcdc19ea80d6507beec41925a9919b148fc2
> Deleted: 77533cc5f4a714a800b9c943273a212fd934ac1ae2d0de6be31eb085832def65
> Deleted: c1857e128beff6a7d52c8636159969734ddfa5ae6f24a46f902f9751b0e3845e
> Deleted: 663e544e4e1729dfd29f36448991233dc69491ea1958107d431720951b89cf51
> Deleted: 03524d3a5ec49d86b43fab3f013937fbf88980b4ff9ff1836d9cf481f5feb83d
> Deleted: cae2f74aee84aa95e111f5d458d5e6426cc302c660212332170d4026245cf446
> Deleted: 8d5f7938d6cc8a66e3611c319d98a6413f2386ba9878bd245109b73ea98e24e0
> 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 #1295

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/1295/changes>