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/26 09:41:52 UTC
Build failed in Jenkins: mesos-reviewbot #9906
See <https://builds.apache.org/job/mesos-reviewbot/9906/>
------------------------------------------
[...truncated 157068 lines...]
[ OK ] ContentType/SchedulerTest.Message/1 (881 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I1126 08:41:16.912010 30268 leveldb.cpp:174] Opened db in 98.112936ms
I1126 08:41:16.992033 30268 leveldb.cpp:181] Compacted db in 79.937816ms
I1126 08:41:16.992269 30268 leveldb.cpp:196] Created db iterator in 29345ns
I1126 08:41:16.992490 30268 leveldb.cpp:202] Seeked to beginning of db in 3869ns
I1126 08:41:16.992508 30268 leveldb.cpp:271] Iterated through 0 keys in the db in 332ns
I1126 08:41:16.992573 30268 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1126 08:41:16.993712 30295 recover.cpp:447] Starting replica recovery
I1126 08:41:16.994027 30295 recover.cpp:473] Replica is in EMPTY status
I1126 08:41:16.997388 30302 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11056)@172.17.19.37:43173
I1126 08:41:16.997833 30294 master.cpp:365] Master 97dd024a-0499-41da-a6ca-cf009ac5beaa (540c39c2376a) started on 172.17.19.37:43173
I1126 08:41:16.997932 30294 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/2giqnM/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/2giqnM/master" --zk_session_timeout="10secs"
I1126 08:41:16.998514 30294 master.cpp:414] Master allowing unauthenticated frameworks to register
I1126 08:41:16.998636 30294 master.cpp:417] Master only allowing authenticated slaves to register
I1126 08:41:16.998776 30294 credentials.hpp:35] Loading credentials for authentication from '/tmp/2giqnM/credentials'
I1126 08:41:16.999286 30294 master.cpp:456] Using default 'crammd5' authenticator
I1126 08:41:16.999984 30295 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1126 08:41:17.000421 30294 master.cpp:493] Authorization enabled
I1126 08:41:17.001515 30295 recover.cpp:564] Updating replica status to STARTING
I1126 08:41:17.001785 30298 whitelist_watcher.cpp:77] No whitelist given
I1126 08:41:17.001906 30296 hierarchical.cpp:162] Initialized hierarchical allocator process
I1126 08:41:17.004117 30294 master.cpp:1625] The newly elected leader is master@172.17.19.37:43173 with id 97dd024a-0499-41da-a6ca-cf009ac5beaa
I1126 08:41:17.004232 30294 master.cpp:1638] Elected as the leading master!
I1126 08:41:17.004353 30294 master.cpp:1383] Recovering from registrar
I1126 08:41:17.004642 30300 registrar.cpp:307] Recovering registrar
I1126 08:41:17.066624 30298 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 62.632851ms
I1126 08:41:17.066972 30298 replica.cpp:321] Persisted replica status to STARTING
I1126 08:41:17.067644 30295 recover.cpp:473] Replica is in STARTING status
I1126 08:41:17.069596 30298 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11057)@172.17.19.37:43173
I1126 08:41:17.070401 30298 recover.cpp:193] Received a recover response from a replica in STARTING status
I1126 08:41:17.071357 30298 recover.cpp:564] Updating replica status to VOTING
2015-11-26 08:41:17,081:30268(0x2b762b683700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:55214] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
I1126 08:41:17.142293 30298 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 70.436136ms
I1126 08:41:17.142554 30298 replica.cpp:321] Persisted replica status to VOTING
I1126 08:41:17.143031 30302 recover.cpp:578] Successfully joined the Paxos group
I1126 08:41:17.143414 30294 recover.cpp:462] Recover process terminated
I1126 08:41:17.144076 30298 log.cpp:659] Attempting to start the writer
I1126 08:41:17.149411 30291 replica.cpp:494] Replica received implicit promise request from (11058)@172.17.19.37:43173 with proposal 1
I1126 08:41:17.194317 30291 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 44.559572ms
I1126 08:41:17.194401 30291 replica.cpp:343] Persisted promised to 1
I1126 08:41:17.196048 30288 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1126 08:41:17.198104 30291 replica.cpp:389] Replica received explicit promise request from (11059)@172.17.19.37:43173 for position 0 with proposal 2
I1126 08:41:17.249997 30291 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 51.267592ms
I1126 08:41:17.250085 30291 replica.cpp:713] Persisted action at 0
I1126 08:41:17.252085 30302 replica.cpp:538] Replica received write request for position 0 from (11060)@172.17.19.37:43173
I1126 08:41:17.252228 30302 leveldb.cpp:436] Reading position from leveldb took 73861ns
I1126 08:41:17.303256 30302 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 50.950648ms
I1126 08:41:17.303345 30302 replica.cpp:713] Persisted action at 0
I1126 08:41:17.304307 30295 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1126 08:41:17.368266 30295 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 63.892606ms
I1126 08:41:17.368362 30295 replica.cpp:713] Persisted action at 0
I1126 08:41:17.368402 30295 replica.cpp:698] Replica learned NOP action at position 0
I1126 08:41:17.369660 30302 log.cpp:675] Writer started with ending position 0
I1126 08:41:17.371481 30302 leveldb.cpp:436] Reading position from leveldb took 68526ns
I1126 08:41:17.374327 30291 registrar.cpp:340] Successfully fetched the registry (0B) in 369.497088ms
I1126 08:41:17.374603 30291 registrar.cpp:439] Applied 1 operations in 39793ns; attempting to update the 'registry'
I1126 08:41:17.375888 30296 log.cpp:683] Attempting to append 176 bytes to the log
I1126 08:41:17.376667 30296 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1126 08:41:17.377918 30296 replica.cpp:538] Replica received write request for position 1 from (11061)@172.17.19.37:43173
I1126 08:41:17.410267 30296 leveldb.cpp:341] Persisting action (195 bytes) to leveldb took 32.137775ms
I1126 08:41:17.410361 30296 replica.cpp:713] Persisted action at 1
I1126 08:41:17.411972 30298 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1126 08:41:17.462352 30298 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 50.139152ms
I1126 08:41:17.462448 30298 replica.cpp:713] Persisted action at 1
I1126 08:41:17.462489 30298 replica.cpp:698] Replica learned APPEND action at position 1
I1126 08:41:17.464036 30298 registrar.cpp:484] Successfully updated the 'registry' in 89.032192ms
I1126 08:41:17.464289 30298 registrar.cpp:370] Successfully recovered registrar
I1126 08:41:17.464408 30293 log.cpp:702] Attempting to truncate the log to 1
I1126 08:41:17.464638 30293 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1126 08:41:17.464951 30298 master.cpp:1435] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1126 08:41:17.465101 30292 hierarchical.cpp:174] Allocator recovery is not supported yet
I1126 08:41:17.466384 30298 replica.cpp:538] Replica received write request for position 2 from (11062)@172.17.19.37:43173
I1126 08:41:17.520030 30298 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 53.488461ms
I1126 08:41:17.520118 30298 replica.cpp:713] Persisted action at 2
I1126 08:41:17.521520 30294 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1126 08:41:17.552062 30294 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 30.368539ms
I1126 08:41:17.552237 30294 leveldb.cpp:399] Deleting ~1 keys from leveldb took 86246ns
I1126 08:41:17.552264 30294 replica.cpp:713] Persisted action at 2
I1126 08:41:17.552301 30294 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1126 08:41:17.559101 30268 scheduler.cpp:154] Version: 0.27.0
I1126 08:41:17.560509 30292 scheduler.cpp:236] New master detected at master@172.17.19.37:43173
I1126 08:41:17.566345 30300 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.19.37:43173
I1126 08:41:17.572700 30287 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1126 08:41:17.572888 30287 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.19.37:42431
I1126 08:41:17.573284 30287 master.cpp:1887] Received subscription request for HTTP framework 'default'
I1126 08:41:17.573431 30287 master.cpp:1664] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1126 08:41:17.574997 30287 master.cpp:1979] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1126 08:41:17.578346 30300 hierarchical.cpp:220] Added framework 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000
I1126 08:41:17.581104 30300 hierarchical.cpp:1064] No resources available to allocate!
I1126 08:41:17.581289 30300 hierarchical.cpp:1157] No inverse offers to send out!
I1126 08:41:17.581384 30300 hierarchical.cpp:959] Performed allocation for 0 slaves in 323307ns
I1126 08:41:17.580791 30290 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.19.37:43173
I1126 08:41:17.583786 30287 master.hpp:1531] Sending heartbeat to 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000
I1126 08:41:17.585297 30290 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.19.37:43173
I1126 08:41:17.585805 30290 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1126 08:41:17.587102 30290 scheduler.cpp:298] Sending REQUEST call to master@172.17.19.37:43173
I1126 08:41:17.592360 30289 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1126 08:41:17.592499 30289 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.19.37:42432
I1126 08:41:17.592718 30289 master.cpp:2634] Processing REQUEST call for framework 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000 (default)
I1126 08:41:17.592978 30289 hierarchical.cpp:494] Received resource request from framework 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000
I1126 08:41:17.595301 30268 master.cpp:926] Master terminating
I1126 08:41:17.596282 30289 hierarchical.cpp:260] Removed framework 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000
E1126 08:41:17.599283 30298 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (809 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I1126 08:41:17.756219 30268 leveldb.cpp:174] Opened db in 130.765563ms
I1126 08:41:17.804486 30268 leveldb.cpp:181] Compacted db in 48.182997ms
I1126 08:41:17.804584 30268 leveldb.cpp:196] Created db iterator in 31181ns
I1126 08:41:17.804606 30268 leveldb.cpp:202] Seeked to beginning of db in 3680ns
I1126 08:41:17.804621 30268 leveldb.cpp:271] Iterated through 0 keys in the db in 377ns
I1126 08:41:17.804688 30268 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1126 08:41:17.805640 30292 recover.cpp:447] Starting replica recovery
I1126 08:41:17.806316 30292 recover.cpp:473] Replica is in EMPTY status
I1126 08:41:17.808589 30292 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11067)@172.17.19.37:43173
I1126 08:41:17.809130 30292 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1126 08:41:17.809806 30292 recover.cpp:564] Updating replica status to STARTING
I1126 08:41:17.818367 30301 master.cpp:365] Master 1f1c347c-920b-4ef4-927c-21637980a621 (540c39c2376a) started on 172.17.19.37:43173
I1126 08:41:17.819011 30301 master.cpp:367] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/z9A0Pp/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/z9A0Pp/master" --zk_session_timeout="10secs"
I1126 08:41:17.819829 30301 master.cpp:414] Master allowing unauthenticated frameworks to register
I1126 08:41:17.820024 30301 master.cpp:417] Master only allowing authenticated slaves to register
I1126 08:41:17.820279 30301 credentials.hpp:35] Loading credentials for authentication from '/tmp/z9A0Pp/credentials'
I1126 08:41:17.821027 30301 master.cpp:456] Using default 'crammd5' authenticator
I1126 08:41:17.821717 30301 master.cpp:493] Authorization enabled
I1126 08:41:17.823547 30300 hierarchical.cpp:162] Initialized hierarchical allocator process
I1126 08:41:17.824093 30299 whitelist_watcher.cpp:77] No whitelist given
I1126 08:41:17.829968 30301 master.cpp:1625] The newly elected leader is master@172.17.19.37:43173 with id 1f1c347c-920b-4ef4-927c-21637980a621
I1126 08:41:17.830354 30301 master.cpp:1638] Elected as the leading master!
I1126 08:41:17.830587 30301 master.cpp:1383] Recovering from registrar
I1126 08:41:17.831058 30297 registrar.cpp:307] Recovering registrar
I1126 08:41:17.848932 30293 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 37.673131ms
I1126 08:41:17.849035 30293 replica.cpp:321] Persisted replica status to STARTING
I1126 08:41:17.849447 30293 recover.cpp:473] Replica is in STARTING status
I1126 08:41:17.851474 30293 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11068)@172.17.19.37:43173
I1126 08:41:17.851874 30293 recover.cpp:193] Received a recover response from a replica in STARTING status
I1126 08:41:17.852571 30293 recover.cpp:564] Updating replica status to VOTING
I1126 08:41:17.896663 30293 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 43.832406ms
I1126 08:41:17.896750 30293 replica.cpp:321] Persisted replica status to VOTING
I1126 08:41:17.897032 30293 recover.cpp:578] Successfully joined the Paxos group
I1126 08:41:17.897920 30294 log.cpp:659] Attempting to start the writer
I1126 08:41:17.899674 30294 replica.cpp:494] Replica received implicit promise request from (11069)@172.17.19.37:43173 with proposal 1
I1126 08:41:17.900002 30293 recover.cpp:462] Recover process terminated
I1126 08:41:17.938561 30294 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.81871ms
I1126 08:41:17.938840 30294 replica.cpp:343] Persisted promised to 1
I1126 08:41:17.940304 30292 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1126 08:41:17.943248 30294 replica.cpp:389] Replica received explicit promise request from (11070)@172.17.19.37:43173 for position 0 with proposal 2
I1126 08:41:17.971194 30294 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 27.743046ms
I1126 08:41:17.971412 30294 replica.cpp:713] Persisted action at 0
I1126 08:41:17.973070 30299 replica.cpp:538] Replica received write request for position 0 from (11071)@172.17.19.37:43173
I1126 08:41:17.973188 30299 leveldb.cpp:436] Reading position from leveldb took 50689ns
I1126 08:41:17.998332 30299 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 25.066309ms
I1126 08:41:17.998580 30299 replica.cpp:713] Persisted action at 0
I1126 08:41:17.999881 30291 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1126 08:41:18.021944 30291 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 21.847494ms
I1126 08:41:18.022482 30291 replica.cpp:713] Persisted action at 0
I1126 08:41:18.022804 30291 replica.cpp:698] Replica learned NOP action at position 0
I1126 08:41:18.024525 30291 log.cpp:675] Writer started with ending position 0
I1126 08:41:18.026703 30291 leveldb.cpp:436] Reading position from leveldb took 76807ns
I1126 08:41:18.028764 30291 registrar.cpp:340] Successfully fetched the registry (0B) in 197.398016ms
I1126 08:41:18.029429 30291 registrar.cpp:439] Applied 1 operations in 47405ns; attempting to update the 'registry'
I1126 08:41:18.031069 30290 log.cpp:683] Attempting to append 176 bytes to the log
I1126 08:41:18.031780 30290 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1126 08:41:18.033532 30292 replica.cpp:538] Replica received write request for position 1 from (11072)@172.17.19.37:43173
I1126 08:41:18.055651 30292 leveldb.cpp:341] Persisting action (195 bytes) to leveldb took 21.832632ms
I1126 08:41:18.056033 30292 replica.cpp:713] Persisted action at 1
I1126 08:41:18.062062 30300 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1126 08:41:18.089146 30300 leveldb.cpp:341] Persisting action (197 bytes) to leveldb took 24.803626ms
I1126 08:41:18.089354 30300 replica.cpp:713] Persisted action at 1
I1126 08:41:18.089499 30300 replica.cpp:698] Replica learned APPEND action at position 1
I1126 08:41:18.091197 30288 registrar.cpp:484] Successfully updated the 'registry' in 61.526016ms
I1126 08:41:18.091366 30288 registrar.cpp:370] Successfully recovered registrar
I1126 08:41:18.091509 30300 log.cpp:702] Attempting to truncate the log to 1
I1126 08:41:18.091817 30288 master.cpp:1435] Recovered 0 slaves from the Registry (137B) ; allowing 10mins for slaves to re-register
I1126 08:41:18.091997 30288 hierarchical.cpp:174] Allocator recovery is not supported yet
I1126 08:41:18.092056 30300 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1126 08:41:18.093266 30295 replica.cpp:538] Replica received write request for position 2 from (11073)@172.17.19.37:43173
I1126 08:41:18.116719 30295 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 23.384956ms
I1126 08:41:18.116803 30295 replica.cpp:713] Persisted action at 2
I1126 08:41:18.119138 30296 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1126 08:41:18.139567 30296 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 19.910572ms
I1126 08:41:18.139720 30296 leveldb.cpp:399] Deleting ~1 keys from leveldb took 72us
I1126 08:41:18.139755 30296 replica.cpp:713] Persisted action at 2
I1126 08:41:18.139794 30296 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1126 08:41:18.143863 30268 scheduler.cpp:154] Version: 0.27.0
I1126 08:41:18.144671 30297 scheduler.cpp:236] New master detected at master@172.17.19.37:43173
I1126 08:41:18.148963 30292 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.19.37:43173
I1126 08:41:18.151881 30296 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1126 08:41:18.151957 30296 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.19.37:42434
I1126 08:41:18.152412 30296 master.cpp:1887] Received subscription request for HTTP framework 'default'
I1126 08:41:18.152503 30296 master.cpp:1664] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1126 08:41:18.152940 30294 master.cpp:1979] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I1126 08:41:18.153520 30296 hierarchical.cpp:220] Added framework 1f1c347c-920b-4ef4-927c-21637980a621-0000
I1126 08:41:18.153585 30296 hierarchical.cpp:1064] No resources available to allocate!
I1126 08:41:18.153623 30296 hierarchical.cpp:1157] No inverse offers to send out!
I1126 08:41:18.153647 30296 hierarchical.cpp:959] Performed allocation for 0 slaves in 101403ns
I1126 08:41:18.153677 30296 master.hpp:1531] Sending heartbeat to 1f1c347c-920b-4ef4-927c-21637980a621-0000
I1126 08:41:18.156234 30299 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.19.37:43173
I1126 08:41:18.157321 30288 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.19.37:43173
I1126 08:41:18.157652 30288 scheduler.cpp:298] Sending REQUEST call to master@172.17.19.37:43173
I1126 08:41:18.160322 30299 master_maintenance_tests.cpp:172] Ignoring HEARTBEAT event
I1126 08:41:18.160781 30287 process.cpp:3067] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1126 08:41:18.160853 30287 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.19.37:42435
I1126 08:41:18.161149 30287 master.cpp:2634] Processing REQUEST call for framework 1f1c347c-920b-4ef4-927c-21637980a621-0000 (default)
I1126 08:41:18.161867 30287 hierarchical.cpp:494] Received resource request from framework 1f1c347c-920b-4ef4-927c-21637980a621-0000
I1126 08:41:18.164450 30292 master.cpp:926] Master terminating
I1126 08:41:18.165388 30297 hierarchical.cpp:260] Removed framework 1f1c347c-920b-4ef4-927c-21637980a621-0000
E1126 08:41:18.166574 30300 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (628 ms)
[----------] 22 tests from ContentType/SchedulerTest (20816 ms total)
[----------] Global test environment tear-down
[==========] 851 tests from 114 test cases ran. (937703 ms total)
[ PASSED ] 850 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] MasterQuotaTest.AvailableResourcesAfterRescinding
1 FAILED TEST
YOU HAVE 6 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-1448523331-23112
Untagged: mesos-1448523331-23112:latest
Deleted: f6e79e555c9fc56f93564db26cabff9fa06f9b9f1d12127e100fba3b24c69ad0
Deleted: bee9aabe90aacfe768baeb141c4bd9aeed007dec1fb1ede54b65d64f442b057e
Deleted: db1debf5ddb964544784fe2e79f0391ab8cca57959abd9e3f7578b920a8c4474
Deleted: 6ffcc9acb5a559293d5c0dc699085c333eb9f6701d2c1b6429b2408bb417ec72
Deleted: f69e7b705bd9fba49604ee733469d2193a59380c3cd1de81a58d468fa687d8b3
Deleted: 439365b0be484990d8d19e164f8232580278c3742cb88df3e6564c3c556e51a0
Deleted: db668cbe84cc70d4c0f68ad47d79167a11c7a5b81751b746718687e008b61cec
Deleted: e28fbdb634f68cde70ee925c6e06a7905f63141cd0820812f7e0687aaf7d7625
Deleted: 30a5b9eeb7430cedcb630a58a329587b7fd7d48fe0bbbfe7f402c28576ad569f
Deleted: db89e65391074386cf008f3050972eaf0675cdf5d2b75cded00aab31c923d50c
Deleted: c11fc8d90110940f68da85d639dc5e5c790e8af813fd0daa3ec0cb9be0f3ac6a
Deleted: 31588aa58642e0a3343f0ab4083342e0d19f45cbcd35d96663037f65de2c9385
Deleted: 8e916f0ce57c0fb6fdd6e1e1c106e392db343875be87bae92640ef6e9a299acd
Error handling URL https://reviews.apache.org/api/review-requests/40732/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 91b1fa4e3fec60d455e279c1da24f4060edcf15b
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : mesos-reviewbot #9907
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/mesos-reviewbot/9907/>
Re: Build failed in Jenkins: mesos-reviewbot #9906
Posted by Joris Van Remoortere <jo...@mesosphere.io>.
Working with Alexr to fix this.
—
*Joris Van Remoortere*
Mesosphere
On Thu, Nov 26, 2015 at 3:41 AM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <https://builds.apache.org/job/mesos-reviewbot/9906/>
>
> ------------------------------------------
> [...truncated 157068 lines...]
> [ OK ] ContentType/SchedulerTest.Message/1 (881 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> I1126 08:41:16.912010 30268 leveldb.cpp:174] Opened db in 98.112936ms
> I1126 08:41:16.992033 30268 leveldb.cpp:181] Compacted db in 79.937816ms
> I1126 08:41:16.992269 30268 leveldb.cpp:196] Created db iterator in 29345ns
> I1126 08:41:16.992490 30268 leveldb.cpp:202] Seeked to beginning of db in
> 3869ns
> I1126 08:41:16.992508 30268 leveldb.cpp:271] Iterated through 0 keys in
> the db in 332ns
> I1126 08:41:16.992573 30268 replica.cpp:778] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1126 08:41:16.993712 30295 recover.cpp:447] Starting replica recovery
> I1126 08:41:16.994027 30295 recover.cpp:473] Replica is in EMPTY status
> I1126 08:41:16.997388 30302 replica.cpp:674] Replica in EMPTY status
> received a broadcasted recover request from (11056)@172.17.19.37:43173
> I1126 08:41:16.997833 30294 master.cpp:365] Master
> 97dd024a-0499-41da-a6ca-cf009ac5beaa (540c39c2376a) started on
> 172.17.19.37:43173
> I1126 08:41:16.997932 30294 master.cpp:367] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/2giqnM/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/2giqnM/master" --zk_session_timeout="10secs"
> I1126 08:41:16.998514 30294 master.cpp:414] Master allowing
> unauthenticated frameworks to register
> I1126 08:41:16.998636 30294 master.cpp:417] Master only allowing
> authenticated slaves to register
> I1126 08:41:16.998776 30294 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/2giqnM/credentials'
> I1126 08:41:16.999286 30294 master.cpp:456] Using default 'crammd5'
> authenticator
> I1126 08:41:16.999984 30295 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I1126 08:41:17.000421 30294 master.cpp:493] Authorization enabled
> I1126 08:41:17.001515 30295 recover.cpp:564] Updating replica status to
> STARTING
> I1126 08:41:17.001785 30298 whitelist_watcher.cpp:77] No whitelist given
> I1126 08:41:17.001906 30296 hierarchical.cpp:162] Initialized hierarchical
> allocator process
> I1126 08:41:17.004117 30294 master.cpp:1625] The newly elected leader is
> master@172.17.19.37:43173 with id 97dd024a-0499-41da-a6ca-cf009ac5beaa
> I1126 08:41:17.004232 30294 master.cpp:1638] Elected as the leading master!
> I1126 08:41:17.004353 30294 master.cpp:1383] Recovering from registrar
> I1126 08:41:17.004642 30300 registrar.cpp:307] Recovering registrar
> I1126 08:41:17.066624 30298 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 62.632851ms
> I1126 08:41:17.066972 30298 replica.cpp:321] Persisted replica status to
> STARTING
> I1126 08:41:17.067644 30295 recover.cpp:473] Replica is in STARTING status
> I1126 08:41:17.069596 30298 replica.cpp:674] Replica in STARTING status
> received a broadcasted recover request from (11057)@172.17.19.37:43173
> I1126 08:41:17.070401 30298 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I1126 08:41:17.071357 30298 recover.cpp:564] Updating replica status to
> VOTING
> 2015-11-26
> 08:41:17,081:30268(0x2b762b683700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:55214] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> I1126 08:41:17.142293 30298 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 70.436136ms
> I1126 08:41:17.142554 30298 replica.cpp:321] Persisted replica status to
> VOTING
> I1126 08:41:17.143031 30302 recover.cpp:578] Successfully joined the Paxos
> group
> I1126 08:41:17.143414 30294 recover.cpp:462] Recover process terminated
> I1126 08:41:17.144076 30298 log.cpp:659] Attempting to start the writer
> I1126 08:41:17.149411 30291 replica.cpp:494] Replica received implicit
> promise request from (11058)@172.17.19.37:43173 with proposal 1
> I1126 08:41:17.194317 30291 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 44.559572ms
> I1126 08:41:17.194401 30291 replica.cpp:343] Persisted promised to 1
> I1126 08:41:17.196048 30288 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I1126 08:41:17.198104 30291 replica.cpp:389] Replica received explicit
> promise request from (11059)@172.17.19.37:43173 for position 0 with
> proposal 2
> I1126 08:41:17.249997 30291 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 51.267592ms
> I1126 08:41:17.250085 30291 replica.cpp:713] Persisted action at 0
> I1126 08:41:17.252085 30302 replica.cpp:538] Replica received write
> request for position 0 from (11060)@172.17.19.37:43173
> I1126 08:41:17.252228 30302 leveldb.cpp:436] Reading position from leveldb
> took 73861ns
> I1126 08:41:17.303256 30302 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 50.950648ms
> I1126 08:41:17.303345 30302 replica.cpp:713] Persisted action at 0
> I1126 08:41:17.304307 30295 replica.cpp:692] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1126 08:41:17.368266 30295 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 63.892606ms
> I1126 08:41:17.368362 30295 replica.cpp:713] Persisted action at 0
> I1126 08:41:17.368402 30295 replica.cpp:698] Replica learned NOP action at
> position 0
> I1126 08:41:17.369660 30302 log.cpp:675] Writer started with ending
> position 0
> I1126 08:41:17.371481 30302 leveldb.cpp:436] Reading position from leveldb
> took 68526ns
> I1126 08:41:17.374327 30291 registrar.cpp:340] Successfully fetched the
> registry (0B) in 369.497088ms
> I1126 08:41:17.374603 30291 registrar.cpp:439] Applied 1 operations in
> 39793ns; attempting to update the 'registry'
> I1126 08:41:17.375888 30296 log.cpp:683] Attempting to append 176 bytes to
> the log
> I1126 08:41:17.376667 30296 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I1126 08:41:17.377918 30296 replica.cpp:538] Replica received write
> request for position 1 from (11061)@172.17.19.37:43173
> I1126 08:41:17.410267 30296 leveldb.cpp:341] Persisting action (195 bytes)
> to leveldb took 32.137775ms
> I1126 08:41:17.410361 30296 replica.cpp:713] Persisted action at 1
> I1126 08:41:17.411972 30298 replica.cpp:692] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1126 08:41:17.462352 30298 leveldb.cpp:341] Persisting action (197 bytes)
> to leveldb took 50.139152ms
> I1126 08:41:17.462448 30298 replica.cpp:713] Persisted action at 1
> I1126 08:41:17.462489 30298 replica.cpp:698] Replica learned APPEND action
> at position 1
> I1126 08:41:17.464036 30298 registrar.cpp:484] Successfully updated the
> 'registry' in 89.032192ms
> I1126 08:41:17.464289 30298 registrar.cpp:370] Successfully recovered
> registrar
> I1126 08:41:17.464408 30293 log.cpp:702] Attempting to truncate the log to
> 1
> I1126 08:41:17.464638 30293 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I1126 08:41:17.464951 30298 master.cpp:1435] Recovered 0 slaves from the
> Registry (137B) ; allowing 10mins for slaves to re-register
> I1126 08:41:17.465101 30292 hierarchical.cpp:174] Allocator recovery is
> not supported yet
> I1126 08:41:17.466384 30298 replica.cpp:538] Replica received write
> request for position 2 from (11062)@172.17.19.37:43173
> I1126 08:41:17.520030 30298 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 53.488461ms
> I1126 08:41:17.520118 30298 replica.cpp:713] Persisted action at 2
> I1126 08:41:17.521520 30294 replica.cpp:692] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1126 08:41:17.552062 30294 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 30.368539ms
> I1126 08:41:17.552237 30294 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 86246ns
> I1126 08:41:17.552264 30294 replica.cpp:713] Persisted action at 2
> I1126 08:41:17.552301 30294 replica.cpp:698] Replica learned TRUNCATE
> action at position 2
> I1126 08:41:17.559101 30268 scheduler.cpp:154] Version: 0.27.0
> I1126 08:41:17.560509 30292 scheduler.cpp:236] New master detected at
> master@172.17.19.37:43173
> I1126 08:41:17.566345 30300 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.19.37:43173
> I1126 08:41:17.572700 30287 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1126 08:41:17.572888 30287 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.19.37:42431
> I1126 08:41:17.573284 30287 master.cpp:1887] Received subscription request
> for HTTP framework 'default'
> I1126 08:41:17.573431 30287 master.cpp:1664] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1126 08:41:17.574997 30287 master.cpp:1979] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I1126 08:41:17.578346 30300 hierarchical.cpp:220] Added framework
> 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000
> I1126 08:41:17.581104 30300 hierarchical.cpp:1064] No resources available
> to allocate!
> I1126 08:41:17.581289 30300 hierarchical.cpp:1157] No inverse offers to
> send out!
> I1126 08:41:17.581384 30300 hierarchical.cpp:959] Performed allocation for
> 0 slaves in 323307ns
> I1126 08:41:17.580791 30290 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.19.37:43173
> I1126 08:41:17.583786 30287 master.hpp:1531] Sending heartbeat to
> 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000
> I1126 08:41:17.585297 30290 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.19.37:43173
> I1126 08:41:17.585805 30290 master_maintenance_tests.cpp:172] Ignoring
> HEARTBEAT event
> I1126 08:41:17.587102 30290 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.19.37:43173
> I1126 08:41:17.592360 30289 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1126 08:41:17.592499 30289 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.19.37:42432
> I1126 08:41:17.592718 30289 master.cpp:2634] Processing REQUEST call for
> framework 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000 (default)
> I1126 08:41:17.592978 30289 hierarchical.cpp:494] Received resource
> request from framework 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000
> I1126 08:41:17.595301 30268 master.cpp:926] Master terminating
> I1126 08:41:17.596282 30289 hierarchical.cpp:260] Removed framework
> 97dd024a-0499-41da-a6ca-cf009ac5beaa-0000
> E1126 08:41:17.599283 30298 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/0 (809 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> I1126 08:41:17.756219 30268 leveldb.cpp:174] Opened db in 130.765563ms
> I1126 08:41:17.804486 30268 leveldb.cpp:181] Compacted db in 48.182997ms
> I1126 08:41:17.804584 30268 leveldb.cpp:196] Created db iterator in 31181ns
> I1126 08:41:17.804606 30268 leveldb.cpp:202] Seeked to beginning of db in
> 3680ns
> I1126 08:41:17.804621 30268 leveldb.cpp:271] Iterated through 0 keys in
> the db in 377ns
> I1126 08:41:17.804688 30268 replica.cpp:778] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1126 08:41:17.805640 30292 recover.cpp:447] Starting replica recovery
> I1126 08:41:17.806316 30292 recover.cpp:473] Replica is in EMPTY status
> I1126 08:41:17.808589 30292 replica.cpp:674] Replica in EMPTY status
> received a broadcasted recover request from (11067)@172.17.19.37:43173
> I1126 08:41:17.809130 30292 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I1126 08:41:17.809806 30292 recover.cpp:564] Updating replica status to
> STARTING
> I1126 08:41:17.818367 30301 master.cpp:365] Master
> 1f1c347c-920b-4ef4-927c-21637980a621 (540c39c2376a) started on
> 172.17.19.37:43173
> I1126 08:41:17.819011 30301 master.cpp:367] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/z9A0Pp/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/z9A0Pp/master" --zk_session_timeout="10secs"
> I1126 08:41:17.819829 30301 master.cpp:414] Master allowing
> unauthenticated frameworks to register
> I1126 08:41:17.820024 30301 master.cpp:417] Master only allowing
> authenticated slaves to register
> I1126 08:41:17.820279 30301 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/z9A0Pp/credentials'
> I1126 08:41:17.821027 30301 master.cpp:456] Using default 'crammd5'
> authenticator
> I1126 08:41:17.821717 30301 master.cpp:493] Authorization enabled
> I1126 08:41:17.823547 30300 hierarchical.cpp:162] Initialized hierarchical
> allocator process
> I1126 08:41:17.824093 30299 whitelist_watcher.cpp:77] No whitelist given
> I1126 08:41:17.829968 30301 master.cpp:1625] The newly elected leader is
> master@172.17.19.37:43173 with id 1f1c347c-920b-4ef4-927c-21637980a621
> I1126 08:41:17.830354 30301 master.cpp:1638] Elected as the leading master!
> I1126 08:41:17.830587 30301 master.cpp:1383] Recovering from registrar
> I1126 08:41:17.831058 30297 registrar.cpp:307] Recovering registrar
> I1126 08:41:17.848932 30293 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 37.673131ms
> I1126 08:41:17.849035 30293 replica.cpp:321] Persisted replica status to
> STARTING
> I1126 08:41:17.849447 30293 recover.cpp:473] Replica is in STARTING status
> I1126 08:41:17.851474 30293 replica.cpp:674] Replica in STARTING status
> received a broadcasted recover request from (11068)@172.17.19.37:43173
> I1126 08:41:17.851874 30293 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I1126 08:41:17.852571 30293 recover.cpp:564] Updating replica status to
> VOTING
> I1126 08:41:17.896663 30293 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 43.832406ms
> I1126 08:41:17.896750 30293 replica.cpp:321] Persisted replica status to
> VOTING
> I1126 08:41:17.897032 30293 recover.cpp:578] Successfully joined the Paxos
> group
> I1126 08:41:17.897920 30294 log.cpp:659] Attempting to start the writer
> I1126 08:41:17.899674 30294 replica.cpp:494] Replica received implicit
> promise request from (11069)@172.17.19.37:43173 with proposal 1
> I1126 08:41:17.900002 30293 recover.cpp:462] Recover process terminated
> I1126 08:41:17.938561 30294 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 38.81871ms
> I1126 08:41:17.938840 30294 replica.cpp:343] Persisted promised to 1
> I1126 08:41:17.940304 30292 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I1126 08:41:17.943248 30294 replica.cpp:389] Replica received explicit
> promise request from (11070)@172.17.19.37:43173 for position 0 with
> proposal 2
> I1126 08:41:17.971194 30294 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 27.743046ms
> I1126 08:41:17.971412 30294 replica.cpp:713] Persisted action at 0
> I1126 08:41:17.973070 30299 replica.cpp:538] Replica received write
> request for position 0 from (11071)@172.17.19.37:43173
> I1126 08:41:17.973188 30299 leveldb.cpp:436] Reading position from leveldb
> took 50689ns
> I1126 08:41:17.998332 30299 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 25.066309ms
> I1126 08:41:17.998580 30299 replica.cpp:713] Persisted action at 0
> I1126 08:41:17.999881 30291 replica.cpp:692] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1126 08:41:18.021944 30291 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 21.847494ms
> I1126 08:41:18.022482 30291 replica.cpp:713] Persisted action at 0
> I1126 08:41:18.022804 30291 replica.cpp:698] Replica learned NOP action at
> position 0
> I1126 08:41:18.024525 30291 log.cpp:675] Writer started with ending
> position 0
> I1126 08:41:18.026703 30291 leveldb.cpp:436] Reading position from leveldb
> took 76807ns
> I1126 08:41:18.028764 30291 registrar.cpp:340] Successfully fetched the
> registry (0B) in 197.398016ms
> I1126 08:41:18.029429 30291 registrar.cpp:439] Applied 1 operations in
> 47405ns; attempting to update the 'registry'
> I1126 08:41:18.031069 30290 log.cpp:683] Attempting to append 176 bytes to
> the log
> I1126 08:41:18.031780 30290 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I1126 08:41:18.033532 30292 replica.cpp:538] Replica received write
> request for position 1 from (11072)@172.17.19.37:43173
> I1126 08:41:18.055651 30292 leveldb.cpp:341] Persisting action (195 bytes)
> to leveldb took 21.832632ms
> I1126 08:41:18.056033 30292 replica.cpp:713] Persisted action at 1
> I1126 08:41:18.062062 30300 replica.cpp:692] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1126 08:41:18.089146 30300 leveldb.cpp:341] Persisting action (197 bytes)
> to leveldb took 24.803626ms
> I1126 08:41:18.089354 30300 replica.cpp:713] Persisted action at 1
> I1126 08:41:18.089499 30300 replica.cpp:698] Replica learned APPEND action
> at position 1
> I1126 08:41:18.091197 30288 registrar.cpp:484] Successfully updated the
> 'registry' in 61.526016ms
> I1126 08:41:18.091366 30288 registrar.cpp:370] Successfully recovered
> registrar
> I1126 08:41:18.091509 30300 log.cpp:702] Attempting to truncate the log to
> 1
> I1126 08:41:18.091817 30288 master.cpp:1435] Recovered 0 slaves from the
> Registry (137B) ; allowing 10mins for slaves to re-register
> I1126 08:41:18.091997 30288 hierarchical.cpp:174] Allocator recovery is
> not supported yet
> I1126 08:41:18.092056 30300 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I1126 08:41:18.093266 30295 replica.cpp:538] Replica received write
> request for position 2 from (11073)@172.17.19.37:43173
> I1126 08:41:18.116719 30295 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 23.384956ms
> I1126 08:41:18.116803 30295 replica.cpp:713] Persisted action at 2
> I1126 08:41:18.119138 30296 replica.cpp:692] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1126 08:41:18.139567 30296 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 19.910572ms
> I1126 08:41:18.139720 30296 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 72us
> I1126 08:41:18.139755 30296 replica.cpp:713] Persisted action at 2
> I1126 08:41:18.139794 30296 replica.cpp:698] Replica learned TRUNCATE
> action at position 2
> I1126 08:41:18.143863 30268 scheduler.cpp:154] Version: 0.27.0
> I1126 08:41:18.144671 30297 scheduler.cpp:236] New master detected at
> master@172.17.19.37:43173
> I1126 08:41:18.148963 30292 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.19.37:43173
> I1126 08:41:18.151881 30296 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1126 08:41:18.151957 30296 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.19.37:42434
> I1126 08:41:18.152412 30296 master.cpp:1887] Received subscription request
> for HTTP framework 'default'
> I1126 08:41:18.152503 30296 master.cpp:1664] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1126 08:41:18.152940 30294 master.cpp:1979] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I1126 08:41:18.153520 30296 hierarchical.cpp:220] Added framework
> 1f1c347c-920b-4ef4-927c-21637980a621-0000
> I1126 08:41:18.153585 30296 hierarchical.cpp:1064] No resources available
> to allocate!
> I1126 08:41:18.153623 30296 hierarchical.cpp:1157] No inverse offers to
> send out!
> I1126 08:41:18.153647 30296 hierarchical.cpp:959] Performed allocation for
> 0 slaves in 101403ns
> I1126 08:41:18.153677 30296 master.hpp:1531] Sending heartbeat to
> 1f1c347c-920b-4ef4-927c-21637980a621-0000
> I1126 08:41:18.156234 30299 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.19.37:43173
> I1126 08:41:18.157321 30288 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.19.37:43173
> I1126 08:41:18.157652 30288 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.19.37:43173
> I1126 08:41:18.160322 30299 master_maintenance_tests.cpp:172] Ignoring
> HEARTBEAT event
> I1126 08:41:18.160781 30287 process.cpp:3067] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1126 08:41:18.160853 30287 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.19.37:42435
> I1126 08:41:18.161149 30287 master.cpp:2634] Processing REQUEST call for
> framework 1f1c347c-920b-4ef4-927c-21637980a621-0000 (default)
> I1126 08:41:18.161867 30287 hierarchical.cpp:494] Received resource
> request from framework 1f1c347c-920b-4ef4-927c-21637980a621-0000
> I1126 08:41:18.164450 30292 master.cpp:926] Master terminating
> I1126 08:41:18.165388 30297 hierarchical.cpp:260] Removed framework
> 1f1c347c-920b-4ef4-927c-21637980a621-0000
> E1126 08:41:18.166574 30300 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (628 ms)
> [----------] 22 tests from ContentType/SchedulerTest (20816 ms total)
>
> [----------] Global test environment tear-down
> [==========] 851 tests from 114 test cases ran. (937703 ms total)
> [ PASSED ] 850 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] MasterQuotaTest.AvailableResourcesAfterRescinding
>
> 1 FAILED TEST
> YOU HAVE 6 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-1448523331-23112
> Untagged: mesos-1448523331-23112:latest
> Deleted: f6e79e555c9fc56f93564db26cabff9fa06f9b9f1d12127e100fba3b24c69ad0
> Deleted: bee9aabe90aacfe768baeb141c4bd9aeed007dec1fb1ede54b65d64f442b057e
> Deleted: db1debf5ddb964544784fe2e79f0391ab8cca57959abd9e3f7578b920a8c4474
> Deleted: 6ffcc9acb5a559293d5c0dc699085c333eb9f6701d2c1b6429b2408bb417ec72
> Deleted: f69e7b705bd9fba49604ee733469d2193a59380c3cd1de81a58d468fa687d8b3
> Deleted: 439365b0be484990d8d19e164f8232580278c3742cb88df3e6564c3c556e51a0
> Deleted: db668cbe84cc70d4c0f68ad47d79167a11c7a5b81751b746718687e008b61cec
> Deleted: e28fbdb634f68cde70ee925c6e06a7905f63141cd0820812f7e0687aaf7d7625
> Deleted: 30a5b9eeb7430cedcb630a58a329587b7fd7d48fe0bbbfe7f402c28576ad569f
> Deleted: db89e65391074386cf008f3050972eaf0675cdf5d2b75cded00aab31c923d50c
> Deleted: c11fc8d90110940f68da85d639dc5e5c790e8af813fd0daa3ec0cb9be0f3ac6a
> Deleted: 31588aa58642e0a3343f0ab4083342e0d19f45cbcd35d96663037f65de2c9385
> Deleted: 8e916f0ce57c0fb6fdd6e1e1c106e392db343875be87bae92640ef6e9a299acd
>
> Error handling URL
> https://reviews.apache.org/api/review-requests/40732/reviews/: INTERNAL
> SERVER ERROR
> git clean -fd
> git reset --hard 91b1fa4e3fec60d455e279c1da24f4060edcf15b
>
> Build step 'Execute shell' marked build as failure
>