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 23:03:01 UTC
Build failed in Jenkins: mesos-reviewbot #10087
See <https://builds.apache.org/job/mesos-reviewbot/10087/changes>
Changes:
[yujie.jay] Fixed hyphen usage in comments.
[yujie.jay] Fixed punctuation usage in some error messages.
------------------------------------------
[...truncated 161754 lines...]
[ RUN ] ContentType/SchedulerTest.Request/0
I1208 22:02:34.222549 30306 leveldb.cpp:174] Opened db in 116.943484ms
I1208 22:02:34.266584 30306 leveldb.cpp:181] Compacted db in 43.950788ms
I1208 22:02:34.266683 30306 leveldb.cpp:196] Created db iterator in 29178ns
I1208 22:02:34.266702 30306 leveldb.cpp:202] Seeked to beginning of db in 3388ns
I1208 22:02:34.266713 30306 leveldb.cpp:271] Iterated through 0 keys in the db in 351ns
I1208 22:02:34.266773 30306 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1208 22:02:34.267557 30339 recover.cpp:447] Starting replica recovery
I1208 22:02:34.267863 30332 recover.cpp:473] Replica is in EMPTY status
I1208 22:02:34.269038 30339 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11624)@172.17.0.4:55298
I1208 22:02:34.269721 30332 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1208 22:02:34.270225 30328 recover.cpp:564] Updating replica status to STARTING
I1208 22:02:34.271350 30326 master.cpp:364] Master 24ad28e5-3246-404e-a182-ec2b1badf15f (c4828fc62d88) started on 172.17.0.4:55298
I1208 22:02:34.271441 30326 master.cpp:366] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/4tMm1j/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/4tMm1j/master" --zk_session_timeout="10secs"
I1208 22:02:34.271795 30326 master.cpp:413] Master allowing unauthenticated frameworks to register
I1208 22:02:34.271811 30326 master.cpp:416] Master only allowing authenticated slaves to register
I1208 22:02:34.271821 30326 credentials.hpp:35] Loading credentials for authentication from '/tmp/4tMm1j/credentials'
I1208 22:02:34.272151 30326 master.cpp:455] Using default 'crammd5' authenticator
I1208 22:02:34.272325 30326 master.cpp:492] Authorization enabled
I1208 22:02:34.272584 30328 whitelist_watcher.cpp:77] No whitelist given
I1208 22:02:34.272650 30337 hierarchical.cpp:147] Initialized hierarchical allocator process
I1208 22:02:34.274559 30333 master.cpp:1631] The newly elected leader is master@172.17.0.4:55298 with id 24ad28e5-3246-404e-a182-ec2b1badf15f
I1208 22:02:34.274597 30333 master.cpp:1644] Elected as the leading master!
I1208 22:02:34.274616 30333 master.cpp:1389] Recovering from registrar
I1208 22:02:34.274885 30333 registrar.cpp:307] Recovering registrar
I1208 22:02:34.312645 30338 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 42.278236ms
I1208 22:02:34.312746 30338 replica.cpp:321] Persisted replica status to STARTING
I1208 22:02:34.313135 30338 recover.cpp:473] Replica is in STARTING status
I1208 22:02:34.315129 30338 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11625)@172.17.0.4:55298
I1208 22:02:34.315553 30325 recover.cpp:193] Received a recover response from a replica in STARTING status
I1208 22:02:34.316071 30325 recover.cpp:564] Updating replica status to VOTING
I1208 22:02:34.354517 30330 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.257823ms
I1208 22:02:34.354612 30330 replica.cpp:321] Persisted replica status to VOTING
I1208 22:02:34.354970 30328 recover.cpp:578] Successfully joined the Paxos group
I1208 22:02:34.355365 30335 recover.cpp:462] Recover process terminated
I1208 22:02:34.364317 30333 log.cpp:659] Attempting to start the writer
I1208 22:02:34.366327 30333 replica.cpp:494] Replica received implicit promise request from (11626)@172.17.0.4:55298 with proposal 1
I1208 22:02:34.404644 30333 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.150585ms
I1208 22:02:34.404733 30333 replica.cpp:343] Persisted promised to 1
I1208 22:02:34.406059 30333 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1208 22:02:34.407884 30333 replica.cpp:389] Replica received explicit promise request from (11627)@172.17.0.4:55298 for position 0 with proposal 2
I1208 22:02:34.446414 30333 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 38.408432ms
I1208 22:02:34.446491 30333 replica.cpp:713] Persisted action at 0
I1208 22:02:34.447893 30325 replica.cpp:538] Replica received write request for position 0 from (11628)@172.17.0.4:55298
I1208 22:02:34.447980 30325 leveldb.cpp:436] Reading position from leveldb took 45991ns
I1208 22:02:34.488284 30325 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 40.230222ms
I1208 22:02:34.488375 30325 replica.cpp:713] Persisted action at 0
I1208 22:02:34.489264 30325 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1208 22:02:34.530264 30325 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 40.944795ms
I1208 22:02:34.530354 30325 replica.cpp:713] Persisted action at 0
I1208 22:02:34.530392 30325 replica.cpp:698] Replica learned NOP action at position 0
I1208 22:02:34.531553 30325 log.cpp:675] Writer started with ending position 0
I1208 22:02:34.533026 30325 leveldb.cpp:436] Reading position from leveldb took 56794ns
I1208 22:02:34.534317 30334 registrar.cpp:340] Successfully fetched the registry (0B) in 259.364096ms
I1208 22:02:34.534453 30334 registrar.cpp:439] Applied 1 operations in 39314ns; attempting to update the 'registry'
I1208 22:02:34.535259 30334 log.cpp:683] Attempting to append 170 bytes to the log
I1208 22:02:34.535361 30325 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1208 22:02:34.536211 30339 replica.cpp:538] Replica received write request for position 1 from (11629)@172.17.0.4:55298
I1208 22:02:34.581135 30339 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 44.860059ms
I1208 22:02:34.581231 30339 replica.cpp:713] Persisted action at 1
I1208 22:02:34.582533 30328 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1208 22:02:34.622885 30328 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 40.298816ms
I1208 22:02:34.622972 30328 replica.cpp:713] Persisted action at 1
I1208 22:02:34.623009 30328 replica.cpp:698] Replica learned APPEND action at position 1
I1208 22:02:34.624996 30339 registrar.cpp:484] Successfully updated the 'registry' in 90.476032ms
I1208 22:02:34.625133 30339 registrar.cpp:370] Successfully recovered registrar
I1208 22:02:34.625254 30328 log.cpp:702] Attempting to truncate the log to 1
I1208 22:02:34.625603 30328 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1208 22:02:34.626775 30328 replica.cpp:538] Replica received write request for position 2 from (11630)@172.17.0.4:55298
I1208 22:02:34.627389 30337 master.cpp:1441] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1208 22:02:34.629420 30339 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I1208 22:02:34.673065 30328 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 46.153169ms
I1208 22:02:34.673156 30328 replica.cpp:713] Persisted action at 2
I1208 22:02:34.674638 30328 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1208 22:02:34.718220 30328 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 43.520968ms
I1208 22:02:34.718384 30328 leveldb.cpp:399] Deleting ~1 keys from leveldb took 78688ns
I1208 22:02:34.718410 30328 replica.cpp:713] Persisted action at 2
I1208 22:02:34.718446 30328 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1208 22:02:34.725371 30306 scheduler.cpp:154] Version: 0.27.0
I1208 22:02:34.726402 30339 scheduler.cpp:236] New master detected at master@172.17.0.4:55298
I1208 22:02:34.727459 30339 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.4:55298
I1208 22:02:34.729513 30339 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 22:02:34.729579 30339 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:52337
I1208 22:02:34.729780 30339 master.cpp:1893] Received subscription request for HTTP framework 'default'
I1208 22:02:34.729849 30339 master.cpp:1670] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1208 22:02:34.730195 30339 master.cpp:1984] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1208 22:02:34.730690 30330 hierarchical.cpp:260] Added framework 24ad28e5-3246-404e-a182-ec2b1badf15f-0000
I1208 22:02:34.730763 30330 hierarchical.cpp:1327] No resources available to allocate!
I1208 22:02:34.730801 30330 hierarchical.cpp:1420] No inverse offers to send out!
I1208 22:02:34.730833 30330 hierarchical.cpp:1078] Performed allocation for 0 slaves in 118959ns
I1208 22:02:34.730872 30330 master.hpp:1602] Sending heartbeat to 24ad28e5-3246-404e-a182-ec2b1badf15f-0000
I1208 22:02:34.731884 30333 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.4:55298
I1208 22:02:34.732355 30333 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.4:55298
I1208 22:02:34.732949 30326 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1208 22:02:34.733196 30340 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.4:55298
I1208 22:02:34.734994 30327 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 22:02:34.735065 30327 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:52338
I1208 22:02:34.735204 30327 master.cpp:2638] Processing REQUEST call for framework 24ad28e5-3246-404e-a182-ec2b1badf15f-0000 (default)
I1208 22:02:34.735460 30327 hierarchical.cpp:579] Received resource request from framework 24ad28e5-3246-404e-a182-ec2b1badf15f-0000
I1208 22:02:34.735930 30328 master.cpp:932] Master terminating
I1208 22:02:34.736147 30327 hierarchical.cpp:321] Removed framework 24ad28e5-3246-404e-a182-ec2b1badf15f-0000
E1208 22:02:34.737305 30326 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (637 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1208 22:02:34.843507 30306 leveldb.cpp:174] Opened db in 101.035094ms
I1208 22:02:34.885638 30306 leveldb.cpp:181] Compacted db in 42.051007ms
I1208 22:02:34.885736 30306 leveldb.cpp:196] Created db iterator in 28535ns
I1208 22:02:34.885754 30306 leveldb.cpp:202] Seeked to beginning of db in 3807ns
I1208 22:02:34.885764 30306 leveldb.cpp:271] Iterated through 0 keys in the db in 305ns
I1208 22:02:34.885823 30306 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1208 22:02:34.886544 30330 recover.cpp:447] Starting replica recovery
I1208 22:02:34.886806 30330 recover.cpp:473] Replica is in EMPTY status
I1208 22:02:34.887872 30338 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11635)@172.17.0.4:55298
I1208 22:02:34.889057 30330 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1208 22:02:34.889498 30325 recover.cpp:564] Updating replica status to STARTING
I1208 22:02:34.891222 30334 master.cpp:364] Master 45c8ff88-d0ad-4487-baaa-97db425b47a5 (c4828fc62d88) started on 172.17.0.4:55298
I1208 22:02:34.891244 30334 master.cpp:366] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/eUhTnQ/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/eUhTnQ/master" --zk_session_timeout="10secs"
I1208 22:02:34.891548 30334 master.cpp:413] Master allowing unauthenticated frameworks to register
I1208 22:02:34.891564 30334 master.cpp:416] Master only allowing authenticated slaves to register
I1208 22:02:34.891572 30334 credentials.hpp:35] Loading credentials for authentication from '/tmp/eUhTnQ/credentials'
I1208 22:02:34.891868 30334 master.cpp:455] Using default 'crammd5' authenticator
I1208 22:02:34.891998 30334 master.cpp:492] Authorization enabled
I1208 22:02:34.893731 30339 hierarchical.cpp:147] Initialized hierarchical allocator process
I1208 22:02:34.893795 30339 whitelist_watcher.cpp:77] No whitelist given
I1208 22:02:34.894402 30336 master.cpp:1631] The newly elected leader is master@172.17.0.4:55298 with id 45c8ff88-d0ad-4487-baaa-97db425b47a5
I1208 22:02:34.894434 30336 master.cpp:1644] Elected as the leading master!
I1208 22:02:34.894457 30336 master.cpp:1389] Recovering from registrar
I1208 22:02:34.894695 30336 registrar.cpp:307] Recovering registrar
I1208 22:02:34.935564 30338 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 45.870261ms
I1208 22:02:34.935659 30338 replica.cpp:321] Persisted replica status to STARTING
I1208 22:02:34.936045 30338 recover.cpp:473] Replica is in STARTING status
I1208 22:02:34.938140 30338 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11636)@172.17.0.4:55298
I1208 22:02:34.938802 30338 recover.cpp:193] Received a recover response from a replica in STARTING status
I1208 22:02:34.939491 30338 recover.cpp:564] Updating replica status to VOTING
I1208 22:02:34.982553 30338 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 42.827667ms
I1208 22:02:34.982645 30338 replica.cpp:321] Persisted replica status to VOTING
I1208 22:02:34.982915 30338 recover.cpp:578] Successfully joined the Paxos group
I1208 22:02:34.983119 30338 recover.cpp:462] Recover process terminated
I1208 22:02:34.983820 30338 log.cpp:659] Attempting to start the writer
I1208 22:02:34.985646 30327 replica.cpp:494] Replica received implicit promise request from (11637)@172.17.0.4:55298 with proposal 1
I1208 22:02:35.024297 30327 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.476253ms
I1208 22:02:35.024391 30327 replica.cpp:343] Persisted promised to 1
I1208 22:02:35.025717 30327 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1208 22:02:35.027469 30331 replica.cpp:389] Replica received explicit promise request from (11638)@172.17.0.4:55298 for position 0 with proposal 2
I1208 22:02:35.066139 30331 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 38.541474ms
I1208 22:02:35.066237 30331 replica.cpp:713] Persisted action at 0
I1208 22:02:35.067988 30325 replica.cpp:538] Replica received write request for position 0 from (11639)@172.17.0.4:55298
I1208 22:02:35.068073 30325 leveldb.cpp:436] Reading position from leveldb took 43147ns
I1208 22:02:35.107970 30325 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 39.818717ms
I1208 22:02:35.108064 30325 replica.cpp:713] Persisted action at 0
I1208 22:02:35.109068 30325 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1208 22:02:35.149801 30325 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 40.680111ms
I1208 22:02:35.149893 30325 replica.cpp:713] Persisted action at 0
I1208 22:02:35.149930 30325 replica.cpp:698] Replica learned NOP action at position 0
I1208 22:02:35.151090 30325 log.cpp:675] Writer started with ending position 0
I1208 22:02:35.152730 30325 leveldb.cpp:436] Reading position from leveldb took 60105ns
I1208 22:02:35.154274 30325 registrar.cpp:340] Successfully fetched the registry (0B) in 259.531264ms
I1208 22:02:35.154507 30325 registrar.cpp:439] Applied 1 operations in 38245ns; attempting to update the 'registry'
I1208 22:02:35.155910 30325 log.cpp:683] Attempting to append 170 bytes to the log
I1208 22:02:35.156239 30332 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1208 22:02:35.157398 30335 replica.cpp:538] Replica received write request for position 1 from (11640)@172.17.0.4:55298
I1208 22:02:35.199996 30335 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 42.434702ms
I1208 22:02:35.200080 30335 replica.cpp:713] Persisted action at 1
I1208 22:02:35.200904 30329 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1208 22:02:35.241802 30329 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 40.844777ms
I1208 22:02:35.241901 30329 replica.cpp:713] Persisted action at 1
I1208 22:02:35.241941 30329 replica.cpp:698] Replica learned APPEND action at position 1
I1208 22:02:35.243506 30328 registrar.cpp:484] Successfully updated the 'registry' in 88.879104ms
I1208 22:02:35.243657 30328 registrar.cpp:370] Successfully recovered registrar
I1208 22:02:35.243866 30329 log.cpp:702] Attempting to truncate the log to 1
I1208 22:02:35.244318 30333 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1208 22:02:35.244369 30329 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I1208 22:02:35.244149 30328 master.cpp:1441] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1208 22:02:35.245465 30333 replica.cpp:538] Replica received write request for position 2 from (11641)@172.17.0.4:55298
2015-12-08 22:02:35,262:30306(0x2b5ca2370700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:44452] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I1208 22:02:35.283622 30333 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.040245ms
I1208 22:02:35.283823 30333 replica.cpp:713] Persisted action at 2
I1208 22:02:35.284993 30333 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1208 22:02:35.325285 30333 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.187259ms
I1208 22:02:35.325449 30333 leveldb.cpp:399] Deleting ~1 keys from leveldb took 78566ns
I1208 22:02:35.325476 30333 replica.cpp:713] Persisted action at 2
I1208 22:02:35.325513 30333 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1208 22:02:35.336755 30306 scheduler.cpp:154] Version: 0.27.0
I1208 22:02:35.337914 30326 scheduler.cpp:236] New master detected at master@172.17.0.4:55298
I1208 22:02:35.339006 30329 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.4:55298
I1208 22:02:35.341229 30328 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 22:02:35.341301 30328 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:52340
I1208 22:02:35.341729 30328 master.cpp:1893] Received subscription request for HTTP framework 'default'
I1208 22:02:35.341807 30328 master.cpp:1670] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1208 22:02:35.342288 30336 master.cpp:1984] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1208 22:02:35.342800 30335 hierarchical.cpp:260] Added framework 45c8ff88-d0ad-4487-baaa-97db425b47a5-0000
I1208 22:02:35.342881 30335 hierarchical.cpp:1327] No resources available to allocate!
I1208 22:02:35.342916 30335 hierarchical.cpp:1420] No inverse offers to send out!
I1208 22:02:35.342938 30335 hierarchical.cpp:1078] Performed allocation for 0 slaves in 112311ns
I1208 22:02:35.343145 30335 master.hpp:1602] Sending heartbeat to 45c8ff88-d0ad-4487-baaa-97db425b47a5-0000
I1208 22:02:35.343955 30327 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.4:55298
I1208 22:02:35.344796 30327 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.4:55298
I1208 22:02:35.345245 30327 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1208 22:02:35.346493 30327 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.4:55298
I1208 22:02:35.348726 30333 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 22:02:35.348799 30333 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.0.4:52341
I1208 22:02:35.349097 30333 master.cpp:2638] Processing REQUEST call for framework 45c8ff88-d0ad-4487-baaa-97db425b47a5-0000 (default)
I1208 22:02:35.349329 30334 hierarchical.cpp:579] Received resource request from framework 45c8ff88-d0ad-4487-baaa-97db425b47a5-0000
I1208 22:02:35.350268 30335 master.cpp:932] Master terminating
I1208 22:02:35.350950 30335 hierarchical.cpp:321] Removed framework 45c8ff88-d0ad-4487-baaa-97db425b47a5-0000
E1208 22:02:35.352056 30331 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (615 ms)
[----------] 22 tests from ContentType/SchedulerTest (17422 ms total)
[----------] Global test environment tear-down
[==========] 887 tests from 116 test cases ran. (808956 ms total)
[ PASSED ] 885 tests.
[ FAILED ] 2 tests, listed below:
[ FAILED ] HealthTest.ObserveEndpoint
[ FAILED ] ReservationTest.ACLMultipleOperations
2 FAILED TESTS
YOU HAVE 7 DISABLED TESTS
make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[4]: *** [check-local] Error 1
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[3]: *** [check-am] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: *** [check] Error 2
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1449608822-14548
Untagged: mesos-1449608822-14548:latest
Deleted: 329af337283df7f59770bfea102b87679916ae12980b05102086c352efa3e96e
Deleted: 4675fde18a31b9d9f32e411e37fcf6c2e67db543491169ba4397181115f598bb
Deleted: 3b6305be588cc974c5ec14868576b338072f50ccaa1ad95c970c771d3245d484
Deleted: 8ea793613e8c19c5856e9bf8b864a75e108a17a0f73374bf5e4df4f93b3c97ca
Deleted: a0834916982ea11a12a5d8d42f401a2537880d37ccc686bb287c951e2969d498
Deleted: 7b82d644c2a65b2cb9564240376c4d5bf867782524fe481a98963a965e485a7c
Deleted: 890476f2af6b7d881c5ca5c9b5d43993b5f1a788159f78104687b9c66e2a037c
Deleted: 01ffa1df9e293e49fa473638977a062dfa454b00ed5965e9018d624f02b15a82
Deleted: 2f9b529418e78af8bd6b0b38fb00e57228d6e6a21efd7af9994914cddf773d72
Deleted: 773050d11b2ec61adc474dfa47a795031df152060a1691a4ef104f0b5c06cd7e
Deleted: bc2b2cb55f14ec83918be1e067a005989d4c576ee7a7ad85a3b5d77463121460
Deleted: 862311176cb7692d6d65abe3f19e67d3ad242976ee2b06e4eedbccaa6d6c2dc0
Deleted: 2c904fbfa7ff8bedcc14aceb5e5a84ab707b9bd2b0107147bb872cd0e355a496
Error handling URL https://reviews.apache.org/api/review-requests/41076/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 04c3d6e66e5310f86f687fc64a6ed45c793d9778
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #10089
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10089/changes>
Build failed in Jenkins: mesos-reviewbot #10088
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/10088/changes>
Changes:
[joris.van.remoortere] Libprocess: Marked Boost as a "system" header.
[joris.van.remoortere] Mesos: Marked Boost as a "system" header.
[yujie.jay] Fixed a broken test: HealthTest.ObserveEndpoint.
[yujie.jay] Added documentation about the Mesos "sandbox".
------------------------------------------
[...truncated 160990 lines...]
I1208 23:29:47.918787 30339 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_twzfUD/slaves/ccfaa141-ce39-421d-ad5b-e7364d2ab45e-S0/frameworks/ccfaa141-ce39-421d-ad5b-e7364d2ab45e-0000' for gc 6.9999893664237days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (559 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1208 23:29:48.017364 30312 leveldb.cpp:174] Opened db in 94.508777ms
I1208 23:29:48.050487 30312 leveldb.cpp:181] Compacted db in 33.057092ms
I1208 23:29:48.050578 30312 leveldb.cpp:196] Created db iterator in 25708ns
I1208 23:29:48.050628 30312 leveldb.cpp:202] Seeked to beginning of db in 3297ns
I1208 23:29:48.050648 30312 leveldb.cpp:271] Iterated through 0 keys in the db in 401ns
I1208 23:29:48.050707 30312 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1208 23:29:48.051308 30339 recover.cpp:447] Starting replica recovery
I1208 23:29:48.051535 30339 recover.cpp:473] Replica is in EMPTY status
I1208 23:29:48.052708 30334 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11655)@172.17.0.1:55250
I1208 23:29:48.053102 30339 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1208 23:29:48.053519 30332 recover.cpp:564] Updating replica status to STARTING
I1208 23:29:48.054370 30335 master.cpp:364] Master 330d477a-d1a8-4022-90ea-e05cbf53f659 (eea11dc22e8b) started on 172.17.0.1:55250
I1208 23:29:48.054394 30335 master.cpp:366] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/nzYYLT/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/nzYYLT/master" --zk_session_timeout="10secs"
I1208 23:29:48.054656 30335 master.cpp:413] Master allowing unauthenticated frameworks to register
I1208 23:29:48.054666 30335 master.cpp:416] Master only allowing authenticated slaves to register
I1208 23:29:48.054672 30335 credentials.hpp:35] Loading credentials for authentication from '/tmp/nzYYLT/credentials'
I1208 23:29:48.054915 30335 master.cpp:455] Using default 'crammd5' authenticator
I1208 23:29:48.055035 30335 master.cpp:492] Authorization enabled
I1208 23:29:48.055188 30344 hierarchical.cpp:147] Initialized hierarchical allocator process
I1208 23:29:48.055188 30340 whitelist_watcher.cpp:77] No whitelist given
I1208 23:29:48.056548 30340 master.cpp:1631] The newly elected leader is master@172.17.0.1:55250 with id 330d477a-d1a8-4022-90ea-e05cbf53f659
I1208 23:29:48.056582 30340 master.cpp:1644] Elected as the leading master!
I1208 23:29:48.056629 30340 master.cpp:1389] Recovering from registrar
I1208 23:29:48.056804 30343 registrar.cpp:307] Recovering registrar
I1208 23:29:48.078359 30339 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 24.625736ms
I1208 23:29:48.078425 30339 replica.cpp:321] Persisted replica status to STARTING
I1208 23:29:48.078722 30334 recover.cpp:473] Replica is in STARTING status
I1208 23:29:48.079797 30344 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11656)@172.17.0.1:55250
I1208 23:29:48.080319 30334 recover.cpp:193] Received a recover response from a replica in STARTING status
I1208 23:29:48.081014 30332 recover.cpp:564] Updating replica status to VOTING
I1208 23:29:48.103590 30331 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.319618ms
I1208 23:29:48.103658 30331 replica.cpp:321] Persisted replica status to VOTING
I1208 23:29:48.103787 30340 recover.cpp:578] Successfully joined the Paxos group
I1208 23:29:48.104145 30340 recover.cpp:462] Recover process terminated
I1208 23:29:48.104739 30343 log.cpp:659] Attempting to start the writer
I1208 23:29:48.106261 30340 replica.cpp:494] Replica received implicit promise request from (11657)@172.17.0.1:55250 with proposal 1
I1208 23:29:48.128726 30340 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.422918ms
I1208 23:29:48.128774 30340 replica.cpp:343] Persisted promised to 1
I1208 23:29:48.130537 30332 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1208 23:29:48.132407 30344 replica.cpp:389] Replica received explicit promise request from (11658)@172.17.0.1:55250 for position 0 with proposal 2
I1208 23:29:48.153733 30344 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 21.282177ms
I1208 23:29:48.153760 30344 replica.cpp:713] Persisted action at 0
I1208 23:29:48.155362 30345 replica.cpp:538] Replica received write request for position 0 from (11659)@172.17.0.1:55250
I1208 23:29:48.155437 30345 leveldb.cpp:436] Reading position from leveldb took 34704ns
I1208 23:29:48.178853 30345 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.355203ms
I1208 23:29:48.178890 30345 replica.cpp:713] Persisted action at 0
I1208 23:29:48.180002 30345 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1208 23:29:48.203989 30345 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.943495ms
I1208 23:29:48.204028 30345 replica.cpp:713] Persisted action at 0
I1208 23:29:48.204051 30345 replica.cpp:698] Replica learned NOP action at position 0
I1208 23:29:48.205013 30339 log.cpp:675] Writer started with ending position 0
I1208 23:29:48.206250 30336 leveldb.cpp:436] Reading position from leveldb took 59182ns
I1208 23:29:48.207341 30336 registrar.cpp:340] Successfully fetched the registry (0B) in 150.49216ms
I1208 23:29:48.207455 30336 registrar.cpp:439] Applied 1 operations in 29546ns; attempting to update the 'registry'
I1208 23:29:48.208133 30344 log.cpp:683] Attempting to append 170 bytes to the log
I1208 23:29:48.208262 30342 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1208 23:29:48.208946 30339 replica.cpp:538] Replica received write request for position 1 from (11660)@172.17.0.1:55250
I1208 23:29:48.229073 30339 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 20.078013ms
I1208 23:29:48.229135 30339 replica.cpp:713] Persisted action at 1
I1208 23:29:48.229888 30345 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1208 23:29:48.254192 30345 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 24.263734ms
I1208 23:29:48.254256 30345 replica.cpp:713] Persisted action at 1
I1208 23:29:48.254284 30345 replica.cpp:698] Replica learned APPEND action at position 1
I1208 23:29:48.255517 30336 registrar.cpp:484] Successfully updated the 'registry' in 47.992832ms
I1208 23:29:48.255674 30336 registrar.cpp:370] Successfully recovered registrar
I1208 23:29:48.255756 30345 log.cpp:702] Attempting to truncate the log to 1
I1208 23:29:48.255939 30333 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1208 23:29:48.256153 30339 master.cpp:1441] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1208 23:29:48.256175 30342 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I1208 23:29:48.256734 30339 replica.cpp:538] Replica received write request for position 2 from (11661)@172.17.0.1:55250
I1208 23:29:48.279292 30339 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 22.50745ms
I1208 23:29:48.279350 30339 replica.cpp:713] Persisted action at 2
I1208 23:29:48.280190 30343 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1208 23:29:48.304560 30343 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 24.32517ms
I1208 23:29:48.304695 30343 leveldb.cpp:399] Deleting ~1 keys from leveldb took 59189ns
I1208 23:29:48.304716 30343 replica.cpp:713] Persisted action at 2
I1208 23:29:48.304744 30343 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1208 23:29:48.307214 30312 scheduler.cpp:154] Version: 0.27.0
I1208 23:29:48.307916 30346 scheduler.cpp:236] New master detected at master@172.17.0.1:55250
I1208 23:29:48.308882 30333 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.1:55250
I1208 23:29:48.310708 30346 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 23:29:48.310757 30346 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:46697
I1208 23:29:48.310925 30346 master.cpp:1893] Received subscription request for HTTP framework 'default'
I1208 23:29:48.310981 30346 master.cpp:1670] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1208 23:29:48.311213 30346 master.cpp:1984] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1208 23:29:48.311579 30333 hierarchical.cpp:260] Added framework 330d477a-d1a8-4022-90ea-e05cbf53f659-0000
I1208 23:29:48.311645 30341 master.hpp:1602] Sending heartbeat to 330d477a-d1a8-4022-90ea-e05cbf53f659-0000
I1208 23:29:48.311738 30333 hierarchical.cpp:1327] No resources available to allocate!
I1208 23:29:48.311898 30333 hierarchical.cpp:1420] No inverse offers to send out!
I1208 23:29:48.312027 30333 hierarchical.cpp:1078] Performed allocation for 0 slaves in 331172ns
I1208 23:29:48.312294 30342 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.1:55250
I1208 23:29:48.312868 30342 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.1:55250
I1208 23:29:48.313506 30333 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1208 23:29:48.313572 30342 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.1:55250
I1208 23:29:48.314836 30336 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 23:29:48.314935 30336 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:46698
I1208 23:29:48.315035 30336 master.cpp:2638] Processing REQUEST call for framework 330d477a-d1a8-4022-90ea-e05cbf53f659-0000 (default)
I1208 23:29:48.315192 30334 hierarchical.cpp:579] Received resource request from framework 330d477a-d1a8-4022-90ea-e05cbf53f659-0000
I1208 23:29:48.315456 30332 master.cpp:932] Master terminating
I1208 23:29:48.315652 30336 hierarchical.cpp:321] Removed framework 330d477a-d1a8-4022-90ea-e05cbf53f659-0000
E1208 23:29:48.316663 30340 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (400 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1208 23:29:48.410049 30312 leveldb.cpp:174] Opened db in 87.562223ms
I1208 23:29:48.443753 30312 leveldb.cpp:181] Compacted db in 33.633694ms
I1208 23:29:48.443840 30312 leveldb.cpp:196] Created db iterator in 23513ns
I1208 23:29:48.443856 30312 leveldb.cpp:202] Seeked to beginning of db in 2856ns
I1208 23:29:48.443863 30312 leveldb.cpp:271] Iterated through 0 keys in the db in 217ns
I1208 23:29:48.443907 30312 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1208 23:29:48.444851 30339 recover.cpp:447] Starting replica recovery
I1208 23:29:48.445102 30339 recover.cpp:473] Replica is in EMPTY status
I1208 23:29:48.446108 30336 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11666)@172.17.0.1:55250
I1208 23:29:48.446553 30340 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1208 23:29:48.446980 30336 recover.cpp:564] Updating replica status to STARTING
I1208 23:29:48.447258 30343 master.cpp:364] Master 520970ce-9ee6-4c24-a60c-e8ca69ae4769 (eea11dc22e8b) started on 172.17.0.1:55250
I1208 23:29:48.447365 30343 master.cpp:366] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/35rgh0/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/35rgh0/master" --zk_session_timeout="10secs"
I1208 23:29:48.447583 30343 master.cpp:413] Master allowing unauthenticated frameworks to register
I1208 23:29:48.447593 30343 master.cpp:416] Master only allowing authenticated slaves to register
I1208 23:29:48.447599 30343 credentials.hpp:35] Loading credentials for authentication from '/tmp/35rgh0/credentials'
I1208 23:29:48.447866 30343 master.cpp:455] Using default 'crammd5' authenticator
I1208 23:29:48.447978 30343 master.cpp:492] Authorization enabled
I1208 23:29:48.448143 30333 hierarchical.cpp:147] Initialized hierarchical allocator process
I1208 23:29:48.448145 30346 whitelist_watcher.cpp:77] No whitelist given
I1208 23:29:48.449648 30332 master.cpp:1631] The newly elected leader is master@172.17.0.1:55250 with id 520970ce-9ee6-4c24-a60c-e8ca69ae4769
I1208 23:29:48.449678 30332 master.cpp:1644] Elected as the leading master!
I1208 23:29:48.449692 30332 master.cpp:1389] Recovering from registrar
I1208 23:29:48.449810 30340 registrar.cpp:307] Recovering registrar
I1208 23:29:48.477480 30334 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.327366ms
I1208 23:29:48.477553 30334 replica.cpp:321] Persisted replica status to STARTING
I1208 23:29:48.477867 30339 recover.cpp:473] Replica is in STARTING status
I1208 23:29:48.478885 30346 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11667)@172.17.0.1:55250
I1208 23:29:48.479291 30333 recover.cpp:193] Received a recover response from a replica in STARTING status
I1208 23:29:48.479797 30331 recover.cpp:564] Updating replica status to VOTING
I1208 23:29:48.510886 30343 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.943416ms
I1208 23:29:48.510943 30343 replica.cpp:321] Persisted replica status to VOTING
I1208 23:29:48.511076 30343 recover.cpp:578] Successfully joined the Paxos group
I1208 23:29:48.511381 30343 recover.cpp:462] Recover process terminated
I1208 23:29:48.511965 30346 log.cpp:659] Attempting to start the writer
I1208 23:29:48.513172 30338 replica.cpp:494] Replica received implicit promise request from (11668)@172.17.0.1:55250 with proposal 1
I1208 23:29:48.544359 30338 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.144133ms
I1208 23:29:48.544423 30338 replica.cpp:343] Persisted promised to 1
I1208 23:29:48.545336 30335 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1208 23:29:48.546802 30346 replica.cpp:389] Replica received explicit promise request from (11669)@172.17.0.1:55250 for position 0 with proposal 2
I1208 23:29:48.573544 30346 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 26.683588ms
I1208 23:29:48.573583 30346 replica.cpp:713] Persisted action at 0
I1208 23:29:48.575026 30341 replica.cpp:538] Replica received write request for position 0 from (11670)@172.17.0.1:55250
I1208 23:29:48.575105 30341 leveldb.cpp:436] Reading position from leveldb took 39204ns
I1208 23:29:48.604914 30341 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 29.745838ms
I1208 23:29:48.604961 30341 replica.cpp:713] Persisted action at 0
I1208 23:29:48.605710 30337 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1208 23:29:48.630000 30337 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 24.244921ms
I1208 23:29:48.630061 30337 replica.cpp:713] Persisted action at 0
I1208 23:29:48.630085 30337 replica.cpp:698] Replica learned NOP action at position 0
I1208 23:29:48.630834 30331 log.cpp:675] Writer started with ending position 0
I1208 23:29:48.631902 30335 leveldb.cpp:436] Reading position from leveldb took 44654ns
I1208 23:29:48.632803 30335 registrar.cpp:340] Successfully fetched the registry (0B) in 182.906112ms
I1208 23:29:48.632910 30335 registrar.cpp:439] Applied 1 operations in 29959ns; attempting to update the 'registry'
I1208 23:29:48.633550 30333 log.cpp:683] Attempting to append 170 bytes to the log
I1208 23:29:48.633678 30331 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1208 23:29:48.634364 30332 replica.cpp:538] Replica received write request for position 1 from (11671)@172.17.0.1:55250
I1208 23:29:48.655035 30332 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 20.625189ms
I1208 23:29:48.655087 30332 replica.cpp:713] Persisted action at 1
I1208 23:29:48.655750 30346 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1208 23:29:48.680296 30346 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 24.501248ms
I1208 23:29:48.680359 30346 replica.cpp:713] Persisted action at 1
I1208 23:29:48.680387 30346 replica.cpp:698] Replica learned APPEND action at position 1
I1208 23:29:48.681674 30345 registrar.cpp:484] Successfully updated the 'registry' in 48.69504ms
I1208 23:29:48.681795 30345 registrar.cpp:370] Successfully recovered registrar
I1208 23:29:48.681965 30335 log.cpp:702] Attempting to truncate the log to 1
I1208 23:29:48.682189 30343 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1208 23:29:48.682379 30331 master.cpp:1441] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1208 23:29:48.682472 30340 hierarchical.cpp:165] Skipping recovery of hierarchical allocator: nothing to recover
I1208 23:29:48.682997 30336 replica.cpp:538] Replica received write request for position 2 from (11672)@172.17.0.1:55250
I1208 23:29:48.705265 30336 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 22.219144ms
I1208 23:29:48.705322 30336 replica.cpp:713] Persisted action at 2
I1208 23:29:48.706131 30339 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1208 23:29:48.747104 30339 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.922052ms
I1208 23:29:48.747242 30339 leveldb.cpp:399] Deleting ~1 keys from leveldb took 68325ns
I1208 23:29:48.747277 30339 replica.cpp:713] Persisted action at 2
I1208 23:29:48.747308 30339 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1208 23:29:48.753553 30312 scheduler.cpp:154] Version: 0.27.0
I1208 23:29:48.754184 30342 scheduler.cpp:236] New master detected at master@172.17.0.1:55250
I1208 23:29:48.755153 30331 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.1:55250
I1208 23:29:48.756898 30337 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 23:29:48.756964 30337 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:46699
I1208 23:29:48.757257 30337 master.cpp:1893] Received subscription request for HTTP framework 'default'
I1208 23:29:48.757316 30337 master.cpp:1670] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1208 23:29:48.757550 30337 master.cpp:1984] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1208 23:29:48.757931 30336 hierarchical.cpp:260] Added framework 520970ce-9ee6-4c24-a60c-e8ca69ae4769-0000
I1208 23:29:48.757997 30336 hierarchical.cpp:1327] No resources available to allocate!
I1208 23:29:48.758030 30337 master.hpp:1602] Sending heartbeat to 520970ce-9ee6-4c24-a60c-e8ca69ae4769-0000
I1208 23:29:48.758034 30336 hierarchical.cpp:1420] No inverse offers to send out!
I1208 23:29:48.758082 30336 hierarchical.cpp:1078] Performed allocation for 0 slaves in 123786ns
I1208 23:29:48.758869 30342 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.1:55250
I1208 23:29:48.759285 30342 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.1:55250
I1208 23:29:48.759865 30346 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1208 23:29:48.759898 30342 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.1:55250
I1208 23:29:48.761257 30339 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1208 23:29:48.761302 30339 http.cpp:340] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:46700
I1208 23:29:48.761497 30339 master.cpp:2638] Processing REQUEST call for framework 520970ce-9ee6-4c24-a60c-e8ca69ae4769-0000 (default)
I1208 23:29:48.761673 30341 hierarchical.cpp:579] Received resource request from framework 520970ce-9ee6-4c24-a60c-e8ca69ae4769-0000
I1208 23:29:48.762192 30344 master.cpp:932] Master terminating
I1208 23:29:48.762419 30332 hierarchical.cpp:321] Removed framework 520970ce-9ee6-4c24-a60c-e8ca69ae4769-0000
E1208 23:29:48.763010 30336 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (446 ms)
[----------] 22 tests from ContentType/SchedulerTest (13809 ms total)
[----------] Global test environment tear-down
[==========] 887 tests from 116 test cases ran. (692368 ms total)
[ PASSED ] 886 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-1449614928-10732
Untagged: mesos-1449614928-10732:latest
Deleted: 40214b0d6e21e7e45aa1fdde6f29f91a053a881b34eb0e4f71aa0cecf68d2e3a
Deleted: e575b3c2060e96f0daa0d5e267069d7f92c2a9522f471d434f88d37516fa1e7c
Deleted: dac9048724dec8f0b555978a21fb9166a1cf5b25c22a7445aa1f3491c2db2dd8
Deleted: 84e3b01f64b6d86cf147df3b6d9d612896a0c92c24731fa34b1fb1463a36b552
Deleted: 0b8c1b9f0d484771856d686ca219f4daf627b6820e3246e6c42e1cc612037308
Deleted: 1d599e75cffd868577d8486c755ad5ab6d23b0aed99f50925a3ef9d6cc0de398
Deleted: b999fc11f4f012d619c86b1a9394837ca1e3410c83b9e31f7491ff21312770fa
Deleted: cbec645c7cf1ee8514a7c64b26aed91edde4539875e4f531b4d7418d586c9f99
Deleted: ea94b3ff8b4482f1e5a7dd3d3115fb39ef3b07cba8874138f6e3f09117a4e9d1
Deleted: d038764777b2fc2142d8b6c5ccc5ef10d95ea72bc5ec69834230ae6e9c232dcf
Deleted: 2f31cbe7c6155c376f4b205bd0229c6305a6e828d0bbe3419ee3403a648a7891
Deleted: 8eb158f45c7351e9230feab00377b0be9cd385d67b152d6765161e5242467563
Deleted: eec63f13100b52d89966e23233ed15ca8d186bce21bd9a1bced3b06f1f712980
Error handling URL https://reviews.apache.org/api/review-requests/41076/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 8833974195e76e6e7cd8377fb511aa2f96e409e6
Build step 'Execute shell' marked build as failure