You are viewing a plain text version of this content. The canonical link for it is here.

Posted to builds@mesos.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/12/07 15:03:10 UTC

Build failed in Jenkins: mesos-reviewbot #10066

See <https://builds.apache.org/job/mesos-reviewbot/10066/>

------------------------------------------
[...truncated 156328 lines...]
I1207 14:02:51.247438 30325 replica.cpp:538] Replica received write request for position 0 from (11297)@172.17.0.1:42610
I1207 14:02:51.247500 30325 leveldb.cpp:436] Reading position from leveldb took 26386ns
I1207 14:02:51.247952 30325 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 404964ns
I1207 14:02:51.247978 30325 replica.cpp:713] Persisted action at 0
I1207 14:02:51.248538 30322 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1207 14:02:51.248991 30322 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 419256ns
I1207 14:02:51.249017 30322 replica.cpp:713] Persisted action at 0
I1207 14:02:51.249034 30322 replica.cpp:698] Replica learned NOP action at position 0
I1207 14:02:51.249570 30335 log.cpp:675] Writer started with ending position 0
I1207 14:02:51.250583 30332 leveldb.cpp:436] Reading position from leveldb took 29020ns
I1207 14:02:51.251462 30330 registrar.cpp:340] Successfully fetched the registry (0B) in 10.425856ms
I1207 14:02:51.251592 30330 registrar.cpp:439] Applied 1 operations in 30687ns; attempting to update the 'registry'
I1207 14:02:51.252459 30321 log.cpp:683] Attempting to append 170 bytes to the log
I1207 14:02:51.252599 30328 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1207 14:02:51.253439 30324 replica.cpp:538] Replica received write request for position 1 from (11298)@172.17.0.1:42610
I1207 14:02:51.253914 30324 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 426270ns
I1207 14:02:51.253945 30324 replica.cpp:713] Persisted action at 1
I1207 14:02:51.254640 30320 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1207 14:02:51.255180 30320 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 500226ns
I1207 14:02:51.255213 30320 replica.cpp:713] Persisted action at 1
I1207 14:02:51.255241 30320 replica.cpp:698] Replica learned APPEND action at position 1
I1207 14:02:51.256286 30333 registrar.cpp:484] Successfully updated the 'registry' in 4.624128ms
I1207 14:02:51.256451 30333 registrar.cpp:370] Successfully recovered registrar
I1207 14:02:51.256538 30320 log.cpp:702] Attempting to truncate the log to 1
I1207 14:02:51.256672 30330 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1207 14:02:51.257063 30323 master.cpp:1450] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1207 14:02:51.257124 30329 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1207 14:02:51.257877 30322 replica.cpp:538] Replica received write request for position 2 from (11299)@172.17.0.1:42610
I1207 14:02:51.258411 30322 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 487411ns
I1207 14:02:51.258442 30322 replica.cpp:713] Persisted action at 2
I1207 14:02:51.259430 30325 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1207 14:02:51.259850 30325 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 372442ns
I1207 14:02:51.259920 30325 leveldb.cpp:399] Deleting ~1 keys from leveldb took 39504ns
I1207 14:02:51.259948 30325 replica.cpp:713] Persisted action at 2
I1207 14:02:51.259973 30325 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1207 14:02:51.267732 30301 scheduler.cpp:154] Version: 0.27.0
I1207 14:02:51.268440 30333 scheduler.cpp:236] New master detected at master@172.17.0.1:42610
I1207 14:02:51.269629 30329 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.1:42610
I1207 14:02:51.271713 30334 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1207 14:02:51.271791 30334 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:34610
I1207 14:02:51.272027 30334 master.cpp:1902] Received subscription request for HTTP framework 'default'
I1207 14:02:51.272099 30334 master.cpp:1679] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1207 14:02:51.272418 30334 master.cpp:1994] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I1207 14:02:51.272794 30321 hierarchical.cpp:267] Added framework e0883391-a0a4-4230-a6bb-7bc2b310d8b8-0000
I1207 14:02:51.272900 30321 hierarchical.cpp:1317] No resources available to allocate!
I1207 14:02:51.272950 30321 hierarchical.cpp:1410] No inverse offers to send out!
I1207 14:02:51.272984 30320 master.hpp:1590] Sending heartbeat to e0883391-a0a4-4230-a6bb-7bc2b310d8b8-0000
I1207 14:02:51.272989 30321 hierarchical.cpp:1063] Performed allocation for 0 slaves in 131777ns
I1207 14:02:51.273898 30323 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.1:42610
I1207 14:02:51.274412 30323 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.1:42610
I1207 14:02:51.275359 30330 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1207 14:02:51.275540 30332 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.1:42610
I1207 14:02:51.277348 30328 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1207 14:02:51.277415 30328 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:34611
I1207 14:02:51.277624 30328 master.cpp:2649] Processing REQUEST call for framework e0883391-a0a4-4230-a6bb-7bc2b310d8b8-0000 (default)
I1207 14:02:51.277796 30321 hierarchical.cpp:562] Received resource request from framework e0883391-a0a4-4230-a6bb-7bc2b310d8b8-0000
I1207 14:02:51.278205 30301 master.cpp:941] Master terminating
I1207 14:02:51.278419 30329 hierarchical.cpp:308] Removed framework e0883391-a0a4-4230-a6bb-7bc2b310d8b8-0000
E1207 14:02:51.279934 30320 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[       OK ] ContentType/SchedulerTest.Request/0 (57 ms)
[ RUN      ] ContentType/SchedulerTest.Request/1
I1207 14:02:51.289686 30301 leveldb.cpp:174] Opened db in 3.004475ms
I1207 14:02:51.290724 30301 leveldb.cpp:181] Compacted db in 994504ns
I1207 14:02:51.290776 30301 leveldb.cpp:196] Created db iterator in 18002ns
I1207 14:02:51.290798 30301 leveldb.cpp:202] Seeked to beginning of db in 2015ns
I1207 14:02:51.290809 30301 leveldb.cpp:271] Iterated through 0 keys in the db in 402ns
I1207 14:02:51.290881 30301 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1207 14:02:51.291421 30323 recover.cpp:447] Starting replica recovery
I1207 14:02:51.291690 30323 recover.cpp:473] Replica is in EMPTY status
I1207 14:02:51.292884 30330 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11304)@172.17.0.1:42610
I1207 14:02:51.293437 30326 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1207 14:02:51.293994 30331 recover.cpp:564] Updating replica status to STARTING
I1207 14:02:51.294723 30322 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 496041ns
I1207 14:02:51.294754 30322 replica.cpp:321] Persisted replica status to STARTING
I1207 14:02:51.295043 30326 master.cpp:368] Master 7be7ff9d-2213-49f2-9a58-ca6af77a49e6 (61ca7c2c25aa) started on 172.17.0.1:42610
I1207 14:02:51.295063 30332 recover.cpp:473] Replica is in STARTING status
I1207 14:02:51.295075 30326 master.cpp:370] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/YkVYfK/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/YkVYfK/master" --zk_session_timeout="10secs"
I1207 14:02:51.295482 30326 master.cpp:417] Master allowing unauthenticated frameworks to register
I1207 14:02:51.295496 30326 master.cpp:420] Master only allowing authenticated slaves to register
I1207 14:02:51.295505 30326 credentials.hpp:35] Loading credentials for authentication from '/tmp/YkVYfK/credentials'
I1207 14:02:51.295809 30326 master.cpp:459] Using default 'crammd5' authenticator
I1207 14:02:51.296012 30326 master.cpp:496] Authorization enabled
I1207 14:02:51.296072 30323 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11305)@172.17.0.1:42610
I1207 14:02:51.296252 30320 whitelist_watcher.cpp:77] No whitelist given
I1207 14:02:51.296321 30330 hierarchical.cpp:163] Initialized hierarchical allocator process
I1207 14:02:51.296689 30322 recover.cpp:193] Received a recover response from a replica in STARTING status
I1207 14:02:51.297358 30334 recover.cpp:564] Updating replica status to VOTING
I1207 14:02:51.298099 30331 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 453602ns
I1207 14:02:51.298128 30331 replica.cpp:321] Persisted replica status to VOTING
I1207 14:02:51.298362 30320 recover.cpp:578] Successfully joined the Paxos group
I1207 14:02:51.298540 30331 master.cpp:1640] The newly elected leader is master@172.17.0.1:42610 with id 7be7ff9d-2213-49f2-9a58-ca6af77a49e6
I1207 14:02:51.298581 30331 master.cpp:1653] Elected as the leading master!
I1207 14:02:51.298610 30331 master.cpp:1398] Recovering from registrar
I1207 14:02:51.298660 30320 recover.cpp:462] Recover process terminated
I1207 14:02:51.298967 30332 registrar.cpp:307] Recovering registrar
I1207 14:02:51.299634 30335 log.cpp:659] Attempting to start the writer
I1207 14:02:51.301059 30321 replica.cpp:494] Replica received implicit promise request from (11306)@172.17.0.1:42610 with proposal 1
I1207 14:02:51.301590 30321 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 486329ns
I1207 14:02:51.301620 30321 replica.cpp:343] Persisted promised to 1
I1207 14:02:51.302310 30327 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1207 14:02:51.303704 30327 replica.cpp:389] Replica received explicit promise request from (11307)@172.17.0.1:42610 for position 0 with proposal 2
I1207 14:02:51.304298 30327 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 541817ns
I1207 14:02:51.304329 30327 replica.cpp:713] Persisted action at 0
I1207 14:02:51.305614 30332 replica.cpp:538] Replica received write request for position 0 from (11308)@172.17.0.1:42610
I1207 14:02:51.305681 30332 leveldb.cpp:436] Reading position from leveldb took 29354ns
I1207 14:02:51.306277 30332 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 539215ns
I1207 14:02:51.306311 30332 replica.cpp:713] Persisted action at 0
I1207 14:02:51.307025 30332 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1207 14:02:51.307586 30332 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 518640ns
I1207 14:02:51.307617 30332 replica.cpp:713] Persisted action at 0
I1207 14:02:51.307638 30332 replica.cpp:698] Replica learned NOP action at position 0
I1207 14:02:51.308305 30333 log.cpp:675] Writer started with ending position 0
I1207 14:02:51.309522 30322 leveldb.cpp:436] Reading position from leveldb took 33094ns
I1207 14:02:51.310650 30332 registrar.cpp:340] Successfully fetched the registry (0B) in 11.62112ms
I1207 14:02:51.310791 30332 registrar.cpp:439] Applied 1 operations in 31196ns; attempting to update the 'registry'
I1207 14:02:51.311728 30325 log.cpp:683] Attempting to append 170 bytes to the log
I1207 14:02:51.311985 30327 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1207 14:02:51.312880 30324 replica.cpp:538] Replica received write request for position 1 from (11309)@172.17.0.1:42610
I1207 14:02:51.313343 30324 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 411780ns
I1207 14:02:51.313374 30324 replica.cpp:713] Persisted action at 1
I1207 14:02:51.314085 30322 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1207 14:02:51.314621 30322 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 439138ns
I1207 14:02:51.314652 30322 replica.cpp:713] Persisted action at 1
I1207 14:02:51.314676 30322 replica.cpp:698] Replica learned APPEND action at position 1
I1207 14:02:51.315716 30334 registrar.cpp:484] Successfully updated the 'registry' in 4.827904ms
I1207 14:02:51.315974 30324 log.cpp:702] Attempting to truncate the log to 1
I1207 14:02:51.316051 30334 registrar.cpp:370] Successfully recovered registrar
I1207 14:02:51.316160 30330 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1207 14:02:51.316771 30332 master.cpp:1450] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1207 14:02:51.316848 30331 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1207 14:02:51.317312 30327 replica.cpp:538] Replica received write request for position 2 from (11310)@172.17.0.1:42610
I1207 14:02:51.317857 30327 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 496541ns
I1207 14:02:51.317890 30327 replica.cpp:713] Persisted action at 2
I1207 14:02:51.318639 30327 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1207 14:02:51.319232 30327 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 551677ns
I1207 14:02:51.319304 30327 leveldb.cpp:399] Deleting ~1 keys from leveldb took 40219ns
I1207 14:02:51.319330 30327 replica.cpp:713] Persisted action at 2
I1207 14:02:51.319353 30327 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1207 14:02:51.327496 30301 scheduler.cpp:154] Version: 0.27.0
I1207 14:02:51.328330 30335 scheduler.cpp:236] New master detected at master@172.17.0.1:42610
I1207 14:02:51.329481 30327 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.1:42610
I1207 14:02:51.331732 30329 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1207 14:02:51.331807 30329 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:34612
I1207 14:02:51.332221 30329 master.cpp:1902] Received subscription request for HTTP framework 'default'
I1207 14:02:51.332293 30329 master.cpp:1679] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1207 14:02:51.332665 30329 master.cpp:1994] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I1207 14:02:51.333037 30333 hierarchical.cpp:267] Added framework 7be7ff9d-2213-49f2-9a58-ca6af77a49e6-0000
I1207 14:02:51.333101 30333 hierarchical.cpp:1317] No resources available to allocate!
I1207 14:02:51.333145 30333 hierarchical.cpp:1410] No inverse offers to send out!
I1207 14:02:51.333178 30333 hierarchical.cpp:1063] Performed allocation for 0 slaves in 110163ns
I1207 14:02:51.333230 30327 master.hpp:1590] Sending heartbeat to 7be7ff9d-2213-49f2-9a58-ca6af77a49e6-0000
I1207 14:02:51.334199 30333 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.1:42610
I1207 14:02:51.334743 30333 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.1:42610
I1207 14:02:51.335477 30329 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1207 14:02:51.335546 30333 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.1:42610
I1207 14:02:51.337330 30335 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1207 14:02:51.337400 30335 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.1:34613
I1207 14:02:51.337693 30335 master.cpp:2649] Processing REQUEST call for framework 7be7ff9d-2213-49f2-9a58-ca6af77a49e6-0000 (default)
I1207 14:02:51.337905 30327 hierarchical.cpp:562] Received resource request from framework 7be7ff9d-2213-49f2-9a58-ca6af77a49e6-0000
I1207 14:02:51.338269 30332 master.cpp:941] Master terminating
I1207 14:02:51.338438 30327 hierarchical.cpp:308] Removed framework 7be7ff9d-2213-49f2-9a58-ca6af77a49e6-0000
E1207 14:02:51.339903 30320 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[       OK ] ContentType/SchedulerTest.Request/1 (60 ms)
[----------] 22 tests from ContentType/SchedulerTest (4185 ms total)

[----------] Global test environment tear-down
[==========] 879 tests from 115 test cases ran. (501637 ms total)
[  PASSED  ] 833 tests.
[  FAILED  ] 46 tests, listed below:
[  FAILED  ] HealthCheckTest.CheckCommandTimeout
[  FAILED  ] MonitorIntegrationTest.RunningExecutor
[  FAILED  ] ReservationEndpointsTest.ReserveAvailableAndOfferedResources
[  FAILED  ] ReservationEndpointsTest.UnreserveAvailableAndOfferedResources
[  FAILED  ] SlaveRecoveryTest/0.RecoverSlaveState, where TypeParam = mesos::internal::slave::MesosContainerizer
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[  FAILED  ] InMemoryStateTest.Names
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndFetch
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreFailAndFetch
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndFetch
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndExpunge
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[  FAILED  ] LevelDBStateTest.Names
[  FAILED  ] LogStateTest.FetchAndStoreAndFetch
[  FAILED  ] LogStateTest.FetchAndStoreAndStoreAndFetch
[  FAILED  ] LogStateTest.FetchAndStoreAndStoreFailAndFetch
[  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndFetch
[  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndExpunge
[  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[  FAILED  ] LogStateTest.Names
[  FAILED  ] LogStateTest.Timeout
[  FAILED  ] LogStateTest.Diff
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndFetch
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreAndFetch
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreFailAndFetch
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndFetch
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndExpunge
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[  FAILED  ] ZooKeeperStateTest.Names
[  FAILED  ] Strict/RegistrarTest.Recover/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Recover/1, where GetParam() = true
[  FAILED  ] Strict/RegistrarTest.Admit/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Admit/1, where GetParam() = true
[  FAILED  ] Strict/RegistrarTest.Readmit/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Readmit/1, where GetParam() = true
[  FAILED  ] Strict/RegistrarTest.Remove/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Remove/1, where GetParam() = true
[  FAILED  ] Strict/RegistrarTest.Bootstrap/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Abort/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Abort/1, where GetParam() = true

46 FAILED TESTS
  YOU HAVE 7 DISABLED TESTS

make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1449495074-25766
Untagged: mesos-1449495074-25766:latest
Deleted: e2132e0721775a5387a1ebc5125c8748a2f8e8293d4fb1fd006504168f6f1d04
Deleted: 534417fc8473ae99d0b5e97abe81f71f826617c2c393b9edc42c52191f3dac8c
Deleted: 238a89e26e6279587385ec02b2682bfdbe1d4bc6de96ad3a92efece30b70ae33
Deleted: 531314ab5a5d36d4e838c0670f4dfe532d524b3cf823e48f15dce13b026f65ae
Deleted: 0adf74efd874d6ef7e861aee224ef18a37bc7c675a77fb04a011322cd368ce90
Deleted: 38eafb07e2f54969f2c7bc43aecb28efee4bf1ce155ef4f86ee062a349d5ab02
Deleted: eec299bd74327b18bd83993b8e39fb59da3d2a75d1e6caab81d2b028dc637950
Deleted: 828f60c5efa0f1efe879859db05448b620a4b9f2e5c0deb365517cba70e74251
Deleted: 6b78e483491da8ce6ed23dc9fc19e61863735802704d4df1c279feef76d4a9a5
Deleted: 83e639804b26c17fa12b329295abcf2b7652c605dd424c27198688bd5ef9fc68
Deleted: 71dffb7089c8b8dd789b5b9e33b502f259061e9a4c88e5efa385353a0c271d78
Deleted: bead5c69a0002e04f083e69d6e4551e752bac1e92ed4120830272c76e25a395f
Deleted: e140e2557211395a25868bbfb1285f61b4166a4783552c5b5de9403edf82d234

Error handling URL https://reviews.apache.org/api/review-requests/40759/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 517e84669790f833f6cf0e7911968a3591c93419

Build step 'Execute shell' marked build as failure

Jenkins build is back to normal : mesos-reviewbot #10070

Posted by Apache Jenkins Server <je...@builds.apache.org>.

See <https://builds.apache.org/job/mesos-reviewbot/10070/>

Re: Build failed in Jenkins: mesos-reviewbot #10069

Posted by Vinod Kone <vi...@twitter.com.INVALID>.

aha. good find!


@vinodkone

On Mon, Dec 7, 2015 at 3:00 PM, Joris Van Remoortere <jo...@mesosphere.io>
wrote:

> This is the CI running on a patch on reviewboard, not on the commit chain.
> This is why it was not immediately obvious.
> The review is https://reviews.apache.org/r/40759/
> I'm removing this from the mesos group for now to avoid triggering this
> error further.
>
> —
> *Joris Van Remoortere*
> Mesosphere
>
> On Mon, Dec 7, 2015 at 5:54 PM, Joris Van Remoortere <jo...@mesosphere.io>
> wrote:
>
> > @vinod Do you know which commits introduced these failures? I tried
> > looking through my history but the first e-mail with these failures I see
> > had no new commits?
> >
> > —
> > *Joris Van Remoortere*
> > Mesosphere
> >
> > On Mon, Dec 7, 2015 at 5:51 PM, Vinod Kone <vi...@apache.org> wrote:
> >
> >> is someone looking into these failures?
> >>
> >> [ RUN      ] InMemoryStateTest.FetchAndStoreAndFetch
> >> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
> >> IsInitialized(): Can't serialize message of type
> >> "mesos.internal.Registry.Slaves" because it is missing required
> >> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
> >> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
> >> unknown file: Failure
> >> C++ exception with description "CHECK failed: IsInitialized(): Can't
> >> serialize message of type "mesos.internal.Registry.Slaves" because it
> >> is missing required fields: slaves[0].info.launcher_dir,
> >> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
> >> slaves[0].info.executor_rootfs" thrown in the test body.
> >> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch (2 ms)
> >> [ RUN      ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
> >> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
> >> IsInitialized(): Can't serialize message of type
> >> "mesos.internal.Registry.Slaves" because it is missing required
> >> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
> >> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
> >> unknown file: Failure
> >> C++ exception with description "CHECK failed: IsInitialized(): Can't
> >> serialize message of type "mesos.internal.Registry.Slaves" because it
> >> is missing required fields: slaves[0].info.launcher_dir,
> >> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
> >> slaves[0].info.executor_rootfs" thrown in the test body.
> >> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch (2 ms)
> >> [ RUN      ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
> >> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
> >> IsInitialized(): Can't serialize message of type
> >> "mesos.internal.Registry.Slaves" because it is missing required
> >> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
> >> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
> >> unknown file: Failure
> >> C++ exception with description "CHECK failed: IsInitialized(): Can't
> >> serialize message of type "mesos.internal.Registry.Slaves" because it
> >> is missing required fields: slaves[0].info.launcher_dir,
> >> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
> >> slaves[0].info.executor_rootfs" thrown in the test body.
> >>
> >>
> >>
> >> On Mon, Dec 7, 2015 at 2:28 PM, Apache Jenkins Server <
> >> jenkins@builds.apache.org> wrote:
> >>
> >> > See <https://builds.apache.org/job/mesos-reviewbot/10069/>
> >> >
> >> > ------------------------------------------
> >> > [...truncated 159973 lines...]
> >> > I1207 22:27:35.090376 30316 leveldb.cpp:436] Reading position from
> >> leveldb
> >> > took 235515ns
> >> > I1207 22:27:35.138778 30316 leveldb.cpp:341] Persisting action (14
> >> bytes)
> >> > to leveldb took 48.315363ms
> >> > I1207 22:27:35.138880 30316 replica.cpp:713] Persisted action at 0
> >> > I1207 22:27:35.140521 30330 replica.cpp:692] Replica received learned
> >> > notice for position 0 from @0.0.0.0:0
> >> > I1207 22:27:35.184093 30330 leveldb.cpp:341] Persisting action (16
> >> bytes)
> >> > to leveldb took 43.503748ms
> >> > I1207 22:27:35.184181 30330 replica.cpp:713] Persisted action at 0
> >> > I1207 22:27:35.184216 30330 replica.cpp:698] Replica learned NOP
> action
> >> at
> >> > position 0
> >> > I1207 22:27:35.185358 30320 log.cpp:675] Writer started with ending
> >> > position 0
> >> > I1207 22:27:35.186812 30320 leveldb.cpp:436] Reading position from
> >> leveldb
> >> > took 56774ns
> >> > I1207 22:27:35.188289 30320 registrar.cpp:340] Successfully fetched
> the
> >> > registry (0B) in 269.81888ms
> >> > I1207 22:27:35.188434 30320 registrar.cpp:439] Applied 1 operations in
> >> > 36841ns; attempting to update the 'registry'
> >> > I1207 22:27:35.189664 30320 log.cpp:683] Attempting to append 170
> bytes
> >> to
> >> > the log
> >> > I1207 22:27:35.189913 30320 coordinator.cpp:348] Coordinator
> attempting
> >> to
> >> > write APPEND action at position 1
> >> > I1207 22:27:35.191028 30324 replica.cpp:538] Replica received write
> >> > request for position 1 from (11284)@172.17.0.5:58099
> >> > I1207 22:27:35.234787 30324 leveldb.cpp:341] Persisting action (189
> >> bytes)
> >> > to leveldb took 43.693038ms
> >> > I1207 22:27:35.234856 30324 replica.cpp:713] Persisted action at 1
> >> > I1207 22:27:35.235985 30324 replica.cpp:692] Replica received learned
> >> > notice for position 1 from @0.0.0.0:0
> >> > I1207 22:27:35.283252 30324 leveldb.cpp:341] Persisting action (191
> >> bytes)
> >> > to leveldb took 47.210343ms
> >> > I1207 22:27:35.283327 30324 replica.cpp:713] Persisted action at 1
> >> > I1207 22:27:35.283360 30324 replica.cpp:698] Replica learned APPEND
> >> action
> >> > at position 1
> >> > I1207 22:27:35.285286 30320 registrar.cpp:484] Successfully updated
> the
> >> > 'registry' in 96768us
> >> > I1207 22:27:35.285794 30324 log.cpp:702] Attempting to truncate the
> log
> >> to
> >> > 1
> >> > I1207 22:27:35.286253 30320 registrar.cpp:370] Successfully recovered
> >> > registrar
> >> > I1207 22:27:35.286731 30330 master.cpp:1450] Recovered 0 slaves from
> the
> >> > Registry (131B) ; allowing 10mins for slaves to re-register
> >> > I1207 22:27:35.286906 30330 hierarchical.cpp:181] Skipping recovery of
> >> > hierarchical allocator: nothing to recover
> >> > I1207 22:27:35.287220 30318 coordinator.cpp:348] Coordinator
> attempting
> >> to
> >> > write TRUNCATE action at position 2
> >> > I1207 22:27:35.288635 30318 replica.cpp:538] Replica received write
> >> > request for position 2 from (11285)@172.17.0.5:58099
> >> > I1207 22:27:35.319257 30318 leveldb.cpp:341] Persisting action (16
> >> bytes)
> >> > to leveldb took 30.50184ms
> >> > I1207 22:27:35.319442 30318 replica.cpp:713] Persisted action at 2
> >> > I1207 22:27:35.320984 30318 replica.cpp:692] Replica received learned
> >> > notice for position 2 from @0.0.0.0:0
> >> > I1207 22:27:35.379997 30318 leveldb.cpp:341] Persisting action (18
> >> bytes)
> >> > to leveldb took 58.701806ms
> >> > I1207 22:27:35.380123 30318 leveldb.cpp:399] Deleting ~1 keys from
> >> leveldb
> >> > took 60205ns
> >> > I1207 22:27:35.380148 30318 replica.cpp:713] Persisted action at 2
> >> > I1207 22:27:35.380189 30318 replica.cpp:698] Replica learned TRUNCATE
> >> > action at position 2
> >> > I1207 22:27:35.390871 30296 scheduler.cpp:154] Version: 0.27.0
> >> > I1207 22:27:35.392123 30318 scheduler.cpp:236] New master detected at
> >> > master@172.17.0.5:58099
> >> > I1207 22:27:35.393574 30317 scheduler.cpp:298] Sending SUBSCRIBE call
> to
> >> > master@172.17.0.5:58099
> >> > I1207 22:27:35.396363 30325 process.cpp:3077] Handling HTTP event for
> >> > process 'master' with path: '/master/api/v1/scheduler'
> >> > I1207 22:27:35.396437 30325 http.cpp:336] HTTP POST for
> >> > /master/api/v1/scheduler from 172.17.0.5:56612
> >> > I1207 22:27:35.396668 30325 master.cpp:1902] Received subscription
> >> request
> >> > for HTTP framework 'default'
> >> > I1207 22:27:35.396745 30325 master.cpp:1679] Authorizing framework
> >> > principal 'test-principal' to receive offers for role '*'
> >> > I1207 22:27:35.397251 30318 master.cpp:1994] Subscribing framework
> >> > 'default' with checkpointing disabled and capabilities [  ]
> >> > I1207 22:27:35.398214 30318 master.hpp:1590] Sending heartbeat to
> >> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> >> > I1207 22:27:35.398437 30316 hierarchical.cpp:267] Added framework
> >> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> >> > I1207 22:27:35.398659 30316 hierarchical.cpp:1317] No resources
> >> available
> >> > to allocate!
> >> > I1207 22:27:35.398846 30316 hierarchical.cpp:1410] No inverse offers
> to
> >> > send out!
> >> > I1207 22:27:35.399011 30316 hierarchical.cpp:1063] Performed
> allocation
> >> > for 0 slaves in 399353ns
> >> > I1207 22:27:35.399127 30319 scheduler.cpp:457] Enqueuing event
> >> SUBSCRIBED
> >> > received from master@172.17.0.5:58099
> >> > I1207 22:27:35.400147 30324 scheduler.cpp:457] Enqueuing event
> HEARTBEAT
> >> > received from master@172.17.0.5:58099
> >> > I1207 22:27:35.400599 30329 master_maintenance_tests.cpp:177] Ignoring
> >> > HEARTBEAT event
> >> > I1207 22:27:35.400758 30319 scheduler.cpp:298] Sending REQUEST call to
> >> > master@172.17.0.5:58099
> >> > I1207 22:27:35.402441 30319 process.cpp:3077] Handling HTTP event for
> >> > process 'master' with path: '/master/api/v1/scheduler'
> >> > I1207 22:27:35.402497 30319 http.cpp:336] HTTP POST for
> >> > /master/api/v1/scheduler from 172.17.0.5:56613
> >> > I1207 22:27:35.402590 30319 master.cpp:2649] Processing REQUEST call
> for
> >> > framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000 (default)
> >> > I1207 22:27:35.402741 30328 hierarchical.cpp:562] Received resource
> >> > request from framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> >> > I1207 22:27:35.403267 30296 master.cpp:941] Master terminating
> >> > I1207 22:27:35.404278 30324 hierarchical.cpp:308] Removed framework
> >> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> >> > E1207 22:27:35.405990 30321 scheduler.cpp:431] End-Of-File received
> from
> >> > master. The master closed the event stream
> >> > [       OK ] ContentType/SchedulerTest.Request/0 (781 ms)
> >> > [ RUN      ] ContentType/SchedulerTest.Request/1
> >> > I1207 22:27:35.582654 30296 leveldb.cpp:174] Opened db in 146.89541ms
> >> > I1207 22:27:35.630651 30296 leveldb.cpp:181] Compacted db in
> 47.91914ms
> >> > I1207 22:27:35.630837 30296 leveldb.cpp:196] Created db iterator in
> >> 29048ns
> >> > I1207 22:27:35.630960 30296 leveldb.cpp:202] Seeked to beginning of db
> >> in
> >> > 3895ns
> >> > I1207 22:27:35.631057 30296 leveldb.cpp:271] Iterated through 0 keys
> in
> >> > the db in 392ns
> >> > I1207 22:27:35.631122 30296 replica.cpp:778] Replica recovered with
> log
> >> > positions 0 -> 0 with 1 holes and 0 unlearned
> >> > I1207 22:27:35.631953 30325 recover.cpp:447] Starting replica recovery
> >> > I1207 22:27:35.632469 30325 recover.cpp:473] Replica is in EMPTY
> status
> >> > I1207 22:27:35.634237 30326 master.cpp:368] Master
> >> > e2d75c25-5903-4d9f-a2b6-d5f587b67977 (04364a599488) started on
> >> > 172.17.0.5:58099
> >> > I1207 22:27:35.634269 30326 master.cpp:370] Flags at startup:
> --acls=""
> >> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> >> > --authenticate="false" --authenticate_slaves="true"
> >> > --authenticators="crammd5" --authorizers="local"
> >> > --credentials="/tmp/gS0Qp6/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/gS0Qp6/master" --zk_session_timeout="10secs"
> >> > I1207 22:27:35.634629 30326 master.cpp:417] Master allowing
> >> > unauthenticated frameworks to register
> >> > I1207 22:27:35.634650 30326 master.cpp:420] Master only allowing
> >> > authenticated slaves to register
> >> > I1207 22:27:35.634662 30326 credentials.hpp:35] Loading credentials
> for
> >> > authentication from '/tmp/gS0Qp6/credentials'
> >> > I1207 22:27:35.634977 30326 master.cpp:459] Using default 'crammd5'
> >> > authenticator
> >> > I1207 22:27:35.635113 30326 master.cpp:496] Authorization enabled
> >> > I1207 22:27:35.635432 30321 hierarchical.cpp:163] Initialized
> >> hierarchical
> >> > allocator process
> >> > I1207 22:27:35.635501 30321 whitelist_watcher.cpp:77] No whitelist
> given
> >> > I1207 22:27:35.636523 30323 replica.cpp:674] Replica in EMPTY status
> >> > received a broadcasted recover request from (11290)@172.17.0.5:58099
> >> > I1207 22:27:35.636797 30329 recover.cpp:193] Received a recover
> response
> >> > from a replica in EMPTY status
> >> > I1207 22:27:35.637473 30323 recover.cpp:564] Updating replica status
> to
> >> > STARTING
> >> > I1207 22:27:35.637545 30329 master.cpp:1640] The newly elected leader
> is
> >> > master@172.17.0.5:58099 with id e2d75c25-5903-4d9f-a2b6-d5f587b67977
> >> > I1207 22:27:35.638653 30329 master.cpp:1653] Elected as the leading
> >> master!
> >> > I1207 22:27:35.638838 30329 master.cpp:1398] Recovering from registrar
> >> > I1207 22:27:35.639302 30329 registrar.cpp:307] Recovering registrar
> >> > I1207 22:27:35.668242 30317 leveldb.cpp:304] Persisting metadata (8
> >> bytes)
> >> > to leveldb took 29.650563ms
> >> > I1207 22:27:35.668308 30317 replica.cpp:321] Persisted replica status
> to
> >> > STARTING
> >> > I1207 22:27:35.668627 30317 recover.cpp:473] Replica is in STARTING
> >> status
> >> > I1207 22:27:35.670066 30327 replica.cpp:674] Replica in STARTING
> status
> >> > received a broadcasted recover request from (11291)@172.17.0.5:58099
> >> > I1207 22:27:35.670300 30319 recover.cpp:193] Received a recover
> response
> >> > from a replica in STARTING status
> >> > I1207 22:27:35.670833 30319 recover.cpp:564] Updating replica status
> to
> >> > VOTING
> >> > I1207 22:27:35.709368 30329 leveldb.cpp:304] Persisting metadata (8
> >> bytes)
> >> > to leveldb took 38.378081ms
> >> > I1207 22:27:35.709432 30329 replica.cpp:321] Persisted replica status
> to
> >> > VOTING
> >> > I1207 22:27:35.709650 30329 recover.cpp:578] Successfully joined the
> >> Paxos
> >> > group
> >> > I1207 22:27:35.709835 30329 recover.cpp:462] Recover process
> terminated
> >> > I1207 22:27:35.710474 30329 log.cpp:659] Attempting to start the
> writer
> >> > I1207 22:27:35.712080 30329 replica.cpp:494] Replica received implicit
> >> > promise request from (11292)@172.17.0.5:58099 with proposal 1
> >> > I1207 22:27:35.760247 30329 leveldb.cpp:304] Persisting metadata (8
> >> bytes)
> >> > to leveldb took 48.032955ms
> >> > I1207 22:27:35.760326 30329 replica.cpp:343] Persisted promised to 1
> >> > I1207 22:27:35.761510 30329 coordinator.cpp:238] Coordinator
> attempting
> >> to
> >> > fill missing positions
> >> > I1207 22:27:35.763252 30317 replica.cpp:389] Replica received explicit
> >> > promise request from (11293)@172.17.0.5:58099 for position 0 with
> >> > proposal 2
> >> > I1207 22:27:35.801486 30317 leveldb.cpp:341] Persisting action (8
> bytes)
> >> > to leveldb took 38.168507ms
> >> > I1207 22:27:35.801558 30317 replica.cpp:713] Persisted action at 0
> >> > I1207 22:27:35.803572 30318 replica.cpp:538] Replica received write
> >> > request for position 0 from (11294)@172.17.0.5:58099
> >> > I1207 22:27:35.803683 30318 leveldb.cpp:436] Reading position from
> >> leveldb
> >> > took 51223ns
> >> > I1207 22:27:35.843870 30318 leveldb.cpp:341] Persisting action (14
> >> bytes)
> >> > to leveldb took 40.115416ms
> >> > I1207 22:27:35.843960 30318 replica.cpp:713] Persisted action at 0
> >> > I1207 22:27:35.844985 30318 replica.cpp:692] Replica received learned
> >> > notice for position 0 from @0.0.0.0:0
> >> > I1207 22:27:35.881268 30318 leveldb.cpp:341] Persisting action (16
> >> bytes)
> >> > to leveldb took 36.231637ms
> >> > I1207 22:27:35.881361 30318 replica.cpp:713] Persisted action at 0
> >> > I1207 22:27:35.881398 30318 replica.cpp:698] Replica learned NOP
> action
> >> at
> >> > position 0
> >> > I1207 22:27:35.882627 30318 log.cpp:675] Writer started with ending
> >> > position 0
> >> > I1207 22:27:35.884521 30324 leveldb.cpp:436] Reading position from
> >> leveldb
> >> > took 60563ns
> >> > I1207 22:27:35.892060 30318 registrar.cpp:340] Successfully fetched
> the
> >> > registry (0B) in 252.649216ms
> >> > I1207 22:27:35.892359 30318 registrar.cpp:439] Applied 1 operations in
> >> > 44025ns; attempting to update the 'registry'
> >> > I1207 22:27:35.893972 30318 log.cpp:683] Attempting to append 170
> bytes
> >> to
> >> > the log
> >> > I1207 22:27:35.894305 30317 coordinator.cpp:348] Coordinator
> attempting
> >> to
> >> > write APPEND action at position 1
> >> > I1207 22:27:35.895391 30319 replica.cpp:538] Replica received write
> >> > request for position 1 from (11295)@172.17.0.5:58099
> >> > I1207 22:27:35.925117 30319 leveldb.cpp:341] Persisting action (189
> >> bytes)
> >> > to leveldb took 29.558304ms
> >> > I1207 22:27:35.925226 30319 replica.cpp:713] Persisted action at 1
> >> > I1207 22:27:35.926812 30319 replica.cpp:692] Replica received learned
> >> > notice for position 1 from @0.0.0.0:0
> >> > I1207 22:27:35.950984 30319 leveldb.cpp:341] Persisting action (191
> >> bytes)
> >> > to leveldb took 24.114276ms
> >> > I1207 22:27:35.951082 30319 replica.cpp:713] Persisted action at 1
> >> > I1207 22:27:35.951123 30319 replica.cpp:698] Replica learned APPEND
> >> action
> >> > at position 1
> >> > I1207 22:27:35.953444 30316 registrar.cpp:484] Successfully updated
> the
> >> > 'registry' in 60.874752ms
> >> > I1207 22:27:35.953763 30317 log.cpp:702] Attempting to truncate the
> log
> >> to
> >> > 1
> >> > I1207 22:27:35.954022 30317 coordinator.cpp:348] Coordinator
> attempting
> >> to
> >> > write TRUNCATE action at position 2
> >> > I1207 22:27:35.954515 30316 registrar.cpp:370] Successfully recovered
> >> > registrar
> >> > I1207 22:27:35.955001 30316 master.cpp:1450] Recovered 0 slaves from
> the
> >> > Registry (131B) ; allowing 10mins for slaves to re-register
> >> > I1207 22:27:35.955236 30316 hierarchical.cpp:181] Skipping recovery of
> >> > hierarchical allocator: nothing to recover
> >> > I1207 22:27:35.956590 30325 replica.cpp:538] Replica received write
> >> > request for position 2 from (11296)@172.17.0.5:58099
> >> > I1207 22:27:35.983829 30325 leveldb.cpp:341] Persisting action (16
> >> bytes)
> >> > to leveldb took 27.148875ms
> >> > I1207 22:27:35.983927 30325 replica.cpp:713] Persisted action at 2
> >> > I1207 22:27:35.985357 30325 replica.cpp:692] Replica received learned
> >> > notice for position 2 from @0.0.0.0:0
> >> > I1207 22:27:36.008911 30325 leveldb.cpp:341] Persisting action (18
> >> bytes)
> >> > to leveldb took 23.494848ms
> >> > I1207 22:27:36.009063 30325 leveldb.cpp:399] Deleting ~1 keys from
> >> leveldb
> >> > took 74929ns
> >> > I1207 22:27:36.009090 30325 replica.cpp:713] Persisted action at 2
> >> > I1207 22:27:36.009124 30325 replica.cpp:698] Replica learned TRUNCATE
> >> > action at position 2
> >> > I1207 22:27:36.018368 30296 scheduler.cpp:154] Version: 0.27.0
> >> > I1207 22:27:36.019350 30325 scheduler.cpp:236] New master detected at
> >> > master@172.17.0.5:58099
> >> > I1207 22:27:36.020905 30325 scheduler.cpp:298] Sending SUBSCRIBE call
> to
> >> > master@172.17.0.5:58099
> >> > I1207 22:27:36.025128 30321 process.cpp:3077] Handling HTTP event for
> >> > process 'master' with path: '/master/api/v1/scheduler'
> >> > I1207 22:27:36.025220 30321 http.cpp:336] HTTP POST for
> >> > /master/api/v1/scheduler from 172.17.0.5:56618
> >> > I1207 22:27:36.025624 30321 master.cpp:1902] Received subscription
> >> request
> >> > for HTTP framework 'default'
> >> > I1207 22:27:36.025699 30321 master.cpp:1679] Authorizing framework
> >> > principal 'test-principal' to receive offers for role '*'
> >> > I1207 22:27:36.026386 30321 master.cpp:1994] Subscribing framework
> >> > 'default' with checkpointing disabled and capabilities [  ]
> >> > I1207 22:27:36.026973 30321 hierarchical.cpp:267] Added framework
> >> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> >> > I1207 22:27:36.027042 30321 hierarchical.cpp:1317] No resources
> >> available
> >> > to allocate!
> >> > I1207 22:27:36.027075 30321 hierarchical.cpp:1410] No inverse offers
> to
> >> > send out!
> >> > I1207 22:27:36.027097 30321 hierarchical.cpp:1063] Performed
> allocation
> >> > for 0 slaves in 99824ns
> >> > I1207 22:27:36.027209 30322 master.hpp:1590] Sending heartbeat to
> >> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> >> > I1207 22:27:36.031739 30327 scheduler.cpp:457] Enqueuing event
> >> SUBSCRIBED
> >> > received from master@172.17.0.5:58099
> >> > I1207 22:27:36.032542 30320 scheduler.cpp:457] Enqueuing event
> HEARTBEAT
> >> > received from master@172.17.0.5:58099
> >> > I1207 22:27:36.033051 30320 master_maintenance_tests.cpp:177] Ignoring
> >> > HEARTBEAT event
> >> > I1207 22:27:36.033721 30328 scheduler.cpp:298] Sending REQUEST call to
> >> > master@172.17.0.5:58099
> >> > I1207 22:27:36.037406 30315 process.cpp:3077] Handling HTTP event for
> >> > process 'master' with path: '/master/api/v1/scheduler'
> >> > I1207 22:27:36.037473 30315 http.cpp:336] HTTP POST for
> >> > /master/api/v1/scheduler from 172.17.0.5:56619
> >> > I1207 22:27:36.037760 30315 master.cpp:2649] Processing REQUEST call
> for
> >> > framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000 (default)
> >> > I1207 22:27:36.038022 30315 hierarchical.cpp:562] Received resource
> >> > request from framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> >> > I1207 22:27:36.038554 30296 master.cpp:941] Master terminating
> >> > I1207 22:27:36.038806 30324 hierarchical.cpp:308] Removed framework
> >> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> >> > E1207 22:27:36.043074 30317 scheduler.cpp:431] End-Of-File received
> from
> >> > master. The master closed the event stream
> >> > [       OK ] ContentType/SchedulerTest.Request/1 (617 ms)
> >> > [----------] 22 tests from ContentType/SchedulerTest (18620 ms total)
> >> >
> >> > [----------] Global test environment tear-down
> >> > [==========] 879 tests from 115 test cases ran. (1039939 ms total)
> >> > [  PASSED  ] 832 tests.
> >> > [  FAILED  ] 47 tests, listed below:
> >> > [  FAILED  ] MonitorIntegrationTest.RunningExecutor
> >> > [  FAILED  ]
> >> ReservationEndpointsTest.ReserveAvailableAndOfferedResources
> >> > [  FAILED  ]
> >> ReservationEndpointsTest.UnreserveAvailableAndOfferedResources
> >> > [  FAILED  ] ReservationTest.ACLMultipleOperations
> >> > [  FAILED  ] SlaveRecoveryTest/0.RecoverSlaveState, where TypeParam =
> >> > mesos::internal::slave::MesosContainerizer
> >> > [  FAILED  ] SlaveRecoveryTest/0.MasterFailover, where TypeParam =
> >> > mesos::internal::slave::MesosContainerizer
> >> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch
> >> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
> >> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
> >> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch
> >> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge
> >> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> >> > [  FAILED  ] InMemoryStateTest.Names
> >> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndFetch
> >> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch
> >> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreFailAndFetch
> >> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndFetch
> >> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndExpunge
> >> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> >> > [  FAILED  ] LevelDBStateTest.Names
> >> > [  FAILED  ] LogStateTest.FetchAndStoreAndFetch
> >> > [  FAILED  ] LogStateTest.FetchAndStoreAndStoreAndFetch
> >> > [  FAILED  ] LogStateTest.FetchAndStoreAndStoreFailAndFetch
> >> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndFetch
> >> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndExpunge
> >> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> >> > [  FAILED  ] LogStateTest.Names
> >> > [  FAILED  ] LogStateTest.Timeout
> >> > [  FAILED  ] LogStateTest.Diff
> >> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndFetch
> >> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreAndFetch
> >> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreFailAndFetch
> >> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndFetch
> >> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndExpunge
> >> > [  FAILED  ]
> ZooKeeperStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> >> > [  FAILED  ] ZooKeeperStateTest.Names
> >> > [  FAILED  ] Strict/RegistrarTest.Recover/0, where GetParam() = false
> >> > [  FAILED  ] Strict/RegistrarTest.Recover/1, where GetParam() = true
> >> > [  FAILED  ] Strict/RegistrarTest.Admit/0, where GetParam() = false
> >> > [  FAILED  ] Strict/RegistrarTest.Admit/1, where GetParam() = true
> >> > [  FAILED  ] Strict/RegistrarTest.Readmit/0, where GetParam() = false
> >> > [  FAILED  ] Strict/RegistrarTest.Readmit/1, where GetParam() = true
> >> > [  FAILED  ] Strict/RegistrarTest.Remove/0, where GetParam() = false
> >> > [  FAILED  ] Strict/RegistrarTest.Remove/1, where GetParam() = true
> >> > [  FAILED  ] Strict/RegistrarTest.Bootstrap/0, where GetParam() =
> false
> >> > [  FAILED  ] Strict/RegistrarTest.Abort/0, where GetParam() = false
> >> > [  FAILED  ] Strict/RegistrarTest.Abort/1, where GetParam() = true
> >> >
> >> > 47 FAILED TESTS
> >> >   YOU HAVE 7 DISABLED TESTS
> >> >
> >> > make[4]: *** [check-local] Error 1
> >> > make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> >> > make[3]: *** [check-am] Error 2
> >> > make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> >> > make[2]: *** [check] Error 2
> >> > make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> >> > make[1]: *** [check-recursive] Error 1
> >> > make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> >> > make: *** [distcheck] Error 1
> >> > + docker rmi mesos-1449523283-3961
> >> > Untagged: mesos-1449523283-3961:latest
> >> > Deleted:
> >> bf1d0236000512633b0d52659e90345e0482c895b74aaa41d8cf335829ffa5b9
> >> > Deleted:
> >> 8c6d8a401155baa8697fd8ed4b18f21fd59f80ac55761caaf70e6203e5eaa6e5
> >> > Deleted:
> >> d8d6a742324ebdb91557481cd1f4bfed2fd9c3d8169eace16e7650f4fd9843ae
> >> > Deleted:
> >> 6e36632fa53927dc9930d6c6a2b34861f5ab01c19c846f16bdbbe2e229d533b5
> >> > Deleted:
> >> e56d47bb8d1394f6bbb5cff2a576dcecf13e0b60d2639fdfb6189e48eebbb8f9
> >> > Deleted:
> >> af62f49a1ef072869b9f318d3aa4be80391af33c0d11abed74069bf03258a519
> >> > Deleted:
> >> 40ea831bd69725cb46f301728c206870eb69012f228b7601679d79a406433e31
> >> > Deleted:
> >> 7f29cce7839475e6e6f480df46a3b809b7caad744d4b261f62115b9ef5603ede
> >> > Deleted:
> >> d7f8b3b948c9407f5edeadb252119d784d4fa4925e0aff60514967290afd77f5
> >> > Deleted:
> >> 1a91b42fcc552dd54f94e024600f3fef75879891ef70d4a3d0d88c12d2fcd1ce
> >> > Deleted:
> >> b377e8a2e554faa238159b0713a9a55ff6de84b2d7ba8871548ee57064621db3
> >> > Deleted:
> >> 5ba54d6b1c4209b886733b3fd4892473b88205721ea4d607c379e039638129e7
> >> > Deleted:
> >> 293adb3eee0890efd7c6deb8ee0b6328de0b3419da9ae8bf0b512c79f7134fda
> >> >
> >> > Error handling URL
> >> > https://reviews.apache.org/api/review-requests/40759/reviews/:
> INTERNAL
> >> > SERVER ERROR
> >> > git clean -fd
> >> > git reset --hard 517e84669790f833f6cf0e7911968a3591c93419
> >> >
> >> > Build step 'Execute shell' marked build as failure
> >> >
> >>
> >
> >
>

Re: Build failed in Jenkins: mesos-reviewbot #10069

Posted by Joris Van Remoortere <jo...@mesosphere.io>.

This is the CI running on a patch on reviewboard, not on the commit chain.
This is why it was not immediately obvious.
The review is https://reviews.apache.org/r/40759/
I'm removing this from the mesos group for now to avoid triggering this
error further.

—
*Joris Van Remoortere*
Mesosphere

On Mon, Dec 7, 2015 at 5:54 PM, Joris Van Remoortere <jo...@mesosphere.io>
wrote:

> @vinod Do you know which commits introduced these failures? I tried
> looking through my history but the first e-mail with these failures I see
> had no new commits?
>
> —
> *Joris Van Remoortere*
> Mesosphere
>
> On Mon, Dec 7, 2015 at 5:51 PM, Vinod Kone <vi...@apache.org> wrote:
>
>> is someone looking into these failures?
>>
>> [ RUN      ] InMemoryStateTest.FetchAndStoreAndFetch
>> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
>> IsInitialized(): Can't serialize message of type
>> "mesos.internal.Registry.Slaves" because it is missing required
>> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
>> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
>> unknown file: Failure
>> C++ exception with description "CHECK failed: IsInitialized(): Can't
>> serialize message of type "mesos.internal.Registry.Slaves" because it
>> is missing required fields: slaves[0].info.launcher_dir,
>> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
>> slaves[0].info.executor_rootfs" thrown in the test body.
>> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch (2 ms)
>> [ RUN      ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
>> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
>> IsInitialized(): Can't serialize message of type
>> "mesos.internal.Registry.Slaves" because it is missing required
>> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
>> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
>> unknown file: Failure
>> C++ exception with description "CHECK failed: IsInitialized(): Can't
>> serialize message of type "mesos.internal.Registry.Slaves" because it
>> is missing required fields: slaves[0].info.launcher_dir,
>> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
>> slaves[0].info.executor_rootfs" thrown in the test body.
>> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch (2 ms)
>> [ RUN      ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
>> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
>> IsInitialized(): Can't serialize message of type
>> "mesos.internal.Registry.Slaves" because it is missing required
>> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
>> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
>> unknown file: Failure
>> C++ exception with description "CHECK failed: IsInitialized(): Can't
>> serialize message of type "mesos.internal.Registry.Slaves" because it
>> is missing required fields: slaves[0].info.launcher_dir,
>> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
>> slaves[0].info.executor_rootfs" thrown in the test body.
>>
>>
>>
>> On Mon, Dec 7, 2015 at 2:28 PM, Apache Jenkins Server <
>> jenkins@builds.apache.org> wrote:
>>
>> > See <https://builds.apache.org/job/mesos-reviewbot/10069/>
>> >
>> > ------------------------------------------
>> > [...truncated 159973 lines...]
>> > I1207 22:27:35.090376 30316 leveldb.cpp:436] Reading position from
>> leveldb
>> > took 235515ns
>> > I1207 22:27:35.138778 30316 leveldb.cpp:341] Persisting action (14
>> bytes)
>> > to leveldb took 48.315363ms
>> > I1207 22:27:35.138880 30316 replica.cpp:713] Persisted action at 0
>> > I1207 22:27:35.140521 30330 replica.cpp:692] Replica received learned
>> > notice for position 0 from @0.0.0.0:0
>> > I1207 22:27:35.184093 30330 leveldb.cpp:341] Persisting action (16
>> bytes)
>> > to leveldb took 43.503748ms
>> > I1207 22:27:35.184181 30330 replica.cpp:713] Persisted action at 0
>> > I1207 22:27:35.184216 30330 replica.cpp:698] Replica learned NOP action
>> at
>> > position 0
>> > I1207 22:27:35.185358 30320 log.cpp:675] Writer started with ending
>> > position 0
>> > I1207 22:27:35.186812 30320 leveldb.cpp:436] Reading position from
>> leveldb
>> > took 56774ns
>> > I1207 22:27:35.188289 30320 registrar.cpp:340] Successfully fetched the
>> > registry (0B) in 269.81888ms
>> > I1207 22:27:35.188434 30320 registrar.cpp:439] Applied 1 operations in
>> > 36841ns; attempting to update the 'registry'
>> > I1207 22:27:35.189664 30320 log.cpp:683] Attempting to append 170 bytes
>> to
>> > the log
>> > I1207 22:27:35.189913 30320 coordinator.cpp:348] Coordinator attempting
>> to
>> > write APPEND action at position 1
>> > I1207 22:27:35.191028 30324 replica.cpp:538] Replica received write
>> > request for position 1 from (11284)@172.17.0.5:58099
>> > I1207 22:27:35.234787 30324 leveldb.cpp:341] Persisting action (189
>> bytes)
>> > to leveldb took 43.693038ms
>> > I1207 22:27:35.234856 30324 replica.cpp:713] Persisted action at 1
>> > I1207 22:27:35.235985 30324 replica.cpp:692] Replica received learned
>> > notice for position 1 from @0.0.0.0:0
>> > I1207 22:27:35.283252 30324 leveldb.cpp:341] Persisting action (191
>> bytes)
>> > to leveldb took 47.210343ms
>> > I1207 22:27:35.283327 30324 replica.cpp:713] Persisted action at 1
>> > I1207 22:27:35.283360 30324 replica.cpp:698] Replica learned APPEND
>> action
>> > at position 1
>> > I1207 22:27:35.285286 30320 registrar.cpp:484] Successfully updated the
>> > 'registry' in 96768us
>> > I1207 22:27:35.285794 30324 log.cpp:702] Attempting to truncate the log
>> to
>> > 1
>> > I1207 22:27:35.286253 30320 registrar.cpp:370] Successfully recovered
>> > registrar
>> > I1207 22:27:35.286731 30330 master.cpp:1450] Recovered 0 slaves from the
>> > Registry (131B) ; allowing 10mins for slaves to re-register
>> > I1207 22:27:35.286906 30330 hierarchical.cpp:181] Skipping recovery of
>> > hierarchical allocator: nothing to recover
>> > I1207 22:27:35.287220 30318 coordinator.cpp:348] Coordinator attempting
>> to
>> > write TRUNCATE action at position 2
>> > I1207 22:27:35.288635 30318 replica.cpp:538] Replica received write
>> > request for position 2 from (11285)@172.17.0.5:58099
>> > I1207 22:27:35.319257 30318 leveldb.cpp:341] Persisting action (16
>> bytes)
>> > to leveldb took 30.50184ms
>> > I1207 22:27:35.319442 30318 replica.cpp:713] Persisted action at 2
>> > I1207 22:27:35.320984 30318 replica.cpp:692] Replica received learned
>> > notice for position 2 from @0.0.0.0:0
>> > I1207 22:27:35.379997 30318 leveldb.cpp:341] Persisting action (18
>> bytes)
>> > to leveldb took 58.701806ms
>> > I1207 22:27:35.380123 30318 leveldb.cpp:399] Deleting ~1 keys from
>> leveldb
>> > took 60205ns
>> > I1207 22:27:35.380148 30318 replica.cpp:713] Persisted action at 2
>> > I1207 22:27:35.380189 30318 replica.cpp:698] Replica learned TRUNCATE
>> > action at position 2
>> > I1207 22:27:35.390871 30296 scheduler.cpp:154] Version: 0.27.0
>> > I1207 22:27:35.392123 30318 scheduler.cpp:236] New master detected at
>> > master@172.17.0.5:58099
>> > I1207 22:27:35.393574 30317 scheduler.cpp:298] Sending SUBSCRIBE call to
>> > master@172.17.0.5:58099
>> > I1207 22:27:35.396363 30325 process.cpp:3077] Handling HTTP event for
>> > process 'master' with path: '/master/api/v1/scheduler'
>> > I1207 22:27:35.396437 30325 http.cpp:336] HTTP POST for
>> > /master/api/v1/scheduler from 172.17.0.5:56612
>> > I1207 22:27:35.396668 30325 master.cpp:1902] Received subscription
>> request
>> > for HTTP framework 'default'
>> > I1207 22:27:35.396745 30325 master.cpp:1679] Authorizing framework
>> > principal 'test-principal' to receive offers for role '*'
>> > I1207 22:27:35.397251 30318 master.cpp:1994] Subscribing framework
>> > 'default' with checkpointing disabled and capabilities [  ]
>> > I1207 22:27:35.398214 30318 master.hpp:1590] Sending heartbeat to
>> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
>> > I1207 22:27:35.398437 30316 hierarchical.cpp:267] Added framework
>> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
>> > I1207 22:27:35.398659 30316 hierarchical.cpp:1317] No resources
>> available
>> > to allocate!
>> > I1207 22:27:35.398846 30316 hierarchical.cpp:1410] No inverse offers to
>> > send out!
>> > I1207 22:27:35.399011 30316 hierarchical.cpp:1063] Performed allocation
>> > for 0 slaves in 399353ns
>> > I1207 22:27:35.399127 30319 scheduler.cpp:457] Enqueuing event
>> SUBSCRIBED
>> > received from master@172.17.0.5:58099
>> > I1207 22:27:35.400147 30324 scheduler.cpp:457] Enqueuing event HEARTBEAT
>> > received from master@172.17.0.5:58099
>> > I1207 22:27:35.400599 30329 master_maintenance_tests.cpp:177] Ignoring
>> > HEARTBEAT event
>> > I1207 22:27:35.400758 30319 scheduler.cpp:298] Sending REQUEST call to
>> > master@172.17.0.5:58099
>> > I1207 22:27:35.402441 30319 process.cpp:3077] Handling HTTP event for
>> > process 'master' with path: '/master/api/v1/scheduler'
>> > I1207 22:27:35.402497 30319 http.cpp:336] HTTP POST for
>> > /master/api/v1/scheduler from 172.17.0.5:56613
>> > I1207 22:27:35.402590 30319 master.cpp:2649] Processing REQUEST call for
>> > framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000 (default)
>> > I1207 22:27:35.402741 30328 hierarchical.cpp:562] Received resource
>> > request from framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
>> > I1207 22:27:35.403267 30296 master.cpp:941] Master terminating
>> > I1207 22:27:35.404278 30324 hierarchical.cpp:308] Removed framework
>> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
>> > E1207 22:27:35.405990 30321 scheduler.cpp:431] End-Of-File received from
>> > master. The master closed the event stream
>> > [       OK ] ContentType/SchedulerTest.Request/0 (781 ms)
>> > [ RUN      ] ContentType/SchedulerTest.Request/1
>> > I1207 22:27:35.582654 30296 leveldb.cpp:174] Opened db in 146.89541ms
>> > I1207 22:27:35.630651 30296 leveldb.cpp:181] Compacted db in 47.91914ms
>> > I1207 22:27:35.630837 30296 leveldb.cpp:196] Created db iterator in
>> 29048ns
>> > I1207 22:27:35.630960 30296 leveldb.cpp:202] Seeked to beginning of db
>> in
>> > 3895ns
>> > I1207 22:27:35.631057 30296 leveldb.cpp:271] Iterated through 0 keys in
>> > the db in 392ns
>> > I1207 22:27:35.631122 30296 replica.cpp:778] Replica recovered with log
>> > positions 0 -> 0 with 1 holes and 0 unlearned
>> > I1207 22:27:35.631953 30325 recover.cpp:447] Starting replica recovery
>> > I1207 22:27:35.632469 30325 recover.cpp:473] Replica is in EMPTY status
>> > I1207 22:27:35.634237 30326 master.cpp:368] Master
>> > e2d75c25-5903-4d9f-a2b6-d5f587b67977 (04364a599488) started on
>> > 172.17.0.5:58099
>> > I1207 22:27:35.634269 30326 master.cpp:370] Flags at startup: --acls=""
>> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
>> > --authenticate="false" --authenticate_slaves="true"
>> > --authenticators="crammd5" --authorizers="local"
>> > --credentials="/tmp/gS0Qp6/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/gS0Qp6/master" --zk_session_timeout="10secs"
>> > I1207 22:27:35.634629 30326 master.cpp:417] Master allowing
>> > unauthenticated frameworks to register
>> > I1207 22:27:35.634650 30326 master.cpp:420] Master only allowing
>> > authenticated slaves to register
>> > I1207 22:27:35.634662 30326 credentials.hpp:35] Loading credentials for
>> > authentication from '/tmp/gS0Qp6/credentials'
>> > I1207 22:27:35.634977 30326 master.cpp:459] Using default 'crammd5'
>> > authenticator
>> > I1207 22:27:35.635113 30326 master.cpp:496] Authorization enabled
>> > I1207 22:27:35.635432 30321 hierarchical.cpp:163] Initialized
>> hierarchical
>> > allocator process
>> > I1207 22:27:35.635501 30321 whitelist_watcher.cpp:77] No whitelist given
>> > I1207 22:27:35.636523 30323 replica.cpp:674] Replica in EMPTY status
>> > received a broadcasted recover request from (11290)@172.17.0.5:58099
>> > I1207 22:27:35.636797 30329 recover.cpp:193] Received a recover response
>> > from a replica in EMPTY status
>> > I1207 22:27:35.637473 30323 recover.cpp:564] Updating replica status to
>> > STARTING
>> > I1207 22:27:35.637545 30329 master.cpp:1640] The newly elected leader is
>> > master@172.17.0.5:58099 with id e2d75c25-5903-4d9f-a2b6-d5f587b67977
>> > I1207 22:27:35.638653 30329 master.cpp:1653] Elected as the leading
>> master!
>> > I1207 22:27:35.638838 30329 master.cpp:1398] Recovering from registrar
>> > I1207 22:27:35.639302 30329 registrar.cpp:307] Recovering registrar
>> > I1207 22:27:35.668242 30317 leveldb.cpp:304] Persisting metadata (8
>> bytes)
>> > to leveldb took 29.650563ms
>> > I1207 22:27:35.668308 30317 replica.cpp:321] Persisted replica status to
>> > STARTING
>> > I1207 22:27:35.668627 30317 recover.cpp:473] Replica is in STARTING
>> status
>> > I1207 22:27:35.670066 30327 replica.cpp:674] Replica in STARTING status
>> > received a broadcasted recover request from (11291)@172.17.0.5:58099
>> > I1207 22:27:35.670300 30319 recover.cpp:193] Received a recover response
>> > from a replica in STARTING status
>> > I1207 22:27:35.670833 30319 recover.cpp:564] Updating replica status to
>> > VOTING
>> > I1207 22:27:35.709368 30329 leveldb.cpp:304] Persisting metadata (8
>> bytes)
>> > to leveldb took 38.378081ms
>> > I1207 22:27:35.709432 30329 replica.cpp:321] Persisted replica status to
>> > VOTING
>> > I1207 22:27:35.709650 30329 recover.cpp:578] Successfully joined the
>> Paxos
>> > group
>> > I1207 22:27:35.709835 30329 recover.cpp:462] Recover process terminated
>> > I1207 22:27:35.710474 30329 log.cpp:659] Attempting to start the writer
>> > I1207 22:27:35.712080 30329 replica.cpp:494] Replica received implicit
>> > promise request from (11292)@172.17.0.5:58099 with proposal 1
>> > I1207 22:27:35.760247 30329 leveldb.cpp:304] Persisting metadata (8
>> bytes)
>> > to leveldb took 48.032955ms
>> > I1207 22:27:35.760326 30329 replica.cpp:343] Persisted promised to 1
>> > I1207 22:27:35.761510 30329 coordinator.cpp:238] Coordinator attempting
>> to
>> > fill missing positions
>> > I1207 22:27:35.763252 30317 replica.cpp:389] Replica received explicit
>> > promise request from (11293)@172.17.0.5:58099 for position 0 with
>> > proposal 2
>> > I1207 22:27:35.801486 30317 leveldb.cpp:341] Persisting action (8 bytes)
>> > to leveldb took 38.168507ms
>> > I1207 22:27:35.801558 30317 replica.cpp:713] Persisted action at 0
>> > I1207 22:27:35.803572 30318 replica.cpp:538] Replica received write
>> > request for position 0 from (11294)@172.17.0.5:58099
>> > I1207 22:27:35.803683 30318 leveldb.cpp:436] Reading position from
>> leveldb
>> > took 51223ns
>> > I1207 22:27:35.843870 30318 leveldb.cpp:341] Persisting action (14
>> bytes)
>> > to leveldb took 40.115416ms
>> > I1207 22:27:35.843960 30318 replica.cpp:713] Persisted action at 0
>> > I1207 22:27:35.844985 30318 replica.cpp:692] Replica received learned
>> > notice for position 0 from @0.0.0.0:0
>> > I1207 22:27:35.881268 30318 leveldb.cpp:341] Persisting action (16
>> bytes)
>> > to leveldb took 36.231637ms
>> > I1207 22:27:35.881361 30318 replica.cpp:713] Persisted action at 0
>> > I1207 22:27:35.881398 30318 replica.cpp:698] Replica learned NOP action
>> at
>> > position 0
>> > I1207 22:27:35.882627 30318 log.cpp:675] Writer started with ending
>> > position 0
>> > I1207 22:27:35.884521 30324 leveldb.cpp:436] Reading position from
>> leveldb
>> > took 60563ns
>> > I1207 22:27:35.892060 30318 registrar.cpp:340] Successfully fetched the
>> > registry (0B) in 252.649216ms
>> > I1207 22:27:35.892359 30318 registrar.cpp:439] Applied 1 operations in
>> > 44025ns; attempting to update the 'registry'
>> > I1207 22:27:35.893972 30318 log.cpp:683] Attempting to append 170 bytes
>> to
>> > the log
>> > I1207 22:27:35.894305 30317 coordinator.cpp:348] Coordinator attempting
>> to
>> > write APPEND action at position 1
>> > I1207 22:27:35.895391 30319 replica.cpp:538] Replica received write
>> > request for position 1 from (11295)@172.17.0.5:58099
>> > I1207 22:27:35.925117 30319 leveldb.cpp:341] Persisting action (189
>> bytes)
>> > to leveldb took 29.558304ms
>> > I1207 22:27:35.925226 30319 replica.cpp:713] Persisted action at 1
>> > I1207 22:27:35.926812 30319 replica.cpp:692] Replica received learned
>> > notice for position 1 from @0.0.0.0:0
>> > I1207 22:27:35.950984 30319 leveldb.cpp:341] Persisting action (191
>> bytes)
>> > to leveldb took 24.114276ms
>> > I1207 22:27:35.951082 30319 replica.cpp:713] Persisted action at 1
>> > I1207 22:27:35.951123 30319 replica.cpp:698] Replica learned APPEND
>> action
>> > at position 1
>> > I1207 22:27:35.953444 30316 registrar.cpp:484] Successfully updated the
>> > 'registry' in 60.874752ms
>> > I1207 22:27:35.953763 30317 log.cpp:702] Attempting to truncate the log
>> to
>> > 1
>> > I1207 22:27:35.954022 30317 coordinator.cpp:348] Coordinator attempting
>> to
>> > write TRUNCATE action at position 2
>> > I1207 22:27:35.954515 30316 registrar.cpp:370] Successfully recovered
>> > registrar
>> > I1207 22:27:35.955001 30316 master.cpp:1450] Recovered 0 slaves from the
>> > Registry (131B) ; allowing 10mins for slaves to re-register
>> > I1207 22:27:35.955236 30316 hierarchical.cpp:181] Skipping recovery of
>> > hierarchical allocator: nothing to recover
>> > I1207 22:27:35.956590 30325 replica.cpp:538] Replica received write
>> > request for position 2 from (11296)@172.17.0.5:58099
>> > I1207 22:27:35.983829 30325 leveldb.cpp:341] Persisting action (16
>> bytes)
>> > to leveldb took 27.148875ms
>> > I1207 22:27:35.983927 30325 replica.cpp:713] Persisted action at 2
>> > I1207 22:27:35.985357 30325 replica.cpp:692] Replica received learned
>> > notice for position 2 from @0.0.0.0:0
>> > I1207 22:27:36.008911 30325 leveldb.cpp:341] Persisting action (18
>> bytes)
>> > to leveldb took 23.494848ms
>> > I1207 22:27:36.009063 30325 leveldb.cpp:399] Deleting ~1 keys from
>> leveldb
>> > took 74929ns
>> > I1207 22:27:36.009090 30325 replica.cpp:713] Persisted action at 2
>> > I1207 22:27:36.009124 30325 replica.cpp:698] Replica learned TRUNCATE
>> > action at position 2
>> > I1207 22:27:36.018368 30296 scheduler.cpp:154] Version: 0.27.0
>> > I1207 22:27:36.019350 30325 scheduler.cpp:236] New master detected at
>> > master@172.17.0.5:58099
>> > I1207 22:27:36.020905 30325 scheduler.cpp:298] Sending SUBSCRIBE call to
>> > master@172.17.0.5:58099
>> > I1207 22:27:36.025128 30321 process.cpp:3077] Handling HTTP event for
>> > process 'master' with path: '/master/api/v1/scheduler'
>> > I1207 22:27:36.025220 30321 http.cpp:336] HTTP POST for
>> > /master/api/v1/scheduler from 172.17.0.5:56618
>> > I1207 22:27:36.025624 30321 master.cpp:1902] Received subscription
>> request
>> > for HTTP framework 'default'
>> > I1207 22:27:36.025699 30321 master.cpp:1679] Authorizing framework
>> > principal 'test-principal' to receive offers for role '*'
>> > I1207 22:27:36.026386 30321 master.cpp:1994] Subscribing framework
>> > 'default' with checkpointing disabled and capabilities [  ]
>> > I1207 22:27:36.026973 30321 hierarchical.cpp:267] Added framework
>> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
>> > I1207 22:27:36.027042 30321 hierarchical.cpp:1317] No resources
>> available
>> > to allocate!
>> > I1207 22:27:36.027075 30321 hierarchical.cpp:1410] No inverse offers to
>> > send out!
>> > I1207 22:27:36.027097 30321 hierarchical.cpp:1063] Performed allocation
>> > for 0 slaves in 99824ns
>> > I1207 22:27:36.027209 30322 master.hpp:1590] Sending heartbeat to
>> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
>> > I1207 22:27:36.031739 30327 scheduler.cpp:457] Enqueuing event
>> SUBSCRIBED
>> > received from master@172.17.0.5:58099
>> > I1207 22:27:36.032542 30320 scheduler.cpp:457] Enqueuing event HEARTBEAT
>> > received from master@172.17.0.5:58099
>> > I1207 22:27:36.033051 30320 master_maintenance_tests.cpp:177] Ignoring
>> > HEARTBEAT event
>> > I1207 22:27:36.033721 30328 scheduler.cpp:298] Sending REQUEST call to
>> > master@172.17.0.5:58099
>> > I1207 22:27:36.037406 30315 process.cpp:3077] Handling HTTP event for
>> > process 'master' with path: '/master/api/v1/scheduler'
>> > I1207 22:27:36.037473 30315 http.cpp:336] HTTP POST for
>> > /master/api/v1/scheduler from 172.17.0.5:56619
>> > I1207 22:27:36.037760 30315 master.cpp:2649] Processing REQUEST call for
>> > framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000 (default)
>> > I1207 22:27:36.038022 30315 hierarchical.cpp:562] Received resource
>> > request from framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
>> > I1207 22:27:36.038554 30296 master.cpp:941] Master terminating
>> > I1207 22:27:36.038806 30324 hierarchical.cpp:308] Removed framework
>> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
>> > E1207 22:27:36.043074 30317 scheduler.cpp:431] End-Of-File received from
>> > master. The master closed the event stream
>> > [       OK ] ContentType/SchedulerTest.Request/1 (617 ms)
>> > [----------] 22 tests from ContentType/SchedulerTest (18620 ms total)
>> >
>> > [----------] Global test environment tear-down
>> > [==========] 879 tests from 115 test cases ran. (1039939 ms total)
>> > [  PASSED  ] 832 tests.
>> > [  FAILED  ] 47 tests, listed below:
>> > [  FAILED  ] MonitorIntegrationTest.RunningExecutor
>> > [  FAILED  ]
>> ReservationEndpointsTest.ReserveAvailableAndOfferedResources
>> > [  FAILED  ]
>> ReservationEndpointsTest.UnreserveAvailableAndOfferedResources
>> > [  FAILED  ] ReservationTest.ACLMultipleOperations
>> > [  FAILED  ] SlaveRecoveryTest/0.RecoverSlaveState, where TypeParam =
>> > mesos::internal::slave::MesosContainerizer
>> > [  FAILED  ] SlaveRecoveryTest/0.MasterFailover, where TypeParam =
>> > mesos::internal::slave::MesosContainerizer
>> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch
>> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
>> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
>> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch
>> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge
>> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
>> > [  FAILED  ] InMemoryStateTest.Names
>> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndFetch
>> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch
>> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreFailAndFetch
>> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndFetch
>> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndExpunge
>> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
>> > [  FAILED  ] LevelDBStateTest.Names
>> > [  FAILED  ] LogStateTest.FetchAndStoreAndFetch
>> > [  FAILED  ] LogStateTest.FetchAndStoreAndStoreAndFetch
>> > [  FAILED  ] LogStateTest.FetchAndStoreAndStoreFailAndFetch
>> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndFetch
>> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndExpunge
>> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
>> > [  FAILED  ] LogStateTest.Names
>> > [  FAILED  ] LogStateTest.Timeout
>> > [  FAILED  ] LogStateTest.Diff
>> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndFetch
>> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreAndFetch
>> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreFailAndFetch
>> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndFetch
>> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndExpunge
>> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
>> > [  FAILED  ] ZooKeeperStateTest.Names
>> > [  FAILED  ] Strict/RegistrarTest.Recover/0, where GetParam() = false
>> > [  FAILED  ] Strict/RegistrarTest.Recover/1, where GetParam() = true
>> > [  FAILED  ] Strict/RegistrarTest.Admit/0, where GetParam() = false
>> > [  FAILED  ] Strict/RegistrarTest.Admit/1, where GetParam() = true
>> > [  FAILED  ] Strict/RegistrarTest.Readmit/0, where GetParam() = false
>> > [  FAILED  ] Strict/RegistrarTest.Readmit/1, where GetParam() = true
>> > [  FAILED  ] Strict/RegistrarTest.Remove/0, where GetParam() = false
>> > [  FAILED  ] Strict/RegistrarTest.Remove/1, where GetParam() = true
>> > [  FAILED  ] Strict/RegistrarTest.Bootstrap/0, where GetParam() = false
>> > [  FAILED  ] Strict/RegistrarTest.Abort/0, where GetParam() = false
>> > [  FAILED  ] Strict/RegistrarTest.Abort/1, where GetParam() = true
>> >
>> > 47 FAILED TESTS
>> >   YOU HAVE 7 DISABLED TESTS
>> >
>> > make[4]: *** [check-local] Error 1
>> > make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
>> > make[3]: *** [check-am] Error 2
>> > make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
>> > make[2]: *** [check] Error 2
>> > make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
>> > make[1]: *** [check-recursive] Error 1
>> > make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
>> > make: *** [distcheck] Error 1
>> > + docker rmi mesos-1449523283-3961
>> > Untagged: mesos-1449523283-3961:latest
>> > Deleted:
>> bf1d0236000512633b0d52659e90345e0482c895b74aaa41d8cf335829ffa5b9
>> > Deleted:
>> 8c6d8a401155baa8697fd8ed4b18f21fd59f80ac55761caaf70e6203e5eaa6e5
>> > Deleted:
>> d8d6a742324ebdb91557481cd1f4bfed2fd9c3d8169eace16e7650f4fd9843ae
>> > Deleted:
>> 6e36632fa53927dc9930d6c6a2b34861f5ab01c19c846f16bdbbe2e229d533b5
>> > Deleted:
>> e56d47bb8d1394f6bbb5cff2a576dcecf13e0b60d2639fdfb6189e48eebbb8f9
>> > Deleted:
>> af62f49a1ef072869b9f318d3aa4be80391af33c0d11abed74069bf03258a519
>> > Deleted:
>> 40ea831bd69725cb46f301728c206870eb69012f228b7601679d79a406433e31
>> > Deleted:
>> 7f29cce7839475e6e6f480df46a3b809b7caad744d4b261f62115b9ef5603ede
>> > Deleted:
>> d7f8b3b948c9407f5edeadb252119d784d4fa4925e0aff60514967290afd77f5
>> > Deleted:
>> 1a91b42fcc552dd54f94e024600f3fef75879891ef70d4a3d0d88c12d2fcd1ce
>> > Deleted:
>> b377e8a2e554faa238159b0713a9a55ff6de84b2d7ba8871548ee57064621db3
>> > Deleted:
>> 5ba54d6b1c4209b886733b3fd4892473b88205721ea4d607c379e039638129e7
>> > Deleted:
>> 293adb3eee0890efd7c6deb8ee0b6328de0b3419da9ae8bf0b512c79f7134fda
>> >
>> > Error handling URL
>> > https://reviews.apache.org/api/review-requests/40759/reviews/: INTERNAL
>> > SERVER ERROR
>> > git clean -fd
>> > git reset --hard 517e84669790f833f6cf0e7911968a3591c93419
>> >
>> > Build step 'Execute shell' marked build as failure
>> >
>>
>
>

Re: Build failed in Jenkins: mesos-reviewbot #10069

Posted by Joris Van Remoortere <jo...@mesosphere.io>.

@vinod Do you know which commits introduced these failures? I tried looking
through my history but the first e-mail with these failures I see had no
new commits?

—
*Joris Van Remoortere*
Mesosphere

On Mon, Dec 7, 2015 at 5:51 PM, Vinod Kone <vi...@apache.org> wrote:

> is someone looking into these failures?
>
> [ RUN      ] InMemoryStateTest.FetchAndStoreAndFetch
> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
> IsInitialized(): Can't serialize message of type
> "mesos.internal.Registry.Slaves" because it is missing required
> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
> unknown file: Failure
> C++ exception with description "CHECK failed: IsInitialized(): Can't
> serialize message of type "mesos.internal.Registry.Slaves" because it
> is missing required fields: slaves[0].info.launcher_dir,
> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
> slaves[0].info.executor_rootfs" thrown in the test body.
> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch (2 ms)
> [ RUN      ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
> IsInitialized(): Can't serialize message of type
> "mesos.internal.Registry.Slaves" because it is missing required
> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
> unknown file: Failure
> C++ exception with description "CHECK failed: IsInitialized(): Can't
> serialize message of type "mesos.internal.Registry.Slaves" because it
> is missing required fields: slaves[0].info.launcher_dir,
> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
> slaves[0].info.executor_rootfs" thrown in the test body.
> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch (2 ms)
> [ RUN      ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
> [libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
> IsInitialized(): Can't serialize message of type
> "mesos.internal.Registry.Slaves" because it is missing required
> fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
> slaves[0].info.switch_user, slaves[0].info.executor_rootfs
> unknown file: Failure
> C++ exception with description "CHECK failed: IsInitialized(): Can't
> serialize message of type "mesos.internal.Registry.Slaves" because it
> is missing required fields: slaves[0].info.launcher_dir,
> slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
> slaves[0].info.executor_rootfs" thrown in the test body.
>
>
>
> On Mon, Dec 7, 2015 at 2:28 PM, Apache Jenkins Server <
> jenkins@builds.apache.org> wrote:
>
> > See <https://builds.apache.org/job/mesos-reviewbot/10069/>
> >
> > ------------------------------------------
> > [...truncated 159973 lines...]
> > I1207 22:27:35.090376 30316 leveldb.cpp:436] Reading position from
> leveldb
> > took 235515ns
> > I1207 22:27:35.138778 30316 leveldb.cpp:341] Persisting action (14 bytes)
> > to leveldb took 48.315363ms
> > I1207 22:27:35.138880 30316 replica.cpp:713] Persisted action at 0
> > I1207 22:27:35.140521 30330 replica.cpp:692] Replica received learned
> > notice for position 0 from @0.0.0.0:0
> > I1207 22:27:35.184093 30330 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 43.503748ms
> > I1207 22:27:35.184181 30330 replica.cpp:713] Persisted action at 0
> > I1207 22:27:35.184216 30330 replica.cpp:698] Replica learned NOP action
> at
> > position 0
> > I1207 22:27:35.185358 30320 log.cpp:675] Writer started with ending
> > position 0
> > I1207 22:27:35.186812 30320 leveldb.cpp:436] Reading position from
> leveldb
> > took 56774ns
> > I1207 22:27:35.188289 30320 registrar.cpp:340] Successfully fetched the
> > registry (0B) in 269.81888ms
> > I1207 22:27:35.188434 30320 registrar.cpp:439] Applied 1 operations in
> > 36841ns; attempting to update the 'registry'
> > I1207 22:27:35.189664 30320 log.cpp:683] Attempting to append 170 bytes
> to
> > the log
> > I1207 22:27:35.189913 30320 coordinator.cpp:348] Coordinator attempting
> to
> > write APPEND action at position 1
> > I1207 22:27:35.191028 30324 replica.cpp:538] Replica received write
> > request for position 1 from (11284)@172.17.0.5:58099
> > I1207 22:27:35.234787 30324 leveldb.cpp:341] Persisting action (189
> bytes)
> > to leveldb took 43.693038ms
> > I1207 22:27:35.234856 30324 replica.cpp:713] Persisted action at 1
> > I1207 22:27:35.235985 30324 replica.cpp:692] Replica received learned
> > notice for position 1 from @0.0.0.0:0
> > I1207 22:27:35.283252 30324 leveldb.cpp:341] Persisting action (191
> bytes)
> > to leveldb took 47.210343ms
> > I1207 22:27:35.283327 30324 replica.cpp:713] Persisted action at 1
> > I1207 22:27:35.283360 30324 replica.cpp:698] Replica learned APPEND
> action
> > at position 1
> > I1207 22:27:35.285286 30320 registrar.cpp:484] Successfully updated the
> > 'registry' in 96768us
> > I1207 22:27:35.285794 30324 log.cpp:702] Attempting to truncate the log
> to
> > 1
> > I1207 22:27:35.286253 30320 registrar.cpp:370] Successfully recovered
> > registrar
> > I1207 22:27:35.286731 30330 master.cpp:1450] Recovered 0 slaves from the
> > Registry (131B) ; allowing 10mins for slaves to re-register
> > I1207 22:27:35.286906 30330 hierarchical.cpp:181] Skipping recovery of
> > hierarchical allocator: nothing to recover
> > I1207 22:27:35.287220 30318 coordinator.cpp:348] Coordinator attempting
> to
> > write TRUNCATE action at position 2
> > I1207 22:27:35.288635 30318 replica.cpp:538] Replica received write
> > request for position 2 from (11285)@172.17.0.5:58099
> > I1207 22:27:35.319257 30318 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 30.50184ms
> > I1207 22:27:35.319442 30318 replica.cpp:713] Persisted action at 2
> > I1207 22:27:35.320984 30318 replica.cpp:692] Replica received learned
> > notice for position 2 from @0.0.0.0:0
> > I1207 22:27:35.379997 30318 leveldb.cpp:341] Persisting action (18 bytes)
> > to leveldb took 58.701806ms
> > I1207 22:27:35.380123 30318 leveldb.cpp:399] Deleting ~1 keys from
> leveldb
> > took 60205ns
> > I1207 22:27:35.380148 30318 replica.cpp:713] Persisted action at 2
> > I1207 22:27:35.380189 30318 replica.cpp:698] Replica learned TRUNCATE
> > action at position 2
> > I1207 22:27:35.390871 30296 scheduler.cpp:154] Version: 0.27.0
> > I1207 22:27:35.392123 30318 scheduler.cpp:236] New master detected at
> > master@172.17.0.5:58099
> > I1207 22:27:35.393574 30317 scheduler.cpp:298] Sending SUBSCRIBE call to
> > master@172.17.0.5:58099
> > I1207 22:27:35.396363 30325 process.cpp:3077] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1207 22:27:35.396437 30325 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.0.5:56612
> > I1207 22:27:35.396668 30325 master.cpp:1902] Received subscription
> request
> > for HTTP framework 'default'
> > I1207 22:27:35.396745 30325 master.cpp:1679] Authorizing framework
> > principal 'test-principal' to receive offers for role '*'
> > I1207 22:27:35.397251 30318 master.cpp:1994] Subscribing framework
> > 'default' with checkpointing disabled and capabilities [  ]
> > I1207 22:27:35.398214 30318 master.hpp:1590] Sending heartbeat to
> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> > I1207 22:27:35.398437 30316 hierarchical.cpp:267] Added framework
> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> > I1207 22:27:35.398659 30316 hierarchical.cpp:1317] No resources available
> > to allocate!
> > I1207 22:27:35.398846 30316 hierarchical.cpp:1410] No inverse offers to
> > send out!
> > I1207 22:27:35.399011 30316 hierarchical.cpp:1063] Performed allocation
> > for 0 slaves in 399353ns
> > I1207 22:27:35.399127 30319 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> > received from master@172.17.0.5:58099
> > I1207 22:27:35.400147 30324 scheduler.cpp:457] Enqueuing event HEARTBEAT
> > received from master@172.17.0.5:58099
> > I1207 22:27:35.400599 30329 master_maintenance_tests.cpp:177] Ignoring
> > HEARTBEAT event
> > I1207 22:27:35.400758 30319 scheduler.cpp:298] Sending REQUEST call to
> > master@172.17.0.5:58099
> > I1207 22:27:35.402441 30319 process.cpp:3077] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1207 22:27:35.402497 30319 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.0.5:56613
> > I1207 22:27:35.402590 30319 master.cpp:2649] Processing REQUEST call for
> > framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000 (default)
> > I1207 22:27:35.402741 30328 hierarchical.cpp:562] Received resource
> > request from framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> > I1207 22:27:35.403267 30296 master.cpp:941] Master terminating
> > I1207 22:27:35.404278 30324 hierarchical.cpp:308] Removed framework
> > 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> > E1207 22:27:35.405990 30321 scheduler.cpp:431] End-Of-File received from
> > master. The master closed the event stream
> > [       OK ] ContentType/SchedulerTest.Request/0 (781 ms)
> > [ RUN      ] ContentType/SchedulerTest.Request/1
> > I1207 22:27:35.582654 30296 leveldb.cpp:174] Opened db in 146.89541ms
> > I1207 22:27:35.630651 30296 leveldb.cpp:181] Compacted db in 47.91914ms
> > I1207 22:27:35.630837 30296 leveldb.cpp:196] Created db iterator in
> 29048ns
> > I1207 22:27:35.630960 30296 leveldb.cpp:202] Seeked to beginning of db in
> > 3895ns
> > I1207 22:27:35.631057 30296 leveldb.cpp:271] Iterated through 0 keys in
> > the db in 392ns
> > I1207 22:27:35.631122 30296 replica.cpp:778] Replica recovered with log
> > positions 0 -> 0 with 1 holes and 0 unlearned
> > I1207 22:27:35.631953 30325 recover.cpp:447] Starting replica recovery
> > I1207 22:27:35.632469 30325 recover.cpp:473] Replica is in EMPTY status
> > I1207 22:27:35.634237 30326 master.cpp:368] Master
> > e2d75c25-5903-4d9f-a2b6-d5f587b67977 (04364a599488) started on
> > 172.17.0.5:58099
> > I1207 22:27:35.634269 30326 master.cpp:370] Flags at startup: --acls=""
> > --allocation_interval="1secs" --allocator="HierarchicalDRF"
> > --authenticate="false" --authenticate_slaves="true"
> > --authenticators="crammd5" --authorizers="local"
> > --credentials="/tmp/gS0Qp6/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/gS0Qp6/master" --zk_session_timeout="10secs"
> > I1207 22:27:35.634629 30326 master.cpp:417] Master allowing
> > unauthenticated frameworks to register
> > I1207 22:27:35.634650 30326 master.cpp:420] Master only allowing
> > authenticated slaves to register
> > I1207 22:27:35.634662 30326 credentials.hpp:35] Loading credentials for
> > authentication from '/tmp/gS0Qp6/credentials'
> > I1207 22:27:35.634977 30326 master.cpp:459] Using default 'crammd5'
> > authenticator
> > I1207 22:27:35.635113 30326 master.cpp:496] Authorization enabled
> > I1207 22:27:35.635432 30321 hierarchical.cpp:163] Initialized
> hierarchical
> > allocator process
> > I1207 22:27:35.635501 30321 whitelist_watcher.cpp:77] No whitelist given
> > I1207 22:27:35.636523 30323 replica.cpp:674] Replica in EMPTY status
> > received a broadcasted recover request from (11290)@172.17.0.5:58099
> > I1207 22:27:35.636797 30329 recover.cpp:193] Received a recover response
> > from a replica in EMPTY status
> > I1207 22:27:35.637473 30323 recover.cpp:564] Updating replica status to
> > STARTING
> > I1207 22:27:35.637545 30329 master.cpp:1640] The newly elected leader is
> > master@172.17.0.5:58099 with id e2d75c25-5903-4d9f-a2b6-d5f587b67977
> > I1207 22:27:35.638653 30329 master.cpp:1653] Elected as the leading
> master!
> > I1207 22:27:35.638838 30329 master.cpp:1398] Recovering from registrar
> > I1207 22:27:35.639302 30329 registrar.cpp:307] Recovering registrar
> > I1207 22:27:35.668242 30317 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 29.650563ms
> > I1207 22:27:35.668308 30317 replica.cpp:321] Persisted replica status to
> > STARTING
> > I1207 22:27:35.668627 30317 recover.cpp:473] Replica is in STARTING
> status
> > I1207 22:27:35.670066 30327 replica.cpp:674] Replica in STARTING status
> > received a broadcasted recover request from (11291)@172.17.0.5:58099
> > I1207 22:27:35.670300 30319 recover.cpp:193] Received a recover response
> > from a replica in STARTING status
> > I1207 22:27:35.670833 30319 recover.cpp:564] Updating replica status to
> > VOTING
> > I1207 22:27:35.709368 30329 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 38.378081ms
> > I1207 22:27:35.709432 30329 replica.cpp:321] Persisted replica status to
> > VOTING
> > I1207 22:27:35.709650 30329 recover.cpp:578] Successfully joined the
> Paxos
> > group
> > I1207 22:27:35.709835 30329 recover.cpp:462] Recover process terminated
> > I1207 22:27:35.710474 30329 log.cpp:659] Attempting to start the writer
> > I1207 22:27:35.712080 30329 replica.cpp:494] Replica received implicit
> > promise request from (11292)@172.17.0.5:58099 with proposal 1
> > I1207 22:27:35.760247 30329 leveldb.cpp:304] Persisting metadata (8
> bytes)
> > to leveldb took 48.032955ms
> > I1207 22:27:35.760326 30329 replica.cpp:343] Persisted promised to 1
> > I1207 22:27:35.761510 30329 coordinator.cpp:238] Coordinator attempting
> to
> > fill missing positions
> > I1207 22:27:35.763252 30317 replica.cpp:389] Replica received explicit
> > promise request from (11293)@172.17.0.5:58099 for position 0 with
> > proposal 2
> > I1207 22:27:35.801486 30317 leveldb.cpp:341] Persisting action (8 bytes)
> > to leveldb took 38.168507ms
> > I1207 22:27:35.801558 30317 replica.cpp:713] Persisted action at 0
> > I1207 22:27:35.803572 30318 replica.cpp:538] Replica received write
> > request for position 0 from (11294)@172.17.0.5:58099
> > I1207 22:27:35.803683 30318 leveldb.cpp:436] Reading position from
> leveldb
> > took 51223ns
> > I1207 22:27:35.843870 30318 leveldb.cpp:341] Persisting action (14 bytes)
> > to leveldb took 40.115416ms
> > I1207 22:27:35.843960 30318 replica.cpp:713] Persisted action at 0
> > I1207 22:27:35.844985 30318 replica.cpp:692] Replica received learned
> > notice for position 0 from @0.0.0.0:0
> > I1207 22:27:35.881268 30318 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 36.231637ms
> > I1207 22:27:35.881361 30318 replica.cpp:713] Persisted action at 0
> > I1207 22:27:35.881398 30318 replica.cpp:698] Replica learned NOP action
> at
> > position 0
> > I1207 22:27:35.882627 30318 log.cpp:675] Writer started with ending
> > position 0
> > I1207 22:27:35.884521 30324 leveldb.cpp:436] Reading position from
> leveldb
> > took 60563ns
> > I1207 22:27:35.892060 30318 registrar.cpp:340] Successfully fetched the
> > registry (0B) in 252.649216ms
> > I1207 22:27:35.892359 30318 registrar.cpp:439] Applied 1 operations in
> > 44025ns; attempting to update the 'registry'
> > I1207 22:27:35.893972 30318 log.cpp:683] Attempting to append 170 bytes
> to
> > the log
> > I1207 22:27:35.894305 30317 coordinator.cpp:348] Coordinator attempting
> to
> > write APPEND action at position 1
> > I1207 22:27:35.895391 30319 replica.cpp:538] Replica received write
> > request for position 1 from (11295)@172.17.0.5:58099
> > I1207 22:27:35.925117 30319 leveldb.cpp:341] Persisting action (189
> bytes)
> > to leveldb took 29.558304ms
> > I1207 22:27:35.925226 30319 replica.cpp:713] Persisted action at 1
> > I1207 22:27:35.926812 30319 replica.cpp:692] Replica received learned
> > notice for position 1 from @0.0.0.0:0
> > I1207 22:27:35.950984 30319 leveldb.cpp:341] Persisting action (191
> bytes)
> > to leveldb took 24.114276ms
> > I1207 22:27:35.951082 30319 replica.cpp:713] Persisted action at 1
> > I1207 22:27:35.951123 30319 replica.cpp:698] Replica learned APPEND
> action
> > at position 1
> > I1207 22:27:35.953444 30316 registrar.cpp:484] Successfully updated the
> > 'registry' in 60.874752ms
> > I1207 22:27:35.953763 30317 log.cpp:702] Attempting to truncate the log
> to
> > 1
> > I1207 22:27:35.954022 30317 coordinator.cpp:348] Coordinator attempting
> to
> > write TRUNCATE action at position 2
> > I1207 22:27:35.954515 30316 registrar.cpp:370] Successfully recovered
> > registrar
> > I1207 22:27:35.955001 30316 master.cpp:1450] Recovered 0 slaves from the
> > Registry (131B) ; allowing 10mins for slaves to re-register
> > I1207 22:27:35.955236 30316 hierarchical.cpp:181] Skipping recovery of
> > hierarchical allocator: nothing to recover
> > I1207 22:27:35.956590 30325 replica.cpp:538] Replica received write
> > request for position 2 from (11296)@172.17.0.5:58099
> > I1207 22:27:35.983829 30325 leveldb.cpp:341] Persisting action (16 bytes)
> > to leveldb took 27.148875ms
> > I1207 22:27:35.983927 30325 replica.cpp:713] Persisted action at 2
> > I1207 22:27:35.985357 30325 replica.cpp:692] Replica received learned
> > notice for position 2 from @0.0.0.0:0
> > I1207 22:27:36.008911 30325 leveldb.cpp:341] Persisting action (18 bytes)
> > to leveldb took 23.494848ms
> > I1207 22:27:36.009063 30325 leveldb.cpp:399] Deleting ~1 keys from
> leveldb
> > took 74929ns
> > I1207 22:27:36.009090 30325 replica.cpp:713] Persisted action at 2
> > I1207 22:27:36.009124 30325 replica.cpp:698] Replica learned TRUNCATE
> > action at position 2
> > I1207 22:27:36.018368 30296 scheduler.cpp:154] Version: 0.27.0
> > I1207 22:27:36.019350 30325 scheduler.cpp:236] New master detected at
> > master@172.17.0.5:58099
> > I1207 22:27:36.020905 30325 scheduler.cpp:298] Sending SUBSCRIBE call to
> > master@172.17.0.5:58099
> > I1207 22:27:36.025128 30321 process.cpp:3077] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1207 22:27:36.025220 30321 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.0.5:56618
> > I1207 22:27:36.025624 30321 master.cpp:1902] Received subscription
> request
> > for HTTP framework 'default'
> > I1207 22:27:36.025699 30321 master.cpp:1679] Authorizing framework
> > principal 'test-principal' to receive offers for role '*'
> > I1207 22:27:36.026386 30321 master.cpp:1994] Subscribing framework
> > 'default' with checkpointing disabled and capabilities [  ]
> > I1207 22:27:36.026973 30321 hierarchical.cpp:267] Added framework
> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> > I1207 22:27:36.027042 30321 hierarchical.cpp:1317] No resources available
> > to allocate!
> > I1207 22:27:36.027075 30321 hierarchical.cpp:1410] No inverse offers to
> > send out!
> > I1207 22:27:36.027097 30321 hierarchical.cpp:1063] Performed allocation
> > for 0 slaves in 99824ns
> > I1207 22:27:36.027209 30322 master.hpp:1590] Sending heartbeat to
> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> > I1207 22:27:36.031739 30327 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> > received from master@172.17.0.5:58099
> > I1207 22:27:36.032542 30320 scheduler.cpp:457] Enqueuing event HEARTBEAT
> > received from master@172.17.0.5:58099
> > I1207 22:27:36.033051 30320 master_maintenance_tests.cpp:177] Ignoring
> > HEARTBEAT event
> > I1207 22:27:36.033721 30328 scheduler.cpp:298] Sending REQUEST call to
> > master@172.17.0.5:58099
> > I1207 22:27:36.037406 30315 process.cpp:3077] Handling HTTP event for
> > process 'master' with path: '/master/api/v1/scheduler'
> > I1207 22:27:36.037473 30315 http.cpp:336] HTTP POST for
> > /master/api/v1/scheduler from 172.17.0.5:56619
> > I1207 22:27:36.037760 30315 master.cpp:2649] Processing REQUEST call for
> > framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000 (default)
> > I1207 22:27:36.038022 30315 hierarchical.cpp:562] Received resource
> > request from framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> > I1207 22:27:36.038554 30296 master.cpp:941] Master terminating
> > I1207 22:27:36.038806 30324 hierarchical.cpp:308] Removed framework
> > e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> > E1207 22:27:36.043074 30317 scheduler.cpp:431] End-Of-File received from
> > master. The master closed the event stream
> > [       OK ] ContentType/SchedulerTest.Request/1 (617 ms)
> > [----------] 22 tests from ContentType/SchedulerTest (18620 ms total)
> >
> > [----------] Global test environment tear-down
> > [==========] 879 tests from 115 test cases ran. (1039939 ms total)
> > [  PASSED  ] 832 tests.
> > [  FAILED  ] 47 tests, listed below:
> > [  FAILED  ] MonitorIntegrationTest.RunningExecutor
> > [  FAILED  ] ReservationEndpointsTest.ReserveAvailableAndOfferedResources
> > [  FAILED  ]
> ReservationEndpointsTest.UnreserveAvailableAndOfferedResources
> > [  FAILED  ] ReservationTest.ACLMultipleOperations
> > [  FAILED  ] SlaveRecoveryTest/0.RecoverSlaveState, where TypeParam =
> > mesos::internal::slave::MesosContainerizer
> > [  FAILED  ] SlaveRecoveryTest/0.MasterFailover, where TypeParam =
> > mesos::internal::slave::MesosContainerizer
> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch
> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch
> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge
> > [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> > [  FAILED  ] InMemoryStateTest.Names
> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndFetch
> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch
> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreFailAndFetch
> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndFetch
> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndExpunge
> > [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> > [  FAILED  ] LevelDBStateTest.Names
> > [  FAILED  ] LogStateTest.FetchAndStoreAndFetch
> > [  FAILED  ] LogStateTest.FetchAndStoreAndStoreAndFetch
> > [  FAILED  ] LogStateTest.FetchAndStoreAndStoreFailAndFetch
> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndFetch
> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndExpunge
> > [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> > [  FAILED  ] LogStateTest.Names
> > [  FAILED  ] LogStateTest.Timeout
> > [  FAILED  ] LogStateTest.Diff
> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndFetch
> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreAndFetch
> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreFailAndFetch
> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndFetch
> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndExpunge
> > [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> > [  FAILED  ] ZooKeeperStateTest.Names
> > [  FAILED  ] Strict/RegistrarTest.Recover/0, where GetParam() = false
> > [  FAILED  ] Strict/RegistrarTest.Recover/1, where GetParam() = true
> > [  FAILED  ] Strict/RegistrarTest.Admit/0, where GetParam() = false
> > [  FAILED  ] Strict/RegistrarTest.Admit/1, where GetParam() = true
> > [  FAILED  ] Strict/RegistrarTest.Readmit/0, where GetParam() = false
> > [  FAILED  ] Strict/RegistrarTest.Readmit/1, where GetParam() = true
> > [  FAILED  ] Strict/RegistrarTest.Remove/0, where GetParam() = false
> > [  FAILED  ] Strict/RegistrarTest.Remove/1, where GetParam() = true
> > [  FAILED  ] Strict/RegistrarTest.Bootstrap/0, where GetParam() = false
> > [  FAILED  ] Strict/RegistrarTest.Abort/0, where GetParam() = false
> > [  FAILED  ] Strict/RegistrarTest.Abort/1, where GetParam() = true
> >
> > 47 FAILED TESTS
> >   YOU HAVE 7 DISABLED TESTS
> >
> > make[4]: *** [check-local] Error 1
> > make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[3]: *** [check-am] Error 2
> > make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[2]: *** [check] Error 2
> > make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> > make[1]: *** [check-recursive] Error 1
> > make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> > make: *** [distcheck] Error 1
> > + docker rmi mesos-1449523283-3961
> > Untagged: mesos-1449523283-3961:latest
> > Deleted: bf1d0236000512633b0d52659e90345e0482c895b74aaa41d8cf335829ffa5b9
> > Deleted: 8c6d8a401155baa8697fd8ed4b18f21fd59f80ac55761caaf70e6203e5eaa6e5
> > Deleted: d8d6a742324ebdb91557481cd1f4bfed2fd9c3d8169eace16e7650f4fd9843ae
> > Deleted: 6e36632fa53927dc9930d6c6a2b34861f5ab01c19c846f16bdbbe2e229d533b5
> > Deleted: e56d47bb8d1394f6bbb5cff2a576dcecf13e0b60d2639fdfb6189e48eebbb8f9
> > Deleted: af62f49a1ef072869b9f318d3aa4be80391af33c0d11abed74069bf03258a519
> > Deleted: 40ea831bd69725cb46f301728c206870eb69012f228b7601679d79a406433e31
> > Deleted: 7f29cce7839475e6e6f480df46a3b809b7caad744d4b261f62115b9ef5603ede
> > Deleted: d7f8b3b948c9407f5edeadb252119d784d4fa4925e0aff60514967290afd77f5
> > Deleted: 1a91b42fcc552dd54f94e024600f3fef75879891ef70d4a3d0d88c12d2fcd1ce
> > Deleted: b377e8a2e554faa238159b0713a9a55ff6de84b2d7ba8871548ee57064621db3
> > Deleted: 5ba54d6b1c4209b886733b3fd4892473b88205721ea4d607c379e039638129e7
> > Deleted: 293adb3eee0890efd7c6deb8ee0b6328de0b3419da9ae8bf0b512c79f7134fda
> >
> > Error handling URL
> > https://reviews.apache.org/api/review-requests/40759/reviews/: INTERNAL
> > SERVER ERROR
> > git clean -fd
> > git reset --hard 517e84669790f833f6cf0e7911968a3591c93419
> >
> > Build step 'Execute shell' marked build as failure
> >
>

Re: Build failed in Jenkins: mesos-reviewbot #10069

Posted by Vinod Kone <vi...@apache.org>.

is someone looking into these failures?

[ RUN      ] InMemoryStateTest.FetchAndStoreAndFetch
[libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
IsInitialized(): Can't serialize message of type
"mesos.internal.Registry.Slaves" because it is missing required
fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
slaves[0].info.switch_user, slaves[0].info.executor_rootfs
unknown file: Failure
C++ exception with description "CHECK failed: IsInitialized(): Can't
serialize message of type "mesos.internal.Registry.Slaves" because it
is missing required fields: slaves[0].info.launcher_dir,
slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
slaves[0].info.executor_rootfs" thrown in the test body.
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch (2 ms)
[ RUN      ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
[libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
IsInitialized(): Can't serialize message of type
"mesos.internal.Registry.Slaves" because it is missing required
fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
slaves[0].info.switch_user, slaves[0].info.executor_rootfs
unknown file: Failure
C++ exception with description "CHECK failed: IsInitialized(): Can't
serialize message of type "mesos.internal.Registry.Slaves" because it
is missing required fields: slaves[0].info.launcher_dir,
slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
slaves[0].info.executor_rootfs" thrown in the test body.
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch (2 ms)
[ RUN      ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
[libprotobuf FATAL google/protobuf/message_lite.cc:273] CHECK failed:
IsInitialized(): Can't serialize message of type
"mesos.internal.Registry.Slaves" because it is missing required
fields: slaves[0].info.launcher_dir, slaves[0].info.sandbox_dir,
slaves[0].info.switch_user, slaves[0].info.executor_rootfs
unknown file: Failure
C++ exception with description "CHECK failed: IsInitialized(): Can't
serialize message of type "mesos.internal.Registry.Slaves" because it
is missing required fields: slaves[0].info.launcher_dir,
slaves[0].info.sandbox_dir, slaves[0].info.switch_user,
slaves[0].info.executor_rootfs" thrown in the test body.



On Mon, Dec 7, 2015 at 2:28 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:

> See <https://builds.apache.org/job/mesos-reviewbot/10069/>
>
> ------------------------------------------
> [...truncated 159973 lines...]
> I1207 22:27:35.090376 30316 leveldb.cpp:436] Reading position from leveldb
> took 235515ns
> I1207 22:27:35.138778 30316 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 48.315363ms
> I1207 22:27:35.138880 30316 replica.cpp:713] Persisted action at 0
> I1207 22:27:35.140521 30330 replica.cpp:692] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1207 22:27:35.184093 30330 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 43.503748ms
> I1207 22:27:35.184181 30330 replica.cpp:713] Persisted action at 0
> I1207 22:27:35.184216 30330 replica.cpp:698] Replica learned NOP action at
> position 0
> I1207 22:27:35.185358 30320 log.cpp:675] Writer started with ending
> position 0
> I1207 22:27:35.186812 30320 leveldb.cpp:436] Reading position from leveldb
> took 56774ns
> I1207 22:27:35.188289 30320 registrar.cpp:340] Successfully fetched the
> registry (0B) in 269.81888ms
> I1207 22:27:35.188434 30320 registrar.cpp:439] Applied 1 operations in
> 36841ns; attempting to update the 'registry'
> I1207 22:27:35.189664 30320 log.cpp:683] Attempting to append 170 bytes to
> the log
> I1207 22:27:35.189913 30320 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I1207 22:27:35.191028 30324 replica.cpp:538] Replica received write
> request for position 1 from (11284)@172.17.0.5:58099
> I1207 22:27:35.234787 30324 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 43.693038ms
> I1207 22:27:35.234856 30324 replica.cpp:713] Persisted action at 1
> I1207 22:27:35.235985 30324 replica.cpp:692] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1207 22:27:35.283252 30324 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 47.210343ms
> I1207 22:27:35.283327 30324 replica.cpp:713] Persisted action at 1
> I1207 22:27:35.283360 30324 replica.cpp:698] Replica learned APPEND action
> at position 1
> I1207 22:27:35.285286 30320 registrar.cpp:484] Successfully updated the
> 'registry' in 96768us
> I1207 22:27:35.285794 30324 log.cpp:702] Attempting to truncate the log to
> 1
> I1207 22:27:35.286253 30320 registrar.cpp:370] Successfully recovered
> registrar
> I1207 22:27:35.286731 30330 master.cpp:1450] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I1207 22:27:35.286906 30330 hierarchical.cpp:181] Skipping recovery of
> hierarchical allocator: nothing to recover
> I1207 22:27:35.287220 30318 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I1207 22:27:35.288635 30318 replica.cpp:538] Replica received write
> request for position 2 from (11285)@172.17.0.5:58099
> I1207 22:27:35.319257 30318 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 30.50184ms
> I1207 22:27:35.319442 30318 replica.cpp:713] Persisted action at 2
> I1207 22:27:35.320984 30318 replica.cpp:692] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1207 22:27:35.379997 30318 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 58.701806ms
> I1207 22:27:35.380123 30318 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 60205ns
> I1207 22:27:35.380148 30318 replica.cpp:713] Persisted action at 2
> I1207 22:27:35.380189 30318 replica.cpp:698] Replica learned TRUNCATE
> action at position 2
> I1207 22:27:35.390871 30296 scheduler.cpp:154] Version: 0.27.0
> I1207 22:27:35.392123 30318 scheduler.cpp:236] New master detected at
> master@172.17.0.5:58099
> I1207 22:27:35.393574 30317 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.5:58099
> I1207 22:27:35.396363 30325 process.cpp:3077] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1207 22:27:35.396437 30325 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.5:56612
> I1207 22:27:35.396668 30325 master.cpp:1902] Received subscription request
> for HTTP framework 'default'
> I1207 22:27:35.396745 30325 master.cpp:1679] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1207 22:27:35.397251 30318 master.cpp:1994] Subscribing framework
> 'default' with checkpointing disabled and capabilities [  ]
> I1207 22:27:35.398214 30318 master.hpp:1590] Sending heartbeat to
> 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> I1207 22:27:35.398437 30316 hierarchical.cpp:267] Added framework
> 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> I1207 22:27:35.398659 30316 hierarchical.cpp:1317] No resources available
> to allocate!
> I1207 22:27:35.398846 30316 hierarchical.cpp:1410] No inverse offers to
> send out!
> I1207 22:27:35.399011 30316 hierarchical.cpp:1063] Performed allocation
> for 0 slaves in 399353ns
> I1207 22:27:35.399127 30319 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.5:58099
> I1207 22:27:35.400147 30324 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.5:58099
> I1207 22:27:35.400599 30329 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I1207 22:27:35.400758 30319 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.5:58099
> I1207 22:27:35.402441 30319 process.cpp:3077] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1207 22:27:35.402497 30319 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.5:56613
> I1207 22:27:35.402590 30319 master.cpp:2649] Processing REQUEST call for
> framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000 (default)
> I1207 22:27:35.402741 30328 hierarchical.cpp:562] Received resource
> request from framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> I1207 22:27:35.403267 30296 master.cpp:941] Master terminating
> I1207 22:27:35.404278 30324 hierarchical.cpp:308] Removed framework
> 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
> E1207 22:27:35.405990 30321 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [       OK ] ContentType/SchedulerTest.Request/0 (781 ms)
> [ RUN      ] ContentType/SchedulerTest.Request/1
> I1207 22:27:35.582654 30296 leveldb.cpp:174] Opened db in 146.89541ms
> I1207 22:27:35.630651 30296 leveldb.cpp:181] Compacted db in 47.91914ms
> I1207 22:27:35.630837 30296 leveldb.cpp:196] Created db iterator in 29048ns
> I1207 22:27:35.630960 30296 leveldb.cpp:202] Seeked to beginning of db in
> 3895ns
> I1207 22:27:35.631057 30296 leveldb.cpp:271] Iterated through 0 keys in
> the db in 392ns
> I1207 22:27:35.631122 30296 replica.cpp:778] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I1207 22:27:35.631953 30325 recover.cpp:447] Starting replica recovery
> I1207 22:27:35.632469 30325 recover.cpp:473] Replica is in EMPTY status
> I1207 22:27:35.634237 30326 master.cpp:368] Master
> e2d75c25-5903-4d9f-a2b6-d5f587b67977 (04364a599488) started on
> 172.17.0.5:58099
> I1207 22:27:35.634269 30326 master.cpp:370] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_slaves="true"
> --authenticators="crammd5" --authorizers="local"
> --credentials="/tmp/gS0Qp6/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/gS0Qp6/master" --zk_session_timeout="10secs"
> I1207 22:27:35.634629 30326 master.cpp:417] Master allowing
> unauthenticated frameworks to register
> I1207 22:27:35.634650 30326 master.cpp:420] Master only allowing
> authenticated slaves to register
> I1207 22:27:35.634662 30326 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/gS0Qp6/credentials'
> I1207 22:27:35.634977 30326 master.cpp:459] Using default 'crammd5'
> authenticator
> I1207 22:27:35.635113 30326 master.cpp:496] Authorization enabled
> I1207 22:27:35.635432 30321 hierarchical.cpp:163] Initialized hierarchical
> allocator process
> I1207 22:27:35.635501 30321 whitelist_watcher.cpp:77] No whitelist given
> I1207 22:27:35.636523 30323 replica.cpp:674] Replica in EMPTY status
> received a broadcasted recover request from (11290)@172.17.0.5:58099
> I1207 22:27:35.636797 30329 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I1207 22:27:35.637473 30323 recover.cpp:564] Updating replica status to
> STARTING
> I1207 22:27:35.637545 30329 master.cpp:1640] The newly elected leader is
> master@172.17.0.5:58099 with id e2d75c25-5903-4d9f-a2b6-d5f587b67977
> I1207 22:27:35.638653 30329 master.cpp:1653] Elected as the leading master!
> I1207 22:27:35.638838 30329 master.cpp:1398] Recovering from registrar
> I1207 22:27:35.639302 30329 registrar.cpp:307] Recovering registrar
> I1207 22:27:35.668242 30317 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 29.650563ms
> I1207 22:27:35.668308 30317 replica.cpp:321] Persisted replica status to
> STARTING
> I1207 22:27:35.668627 30317 recover.cpp:473] Replica is in STARTING status
> I1207 22:27:35.670066 30327 replica.cpp:674] Replica in STARTING status
> received a broadcasted recover request from (11291)@172.17.0.5:58099
> I1207 22:27:35.670300 30319 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I1207 22:27:35.670833 30319 recover.cpp:564] Updating replica status to
> VOTING
> I1207 22:27:35.709368 30329 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 38.378081ms
> I1207 22:27:35.709432 30329 replica.cpp:321] Persisted replica status to
> VOTING
> I1207 22:27:35.709650 30329 recover.cpp:578] Successfully joined the Paxos
> group
> I1207 22:27:35.709835 30329 recover.cpp:462] Recover process terminated
> I1207 22:27:35.710474 30329 log.cpp:659] Attempting to start the writer
> I1207 22:27:35.712080 30329 replica.cpp:494] Replica received implicit
> promise request from (11292)@172.17.0.5:58099 with proposal 1
> I1207 22:27:35.760247 30329 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 48.032955ms
> I1207 22:27:35.760326 30329 replica.cpp:343] Persisted promised to 1
> I1207 22:27:35.761510 30329 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I1207 22:27:35.763252 30317 replica.cpp:389] Replica received explicit
> promise request from (11293)@172.17.0.5:58099 for position 0 with
> proposal 2
> I1207 22:27:35.801486 30317 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 38.168507ms
> I1207 22:27:35.801558 30317 replica.cpp:713] Persisted action at 0
> I1207 22:27:35.803572 30318 replica.cpp:538] Replica received write
> request for position 0 from (11294)@172.17.0.5:58099
> I1207 22:27:35.803683 30318 leveldb.cpp:436] Reading position from leveldb
> took 51223ns
> I1207 22:27:35.843870 30318 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 40.115416ms
> I1207 22:27:35.843960 30318 replica.cpp:713] Persisted action at 0
> I1207 22:27:35.844985 30318 replica.cpp:692] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I1207 22:27:35.881268 30318 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 36.231637ms
> I1207 22:27:35.881361 30318 replica.cpp:713] Persisted action at 0
> I1207 22:27:35.881398 30318 replica.cpp:698] Replica learned NOP action at
> position 0
> I1207 22:27:35.882627 30318 log.cpp:675] Writer started with ending
> position 0
> I1207 22:27:35.884521 30324 leveldb.cpp:436] Reading position from leveldb
> took 60563ns
> I1207 22:27:35.892060 30318 registrar.cpp:340] Successfully fetched the
> registry (0B) in 252.649216ms
> I1207 22:27:35.892359 30318 registrar.cpp:439] Applied 1 operations in
> 44025ns; attempting to update the 'registry'
> I1207 22:27:35.893972 30318 log.cpp:683] Attempting to append 170 bytes to
> the log
> I1207 22:27:35.894305 30317 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I1207 22:27:35.895391 30319 replica.cpp:538] Replica received write
> request for position 1 from (11295)@172.17.0.5:58099
> I1207 22:27:35.925117 30319 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 29.558304ms
> I1207 22:27:35.925226 30319 replica.cpp:713] Persisted action at 1
> I1207 22:27:35.926812 30319 replica.cpp:692] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I1207 22:27:35.950984 30319 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 24.114276ms
> I1207 22:27:35.951082 30319 replica.cpp:713] Persisted action at 1
> I1207 22:27:35.951123 30319 replica.cpp:698] Replica learned APPEND action
> at position 1
> I1207 22:27:35.953444 30316 registrar.cpp:484] Successfully updated the
> 'registry' in 60.874752ms
> I1207 22:27:35.953763 30317 log.cpp:702] Attempting to truncate the log to
> 1
> I1207 22:27:35.954022 30317 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I1207 22:27:35.954515 30316 registrar.cpp:370] Successfully recovered
> registrar
> I1207 22:27:35.955001 30316 master.cpp:1450] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I1207 22:27:35.955236 30316 hierarchical.cpp:181] Skipping recovery of
> hierarchical allocator: nothing to recover
> I1207 22:27:35.956590 30325 replica.cpp:538] Replica received write
> request for position 2 from (11296)@172.17.0.5:58099
> I1207 22:27:35.983829 30325 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 27.148875ms
> I1207 22:27:35.983927 30325 replica.cpp:713] Persisted action at 2
> I1207 22:27:35.985357 30325 replica.cpp:692] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I1207 22:27:36.008911 30325 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 23.494848ms
> I1207 22:27:36.009063 30325 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 74929ns
> I1207 22:27:36.009090 30325 replica.cpp:713] Persisted action at 2
> I1207 22:27:36.009124 30325 replica.cpp:698] Replica learned TRUNCATE
> action at position 2
> I1207 22:27:36.018368 30296 scheduler.cpp:154] Version: 0.27.0
> I1207 22:27:36.019350 30325 scheduler.cpp:236] New master detected at
> master@172.17.0.5:58099
> I1207 22:27:36.020905 30325 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.5:58099
> I1207 22:27:36.025128 30321 process.cpp:3077] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1207 22:27:36.025220 30321 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.5:56618
> I1207 22:27:36.025624 30321 master.cpp:1902] Received subscription request
> for HTTP framework 'default'
> I1207 22:27:36.025699 30321 master.cpp:1679] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I1207 22:27:36.026386 30321 master.cpp:1994] Subscribing framework
> 'default' with checkpointing disabled and capabilities [  ]
> I1207 22:27:36.026973 30321 hierarchical.cpp:267] Added framework
> e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> I1207 22:27:36.027042 30321 hierarchical.cpp:1317] No resources available
> to allocate!
> I1207 22:27:36.027075 30321 hierarchical.cpp:1410] No inverse offers to
> send out!
> I1207 22:27:36.027097 30321 hierarchical.cpp:1063] Performed allocation
> for 0 slaves in 99824ns
> I1207 22:27:36.027209 30322 master.hpp:1590] Sending heartbeat to
> e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> I1207 22:27:36.031739 30327 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.5:58099
> I1207 22:27:36.032542 30320 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.5:58099
> I1207 22:27:36.033051 30320 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I1207 22:27:36.033721 30328 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.5:58099
> I1207 22:27:36.037406 30315 process.cpp:3077] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I1207 22:27:36.037473 30315 http.cpp:336] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.5:56619
> I1207 22:27:36.037760 30315 master.cpp:2649] Processing REQUEST call for
> framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000 (default)
> I1207 22:27:36.038022 30315 hierarchical.cpp:562] Received resource
> request from framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> I1207 22:27:36.038554 30296 master.cpp:941] Master terminating
> I1207 22:27:36.038806 30324 hierarchical.cpp:308] Removed framework
> e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
> E1207 22:27:36.043074 30317 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [       OK ] ContentType/SchedulerTest.Request/1 (617 ms)
> [----------] 22 tests from ContentType/SchedulerTest (18620 ms total)
>
> [----------] Global test environment tear-down
> [==========] 879 tests from 115 test cases ran. (1039939 ms total)
> [  PASSED  ] 832 tests.
> [  FAILED  ] 47 tests, listed below:
> [  FAILED  ] MonitorIntegrationTest.RunningExecutor
> [  FAILED  ] ReservationEndpointsTest.ReserveAvailableAndOfferedResources
> [  FAILED  ] ReservationEndpointsTest.UnreserveAvailableAndOfferedResources
> [  FAILED  ] ReservationTest.ACLMultipleOperations
> [  FAILED  ] SlaveRecoveryTest/0.RecoverSlaveState, where TypeParam =
> mesos::internal::slave::MesosContainerizer
> [  FAILED  ] SlaveRecoveryTest/0.MasterFailover, where TypeParam =
> mesos::internal::slave::MesosContainerizer
> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch
> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch
> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge
> [  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> [  FAILED  ] InMemoryStateTest.Names
> [  FAILED  ] LevelDBStateTest.FetchAndStoreAndFetch
> [  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch
> [  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreFailAndFetch
> [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndFetch
> [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndExpunge
> [  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> [  FAILED  ] LevelDBStateTest.Names
> [  FAILED  ] LogStateTest.FetchAndStoreAndFetch
> [  FAILED  ] LogStateTest.FetchAndStoreAndStoreAndFetch
> [  FAILED  ] LogStateTest.FetchAndStoreAndStoreFailAndFetch
> [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndFetch
> [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndExpunge
> [  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> [  FAILED  ] LogStateTest.Names
> [  FAILED  ] LogStateTest.Timeout
> [  FAILED  ] LogStateTest.Diff
> [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndFetch
> [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreAndFetch
> [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreFailAndFetch
> [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndFetch
> [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndExpunge
> [  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
> [  FAILED  ] ZooKeeperStateTest.Names
> [  FAILED  ] Strict/RegistrarTest.Recover/0, where GetParam() = false
> [  FAILED  ] Strict/RegistrarTest.Recover/1, where GetParam() = true
> [  FAILED  ] Strict/RegistrarTest.Admit/0, where GetParam() = false
> [  FAILED  ] Strict/RegistrarTest.Admit/1, where GetParam() = true
> [  FAILED  ] Strict/RegistrarTest.Readmit/0, where GetParam() = false
> [  FAILED  ] Strict/RegistrarTest.Readmit/1, where GetParam() = true
> [  FAILED  ] Strict/RegistrarTest.Remove/0, where GetParam() = false
> [  FAILED  ] Strict/RegistrarTest.Remove/1, where GetParam() = true
> [  FAILED  ] Strict/RegistrarTest.Bootstrap/0, where GetParam() = false
> [  FAILED  ] Strict/RegistrarTest.Abort/0, where GetParam() = false
> [  FAILED  ] Strict/RegistrarTest.Abort/1, where GetParam() = true
>
> 47 FAILED TESTS
>   YOU HAVE 7 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1449523283-3961
> Untagged: mesos-1449523283-3961:latest
> Deleted: bf1d0236000512633b0d52659e90345e0482c895b74aaa41d8cf335829ffa5b9
> Deleted: 8c6d8a401155baa8697fd8ed4b18f21fd59f80ac55761caaf70e6203e5eaa6e5
> Deleted: d8d6a742324ebdb91557481cd1f4bfed2fd9c3d8169eace16e7650f4fd9843ae
> Deleted: 6e36632fa53927dc9930d6c6a2b34861f5ab01c19c846f16bdbbe2e229d533b5
> Deleted: e56d47bb8d1394f6bbb5cff2a576dcecf13e0b60d2639fdfb6189e48eebbb8f9
> Deleted: af62f49a1ef072869b9f318d3aa4be80391af33c0d11abed74069bf03258a519
> Deleted: 40ea831bd69725cb46f301728c206870eb69012f228b7601679d79a406433e31
> Deleted: 7f29cce7839475e6e6f480df46a3b809b7caad744d4b261f62115b9ef5603ede
> Deleted: d7f8b3b948c9407f5edeadb252119d784d4fa4925e0aff60514967290afd77f5
> Deleted: 1a91b42fcc552dd54f94e024600f3fef75879891ef70d4a3d0d88c12d2fcd1ce
> Deleted: b377e8a2e554faa238159b0713a9a55ff6de84b2d7ba8871548ee57064621db3
> Deleted: 5ba54d6b1c4209b886733b3fd4892473b88205721ea4d607c379e039638129e7
> Deleted: 293adb3eee0890efd7c6deb8ee0b6328de0b3419da9ae8bf0b512c79f7134fda
>
> Error handling URL
> https://reviews.apache.org/api/review-requests/40759/reviews/: INTERNAL
> SERVER ERROR
> git clean -fd
> git reset --hard 517e84669790f833f6cf0e7911968a3591c93419
>
> Build step 'Execute shell' marked build as failure
>

Build failed in Jenkins: mesos-reviewbot #10069

Posted by Apache Jenkins Server <je...@builds.apache.org>.

See <https://builds.apache.org/job/mesos-reviewbot/10069/>

------------------------------------------
[...truncated 159973 lines...]
I1207 22:27:35.090376 30316 leveldb.cpp:436] Reading position from leveldb took 235515ns
I1207 22:27:35.138778 30316 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 48.315363ms
I1207 22:27:35.138880 30316 replica.cpp:713] Persisted action at 0
I1207 22:27:35.140521 30330 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1207 22:27:35.184093 30330 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 43.503748ms
I1207 22:27:35.184181 30330 replica.cpp:713] Persisted action at 0
I1207 22:27:35.184216 30330 replica.cpp:698] Replica learned NOP action at position 0
I1207 22:27:35.185358 30320 log.cpp:675] Writer started with ending position 0
I1207 22:27:35.186812 30320 leveldb.cpp:436] Reading position from leveldb took 56774ns
I1207 22:27:35.188289 30320 registrar.cpp:340] Successfully fetched the registry (0B) in 269.81888ms
I1207 22:27:35.188434 30320 registrar.cpp:439] Applied 1 operations in 36841ns; attempting to update the 'registry'
I1207 22:27:35.189664 30320 log.cpp:683] Attempting to append 170 bytes to the log
I1207 22:27:35.189913 30320 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1207 22:27:35.191028 30324 replica.cpp:538] Replica received write request for position 1 from (11284)@172.17.0.5:58099
I1207 22:27:35.234787 30324 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 43.693038ms
I1207 22:27:35.234856 30324 replica.cpp:713] Persisted action at 1
I1207 22:27:35.235985 30324 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1207 22:27:35.283252 30324 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 47.210343ms
I1207 22:27:35.283327 30324 replica.cpp:713] Persisted action at 1
I1207 22:27:35.283360 30324 replica.cpp:698] Replica learned APPEND action at position 1
I1207 22:27:35.285286 30320 registrar.cpp:484] Successfully updated the 'registry' in 96768us
I1207 22:27:35.285794 30324 log.cpp:702] Attempting to truncate the log to 1
I1207 22:27:35.286253 30320 registrar.cpp:370] Successfully recovered registrar
I1207 22:27:35.286731 30330 master.cpp:1450] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1207 22:27:35.286906 30330 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1207 22:27:35.287220 30318 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1207 22:27:35.288635 30318 replica.cpp:538] Replica received write request for position 2 from (11285)@172.17.0.5:58099
I1207 22:27:35.319257 30318 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 30.50184ms
I1207 22:27:35.319442 30318 replica.cpp:713] Persisted action at 2
I1207 22:27:35.320984 30318 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1207 22:27:35.379997 30318 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 58.701806ms
I1207 22:27:35.380123 30318 leveldb.cpp:399] Deleting ~1 keys from leveldb took 60205ns
I1207 22:27:35.380148 30318 replica.cpp:713] Persisted action at 2
I1207 22:27:35.380189 30318 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1207 22:27:35.390871 30296 scheduler.cpp:154] Version: 0.27.0
I1207 22:27:35.392123 30318 scheduler.cpp:236] New master detected at master@172.17.0.5:58099
I1207 22:27:35.393574 30317 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.5:58099
I1207 22:27:35.396363 30325 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1207 22:27:35.396437 30325 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:56612
I1207 22:27:35.396668 30325 master.cpp:1902] Received subscription request for HTTP framework 'default'
I1207 22:27:35.396745 30325 master.cpp:1679] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1207 22:27:35.397251 30318 master.cpp:1994] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I1207 22:27:35.398214 30318 master.hpp:1590] Sending heartbeat to 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
I1207 22:27:35.398437 30316 hierarchical.cpp:267] Added framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
I1207 22:27:35.398659 30316 hierarchical.cpp:1317] No resources available to allocate!
I1207 22:27:35.398846 30316 hierarchical.cpp:1410] No inverse offers to send out!
I1207 22:27:35.399011 30316 hierarchical.cpp:1063] Performed allocation for 0 slaves in 399353ns
I1207 22:27:35.399127 30319 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.5:58099
I1207 22:27:35.400147 30324 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.5:58099
I1207 22:27:35.400599 30329 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1207 22:27:35.400758 30319 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.5:58099
I1207 22:27:35.402441 30319 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1207 22:27:35.402497 30319 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:56613
I1207 22:27:35.402590 30319 master.cpp:2649] Processing REQUEST call for framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000 (default)
I1207 22:27:35.402741 30328 hierarchical.cpp:562] Received resource request from framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
I1207 22:27:35.403267 30296 master.cpp:941] Master terminating
I1207 22:27:35.404278 30324 hierarchical.cpp:308] Removed framework 6f58ed45-7387-4f8f-84c8-5876d60cbd5f-0000
E1207 22:27:35.405990 30321 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[       OK ] ContentType/SchedulerTest.Request/0 (781 ms)
[ RUN      ] ContentType/SchedulerTest.Request/1
I1207 22:27:35.582654 30296 leveldb.cpp:174] Opened db in 146.89541ms
I1207 22:27:35.630651 30296 leveldb.cpp:181] Compacted db in 47.91914ms
I1207 22:27:35.630837 30296 leveldb.cpp:196] Created db iterator in 29048ns
I1207 22:27:35.630960 30296 leveldb.cpp:202] Seeked to beginning of db in 3895ns
I1207 22:27:35.631057 30296 leveldb.cpp:271] Iterated through 0 keys in the db in 392ns
I1207 22:27:35.631122 30296 replica.cpp:778] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I1207 22:27:35.631953 30325 recover.cpp:447] Starting replica recovery
I1207 22:27:35.632469 30325 recover.cpp:473] Replica is in EMPTY status
I1207 22:27:35.634237 30326 master.cpp:368] Master e2d75c25-5903-4d9f-a2b6-d5f587b67977 (04364a599488) started on 172.17.0.5:58099
I1207 22:27:35.634269 30326 master.cpp:370] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/gS0Qp6/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/gS0Qp6/master" --zk_session_timeout="10secs"
I1207 22:27:35.634629 30326 master.cpp:417] Master allowing unauthenticated frameworks to register
I1207 22:27:35.634650 30326 master.cpp:420] Master only allowing authenticated slaves to register
I1207 22:27:35.634662 30326 credentials.hpp:35] Loading credentials for authentication from '/tmp/gS0Qp6/credentials'
I1207 22:27:35.634977 30326 master.cpp:459] Using default 'crammd5' authenticator
I1207 22:27:35.635113 30326 master.cpp:496] Authorization enabled
I1207 22:27:35.635432 30321 hierarchical.cpp:163] Initialized hierarchical allocator process
I1207 22:27:35.635501 30321 whitelist_watcher.cpp:77] No whitelist given
I1207 22:27:35.636523 30323 replica.cpp:674] Replica in EMPTY status received a broadcasted recover request from (11290)@172.17.0.5:58099
I1207 22:27:35.636797 30329 recover.cpp:193] Received a recover response from a replica in EMPTY status
I1207 22:27:35.637473 30323 recover.cpp:564] Updating replica status to STARTING
I1207 22:27:35.637545 30329 master.cpp:1640] The newly elected leader is master@172.17.0.5:58099 with id e2d75c25-5903-4d9f-a2b6-d5f587b67977
I1207 22:27:35.638653 30329 master.cpp:1653] Elected as the leading master!
I1207 22:27:35.638838 30329 master.cpp:1398] Recovering from registrar
I1207 22:27:35.639302 30329 registrar.cpp:307] Recovering registrar
I1207 22:27:35.668242 30317 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.650563ms
I1207 22:27:35.668308 30317 replica.cpp:321] Persisted replica status to STARTING
I1207 22:27:35.668627 30317 recover.cpp:473] Replica is in STARTING status
I1207 22:27:35.670066 30327 replica.cpp:674] Replica in STARTING status received a broadcasted recover request from (11291)@172.17.0.5:58099
I1207 22:27:35.670300 30319 recover.cpp:193] Received a recover response from a replica in STARTING status
I1207 22:27:35.670833 30319 recover.cpp:564] Updating replica status to VOTING
I1207 22:27:35.709368 30329 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.378081ms
I1207 22:27:35.709432 30329 replica.cpp:321] Persisted replica status to VOTING
I1207 22:27:35.709650 30329 recover.cpp:578] Successfully joined the Paxos group
I1207 22:27:35.709835 30329 recover.cpp:462] Recover process terminated
I1207 22:27:35.710474 30329 log.cpp:659] Attempting to start the writer
I1207 22:27:35.712080 30329 replica.cpp:494] Replica received implicit promise request from (11292)@172.17.0.5:58099 with proposal 1
I1207 22:27:35.760247 30329 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 48.032955ms
I1207 22:27:35.760326 30329 replica.cpp:343] Persisted promised to 1
I1207 22:27:35.761510 30329 coordinator.cpp:238] Coordinator attempting to fill missing positions
I1207 22:27:35.763252 30317 replica.cpp:389] Replica received explicit promise request from (11293)@172.17.0.5:58099 for position 0 with proposal 2
I1207 22:27:35.801486 30317 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 38.168507ms
I1207 22:27:35.801558 30317 replica.cpp:713] Persisted action at 0
I1207 22:27:35.803572 30318 replica.cpp:538] Replica received write request for position 0 from (11294)@172.17.0.5:58099
I1207 22:27:35.803683 30318 leveldb.cpp:436] Reading position from leveldb took 51223ns
I1207 22:27:35.843870 30318 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 40.115416ms
I1207 22:27:35.843960 30318 replica.cpp:713] Persisted action at 0
I1207 22:27:35.844985 30318 replica.cpp:692] Replica received learned notice for position 0 from @0.0.0.0:0
I1207 22:27:35.881268 30318 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 36.231637ms
I1207 22:27:35.881361 30318 replica.cpp:713] Persisted action at 0
I1207 22:27:35.881398 30318 replica.cpp:698] Replica learned NOP action at position 0
I1207 22:27:35.882627 30318 log.cpp:675] Writer started with ending position 0
I1207 22:27:35.884521 30324 leveldb.cpp:436] Reading position from leveldb took 60563ns
I1207 22:27:35.892060 30318 registrar.cpp:340] Successfully fetched the registry (0B) in 252.649216ms
I1207 22:27:35.892359 30318 registrar.cpp:439] Applied 1 operations in 44025ns; attempting to update the 'registry'
I1207 22:27:35.893972 30318 log.cpp:683] Attempting to append 170 bytes to the log
I1207 22:27:35.894305 30317 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I1207 22:27:35.895391 30319 replica.cpp:538] Replica received write request for position 1 from (11295)@172.17.0.5:58099
I1207 22:27:35.925117 30319 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 29.558304ms
I1207 22:27:35.925226 30319 replica.cpp:713] Persisted action at 1
I1207 22:27:35.926812 30319 replica.cpp:692] Replica received learned notice for position 1 from @0.0.0.0:0
I1207 22:27:35.950984 30319 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 24.114276ms
I1207 22:27:35.951082 30319 replica.cpp:713] Persisted action at 1
I1207 22:27:35.951123 30319 replica.cpp:698] Replica learned APPEND action at position 1
I1207 22:27:35.953444 30316 registrar.cpp:484] Successfully updated the 'registry' in 60.874752ms
I1207 22:27:35.953763 30317 log.cpp:702] Attempting to truncate the log to 1
I1207 22:27:35.954022 30317 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I1207 22:27:35.954515 30316 registrar.cpp:370] Successfully recovered registrar
I1207 22:27:35.955001 30316 master.cpp:1450] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I1207 22:27:35.955236 30316 hierarchical.cpp:181] Skipping recovery of hierarchical allocator: nothing to recover
I1207 22:27:35.956590 30325 replica.cpp:538] Replica received write request for position 2 from (11296)@172.17.0.5:58099
I1207 22:27:35.983829 30325 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 27.148875ms
I1207 22:27:35.983927 30325 replica.cpp:713] Persisted action at 2
I1207 22:27:35.985357 30325 replica.cpp:692] Replica received learned notice for position 2 from @0.0.0.0:0
I1207 22:27:36.008911 30325 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.494848ms
I1207 22:27:36.009063 30325 leveldb.cpp:399] Deleting ~1 keys from leveldb took 74929ns
I1207 22:27:36.009090 30325 replica.cpp:713] Persisted action at 2
I1207 22:27:36.009124 30325 replica.cpp:698] Replica learned TRUNCATE action at position 2
I1207 22:27:36.018368 30296 scheduler.cpp:154] Version: 0.27.0
I1207 22:27:36.019350 30325 scheduler.cpp:236] New master detected at master@172.17.0.5:58099
I1207 22:27:36.020905 30325 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.5:58099
I1207 22:27:36.025128 30321 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1207 22:27:36.025220 30321 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:56618
I1207 22:27:36.025624 30321 master.cpp:1902] Received subscription request for HTTP framework 'default'
I1207 22:27:36.025699 30321 master.cpp:1679] Authorizing framework principal 'test-principal' to receive offers for role '*'
I1207 22:27:36.026386 30321 master.cpp:1994] Subscribing framework 'default' with checkpointing disabled and capabilities [  ]
I1207 22:27:36.026973 30321 hierarchical.cpp:267] Added framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
I1207 22:27:36.027042 30321 hierarchical.cpp:1317] No resources available to allocate!
I1207 22:27:36.027075 30321 hierarchical.cpp:1410] No inverse offers to send out!
I1207 22:27:36.027097 30321 hierarchical.cpp:1063] Performed allocation for 0 slaves in 99824ns
I1207 22:27:36.027209 30322 master.hpp:1590] Sending heartbeat to e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
I1207 22:27:36.031739 30327 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.5:58099
I1207 22:27:36.032542 30320 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.5:58099
I1207 22:27:36.033051 30320 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I1207 22:27:36.033721 30328 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.5:58099
I1207 22:27:36.037406 30315 process.cpp:3077] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I1207 22:27:36.037473 30315 http.cpp:336] HTTP POST for /master/api/v1/scheduler from 172.17.0.5:56619
I1207 22:27:36.037760 30315 master.cpp:2649] Processing REQUEST call for framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000 (default)
I1207 22:27:36.038022 30315 hierarchical.cpp:562] Received resource request from framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
I1207 22:27:36.038554 30296 master.cpp:941] Master terminating
I1207 22:27:36.038806 30324 hierarchical.cpp:308] Removed framework e2d75c25-5903-4d9f-a2b6-d5f587b67977-0000
E1207 22:27:36.043074 30317 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[       OK ] ContentType/SchedulerTest.Request/1 (617 ms)
[----------] 22 tests from ContentType/SchedulerTest (18620 ms total)

[----------] Global test environment tear-down
[==========] 879 tests from 115 test cases ran. (1039939 ms total)
[  PASSED  ] 832 tests.
[  FAILED  ] 47 tests, listed below:
[  FAILED  ] MonitorIntegrationTest.RunningExecutor
[  FAILED  ] ReservationEndpointsTest.ReserveAvailableAndOfferedResources
[  FAILED  ] ReservationEndpointsTest.UnreserveAvailableAndOfferedResources
[  FAILED  ] ReservationTest.ACLMultipleOperations
[  FAILED  ] SlaveRecoveryTest/0.RecoverSlaveState, where TypeParam = mesos::internal::slave::MesosContainerizer
[  FAILED  ] SlaveRecoveryTest/0.MasterFailover, where TypeParam = mesos::internal::slave::MesosContainerizer
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndFetch
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreAndFetch
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndStoreFailAndFetch
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndFetch
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndExpunge
[  FAILED  ] InMemoryStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[  FAILED  ] InMemoryStateTest.Names
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndFetch
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreAndFetch
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndStoreFailAndFetch
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndFetch
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndExpunge
[  FAILED  ] LevelDBStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[  FAILED  ] LevelDBStateTest.Names
[  FAILED  ] LogStateTest.FetchAndStoreAndFetch
[  FAILED  ] LogStateTest.FetchAndStoreAndStoreAndFetch
[  FAILED  ] LogStateTest.FetchAndStoreAndStoreFailAndFetch
[  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndFetch
[  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndExpunge
[  FAILED  ] LogStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[  FAILED  ] LogStateTest.Names
[  FAILED  ] LogStateTest.Timeout
[  FAILED  ] LogStateTest.Diff
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndFetch
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreAndFetch
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndStoreFailAndFetch
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndFetch
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndExpunge
[  FAILED  ] ZooKeeperStateTest.FetchAndStoreAndExpungeAndStoreAndFetch
[  FAILED  ] ZooKeeperStateTest.Names
[  FAILED  ] Strict/RegistrarTest.Recover/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Recover/1, where GetParam() = true
[  FAILED  ] Strict/RegistrarTest.Admit/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Admit/1, where GetParam() = true
[  FAILED  ] Strict/RegistrarTest.Readmit/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Readmit/1, where GetParam() = true
[  FAILED  ] Strict/RegistrarTest.Remove/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Remove/1, where GetParam() = true
[  FAILED  ] Strict/RegistrarTest.Bootstrap/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Abort/0, where GetParam() = false
[  FAILED  ] Strict/RegistrarTest.Abort/1, where GetParam() = true

47 FAILED TESTS
  YOU HAVE 7 DISABLED TESTS

make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.27.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.27.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1449523283-3961
Untagged: mesos-1449523283-3961:latest
Deleted: bf1d0236000512633b0d52659e90345e0482c895b74aaa41d8cf335829ffa5b9
Deleted: 8c6d8a401155baa8697fd8ed4b18f21fd59f80ac55761caaf70e6203e5eaa6e5
Deleted: d8d6a742324ebdb91557481cd1f4bfed2fd9c3d8169eace16e7650f4fd9843ae
Deleted: 6e36632fa53927dc9930d6c6a2b34861f5ab01c19c846f16bdbbe2e229d533b5
Deleted: e56d47bb8d1394f6bbb5cff2a576dcecf13e0b60d2639fdfb6189e48eebbb8f9
Deleted: af62f49a1ef072869b9f318d3aa4be80391af33c0d11abed74069bf03258a519
Deleted: 40ea831bd69725cb46f301728c206870eb69012f228b7601679d79a406433e31
Deleted: 7f29cce7839475e6e6f480df46a3b809b7caad744d4b261f62115b9ef5603ede
Deleted: d7f8b3b948c9407f5edeadb252119d784d4fa4925e0aff60514967290afd77f5
Deleted: 1a91b42fcc552dd54f94e024600f3fef75879891ef70d4a3d0d88c12d2fcd1ce
Deleted: b377e8a2e554faa238159b0713a9a55ff6de84b2d7ba8871548ee57064621db3
Deleted: 5ba54d6b1c4209b886733b3fd4892473b88205721ea4d607c379e039638129e7
Deleted: 293adb3eee0890efd7c6deb8ee0b6328de0b3419da9ae8bf0b512c79f7134fda

Error handling URL https://reviews.apache.org/api/review-requests/40759/reviews/: INTERNAL SERVER ERROR
git clean -fd
git reset --hard 517e84669790f833f6cf0e7911968a3591c93419

Build step 'Execute shell' marked build as failure

Build failed in Jenkins: mesos-reviewbot #10068

Posted by Apache Jenkins Server <je...@builds.apache.org>.

See <https://builds.apache.org/job/mesos-reviewbot/10068/>

------------------------------------------
[...truncated 7966 lines...]
rm -f tests/containerizer/.dirstamp
rm -f uri/.deps/.dirstamp
rm -f uri/.dirstamp
rm -rf version/.libs version/_libs
rm -f uri/fetchers/.deps/.dirstamp
rm -rf watcher/.libs watcher/_libs
rm -f uri/fetchers/.dirstamp
rm -rf zookeeper/.libs zookeeper/_libs
rm -f usage/.deps/.dirstamp
rm -f usage/.dirstamp
rm -f v1/.deps/.dirstamp
rm -f v1/.dirstamp
rm -f version/.deps/.dirstamp
rm -f version/.dirstamp
rm -f watcher/.deps/.dirstamp
rm -f watcher/.dirstamp
rm -f zookeeper/.deps/.dirstamp
rm -f zookeeper/.dirstamp
rm -f tests/common/*.o
rm -f tests/containerizer/*.o
rm -f uri/*.o
rm -f uri/*.lo
rm -f uri/fetchers/*.o
rm -f uri/fetchers/*.lo
rm -f usage/*.o
rm -f usage/*.lo
rm -f v1/*.o
rm -f v1/*.lo
rm -f version/*.o
rm -f version/*.lo
rm -f watcher/*.o
rm -f watcher/*.lo
rm -f zookeeper/*.o
rm -f zookeeper/*.lo
rm -rf ../include/mesos/.deps ../include/mesos/authentication/.deps ../include/mesos/authorizer/.deps ../include/mesos/containerizer/.deps ../include/mesos/executor/.deps ../include/mesos/fetcher/.deps ../include/mesos/maintenance/.deps ../include/mesos/master/.deps ../include/mesos/module/.deps ../include/mesos/quota/.deps ../include/mesos/scheduler/.deps ../include/mesos/slave/.deps ../include/mesos/uri/.deps ../include/mesos/v1/.deps ../include/mesos/v1/executor/.deps ../include/mesos/v1/scheduler/.deps authentication/cram_md5/.deps authorizer/.deps authorizer/local/.deps cli/.deps common/.deps docker/.deps examples/.deps exec/.deps files/.deps hdfs/.deps health-check/.deps hook/.deps internal/.deps java/jni/.deps jvm/.deps jvm/org/apache/.deps launcher/.deps linux/.deps linux/routing/.deps linux/routing/diagnosis/.deps linux/routing/filter/.deps linux/routing/link/.deps linux/routing/queueing/.deps local/.deps log/.deps log/tool/.deps logging/.deps master/.deps master/allocator/.deps master/allocator/mesos/.deps master/allocator/sorter/drf/.deps messages/.deps module/.deps sched/.deps scheduler/.deps slave/.deps slave/containerizer/.deps slave/containerizer/mesos/.deps slave/containerizer/mesos/isolators/cgroups/.deps slave/containerizer/mesos/isolators/filesystem/.deps slave/containerizer/mesos/isolators/namespaces/.deps slave/containerizer/mesos/isolators/network/.deps slave/containerizer/mesos/isolators/posix/.deps slave/containerizer/mesos/provisioner/.deps slave/containerizer/mesos/provisioner/appc/.deps slave/containerizer/mesos/provisioner/backends/.deps slave/containerizer/mesos/provisioner/docker/.deps slave/qos_controllers/.deps slave/resource_estimators/.deps state/.deps tests/.deps tests/common/.deps tests/containerizer/.deps uri/.deps uri/fetchers/.deps usage/.deps v1/.deps version/.deps watcher/.deps zookeeper/.deps
rm -f Makefile
make[2]: Leaving directory `<https://builds.apache.org/job/mesos-reviewbot/ws/mesos-0.27.0/_build/src'>
rm -f config.status config.cache config.log configure.lineno config.status.lineno
rm -f Makefile
make[1]: Leaving directory `<https://builds.apache.org/job/mesos-reviewbot/ws/mesos-0.27.0/_build'>
if test -d "mesos-0.27.0"; then find "mesos-0.27.0" -type d ! -perm -200 -exec chmod u+w {} ';' && rm -rf "mesos-0.27.0" || { sleep 5 && rm -rf "mesos-0.27.0"; }; else :; fi
==============================================
mesos-0.27.0 archives ready for distribution: 
mesos-0.27.0.tar.gz
==============================================

real	29m27.194s
user	111m55.117s
sys	8m47.152s
+ chmod -R +w 3rdparty aclocal.m4 ar-lib autom4te.cache bin bootstrap CHANGELOG cmake CMakeLists.txt compile config.guess config.log config.lt config.status config.sub configure configure.ac depcomp Dockerfile docs Doxyfile include install-sh libtool LICENSE ltmain.sh m4 Makefile Makefile.am Makefile.in mesos-0.27.0.tar.gz mesos.pc mesos.pc.in missing mpi NOTICE README.md site src support
+ git clean -fdx
Removing .clang-format
Removing .gitignore
Removing .libs/
Removing .reviewboardrc
Removing 3rdparty/Makefile
Removing 3rdparty/Makefile.in
Removing 3rdparty/libprocess/.deps/
Removing 3rdparty/libprocess/3rdparty/.deps/
Removing 3rdparty/libprocess/3rdparty/Makefile
Removing 3rdparty/libprocess/3rdparty/Makefile.in
Removing 3rdparty/libprocess/3rdparty/gmock_sources.cc
Removing 3rdparty/libprocess/3rdparty/stout/Makefile
Removing 3rdparty/libprocess/3rdparty/stout/Makefile.in
Removing 3rdparty/libprocess/3rdparty/stout/aclocal.m4
Removing 3rdparty/libprocess/3rdparty/stout/autom4te.cache/
Removing 3rdparty/libprocess/3rdparty/stout/config.log
Removing 3rdparty/libprocess/3rdparty/stout/config.status
Removing 3rdparty/libprocess/3rdparty/stout/configure
Removing 3rdparty/libprocess/3rdparty/stout/include/Makefile
Removing 3rdparty/libprocess/3rdparty/stout/include/Makefile.in
Removing 3rdparty/libprocess/3rdparty/stout/missing
Removing 3rdparty/libprocess/Makefile
Removing 3rdparty/libprocess/Makefile.in
Removing 3rdparty/libprocess/aclocal.m4
Removing 3rdparty/libprocess/ar-lib
Removing 3rdparty/libprocess/autom4te.cache/
Removing 3rdparty/libprocess/compile
Removing 3rdparty/libprocess/config.guess
Removing 3rdparty/libprocess/config.log
Removing 3rdparty/libprocess/config.lt
Removing 3rdparty/libprocess/config.status
Removing 3rdparty/libprocess/config.sub
Removing 3rdparty/libprocess/configure
Removing 3rdparty/libprocess/depcomp
Removing 3rdparty/libprocess/include/Makefile
Removing 3rdparty/libprocess/include/Makefile.in
Removing 3rdparty/libprocess/libtool
Removing 3rdparty/libprocess/ltmain.sh
Removing 3rdparty/libprocess/m4/libtool.m4
Removing 3rdparty/libprocess/m4/ltoptions.m4
Removing 3rdparty/libprocess/m4/ltsugar.m4
Removing 3rdparty/libprocess/m4/ltversion.m4
Removing 3rdparty/libprocess/m4/lt~obsolete.m4
Removing 3rdparty/libprocess/missing
Removing Makefile
Removing Makefile.in
Removing aclocal.m4
Removing ar-lib
Removing autom4te.cache/
Removing bin/gdb-mesos-local.sh
Removing bin/gdb-mesos-master.sh
Removing bin/gdb-mesos-slave.sh
Removing bin/gdb-mesos-tests.sh
Removing bin/lldb-mesos-local.sh
Removing bin/lldb-mesos-master.sh
Removing bin/lldb-mesos-slave.sh
Removing bin/lldb-mesos-tests.sh
Removing bin/mesos-local-flags.sh
Removing bin/mesos-local.sh
Removing bin/mesos-master-flags.sh
Removing bin/mesos-master.sh
Removing bin/mesos-slave-flags.sh
Removing bin/mesos-slave.sh
Removing bin/mesos-tests-flags.sh
Removing bin/mesos-tests.sh
Removing bin/mesos.sh
Removing bin/valgrind-mesos-local.sh
Removing bin/valgrind-mesos-master.sh
Removing bin/valgrind-mesos-slave.sh
Removing bin/valgrind-mesos-tests.sh
Removing compile
Removing config.guess
Removing config.log
Removing config.lt
Removing config.status
Removing config.sub
Removing configure
Removing depcomp
Removing include/mesos/.deps/
Removing include/mesos/authentication/.deps/
Removing include/mesos/authorizer/.deps/
Removing include/mesos/containerizer/.deps/
Removing include/mesos/executor/.deps/
Removing include/mesos/fetcher/.deps/
Removing include/mesos/maintenance/.deps/
Removing include/mesos/master/.deps/
Removing include/mesos/module/.deps/
Removing include/mesos/quota/.deps/
Removing include/mesos/scheduler/.deps/
Removing include/mesos/slave/.deps/
Removing include/mesos/uri/.deps/
Removing include/mesos/v1/.deps/
Removing include/mesos/v1/executor/.deps/
Removing include/mesos/v1/scheduler/.deps/
Removing include/mesos/version.hpp
Removing install-sh
Removing libtool
Removing ltmain.sh
Removing m4/libtool.m4
Removing m4/ltoptions.m4
Removing m4/ltsugar.m4
Removing m4/ltversion.m4
Removing m4/lt~obsolete.m4
Removing mesos-0.27.0.tar.gz
Removing mesos.pc
Removing missing
Removing mpi/mpiexec-mesos
Removing src/Makefile
Removing src/Makefile.in
Removing src/authentication/cram_md5/.deps/
Removing src/authorizer/.deps/
Removing src/authorizer/local/.deps/
Removing src/cli/.deps/
Removing src/common/.deps/
Removing src/deploy/mesos-daemon.sh
Removing src/deploy/mesos-start-cluster.sh
Removing src/deploy/mesos-start-masters.sh
Removing src/deploy/mesos-start-slaves.sh
Removing src/deploy/mesos-stop-cluster.sh
Removing src/deploy/mesos-stop-masters.sh
Removing src/deploy/mesos-stop-slaves.sh
Removing src/docker/.deps/
Removing src/examples/.deps/
Removing src/examples/java/test-exception-framework
Removing src/examples/java/test-executor
Removing src/examples/java/test-framework
Removing src/examples/java/test-log
Removing src/examples/java/test-multiple-executors-framework
Removing src/examples/python/test-containerizer
Removing src/examples/python/test-executor
Removing src/examples/python/test-framework
Removing src/exec/.deps/
Removing src/files/.deps/
Removing src/hdfs/.deps/
Removing src/health-check/.deps/
Removing src/hook/.deps/
Removing src/internal/.deps/
Removing src/java/generated/org/apache/mesos/MesosNativeLibrary.java
Removing src/java/jni/.deps/
Removing src/java/mesos.pom
Removing src/jvm/.deps/
Removing src/jvm/org/apache/.deps/
Removing src/launcher/.deps/
Removing src/linux/.deps/
Removing src/linux/routing/.deps/
Removing src/linux/routing/diagnosis/.deps/
Removing src/linux/routing/filter/.deps/
Removing src/linux/routing/link/.deps/
Removing src/linux/routing/queueing/.deps/
Removing src/local/.deps/
Removing src/log/.deps/
Removing src/log/tool/.deps/
Removing src/logging/.deps/
Removing src/master/.deps/
Removing src/master/allocator/.deps/
Removing src/master/allocator/mesos/.deps/
Removing src/master/allocator/sorter/drf/.deps/
Removing src/messages/.deps/
Removing src/module/.deps/
Removing src/python/cli/setup.py
Removing src/python/interface/setup.py
Removing src/python/native/ext_modules.py
Removing src/python/native/setup.py
Removing src/python/setup.py
Removing src/sched/.deps/
Removing src/scheduler/.deps/
Removing src/slave/.deps/
Removing src/slave/containerizer/.deps/
Removing src/slave/containerizer/mesos/.deps/
Removing src/slave/containerizer/mesos/isolators/cgroups/.deps/
Removing src/slave/containerizer/mesos/isolators/filesystem/.deps/
Removing src/slave/containerizer/mesos/isolators/namespaces/.deps/
Removing src/slave/containerizer/mesos/isolators/network/.deps/
Removing src/slave/containerizer/mesos/isolators/posix/.deps/
Removing src/slave/containerizer/mesos/provisioner/.deps/
Removing src/slave/containerizer/mesos/provisioner/appc/.deps/
Removing src/slave/containerizer/mesos/provisioner/backends/.deps/
Removing src/slave/containerizer/mesos/provisioner/docker/.deps/
Removing src/slave/qos_controllers/.deps/
Removing src/slave/resource_estimators/.deps/
Removing src/state/.deps/
Removing src/tests/.deps/
Removing src/tests/common/.deps/
Removing src/tests/containerizer/.deps/
Removing src/uri/.deps/
Removing src/uri/fetchers/.deps/
Removing src/usage/.deps/
Removing src/v1/.deps/
Removing src/version/.deps/
Removing src/watcher/.deps/
Removing src/zookeeper/.deps/
+ git reset --hard HEAD
HEAD is now at 517e846 Added test for the Subscribe->Subscribed workflow for the Executor
+ date
Mon Dec  7 17:55:44 UTC 2015
+ set +x
Build timed out (after 180 minutes). Marking the build as failed.
Build was aborted

Build failed in Jenkins: mesos-reviewbot #10067

Posted by Apache Jenkins Server <je...@builds.apache.org>.

See <https://builds.apache.org/job/mesos-reviewbot/10067/>

------------------------------------------
[...truncated 7966 lines...]
rm -f tests/*.o
rm -rf uri/.libs uri/_libs
rm -f usage/.deps/.dirstamp
rm -rf uri/fetchers/.libs uri/fetchers/_libs
rm -f usage/.dirstamp
rm -rf usage/.libs usage/_libs
rm -f v1/.deps/.dirstamp
rm -f v1/.dirstamp
rm -rf v1/.libs v1/_libs
rm -f version/.deps/.dirstamp
rm -rf version/.libs version/_libs
rm -f version/.dirstamp
rm -rf watcher/.libs watcher/_libs
rm -f watcher/.deps/.dirstamp
rm -f watcher/.dirstamp
rm -rf zookeeper/.libs zookeeper/_libs
rm -f zookeeper/.deps/.dirstamp
rm -f zookeeper/.dirstamp
rm -f tests/common/*.o
rm -f tests/containerizer/*.o
rm -f uri/*.o
rm -f uri/*.lo
rm -f uri/fetchers/*.o
rm -f uri/fetchers/*.lo
rm -f usage/*.o
rm -f usage/*.lo
rm -f v1/*.o
rm -f v1/*.lo
rm -f version/*.o
rm -f version/*.lo
rm -f watcher/*.o
rm -f watcher/*.lo
rm -f zookeeper/*.o
rm -f zookeeper/*.lo
rm -rf ../include/mesos/.deps ../include/mesos/authentication/.deps ../include/mesos/authorizer/.deps ../include/mesos/containerizer/.deps ../include/mesos/executor/.deps ../include/mesos/fetcher/.deps ../include/mesos/maintenance/.deps ../include/mesos/master/.deps ../include/mesos/module/.deps ../include/mesos/quota/.deps ../include/mesos/scheduler/.deps ../include/mesos/slave/.deps ../include/mesos/uri/.deps ../include/mesos/v1/.deps ../include/mesos/v1/executor/.deps ../include/mesos/v1/scheduler/.deps authentication/cram_md5/.deps authorizer/.deps authorizer/local/.deps cli/.deps common/.deps docker/.deps examples/.deps exec/.deps files/.deps hdfs/.deps health-check/.deps hook/.deps internal/.deps java/jni/.deps jvm/.deps jvm/org/apache/.deps launcher/.deps linux/.deps linux/routing/.deps linux/routing/diagnosis/.deps linux/routing/filter/.deps linux/routing/link/.deps linux/routing/queueing/.deps local/.deps log/.deps log/tool/.deps logging/.deps master/.deps master/allocator/.deps master/allocator/mesos/.deps master/allocator/sorter/drf/.deps messages/.deps module/.deps sched/.deps scheduler/.deps slave/.deps slave/containerizer/.deps slave/containerizer/mesos/.deps slave/containerizer/mesos/isolators/cgroups/.deps slave/containerizer/mesos/isolators/filesystem/.deps slave/containerizer/mesos/isolators/namespaces/.deps slave/containerizer/mesos/isolators/network/.deps slave/containerizer/mesos/isolators/posix/.deps slave/containerizer/mesos/provisioner/.deps slave/containerizer/mesos/provisioner/appc/.deps slave/containerizer/mesos/provisioner/backends/.deps slave/containerizer/mesos/provisioner/docker/.deps slave/qos_controllers/.deps slave/resource_estimators/.deps state/.deps tests/.deps tests/common/.deps tests/containerizer/.deps uri/.deps uri/fetchers/.deps usage/.deps v1/.deps version/.deps watcher/.deps zookeeper/.deps
rm -f Makefile
make[2]: Leaving directory `<https://builds.apache.org/job/mesos-reviewbot/ws/mesos-0.27.0/_build/src'>
rm -f config.status config.cache config.log configure.lineno config.status.lineno
rm -f Makefile
make[1]: Leaving directory `<https://builds.apache.org/job/mesos-reviewbot/ws/mesos-0.27.0/_build'>
if test -d "mesos-0.27.0"; then find "mesos-0.27.0" -type d ! -perm -200 -exec chmod u+w {} ';' && rm -rf "mesos-0.27.0" || { sleep 5 && rm -rf "mesos-0.27.0"; }; else :; fi
==============================================
mesos-0.27.0 archives ready for distribution: 
mesos-0.27.0.tar.gz
==============================================

real	119m4.924s
user	145m27.519s
sys	14m18.015s
+ chmod -R +w 3rdparty aclocal.m4 ar-lib autom4te.cache bin bootstrap CHANGELOG cmake CMakeLists.txt compile config.guess config.log config.lt config.status config.sub configure configure.ac depcomp Dockerfile docs Doxyfile include install-sh libtool LICENSE ltmain.sh m4 Makefile Makefile.am Makefile.in mesos-0.27.0.tar.gz mesos.pc mesos.pc.in missing mpi NOTICE README.md site src support
+ git clean -fdx
Removing .clang-format
Removing .gitignore
Removing .libs/
Removing .reviewboardrc
Removing 3rdparty/Makefile
Removing 3rdparty/Makefile.in
Removing 3rdparty/libprocess/.deps/
Removing 3rdparty/libprocess/3rdparty/.deps/
Removing 3rdparty/libprocess/3rdparty/Makefile
Removing 3rdparty/libprocess/3rdparty/Makefile.in
Removing 3rdparty/libprocess/3rdparty/gmock_sources.cc
Removing 3rdparty/libprocess/3rdparty/stout/Makefile
Removing 3rdparty/libprocess/3rdparty/stout/Makefile.in
Removing 3rdparty/libprocess/3rdparty/stout/aclocal.m4
Removing 3rdparty/libprocess/3rdparty/stout/autom4te.cache/
Removing 3rdparty/libprocess/3rdparty/stout/config.log
Removing 3rdparty/libprocess/3rdparty/stout/config.status
Removing 3rdparty/libprocess/3rdparty/stout/configure
Removing 3rdparty/libprocess/3rdparty/stout/include/Makefile
Removing 3rdparty/libprocess/3rdparty/stout/include/Makefile.in
Removing 3rdparty/libprocess/3rdparty/stout/missing
Removing 3rdparty/libprocess/Makefile
Removing 3rdparty/libprocess/Makefile.in
Removing 3rdparty/libprocess/aclocal.m4
Removing 3rdparty/libprocess/ar-lib
Removing 3rdparty/libprocess/autom4te.cache/
Removing 3rdparty/libprocess/compile
Removing 3rdparty/libprocess/config.guess
Removing 3rdparty/libprocess/config.log
Removing 3rdparty/libprocess/config.lt
Removing 3rdparty/libprocess/config.status
Removing 3rdparty/libprocess/config.sub
Removing 3rdparty/libprocess/configure
Removing 3rdparty/libprocess/depcomp
Removing 3rdparty/libprocess/include/Makefile
Removing 3rdparty/libprocess/include/Makefile.in
Removing 3rdparty/libprocess/libtool
Removing 3rdparty/libprocess/ltmain.sh
Removing 3rdparty/libprocess/m4/libtool.m4
Removing 3rdparty/libprocess/m4/ltoptions.m4
Removing 3rdparty/libprocess/m4/ltsugar.m4
Removing 3rdparty/libprocess/m4/ltversion.m4
Removing 3rdparty/libprocess/m4/lt~obsolete.m4
Removing 3rdparty/libprocess/missing
Removing Makefile
Removing Makefile.in
Removing aclocal.m4
Removing ar-lib
Removing autom4te.cache/
Removing bin/gdb-mesos-local.sh
Removing bin/gdb-mesos-master.sh
Removing bin/gdb-mesos-slave.sh
Removing bin/gdb-mesos-tests.sh
Removing bin/lldb-mesos-local.sh
Removing bin/lldb-mesos-master.sh
Removing bin/lldb-mesos-slave.sh
Removing bin/lldb-mesos-tests.sh
Removing bin/mesos-local-flags.sh
Removing bin/mesos-local.sh
Removing bin/mesos-master-flags.sh
Removing bin/mesos-master.sh
Removing bin/mesos-slave-flags.sh
Removing bin/mesos-slave.sh
Removing bin/mesos-tests-flags.sh
Removing bin/mesos-tests.sh
Removing bin/mesos.sh
Removing bin/valgrind-mesos-local.sh
Removing bin/valgrind-mesos-master.sh
Removing bin/valgrind-mesos-slave.sh
Removing bin/valgrind-mesos-tests.sh
Removing compile
Removing config.guess
Removing config.log
Removing config.lt
Removing config.status
Removing config.sub
Removing configure
Removing depcomp
Removing include/mesos/.deps/
Removing include/mesos/authentication/.deps/
Removing include/mesos/authorizer/.deps/
Removing include/mesos/containerizer/.deps/
Removing include/mesos/executor/.deps/
Removing include/mesos/fetcher/.deps/
Removing include/mesos/maintenance/.deps/
Removing include/mesos/master/.deps/
Removing include/mesos/module/.deps/
Removing include/mesos/quota/.deps/
Removing include/mesos/scheduler/.deps/
Removing include/mesos/slave/.deps/
Removing include/mesos/uri/.deps/
Removing include/mesos/v1/.deps/
Removing include/mesos/v1/executor/.deps/
Removing include/mesos/v1/scheduler/.deps/
Removing include/mesos/version.hpp
Removing install-sh
Removing libtool
Removing ltmain.sh
Removing m4/libtool.m4
Removing m4/ltoptions.m4
Removing m4/ltsugar.m4
Removing m4/ltversion.m4
Removing m4/lt~obsolete.m4
Removing mesos-0.27.0.tar.gz
Removing mesos.pc
Removing missing
Removing mpi/mpiexec-mesos
Removing src/Makefile
Removing src/Makefile.in
Removing src/authentication/cram_md5/.deps/
Removing src/authorizer/.deps/
Removing src/authorizer/local/.deps/
Removing src/cli/.deps/
Removing src/common/.deps/
Removing src/deploy/mesos-daemon.sh
Removing src/deploy/mesos-start-cluster.sh
Removing src/deploy/mesos-start-masters.sh
Removing src/deploy/mesos-start-slaves.sh
Removing src/deploy/mesos-stop-cluster.sh
Removing src/deploy/mesos-stop-masters.sh
Removing src/deploy/mesos-stop-slaves.sh
Removing src/docker/.deps/
Removing src/examples/.deps/
Removing src/examples/java/test-exception-framework
Removing src/examples/java/test-executor
Removing src/examples/java/test-framework
Removing src/examples/java/test-log
Removing src/examples/java/test-multiple-executors-framework
Removing src/examples/python/test-containerizer
Removing src/examples/python/test-executor
Removing src/examples/python/test-framework
Removing src/exec/.deps/
Removing src/files/.deps/
Removing src/hdfs/.deps/
Removing src/health-check/.deps/
Removing src/hook/.deps/
Removing src/internal/.deps/
Removing src/java/generated/org/apache/mesos/MesosNativeLibrary.java
Removing src/java/jni/.deps/
Removing src/java/mesos.pom
Removing src/jvm/.deps/
Removing src/jvm/org/apache/.deps/
Removing src/launcher/.deps/
Removing src/linux/.deps/
Removing src/linux/routing/.deps/
Removing src/linux/routing/diagnosis/.deps/
Removing src/linux/routing/filter/.deps/
Removing src/linux/routing/link/.deps/
Removing src/linux/routing/queueing/.deps/
Removing src/local/.deps/
Removing src/log/.deps/
Removing src/log/tool/.deps/
Removing src/logging/.deps/
Removing src/master/.deps/
Removing src/master/allocator/.deps/
Removing src/master/allocator/mesos/.deps/
Removing src/master/allocator/sorter/drf/.deps/
Removing src/messages/.deps/
Removing src/module/.deps/
Removing src/python/cli/setup.py
Removing src/python/interface/setup.py
Removing src/python/native/ext_modules.py
Removing src/python/native/setup.py
Removing src/python/setup.py
Removing src/sched/.deps/
Removing src/scheduler/.deps/
Removing src/slave/.deps/
Removing src/slave/containerizer/.deps/
Removing src/slave/containerizer/mesos/.deps/
Removing src/slave/containerizer/mesos/isolators/cgroups/.deps/
Removing src/slave/containerizer/mesos/isolators/filesystem/.deps/
Removing src/slave/containerizer/mesos/isolators/namespaces/.deps/
Removing src/slave/containerizer/mesos/isolators/network/.deps/
Removing src/slave/containerizer/mesos/isolators/posix/.deps/
Removing src/slave/containerizer/mesos/provisioner/.deps/
Removing src/slave/containerizer/mesos/provisioner/appc/.deps/
Removing src/slave/containerizer/mesos/provisioner/backends/.deps/
Removing src/slave/containerizer/mesos/provisioner/docker/.deps/
Removing src/slave/qos_controllers/.deps/
Removing src/slave/resource_estimators/.deps/
Removing src/state/.deps/
Removing src/tests/.deps/
Removing src/tests/common/.deps/
Removing src/tests/containerizer/.deps/
Removing src/uri/.deps/
Removing src/uri/fetchers/.deps/
Removing src/usage/.deps/
Removing src/v1/.deps/
Removing src/version/.deps/
Removing src/watcher/.deps/
Removing src/zookeeper/.deps/
+ git reset --hard HEAD
HEAD is now at 517e846 Added test for the Subscribe->Subscribed workflow for the Executor
+ date
Mon Dec  7 16:19:19 UTC 2015
+ set +x
Build timed out (after 180 minutes). Marking the build as failed.
Build was aborted