You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/11/25 04:22:25 UTC
Build failed in Jenkins: mesos-reviewbot #9885
See <https://builds.apache.org/job/mesos-reviewbot/9885/changes>
Changes:
[mpark] Improved docs for dynamic reservation HTTP endpoints.
[mpark] Documented `role` field in `Resource` protobuf message.
[mpark] Renamed `remaining` to `required` in `Master::Http::_operation`.
[mpark] Fixed typos in comments.
[mpark] Removed unused `using` statements from tests.
------------------------------------------
[...truncated 153522 lines...]
I1125 03:22:06.936130 30300 status_update_manager.cpp:528] Cleaning up status update stream for task 1286a600-6e31-4cbf-a7b5-93c88115e004 of framework 4373b6ac-40d1-4098-a826-a09f0ebe36d4-0000
[ OK ] ContentType/SchedulerTest.Message/1 (570 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1125 03:22:07.086217 30268 leveldb.cpp:174] Opened db in 144.9192ms
I1125 03:22:07.128386 30268 leveldb.cpp:181] Compacted db in 42.126151ms
I1125 03:22:07.128453 30268 leveldb.cpp:196] Created db iterator in 21862ns
I1125 03:22:07.128473 30268 leveldb.cpp:202] Seeked to beginning of db in 1978ns
I1125 03:22:07.128484 30268 leveldb.cpp:271] Iterated through 0 keys in the db in 420ns
I1125 03:22:07.128531 30268 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1125 03:22:07.129129 30302 recover.cpp:447] Starting replica recovery
I1125 03:22:07.129520 30297 recover.cpp:473] Replica is in EMPTY status
I1125 03:22:07.130789 30302 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (10916)@172.17.22.73:33379
I1125 03:22:07.131352 30300 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1125 03:22:07.132032 30292 recover.cpp:564] Updating replica status to STARTING
I1125 03:22:07.132531 30293 master.cpp:365] Master 48885fb7-00b2-4bad-96c5-e1319696dfad (a670689e8c40) started on 172.17.22.73:33379
I1125 03:22:07.132562 30293 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/wvM4NE/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/wvM4NE/master" --zk_session_timeout="10secs"
I1125 03:22:07.132921 30293 master.cpp:414] Master allowing unauthenticated frameworks to register
I1125 03:22:07.132941 30293 master.cpp:417] Master only allowing authenticated slaves to register
I1125 03:22:07.132953 30293 credentials.hpp:35] Loading credentials for authentication from '/tmp/wvM4NE/credentials'
I1125 03:22:07.133216 30293 master.cpp:456] Using default 'crammd5' authenticator
I1125 03:22:07.133350 30293 master.cpp:493] Authorization enabled
I1125 03:22:07.133558 30301 whitelist_watcher.cpp:77] No whitelist given
I1125 03:22:07.133656 30295 hierarchical.cpp:162] Initialized hierarchical allocator process
I1125 03:22:07.135179 30287 master.cpp:1625] The newly elected leader is master@172.17.22.73:33379 with id 48885fb7-00b2-4bad-96c5-e1319696dfad
I1125 03:22:07.135216 30287 master.cpp:1638] Elected as the leading master!
I1125 03:22:07.135241 30287 master.cpp:1383] Recovering from registrar
I1125 03:22:07.135439 30299 registrar.cpp:307] Recovering registrar
I1125 03:22:07.155534 30289 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 23.312114ms
I1125 03:22:07.155566 30289 replica.cpp:321] Persisted replica status to STARTING
I1125 03:22:07.155838 30293 recover.cpp:473] Replica is in STARTING status
I1125 03:22:07.156823 30289 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (10917)@172.17.22.73:33379
I1125 03:22:07.157145 30302 recover.cpp:193] Received a recover response from a replica in STARTING status
I1125 03:22:07.157573 30289 recover.cpp:564] Updating replica status to VOTING
I1125 03:22:07.180598 30301 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.817825ms
I1125 03:22:07.180656 30301 replica.cpp:321] Persisted replica status to VOTING
I1125 03:22:07.180783 30292 recover.cpp:578] Successfully joined the Paxos group
I1125 03:22:07.181041 30292 recover.cpp:462] Recover process terminated
I1125 03:22:07.181581 30301 log.cpp:659] Attempting to start the writer
I1125 03:22:07.182981 30289 replica.cpp:494] Replica received implicit promise request from (10918)@172.17.22.73:33379 with proposal 1
I1125 03:22:07.205911 30289 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.89185ms
I1125 03:22:07.205956 30289 replica.cpp:343] Persisted promised to 1
I1125 03:22:07.206845 30290 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1125 03:22:07.208252 30297 replica.cpp:389] Replica received explicit promise request from (10919)@172.17.22.73:33379 for position 0 with proposal 2
I1125 03:22:07.230845 30297 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 22.538208ms
I1125 03:22:07.230882 30297 replica.cpp:713] Persisted action at 0
I1125 03:22:07.232197 30287 replica.cpp:538] Replica received write request for position 0 from (10920)@172.17.22.73:33379
I1125 03:22:07.232280 30287 leveldb.cpp:436] Reading position from leveldb took 40954ns
I1125 03:22:07.255945 30287 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.600616ms
I1125 03:22:07.255985 30287 replica.cpp:713] Persisted action at 0
I1125 03:22:07.256659 30298 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1125 03:22:07.281117 30298 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 24.415089ms
I1125 03:22:07.281164 30298 replica.cpp:713] Persisted action at 0
I1125 03:22:07.281189 30298 replica.cpp:698] Replica learned NOP action at position 0
I1125 03:22:07.281867 30289 log.cpp:675] Writer started with ending position 0
I1125 03:22:07.283071 30294 leveldb.cpp:436] Reading position from leveldb took 34494ns
I1125 03:22:07.284096 30296 registrar.cpp:340] Successfully fetched the registry (0B) in 148.587776ms
I1125 03:22:07.284210 30296 registrar.cpp:439] Applied 1 operations in 24666ns; attempting to update the 'registry'
I1125 03:22:07.284914 30298 log.cpp:683] Attempting to append 176 bytes to the log
I1125 03:22:07.285051 30288 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1125 03:22:07.285877 30290 replica.cpp:538] Replica received write request for position 1 from (10921)@172.17.22.73:33379
I1125 03:22:07.420768 30290 leveldb.cpp:341] Persisting action (195 bytes) to leveldb took 134.837135ms
I1125 03:22:07.420819 30290 replica.cpp:713] Persisted action at 1
I1125 03:22:07.421785 30300 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1125 03:22:07.525213 30300 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 103.393802ms
I1125 03:22:07.525260 30300 replica.cpp:713] Persisted action at 1
I1125 03:22:07.525286 30300 replica.cpp:698] Replica learned APPEND action at position 1
I1125 03:22:07.526587 30300 registrar.cpp:484] Successfully updated the 'registry' in 242.301184ms
I1125 03:22:07.526794 30300 registrar.cpp:370] Successfully recovered registrar
I1125 03:22:07.526986 30291 log.cpp:702] Attempting to truncate the log to 1
I1125 03:22:07.527159 30300 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1125 03:22:07.527328 30298 master.cpp:1435] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1125 03:22:07.527369 30295 hierarchical.cpp:174] Allocator recovery is not supported yet
I1125 03:22:07.528221 30291 replica.cpp:538] Replica received write request for position 2 from (10922)@172.17.22.73:33379
I1125 03:22:07.566939 30291 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 38.672193ms
I1125 03:22:07.566978 30291 replica.cpp:713] Persisted action at 2
I1125 03:22:07.567802 30289 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1125 03:22:07.608589 30289 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 40.741772ms
I1125 03:22:07.608680 30289 leveldb.cpp:399] Deleting ~1 keys from leveldb took 41769ns
I1125 03:22:07.608706 30289 replica.cpp:713] Persisted action at 2
I1125 03:22:07.608739 30289 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1125 03:22:07.618548 30268 scheduler.cpp:154] Version: 0.27.0
I1125 03:22:07.619248 30289 scheduler.cpp:236] New master detected at master@172.17.22.73:33379
I1125 03:22:07.620791 30295 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.22.73:33379
I1125 03:22:07.622824 30301 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1125 03:22:07.622889 30301 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.22.73:45933
I1125 03:22:07.623100 30301 master.cpp:1887] Received subscription request for HTTP framework 'default'
I1125 03:22:07.623180 30301 master.cpp:1664] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1125 03:22:07.623509 30301 master.cpp:1979] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1125 03:22:07.623903 30292 master.hpp:1514] Sending heartbeat to 48885fb7-00b2-4bad-96c5-e1319696dfad-0000
I1125 03:22:07.624030 30299 hierarchical.cpp:220] Added framework 48885fb7-00b2-4bad-96c5-e1319696dfad-0000
I1125 03:22:07.624083 30299 hierarchical.cpp:1066] No resources available to allocate!
I1125 03:22:07.624110 30299 hierarchical.cpp:1159] No inverse offers to send out!
I1125 03:22:07.624130 30299 hierarchical.cpp:961] Performed allocation for 0 slaves in 72741ns
I1125 03:22:07.625166 30296 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.22.73:33379
I1125 03:22:07.626272 30293 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.22.73:33379
I1125 03:22:07.627187 30298 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1125 03:22:07.627235 30293 scheduler.cpp:298] Sending REQUEST call to master@172.17.22.73:33379
I1125 03:22:07.629235 30290 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1125 03:22:07.629312 30290 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.22.73:45934
I1125 03:22:07.629452 30290 master.cpp:2634] Processing REQUEST call for framework 48885fb7-00b2-4bad-96c5-e1319696dfad-0000 (default)
I1125 03:22:07.629815 30291 hierarchical.cpp:494] Received resource request from framework 48885fb7-00b2-4bad-96c5-e1319696dfad-0000
I1125 03:22:07.630220 30302 master.cpp:926] Master terminating
I1125 03:22:07.630414 30299 hierarchical.cpp:260] Removed framework 48885fb7-00b2-4bad-96c5-e1319696dfad-0000
E1125 03:22:07.631940 30290 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (698 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1125 03:22:07.752761 30268 leveldb.cpp:174] Opened db in 113.788167ms
I1125 03:22:07.803017 30268 leveldb.cpp:181] Compacted db in 50.211383ms
I1125 03:22:07.803081 30268 leveldb.cpp:196] Created db iterator in 18790ns
I1125 03:22:07.803100 30268 leveldb.cpp:202] Seeked to beginning of db in 1632ns
I1125 03:22:07.803112 30268 leveldb.cpp:271] Iterated through 0 keys in the db in 368ns
I1125 03:22:07.803153 30268 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1125 03:22:07.803692 30289 recover.cpp:447] Starting replica recovery
I1125 03:22:07.804069 30289 recover.cpp:473] Replica is in EMPTY status
I1125 03:22:07.805102 30300 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (10927)@172.17.22.73:33379
I1125 03:22:07.805516 30287 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1125 03:22:07.806056 30289 recover.cpp:564] Updating replica status to STARTING
I1125 03:22:07.806895 30287 master.cpp:365] Master a6d8d2a2-b6a2-4c1d-81bc-488fecfaeb88 (a670689e8c40) started on 172.17.22.73:33379
I1125 03:22:07.806922 30287 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/QGvWgJ/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/QGvWgJ/master" --zk_session_timeout="10secs"
I1125 03:22:07.807286 30287 master.cpp:414] Master allowing unauthenticated frameworks to register
I1125 03:22:07.807309 30287 master.cpp:417] Master only allowing authenticated slaves to register
I1125 03:22:07.807327 30287 credentials.hpp:35] Loading credentials for authentication from '/tmp/QGvWgJ/credentials'
I1125 03:22:07.807703 30287 master.cpp:456] Using default 'crammd5' authenticator
I1125 03:22:07.807857 30287 master.cpp:493] Authorization enabled
I1125 03:22:07.808099 30295 whitelist_watcher.cpp:77] No whitelist given
I1125 03:22:07.808167 30297 hierarchical.cpp:162] Initialized hierarchical allocator process
I1125 03:22:07.809954 30293 master.cpp:1625] The newly elected leader is master@172.17.22.73:33379 with id a6d8d2a2-b6a2-4c1d-81bc-488fecfaeb88
I1125 03:22:07.809991 30293 master.cpp:1638] Elected as the leading master!
I1125 03:22:07.810009 30293 master.cpp:1383] Recovering from registrar
I1125 03:22:07.810158 30291 registrar.cpp:307] Recovering registrar
I1125 03:22:07.848362 30301 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 42.093499ms
I1125 03:22:07.848412 30301 replica.cpp:321] Persisted replica status to STARTING
I1125 03:22:07.848742 30302 recover.cpp:473] Replica is in STARTING status
I1125 03:22:07.849853 30301 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (10928)@172.17.22.73:33379
I1125 03:22:07.850139 30299 recover.cpp:193] Received a recover response from a replica in STARTING status
I1125 03:22:07.850714 30288 recover.cpp:564] Updating replica status to VOTING
I1125 03:22:07.881710 30294 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.80919ms
I1125 03:22:07.881752 30294 replica.cpp:321] Persisted replica status to VOTING
I1125 03:22:07.881870 30300 recover.cpp:578] Successfully joined the Paxos group
I1125 03:22:07.882149 30300 recover.cpp:462] Recover process terminated
I1125 03:22:07.882701 30287 log.cpp:659] Attempting to start the writer
I1125 03:22:07.884099 30297 replica.cpp:494] Replica received implicit promise request from (10929)@172.17.22.73:33379 with proposal 1
I1125 03:22:07.906746 30297 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.605526ms
I1125 03:22:07.906785 30297 replica.cpp:343] Persisted promised to 1
I1125 03:22:07.907544 30288 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1125 03:22:07.908952 30300 replica.cpp:389] Replica received explicit promise request from (10930)@172.17.22.73:33379 for position 0 with proposal 2
I1125 03:22:07.931865 30300 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 22.855775ms
I1125 03:22:07.931910 30300 replica.cpp:713] Persisted action at 0
I1125 03:22:07.933481 30297 replica.cpp:538] Replica received write request for position 0 from (10931)@172.17.22.73:33379
I1125 03:22:07.933555 30297 leveldb.cpp:436] Reading position from leveldb took 34599ns
I1125 03:22:07.957064 30297 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.456837ms
I1125 03:22:07.957109 30297 replica.cpp:713] Persisted action at 0
I1125 03:22:07.958040 30298 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1125 03:22:07.979950 30298 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 21.876774ms
I1125 03:22:07.979998 30298 replica.cpp:713] Persisted action at 0
I1125 03:22:07.980033 30298 replica.cpp:698] Replica learned NOP action at position 0
I1125 03:22:07.980737 30295 log.cpp:675] Writer started with ending position 0
I1125 03:22:07.982074 30292 leveldb.cpp:436] Reading position from leveldb took 29757ns
I1125 03:22:07.983199 30292 registrar.cpp:340] Successfully fetched the registry (0B) in 172.988928ms
I1125 03:22:07.983383 30292 registrar.cpp:439] Applied 1 operations in 30415ns; attempting to update the 'registry'
I1125 03:22:07.984220 30297 log.cpp:683] Attempting to append 176 bytes to the log
I1125 03:22:07.984419 30290 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1125 03:22:07.985273 30298 replica.cpp:538] Replica received write request for position 1 from (10932)@172.17.22.73:33379
I1125 03:22:08.005110 30298 leveldb.cpp:341] Persisting action (195 bytes) to leveldb took 19.787644ms
I1125 03:22:08.005156 30298 replica.cpp:713] Persisted action at 1
I1125 03:22:08.006058 30293 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1125 03:22:08.030201 30293 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 24.101294ms
I1125 03:22:08.030241 30293 replica.cpp:713] Persisted action at 1
I1125 03:22:08.030264 30293 replica.cpp:698] Replica learned APPEND action at position 1
I1125 03:22:08.031430 30294 registrar.cpp:484] Successfully updated the 'registry' in 47.980032ms
I1125 03:22:08.031666 30294 registrar.cpp:370] Successfully recovered registrar
I1125 03:22:08.031786 30300 log.cpp:702] Attempting to truncate the log to 1
I1125 03:22:08.031922 30291 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1125 03:22:08.032310 30302 master.cpp:1435] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1125 03:22:08.032336 30296 hierarchical.cpp:174] Allocator recovery is not supported yet
I1125 03:22:08.033045 30291 replica.cpp:538] Replica received write request for position 2 from (10933)@172.17.22.73:33379
I1125 03:22:08.055260 30291 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 22.171867ms
I1125 03:22:08.055294 30291 replica.cpp:713] Persisted action at 2
I1125 03:22:08.055915 30293 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1125 03:22:08.090919 30293 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 34.968619ms
I1125 03:22:08.090998 30293 leveldb.cpp:399] Deleting ~1 keys from leveldb took 38786ns
I1125 03:22:08.091022 30293 replica.cpp:713] Persisted action at 2
I1125 03:22:08.091044 30293 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1125 03:22:08.093364 30268 scheduler.cpp:154] Version: 0.27.0
I1125 03:22:08.094063 30287 scheduler.cpp:236] New master detected at master@172.17.22.73:33379
I1125 03:22:08.095291 30298 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.22.73:33379
I1125 03:22:08.097539 30290 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1125 03:22:08.097679 30290 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.22.73:45935
I1125 03:22:08.098042 30290 master.cpp:1887] Received subscription request for HTTP framework 'default'
I1125 03:22:08.098112 30290 master.cpp:1664] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1125 03:22:08.098351 30290 master.cpp:1979] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1125 03:22:08.098799 30297 hierarchical.cpp:220] Added framework a6d8d2a2-b6a2-4c1d-81bc-488fecfaeb88-0000
I1125 03:22:08.098860 30297 hierarchical.cpp:1066] No resources available to allocate!
I1125 03:22:08.098907 30297 hierarchical.cpp:1159] No inverse offers to send out!
I1125 03:22:08.098942 30297 hierarchical.cpp:961] Performed allocation for 0 slaves in 111165ns
I1125 03:22:08.098945 30288 master.hpp:1514] Sending heartbeat to a6d8d2a2-b6a2-4c1d-81bc-488fecfaeb88-0000
I1125 03:22:08.099866 30302 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.22.73:33379
I1125 03:22:08.100319 30302 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.22.73:33379
I1125 03:22:08.101076 30297 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1125 03:22:08.101088 30302 scheduler.cpp:298] Sending REQUEST call to master@172.17.22.73:33379
I1125 03:22:08.102722 30288 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1125 03:22:08.102874 30288 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.22.73:45936
I1125 03:22:08.103119 30288 master.cpp:2634] Processing REQUEST call for framework a6d8d2a2-b6a2-4c1d-81bc-488fecfaeb88-0000 (default)
I1125 03:22:08.103281 30298 hierarchical.cpp:494] Received resource request from framework a6d8d2a2-b6a2-4c1d-81bc-488fecfaeb88-0000
I1125 03:22:08.103694 30268 master.cpp:926] Master terminating
I1125 03:22:08.103938 30288 hierarchical.cpp:260] Removed framework a6d8d2a2-b6a2-4c1d-81bc-488fecfaeb88-0000
E1125 03:22:08.105129 30297 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (473 ms)
[----------] 22 tests from ContentType/SchedulerTest (15594 ms total)
[----------] Global test environment tear-down
[==========] 842 tests from 114 test cases ran. (696564 ms total)
[ PASSED ] 841 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ResourcesTest.PrintingExtendedAttributes
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-1448419286-24047
Untagged: mesos-1448419286-24047:latest
Deleted: b6f917747c32b962a34397c4e80ec7c8f11b77503483d719cab085e0d15dbac4
Deleted: f64d019ec39a1f1674d0aadc2e9f51d788717b6eac36d51810519ca5e83b3c8e
Deleted: 6d401d4aa04010ebeaa3c815870e75b1377769580ff0ab011674a3e3f4eb3646
Deleted: cc69b7a5071ebeca29405531497ebcbf616fc04967b70b3f147b2ed2cf1b4977
Deleted: 0e73b20c7124326f3b5441696e75c25973cda15b6d61e3995b9cf4be12acbc9a
Deleted: a29572b622876f37444e9408d098a39a5c4f07f8584fd85bfe1aab483c8c096f
Deleted: 2151ad500ce01359cf3897cc656273bc6a46aa6250dee5e5ba56b12ac04cc6d0
Deleted: 9196222f2919d2f21ff6579f87cc09eb83eee7d0905af566aeaec0cc46688f7b
Deleted: 91b351b40346d97da841d158d0126171af469c2d7b8204e531deaffbe0ba38e1
Deleted: 1259bdaa0a94de6763b4ae6d3654b0e765bed2f416fb2ec3684f9a96877af183
Deleted: 34e4c7a7c91adf42c365167c02d8d92233a50030091f6f369da35832595dafec
Deleted: 1b628402816e5b0a88224e38f37a123f0ba7e4b289963cd316a00d296e9ad5ef
Deleted: 7e5ccf59bb22a8c55adbcad9084a0536af52873c5f5d59506931b883fbf3a0f6
Error handling URL https://reviews.apache.org/api/review-requests/40379/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 92e5366752b5cbcdf72c8d50fcf965b8a5bd5561
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #9887
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/9887/changes>
Build failed in Jenkins: mesos-reviewbot #9886
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/9886/>
------------------------------------------
[...truncated 153818 lines...]
[ OK ] ContentType/SchedulerTest.Message/1 (820 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1125 04:37:46.313771 30272 leveldb.cpp:174] Opened db in 120.458344ms
I1125 04:37:46.356000 30272 leveldb.cpp:181] Compacted db in 42.183662ms
I1125 04:37:46.356065 30272 leveldb.cpp:196] Created db iterator in 19514ns
I1125 04:37:46.356086 30272 leveldb.cpp:202] Seeked to beginning of db in 2005ns
I1125 04:37:46.356097 30272 leveldb.cpp:271] Iterated through 0 keys in the db in 283ns
I1125 04:37:46.356142 30272 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1125 04:37:46.356686 30300 recover.cpp:447] Starting replica recovery
I1125 04:37:46.356958 30302 recover.cpp:473] Replica is in EMPTY status
I1125 04:37:46.357933 30292 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (10928)@172.17.22.80:41947
I1125 04:37:46.358253 30304 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1125 04:37:46.358824 30302 recover.cpp:564] Updating replica status to STARTING
I1125 04:37:46.359220 30298 master.cpp:365] Master c3376e0e-3abc-4b2e-a3a9-6406fda102cd (cf4386c04b1f) started on 172.17.22.80:41947
I1125 04:37:46.359326 30298 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/FeQ7jO/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/FeQ7jO/master" --zk_session_timeout="10secs"
I1125 04:37:46.359730 30298 master.cpp:414] Master allowing unauthenticated frameworks to register
I1125 04:37:46.359745 30298 master.cpp:417] Master only allowing authenticated slaves to register
I1125 04:37:46.359753 30298 credentials.hpp:35] Loading credentials for authentication from '/tmp/FeQ7jO/credentials'
I1125 04:37:46.359999 30298 master.cpp:456] Using default 'crammd5' authenticator
I1125 04:37:46.360113 30298 master.cpp:493] Authorization enabled
I1125 04:37:46.360309 30299 whitelist_watcher.cpp:77] No whitelist given
I1125 04:37:46.360383 30306 hierarchical.cpp:162] Initialized hierarchical allocator process
I1125 04:37:46.361655 30296 master.cpp:1625] The newly elected leader is master@172.17.22.80:41947 with id c3376e0e-3abc-4b2e-a3a9-6406fda102cd
I1125 04:37:46.361686 30296 master.cpp:1638] Elected as the leading master!
I1125 04:37:46.361704 30296 master.cpp:1383] Recovering from registrar
I1125 04:37:46.361819 30294 registrar.cpp:307] Recovering registrar
I1125 04:37:46.422587 30297 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 63.509602ms
I1125 04:37:46.422653 30297 replica.cpp:321] Persisted replica status to STARTING
I1125 04:37:46.422885 30297 recover.cpp:473] Replica is in STARTING status
I1125 04:37:46.423794 30294 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (10929)@172.17.22.80:41947
I1125 04:37:46.424151 30293 recover.cpp:193] Received a recover response from a replica in STARTING status
I1125 04:37:46.424536 30305 recover.cpp:564] Updating replica status to VOTING
I1125 04:37:46.470561 30292 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 45.815671ms
I1125 04:37:46.470600 30292 replica.cpp:321] Persisted replica status to VOTING
I1125 04:37:46.470772 30292 recover.cpp:578] Successfully joined the Paxos group
I1125 04:37:46.470959 30292 recover.cpp:462] Recover process terminated
I1125 04:37:46.471490 30292 log.cpp:659] Attempting to start the writer
I1125 04:37:46.472988 30301 replica.cpp:494] Replica received implicit promise request from (10930)@172.17.22.80:41947 with proposal 1
I1125 04:37:46.504017 30301 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.948457ms
I1125 04:37:46.504062 30301 replica.cpp:343] Persisted promised to 1
I1125 04:37:46.504688 30296 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1125 04:37:46.505728 30304 replica.cpp:389] Replica received explicit promise request from (10931)@172.17.22.80:41947 for position 0 with proposal 2
2015-11-25 04:37:46,530:30272(0x2b265f9b1700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:39790] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I1125 04:37:46.537621 30304 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 31.834728ms
I1125 04:37:46.537665 30304 replica.cpp:713] Persisted action at 0
I1125 04:37:46.538759 30301 replica.cpp:538] Replica received write request for position 0 from (10932)@172.17.22.80:41947
I1125 04:37:46.538813 30301 leveldb.cpp:436] Reading position from leveldb took 25806ns
I1125 04:37:46.570942 30301 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 32.090266ms
I1125 04:37:46.570982 30301 replica.cpp:713] Persisted action at 0
I1125 04:37:46.571540 30299 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1125 04:37:46.604439 30299 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.828957ms
I1125 04:37:46.604478 30299 replica.cpp:713] Persisted action at 0
I1125 04:37:46.604501 30299 replica.cpp:698] Replica learned NOP action at position 0
I1125 04:37:46.605319 30302 log.cpp:675] Writer started with ending position 0
I1125 04:37:46.606243 30301 leveldb.cpp:436] Reading position from leveldb took 26945ns
I1125 04:37:46.607076 30293 registrar.cpp:340] Successfully fetched the registry (0B) in 245.210112ms
I1125 04:37:46.607180 30293 registrar.cpp:439] Applied 1 operations in 27088ns; attempting to update the 'registry'
I1125 04:37:46.607854 30305 log.cpp:683] Attempting to append 176 bytes to the log
I1125 04:37:46.607975 30306 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1125 04:37:46.608659 30291 replica.cpp:538] Replica received write request for position 1 from (10933)@172.17.22.80:41947
I1125 04:37:46.638020 30291 leveldb.cpp:341] Persisting action (195 bytes) to leveldb took 29.323277ms
I1125 04:37:46.638069 30291 replica.cpp:713] Persisted action at 1
I1125 04:37:46.638811 30300 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1125 04:37:46.671404 30300 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 32.560543ms
I1125 04:37:46.671443 30300 replica.cpp:713] Persisted action at 1
I1125 04:37:46.671468 30300 replica.cpp:698] Replica learned APPEND action at position 1
I1125 04:37:46.672726 30299 registrar.cpp:484] Successfully updated the 'registry' in 65.480192ms
I1125 04:37:46.672879 30299 registrar.cpp:370] Successfully recovered registrar
I1125 04:37:46.673054 30305 log.cpp:702] Attempting to truncate the log to 1
I1125 04:37:46.673360 30292 master.cpp:1435] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1125 04:37:46.673470 30303 hierarchical.cpp:174] Allocator recovery is not supported yet
I1125 04:37:46.673421 30300 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1125 04:37:46.674190 30303 replica.cpp:538] Replica received write request for position 2 from (10934)@172.17.22.80:41947
I1125 04:37:46.704972 30303 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 30.744105ms
I1125 04:37:46.705019 30303 replica.cpp:713] Persisted action at 2
I1125 04:37:46.705749 30295 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1125 04:37:46.738369 30295 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.563897ms
I1125 04:37:46.738451 30295 leveldb.cpp:399] Deleting ~1 keys from leveldb took 40076ns
I1125 04:37:46.738484 30295 replica.cpp:713] Persisted action at 2
I1125 04:37:46.738510 30295 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1125 04:37:46.744446 30272 scheduler.cpp:154] Version: 0.27.0
I1125 04:37:46.744976 30296 scheduler.cpp:236] New master detected at master@172.17.22.80:41947
I1125 04:37:46.746040 30293 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.22.80:41947
I1125 04:37:46.747814 30292 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1125 04:37:46.747867 30292 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.22.80:52303
I1125 04:37:46.748049 30292 master.cpp:1887] Received subscription request for HTTP framework 'default'
I1125 04:37:46.748106 30292 master.cpp:1664] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1125 04:37:46.748349 30292 master.cpp:1979] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1125 04:37:46.748693 30294 hierarchical.cpp:220] Added framework c3376e0e-3abc-4b2e-a3a9-6406fda102cd-0000
I1125 04:37:46.748746 30295 master.hpp:1514] Sending heartbeat to c3376e0e-3abc-4b2e-a3a9-6406fda102cd-0000
I1125 04:37:46.748766 30294 hierarchical.cpp:1066] No resources available to allocate!
I1125 04:37:46.748816 30294 hierarchical.cpp:1159] No inverse offers to send out!
I1125 04:37:46.748864 30294 hierarchical.cpp:961] Performed allocation for 0 slaves in 146671ns
I1125 04:37:46.749361 30303 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.22.80:41947
I1125 04:37:46.749775 30303 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.22.80:41947
I1125 04:37:46.750349 30306 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1125 04:37:46.750437 30303 scheduler.cpp:298] Sending REQUEST call to master@172.17.22.80:41947
I1125 04:37:46.751821 30305 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1125 04:37:46.751878 30305 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.22.80:52304
I1125 04:37:46.751974 30305 master.cpp:2634] Processing REQUEST call for framework c3376e0e-3abc-4b2e-a3a9-6406fda102cd-0000 (default)
I1125 04:37:46.752145 30306 hierarchical.cpp:494] Received resource request from framework c3376e0e-3abc-4b2e-a3a9-6406fda102cd-0000
I1125 04:37:46.752362 30293 master.cpp:926] Master terminating
I1125 04:37:46.752519 30296 hierarchical.cpp:260] Removed framework c3376e0e-3abc-4b2e-a3a9-6406fda102cd-0000
E1125 04:37:46.753456 30301 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (564 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1125 04:37:46.890434 30272 leveldb.cpp:174] Opened db in 132.337636ms
I1125 04:37:46.924372 30272 leveldb.cpp:181] Compacted db in 33.892331ms
I1125 04:37:46.924438 30272 leveldb.cpp:196] Created db iterator in 20217ns
I1125 04:37:46.924459 30272 leveldb.cpp:202] Seeked to beginning of db in 1967ns
I1125 04:37:46.924471 30272 leveldb.cpp:271] Iterated through 0 keys in the db in 285ns
I1125 04:37:46.924528 30272 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1125 04:37:46.925120 30304 recover.cpp:447] Starting replica recovery
I1125 04:37:46.925412 30304 recover.cpp:473] Replica is in EMPTY status
I1125 04:37:46.926898 30298 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (10939)@172.17.22.80:41947
I1125 04:37:46.927214 30293 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1125 04:37:46.927589 30302 recover.cpp:564] Updating replica status to STARTING
I1125 04:37:46.927721 30300 master.cpp:365] Master 5900da8c-3f6e-420c-9d07-4b6f5ea4d5ba (cf4386c04b1f) started on 172.17.22.80:41947
I1125 04:37:46.927738 30300 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/RDL0gG/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/RDL0gG/master" --zk_session_timeout="10secs"
I1125 04:37:46.928001 30300 master.cpp:414] Master allowing unauthenticated frameworks to register
I1125 04:37:46.928020 30300 master.cpp:417] Master only allowing authenticated slaves to register
I1125 04:37:46.928033 30300 credentials.hpp:35] Loading credentials for authentication from '/tmp/RDL0gG/credentials'
I1125 04:37:46.928283 30300 master.cpp:456] Using default 'crammd5' authenticator
I1125 04:37:46.928391 30300 master.cpp:493] Authorization enabled
I1125 04:37:46.928575 30292 whitelist_watcher.cpp:77] No whitelist given
I1125 04:37:46.928644 30306 hierarchical.cpp:162] Initialized hierarchical allocator process
I1125 04:37:46.929888 30294 master.cpp:1625] The newly elected leader is master@172.17.22.80:41947 with id 5900da8c-3f6e-420c-9d07-4b6f5ea4d5ba
I1125 04:37:46.929927 30294 master.cpp:1638] Elected as the leading master!
I1125 04:37:46.929946 30294 master.cpp:1383] Recovering from registrar
I1125 04:37:46.930109 30297 registrar.cpp:307] Recovering registrar
I1125 04:37:46.957628 30291 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.846172ms
I1125 04:37:46.957672 30291 replica.cpp:321] Persisted replica status to STARTING
I1125 04:37:46.957911 30291 recover.cpp:473] Replica is in STARTING status
I1125 04:37:46.959127 30305 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (10940)@172.17.22.80:41947
I1125 04:37:46.959933 30295 recover.cpp:193] Received a recover response from a replica in STARTING status
I1125 04:37:46.960579 30298 recover.cpp:564] Updating replica status to VOTING
I1125 04:37:46.991050 30292 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.334835ms
I1125 04:37:46.991093 30292 replica.cpp:321] Persisted replica status to VOTING
I1125 04:37:46.991230 30300 recover.cpp:578] Successfully joined the Paxos group
I1125 04:37:46.991519 30300 recover.cpp:462] Recover process terminated
I1125 04:37:46.992063 30294 log.cpp:659] Attempting to start the writer
I1125 04:37:46.993203 30301 replica.cpp:494] Replica received implicit promise request from (10941)@172.17.22.80:41947 with proposal 1
I1125 04:37:47.022294 30301 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.058719ms
I1125 04:37:47.022339 30301 replica.cpp:343] Persisted promised to 1
I1125 04:37:47.022945 30294 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1125 04:37:47.023967 30296 replica.cpp:389] Replica received explicit promise request from (10942)@172.17.22.80:41947 for position 0 with proposal 2
I1125 04:37:47.056404 30296 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 32.399868ms
I1125 04:37:47.056433 30296 replica.cpp:713] Persisted action at 0
I1125 04:37:47.057416 30300 replica.cpp:538] Replica received write request for position 0 from (10943)@172.17.22.80:41947
I1125 04:37:47.057502 30300 leveldb.cpp:436] Reading position from leveldb took 34566ns
I1125 04:37:47.089893 30300 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 32.336439ms
I1125 04:37:47.089946 30300 replica.cpp:713] Persisted action at 0
I1125 04:37:47.090704 30305 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1125 04:37:47.123477 30305 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.737764ms
I1125 04:37:47.123517 30305 replica.cpp:713] Persisted action at 0
I1125 04:37:47.123540 30305 replica.cpp:698] Replica learned NOP action at position 0
I1125 04:37:47.124263 30296 log.cpp:675] Writer started with ending position 0
I1125 04:37:47.125170 30297 leveldb.cpp:436] Reading position from leveldb took 26139ns
I1125 04:37:47.126008 30293 registrar.cpp:340] Successfully fetched the registry (0B) in 195.84512ms
I1125 04:37:47.126104 30293 registrar.cpp:439] Applied 1 operations in 24841ns; attempting to update the 'registry'
I1125 04:37:47.126709 30295 log.cpp:683] Attempting to append 176 bytes to the log
I1125 04:37:47.126819 30302 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1125 04:37:47.127396 30292 replica.cpp:538] Replica received write request for position 1 from (10944)@172.17.22.80:41947
I1125 04:37:47.165304 30292 leveldb.cpp:341] Persisting action (195 bytes) to leveldb took 37.86951ms
I1125 04:37:47.165349 30292 replica.cpp:713] Persisted action at 1
I1125 04:37:47.166229 30303 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1125 04:37:47.195020 30303 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 28.758553ms
I1125 04:37:47.195062 30303 replica.cpp:713] Persisted action at 1
I1125 04:37:47.195094 30303 replica.cpp:698] Replica learned APPEND action at position 1
I1125 04:37:47.196101 30291 registrar.cpp:484] Successfully updated the 'registry' in 69.924864ms
I1125 04:37:47.196214 30291 registrar.cpp:370] Successfully recovered registrar
I1125 04:37:47.196274 30305 log.cpp:702] Attempting to truncate the log to 1
I1125 04:37:47.196421 30294 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1125 04:37:47.196686 30292 master.cpp:1435] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1125 04:37:47.196702 30300 hierarchical.cpp:174] Allocator recovery is not supported yet
I1125 04:37:47.197222 30301 replica.cpp:538] Replica received write request for position 2 from (10945)@172.17.22.80:41947
I1125 04:37:47.245303 30301 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 48.047925ms
I1125 04:37:47.245352 30301 replica.cpp:713] Persisted action at 2
I1125 04:37:47.246237 30291 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1125 04:37:47.282594 30291 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 36.315807ms
I1125 04:37:47.282708 30291 leveldb.cpp:399] Deleting ~1 keys from leveldb took 42932ns
I1125 04:37:47.282732 30291 replica.cpp:713] Persisted action at 2
I1125 04:37:47.282755 30291 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1125 04:37:47.288130 30272 scheduler.cpp:154] Version: 0.27.0
I1125 04:37:47.288741 30302 scheduler.cpp:236] New master detected at master@172.17.22.80:41947
I1125 04:37:47.289639 30292 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.22.80:41947
I1125 04:37:47.291527 30303 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1125 04:37:47.291625 30303 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.22.80:52305
I1125 04:37:47.291986 30303 master.cpp:1887] Received subscription request for HTTP framework 'default'
I1125 04:37:47.292067 30303 master.cpp:1664] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1125 04:37:47.292336 30303 master.cpp:1979] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1125 04:37:47.292709 30294 hierarchical.cpp:220] Added framework 5900da8c-3f6e-420c-9d07-4b6f5ea4d5ba-0000
I1125 04:37:47.292759 30294 hierarchical.cpp:1066] No resources available to allocate!
I1125 04:37:47.292819 30297 master.hpp:1514] Sending heartbeat to 5900da8c-3f6e-420c-9d07-4b6f5ea4d5ba-0000
I1125 04:37:47.292888 30294 hierarchical.cpp:1159] No inverse offers to send out!
I1125 04:37:47.292917 30294 hierarchical.cpp:961] Performed allocation for 0 slaves in 182879ns
I1125 04:37:47.293489 30295 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.22.80:41947
I1125 04:37:47.294035 30295 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.22.80:41947
I1125 04:37:47.294526 30293 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1125 04:37:47.294533 30295 scheduler.cpp:298] Sending REQUEST call to master@172.17.22.80:41947
I1125 04:37:47.295887 30297 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1125 04:37:47.295940 30297 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.22.80:52306
I1125 04:37:47.296142 30297 master.cpp:2634] Processing REQUEST call for framework 5900da8c-3f6e-420c-9d07-4b6f5ea4d5ba-0000 (default)
I1125 04:37:47.296286 30304 hierarchical.cpp:494] Received resource request from framework 5900da8c-3f6e-420c-9d07-4b6f5ea4d5ba-0000
I1125 04:37:47.296591 30296 master.cpp:926] Master terminating
I1125 04:37:47.296838 30294 hierarchical.cpp:260] Removed framework 5900da8c-3f6e-420c-9d07-4b6f5ea4d5ba-0000
E1125 04:37:47.297596 30295 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (560 ms)
[----------] 22 tests from ContentType/SchedulerTest (18421 ms total)
[----------] Global test environment tear-down
[==========] 842 tests from 114 test cases ran. (804095 ms total)
[ PASSED ] 841 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ResourcesTest.PrintingExtendedAttributes
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]: Leaving directory `/mesos/mesos-0.27.0/_build'
make[1]: *** [check-recursive] Error 1
make: *** [distcheck] Error 1
+ docker rmi mesos-1448423447-2594
Untagged: mesos-1448423447-2594:latest
Deleted: 18d5c4c8271e0bba70b85640b415af8b73ef3fa741ed519ca7005bbb7c25fbc7
Deleted: 4a01b9ec92121903e75c8bc4ca899f10363dea096ac14a28dc2493f2b89f7ecf
Deleted: b3001d1ecc9933cc0082b613645fdbf303b29410e5c615c0c0e3331420b984ee
Deleted: 1314afd3243d26fee928066998e6fb878b03da554629cdcfea03771e3241388b
Deleted: fb00de5c39ccb389027c2f802137b817ff7aeee22ce2d71be07161c16892ab29
Deleted: 23e45a3f1a494a1dfa3683e098f588e01b0b79bf2cc7521df09a7b47a5be8108
Deleted: 4f6208abbd2f52c5a5c3cd700573231e74b10bd4af4fea453384d3125141e350
Deleted: 07cb1eeffb1c91414b976ec1a738c731661d94a7cf3f0d10151759d7c57e942f
Deleted: 6891c28f2d3816b3c06ce7087d13a9dcd07fe419203f51f2e96ce8a00e09855d
Deleted: ec1a4bc3994fb9e2f285c4f1341f55bc6b904ef6d3a8184776e3e05d8819a070
Deleted: e450a5880c8fedfe345cf6778cbd8cb60bd7b6340de731e7fc4e96771d879579
Deleted: eae237c7bd12ca7d51edcd904d2e511bdc53153f79cf0416502f7b1a830047dd
Deleted: 323bc2596cf1e373bd09766f887f4e9c8ed8873ddc8d7ed4f53fba17cc072d80
Error handling URL https://reviews.apache.org/api/review-requests/40379/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 92e5366752b5cbcdf72c8d50fcf965b8a5bd5561
Build step 'Execute shell' marked build as failure