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/12/14 03:50:27 UTC
Build failed in Jenkins: mesos-reviewbot #10157
See <https://builds.apache.org/job/mesos-reviewbot/10157/>
------------------------------------------
[...truncated 165221 lines...]
[ OK ] ContentType/SchedulerTest.Message/1 (746 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1214 02:43:28.862740 30299 leveldb.cpp:174] Opened db in 108.460239ms
I1214 02:43:28.903323 30299 leveldb.cpp:181] Compacted db in 40.496654ms
I1214 02:43:28.903416 30299 leveldb.cpp:196] Created db iterator in 23438ns
I1214 02:43:28.903435 30299 leveldb.cpp:202] Seeked to beginning of db in 3217ns
I1214 02:43:28.903446 30299 leveldb.cpp:271] Iterated through 0 keys in the db in 240ns
I1214 02:43:28.903499 30299 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1214 02:43:28.904395 30318 recover.cpp:447] Starting replica recovery
I1214 02:43:28.904681 30318 recover.cpp:473] Replica is in EMPTY status
I1214 02:43:28.906260 30321 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (11998)@172.17.0.14:47889
I1214 02:43:28.906774 30318 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1214 02:43:28.907250 30324 recover.cpp:564] Updating replica status to STARTING
I1214 02:43:28.934959 30332 master.cpp:366] Master 9f7c0c51-c954-4656-8c70-8928edaf1c37 (7e18acc3efba) started on 172.17.0.14:47889
I1214 02:43:28.935000 30332 master.cpp:368] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/dxVfzg/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/dxVfzg/master" --zk_session_timeout="10secs"
I1214 02:43:28.935612 30332 master.cpp:415] Master allowing unauthenticated frameworks to register
I1214 02:43:28.935626 30332 master.cpp:418] Master only allowing authenticated slaves to register
I1214 02:43:28.935636 30332 credentials.hpp:35] Loading credentials for authentication from '/tmp/dxVfzg/credentials'
I1214 02:43:28.935968 30332 master.cpp:457] Using default 'crammd5' authenticator
I1214 02:43:28.936133 30332 master.cpp:494] Authorization enabled
I1214 02:43:28.938345 30318 hierarchical.cpp:163] Initialized hierarchical allocator process
I1214 02:43:28.938444 30318 whitelist_watcher.cpp:77] No whitelist given
I1214 02:43:28.941223 30324 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 33.570634ms
I1214 02:43:28.941279 30324 replica.cpp:320] Persisted replica status to STARTING
I1214 02:43:28.941766 30324 recover.cpp:473] Replica is in STARTING status
I1214 02:43:28.943775 30332 master.cpp:1636] The newly elected leader is master@172.17.0.14:47889 with id 9f7c0c51-c954-4656-8c70-8928edaf1c37
I1214 02:43:28.943809 30332 master.cpp:1649] Elected as the leading master!
I1214 02:43:28.943830 30332 master.cpp:1394] Recovering from registrar
I1214 02:43:28.944121 30324 registrar.cpp:307] Recovering registrar
I1214 02:43:28.945231 30319 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (12000)@172.17.0.14:47889
I1214 02:43:28.945585 30332 recover.cpp:193] Received a recover response from a replica in STARTING status
I1214 02:43:28.946403 30324 recover.cpp:564] Updating replica status to VOTING
I1214 02:43:28.973847 30324 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 27.118069ms
I1214 02:43:28.973935 30324 replica.cpp:320] Persisted replica status to VOTING
I1214 02:43:28.974591 30324 recover.cpp:578] Successfully joined the Paxos group
I1214 02:43:28.974797 30324 recover.cpp:462] Recover process terminated
I1214 02:43:28.975829 30319 log.cpp:659] Attempting to start the writer
I1214 02:43:28.979872 30319 replica.cpp:493] Replica received implicit promise request from (12001)@172.17.0.14:47889 with proposal 1
I1214 02:43:29.008275 30319 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 28.308364ms
I1214 02:43:29.008368 30319 replica.cpp:342] Persisted promised to 1
I1214 02:43:29.012761 30324 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1214 02:43:29.015265 30329 replica.cpp:388] Replica received explicit promise request from (12002)@172.17.0.14:47889 for position 0 with proposal 2
2015-12-14 02:43:29,022:30299(0x2ac688401700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:42074] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I1214 02:43:29.041255 30329 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 25.898263ms
I1214 02:43:29.041337 30329 replica.cpp:712] Persisted action at 0
I1214 02:43:29.043071 30333 replica.cpp:537] Replica received write request for position 0 from (12003)@172.17.0.14:47889
I1214 02:43:29.043149 30333 leveldb.cpp:436] Reading position from leveldb took 40765ns
I1214 02:43:29.066076 30333 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 21.822771ms
I1214 02:43:29.066174 30333 replica.cpp:712] Persisted action at 0
I1214 02:43:29.067260 30331 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I1214 02:43:29.091261 30331 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.944803ms
I1214 02:43:29.091347 30331 replica.cpp:712] Persisted action at 0
I1214 02:43:29.091384 30331 replica.cpp:697] Replica learned NOP action at position 0
I1214 02:43:29.094339 30331 log.cpp:675] Writer started with ending position 0
I1214 02:43:29.096915 30331 leveldb.cpp:436] Reading position from leveldb took 63144ns
I1214 02:43:29.098291 30326 registrar.cpp:340] Successfully fetched the registry (0B) in 154.077952ms
I1214 02:43:29.098435 30326 registrar.cpp:439] Applied 1 operations in 37296ns; attempting to update the 'registry'
I1214 02:43:29.101058 30326 log.cpp:683] Attempting to append 173 bytes to the log
I1214 02:43:29.101330 30326 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1214 02:43:29.104568 30326 replica.cpp:537] Replica received write request for position 1 from (12004)@172.17.0.14:47889
I1214 02:43:29.124737 30326 leveldb.cpp:341] Persisting action (192 bytes) to leveldb took 20.078639ms
I1214 02:43:29.124825 30326 replica.cpp:712] Persisted action at 1
I1214 02:43:29.126269 30321 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I1214 02:43:29.149899 30321 leveldb.cpp:341] Persisting action (194 bytes) to leveldb took 23.576612ms
I1214 02:43:29.149991 30321 replica.cpp:712] Persisted action at 1
I1214 02:43:29.150032 30321 replica.cpp:697] Replica learned APPEND action at position 1
I1214 02:43:29.152648 30321 registrar.cpp:484] Successfully updated the 'registry' in 54.128384ms
I1214 02:43:29.152859 30321 registrar.cpp:370] Successfully recovered registrar
I1214 02:43:29.153151 30321 log.cpp:702] Attempting to truncate the log to 1
I1214 02:43:29.153640 30318 master.cpp:1446] Recovered 0 slaves from the Registry (134B) ; allowing 10mins for slaves to re-register
I1214 02:43:29.153939 30330 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1214 02:43:29.154611 30318 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1214 02:43:29.156065 30325 replica.cpp:537] Replica received write request for position 2 from (12005)@172.17.0.14:47889
I1214 02:43:29.210281 30325 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 54.130515ms
I1214 02:43:29.210387 30325 replica.cpp:712] Persisted action at 2
I1214 02:43:29.212040 30329 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I1214 02:43:29.246289 30329 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 34.143003ms
I1214 02:43:29.246459 30329 leveldb.cpp:399] Deleting ~1 keys from leveldb took 85892ns
I1214 02:43:29.246490 30329 replica.cpp:712] Persisted action at 2
I1214 02:43:29.246529 30329 replica.cpp:697] Replica learned TRUNCATE action at position 2
I1214 02:43:29.260473 30299 scheduler.cpp:154] Version: 0.27.0
I1214 02:43:29.265128 30323 scheduler.cpp:236] New master detected at master@172.17.0.14:47889
I1214 02:43:29.277081 30332 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.14:47889
I1214 02:43:29.282414 30325 process.cpp:3224] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1214 02:43:29.282503 30325 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.14:39627
I1214 02:43:29.282788 30325 master.cpp:1898] Received subscription request for HTTP framework 'default'
I1214 02:43:29.282872 30325 master.cpp:1675] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1214 02:43:29.283383 30325 master.cpp:1990] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1214 02:43:29.283975 30325 hierarchical.cpp:267] Added framework 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000
I1214 02:43:29.284054 30325 hierarchical.cpp:1317] No resources available to allocate!
I1214 02:43:29.284091 30325 hierarchical.cpp:1410] No inverse offers to send out!
I1214 02:43:29.284121 30325 hierarchical.cpp:1063] Performed allocation for 0 slaves in 121039ns
I1214 02:43:29.284154 30325 master.hpp:1588] Sending heartbeat to 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000
I1214 02:43:29.286221 30328 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.14:47889
I1214 02:43:29.286674 30328 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.14:47889
I1214 02:43:29.287137 30320 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1214 02:43:29.287746 30330 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.14:47889
I1214 02:43:29.290495 30331 process.cpp:3224] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1214 02:43:29.290568 30331 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.14:39716
I1214 02:43:29.290711 30331 master.cpp:2645] Processing REQUEST call for framework 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000 (default)
I1214 02:43:29.290971 30331 hierarchical.cpp:562] Received resource request from framework 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000
I1214 02:43:29.293977 30327 master.cpp:937] Master terminating
I1214 02:43:29.297351 30328 hierarchical.cpp:308] Removed framework 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000
[ OK ] ContentType/SchedulerTest.Request/0 (574 ms)
E1214 02:43:29.308874 30326 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ RUN ] ContentType/SchedulerTest.Request/1
I1214 02:43:29.432704 30299 leveldb.cpp:174] Opened db in 103.870417ms
I1214 02:43:29.467363 30299 leveldb.cpp:181] Compacted db in 34.570162ms
I1214 02:43:29.467458 30299 leveldb.cpp:196] Created db iterator in 23607ns
I1214 02:43:29.467474 30299 leveldb.cpp:202] Seeked to beginning of db in 3570ns
I1214 02:43:29.467484 30299 leveldb.cpp:271] Iterated through 0 keys in the db in 278ns
I1214 02:43:29.467540 30299 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1214 02:43:29.468473 30330 recover.cpp:447] Starting replica recovery
I1214 02:43:29.469003 30322 recover.cpp:473] Replica is in EMPTY status
I1214 02:43:29.470449 30322 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (12012)@172.17.0.14:47889
I1214 02:43:29.470827 30330 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1214 02:43:29.471329 30332 recover.cpp:564] Updating replica status to STARTING
I1214 02:43:29.474527 30325 master.cpp:366] Master 00edb7b9-a97e-4fc5-b64e-e013e8db1208 (7e18acc3efba) started on 172.17.0.14:47889
I1214 02:43:29.474565 30325 master.cpp:368] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/yftNcT/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/yftNcT/master" --zk_session_timeout="10secs"
I1214 02:43:29.474912 30325 master.cpp:415] Master allowing unauthenticated frameworks to register
I1214 02:43:29.474925 30325 master.cpp:418] Master only allowing authenticated slaves to register
I1214 02:43:29.474933 30325 credentials.hpp:35] Loading credentials for authentication from '/tmp/yftNcT/credentials'
I1214 02:43:29.475311 30325 master.cpp:457] Using default 'crammd5' authenticator
I1214 02:43:29.475474 30325 master.cpp:494] Authorization enabled
I1214 02:43:29.475920 30321 hierarchical.cpp:163] Initialized hierarchical allocator process
I1214 02:43:29.476060 30321 whitelist_watcher.cpp:77] No whitelist given
I1214 02:43:29.478646 30327 master.cpp:1636] The newly elected leader is master@172.17.0.14:47889 with id 00edb7b9-a97e-4fc5-b64e-e013e8db1208
I1214 02:43:29.478701 30327 master.cpp:1649] Elected as the leading master!
I1214 02:43:29.478723 30327 master.cpp:1394] Recovering from registrar
I1214 02:43:29.478981 30323 registrar.cpp:307] Recovering registrar
I1214 02:43:29.498013 30332 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 26.436883ms
I1214 02:43:29.498098 30332 replica.cpp:320] Persisted replica status to STARTING
I1214 02:43:29.498478 30332 recover.cpp:473] Replica is in STARTING status
I1214 02:43:29.500242 30323 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (12013)@172.17.0.14:47889
I1214 02:43:29.500944 30332 recover.cpp:193] Received a recover response from a replica in STARTING status
I1214 02:43:29.501499 30328 recover.cpp:564] Updating replica status to VOTING
I1214 02:43:29.517614 30332 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 15.936743ms
I1214 02:43:29.517702 30332 replica.cpp:320] Persisted replica status to VOTING
I1214 02:43:29.517997 30332 recover.cpp:578] Successfully joined the Paxos group
I1214 02:43:29.519294 30332 recover.cpp:462] Recover process terminated
I1214 02:43:29.520104 30332 log.cpp:659] Attempting to start the writer
I1214 02:43:29.529294 30323 replica.cpp:493] Replica received implicit promise request from (12014)@172.17.0.14:47889 with proposal 1
I1214 02:43:29.551255 30323 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.878344ms
I1214 02:43:29.551338 30323 replica.cpp:342] Persisted promised to 1
I1214 02:43:29.552628 30323 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1214 02:43:29.554110 30319 replica.cpp:388] Replica received explicit promise request from (12015)@172.17.0.14:47889 for position 0 with proposal 2
I1214 02:43:29.576231 30319 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 22.04418ms
I1214 02:43:29.576313 30319 replica.cpp:712] Persisted action at 0
I1214 02:43:29.577962 30319 replica.cpp:537] Replica received write request for position 0 from (12016)@172.17.0.14:47889
I1214 02:43:29.578052 30319 leveldb.cpp:436] Reading position from leveldb took 41520ns
I1214 02:43:29.601379 30319 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.263854ms
I1214 02:43:29.601464 30319 replica.cpp:712] Persisted action at 0
I1214 02:43:29.602502 30319 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I1214 02:43:29.618255 30319 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 15.360019ms
I1214 02:43:29.618336 30319 replica.cpp:712] Persisted action at 0
I1214 02:43:29.618371 30319 replica.cpp:697] Replica learned NOP action at position 0
I1214 02:43:29.619550 30319 log.cpp:675] Writer started with ending position 0
I1214 02:43:29.621335 30319 leveldb.cpp:436] Reading position from leveldb took 60420ns
I1214 02:43:29.631992 30329 registrar.cpp:340] Successfully fetched the registry (0B) in 152.938752ms
I1214 02:43:29.632189 30329 registrar.cpp:439] Applied 1 operations in 51893ns; attempting to update the 'registry'
I1214 02:43:29.633105 30322 log.cpp:683] Attempting to append 173 bytes to the log
I1214 02:43:29.634318 30322 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1214 02:43:29.636517 30319 replica.cpp:537] Replica received write request for position 1 from (12017)@172.17.0.14:47889
I1214 02:43:29.660120 30319 leveldb.cpp:341] Persisting action (192 bytes) to leveldb took 23.528111ms
I1214 02:43:29.660213 30319 replica.cpp:712] Persisted action at 1
I1214 02:43:29.664937 30331 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I1214 02:43:29.690273 30331 leveldb.cpp:341] Persisting action (194 bytes) to leveldb took 25.260655ms
I1214 02:43:29.690376 30331 replica.cpp:712] Persisted action at 1
I1214 02:43:29.690415 30331 replica.cpp:697] Replica learned APPEND action at position 1
I1214 02:43:29.695057 30331 registrar.cpp:484] Successfully updated the 'registry' in 62.77888ms
I1214 02:43:29.695313 30331 registrar.cpp:370] Successfully recovered registrar
I1214 02:43:29.695605 30331 log.cpp:702] Attempting to truncate the log to 1
I1214 02:43:29.696771 30331 master.cpp:1446] Recovered 0 slaves from the Registry (134B) ; allowing 10mins for slaves to re-register
I1214 02:43:29.696935 30331 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1214 02:43:29.697432 30323 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1214 02:43:29.699504 30329 replica.cpp:537] Replica received write request for position 2 from (12018)@172.17.0.14:47889
I1214 02:43:29.732065 30329 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.483979ms
I1214 02:43:29.732173 30329 replica.cpp:712] Persisted action at 2
I1214 02:43:29.741094 30329 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I1214 02:43:29.774277 30329 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 33.101424ms
I1214 02:43:29.774442 30329 leveldb.cpp:399] Deleting ~1 keys from leveldb took 73627ns
I1214 02:43:29.774472 30329 replica.cpp:712] Persisted action at 2
I1214 02:43:29.774513 30329 replica.cpp:697] Replica learned TRUNCATE action at position 2
I1214 02:43:29.783314 30299 scheduler.cpp:154] Version: 0.27.0
I1214 02:43:29.784826 30320 scheduler.cpp:236] New master detected at master@172.17.0.14:47889
I1214 02:43:29.803352 30322 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.14:47889
I1214 02:43:29.811533 30329 process.cpp:3224] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1214 02:43:29.811658 30329 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.14:42893
I1214 02:43:29.812353 30329 master.cpp:1898] Received subscription request for HTTP framework 'default'
I1214 02:43:29.812456 30329 master.cpp:1675] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1214 02:43:29.813136 30329 master.cpp:1990] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1214 02:43:29.816903 30331 hierarchical.cpp:267] Added framework 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000
I1214 02:43:29.817010 30331 hierarchical.cpp:1317] No resources available to allocate!
I1214 02:43:29.817054 30331 hierarchical.cpp:1410] No inverse offers to send out!
I1214 02:43:29.817087 30331 hierarchical.cpp:1063] Performed allocation for 0 slaves in 144726ns
I1214 02:43:29.817091 30329 master.hpp:1588] Sending heartbeat to 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000
I1214 02:43:29.818753 30321 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.14:47889
I1214 02:43:29.819412 30321 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.14:47889
I1214 02:43:29.820466 30332 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1214 02:43:29.820691 30330 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.14:47889
I1214 02:43:29.824892 30330 process.cpp:3224] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1214 02:43:29.825008 30330 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.14:42991
I1214 02:43:29.825564 30330 master.cpp:2645] Processing REQUEST call for framework 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000 (default)
I1214 02:43:29.826022 30330 hierarchical.cpp:562] Received resource request from framework 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000
I1214 02:43:29.831028 30322 master.cpp:937] Master terminating
I1214 02:43:29.833400 30333 hierarchical.cpp:308] Removed framework 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000
E1214 02:43:29.842592 30331 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (557 ms)
[----------] 22 tests from ContentType/SchedulerTest (17854 ms total)
[----------] Global test environment tear-down
[==========] 881 tests from 115 test cases ran. (829481 ms total)
[ PASSED ] 880 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] HealthCheckTest.HealthStatusChange
1 FAILED TEST
YOU HAVE 6 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[3]: *** [check-am] Error 2
make[2]: make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
*** [check] Error 2
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
*** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1450055668-7032
Untagged: mesos-1450055668-7032:latest
Deleted: 75c58ee8bc23b54f13562927a3bf4588f07e6141f1afc6b5f57eeeb79539c9e0
Deleted: d5a1f507cbab6750d33746aa1c850a14992ed03f4701103746529f74e3e1ec6d
Deleted: 0dba65bec7001d712ca34f9317db754bc3f4c53717be70c458f6eecedcdd66ee
Deleted: 22a19ab5a0d60fc0a21fa929ef61bf1236c130c2000ac1913d40e5f0ef3bccbb
Deleted: fa6f6bb3926b409a482afdd4002878419e7412f925f86b4f8b5dff8ce8d799b1
Deleted: 71d8965af689e3efc742334724a59f6240570725d854ccdef4dff35676f5814e
Deleted: c32a91a96b27819e5951d4de9a7e5d55f1899fbfa0bb26bfef991e75d3f0e0f1
Deleted: 0ed8fa301699f040f5b6be13449935abd1c282d0c8d2c97f59eee07ecb5359c5
Deleted: 80e349930a4cf33cfa08cab7cfc6c0de53403369c9a9b57d5bda83ec8ff1ff60
Deleted: da80a16d7c98a3a6be6a98ca668be0ba454abf2c7ffa03627a15463bfff7f50e
Deleted: 3afb12f89e0fccea0feab164c24b1d6b25322ebd154ff20505485b19cb84fca6
Deleted: 3833e7b6bb0b64d3433e7b4caa450a051752c03a9de560f1f1d075cfbb4e153f
Deleted: abb78fc0577f005bc3e1de74a8eee14e3278ca66fc5baf41f47f43203863bf11
Error handling URL https://reviews.apache.org/api/review-requests/41299/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 6eb2f98b3f4f770319a63da31459459628b48b46
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #10158
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10158/>
Re: Build failed in Jenkins: mesos-reviewbot #10157
Posted by Joris Van Remoortere <jo...@mesosphere.io>.
https://issues.apache.org/jira/browse/MESOS-1802
—
*Joris Van Remoortere*
Mesosphere
On Sun, Dec 13, 2015 at 6:50 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <https://builds.apache.org/job/mesos-reviewbot/10157/>
>
> ------------------------------------------
> [...truncated 165221 lines...]
> [ OK ] ContentType/SchedulerTest.Message/1 (746 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> I1214 02:43:28.862740 30299 leveldb.cpp:174] Opened db in 108.460239ms
> I1214 02:43:28.903323 30299 leveldb.cpp:181] Compacted db in 40.496654ms
> I1214 02:43:28.903416 30299 leveldb.cpp:196] Created db iterator in 23438ns
> I1214 02:43:28.903435 30299 leveldb.cpp:202] Seeked to beginning of db in
> 3217ns
> I1214 02:43:28.903446 30299 leveldb.cpp:271] Iterated through 0 keys in
> the db in 240ns
> I1214 02:43:28.903499 30299 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1214 02:43:28.904395 30318 recover.cpp:447] Starting replica recovery
> I1214 02:43:28.904681 30318 recover.cpp:473] Replica is in EMPTY status
> I1214 02:43:28.906260 30321 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (11998)@172.17.0.14:47889
> I1214 02:43:28.906774 30318 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I1214 02:43:28.907250 30324 recover.cpp:564] Updating replica status to
> STARTING
> I1214 02:43:28.934959 30332 master.cpp:366] Master
> 9f7c0c51-c954-4656-8c70-8928edaf1c37 (7e18acc3efba) started on
> 172.17.0.14:47889
> I1214 02:43:28.935000 30332 master.cpp:368] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/dxVfzg/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/dxVfzg/master" --zk_session_timeout="10secs"
> I1214 02:43:28.935612 30332 master.cpp:415] Master allowing
> unauthenticated frameworks to register
> I1214 02:43:28.935626 30332 master.cpp:418] Master only allowing
> authenticated slaves to register
> I1214 02:43:28.935636 30332 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/dxVfzg/credentials'
> I1214 02:43:28.935968 30332 master.cpp:457] Using default 'crammd5'
> authenticator
> I1214 02:43:28.936133 30332 master.cpp:494] Authorization enabled
> I1214 02:43:28.938345 30318 hierarchical.cpp:163] Initialized hierarchical
> allocator process
> I1214 02:43:28.938444 30318 whitelist_watcher.cpp:77] No whitelist given
> I1214 02:43:28.941223 30324 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 33.570634ms
> I1214 02:43:28.941279 30324 replica.cpp:320] Persisted replica status to
> STARTING
> I1214 02:43:28.941766 30324 recover.cpp:473] Replica is in STARTING status
> I1214 02:43:28.943775 30332 master.cpp:1636] The newly elected leader is
> master@172.17.0.14:47889 with id 9f7c0c51-c954-4656-8c70-8928edaf1c37
> I1214 02:43:28.943809 30332 master.cpp:1649] Elected as the leading master!
> I1214 02:43:28.943830 30332 master.cpp:1394] Recovering from registrar
> I1214 02:43:28.944121 30324 registrar.cpp:307] Recovering registrar
> I1214 02:43:28.945231 30319 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (12000)@172.17.0.14:47889
> I1214 02:43:28.945585 30332 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I1214 02:43:28.946403 30324 recover.cpp:564] Updating replica status to
> VOTING
> I1214 02:43:28.973847 30324 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 27.118069ms
> I1214 02:43:28.973935 30324 replica.cpp:320] Persisted replica status to
> VOTING
> I1214 02:43:28.974591 30324 recover.cpp:578] Successfully joined the Paxos
> group
> I1214 02:43:28.974797 30324 recover.cpp:462] Recover process terminated
> I1214 02:43:28.975829 30319 log.cpp:659] Attempting to start the writer
> I1214 02:43:28.979872 30319 replica.cpp:493] Replica received implicit
> promise request from (12001)@172.17.0.14:47889 with proposal 1
> I1214 02:43:29.008275 30319 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 28.308364ms
> I1214 02:43:29.008368 30319 replica.cpp:342] Persisted promised to 1
> I1214 02:43:29.012761 30324 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I1214 02:43:29.015265 30329 replica.cpp:388] Replica received explicit
> promise request from (12002)@172.17.0.14:47889 for position 0 with
> proposal 2
> 2015-12-14
> 02:43:29,022:30299(0x2ac688401700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:42074] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> I1214 02:43:29.041255 30329 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 25.898263ms
> I1214 02:43:29.041337 30329 replica.cpp:712] Persisted action at 0
> I1214 02:43:29.043071 30333 replica.cpp:537] Replica received write
> request for position 0 from (12003)@172.17.0.14:47889
> I1214 02:43:29.043149 30333 leveldb.cpp:436] Reading position from leveldb
> took 40765ns
> I1214 02:43:29.066076 30333 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 21.822771ms
> I1214 02:43:29.066174 30333 replica.cpp:712] Persisted action at 0
> I1214 02:43:29.067260 30331 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1214 02:43:29.091261 30331 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 23.944803ms
> I1214 02:43:29.091347 30331 replica.cpp:712] Persisted action at 0
> I1214 02:43:29.091384 30331 replica.cpp:697] Replica learned NOP action at
> position 0
> I1214 02:43:29.094339 30331 log.cpp:675] Writer started with ending
> position 0
> I1214 02:43:29.096915 30331 leveldb.cpp:436] Reading position from leveldb
> took 63144ns
> I1214 02:43:29.098291 30326 registrar.cpp:340] Successfully fetched the
> registry (0B) in 154.077952ms
> I1214 02:43:29.098435 30326 registrar.cpp:439] Applied 1 operations in
> 37296ns; attempting to update the 'registry'
> I1214 02:43:29.101058 30326 log.cpp:683] Attempting to append 173 bytes to
> the log
> I1214 02:43:29.101330 30326 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I1214 02:43:29.104568 30326 replica.cpp:537] Replica received write
> request for position 1 from (12004)@172.17.0.14:47889
> I1214 02:43:29.124737 30326 leveldb.cpp:341] Persisting action (192 bytes)
> to leveldb took 20.078639ms
> I1214 02:43:29.124825 30326 replica.cpp:712] Persisted action at 1
> I1214 02:43:29.126269 30321 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1214 02:43:29.149899 30321 leveldb.cpp:341] Persisting action (194 bytes)
> to leveldb took 23.576612ms
> I1214 02:43:29.149991 30321 replica.cpp:712] Persisted action at 1
> I1214 02:43:29.150032 30321 replica.cpp:697] Replica learned APPEND action
> at position 1
> I1214 02:43:29.152648 30321 registrar.cpp:484] Successfully updated the
> 'registry' in 54.128384ms
> I1214 02:43:29.152859 30321 registrar.cpp:370] Successfully recovered
> registrar
> I1214 02:43:29.153151 30321 log.cpp:702] Attempting to truncate the log to
> 1
> I1214 02:43:29.153640 30318 master.cpp:1446] Recovered 0 slaves from the
> Registry (134B) ; allowing 10mins for slaves to re-register
> I1214 02:43:29.153939 30330 hierarchical.cpp:181] Skipping recovery of
> hierarchical allocator: nothing to recover
> I1214 02:43:29.154611 30318 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I1214 02:43:29.156065 30325 replica.cpp:537] Replica received write
> request for position 2 from (12005)@172.17.0.14:47889
> I1214 02:43:29.210281 30325 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 54.130515ms
> I1214 02:43:29.210387 30325 replica.cpp:712] Persisted action at 2
> I1214 02:43:29.212040 30329 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1214 02:43:29.246289 30329 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 34.143003ms
> I1214 02:43:29.246459 30329 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 85892ns
> I1214 02:43:29.246490 30329 replica.cpp:712] Persisted action at 2
> I1214 02:43:29.246529 30329 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I1214 02:43:29.260473 30299 scheduler.cpp:154] Version: 0.27.0
> I1214 02:43:29.265128 30323 scheduler.cpp:236] New master detected at
> master@172.17.0.14:47889
> I1214 02:43:29.277081 30332 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.14:47889
> I1214 02:43:29.282414 30325 process.cpp:3224] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1214 02:43:29.282503 30325 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.14:39627
> I1214 02:43:29.282788 30325 master.cpp:1898] Received subscription request
> for HTTP framework 'default'
> I1214 02:43:29.282872 30325 master.cpp:1675] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1214 02:43:29.283383 30325 master.cpp:1990] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I1214 02:43:29.283975 30325 hierarchical.cpp:267] Added framework
> 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000
> I1214 02:43:29.284054 30325 hierarchical.cpp:1317] No resources available
> to allocate!
> I1214 02:43:29.284091 30325 hierarchical.cpp:1410] No inverse offers to
> send out!
> I1214 02:43:29.284121 30325 hierarchical.cpp:1063] Performed allocation
> for 0 slaves in 121039ns
> I1214 02:43:29.284154 30325 master.hpp:1588] Sending heartbeat to
> 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000
> I1214 02:43:29.286221 30328 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.14:47889
> I1214 02:43:29.286674 30328 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.14:47889
> I1214 02:43:29.287137 30320 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I1214 02:43:29.287746 30330 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.14:47889
> I1214 02:43:29.290495 30331 process.cpp:3224] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1214 02:43:29.290568 30331 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.14:39716
> I1214 02:43:29.290711 30331 master.cpp:2645] Processing REQUEST call for
> framework 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000 (default)
> I1214 02:43:29.290971 30331 hierarchical.cpp:562] Received resource
> request from framework 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000
> I1214 02:43:29.293977 30327 master.cpp:937] Master terminating
> I1214 02:43:29.297351 30328 hierarchical.cpp:308] Removed framework
> 9f7c0c51-c954-4656-8c70-8928edaf1c37-0000
> [ OK ] ContentType/SchedulerTest.Request/0 (574 ms)
> E1214 02:43:29.308874 30326 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ RUN ] ContentType/SchedulerTest.Request/1
> I1214 02:43:29.432704 30299 leveldb.cpp:174] Opened db in 103.870417ms
> I1214 02:43:29.467363 30299 leveldb.cpp:181] Compacted db in 34.570162ms
> I1214 02:43:29.467458 30299 leveldb.cpp:196] Created db iterator in 23607ns
> I1214 02:43:29.467474 30299 leveldb.cpp:202] Seeked to beginning of db in
> 3570ns
> I1214 02:43:29.467484 30299 leveldb.cpp:271] Iterated through 0 keys in
> the db in 278ns
> I1214 02:43:29.467540 30299 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1214 02:43:29.468473 30330 recover.cpp:447] Starting replica recovery
> I1214 02:43:29.469003 30322 recover.cpp:473] Replica is in EMPTY status
> I1214 02:43:29.470449 30322 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (12012)@172.17.0.14:47889
> I1214 02:43:29.470827 30330 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I1214 02:43:29.471329 30332 recover.cpp:564] Updating replica status to
> STARTING
> I1214 02:43:29.474527 30325 master.cpp:366] Master
> 00edb7b9-a97e-4fc5-b64e-e013e8db1208 (7e18acc3efba) started on
> 172.17.0.14:47889
> I1214 02:43:29.474565 30325 master.cpp:368] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/yftNcT/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/yftNcT/master" --zk_session_timeout="10secs"
> I1214 02:43:29.474912 30325 master.cpp:415] Master allowing
> unauthenticated frameworks to register
> I1214 02:43:29.474925 30325 master.cpp:418] Master only allowing
> authenticated slaves to register
> I1214 02:43:29.474933 30325 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/yftNcT/credentials'
> I1214 02:43:29.475311 30325 master.cpp:457] Using default 'crammd5'
> authenticator
> I1214 02:43:29.475474 30325 master.cpp:494] Authorization enabled
> I1214 02:43:29.475920 30321 hierarchical.cpp:163] Initialized hierarchical
> allocator process
> I1214 02:43:29.476060 30321 whitelist_watcher.cpp:77] No whitelist given
> I1214 02:43:29.478646 30327 master.cpp:1636] The newly elected leader is
> master@172.17.0.14:47889 with id 00edb7b9-a97e-4fc5-b64e-e013e8db1208
> I1214 02:43:29.478701 30327 master.cpp:1649] Elected as the leading master!
> I1214 02:43:29.478723 30327 master.cpp:1394] Recovering from registrar
> I1214 02:43:29.478981 30323 registrar.cpp:307] Recovering registrar
> I1214 02:43:29.498013 30332 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 26.436883ms
> I1214 02:43:29.498098 30332 replica.cpp:320] Persisted replica status to
> STARTING
> I1214 02:43:29.498478 30332 recover.cpp:473] Replica is in STARTING status
> I1214 02:43:29.500242 30323 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (12013)@172.17.0.14:47889
> I1214 02:43:29.500944 30332 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I1214 02:43:29.501499 30328 recover.cpp:564] Updating replica status to
> VOTING
> I1214 02:43:29.517614 30332 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 15.936743ms
> I1214 02:43:29.517702 30332 replica.cpp:320] Persisted replica status to
> VOTING
> I1214 02:43:29.517997 30332 recover.cpp:578] Successfully joined the Paxos
> group
> I1214 02:43:29.519294 30332 recover.cpp:462] Recover process terminated
> I1214 02:43:29.520104 30332 log.cpp:659] Attempting to start the writer
> I1214 02:43:29.529294 30323 replica.cpp:493] Replica received implicit
> promise request from (12014)@172.17.0.14:47889 with proposal 1
> I1214 02:43:29.551255 30323 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 21.878344ms
> I1214 02:43:29.551338 30323 replica.cpp:342] Persisted promised to 1
> I1214 02:43:29.552628 30323 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I1214 02:43:29.554110 30319 replica.cpp:388] Replica received explicit
> promise request from (12015)@172.17.0.14:47889 for position 0 with
> proposal 2
> I1214 02:43:29.576231 30319 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 22.04418ms
> I1214 02:43:29.576313 30319 replica.cpp:712] Persisted action at 0
> I1214 02:43:29.577962 30319 replica.cpp:537] Replica received write
> request for position 0 from (12016)@172.17.0.14:47889
> I1214 02:43:29.578052 30319 leveldb.cpp:436] Reading position from leveldb
> took 41520ns
> I1214 02:43:29.601379 30319 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 23.263854ms
> I1214 02:43:29.601464 30319 replica.cpp:712] Persisted action at 0
> I1214 02:43:29.602502 30319 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1214 02:43:29.618255 30319 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 15.360019ms
> I1214 02:43:29.618336 30319 replica.cpp:712] Persisted action at 0
> I1214 02:43:29.618371 30319 replica.cpp:697] Replica learned NOP action at
> position 0
> I1214 02:43:29.619550 30319 log.cpp:675] Writer started with ending
> position 0
> I1214 02:43:29.621335 30319 leveldb.cpp:436] Reading position from leveldb
> took 60420ns
> I1214 02:43:29.631992 30329 registrar.cpp:340] Successfully fetched the
> registry (0B) in 152.938752ms
> I1214 02:43:29.632189 30329 registrar.cpp:439] Applied 1 operations in
> 51893ns; attempting to update the 'registry'
> I1214 02:43:29.633105 30322 log.cpp:683] Attempting to append 173 bytes to
> the log
> I1214 02:43:29.634318 30322 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I1214 02:43:29.636517 30319 replica.cpp:537] Replica received write
> request for position 1 from (12017)@172.17.0.14:47889
> I1214 02:43:29.660120 30319 leveldb.cpp:341] Persisting action (192 bytes)
> to leveldb took 23.528111ms
> I1214 02:43:29.660213 30319 replica.cpp:712] Persisted action at 1
> I1214 02:43:29.664937 30331 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1214 02:43:29.690273 30331 leveldb.cpp:341] Persisting action (194 bytes)
> to leveldb took 25.260655ms
> I1214 02:43:29.690376 30331 replica.cpp:712] Persisted action at 1
> I1214 02:43:29.690415 30331 replica.cpp:697] Replica learned APPEND action
> at position 1
> I1214 02:43:29.695057 30331 registrar.cpp:484] Successfully updated the
> 'registry' in 62.77888ms
> I1214 02:43:29.695313 30331 registrar.cpp:370] Successfully recovered
> registrar
> I1214 02:43:29.695605 30331 log.cpp:702] Attempting to truncate the log to
> 1
> I1214 02:43:29.696771 30331 master.cpp:1446] Recovered 0 slaves from the
> Registry (134B) ; allowing 10mins for slaves to re-register
> I1214 02:43:29.696935 30331 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I1214 02:43:29.697432 30323 hierarchical.cpp:181] Skipping recovery of
> hierarchical allocator: nothing to recover
> I1214 02:43:29.699504 30329 replica.cpp:537] Replica received write
> request for position 2 from (12018)@172.17.0.14:47889
> I1214 02:43:29.732065 30329 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 32.483979ms
> I1214 02:43:29.732173 30329 replica.cpp:712] Persisted action at 2
> I1214 02:43:29.741094 30329 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1214 02:43:29.774277 30329 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 33.101424ms
> I1214 02:43:29.774442 30329 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 73627ns
> I1214 02:43:29.774472 30329 replica.cpp:712] Persisted action at 2
> I1214 02:43:29.774513 30329 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I1214 02:43:29.783314 30299 scheduler.cpp:154] Version: 0.27.0
> I1214 02:43:29.784826 30320 scheduler.cpp:236] New master detected at
> master@172.17.0.14:47889
> I1214 02:43:29.803352 30322 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.14:47889
> I1214 02:43:29.811533 30329 process.cpp:3224] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1214 02:43:29.811658 30329 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.14:42893
> I1214 02:43:29.812353 30329 master.cpp:1898] Received subscription request
> for HTTP framework 'default'
> I1214 02:43:29.812456 30329 master.cpp:1675] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1214 02:43:29.813136 30329 master.cpp:1990] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I1214 02:43:29.816903 30331 hierarchical.cpp:267] Added framework
> 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000
> I1214 02:43:29.817010 30331 hierarchical.cpp:1317] No resources available
> to allocate!
> I1214 02:43:29.817054 30331 hierarchical.cpp:1410] No inverse offers to
> send out!
> I1214 02:43:29.817087 30331 hierarchical.cpp:1063] Performed allocation
> for 0 slaves in 144726ns
> I1214 02:43:29.817091 30329 master.hpp:1588] Sending heartbeat to
> 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000
> I1214 02:43:29.818753 30321 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.14:47889
> I1214 02:43:29.819412 30321 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.14:47889
> I1214 02:43:29.820466 30332 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I1214 02:43:29.820691 30330 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.14:47889
> I1214 02:43:29.824892 30330 process.cpp:3224] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1214 02:43:29.825008 30330 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.14:42991
> I1214 02:43:29.825564 30330 master.cpp:2645] Processing REQUEST call for
> framework 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000 (default)
> I1214 02:43:29.826022 30330 hierarchical.cpp:562] Received resource
> request from framework 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000
> I1214 02:43:29.831028 30322 master.cpp:937] Master terminating
> I1214 02:43:29.833400 30333 hierarchical.cpp:308] Removed framework
> 00edb7b9-a97e-4fc5-b64e-e013e8db1208-0000
> E1214 02:43:29.842592 30331 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (557 ms)
> [----------] 22 tests from ContentType/SchedulerTest (17854 ms total)
>
> [----------] Global test environment tear-down
> [==========] 881 tests from 115 test cases ran. (829481 ms total)
> [ PASSED ] 880 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] HealthCheckTest.HealthStatusChange
>
> 1 FAILED TEST
> YOU HAVE 6 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[3]: *** [check-am] Error 2
> make[2]: make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> *** [check] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[1]: make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> *** [check-recursive] Error 1
> make: *** [distcheck] Error 1
> + docker rmi mesos-1450055668-7032
> Untagged: mesos-1450055668-7032:latest
> Deleted: 75c58ee8bc23b54f13562927a3bf4588f07e6141f1afc6b5f57eeeb79539c9e0
> Deleted: d5a1f507cbab6750d33746aa1c850a14992ed03f4701103746529f74e3e1ec6d
> Deleted: 0dba65bec7001d712ca34f9317db754bc3f4c53717be70c458f6eecedcdd66ee
> Deleted: 22a19ab5a0d60fc0a21fa929ef61bf1236c130c2000ac1913d40e5f0ef3bccbb
> Deleted: fa6f6bb3926b409a482afdd4002878419e7412f925f86b4f8b5dff8ce8d799b1
> Deleted: 71d8965af689e3efc742334724a59f6240570725d854ccdef4dff35676f5814e
> Deleted: c32a91a96b27819e5951d4de9a7e5d55f1899fbfa0bb26bfef991e75d3f0e0f1
> Deleted: 0ed8fa301699f040f5b6be13449935abd1c282d0c8d2c97f59eee07ecb5359c5
> Deleted: 80e349930a4cf33cfa08cab7cfc6c0de53403369c9a9b57d5bda83ec8ff1ff60
> Deleted: da80a16d7c98a3a6be6a98ca668be0ba454abf2c7ffa03627a15463bfff7f50e
> Deleted: 3afb12f89e0fccea0feab164c24b1d6b25322ebd154ff20505485b19cb84fca6
> Deleted: 3833e7b6bb0b64d3433e7b4caa450a051752c03a9de560f1f1d075cfbb4e153f
> Deleted: abb78fc0577f005bc3e1de74a8eee14e3278ca66fc5baf41f47f43203863bf11
>
> Error handling URL
> https://reviews.apache.org/api/review-requests/41299/reviews/: INTERNAL
> SERVER ERROR
> git clean -fd
> git reset --hard 6eb2f98b3f4f770319a63da31459459628b48b46
>
> Build step 'Execute shell' marked build as failure
>