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/08/13 21:34:48 UTC
Build failed in Jenkins: Mesos » gcc,--verbose,centos:7,docker||Hadoop #704
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,OS=centos%3A7,label_exp=docker%7C%7CHadoop/704/changes>
Changes:
[vinodkone] Added Heartbeater to master to send periodic heartbeats to HTTP schedulers.
[vinodkone] Fixed style issue in master/main.cpp.
------------------------------------------
[...truncated 84820 lines...]
I0813 19:35:33.180716 27961 registrar.cpp:488] Successfully updated the 'registry' in 7.37408ms
I0813 19:35:33.180862 27961 registrar.cpp:374] Successfully recovered registrar
I0813 19:35:33.181066 27967 log.cpp:704] Attempting to truncate the log to 1
I0813 19:35:33.181197 27963 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
I0813 19:35:33.181247 27971 master.cpp:1334] Recovered 0 slaves from the Registry (132B) ; allowing 10mins for slaves to re-register
I0813 19:35:33.182263 27971 replica.cpp:511] Replica received write request for position 2
I0813 19:35:33.182689 27971 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 385700ns
I0813 19:35:33.182718 27971 replica.cpp:679] Persisted action at 2
I0813 19:35:33.183531 27970 replica.cpp:658] Replica received learned notice for position 2
I0813 19:35:33.183948 27970 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 387570ns
I0813 19:35:33.184039 27970 leveldb.cpp:401] Deleting ~1 keys from leveldb took 61194ns
I0813 19:35:33.184072 27970 replica.cpp:679] Persisted action at 2
I0813 19:35:33.184099 27970 replica.cpp:664] Replica learned TRUNCATE action at position 2
I0813 19:35:33.192210 27937 scheduler.cpp:157] Version: 0.24.0
I0813 19:35:33.195602 27959 scheduler.cpp:272] New master detected at master@172.17.2.6:57874
I0813 19:35:33.199416 27968 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0813 19:35:33.199842 27968 master.cpp:1782] Received subscription request for HTTP framework 'default'
I0813 19:35:33.200068 27968 master.cpp:1563] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0813 19:35:33.200547 27968 master.cpp:1874] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0813 19:35:33.201159 27965 hierarchical.hpp:391] Added framework 20150813-193533-100798892-57874-27937-0000
I0813 19:35:33.201202 27965 hierarchical.hpp:1008] No resources available to allocate!
I0813 19:35:33.201225 27965 hierarchical.hpp:908] Performed allocation for 0 slaves in 33434ns
I0813 19:35:33.201437 27971 master.hpp:1305] Sending heartbeat to 20150813-193533-100798892-57874-27937-0000
../../src/tests/scheduler_tests.cpp:168: Failure
Value of: event.get().type()
Actual: HEARTBEAT
Expected: Event::SUBSCRIBED
Which is: SUBSCRIBED
../../src/tests/scheduler_tests.cpp:169: Failure
Value of: event.get().subscribed().framework_id()
Actual:
Expected: id
I0813 19:35:33.204236 27965 master.cpp:859] Master terminating
Which is: 20150813-193533-100798892-57874-27937-0000
I0813 19:35:33.204521 27966 hierarchical.hpp:428] Removed framework 20150813-193533-100798892-57874-27937-0000
I0813 19:35:33.204538 27970 scheduler.cpp:381] Ignoring event from old stale connection
I0813 19:35:33.206183 27972 process.cpp:2319] Returning '404 Not Found' for '/master/api/v1/scheduler'
I0813 19:35:33.207617 27968 scheduler.cpp:421] Enqueuing locally injected event ERROR
[ FAILED ] SchedulerTest.Subscribe (65 ms)
[ RUN ] SchedulerTest.TaskRunning
Using temporary directory '/tmp/SchedulerTest_TaskRunning_4ccDyW'
I0813 19:35:33.214772 27937 leveldb.cpp:176] Opened db in 2.658322ms
I0813 19:35:33.215837 27937 leveldb.cpp:183] Compacted db in 1.028959ms
I0813 19:35:33.215894 27937 leveldb.cpp:198] Created db iterator in 16092ns
I0813 19:35:33.215915 27937 leveldb.cpp:204] Seeked to beginning of db in 1643ns
I0813 19:35:33.215930 27937 leveldb.cpp:273] Iterated through 0 keys in the db in 203ns
I0813 19:35:33.215962 27937 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0813 19:35:33.216523 27967 recover.cpp:449] Starting replica recovery
I0813 19:35:33.216802 27967 recover.cpp:475] Replica is in EMPTY status
I0813 19:35:33.217892 27957 replica.cpp:641] Replica in EMPTY status received a broadcasted recover request
I0813 19:35:33.218382 27964 recover.cpp:195] Received a recover response from a replica in EMPTY status
I0813 19:35:33.218955 27959 recover.cpp:566] Updating replica status to STARTING
I0813 19:35:33.220217 27960 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 678282ns
I0813 19:35:33.220257 27960 replica.cpp:323] Persisted replica status to STARTING
I0813 19:35:33.220314 27969 master.cpp:378] Master 20150813-193533-100798892-57874-27937 (bbaeb86d2a9a) started on 172.17.2.6:57874
I0813 19:35:33.220499 27956 recover.cpp:475] Replica is in STARTING status
I0813 19:35:33.220343 27969 master.cpp:380] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --credentials="/tmp/SchedulerTest_TaskRunning_4ccDyW/credentials" --framework_sorter="drf" --help="false" --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.24.0/_inst/share/mesos/webui" --work_dir="/tmp/SchedulerTest_TaskRunning_4ccDyW/master" --zk_session_timeout="10secs"
I0813 19:35:33.220685 27969 master.cpp:427] Master allowing unauthenticated frameworks to register
I0813 19:35:33.220700 27969 master.cpp:430] Master only allowing authenticated slaves to register
I0813 19:35:33.220708 27969 credentials.hpp:37] Loading credentials for authentication from '/tmp/SchedulerTest_TaskRunning_4ccDyW/credentials'
I0813 19:35:33.221035 27969 master.cpp:469] Using default 'crammd5' authenticator
I0813 19:35:33.221180 27969 master.cpp:506] Authorization enabled
I0813 19:35:33.221422 27963 whitelist_watcher.cpp:79] No whitelist given
I0813 19:35:33.221521 27961 replica.cpp:641] Replica in STARTING status received a broadcasted recover request
I0813 19:35:33.221530 27962 hierarchical.hpp:346] Initialized hierarchical allocator process
I0813 19:35:33.222030 27965 recover.cpp:195] Received a recover response from a replica in STARTING status
I0813 19:35:33.222553 27957 recover.cpp:566] Updating replica status to VOTING
I0813 19:35:33.222849 27969 master.cpp:1524] The newly elected leader is master@172.17.2.6:57874 with id 20150813-193533-100798892-57874-27937
I0813 19:35:33.222880 27969 master.cpp:1537] Elected as the leading master!
I0813 19:35:33.222898 27969 master.cpp:1307] Recovering from registrar
I0813 19:35:33.223048 27966 registrar.cpp:311] Recovering registrar
I0813 19:35:33.223145 27958 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 396952ns
I0813 19:35:33.223196 27958 replica.cpp:323] Persisted replica status to VOTING
I0813 19:35:33.223364 27961 recover.cpp:580] Successfully joined the Paxos group
I0813 19:35:33.223636 27961 recover.cpp:464] Recover process terminated
I0813 19:35:33.224115 27968 log.cpp:661] Attempting to start the writer
I0813 19:35:33.225520 27967 replica.cpp:477] Replica received implicit promise request with proposal 1
I0813 19:35:33.225910 27967 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 355677ns
I0813 19:35:33.225935 27967 replica.cpp:345] Persisted promised to 1
I0813 19:35:33.226610 27965 coordinator.cpp:231] Coordinator attemping to fill missing position
I0813 19:35:33.228232 27967 replica.cpp:378] Replica received explicit promise request for position 0 with proposal 2
I0813 19:35:33.228632 27967 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 359071ns
I0813 19:35:33.228660 27967 replica.cpp:679] Persisted action at 0
I0813 19:35:33.230082 27964 replica.cpp:511] Replica received write request for position 0
I0813 19:35:33.230154 27964 leveldb.cpp:438] Reading position from leveldb took 36144ns
I0813 19:35:33.230584 27964 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 382887ns
I0813 19:35:33.230613 27964 replica.cpp:679] Persisted action at 0
I0813 19:35:33.231350 27957 replica.cpp:658] Replica received learned notice for position 0
I0813 19:35:33.231775 27957 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 389775ns
I0813 19:35:33.231804 27957 replica.cpp:679] Persisted action at 0
I0813 19:35:33.231827 27957 replica.cpp:664] Replica learned NOP action at position 0
I0813 19:35:33.232637 27966 log.cpp:677] Writer started with ending position 0
I0813 19:35:33.233857 27958 leveldb.cpp:438] Reading position from leveldb took 36504ns
I0813 19:35:33.237362 27967 registrar.cpp:344] Successfully fetched the registry (0B) in 14.26304ms
I0813 19:35:33.237478 27967 registrar.cpp:443] Applied 1 operations in 21490ns; attempting to update the 'registry'
I0813 19:35:33.240655 27965 log.cpp:685] Attempting to append 171 bytes to the log
I0813 19:35:33.240813 27961 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 1
I0813 19:35:33.241724 27961 replica.cpp:511] Replica received write request for position 1
I0813 19:35:33.242185 27961 leveldb.cpp:343] Persisting action (190 bytes) to leveldb took 421591ns
I0813 19:35:33.242219 27961 replica.cpp:679] Persisted action at 1
I0813 19:35:33.242921 27961 replica.cpp:658] Replica received learned notice for position 1
I0813 19:35:33.243432 27961 leveldb.cpp:343] Persisting action (192 bytes) to leveldb took 477340ns
I0813 19:35:33.243461 27961 replica.cpp:679] Persisted action at 1
I0813 19:35:33.243484 27961 replica.cpp:664] Replica learned APPEND action at position 1
I0813 19:35:33.244601 27959 registrar.cpp:488] Successfully updated the 'registry' in 7.053824ms
I0813 19:35:33.244771 27959 registrar.cpp:374] Successfully recovered registrar
I0813 19:35:33.244845 27968 log.cpp:704] Attempting to truncate the log to 1
I0813 19:35:33.245014 27957 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
I0813 19:35:33.245301 27965 master.cpp:1334] Recovered 0 slaves from the Registry (132B) ; allowing 10mins for slaves to re-register
I0813 19:35:33.245982 27970 replica.cpp:511] Replica received write request for position 2
I0813 19:35:33.246423 27970 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 339091ns
I0813 19:35:33.246454 27970 replica.cpp:679] Persisted action at 2
I0813 19:35:33.247315 27969 replica.cpp:658] Replica received learned notice for position 2
I0813 19:35:33.247689 27969 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 337001ns
I0813 19:35:33.247753 27969 leveldb.cpp:401] Deleting ~1 keys from leveldb took 33466ns
I0813 19:35:33.247777 27969 replica.cpp:679] Persisted action at 2
I0813 19:35:33.247802 27969 replica.cpp:664] Replica learned TRUNCATE action at position 2
I0813 19:35:33.262266 27959 slave.cpp:190] Slave started on 211)@172.17.2.6:57874
I0813 19:35:33.262295 27959 slave.cpp:191] Flags at startup: --authenticatee="crammd5" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SchedulerTest_TaskRunning_xHtdhR/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SchedulerTest_TaskRunning_xHtdhR/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher_dir="/mesos/mesos-0.24.0/_build/src" --logbufsecs="0" --logging_level="INFO" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_monitoring_interval="1secs" --resources="cpus:2;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --version="false" --work_dir="/tmp/SchedulerTest_TaskRunning_xHtdhR"
I0813 19:35:33.262857 27959 credentials.hpp:85] Loading credential for authentication from '/tmp/SchedulerTest_TaskRunning_xHtdhR/credential'
I0813 19:35:33.263110 27959 slave.cpp:321] Slave using credential for: test-principal
I0813 19:35:33.263519 27959 slave.cpp:354] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0813 19:35:33.263609 27959 slave.cpp:384] Slave hostname: bbaeb86d2a9a
I0813 19:35:33.263627 27959 slave.cpp:389] Slave checkpoint: true
I0813 19:35:33.263777 27937 scheduler.cpp:157] Version: 0.24.0
I0813 19:35:33.264336 27963 state.cpp:54] Recovering state from '/tmp/SchedulerTest_TaskRunning_xHtdhR/meta'
I0813 19:35:33.264853 27965 status_update_manager.cpp:202] Recovering status update manager
I0813 19:35:33.265223 27962 slave.cpp:4069] Finished recovery
I0813 19:35:33.265732 27962 slave.cpp:4226] Querying resource estimator for oversubscribable resources
I0813 19:35:33.266064 27962 slave.cpp:684] New master detected at master@172.17.2.6:57874
I0813 19:35:33.266232 27962 slave.cpp:747] Authenticating with master master@172.17.2.6:57874
I0813 19:35:33.266263 27962 slave.cpp:752] Using default CRAM-MD5 authenticatee
I0813 19:35:33.266281 27966 status_update_manager.cpp:176] Pausing sending status updates
I0813 19:35:33.266459 27962 slave.cpp:720] Detecting new master
I0813 19:35:33.266468 27965 scheduler.cpp:272] New master detected at master@172.17.2.6:57874
I0813 19:35:33.266499 27970 authenticatee.cpp:115] Creating new client SASL connection
I0813 19:35:33.266743 27962 slave.cpp:4240] Received oversubscribable resources from the resource estimator
I0813 19:35:33.266882 27957 master.cpp:4693] Authenticating slave(211)@172.17.2.6:57874
I0813 19:35:33.267040 27971 authenticator.cpp:407] Starting authentication session for crammd5_authenticatee(516)@172.17.2.6:57874
I0813 19:35:33.267624 27961 authenticator.cpp:92] Creating new server SASL connection
I0813 19:35:33.267915 27964 authenticatee.cpp:206] Received SASL authentication mechanisms: CRAM-MD5
I0813 19:35:33.267963 27964 authenticatee.cpp:232] Attempting to authenticate with mechanism 'CRAM-MD5'
I0813 19:35:33.268110 27963 authenticator.cpp:197] Received SASL authentication start
I0813 19:35:33.268185 27963 authenticator.cpp:319] Authentication requires more steps
I0813 19:35:33.268326 27961 authenticatee.cpp:252] Received SASL authentication step
I0813 19:35:33.268471 27961 authenticator.cpp:225] Received SASL authentication step
I0813 19:35:33.268515 27961 auxprop.cpp:102] Request to lookup properties for user: 'test-principal' realm: 'bbaeb86d2a9a' server FQDN: 'bbaeb86d2a9a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0813 19:35:33.268544 27961 auxprop.cpp:174] Looking up auxiliary property '*userPassword'
I0813 19:35:33.268594 27961 auxprop.cpp:174] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0813 19:35:33.268635 27961 auxprop.cpp:102] Request to lookup properties for user: 'test-principal' realm: 'bbaeb86d2a9a' server FQDN: 'bbaeb86d2a9a' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0813 19:35:33.268651 27961 auxprop.cpp:124] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0813 19:35:33.268659 27961 auxprop.cpp:124] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0813 19:35:33.268681 27961 authenticator.cpp:311] Authentication success
I0813 19:35:33.268797 27963 authenticatee.cpp:292] Authentication success
I0813 19:35:33.268831 27964 master.cpp:4723] Successfully authenticated principal 'test-principal' at slave(211)@172.17.2.6:57874
I0813 19:35:33.268884 27961 authenticator.cpp:425] Authentication session cleanup for crammd5_authenticatee(516)@172.17.2.6:57874
I0813 19:35:33.269143 27963 slave.cpp:815] Successfully authenticated with master master@172.17.2.6:57874
I0813 19:35:33.269306 27963 slave.cpp:1209] Will retry registration in 12.448516ms if necessary
I0813 19:35:33.269497 27964 master.cpp:3635] Registering slave at slave(211)@172.17.2.6:57874 (bbaeb86d2a9a) with id 20150813-193533-100798892-57874-27937-S0
I0813 19:35:33.270050 27971 registrar.cpp:443] Applied 1 operations in 84955ns; attempting to update the 'registry'
I0813 19:35:33.270140 27957 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0813 19:35:33.270468 27957 master.cpp:1782] Received subscription request for HTTP framework 'default'
I0813 19:35:33.270547 27957 master.cpp:1563] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0813 19:35:33.270908 27970 master.cpp:1874] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0813 19:35:33.271354 27964 hierarchical.hpp:391] Added framework 20150813-193533-100798892-57874-27937-0000
I0813 19:35:33.271396 27964 hierarchical.hpp:1008] No resources available to allocate!
I0813 19:35:33.271417 27964 hierarchical.hpp:908] Performed allocation for 0 slaves in 30785ns
I0813 19:35:33.271441 27961 master.hpp:1305] Sending heartbeat to 20150813-193533-100798892-57874-27937-0000
../../src/tests/scheduler_tests.cpp:227: Failure
Value of: event.get().type()
Actual: HEARTBEAT
Expected: Event::OFFERS
Which is: OFFERS
../../src/tests/scheduler_tests.cpp:228: Failure
Expected: (0) != (event.get().offers().offers().size()), actual: 0 vs 0
I0813 19:35:33.273845 27971 log.cpp:685] Attempting to append 341 bytes to the log
I0813 19:35:33.274086 27956 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 3
[libprotobuf FATAL ../3rdparty/libprocess/3rdparty/protobuf-2.5.0/src/google/protobuf/repeated_field.h:824] CHECK failed: (index) < (size()):
../../src/tests/scheduler_tests.cpp:237: Failure
Actual function call count doesn't match EXPECT_CALL(containerizer, update(_, _))...
Expected: to be called at least once
Actual: never called - unsatisfied and active
../../src/tests/scheduler_tests.cpp:233: Failure
Actual function call count doesn't match EXPECT_CALL(exec, launchTask(_, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
../../src/tests/scheduler_tests.cpp:230: Failure
Actual function call count doesn't match EXPECT_CALL(exec, registered(_, _, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
I0813 19:35:33.275491 27968 replica.cpp:511] Replica received write request for position 3
I0813 19:35:33.275732 27968 leveldb.cpp:343] Persisting action (360 bytes) to leveldb took 192002ns
I0813 19:35:33.275760 27968 replica.cpp:679] Persisted action at 3
unknown file: Failure
C++ exception with description "CHECK failed: (index) < (size()): " thrown in the test body.
I0813 19:35:33.276314 27971 master.cpp:859] Master terminating
I0813 19:35:33.276752 27961 replica.cpp:658] Replica received learned notice for position 3
I0813 19:35:33.276753 27962 hierarchical.hpp:428] Removed framework 20150813-193533-100798892-57874-27937-0000
I0813 19:35:33.277359 27961 leveldb.cpp:343] Persisting action (362 bytes) to leveldb took 569161ns
I0813 19:35:33.277393 27961 replica.cpp:679] Persisted action at 3
I0813 19:35:33.277426 27961 replica.cpp:664] Replica learned APPEND action at position 3
I0813 19:35:33.277590 27956 slave.cpp:3143] master@172.17.2.6:57874 exited
W0813 19:35:33.277619 27956 slave.cpp:3146] Master disconnected! Waiting for a new master to be elected
I0813 19:35:33.279216 27963 registrar.cpp:488] Successfully updated the 'registry' in 9.095936ms
I0813 19:35:33.279525 27970 log.cpp:704] Attempting to truncate the log to 3
I0813 19:35:33.279755 27968 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 4
I0813 19:35:33.280606 27962 replica.cpp:511] Replica received write request for position 4
I0813 19:35:33.280959 27962 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 324662ns
I0813 19:35:33.280987 27962 replica.cpp:679] Persisted action at 4
I0813 19:35:33.282237 27970 slave.cpp:1209] Will retry registration in 12.910741ms if necessary
F0813 19:35:33.282237 27937 logging.cpp:57] RAW: Pure virtual method called
I0813 19:35:33.295886 27966 slave.cpp:1209] Will retry registration in 32.823984ms if necessary
@ 0x7f1f4a25632a google::LogMessage::Fail()
@ 0x7f1f4a25b80c google::RawLog__()
@ 0x7f1f496a8a6a __cxa_pure_virtual
@ 0x8e281c mesos::internal::tests::Cluster::Slaves::shutdown()
@ 0xe2240e mesos::internal::tests::MesosTest::ShutdownSlaves()
I0813 19:35:33.329470 27957 slave.cpp:1209] Will retry registration in 141.635033ms if necessary
@ 0xe223d0 mesos::internal::tests::MesosTest::Shutdown()
@ 0xe1e9b5 mesos::internal::tests::MesosTest::TearDown()
@ 0x131c0cc testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x1316f42 testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x12f85d2 testing::Test::Run()
@ 0x12f8d0c testing::TestInfo::Run()
@ 0x12f9352 testing::TestCase::Run()
@ 0x12ffc2c testing::internal::UnitTestImpl::RunAllTests()
@ 0x131ccf1 testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x1317ab8 testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x12fe972 testing::UnitTest::Run()
@ 0xc8ec0f RUN_ALL_TESTS()
@ 0xc8e904 main
@ 0x7f1f455f5af5 __libc_start_main
@ 0x8d85e9 (unknown)
make[4]: *** [check-local] Aborted
make[4]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.24.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1439493477-27025
Untagged: mesos-1439493477-27025:latest
Deleted: f3953c2e308986a26b70bac96dd6922f0804b36eac17a030d0e71639e636218e
Deleted: eeb17af59226236cbab2061629c7706f8a20f48dd251ff41916400c8a4aefaa2
Deleted: 485385001ec338032a95f03418c863f21c9a66048dd3f23f57ae69460f18ae19
Deleted: 4f434ee98999890872181223763a372ece88fe3b810055c5337decc479cc6029
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » gcc,--verbose,centos:7,docker||Hadoop #706
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,OS=centos%3A7,label_exp=docker%7C%7CHadoop/706/changes>
Build failed in Jenkins: Mesos » gcc,--verbose,centos:7,docker||Hadoop #705
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,OS=centos%3A7,label_exp=docker%7C%7CHadoop/705/changes>
Changes:
[benjamin.mahler] Updated /scheduler endopint to use Request::acceptsMediaType.
[benjamin.mahler] Removed unnecessary std:: prefix in http_api_tests.cpp.
[benjamin.mahler] Ensure the Content-Type is set for the streaming scheduler endpoint.
[benjamin.mahler] Only accept POST requests for /scheduler endpoint.
------------------------------------------
[...truncated 84795 lines...]
I0813 19:53:55.506963 27972 replica.cpp:664] Replica learned APPEND action at position 1
I0813 19:53:55.508016 27961 registrar.cpp:488] Successfully updated the 'registry' in 7.060224ms
I0813 19:53:55.508199 27961 registrar.cpp:374] Successfully recovered registrar
I0813 19:53:55.508261 27960 log.cpp:704] Attempting to truncate the log to 1
I0813 19:53:55.508505 27964 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
I0813 19:53:55.508715 27966 master.cpp:1334] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I0813 19:53:55.509584 27958 replica.cpp:511] Replica received write request for position 2
I0813 19:53:55.510068 27958 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 420035ns
I0813 19:53:55.510102 27958 replica.cpp:679] Persisted action at 2
I0813 19:53:55.510825 27957 replica.cpp:658] Replica received learned notice for position 2
I0813 19:53:55.511214 27957 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 353868ns
I0813 19:53:55.511276 27957 leveldb.cpp:401] Deleting ~1 keys from leveldb took 33552ns
I0813 19:53:55.511299 27957 replica.cpp:679] Persisted action at 2
I0813 19:53:55.511322 27957 replica.cpp:664] Replica learned TRUNCATE action at position 2
I0813 19:53:55.519680 27938 scheduler.cpp:157] Version: 0.24.0
I0813 19:53:55.522828 27964 scheduler.cpp:272] New master detected at master@172.17.1.176:40105
I0813 19:53:55.526276 27964 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0813 19:53:55.526834 27964 master.cpp:1782] Received subscription request for HTTP framework 'default'
I0813 19:53:55.526990 27964 master.cpp:1563] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0813 19:53:55.527372 27964 master.cpp:1874] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0813 19:53:55.527912 27969 hierarchical.hpp:391] Added framework 20150813-195355-2952860076-40105-27938-0000
I0813 19:53:55.527947 27969 hierarchical.hpp:1008] No resources available to allocate!
I0813 19:53:55.527961 27969 hierarchical.hpp:908] Performed allocation for 0 slaves in 22399ns
I0813 19:53:55.528218 27963 master.hpp:1305] Sending heartbeat to 20150813-195355-2952860076-40105-27938-0000
../../src/tests/scheduler_tests.cpp:168: Failure
Value of: event.get().type()
Actual: HEARTBEAT
Expected: Event::SUBSCRIBED
Which is: SUBSCRIBED
../../src/tests/scheduler_tests.cpp:169: Failure
Value of: event.get().subscribed().framework_id()
Actual:
Expected: id
Which is: 20150813-195355-2952860076-40105-27938-0000
I0813 19:53:55.530786 27938 master.cpp:859] Master terminating
I0813 19:53:55.530926 27958 scheduler.cpp:381] Ignoring event from old stale connection
I0813 19:53:55.530935 27966 hierarchical.hpp:428] Removed framework 20150813-195355-2952860076-40105-27938-0000
I0813 19:53:55.532276 27973 process.cpp:2319] Returning '404 Not Found' for '/master/api/v1/scheduler'
I0813 19:53:55.533380 27968 scheduler.cpp:421] Enqueuing locally injected event ERROR
[ FAILED ] SchedulerTest.Subscribe (60 ms)
[ RUN ] SchedulerTest.TaskRunning
Using temporary directory '/tmp/SchedulerTest_TaskRunning_iqtLR8'
I0813 19:53:55.539235 27938 leveldb.cpp:176] Opened db in 3.057226ms
I0813 19:53:55.540305 27938 leveldb.cpp:183] Compacted db in 1.030773ms
I0813 19:53:55.540364 27938 leveldb.cpp:198] Created db iterator in 16560ns
I0813 19:53:55.540385 27938 leveldb.cpp:204] Seeked to beginning of db in 1805ns
I0813 19:53:55.540396 27938 leveldb.cpp:273] Iterated through 0 keys in the db in 210ns
I0813 19:53:55.540433 27938 replica.cpp:744] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0813 19:53:55.540966 27964 recover.cpp:449] Starting replica recovery
I0813 19:53:55.541322 27968 recover.cpp:475] Replica is in EMPTY status
I0813 19:53:55.542428 27971 replica.cpp:641] Replica in EMPTY status received a broadcasted recover request
I0813 19:53:55.542966 27964 recover.cpp:195] Received a recover response from a replica in EMPTY status
I0813 19:53:55.543443 27960 recover.cpp:566] Updating replica status to STARTING
I0813 19:53:55.544198 27971 master.cpp:378] Master 20150813-195355-2952860076-40105-27938 (e4742810468d) started on 172.17.1.176:40105
I0813 19:53:55.544247 27958 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 574362ns
I0813 19:53:55.544276 27958 replica.cpp:323] Persisted replica status to STARTING
I0813 19:53:55.544229 27971 master.cpp:380] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_slaves="true" --authenticators="crammd5" --credentials="/tmp/SchedulerTest_TaskRunning_iqtLR8/credentials" --framework_sorter="drf" --help="false" --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.24.0/_inst/share/mesos/webui" --work_dir="/tmp/SchedulerTest_TaskRunning_iqtLR8/master" --zk_session_timeout="10secs"
I0813 19:53:55.544595 27971 master.cpp:427] Master allowing unauthenticated frameworks to register
I0813 19:53:55.544636 27971 master.cpp:430] Master only allowing authenticated slaves to register
I0813 19:53:55.544657 27971 credentials.hpp:37] Loading credentials for authentication from '/tmp/SchedulerTest_TaskRunning_iqtLR8/credentials'
I0813 19:53:55.544760 27964 recover.cpp:475] Replica is in STARTING status
I0813 19:53:55.545013 27971 master.cpp:469] Using default 'crammd5' authenticator
I0813 19:53:55.545171 27971 master.cpp:506] Authorization enabled
I0813 19:53:55.545434 27965 hierarchical.hpp:346] Initialized hierarchical allocator process
I0813 19:53:55.545456 27967 whitelist_watcher.cpp:79] No whitelist given
I0813 19:53:55.545852 27963 replica.cpp:641] Replica in STARTING status received a broadcasted recover request
I0813 19:53:55.546391 27968 recover.cpp:195] Received a recover response from a replica in STARTING status
I0813 19:53:55.546840 27967 master.cpp:1524] The newly elected leader is master@172.17.1.176:40105 with id 20150813-195355-2952860076-40105-27938
I0813 19:53:55.546876 27967 master.cpp:1537] Elected as the leading master!
I0813 19:53:55.546900 27967 master.cpp:1307] Recovering from registrar
I0813 19:53:55.546996 27965 recover.cpp:566] Updating replica status to VOTING
I0813 19:53:55.547032 27962 registrar.cpp:311] Recovering registrar
I0813 19:53:55.547654 27966 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 475105ns
I0813 19:53:55.547684 27966 replica.cpp:323] Persisted replica status to VOTING
I0813 19:53:55.547828 27972 recover.cpp:580] Successfully joined the Paxos group
I0813 19:53:55.548084 27972 recover.cpp:464] Recover process terminated
I0813 19:53:55.548655 27957 log.cpp:661] Attempting to start the writer
I0813 19:53:55.550096 27966 replica.cpp:477] Replica received implicit promise request with proposal 1
I0813 19:53:55.550524 27966 leveldb.cpp:306] Persisting metadata (8 bytes) to leveldb took 390689ns
I0813 19:53:55.550554 27966 replica.cpp:345] Persisted promised to 1
I0813 19:53:55.551257 27959 coordinator.cpp:231] Coordinator attemping to fill missing position
I0813 19:53:55.552727 27967 replica.cpp:378] Replica received explicit promise request for position 0 with proposal 2
I0813 19:53:55.553220 27967 leveldb.cpp:343] Persisting action (8 bytes) to leveldb took 451756ns
I0813 19:53:55.553251 27967 replica.cpp:679] Persisted action at 0
I0813 19:53:55.554450 27971 replica.cpp:511] Replica received write request for position 0
I0813 19:53:55.554510 27971 leveldb.cpp:438] Reading position from leveldb took 29222ns
I0813 19:53:55.554920 27971 leveldb.cpp:343] Persisting action (14 bytes) to leveldb took 362635ns
I0813 19:53:55.554951 27971 replica.cpp:679] Persisted action at 0
I0813 19:53:55.555714 27962 replica.cpp:658] Replica received learned notice for position 0
I0813 19:53:55.556171 27962 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 421828ns
I0813 19:53:55.556202 27962 replica.cpp:679] Persisted action at 0
I0813 19:53:55.556223 27962 replica.cpp:664] Replica learned NOP action at position 0
I0813 19:53:55.556926 27964 log.cpp:677] Writer started with ending position 0
I0813 19:53:55.558037 27967 leveldb.cpp:438] Reading position from leveldb took 31096ns
I0813 19:53:55.561350 27965 registrar.cpp:344] Successfully fetched the registry (0B) in 14.264832ms
I0813 19:53:55.561460 27965 registrar.cpp:443] Applied 1 operations in 20039ns; attempting to update the 'registry'
I0813 19:53:55.564553 27959 log.cpp:685] Attempting to append 178 bytes to the log
I0813 19:53:55.564707 27966 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 1
I0813 19:53:55.565553 27967 replica.cpp:511] Replica received write request for position 1
I0813 19:53:55.566058 27967 leveldb.cpp:343] Persisting action (197 bytes) to leveldb took 459394ns
I0813 19:53:55.566090 27967 replica.cpp:679] Persisted action at 1
I0813 19:53:55.566865 27969 replica.cpp:658] Replica received learned notice for position 1
I0813 19:53:55.567298 27969 leveldb.cpp:343] Persisting action (199 bytes) to leveldb took 400090ns
I0813 19:53:55.567332 27969 replica.cpp:679] Persisted action at 1
I0813 19:53:55.567361 27969 replica.cpp:664] Replica learned APPEND action at position 1
I0813 19:53:55.568462 27960 registrar.cpp:488] Successfully updated the 'registry' in 6.932224ms
I0813 19:53:55.568675 27960 registrar.cpp:374] Successfully recovered registrar
I0813 19:53:55.568842 27972 log.cpp:704] Attempting to truncate the log to 1
I0813 19:53:55.569041 27960 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 2
I0813 19:53:55.569140 27964 master.cpp:1334] Recovered 0 slaves from the Registry (139B) ; allowing 10mins for slaves to re-register
I0813 19:53:55.570082 27969 replica.cpp:511] Replica received write request for position 2
I0813 19:53:55.570533 27969 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 412106ns
I0813 19:53:55.570564 27969 replica.cpp:679] Persisted action at 2
I0813 19:53:55.571297 27959 replica.cpp:658] Replica received learned notice for position 2
I0813 19:53:55.571791 27959 leveldb.cpp:343] Persisting action (18 bytes) to leveldb took 458462ns
I0813 19:53:55.571866 27959 leveldb.cpp:401] Deleting ~1 keys from leveldb took 41016ns
I0813 19:53:55.571899 27959 replica.cpp:679] Persisted action at 2
I0813 19:53:55.571923 27959 replica.cpp:664] Replica learned TRUNCATE action at position 2
I0813 19:53:55.586071 27966 slave.cpp:190] Slave started on 211)@172.17.1.176:40105
I0813 19:53:55.586103 27966 slave.cpp:191] Flags at startup: --authenticatee="crammd5" --cgroups_cpu_enable_pids_and_tids_count="false" --cgroups_enable_cfs="false" --cgroups_hierarchy="/sys/fs/cgroup" --cgroups_limit_swap="false" --cgroups_root="mesos" --container_disk_watch_interval="15secs" --containerizers="mesos" --credential="/tmp/SchedulerTest_TaskRunning_sHOP70/credential" --default_role="*" --disk_watch_interval="1mins" --docker="docker" --docker_kill_orphans="true" --docker_remove_delay="6hrs" --docker_socket="/var/run/docker.sock" --docker_stop_timeout="0ns" --enforce_container_disk_quota="false" --executor_registration_timeout="1mins" --executor_shutdown_grace_period="5secs" --fetcher_cache_dir="/tmp/SchedulerTest_TaskRunning_sHOP70/fetch" --fetcher_cache_size="2GB" --frameworks_home="" --gc_delay="1weeks" --gc_disk_headroom="0.1" --hadoop_home="" --help="false" --initialize_driver_logging="true" --isolation="posix/cpu,posix/mem" --launcher_dir="/mesos/mesos-0.24.0/_build/src" --logbufsecs="0" --logging_level="INFO" --oversubscribed_resources_interval="15secs" --perf_duration="10secs" --perf_interval="1mins" --qos_correction_interval_min="0ns" --quiet="false" --recover="reconnect" --recovery_timeout="15mins" --registration_backoff_factor="10ms" --resource_monitoring_interval="1secs" --resources="cpus:2;mem:1024;disk:1024;ports:[31000-32000]" --revocable_cpu_low_priority="true" --sandbox_directory="/mnt/mesos/sandbox" --strict="true" --switch_user="true" --version="false" --work_dir="/tmp/SchedulerTest_TaskRunning_sHOP70"
I0813 19:53:55.586782 27966 credentials.hpp:85] Loading credential for authentication from '/tmp/SchedulerTest_TaskRunning_sHOP70/credential'
I0813 19:53:55.587034 27966 slave.cpp:321] Slave using credential for: test-principal
I0813 19:53:55.587781 27966 slave.cpp:354] Slave resources: cpus(*):2; mem(*):1024; disk(*):1024; ports(*):[31000-32000]
I0813 19:53:55.587925 27966 slave.cpp:384] Slave hostname: e4742810468d
I0813 19:53:55.587956 27966 slave.cpp:389] Slave checkpoint: true
I0813 19:53:55.588335 27938 scheduler.cpp:157] Version: 0.24.0
I0813 19:53:55.588906 27961 state.cpp:54] Recovering state from '/tmp/SchedulerTest_TaskRunning_sHOP70/meta'
I0813 19:53:55.589279 27968 status_update_manager.cpp:202] Recovering status update manager
I0813 19:53:55.589629 27964 slave.cpp:4069] Finished recovery
I0813 19:53:55.590160 27964 slave.cpp:4226] Querying resource estimator for oversubscribable resources
I0813 19:53:55.590464 27957 status_update_manager.cpp:176] Pausing sending status updates
I0813 19:53:55.590483 27964 slave.cpp:684] New master detected at master@172.17.1.176:40105
I0813 19:53:55.590629 27964 slave.cpp:747] Authenticating with master master@172.17.1.176:40105
I0813 19:53:55.590656 27964 slave.cpp:752] Using default CRAM-MD5 authenticatee
I0813 19:53:55.590792 27969 scheduler.cpp:272] New master detected at master@172.17.1.176:40105
I0813 19:53:55.590884 27964 slave.cpp:720] Detecting new master
I0813 19:53:55.590941 27971 authenticatee.cpp:115] Creating new client SASL connection
I0813 19:53:55.591023 27964 slave.cpp:4240] Received oversubscribable resources from the resource estimator
I0813 19:53:55.591202 27961 master.cpp:4693] Authenticating slave(211)@172.17.1.176:40105
I0813 19:53:55.591321 27972 authenticator.cpp:407] Starting authentication session for crammd5_authenticatee(515)@172.17.1.176:40105
I0813 19:53:55.591703 27960 authenticator.cpp:92] Creating new server SASL connection
I0813 19:53:55.591943 27969 authenticatee.cpp:206] Received SASL authentication mechanisms: CRAM-MD5
I0813 19:53:55.591979 27969 authenticatee.cpp:232] Attempting to authenticate with mechanism 'CRAM-MD5'
I0813 19:53:55.592123 27968 authenticator.cpp:197] Received SASL authentication start
I0813 19:53:55.592195 27968 authenticator.cpp:319] Authentication requires more steps
I0813 19:53:55.592288 27968 authenticatee.cpp:252] Received SASL authentication step
I0813 19:53:55.592406 27958 authenticator.cpp:225] Received SASL authentication step
I0813 19:53:55.592439 27958 auxprop.cpp:102] Request to lookup properties for user: 'test-principal' realm: 'e4742810468d' server FQDN: 'e4742810468d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: false
I0813 19:53:55.592452 27958 auxprop.cpp:174] Looking up auxiliary property '*userPassword'
I0813 19:53:55.592489 27958 auxprop.cpp:174] Looking up auxiliary property '*cmusaslsecretCRAM-MD5'
I0813 19:53:55.592510 27958 auxprop.cpp:102] Request to lookup properties for user: 'test-principal' realm: 'e4742810468d' server FQDN: 'e4742810468d' SASL_AUXPROP_VERIFY_AGAINST_HASH: false SASL_AUXPROP_OVERRIDE: false SASL_AUXPROP_AUTHZID: true
I0813 19:53:55.592519 27958 auxprop.cpp:124] Skipping auxiliary property '*userPassword' since SASL_AUXPROP_AUTHZID == true
I0813 19:53:55.592525 27958 auxprop.cpp:124] Skipping auxiliary property '*cmusaslsecretCRAM-MD5' since SASL_AUXPROP_AUTHZID == true
I0813 19:53:55.592540 27958 authenticator.cpp:311] Authentication success
I0813 19:53:55.592627 27960 authenticatee.cpp:292] Authentication success
I0813 19:53:55.592667 27964 master.cpp:4723] Successfully authenticated principal 'test-principal' at slave(211)@172.17.1.176:40105
I0813 19:53:55.592741 27968 authenticator.cpp:425] Authentication session cleanup for crammd5_authenticatee(515)@172.17.1.176:40105
I0813 19:53:55.592942 27962 slave.cpp:815] Successfully authenticated with master master@172.17.1.176:40105
I0813 19:53:55.593040 27962 slave.cpp:1209] Will retry registration in 17.876256ms if necessary
I0813 19:53:55.593189 27959 master.cpp:3635] Registering slave at slave(211)@172.17.1.176:40105 (e4742810468d) with id 20150813-195355-2952860076-40105-27938-S0
I0813 19:53:55.593529 27963 registrar.cpp:443] Applied 1 operations in 49783ns; attempting to update the 'registry'
I0813 19:53:55.593740 27966 process.cpp:3043] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0813 19:53:55.594066 27966 master.cpp:1782] Received subscription request for HTTP framework 'default'
I0813 19:53:55.594135 27966 master.cpp:1563] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0813 19:53:55.594410 27966 master.cpp:1874] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0813 19:53:55.594735 27961 hierarchical.hpp:391] Added framework 20150813-195355-2952860076-40105-27938-0000
I0813 19:53:55.594768 27961 hierarchical.hpp:1008] No resources available to allocate!
I0813 19:53:55.594784 27961 hierarchical.hpp:908] Performed allocation for 0 slaves in 25837ns
I0813 19:53:55.594811 27961 master.hpp:1305] Sending heartbeat to 20150813-195355-2952860076-40105-27938-0000
I0813 19:53:55.595929 27964 log.cpp:685] Attempting to append 349 bytes to the log
I0813 19:53:55.596071 27972 coordinator.cpp:341] Coordinator attempting to write APPEND action at position 3
../../src/tests/scheduler_tests.cpp:227: Failure
Value of: event.get().type()
Actual: HEARTBEAT
Expected: Event::OFFERS
Which is: OFFERS
../../src/tests/scheduler_tests.cpp:228: Failure
Expected: (0) != (event.get().offers().offers().size()), actual: 0 vs 0
I0813 19:53:55.597309 27961 replica.cpp:511] Replica received write request for position 3
[libprotobuf FATAL ../3rdparty/libprocess/3rdparty/protobuf-2.5.0/src/google/protobuf/repeated_field.h:824] CHECK failed: (index) < (size()):
I0813 19:53:55.597919 27961 leveldb.cpp:343] Persisting action (368 bytes) to leveldb took 496413ns
I0813 19:53:55.597945 27961 replica.cpp:679] Persisted action at 3
../../src/tests/scheduler_tests.cpp:237: Failure
Actual function call count doesn't match EXPECT_CALL(containerizer, update(_, _))...
Expected: to be called at least once
Actual: never called - unsatisfied and active
../../src/tests/scheduler_tests.cpp:233: Failure
Actual function call count doesn't match EXPECT_CALL(exec, launchTask(_, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
../../src/tests/scheduler_tests.cpp:230: Failure
Actual function call count doesn't match EXPECT_CALL(exec, registered(_, _, _, _))...
Expected: to be called once
Actual: never called - unsatisfied and active
unknown file: Failure
C++ exception with description "CHECK failed: (index) < (size()): " thrown in the test body.
I0813 19:53:55.598817 27964 replica.cpp:658] Replica received learned notice for position 3
I0813 19:53:55.599270 27962 master.cpp:859] Master terminating
I0813 19:53:55.599311 27964 leveldb.cpp:343] Persisting action (370 bytes) to leveldb took 467786ns
I0813 19:53:55.599336 27964 replica.cpp:679] Persisted action at 3
I0813 19:53:55.599364 27964 replica.cpp:664] Replica learned APPEND action at position 3
I0813 19:53:55.600049 27958 slave.cpp:3143] master@172.17.1.176:40105 exited
W0813 19:53:55.600172 27958 slave.cpp:3146] Master disconnected! Waiting for a new master to be elected
I0813 19:53:55.599438 27961 hierarchical.hpp:428] Removed framework 20150813-195355-2952860076-40105-27938-0000
I0813 19:53:55.601364 27957 registrar.cpp:488] Successfully updated the 'registry' in 7.769856ms
I0813 19:53:55.601544 27967 log.cpp:704] Attempting to truncate the log to 3
I0813 19:53:55.601698 27957 coordinator.cpp:341] Coordinator attempting to write TRUNCATE action at position 4
I0813 19:53:55.603103 27968 replica.cpp:511] Replica received write request for position 4
I0813 19:53:55.603567 27968 leveldb.cpp:343] Persisting action (16 bytes) to leveldb took 423250ns
I0813 19:53:55.603607 27968 replica.cpp:679] Persisted action at 4
F0813 19:53:55.603607 27938 logging.cpp:57] RAW: Pure virtual method called
I0813 19:53:55.611759 27970 slave.cpp:1209] Will retry registration in 33.19419ms if necessary
@ 0x7f542d934e2e google::LogMessage::Fail()
@ 0x7f542d93a310 google::RawLog__()
@ 0x7f542cd86f2a __cxa_pure_virtual
@ 0x8e292c mesos::internal::tests::Cluster::Slaves::shutdown()
@ 0xe256f2 mesos::internal::tests::MesosTest::ShutdownSlaves()
@ 0xe256b4 mesos::internal::tests::MesosTest::Shutdown()
@ 0xe21c99 mesos::internal::tests::MesosTest::TearDown()
I0813 19:53:55.645457 27966 slave.cpp:1209] Will retry registration in 69.494313ms if necessary
@ 0x131f3b0 testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x131a226 testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x12fb8b6 testing::Test::Run()
@ 0x12fbff0 testing::TestInfo::Run()
@ 0x12fc636 testing::TestCase::Run()
@ 0x1302f10 testing::internal::UnitTestImpl::RunAllTests()
@ 0x131ffd5 testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x131ad9c testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x1301c56 testing::UnitTest::Run()
@ 0xc91ef3 RUN_ALL_TESTS()
@ 0xc91be8 main
@ 0x7f5428cd3af5 __libc_start_main
@ 0x8d86f9 (unknown)
make[4]: *** [check-local] Aborted
make[4]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.24.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.24.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1439494456-30794
Untagged: mesos-1439494456-30794:latest
Deleted: 2220a221b652c0ce2958da44d1c7e7f730d3190200fd9716cbdf5a0c3bffee4c
Deleted: e586c5409bf7a07cf68376129216e71204acc03d9d3f6c71ea607471de7ee3ea
Deleted: a408d6574b84cd2ec956fac3a41de020a488ea8cff2c6ba8fc1eebb537095676
Deleted: 8e3144e93ae42f1d2c814602871833b6d1a515266f167230ece199262be46c27
Build step 'Execute shell' marked build as failure