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/08 12:03:06 UTC
Build failed in Jenkins: mesos-reviewbot #10079
See <https://builds.apache.org/job/mesos-reviewbot/10079/>
------------------------------------------
[...truncated 161719 lines...]
[ RUN ] ContentType/SchedulerTest.Request/0
I1208 11:02:42.708636 30294 leveldb.cpp:174] Opened db in 117.309094ms
I1208 11:02:42.759570 30294 leveldb.cpp:181] Compacted db in 50.866453ms
I1208 11:02:42.759654 30294 leveldb.cpp:196] Created db iterator in 23607ns
I1208 11:02:42.759672 30294 leveldb.cpp:202] Seeked to beginning of db in 2866ns
I1208 11:02:42.759685 30294 leveldb.cpp:271] Iterated through 0 keys in the db in 213ns
I1208 11:02:42.759734 30294 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1208 11:02:42.761361 30328 recover.cpp:447] Starting replica recovery
I1208 11:02:42.761669 30328 recover.cpp:473] Replica is in EMPTY status
I1208 11:02:42.763216 30323 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11509)@172.17.0.4:42696
I1208 11:02:42.763890 30317 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1208 11:02:42.766067 30317 recover.cpp:564] Updating replica status to STARTING
I1208 11:02:42.767665 30328 master.cpp:365] Master 56767078-cda1-45bc-9606-9a12ed224f5f (b46be12fa59d) started on 172.17.0.4:42696
I1208 11:02:42.767690 30328 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/0yY072/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/0yY072/master" --zk_session_timeout="10secs"
I1208 11:02:42.768028 30328 master.cpp:414] Master allowing unauthenticated frameworks to register
I1208 11:02:42.768046 30328 master.cpp:417] Master only allowing authenticated slaves to register
I1208 11:02:42.768056 30328 credentials.hpp:35] Loading credentials for authentication from '/tmp/0yY072/credentials'
I1208 11:02:42.768369 30328 master.cpp:456] Using default 'crammd5' authenticator
I1208 11:02:42.768507 30328 master.cpp:493] Authorization enabled
I1208 11:02:42.769352 30319 hierarchical.cpp:163] Initialized hierarchical allocator process
I1208 11:02:42.769420 30319 whitelist_watcher.cpp:77] No whitelist given
I1208 11:02:42.771370 30328 master.cpp:1637] The newly elected leader is master@172.17.0.4:42696 with id 56767078-cda1-45bc-9606-9a12ed224f5f
I1208 11:02:42.771410 30328 master.cpp:1650] Elected as the leading master!
I1208 11:02:42.771428 30328 master.cpp:1395] Recovering from registrar
I1208 11:02:42.771682 30328 registrar.cpp:307] Recovering registrar
I1208 11:02:42.810569 30326 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 44.301215ms
I1208 11:02:42.810757 30326 replica.cpp:321] Persisted replica status to STARTING
I1208 11:02:42.811323 30326 recover.cpp:473] Replica is in STARTING status
I1208 11:02:42.813271 30322 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11510)@172.17.0.4:42696
I1208 11:02:42.813964 30322 recover.cpp:193] Received a recover response from a replica in STARTING status
I1208 11:02:42.814779 30322 recover.cpp:564] Updating replica status to VOTING
I1208 11:02:42.848274 30322 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 33.138829ms
I1208 11:02:42.848382 30322 replica.cpp:321] Persisted replica status to VOTING
I1208 11:02:42.848706 30322 recover.cpp:578] Successfully joined the Paxos group
I1208 11:02:42.848961 30322 recover.cpp:462] Recover process terminated
I1208 11:02:42.850280 30322 log.cpp:659] Attempting to start the writer
I1208 11:02:42.853272 30326 replica.cpp:494] Replica received implicit promise request from (11511)@172.17.0.4:42696 with proposal 1
I1208 11:02:42.877527 30326 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 24.16608ms
I1208 11:02:42.877604 30326 replica.cpp:343] Persisted promised to 1
I1208 11:02:42.879071 30320 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1208 11:02:42.880864 30327 replica.cpp:389] Replica received explicit promise request from (11512)@172.17.0.4:42696 for position 0 with proposal 2
I1208 11:02:42.902678 30327 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 21.718411ms
I1208 11:02:42.902760 30327 replica.cpp:713] Persisted action at 0
I1208 11:02:42.904242 30326 replica.cpp:538] Replica received write request for position 0 from (11513)@172.17.0.4:42696
I1208 11:02:42.904328 30326 leveldb.cpp:436] Reading position from leveldb took 39369ns
I1208 11:02:42.953554 30326 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 49.157829ms
I1208 11:02:42.953631 30326 replica.cpp:713] Persisted action at 0
I1208 11:02:42.954509 30319 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1208 11:02:42.973459 30319 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 18.896116ms
I1208 11:02:42.973533 30319 replica.cpp:713] Persisted action at 0
I1208 11:02:42.973564 30319 replica.cpp:698] Replica learned NOP action at position 0
I1208 11:02:42.974592 30326 log.cpp:675] Writer started with ending position 0
I1208 11:02:42.976153 30326 leveldb.cpp:436] Reading position from leveldb took 44274ns
I1208 11:02:42.977413 30326 registrar.cpp:340] Successfully fetched the registry (0B) in 205.682944ms
I1208 11:02:42.977726 30326 registrar.cpp:439] Applied 1 operations in 32709ns; attempting to update the 'registry'
I1208 11:02:42.978799 30326 log.cpp:683] Attempting to append 170 bytes to the log
I1208 11:02:42.979085 30326 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1208 11:02:42.980140 30326 replica.cpp:538] Replica received write request for position 1 from (11514)@172.17.0.4:42696
I1208 11:02:42.998595 30326 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 18.313616ms
I1208 11:02:42.998669 30326 replica.cpp:713] Persisted action at 1
I1208 11:02:42.999613 30316 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1208 11:02:43.024250 30316 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 24.584661ms
I1208 11:02:43.024327 30316 replica.cpp:713] Persisted action at 1
I1208 11:02:43.024356 30316 replica.cpp:698] Replica learned APPEND action at position 1
I1208 11:02:43.025913 30320 registrar.cpp:484] Successfully updated the 'registry' in 48.00512ms
I1208 11:02:43.026062 30320 registrar.cpp:370] Successfully recovered registrar
I1208 11:02:43.026466 30320 master.cpp:1447] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1208 11:02:43.026636 30320 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1208 11:02:43.026993 30324 log.cpp:702] Attempting to truncate the log to 1
I1208 11:02:43.027156 30328 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1208 11:02:43.028157 30323 replica.cpp:538] Replica received write request for position 2 from (11515)@172.17.0.4:42696
I1208 11:02:43.049252 30323 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 21.022607ms
I1208 11:02:43.049338 30323 replica.cpp:713] Persisted action at 2
I1208 11:02:43.051026 30326 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1208 11:02:43.074249 30326 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.150612ms
I1208 11:02:43.074400 30326 leveldb.cpp:399] Deleting ~1 keys from leveldb took 74798ns
I1208 11:02:43.074431 30326 replica.cpp:713] Persisted action at 2
I1208 11:02:43.074465 30326 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1208 11:02:43.080986 30294 scheduler.cpp:154] Version: 0.27.0
I1208 11:02:43.082644 30323 scheduler.cpp:236] New master detected at master@172.17.0.4:42696
I1208 11:02:43.085331 30324 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.4:42696
I1208 11:02:43.089412 30323 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 11:02:43.089507 30323 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:58812
I1208 11:02:43.089752 30323 master.cpp:1899] Received subscription request for HTTP framework 'default'
I1208 11:02:43.089835 30323 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1208 11:02:43.090270 30323 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1208 11:02:43.090682 30315 hierarchical.cpp:267] Added framework 56767078-cda1-45bc-9606-9a12ed224f5f-0000
I1208 11:02:43.090757 30315 hierarchical.cpp:1317] No resources available to allocate!
I1208 11:02:43.090797 30315 hierarchical.cpp:1410] No inverse offers to send out!
I1208 11:02:43.090827 30315 hierarchical.cpp:1063] Performed allocation for 0 slaves in 118311ns
I1208 11:02:43.090839 30323 master.hpp:1583] Sending heartbeat to 56767078-cda1-45bc-9606-9a12ed224f5f-0000
I1208 11:02:43.093649 30315 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.4:42696
I1208 11:02:43.094549 30315 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.4:42696
I1208 11:02:43.095134 30315 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1208 11:02:43.097353 30315 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.4:42696
I1208 11:02:43.101413 30321 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 11:02:43.101502 30321 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:58813
I1208 11:02:43.101658 30321 master.cpp:2646] Processing REQUEST call for framework 56767078-cda1-45bc-9606-9a12ed224f5f-0000 (default)
I1208 11:02:43.101932 30321 hierarchical.cpp:562] Received resource request from framework 56767078-cda1-45bc-9606-9a12ed224f5f-0000
I1208 11:02:43.102756 30294 master.cpp:938] Master terminating
I1208 11:02:43.103385 30315 hierarchical.cpp:308] Removed framework 56767078-cda1-45bc-9606-9a12ed224f5f-0000
E1208 11:02:43.111222 30313 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (526 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1208 11:02:43.219132 30294 leveldb.cpp:174] Opened db in 101.708684ms
I1208 11:02:43.246203 30294 leveldb.cpp:181] Compacted db in 26.987924ms
I1208 11:02:43.246301 30294 leveldb.cpp:196] Created db iterator in 26481ns
I1208 11:02:43.246321 30294 leveldb.cpp:202] Seeked to beginning of db in 2945ns
I1208 11:02:43.246332 30294 leveldb.cpp:271] Iterated through 0 keys in the db in 210ns
I1208 11:02:43.246384 30294 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1208 11:02:43.247864 30326 recover.cpp:447] Starting replica recovery
I1208 11:02:43.249212 30318 recover.cpp:473] Replica is in EMPTY status
I1208 11:02:43.250052 30321 master.cpp:365] Master ad32feed-f9cb-449c-a00a-d81be86e6676 (b46be12fa59d) started on 172.17.0.4:42696
I1208 11:02:43.250073 30321 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/xsojjE/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/xsojjE/master" --zk_session_timeout="10secs"
I1208 11:02:43.250387 30321 master.cpp:414] Master allowing unauthenticated frameworks to register
I1208 11:02:43.250401 30321 master.cpp:417] Master only allowing authenticated slaves to register
I1208 11:02:43.250408 30321 credentials.hpp:35] Loading credentials for authentication from '/tmp/xsojjE/credentials'
I1208 11:02:43.250680 30321 master.cpp:456] Using default 'crammd5' authenticator
I1208 11:02:43.250797 30321 master.cpp:493] Authorization enabled
I1208 11:02:43.251426 30319 hierarchical.cpp:163] Initialized hierarchical allocator process
I1208 11:02:43.251490 30319 whitelist_watcher.cpp:77] No whitelist given
I1208 11:02:43.254575 30313 master.cpp:1637] The newly elected leader is master@172.17.0.4:42696 with id ad32feed-f9cb-449c-a00a-d81be86e6676
I1208 11:02:43.254621 30313 master.cpp:1650] Elected as the leading master!
I1208 11:02:43.254609 30326 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11520)@172.17.0.4:42696
I1208 11:02:43.254640 30313 master.cpp:1395] Recovering from registrar
I1208 11:02:43.254881 30313 registrar.cpp:307] Recovering registrar
I1208 11:02:43.255031 30327 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1208 11:02:43.255683 30327 recover.cpp:564] Updating replica status to STARTING
I1208 11:02:43.277664 30327 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.759923ms
I1208 11:02:43.277741 30327 replica.cpp:321] Persisted replica status to STARTING
I1208 11:02:43.278056 30327 recover.cpp:473] Replica is in STARTING status
I1208 11:02:43.280164 30327 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11521)@172.17.0.4:42696
I1208 11:02:43.280796 30328 recover.cpp:193] Received a recover response from a replica in STARTING status
I1208 11:02:43.281668 30328 recover.cpp:564] Updating replica status to VOTING
I1208 11:02:43.302819 30315 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 20.813156ms
I1208 11:02:43.302888 30315 replica.cpp:321] Persisted replica status to VOTING
I1208 11:02:43.303104 30315 recover.cpp:578] Successfully joined the Paxos group
I1208 11:02:43.303814 30328 log.cpp:659] Attempting to start the writer
I1208 11:02:43.305371 30315 recover.cpp:462] Recover process terminated
I1208 11:02:43.305965 30328 replica.cpp:494] Replica received implicit promise request from (11522)@172.17.0.4:42696 with proposal 1
I1208 11:02:43.337937 30328 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.916396ms
I1208 11:02:43.338021 30328 replica.cpp:343] Persisted promised to 1
I1208 11:02:43.346842 30313 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1208 11:02:43.348914 30319 replica.cpp:389] Replica received explicit promise request from (11523)@172.17.0.4:42696 for position 0 with proposal 2
I1208 11:02:43.388250 30319 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 39.248184ms
I1208 11:02:43.388330 30319 replica.cpp:713] Persisted action at 0
I1208 11:02:43.390197 30317 replica.cpp:538] Replica received write request for position 0 from (11524)@172.17.0.4:42696
I1208 11:02:43.390389 30317 leveldb.cpp:436] Reading position from leveldb took 52582ns
I1208 11:02:43.429924 30317 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 39.448233ms
I1208 11:02:43.430001 30317 replica.cpp:713] Persisted action at 0
I1208 11:02:43.431082 30321 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1208 11:02:43.471719 30321 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 40.000692ms
I1208 11:02:43.471823 30321 replica.cpp:713] Persisted action at 0
I1208 11:02:43.471864 30321 replica.cpp:698] Replica learned NOP action at position 0
I1208 11:02:43.473292 30321 log.cpp:675] Writer started with ending position 0
I1208 11:02:43.475216 30321 leveldb.cpp:436] Reading position from leveldb took 71407ns
I1208 11:02:43.476934 30321 registrar.cpp:340] Successfully fetched the registry (0B) in 222.00704ms
I1208 11:02:43.477366 30321 registrar.cpp:439] Applied 1 operations in 60745ns; attempting to update the 'registry'
I1208 11:02:43.478932 30321 log.cpp:683] Attempting to append 170 bytes to the log
I1208 11:02:43.479238 30324 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1208 11:02:43.480234 30318 replica.cpp:538] Replica received write request for position 1 from (11525)@172.17.0.4:42696
I1208 11:02:43.521983 30318 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 41.54012ms
I1208 11:02:43.522073 30318 replica.cpp:713] Persisted action at 1
I1208 11:02:43.523551 30327 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1208 11:02:43.563683 30327 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 40.068315ms
I1208 11:02:43.563778 30327 replica.cpp:713] Persisted action at 1
I1208 11:02:43.563817 30327 replica.cpp:698] Replica learned APPEND action at position 1
I1208 11:02:43.566040 30327 registrar.cpp:484] Successfully updated the 'registry' in 88.54016ms
I1208 11:02:43.566303 30322 log.cpp:702] Attempting to truncate the log to 1
I1208 11:02:43.566704 30322 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1208 11:02:43.567920 30322 replica.cpp:538] Replica received write request for position 2 from (11526)@172.17.0.4:42696
I1208 11:02:43.568163 30327 registrar.cpp:370] Successfully recovered registrar
I1208 11:02:43.568673 30327 master.cpp:1447] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1208 11:02:43.568907 30325 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1208 11:02:43.613893 30322 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 45.854122ms
I1208 11:02:43.613994 30322 replica.cpp:713] Persisted action at 2
I1208 11:02:43.615499 30322 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1208 11:02:43.655616 30322 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.060238ms
I1208 11:02:43.655791 30322 leveldb.cpp:399] Deleting ~1 keys from leveldb took 81251ns
I1208 11:02:43.655819 30322 replica.cpp:713] Persisted action at 2
I1208 11:02:43.655858 30322 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1208 11:02:43.663938 30294 scheduler.cpp:154] Version: 0.27.0
I1208 11:02:43.665838 30314 scheduler.cpp:236] New master detected at master@172.17.0.4:42696
I1208 11:02:43.668421 30316 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.4:42696
I1208 11:02:43.672642 30316 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 11:02:43.672736 30316 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:58814
I1208 11:02:43.674294 30316 master.cpp:1899] Received subscription request for HTTP framework 'default'
I1208 11:02:43.674396 30316 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1208 11:02:43.675807 30316 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1208 11:02:43.676642 30322 hierarchical.cpp:267] Added framework ad32feed-f9cb-449c-a00a-d81be86e6676-0000
I1208 11:02:43.676738 30322 hierarchical.cpp:1317] No resources available to allocate!
I1208 11:02:43.676780 30322 hierarchical.cpp:1410] No inverse offers to send out!
I1208 11:02:43.676810 30322 hierarchical.cpp:1063] Performed allocation for 0 slaves in 140733ns
I1208 11:02:43.677814 30316 master.hpp:1583] Sending heartbeat to ad32feed-f9cb-449c-a00a-d81be86e6676-0000
I1208 11:02:43.680929 30316 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.4:42696
I1208 11:02:43.684211 30316 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.4:42696
I1208 11:02:43.685528 30316 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.4:42696
I1208 11:02:43.689817 30316 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1208 11:02:43.692481 30314 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 11:02:43.692579 30314 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:58815
I1208 11:02:43.692998 30314 master.cpp:2646] Processing REQUEST call for framework ad32feed-f9cb-449c-a00a-d81be86e6676-0000 (default)
I1208 11:02:43.693294 30313 hierarchical.cpp:562] Received resource request from framework ad32feed-f9cb-449c-a00a-d81be86e6676-0000
I1208 11:02:43.694356 30294 master.cpp:938] Master terminating
I1208 11:02:43.695092 30316 hierarchical.cpp:308] Removed framework ad32feed-f9cb-449c-a00a-d81be86e6676-0000
E1208 11:02:43.696879 30315 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (591 ms)
[----------] 22 tests from ContentType/SchedulerTest (17251 ms total)
[----------] Global test environment tear-down
2015-12-08 11:02:44,014:30294(0x2b172b27f700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:54668] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
[==========] 879 tests from 115 test cases ran. (804655 ms total)
[ PASSED ] 877 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ] HealthCheckTest.CheckCommandTimeout
[ FAILED ] ReservationTest.ACLMultipleOperations
2 FAILED TESTS
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-1449568771-29250
Untagged: mesos-1449568771-29250:latest
Deleted: f148a40a1e43eda18c887c92881656f5ec3e4cdb0e9c1268b1a111e8006027a8
Deleted: 1ca1f8dd62b8a4444f7c177f0d26308bcea1ee8a61defde18626eecf18f6b303
Deleted: 49c3ab9ebfb0417418995c72725dd0716b49a8c0d293522539ff7fa14e6912ec
Deleted: c8e16db767c70a268c2b877f7d95e0cb9f0718019bafe379ffbfa970e9bd7b00
Deleted: d65506c8f0e02c9cd6d131759d48631574f4583f5977352e036d479d6bab38bc
Deleted: 80821eef292f02042455383e0b6e5652346bc4a92886ddc0e112da226e3b9f94
Deleted: dbc0195ebfebbe7db400bdf23d3efa65727e14346030ea8a0e88041e5e7243e9
Deleted: 4974c14d772d2e2e673c60f0317a41364c99e20571f3a23b85f3842e18abc9fc
Deleted: 967a715890ed156def00ccc084b4c15a95a0a0224b9e3098952855b5020186e5
Deleted: 85c65d059e8f6aa858d61a2ff55c135944b605216d88ddb56fe5fb4d528d00c4
Deleted: f61bad0efd6a2e5be64c7c1a373a4ef03e64f1edab6241e0521c564b9a53fdf9
Deleted: 35f1e571ce1921c055579fa2dd444a383392d76d5e0627fd717ae2fedcd59dd8
Deleted: c6bb6fec0174ac93108fee5e5d09e691baa51acaba913d4b8d080bcff231a9b7
Error handling URL https://reviews.apache.org/api/review-requests/41078/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard bdb64bc01e706ee44753d195a2d8982d9e44a77c
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #10081
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10081/>
Build failed in Jenkins: mesos-reviewbot #10080
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10080/>
------------------------------------------
[...truncated 161788 lines...]
[ RUN ] ContentType/SchedulerTest.Request/0
I1208 13:03:17.542994 30303 leveldb.cpp:174] Opened db in 182.515569ms
I1208 13:03:17.586107 30303 leveldb.cpp:181] Compacted db in 42.929878ms
I1208 13:03:17.586410 30303 leveldb.cpp:196] Created db iterator in 31458ns
I1208 13:03:17.586642 30303 leveldb.cpp:202] Seeked to beginning of db in 3860ns
I1208 13:03:17.586839 30303 leveldb.cpp:271] Iterated through 0 keys in the db in 441ns
I1208 13:03:17.587083 30303 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1208 13:03:17.588222 30333 recover.cpp:447] Starting replica recovery
I1208 13:03:17.588757 30336 recover.cpp:473] Replica is in EMPTY status
I1208 13:03:17.590545 30336 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11496)@172.17.0.4:49930
I1208 13:03:17.590912 30323 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1208 13:03:17.591461 30333 recover.cpp:564] Updating replica status to STARTING
I1208 13:03:17.593677 30329 master.cpp:365] Master 7f3ff10e-9c38-49a9-a5f8-e162de30ae47 (1c45dd3d9c95) started on 172.17.0.4:49930
I1208 13:03:17.593853 30329 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/oDD2Et/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/oDD2Et/master" --zk_session_timeout="10secs"
I1208 13:03:17.594401 30329 master.cpp:414] Master allowing unauthenticated frameworks to register
I1208 13:03:17.594614 30329 master.cpp:417] Master only allowing authenticated slaves to register
I1208 13:03:17.594779 30329 credentials.hpp:35] Loading credentials for authentication from '/tmp/oDD2Et/credentials'
I1208 13:03:17.595262 30329 master.cpp:456] Using default 'crammd5' authenticator
I1208 13:03:17.595901 30329 master.cpp:493] Authorization enabled
I1208 13:03:17.596299 30336 hierarchical.cpp:163] Initialized hierarchical allocator process
I1208 13:03:17.596504 30326 whitelist_watcher.cpp:77] No whitelist given
I1208 13:03:17.598965 30329 master.cpp:1637] The newly elected leader is master@172.17.0.4:49930 with id 7f3ff10e-9c38-49a9-a5f8-e162de30ae47
I1208 13:03:17.599009 30329 master.cpp:1650] Elected as the leading master!
I1208 13:03:17.599030 30329 master.cpp:1395] Recovering from registrar
I1208 13:03:17.599246 30327 registrar.cpp:307] Recovering registrar
I1208 13:03:17.626925 30333 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 35.137267ms
I1208 13:03:17.627008 30333 replica.cpp:321] Persisted replica status to STARTING
I1208 13:03:17.627413 30333 recover.cpp:473] Replica is in STARTING status
I1208 13:03:17.629757 30333 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11497)@172.17.0.4:49930
I1208 13:03:17.630434 30333 recover.cpp:193] Received a recover response from a replica in STARTING status
I1208 13:03:17.631149 30333 recover.cpp:564] Updating replica status to VOTING
I1208 13:03:17.666054 30326 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 34.54523ms
I1208 13:03:17.666133 30326 replica.cpp:321] Persisted replica status to VOTING
I1208 13:03:17.666424 30326 recover.cpp:578] Successfully joined the Paxos group
I1208 13:03:17.666630 30326 recover.cpp:462] Recover process terminated
I1208 13:03:17.667382 30326 log.cpp:659] Attempting to start the writer
I1208 13:03:17.669250 30330 replica.cpp:494] Replica received implicit promise request from (11498)@172.17.0.4:49930 with proposal 1
I1208 13:03:17.708153 30330 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.785843ms
I1208 13:03:17.708230 30330 replica.cpp:343] Persisted promised to 1
I1208 13:03:17.709489 30330 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1208 13:03:17.711244 30330 replica.cpp:389] Replica received explicit promise request from (11499)@172.17.0.4:49930 for position 0 with proposal 2
I1208 13:03:17.759052 30330 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 47.690501ms
I1208 13:03:17.759261 30330 replica.cpp:713] Persisted action at 0
I1208 13:03:17.761328 30330 replica.cpp:538] Replica received write request for position 0 from (11500)@172.17.0.4:49930
I1208 13:03:17.761426 30330 leveldb.cpp:436] Reading position from leveldb took 49153ns
I1208 13:03:17.796268 30330 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 34.764701ms
I1208 13:03:17.796362 30330 replica.cpp:713] Persisted action at 0
I1208 13:03:17.797447 30330 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1208 13:03:17.835278 30330 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 37.765441ms
I1208 13:03:17.835376 30330 replica.cpp:713] Persisted action at 0
I1208 13:03:17.835424 30330 replica.cpp:698] Replica learned NOP action at position 0
I1208 13:03:17.836695 30337 log.cpp:675] Writer started with ending position 0
I1208 13:03:17.838652 30337 leveldb.cpp:436] Reading position from leveldb took 63799ns
I1208 13:03:17.840139 30336 registrar.cpp:340] Successfully fetched the registry (0B) in 240.839936ms
I1208 13:03:17.840400 30336 registrar.cpp:439] Applied 1 operations in 42551ns; attempting to update the 'registry'
I1208 13:03:17.841871 30336 log.cpp:683] Attempting to append 170 bytes to the log
I1208 13:03:17.842095 30336 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1208 13:03:17.843112 30322 replica.cpp:538] Replica received write request for position 1 from (11501)@172.17.0.4:49930
I1208 13:03:17.887254 30322 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 44.077939ms
I1208 13:03:17.887332 30322 replica.cpp:713] Persisted action at 1
I1208 13:03:17.888633 30322 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1208 13:03:17.935534 30322 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 46.850015ms
I1208 13:03:17.935612 30322 replica.cpp:713] Persisted action at 1
I1208 13:03:17.935650 30322 replica.cpp:698] Replica learned APPEND action at position 1
I1208 13:03:17.938073 30322 registrar.cpp:484] Successfully updated the 'registry' in 97.593856ms
I1208 13:03:17.938405 30322 registrar.cpp:370] Successfully recovered registrar
I1208 13:03:17.938873 30322 master.cpp:1447] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1208 13:03:17.939061 30322 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1208 13:03:17.938215 30326 log.cpp:702] Attempting to truncate the log to 1
I1208 13:03:17.939467 30326 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1208 13:03:17.940732 30326 replica.cpp:538] Replica received write request for position 2 from (11502)@172.17.0.4:49930
I1208 13:03:17.986363 30326 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 45.567642ms
I1208 13:03:17.986440 30326 replica.cpp:713] Persisted action at 2
I1208 13:03:17.987740 30326 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1208 13:03:18.022812 30326 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 35.026186ms
I1208 13:03:18.022964 30326 leveldb.cpp:399] Deleting ~1 keys from leveldb took 75241ns
I1208 13:03:18.022992 30326 replica.cpp:713] Persisted action at 2
I1208 13:03:18.023025 30326 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1208 13:03:18.028048 30303 scheduler.cpp:154] Version: 0.27.0
I1208 13:03:18.029177 30326 scheduler.cpp:236] New master detected at master@172.17.0.4:49930
I1208 13:03:18.030601 30337 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.4:49930
I1208 13:03:18.033293 30325 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 13:03:18.033377 30325 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:57066
I1208 13:03:18.033632 30325 master.cpp:1899] Received subscription request for HTTP framework 'default'
I1208 13:03:18.033716 30325 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1208 13:03:18.034512 30325 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1208 13:03:18.036058 30328 master.hpp:1583] Sending heartbeat to 7f3ff10e-9c38-49a9-a5f8-e162de30ae47-0000
I1208 13:03:18.036205 30325 hierarchical.cpp:267] Added framework 7f3ff10e-9c38-49a9-a5f8-e162de30ae47-0000
I1208 13:03:18.036288 30325 hierarchical.cpp:1317] No resources available to allocate!
I1208 13:03:18.036324 30325 hierarchical.cpp:1410] No inverse offers to send out!
I1208 13:03:18.036352 30325 hierarchical.cpp:1063] Performed allocation for 0 slaves in 122501ns
I1208 13:03:18.038148 30337 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.4:49930
I1208 13:03:18.040458 30336 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.4:49930
I1208 13:03:18.041133 30336 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1208 13:03:18.042390 30332 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.4:49930
I1208 13:03:18.046270 30336 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 13:03:18.046418 30336 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:57067
I1208 13:03:18.046656 30336 master.cpp:2646] Processing REQUEST call for framework 7f3ff10e-9c38-49a9-a5f8-e162de30ae47-0000 (default)
I1208 13:03:18.047207 30336 hierarchical.cpp:562] Received resource request from framework 7f3ff10e-9c38-49a9-a5f8-e162de30ae47-0000
I1208 13:03:18.051107 30333 master.cpp:938] Master terminating
I1208 13:03:18.054368 30326 hierarchical.cpp:308] Removed framework 7f3ff10e-9c38-49a9-a5f8-e162de30ae47-0000
E1208 13:03:18.058095 30323 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (706 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
2015-12-08 13:03:18,070:30303(0x2af7b2673700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:40803] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I1208 13:03:18.207725 30303 leveldb.cpp:174] Opened db in 141.510926ms
I1208 13:03:18.271338 30303 leveldb.cpp:181] Compacted db in 63.523189ms
I1208 13:03:18.271689 30303 leveldb.cpp:196] Created db iterator in 43041ns
I1208 13:03:18.271812 30303 leveldb.cpp:202] Seeked to beginning of db in 3984ns
I1208 13:03:18.271920 30303 leveldb.cpp:271] Iterated through 0 keys in the db in 534ns
I1208 13:03:18.272089 30303 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1208 13:03:18.273721 30328 recover.cpp:447] Starting replica recovery
I1208 13:03:18.274047 30328 recover.cpp:473] Replica is in EMPTY status
I1208 13:03:18.275940 30328 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11507)@172.17.0.4:49930
I1208 13:03:18.276595 30328 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1208 13:03:18.278504 30328 recover.cpp:564] Updating replica status to STARTING
I1208 13:03:18.281433 30334 master.cpp:365] Master c120b0f6-3d51-4477-a3b4-675a011ed29a (1c45dd3d9c95) started on 172.17.0.4:49930
I1208 13:03:18.281568 30334 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/oIAKMM/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/oIAKMM/master" --zk_session_timeout="10secs"
I1208 13:03:18.282121 30334 master.cpp:414] Master allowing unauthenticated frameworks to register
I1208 13:03:18.282275 30334 master.cpp:417] Master only allowing authenticated slaves to register
I1208 13:03:18.282392 30334 credentials.hpp:35] Loading credentials for authentication from '/tmp/oIAKMM/credentials'
I1208 13:03:18.282944 30334 master.cpp:456] Using default 'crammd5' authenticator
I1208 13:03:18.283408 30334 master.cpp:493] Authorization enabled
I1208 13:03:18.283967 30327 hierarchical.cpp:163] Initialized hierarchical allocator process
I1208 13:03:18.284072 30327 whitelist_watcher.cpp:77] No whitelist given
I1208 13:03:18.286753 30337 master.cpp:1637] The newly elected leader is master@172.17.0.4:49930 with id c120b0f6-3d51-4477-a3b4-675a011ed29a
I1208 13:03:18.286989 30337 master.cpp:1650] Elected as the leading master!
I1208 13:03:18.287130 30337 master.cpp:1395] Recovering from registrar
I1208 13:03:18.287426 30331 registrar.cpp:307] Recovering registrar
I1208 13:03:18.309039 30328 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.981714ms
I1208 13:03:18.309221 30328 replica.cpp:321] Persisted replica status to STARTING
I1208 13:03:18.309721 30328 recover.cpp:473] Replica is in STARTING status
I1208 13:03:18.312261 30336 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11508)@172.17.0.4:49930
I1208 13:03:18.313343 30328 recover.cpp:193] Received a recover response from a replica in STARTING status
I1208 13:03:18.314087 30328 recover.cpp:564] Updating replica status to VOTING
I1208 13:03:18.351277 30335 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 36.929774ms
I1208 13:03:18.351354 30335 replica.cpp:321] Persisted replica status to VOTING
I1208 13:03:18.351601 30335 recover.cpp:578] Successfully joined the Paxos group
I1208 13:03:18.351790 30335 recover.cpp:462] Recover process terminated
I1208 13:03:18.353031 30335 log.cpp:659] Attempting to start the writer
I1208 13:03:18.355144 30335 replica.cpp:494] Replica received implicit promise request from (11509)@172.17.0.4:49930 with proposal 1
I1208 13:03:18.383256 30335 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 27.898085ms
I1208 13:03:18.383333 30335 replica.cpp:343] Persisted promised to 1
I1208 13:03:18.384863 30335 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1208 13:03:18.387364 30335 replica.cpp:389] Replica received explicit promise request from (11510)@172.17.0.4:49930 for position 0 with proposal 2
I1208 13:03:18.450050 30335 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 62.463088ms
I1208 13:03:18.450289 30335 replica.cpp:713] Persisted action at 0
I1208 13:03:18.452249 30323 replica.cpp:538] Replica received write request for position 0 from (11511)@172.17.0.4:49930
I1208 13:03:18.452448 30323 leveldb.cpp:436] Reading position from leveldb took 62675ns
I1208 13:03:18.473393 30323 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 20.780305ms
I1208 13:03:18.473608 30323 replica.cpp:713] Persisted action at 0
I1208 13:03:18.474932 30323 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1208 13:03:18.509982 30323 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 34.252748ms
I1208 13:03:18.510200 30323 replica.cpp:713] Persisted action at 0
I1208 13:03:18.510375 30323 replica.cpp:698] Replica learned NOP action at position 0
I1208 13:03:18.513864 30322 log.cpp:675] Writer started with ending position 0
I1208 13:03:18.515717 30323 leveldb.cpp:436] Reading position from leveldb took 70136ns
I1208 13:03:18.518301 30322 registrar.cpp:340] Successfully fetched the registry (0B) in 230.815232ms
I1208 13:03:18.518584 30322 registrar.cpp:439] Applied 1 operations in 45326ns; attempting to update the 'registry'
I1208 13:03:18.520014 30326 log.cpp:683] Attempting to append 170 bytes to the log
I1208 13:03:18.520412 30326 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1208 13:03:18.523002 30326 replica.cpp:538] Replica received write request for position 1 from (11512)@172.17.0.4:49930
I1208 13:03:18.569507 30326 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 46.397018ms
I1208 13:03:18.569630 30326 replica.cpp:713] Persisted action at 1
I1208 13:03:18.572293 30334 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1208 13:03:18.602931 30334 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 30.55652ms
I1208 13:03:18.603281 30334 replica.cpp:713] Persisted action at 1
I1208 13:03:18.603497 30334 replica.cpp:698] Replica learned APPEND action at position 1
I1208 13:03:18.606344 30327 registrar.cpp:484] Successfully updated the 'registry' in 87.676928ms
I1208 13:03:18.606868 30337 log.cpp:702] Attempting to truncate the log to 1
I1208 13:03:18.607089 30327 registrar.cpp:370] Successfully recovered registrar
I1208 13:03:18.607766 30327 master.cpp:1447] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1208 13:03:18.608197 30327 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1208 13:03:18.608750 30337 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1208 13:03:18.610071 30324 replica.cpp:538] Replica received write request for position 2 from (11513)@172.17.0.4:49930
I1208 13:03:18.645365 30324 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 35.222836ms
I1208 13:03:18.645563 30324 replica.cpp:713] Persisted action at 2
I1208 13:03:18.647274 30337 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1208 13:03:18.670342 30337 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 22.913046ms
I1208 13:03:18.670487 30337 leveldb.cpp:399] Deleting ~1 keys from leveldb took 68722ns
I1208 13:03:18.670519 30337 replica.cpp:713] Persisted action at 2
I1208 13:03:18.670558 30337 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1208 13:03:18.673025 30303 scheduler.cpp:154] Version: 0.27.0
I1208 13:03:18.674185 30337 scheduler.cpp:236] New master detected at master@172.17.0.4:49930
I1208 13:03:18.676481 30337 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.4:49930
I1208 13:03:18.683022 30324 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 13:03:18.684345 30324 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:57071
I1208 13:03:18.684906 30324 master.cpp:1899] Received subscription request for HTTP framework 'default'
I1208 13:03:18.685014 30324 master.cpp:1676] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1208 13:03:18.685729 30324 master.cpp:1991] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1208 13:03:18.688731 30327 hierarchical.cpp:267] Added framework c120b0f6-3d51-4477-a3b4-675a011ed29a-0000
I1208 13:03:18.688846 30327 hierarchical.cpp:1317] No resources available to allocate!
I1208 13:03:18.688891 30327 hierarchical.cpp:1410] No inverse offers to send out!
I1208 13:03:18.688925 30327 hierarchical.cpp:1063] Performed allocation for 0 slaves in 142284ns
I1208 13:03:18.689795 30332 master.hpp:1583] Sending heartbeat to c120b0f6-3d51-4477-a3b4-675a011ed29a-0000
I1208 13:03:18.697161 30334 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.4:49930
I1208 13:03:18.698132 30334 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.4:49930
I1208 13:03:18.700101 30334 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1208 13:03:18.702338 30334 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.4:49930
I1208 13:03:18.706881 30327 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 13:03:18.707108 30327 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:57072
I1208 13:03:18.707635 30327 master.cpp:2646] Processing REQUEST call for framework c120b0f6-3d51-4477-a3b4-675a011ed29a-0000 (default)
I1208 13:03:18.707968 30327 hierarchical.cpp:562] Received resource request from framework c120b0f6-3d51-4477-a3b4-675a011ed29a-0000
I1208 13:03:18.719604 30333 master.cpp:938] Master terminating
I1208 13:03:18.719854 30336 hierarchical.cpp:308] Removed framework c120b0f6-3d51-4477-a3b4-675a011ed29a-0000
E1208 13:03:18.742895 30325 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (708 ms)
[----------] 22 tests from ContentType/SchedulerTest (22798 ms total)
[----------] Global test environment tear-down
2015-12-08 13:03:21,406:30303(0x2af7b2673700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:40803] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
[==========] 879 tests from 115 test cases ran. (888356 ms total)
[ PASSED ] 878 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ReservationTest.ACLMultipleOperations
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-1449576071-2140
Untagged: mesos-1449576071-2140:latest
Deleted: cf1dced55f4d9d13ab7eea9d84f93e892234227b04b53e6f56ebae060f8bdf07
Deleted: b3b01e0917358124ef85fea83c328a82317c1238351008e7f7cfe399ec3ecfcc
Deleted: 51d7b0185cbf547a5ef7bbb3c6541dae6f6f7e590cf1be79ce885cbdeb57ebdb
Deleted: c9d6989e5f3b7d9ddd6e368d12c9babc93d54415a3dd70f17b056041871f3d91
Deleted: 3b5948008abc3215f52dd8e6c403db69ac48f44e41201cfa10402059a33ff7be
Deleted: a1dba964aaa3c0b52d7a4c6d2c9399341b05ea1c2da374c02374769648337798
Deleted: 40890368196bc97ab626edd4fd6e2cfabeb6e3022311358e8797a080f6d9f499
Deleted: 69a796a0eddf7894369ed1920b5ac7c345322d110ce6d16ca4466604e02d00a7
Deleted: 5b2cbc74919ecf47deba0a70732ab553068d680d9e9c364fed7e91c612750c1a
Deleted: a64eb692701b97c6b1f83bce9970012a5858fec6a733dc6450c0cece128d8f72
Deleted: ee41fd63e8ce05ba0d6c0512c62ae16a58d3ccf6a4bfdeb71dd3fe7bf526e836
Deleted: 04f03c37b5e53ffb412d6266666169a471e0907107b14a535907d23d82a87c98
Deleted: 655e2a7151ab4366550f30643521ff616d2c3c176cba57162a457c588f51fc36
Error handling URL https://reviews.apache.org/api/review-requests/41078/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard bdb64bc01e706ee44753d195a2d8982d9e44a77c
Build step 'Execute shell' marked build as failure