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 2016/02/06 01:25:06 UTC
Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1631
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1631/changes>
Changes:
[mpark] Added `Resources::size()`.
[mpark] Tweaked some resource test cases.
[mpark] Fixed some typos in test case comments.
[mpark] Allowed `createLabel` to take an optional `value`.
------------------------------------------
[...truncated 163521 lines...]
I0206 00:25:04.308754 2858 gc.cpp:54] Scheduling '/tmp/ContentType_SchedulerTest_Message_1_ZFL74d/slaves/d7289a18-b1dc-410d-938c-5157cc0c5406-S0/frameworks/d7289a18-b1dc-410d-938c-5157cc0c5406-0000' for gc 6.99999644126222days in the future
[ OK ] ContentType/SchedulerTest.Message/1 (162 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0206 00:25:04.317455 2824 leveldb.cpp:174] Opened db in 2.920983ms
I0206 00:25:04.320734 2824 leveldb.cpp:181] Compacted db in 3.126857ms
I0206 00:25:04.320793 2824 leveldb.cpp:196] Created db iterator in 22401ns
I0206 00:25:04.320814 2824 leveldb.cpp:202] Seeked to beginning of db in 2001ns
I0206 00:25:04.320828 2824 leveldb.cpp:271] Iterated through 0 keys in the db in 278ns
I0206 00:25:04.320878 2824 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0206 00:25:04.321467 2851 recover.cpp:447] Starting replica recovery
I0206 00:25:04.321746 2851 recover.cpp:473] Replica is in EMPTY status
I0206 00:25:04.323176 2844 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14299)@172.17.0.2:43484
I0206 00:25:04.323871 2844 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0206 00:25:04.324514 2852 recover.cpp:564] Updating replica status to STARTING
I0206 00:25:04.325181 2853 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 574251ns
I0206 00:25:04.325215 2853 replica.cpp:320] Persisted replica status to STARTING
I0206 00:25:04.325532 2853 recover.cpp:473] Replica is in STARTING status
I0206 00:25:04.326107 2858 master.cpp:376] Master ff279b50-f5ef-4b9c-a1e5-32dbcc96108f (6632562f1ade) started on 172.17.0.2:43484
I0206 00:25:04.326131 2858 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/3L3HMF/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/3L3HMF/master" --zk_session_timeout="10secs"
I0206 00:25:04.326530 2858 master.cpp:425] Master allowing unauthenticated frameworks to register
I0206 00:25:04.326547 2858 master.cpp:428] Master only allowing authenticated slaves to register
I0206 00:25:04.326560 2858 credentials.hpp:35] Loading credentials for authentication from '/tmp/3L3HMF/credentials'
I0206 00:25:04.326959 2858 master.cpp:468] Using default 'crammd5' authenticator
I0206 00:25:04.327028 2849 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14300)@172.17.0.2:43484
I0206 00:25:04.327126 2858 master.cpp:537] Using default 'basic' HTTP authenticator
I0206 00:25:04.327409 2858 master.cpp:571] Authorization enabled
I0206 00:25:04.327703 2849 hierarchical.cpp:144] Initialized hierarchical allocator process
I0206 00:25:04.327853 2849 whitelist_watcher.cpp:77] No whitelist given
I0206 00:25:04.327863 2846 recover.cpp:193] Received a recover response from a replica in STARTING status
I0206 00:25:04.329155 2855 recover.cpp:564] Updating replica status to VOTING
I0206 00:25:04.329756 2855 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 450316ns
I0206 00:25:04.329790 2855 replica.cpp:320] Persisted replica status to VOTING
I0206 00:25:04.329911 2855 recover.cpp:578] Successfully joined the Paxos group
I0206 00:25:04.330178 2855 recover.cpp:462] Recover process terminated
I0206 00:25:04.331867 2849 master.cpp:1712] The newly elected leader is master@172.17.0.2:43484 with id ff279b50-f5ef-4b9c-a1e5-32dbcc96108f
I0206 00:25:04.331987 2849 master.cpp:1725] Elected as the leading master!
I0206 00:25:04.332096 2849 master.cpp:1470] Recovering from registrar
I0206 00:25:04.332336 2848 registrar.cpp:307] Recovering registrar
I0206 00:25:04.333096 2848 log.cpp:659] Attempting to start the writer
I0206 00:25:04.334532 2858 replica.cpp:493] Replica received implicit promise request from (14302)@172.17.0.2:43484 with proposal 1
I0206 00:25:04.334967 2858 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 396443ns
I0206 00:25:04.334998 2858 replica.cpp:342] Persisted promised to 1
I0206 00:25:04.336072 2858 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0206 00:25:04.337970 2852 replica.cpp:388] Replica received explicit promise request from (14303)@172.17.0.2:43484 for position 0 with proposal 2
I0206 00:25:04.338479 2852 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 439504ns
I0206 00:25:04.338524 2852 replica.cpp:712] Persisted action at 0
I0206 00:25:04.339539 2856 replica.cpp:537] Replica received write request for position 0 from (14304)@172.17.0.2:43484
I0206 00:25:04.339617 2856 leveldb.cpp:436] Reading position from leveldb took 38900ns
I0206 00:25:04.340200 2856 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 335209ns
I0206 00:25:04.340319 2856 replica.cpp:712] Persisted action at 0
I0206 00:25:04.341228 2858 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0206 00:25:04.341586 2858 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 323187ns
I0206 00:25:04.341614 2858 replica.cpp:712] Persisted action at 0
I0206 00:25:04.341666 2858 replica.cpp:697] Replica learned NOP action at position 0
I0206 00:25:04.342496 2857 log.cpp:675] Writer started with ending position 0
I0206 00:25:04.344354 2857 leveldb.cpp:436] Reading position from leveldb took 61235ns
I0206 00:25:04.346001 2845 registrar.cpp:340] Successfully fetched the registry (0B) in 13.607168ms
I0206 00:25:04.346161 2845 registrar.cpp:439] Applied 1 operations in 45925ns; attempting to update the 'registry'
I0206 00:25:04.347564 2846 log.cpp:683] Attempting to append 170 bytes to the log
I0206 00:25:04.347847 2846 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0206 00:25:04.349110 2846 replica.cpp:537] Replica received write request for position 1 from (14305)@172.17.0.2:43484
I0206 00:25:04.349643 2846 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 473783ns
I0206 00:25:04.349678 2846 replica.cpp:712] Persisted action at 1
I0206 00:25:04.350703 2850 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0206 00:25:04.351888 2850 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 1.153803ms
I0206 00:25:04.351928 2850 replica.cpp:712] Persisted action at 1
I0206 00:25:04.351948 2850 replica.cpp:697] Replica learned APPEND action at position 1
I0206 00:25:04.353636 2853 registrar.cpp:484] Successfully updated the 'registry' in 7.394048ms
I0206 00:25:04.353675 2850 log.cpp:702] Attempting to truncate the log to 1
I0206 00:25:04.353802 2853 registrar.cpp:370] Successfully recovered registrar
I0206 00:25:04.353909 2850 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0206 00:25:04.354221 2853 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0206 00:25:04.354375 2850 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0206 00:25:04.354903 2855 replica.cpp:537] Replica received write request for position 2 from (14306)@172.17.0.2:43484
I0206 00:25:04.355337 2855 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 390572ns
I0206 00:25:04.355370 2855 replica.cpp:712] Persisted action at 2
I0206 00:25:04.356128 2855 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0206 00:25:04.356596 2855 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 431031ns
I0206 00:25:04.356672 2855 leveldb.cpp:399] Deleting ~1 keys from leveldb took 39172ns
I0206 00:25:04.356700 2855 replica.cpp:712] Persisted action at 2
I0206 00:25:04.356722 2855 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0206 00:25:04.365372 2824 scheduler.cpp:154] Version: 0.28.0
I0206 00:25:04.366248 2855 scheduler.cpp:236] New master detected at master@172.17.0.2:43484
I0206 00:25:04.367859 2855 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:43484
I0206 00:25:04.370573 2846 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 00:25:04.371161 2846 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44573
I0206 00:25:04.371392 2846 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0206 00:25:04.371469 2846 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0206 00:25:04.372514 2846 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0206 00:25:04.373555 2847 hierarchical.cpp:265] Added framework ff279b50-f5ef-4b9c-a1e5-32dbcc96108f-0000
I0206 00:25:04.373802 2846 master.hpp:1658] Sending heartbeat to ff279b50-f5ef-4b9c-a1e5-32dbcc96108f-0000
I0206 00:25:04.374073 2847 hierarchical.cpp:1403] No resources available to allocate!
I0206 00:25:04.374114 2847 hierarchical.cpp:1498] No inverse offers to send out!
I0206 00:25:04.374141 2847 hierarchical.cpp:1096] Performed allocation for 0 slaves in 555288ns
I0206 00:25:04.374899 2850 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:43484
I0206 00:25:04.376168 2854 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:43484
I0206 00:25:04.376821 2854 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0206 00:25:04.377894 2843 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:43484
I0206 00:25:04.380071 2848 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 00:25:04.380714 2845 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44575
I0206 00:25:04.380841 2845 master.cpp:2719] Processing REQUEST call for framework ff279b50-f5ef-4b9c-a1e5-32dbcc96108f-0000 (default)
I0206 00:25:04.381053 2852 hierarchical.cpp:589] Received resource request from framework ff279b50-f5ef-4b9c-a1e5-32dbcc96108f-0000
I0206 00:25:04.381655 2824 master.cpp:1027] Master terminating
I0206 00:25:04.382278 2852 hierarchical.cpp:326] Removed framework ff279b50-f5ef-4b9c-a1e5-32dbcc96108f-0000
E0206 00:25:04.383191 2851 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (81 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0206 00:25:04.398036 2824 leveldb.cpp:174] Opened db in 2.884675ms
I0206 00:25:04.399323 2824 leveldb.cpp:181] Compacted db in 1.236984ms
I0206 00:25:04.399379 2824 leveldb.cpp:196] Created db iterator in 23206ns
I0206 00:25:04.399488 2824 leveldb.cpp:202] Seeked to beginning of db in 2355ns
I0206 00:25:04.399575 2824 leveldb.cpp:271] Iterated through 0 keys in the db in 449ns
I0206 00:25:04.399737 2824 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0206 00:25:04.400580 2844 recover.cpp:447] Starting replica recovery
I0206 00:25:04.400892 2844 recover.cpp:473] Replica is in EMPTY status
I0206 00:25:04.402050 2858 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14314)@172.17.0.2:43484
I0206 00:25:04.402933 2848 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0206 00:25:04.408396 2858 master.cpp:376] Master 64af991c-37ff-4e55-9c2a-c75c7d4fb99d (6632562f1ade) started on 172.17.0.2:43484
I0206 00:25:04.408424 2858 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/jKKg39/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/jKKg39/master" --zk_session_timeout="10secs"
I0206 00:25:04.408794 2858 master.cpp:425] Master allowing unauthenticated frameworks to register
I0206 00:25:04.408807 2858 master.cpp:428] Master only allowing authenticated slaves to register
I0206 00:25:04.408815 2858 credentials.hpp:35] Loading credentials for authentication from '/tmp/jKKg39/credentials'
I0206 00:25:04.409168 2848 recover.cpp:564] Updating replica status to STARTING
I0206 00:25:04.409178 2858 master.cpp:468] Using default 'crammd5' authenticator
I0206 00:25:04.409471 2858 master.cpp:537] Using default 'basic' HTTP authenticator
I0206 00:25:04.409603 2858 master.cpp:571] Authorization enabled
I0206 00:25:04.409790 2848 hierarchical.cpp:144] Initialized hierarchical allocator process
I0206 00:25:04.409813 2843 whitelist_watcher.cpp:77] No whitelist given
I0206 00:25:04.410315 2849 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 763617ns
I0206 00:25:04.410343 2849 replica.cpp:320] Persisted replica status to STARTING
I0206 00:25:04.410562 2849 recover.cpp:473] Replica is in STARTING status
I0206 00:25:04.411911 2849 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14316)@172.17.0.2:43484
I0206 00:25:04.412901 2848 recover.cpp:193] Received a recover response from a replica in STARTING status
I0206 00:25:04.413025 2843 master.cpp:1712] The newly elected leader is master@172.17.0.2:43484 with id 64af991c-37ff-4e55-9c2a-c75c7d4fb99d
I0206 00:25:04.413087 2843 master.cpp:1725] Elected as the leading master!
I0206 00:25:04.413113 2843 master.cpp:1470] Recovering from registrar
I0206 00:25:04.413252 2847 registrar.cpp:307] Recovering registrar
I0206 00:25:04.413453 2850 recover.cpp:564] Updating replica status to VOTING
I0206 00:25:04.414072 2858 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 380167ns
I0206 00:25:04.414101 2858 replica.cpp:320] Persisted replica status to VOTING
I0206 00:25:04.414208 2858 recover.cpp:578] Successfully joined the Paxos group
I0206 00:25:04.414424 2858 recover.cpp:462] Recover process terminated
I0206 00:25:04.415081 2847 log.cpp:659] Attempting to start the writer
I0206 00:25:04.416215 2843 replica.cpp:493] Replica received implicit promise request from (14317)@172.17.0.2:43484 with proposal 1
I0206 00:25:04.416595 2843 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 347941ns
I0206 00:25:04.416636 2843 replica.cpp:342] Persisted promised to 1
I0206 00:25:04.417314 2843 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0206 00:25:04.418524 2856 replica.cpp:388] Replica received explicit promise request from (14318)@172.17.0.2:43484 for position 0 with proposal 2
I0206 00:25:04.418864 2856 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 300872ns
I0206 00:25:04.418891 2856 replica.cpp:712] Persisted action at 0
I0206 00:25:04.421177 2851 replica.cpp:537] Replica received write request for position 0 from (14319)@172.17.0.2:43484
I0206 00:25:04.421244 2851 leveldb.cpp:436] Reading position from leveldb took 35024ns
I0206 00:25:04.421792 2851 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 358490ns
I0206 00:25:04.421829 2851 replica.cpp:712] Persisted action at 0
I0206 00:25:04.422682 2851 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0206 00:25:04.423013 2851 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 300521ns
I0206 00:25:04.423039 2851 replica.cpp:712] Persisted action at 0
I0206 00:25:04.423063 2851 replica.cpp:697] Replica learned NOP action at position 0
I0206 00:25:04.423616 2849 log.cpp:675] Writer started with ending position 0
I0206 00:25:04.424793 2854 leveldb.cpp:436] Reading position from leveldb took 22218ns
I0206 00:25:04.425736 2849 registrar.cpp:340] Successfully fetched the registry (0B) in 12.231936ms
I0206 00:25:04.425878 2849 registrar.cpp:439] Applied 1 operations in 42111ns; attempting to update the 'registry'
I0206 00:25:04.426728 2849 log.cpp:683] Attempting to append 170 bytes to the log
I0206 00:25:04.426856 2847 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0206 00:25:04.427507 2845 replica.cpp:537] Replica received write request for position 1 from (14320)@172.17.0.2:43484
I0206 00:25:04.428074 2845 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 364276ns
I0206 00:25:04.428100 2845 replica.cpp:712] Persisted action at 1
I0206 00:25:04.428671 2854 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0206 00:25:04.429050 2854 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 350385ns
I0206 00:25:04.429075 2854 replica.cpp:712] Persisted action at 1
I0206 00:25:04.429098 2854 replica.cpp:697] Replica learned APPEND action at position 1
I0206 00:25:04.429976 2854 registrar.cpp:484] Successfully updated the 'registry' in 4.030208ms
I0206 00:25:04.430279 2843 log.cpp:702] Attempting to truncate the log to 1
I0206 00:25:04.430454 2854 registrar.cpp:370] Successfully recovered registrar
I0206 00:25:04.430568 2849 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0206 00:25:04.431360 2849 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0206 00:25:04.431602 2845 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0206 00:25:04.431797 2856 replica.cpp:537] Replica received write request for position 2 from (14321)@172.17.0.2:43484
I0206 00:25:04.432176 2856 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 340953ns
I0206 00:25:04.432212 2856 replica.cpp:712] Persisted action at 2
I0206 00:25:04.433259 2851 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0206 00:25:04.433756 2851 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 454962ns
I0206 00:25:04.433830 2851 leveldb.cpp:399] Deleting ~1 keys from leveldb took 41670ns
I0206 00:25:04.433859 2851 replica.cpp:712] Persisted action at 2
I0206 00:25:04.433892 2851 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0206 00:25:04.442319 2824 scheduler.cpp:154] Version: 0.28.0
I0206 00:25:04.443181 2856 scheduler.cpp:236] New master detected at master@172.17.0.2:43484
I0206 00:25:04.444283 2845 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.2:43484
I0206 00:25:04.447542 2856 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 00:25:04.448256 2856 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44576
I0206 00:25:04.448916 2856 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0206 00:25:04.449115 2856 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0206 00:25:04.449690 2856 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0206 00:25:04.450419 2854 hierarchical.cpp:265] Added framework 64af991c-37ff-4e55-9c2a-c75c7d4fb99d-0000
I0206 00:25:04.450738 2856 master.hpp:1658] Sending heartbeat to 64af991c-37ff-4e55-9c2a-c75c7d4fb99d-0000
I0206 00:25:04.450520 2854 hierarchical.cpp:1403] No resources available to allocate!
I0206 00:25:04.451709 2843 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.2:43484
I0206 00:25:04.451745 2854 hierarchical.cpp:1498] No inverse offers to send out!
I0206 00:25:04.451781 2854 hierarchical.cpp:1096] Performed allocation for 0 slaves in 1.328478ms
I0206 00:25:04.452983 2844 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.2:43484
I0206 00:25:04.453935 2852 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0206 00:25:04.454262 2852 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.2:43484
I0206 00:25:04.456712 2843 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 00:25:04.457193 2843 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44577
I0206 00:25:04.457461 2843 master.cpp:2719] Processing REQUEST call for framework 64af991c-37ff-4e55-9c2a-c75c7d4fb99d-0000 (default)
I0206 00:25:04.457605 2848 hierarchical.cpp:589] Received resource request from framework 64af991c-37ff-4e55-9c2a-c75c7d4fb99d-0000
I0206 00:25:04.458014 2824 master.cpp:1027] Master terminating
I0206 00:25:04.458611 2856 hierarchical.cpp:326] Removed framework 64af991c-37ff-4e55-9c2a-c75c7d4fb99d-0000
E0206 00:25:04.459311 2843 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (73 ms)
[----------] 22 tests from ContentType/SchedulerTest (4986 ms total)
[----------] Global test environment tear-down
[==========] 975 tests from 128 test cases ran. (377643 ms total)
[ PASSED ] 974 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveRecoveryTest/0.CleanupHTTPExecutor, where TypeParam = mesos::internal::slave::MesosContainerizer
1 FAILED TEST
YOU HAVE 9 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1454716380-13675
Untagged: mesos-1454716380-13675:latest
Deleted: 925f0e9bbeb7ecce46153773eb71db6b94e085ef686e0daa8c79fdce7b81ebb7
Deleted: dc4de0a625377e4410c2e3bda75c0fb6477477ee885f5649ce19fc7e3ef117b9
Deleted: e8e61598658d3cb32df69776a9d6724d3138d0ecb4d8e99bf15f300d89d0865b
Deleted: e1fc686a80aa62ed223c8ce2be9382cb48b61e88c7a2f1e46163dfd64cfd2dd5
Deleted: 6b7d5c1b40f88dbf2142ccf73f534d11b5cd3323dbcb538dc67cd3ddb6c9a1bd
Deleted: 7c4cbcc66b933f7a53f92fada0d18d4fa300c677d2c99bdb2ef112610e4406a5
Deleted: 2db5b185640ee30ac50713a3655d59ebdf582be180171c362c3e992b0f9b4afc
Deleted: ff2b7883b2773a2ea23479b570b7629653b42f6a8dc17d53a20915d260d9ef1d
Deleted: 7ca54569caf2cbf687e794c0ebd9d05a7eab9f0f02546bf0829d2b3273fcedfb
Deleted: ed2dc75b858f1ab2d30e2e65ceeba8a59b120d2c5dc7c2dc1a4e0cb43b5f540d
Deleted: 540b2311eae2a2961e3f5d787a72230f4e29d330620ee6c834a803b7c2868c4c
Deleted: e7a883e03bcff2f164f7259dd6686c7bf8df0f7b63860a4536d7564790a80f03
Deleted: e8dc70ab4b43783a6bc65fe059444a54f728c79fe6abe10fbe0d7ae7436d2bfb
Deleted: ed50949f084d949bee78b43be9f3c31d9eab04cc3879481ce30c9d5d696bfa65
Deleted: c5f9e7009fbce2e66ad774772217f98e656a8856ea35d900eec3cc06bc9cb692
Deleted: 238d06ef682b79c1e52790c0888089b3b1efb655ba8a51783af55ee0fa2a06d5
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1633
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1633/changes>
Re: Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1632
Posted by Greg Mann <gr...@mesosphere.io>.
Looks like we didn't have a ticket for this one, so I created one here:
https://issues.apache.org/jira/browse/MESOS-4615
On Fri, Feb 5, 2016 at 5:37 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1632/changes
> >
>
> Changes:
>
> [yujie.jay] Added helper functions in cgroup for supporting net_cls
> subsystem.
>
> ------------------------------------------
> [...truncated 167635 lines...]
> [ OK ] ContentType/SchedulerTest.Message/1 (616 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> I0206 01:37:28.679303 2824 leveldb.cpp:174] Opened db in 76.966471ms
> I0206 01:37:28.704545 2824 leveldb.cpp:181] Compacted db in 25.081696ms
> I0206 01:37:28.704617 2824 leveldb.cpp:196] Created db iterator in 30299ns
> I0206 01:37:28.704635 2824 leveldb.cpp:202] Seeked to beginning of db in
> 1620ns
> I0206 01:37:28.704643 2824 leveldb.cpp:271] Iterated through 0 keys in
> the db in 268ns
> I0206 01:37:28.704689 2824 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0206 01:37:28.705224 2856 recover.cpp:447] Starting replica recovery
> I0206 01:37:28.705482 2856 recover.cpp:473] Replica is in EMPTY status
> I0206 01:37:28.706450 2855 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14378)@172.17.0.8:37843
> I0206 01:37:28.706908 2852 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0206 01:37:28.707272 2855 recover.cpp:564] Updating replica status to
> STARTING
> I0206 01:37:28.707988 2848 master.cpp:376] Master
> f8669fe3-e905-45e0-8063-a4968d66a97e (74ef606c4063) started on
> 172.17.0.8:37843
> I0206 01:37:28.708010 2848 master.cpp:378] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/z2SLxg/credentials"
> --framework_sorter="drf" --help="false" --hostname_lookup="true"
> --http_authenticators="basic" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
> --max_slave_ping_timeouts="5" --quiet="false"
> --recovery_slave_removal_limit="100%" --registry="replicated_log"
> --registry_fetch_timeout="1mins" --registry_store_timeout="100secs"
> --registry_strict="true" --root_submissions="true"
> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui"
> --work_dir="/tmp/z2SLxg/master" --zk_session_timeout="10secs"
> I0206 01:37:28.708268 2848 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0206 01:37:28.708278 2848 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0206 01:37:28.708284 2848 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/z2SLxg/credentials'
> I0206 01:37:28.708549 2848 master.cpp:468] Using default 'crammd5'
> authenticator
> I0206 01:37:28.708688 2848 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0206 01:37:28.708824 2848 master.cpp:571] Authorization enabled
> I0206 01:37:28.708986 2856 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0206 01:37:28.709018 2846 whitelist_watcher.cpp:77] No whitelist given
> I0206 01:37:28.710572 2851 master.cpp:1712] The newly elected leader is
> master@172.17.0.8:37843 with id f8669fe3-e905-45e0-8063-a4968d66a97e
> I0206 01:37:28.710618 2851 master.cpp:1725] Elected as the leading master!
> I0206 01:37:28.710708 2851 master.cpp:1470] Recovering from registrar
> I0206 01:37:28.710876 2843 registrar.cpp:307] Recovering registrar
> I0206 01:37:28.729671 2853 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 22.184621ms
> I0206 01:37:28.729707 2853 replica.cpp:320] Persisted replica status to
> STARTING
> I0206 01:37:28.729889 2852 recover.cpp:473] Replica is in STARTING status
> I0206 01:37:28.730726 2858 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14380)@172.17.0.8:37843
> I0206 01:37:28.731076 2857 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0206 01:37:28.731470 2852 recover.cpp:564] Updating replica status to
> VOTING
> I0206 01:37:28.761700 2844 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 30.01581ms
> I0206 01:37:28.761744 2844 replica.cpp:320] Persisted replica status to
> VOTING
> I0206 01:37:28.761885 2848 recover.cpp:578] Successfully joined the Paxos
> group
> I0206 01:37:28.762101 2848 recover.cpp:462] Recover process terminated
> I0206 01:37:28.762521 2857 log.cpp:659] Attempting to start the writer
> I0206 01:37:28.763659 2855 replica.cpp:493] Replica received implicit
> promise request from (14381)@172.17.0.8:37843 with proposal 1
> I0206 01:37:28.795686 2855 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 31.930403ms
> I0206 01:37:28.795774 2855 replica.cpp:342] Persisted promised to 1
> I0206 01:37:28.798104 2857 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0206 01:37:28.799767 2845 replica.cpp:388] Replica received explicit
> promise request from (14382)@172.17.0.8:37843 for position 0 with
> proposal 2
> I0206 01:37:28.828594 2845 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 28.759079ms
> I0206 01:37:28.828680 2845 replica.cpp:712] Persisted action at 0
> I0206 01:37:28.829860 2846 replica.cpp:537] Replica received write
> request for position 0 from (14383)@172.17.0.8:37843
> I0206 01:37:28.829978 2846 leveldb.cpp:436] Reading position from leveldb
> took 39903ns
> I0206 01:37:28.862035 2846 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 31.989561ms
> I0206 01:37:28.862118 2846 replica.cpp:712] Persisted action at 0
> I0206 01:37:28.863024 2847 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0206 01:37:28.895505 2847 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 32.258897ms
> I0206 01:37:28.895747 2847 replica.cpp:712] Persisted action at 0
> I0206 01:37:28.895907 2847 replica.cpp:697] Replica learned NOP action at
> position 0
> I0206 01:37:28.896898 2847 log.cpp:675] Writer started with ending
> position 0
> I0206 01:37:28.898190 2855 leveldb.cpp:436] Reading position from leveldb
> took 45954ns
> I0206 01:37:28.899428 2855 registrar.cpp:340] Successfully fetched the
> registry (0B) in 188.497152ms
> I0206 01:37:28.899546 2855 registrar.cpp:439] Applied 1 operations in
> 35031ns; attempting to update the 'registry'
> I0206 01:37:28.900215 2852 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0206 01:37:28.900336 2854 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0206 01:37:28.901142 2858 replica.cpp:537] Replica received write
> request for position 1 from (14384)@172.17.0.8:37843
> I0206 01:37:28.948690 2858 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 47.491423ms
> I0206 01:37:28.948776 2858 replica.cpp:712] Persisted action at 1
> I0206 01:37:28.950230 2845 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0206 01:37:28.979105 2845 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 28.765891ms
> I0206 01:37:28.979189 2845 replica.cpp:712] Persisted action at 1
> I0206 01:37:28.979225 2845 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0206 01:37:28.981251 2845 registrar.cpp:484] Successfully updated the
> 'registry' in 81.641984ms
> I0206 01:37:28.981386 2845 registrar.cpp:370] Successfully recovered
> registrar
> I0206 01:37:28.981757 2855 log.cpp:702] Attempting to truncate the log to
> 1
> I0206 01:37:28.982208 2851 master.cpp:1522] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0206 01:37:28.982374 2853 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0206 01:37:28.983206 2858 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0206 01:37:28.984444 2853 replica.cpp:537] Replica received write
> request for position 2 from (14385)@172.17.0.8:37843
> I0206 01:37:29.012693 2853 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 28.184253ms
> I0206 01:37:29.012780 2853 replica.cpp:712] Persisted action at 2
> I0206 01:37:29.014279 2854 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0206 01:37:29.046149 2854 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 31.824309ms
> I0206 01:37:29.046288 2854 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 66669ns
> I0206 01:37:29.046309 2854 replica.cpp:712] Persisted action at 2
> I0206 01:37:29.046340 2854 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0206 01:37:29.055670 2824 scheduler.cpp:154] Version: 0.28.0
> I0206 01:37:29.056491 2854 scheduler.cpp:236] New master detected at
> master@172.17.0.8:37843
> I0206 01:37:29.057818 2851 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.8:37843
> I0206 01:37:29.059360 2855 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0206 01:37:29.059873 2858 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.8:47819
> I0206 01:37:29.060060 2858 master.cpp:1974] Received subscription request
> for HTTP framework 'default'
> I0206 01:37:29.060124 2858 master.cpp:1751] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0206 01:37:29.060379 2858 master.cpp:2065] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0206 01:37:29.060849 2858 master.hpp:1658] Sending heartbeat to
> f8669fe3-e905-45e0-8063-a4968d66a97e-0000
> I0206 01:37:29.060854 2855 hierarchical.cpp:265] Added framework
> f8669fe3-e905-45e0-8063-a4968d66a97e-0000
> I0206 01:37:29.061645 2853 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.8:37843
> I0206 01:37:29.061714 2855 hierarchical.cpp:1403] No resources available
> to allocate!
> I0206 01:37:29.061748 2855 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0206 01:37:29.061771 2855 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 117038ns
> I0206 01:37:29.062062 2853 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.8:37843
> I0206 01:37:29.062855 2853 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.8:37843
> I0206 01:37:29.062923 2850 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0206 01:37:29.064299 2843 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0206 01:37:29.064771 2855 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.8:47820
> I0206 01:37:29.064883 2855 master.cpp:2719] Processing REQUEST call for
> framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000 (default)
> I0206 01:37:29.065019 2844 hierarchical.cpp:589] Received resource
> request from framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000
> I0206 01:37:29.065457 2845 master.cpp:1027] Master terminating
> I0206 01:37:29.065826 2850 hierarchical.cpp:326] Removed framework
> f8669fe3-e905-45e0-8063-a4968d66a97e-0000
> E0206 01:37:29.066864 2844 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/0 (472 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> I0206 01:37:29.180583 2824 leveldb.cpp:174] Opened db in 105.938866ms
> I0206 01:37:29.223002 2824 leveldb.cpp:181] Compacted db in 42.322554ms
> I0206 01:37:29.223089 2824 leveldb.cpp:196] Created db iterator in 21712ns
> I0206 01:37:29.223108 2824 leveldb.cpp:202] Seeked to beginning of db in
> 2666ns
> I0206 01:37:29.223119 2824 leveldb.cpp:271] Iterated through 0 keys in
> the db in 322ns
> I0206 01:37:29.223170 2824 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0206 01:37:29.223954 2847 recover.cpp:447] Starting replica recovery
> I0206 01:37:29.224406 2847 recover.cpp:473] Replica is in EMPTY status
> I0206 01:37:29.226563 2843 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14393)@172.17.0.8:37843
> I0206 01:37:29.226886 2849 master.cpp:376] Master
> 675a84d4-4d67-4255-b221-c63e4669bf79 (74ef606c4063) started on
> 172.17.0.8:37843
> I0206 01:37:29.226912 2849 master.cpp:378] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/K1hwPx/credentials"
> --framework_sorter="drf" --help="false" --hostname_lookup="true"
> --http_authenticators="basic" --initialize_driver_logging="true"
> --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO"
> --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000"
> --max_slave_ping_timeouts="5" --quiet="false"
> --recovery_slave_removal_limit="100%" --registry="replicated_log"
> --registry_fetch_timeout="1mins" --registry_store_timeout="100secs"
> --registry_strict="true" --root_submissions="true"
> --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins"
> --user_sorter="drf" --version="false"
> --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui"
> --work_dir="/tmp/K1hwPx/master" --zk_session_timeout="10secs"
> I0206 01:37:29.227296 2849 master.cpp:425] Master allowing
> unauthenticated frameworks to register
> I0206 01:37:29.227314 2849 master.cpp:428] Master only allowing
> authenticated slaves to register
> I0206 01:37:29.227325 2849 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/K1hwPx/credentials'
> I0206 01:37:29.227718 2849 master.cpp:468] Using default 'crammd5'
> authenticator
> I0206 01:37:29.227891 2849 master.cpp:537] Using default 'basic' HTTP
> authenticator
> I0206 01:37:29.228045 2849 master.cpp:571] Authorization enabled
> I0206 01:37:29.229780 2850 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0206 01:37:29.229867 2850 whitelist_watcher.cpp:77] No whitelist given
> I0206 01:37:29.231914 2852 master.cpp:1712] The newly elected leader is
> master@172.17.0.8:37843 with id 675a84d4-4d67-4255-b221-c63e4669bf79
> I0206 01:37:29.231953 2852 master.cpp:1725] Elected as the leading master!
> I0206 01:37:29.231976 2852 master.cpp:1470] Recovering from registrar
> I0206 01:37:29.232695 2857 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0206 01:37:29.234669 2857 recover.cpp:564] Updating replica status to
> STARTING
> I0206 01:37:29.235661 2852 registrar.cpp:307] Recovering registrar
> I0206 01:37:29.256692 2843 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 21.762851ms
> I0206 01:37:29.256778 2843 replica.cpp:320] Persisted replica status to
> STARTING
> I0206 01:37:29.257149 2843 recover.cpp:473] Replica is in STARTING status
> I0206 01:37:29.258880 2854 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14395)@172.17.0.8:37843
> I0206 01:37:29.259963 2854 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0206 01:37:29.260452 2851 recover.cpp:564] Updating replica status to
> VOTING
> I0206 01:37:29.281335 2852 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 20.649922ms
> I0206 01:37:29.281419 2852 replica.cpp:320] Persisted replica status to
> VOTING
> I0206 01:37:29.281673 2847 recover.cpp:578] Successfully joined the Paxos
> group
> I0206 01:37:29.281895 2847 recover.cpp:462] Recover process terminated
> I0206 01:37:29.282418 2851 log.cpp:659] Attempting to start the writer
> I0206 01:37:29.283726 2858 replica.cpp:493] Replica received implicit
> promise request from (14396)@172.17.0.8:37843 with proposal 1
> I0206 01:37:29.306686 2858 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 22.909015ms
> I0206 01:37:29.306771 2858 replica.cpp:342] Persisted promised to 1
> I0206 01:37:29.308105 2843 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0206 01:37:29.310048 2848 replica.cpp:388] Replica received explicit
> promise request from (14397)@172.17.0.8:37843 for position 0 with
> proposal 2
> I0206 01:37:29.331552 2848 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 21.429846ms
> I0206 01:37:29.331781 2848 replica.cpp:712] Persisted action at 0
> I0206 01:37:29.334182 2848 replica.cpp:537] Replica received write
> request for position 0 from (14398)@172.17.0.8:37843
> I0206 01:37:29.334568 2848 leveldb.cpp:436] Reading position from leveldb
> took 42222ns
> I0206 01:37:29.358628 2848 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 23.773959ms
> I0206 01:37:29.358721 2848 replica.cpp:712] Persisted action at 0
> I0206 01:37:29.359482 2848 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0206 01:37:29.392504 2848 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 32.969204ms
> I0206 01:37:29.392735 2848 replica.cpp:712] Persisted action at 0
> I0206 01:37:29.392900 2848 replica.cpp:697] Replica learned NOP action at
> position 0
> I0206 01:37:29.394258 2853 log.cpp:675] Writer started with ending
> position 0
> I0206 01:37:29.395711 2853 leveldb.cpp:436] Reading position from leveldb
> took 77483ns
> I0206 01:37:29.397044 2849 registrar.cpp:340] Successfully fetched the
> registry (0B) in 161.316864ms
> I0206 01:37:29.397209 2849 registrar.cpp:439] Applied 1 operations in
> 43170ns; attempting to update the 'registry'
> I0206 01:37:29.398488 2851 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0206 01:37:29.398684 2846 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0206 01:37:29.400101 2846 replica.cpp:537] Replica received write
> request for position 1 from (14399)@172.17.0.8:37843
> I0206 01:37:29.442999 2846 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 42.830963ms
> I0206 01:37:29.443084 2846 replica.cpp:712] Persisted action at 1
> I0206 01:37:29.444593 2857 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0206 01:37:29.491114 2857 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 46.373782ms
> I0206 01:37:29.491322 2857 replica.cpp:712] Persisted action at 1
> I0206 01:37:29.491787 2857 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0206 01:37:29.493533 2847 registrar.cpp:484] Successfully updated the
> 'registry' in 96.251904ms
> I0206 01:37:29.493690 2847 registrar.cpp:370] Successfully recovered
> registrar
> I0206 01:37:29.493767 2852 log.cpp:702] Attempting to truncate the log to
> 1
> I0206 01:37:29.493958 2847 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0206 01:37:29.494138 2852 master.cpp:1522] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0206 01:37:29.494397 2850 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0206 01:37:29.496155 2850 replica.cpp:537] Replica received write
> request for position 2 from (14400)@172.17.0.8:37843
> I0206 01:37:29.531819 2850 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 35.583922ms
> I0206 01:37:29.531920 2850 replica.cpp:712] Persisted action at 2
> I0206 01:37:29.533265 2846 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0206 01:37:29.556874 2846 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 23.561286ms
> I0206 01:37:29.556985 2846 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 51812ns
> I0206 01:37:29.557010 2846 replica.cpp:712] Persisted action at 2
> I0206 01:37:29.557040 2846 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0206 01:37:29.567399 2824 scheduler.cpp:154] Version: 0.28.0
> I0206 01:37:29.570505 2844 scheduler.cpp:236] New master detected at
> master@172.17.0.8:37843
> I0206 01:37:29.573139 2856 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.8:37843
> I0206 01:37:29.577590 2852 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0206 01:37:29.578420 2852 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.8:47821
> I0206 01:37:29.579581 2852 master.cpp:1974] Received subscription request
> for HTTP framework 'default'
> I0206 01:37:29.579772 2852 master.cpp:1751] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0206 01:37:29.580469 2852 master.cpp:2065] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0206 01:37:29.583930 2854 hierarchical.cpp:265] Added framework
> 675a84d4-4d67-4255-b221-c63e4669bf79-0000
> I0206 01:37:29.584069 2854 hierarchical.cpp:1403] No resources available
> to allocate!
> I0206 01:37:29.584120 2854 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0206 01:37:29.584152 2854 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 165356ns
> I0206 01:37:29.584631 2854 master.hpp:1658] Sending heartbeat to
> 675a84d4-4d67-4255-b221-c63e4669bf79-0000
> I0206 01:37:29.586541 2855 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.8:37843
> I0206 01:37:29.588764 2855 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.8:37843
> I0206 01:37:29.593571 2850 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.8:37843
> I0206 01:37:29.594358 2850 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0206 01:37:29.596145 2850 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0206 01:37:29.596845 2850 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.8:47822
> I0206 01:37:29.597326 2850 master.cpp:2719] Processing REQUEST call for
> framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000 (default)
> I0206 01:37:29.597625 2850 hierarchical.cpp:589] Received resource
> request from framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000
> I0206 01:37:29.599362 2824 master.cpp:1027] Master terminating
> I0206 01:37:29.599967 2849 hierarchical.cpp:326] Removed framework
> 675a84d4-4d67-4255-b221-c63e4669bf79-0000
> E0206 01:37:29.601402 2856 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (574 ms)
> [----------] 22 tests from ContentType/SchedulerTest (15447 ms total)
>
> [----------] Global test environment tear-down
> 2016-02-06
> 01:37:30,165:2824(0x7f25627fc700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:35224] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> [==========] 975 tests from 128 test cases ran. (759070 ms total)
> [ PASSED ] 974 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] ContainerLoggerTest.DefaultToSandbox
>
> 1 FAILED TEST
> YOU HAVE 9 DISABLED TESTS
>
> make[4]: *** [check-local] Error 1
> make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[3]: *** [check-am] Error 2
> make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[2]: *** [check] Error 2
> make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
> make[1]: *** [check-recursive] Error 1
> make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
> make: *** [distcheck] Error 1
> + docker rmi mesos-1454720011-2393
> Untagged: mesos-1454720011-2393:latest
> Deleted: e42c99fcaa72eb17cc1611376204fd545950000d1790a9268348eecfbe30fde8
> Deleted: 185e0e3627ee9ba9e7f301d825100654545b1372782cd9d8f8e13a6d5a268194
> Deleted: 1cfb55e4d4cc6825a688ab1754693915bc6a298f02a78709e1802cb846f14d9b
> Deleted: 9c1de076fef7cc3437e97beb23056d01b7ccc2e760d45794fd4663740a9b6336
> Deleted: fdc9e659e296cb00bbe5a898b7d579a4f22dc92511bb663a369d7c27b7ed9609
> Deleted: 128bc0580c75f17df712dc6444eb8fe4b4ca7a2c4e8eb26cb3a8f283c267c68b
> Deleted: f82740bbdbbfc84ccbd5ab8f8bea8a6d836107d5abe6f8bd5aee9ebc38d1f51c
> Deleted: fa86e52e13d0b16978d9d4054daa7c93d69f6d2011038e6d3bde292e986ad49a
> Deleted: 00fecc2a5e8492452e7fc63e4c0a4a17a4a3b3460cd810c2e8e07e76d420cf8e
> Deleted: 02681e291122d4d87e6e37d61a92e9bfdc9cf8fa00f7aa5e40fb2e10ec10ba94
> Deleted: 181b660e5c6ab86421c2fd12552119d635981cfd09b25b22d9e5540346b4127c
> Deleted: c0afc00571a7808ede99b86b6be19c862ca541fc62bf9903a352b12849efed42
> Deleted: d69ed4589d9496dc861c5d922e0fe8a09f2ddafefa36576d5a80d4a2002062ed
> Deleted: 28508a2d7ecb6046efd260b46698d0100a7d7fe059ff208f91dc6db22a633f73
> Deleted: bd17c85774c72a7e2d71b965cefca5569bef2c360923f5ed2eacca24e95baac4
> Deleted: 8dc0ccb1195d7dfd955154b09a7368e0886ac2c9667b8e6cd020dccfb698592c
> Build step 'Execute shell' marked build as failure
>
Build failed in Jenkins: Mesos » gcc,--verbose --enable-libevent --enable-ssl,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1632
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose%20--enable-libevent%20--enable-ssl,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1632/changes>
Changes:
[yujie.jay] Added helper functions in cgroup for supporting net_cls subsystem.
------------------------------------------
[...truncated 167635 lines...]
[ OK ] ContentType/SchedulerTest.Message/1 (616 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0206 01:37:28.679303 2824 leveldb.cpp:174] Opened db in 76.966471ms
I0206 01:37:28.704545 2824 leveldb.cpp:181] Compacted db in 25.081696ms
I0206 01:37:28.704617 2824 leveldb.cpp:196] Created db iterator in 30299ns
I0206 01:37:28.704635 2824 leveldb.cpp:202] Seeked to beginning of db in 1620ns
I0206 01:37:28.704643 2824 leveldb.cpp:271] Iterated through 0 keys in the db in 268ns
I0206 01:37:28.704689 2824 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0206 01:37:28.705224 2856 recover.cpp:447] Starting replica recovery
I0206 01:37:28.705482 2856 recover.cpp:473] Replica is in EMPTY status
I0206 01:37:28.706450 2855 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14378)@172.17.0.8:37843
I0206 01:37:28.706908 2852 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0206 01:37:28.707272 2855 recover.cpp:564] Updating replica status to STARTING
I0206 01:37:28.707988 2848 master.cpp:376] Master f8669fe3-e905-45e0-8063-a4968d66a97e (74ef606c4063) started on 172.17.0.8:37843
I0206 01:37:28.708010 2848 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/z2SLxg/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/z2SLxg/master" --zk_session_timeout="10secs"
I0206 01:37:28.708268 2848 master.cpp:425] Master allowing unauthenticated frameworks to register
I0206 01:37:28.708278 2848 master.cpp:428] Master only allowing authenticated slaves to register
I0206 01:37:28.708284 2848 credentials.hpp:35] Loading credentials for authentication from '/tmp/z2SLxg/credentials'
I0206 01:37:28.708549 2848 master.cpp:468] Using default 'crammd5' authenticator
I0206 01:37:28.708688 2848 master.cpp:537] Using default 'basic' HTTP authenticator
I0206 01:37:28.708824 2848 master.cpp:571] Authorization enabled
I0206 01:37:28.708986 2856 hierarchical.cpp:144] Initialized hierarchical allocator process
I0206 01:37:28.709018 2846 whitelist_watcher.cpp:77] No whitelist given
I0206 01:37:28.710572 2851 master.cpp:1712] The newly elected leader is master@172.17.0.8:37843 with id f8669fe3-e905-45e0-8063-a4968d66a97e
I0206 01:37:28.710618 2851 master.cpp:1725] Elected as the leading master!
I0206 01:37:28.710708 2851 master.cpp:1470] Recovering from registrar
I0206 01:37:28.710876 2843 registrar.cpp:307] Recovering registrar
I0206 01:37:28.729671 2853 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.184621ms
I0206 01:37:28.729707 2853 replica.cpp:320] Persisted replica status to STARTING
I0206 01:37:28.729889 2852 recover.cpp:473] Replica is in STARTING status
I0206 01:37:28.730726 2858 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14380)@172.17.0.8:37843
I0206 01:37:28.731076 2857 recover.cpp:193] Received a recover response from a replica in STARTING status
I0206 01:37:28.731470 2852 recover.cpp:564] Updating replica status to VOTING
I0206 01:37:28.761700 2844 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.01581ms
I0206 01:37:28.761744 2844 replica.cpp:320] Persisted replica status to VOTING
I0206 01:37:28.761885 2848 recover.cpp:578] Successfully joined the Paxos group
I0206 01:37:28.762101 2848 recover.cpp:462] Recover process terminated
I0206 01:37:28.762521 2857 log.cpp:659] Attempting to start the writer
I0206 01:37:28.763659 2855 replica.cpp:493] Replica received implicit promise request from (14381)@172.17.0.8:37843 with proposal 1
I0206 01:37:28.795686 2855 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 31.930403ms
I0206 01:37:28.795774 2855 replica.cpp:342] Persisted promised to 1
I0206 01:37:28.798104 2857 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0206 01:37:28.799767 2845 replica.cpp:388] Replica received explicit promise request from (14382)@172.17.0.8:37843 for position 0 with proposal 2
I0206 01:37:28.828594 2845 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 28.759079ms
I0206 01:37:28.828680 2845 replica.cpp:712] Persisted action at 0
I0206 01:37:28.829860 2846 replica.cpp:537] Replica received write request for position 0 from (14383)@172.17.0.8:37843
I0206 01:37:28.829978 2846 leveldb.cpp:436] Reading position from leveldb took 39903ns
I0206 01:37:28.862035 2846 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.989561ms
I0206 01:37:28.862118 2846 replica.cpp:712] Persisted action at 0
I0206 01:37:28.863024 2847 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0206 01:37:28.895505 2847 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.258897ms
I0206 01:37:28.895747 2847 replica.cpp:712] Persisted action at 0
I0206 01:37:28.895907 2847 replica.cpp:697] Replica learned NOP action at position 0
I0206 01:37:28.896898 2847 log.cpp:675] Writer started with ending position 0
I0206 01:37:28.898190 2855 leveldb.cpp:436] Reading position from leveldb took 45954ns
I0206 01:37:28.899428 2855 registrar.cpp:340] Successfully fetched the registry (0B) in 188.497152ms
I0206 01:37:28.899546 2855 registrar.cpp:439] Applied 1 operations in 35031ns; attempting to update the 'registry'
I0206 01:37:28.900215 2852 log.cpp:683] Attempting to append 170 bytes to the log
I0206 01:37:28.900336 2854 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0206 01:37:28.901142 2858 replica.cpp:537] Replica received write request for position 1 from (14384)@172.17.0.8:37843
I0206 01:37:28.948690 2858 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 47.491423ms
I0206 01:37:28.948776 2858 replica.cpp:712] Persisted action at 1
I0206 01:37:28.950230 2845 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0206 01:37:28.979105 2845 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 28.765891ms
I0206 01:37:28.979189 2845 replica.cpp:712] Persisted action at 1
I0206 01:37:28.979225 2845 replica.cpp:697] Replica learned APPEND action at position 1
I0206 01:37:28.981251 2845 registrar.cpp:484] Successfully updated the 'registry' in 81.641984ms
I0206 01:37:28.981386 2845 registrar.cpp:370] Successfully recovered registrar
I0206 01:37:28.981757 2855 log.cpp:702] Attempting to truncate the log to 1
I0206 01:37:28.982208 2851 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0206 01:37:28.982374 2853 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0206 01:37:28.983206 2858 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0206 01:37:28.984444 2853 replica.cpp:537] Replica received write request for position 2 from (14385)@172.17.0.8:37843
I0206 01:37:29.012693 2853 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 28.184253ms
I0206 01:37:29.012780 2853 replica.cpp:712] Persisted action at 2
I0206 01:37:29.014279 2854 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0206 01:37:29.046149 2854 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 31.824309ms
I0206 01:37:29.046288 2854 leveldb.cpp:399] Deleting ~1 keys from leveldb took 66669ns
I0206 01:37:29.046309 2854 replica.cpp:712] Persisted action at 2
I0206 01:37:29.046340 2854 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0206 01:37:29.055670 2824 scheduler.cpp:154] Version: 0.28.0
I0206 01:37:29.056491 2854 scheduler.cpp:236] New master detected at master@172.17.0.8:37843
I0206 01:37:29.057818 2851 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.8:37843
I0206 01:37:29.059360 2855 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 01:37:29.059873 2858 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.8:47819
I0206 01:37:29.060060 2858 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0206 01:37:29.060124 2858 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0206 01:37:29.060379 2858 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0206 01:37:29.060849 2858 master.hpp:1658] Sending heartbeat to f8669fe3-e905-45e0-8063-a4968d66a97e-0000
I0206 01:37:29.060854 2855 hierarchical.cpp:265] Added framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000
I0206 01:37:29.061645 2853 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.8:37843
I0206 01:37:29.061714 2855 hierarchical.cpp:1403] No resources available to allocate!
I0206 01:37:29.061748 2855 hierarchical.cpp:1498] No inverse offers to send out!
I0206 01:37:29.061771 2855 hierarchical.cpp:1096] Performed allocation for 0 slaves in 117038ns
I0206 01:37:29.062062 2853 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.8:37843
I0206 01:37:29.062855 2853 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.8:37843
I0206 01:37:29.062923 2850 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0206 01:37:29.064299 2843 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 01:37:29.064771 2855 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.8:47820
I0206 01:37:29.064883 2855 master.cpp:2719] Processing REQUEST call for framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000 (default)
I0206 01:37:29.065019 2844 hierarchical.cpp:589] Received resource request from framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000
I0206 01:37:29.065457 2845 master.cpp:1027] Master terminating
I0206 01:37:29.065826 2850 hierarchical.cpp:326] Removed framework f8669fe3-e905-45e0-8063-a4968d66a97e-0000
E0206 01:37:29.066864 2844 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (472 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0206 01:37:29.180583 2824 leveldb.cpp:174] Opened db in 105.938866ms
I0206 01:37:29.223002 2824 leveldb.cpp:181] Compacted db in 42.322554ms
I0206 01:37:29.223089 2824 leveldb.cpp:196] Created db iterator in 21712ns
I0206 01:37:29.223108 2824 leveldb.cpp:202] Seeked to beginning of db in 2666ns
I0206 01:37:29.223119 2824 leveldb.cpp:271] Iterated through 0 keys in the db in 322ns
I0206 01:37:29.223170 2824 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0206 01:37:29.223954 2847 recover.cpp:447] Starting replica recovery
I0206 01:37:29.224406 2847 recover.cpp:473] Replica is in EMPTY status
I0206 01:37:29.226563 2843 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14393)@172.17.0.8:37843
I0206 01:37:29.226886 2849 master.cpp:376] Master 675a84d4-4d67-4255-b221-c63e4669bf79 (74ef606c4063) started on 172.17.0.8:37843
I0206 01:37:29.226912 2849 master.cpp:378] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/K1hwPx/credentials" --framework_sorter="drf" --help="false" --hostname_lookup="true" --http_authenticators="basic" --initialize_driver_logging="true" --log_auto_initialize="true" --logbufsecs="0" --logging_level="INFO" --max_completed_frameworks="50" --max_completed_tasks_per_framework="1000" --max_slave_ping_timeouts="5" --quiet="false" --recovery_slave_removal_limit="100%" --registry="replicated_log" --registry_fetch_timeout="1mins" --registry_store_timeout="100secs" --registry_strict="true" --root_submissions="true" --slave_ping_timeout="15secs" --slave_reregister_timeout="10mins" --user_sorter="drf" --version="false" --webui_dir="/mesos/mesos-0.28.0/_inst/share/mesos/webui" --work_dir="/tmp/K1hwPx/master" --zk_session_timeout="10secs"
I0206 01:37:29.227296 2849 master.cpp:425] Master allowing unauthenticated frameworks to register
I0206 01:37:29.227314 2849 master.cpp:428] Master only allowing authenticated slaves to register
I0206 01:37:29.227325 2849 credentials.hpp:35] Loading credentials for authentication from '/tmp/K1hwPx/credentials'
I0206 01:37:29.227718 2849 master.cpp:468] Using default 'crammd5' authenticator
I0206 01:37:29.227891 2849 master.cpp:537] Using default 'basic' HTTP authenticator
I0206 01:37:29.228045 2849 master.cpp:571] Authorization enabled
I0206 01:37:29.229780 2850 hierarchical.cpp:144] Initialized hierarchical allocator process
I0206 01:37:29.229867 2850 whitelist_watcher.cpp:77] No whitelist given
I0206 01:37:29.231914 2852 master.cpp:1712] The newly elected leader is master@172.17.0.8:37843 with id 675a84d4-4d67-4255-b221-c63e4669bf79
I0206 01:37:29.231953 2852 master.cpp:1725] Elected as the leading master!
I0206 01:37:29.231976 2852 master.cpp:1470] Recovering from registrar
I0206 01:37:29.232695 2857 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0206 01:37:29.234669 2857 recover.cpp:564] Updating replica status to STARTING
I0206 01:37:29.235661 2852 registrar.cpp:307] Recovering registrar
I0206 01:37:29.256692 2843 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 21.762851ms
I0206 01:37:29.256778 2843 replica.cpp:320] Persisted replica status to STARTING
I0206 01:37:29.257149 2843 recover.cpp:473] Replica is in STARTING status
I0206 01:37:29.258880 2854 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14395)@172.17.0.8:37843
I0206 01:37:29.259963 2854 recover.cpp:193] Received a recover response from a replica in STARTING status
I0206 01:37:29.260452 2851 recover.cpp:564] Updating replica status to VOTING
I0206 01:37:29.281335 2852 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 20.649922ms
I0206 01:37:29.281419 2852 replica.cpp:320] Persisted replica status to VOTING
I0206 01:37:29.281673 2847 recover.cpp:578] Successfully joined the Paxos group
I0206 01:37:29.281895 2847 recover.cpp:462] Recover process terminated
I0206 01:37:29.282418 2851 log.cpp:659] Attempting to start the writer
I0206 01:37:29.283726 2858 replica.cpp:493] Replica received implicit promise request from (14396)@172.17.0.8:37843 with proposal 1
I0206 01:37:29.306686 2858 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 22.909015ms
I0206 01:37:29.306771 2858 replica.cpp:342] Persisted promised to 1
I0206 01:37:29.308105 2843 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0206 01:37:29.310048 2848 replica.cpp:388] Replica received explicit promise request from (14397)@172.17.0.8:37843 for position 0 with proposal 2
I0206 01:37:29.331552 2848 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 21.429846ms
I0206 01:37:29.331781 2848 replica.cpp:712] Persisted action at 0
I0206 01:37:29.334182 2848 replica.cpp:537] Replica received write request for position 0 from (14398)@172.17.0.8:37843
I0206 01:37:29.334568 2848 leveldb.cpp:436] Reading position from leveldb took 42222ns
I0206 01:37:29.358628 2848 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.773959ms
I0206 01:37:29.358721 2848 replica.cpp:712] Persisted action at 0
I0206 01:37:29.359482 2848 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0206 01:37:29.392504 2848 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.969204ms
I0206 01:37:29.392735 2848 replica.cpp:712] Persisted action at 0
I0206 01:37:29.392900 2848 replica.cpp:697] Replica learned NOP action at position 0
I0206 01:37:29.394258 2853 log.cpp:675] Writer started with ending position 0
I0206 01:37:29.395711 2853 leveldb.cpp:436] Reading position from leveldb took 77483ns
I0206 01:37:29.397044 2849 registrar.cpp:340] Successfully fetched the registry (0B) in 161.316864ms
I0206 01:37:29.397209 2849 registrar.cpp:439] Applied 1 operations in 43170ns; attempting to update the 'registry'
I0206 01:37:29.398488 2851 log.cpp:683] Attempting to append 170 bytes to the log
I0206 01:37:29.398684 2846 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0206 01:37:29.400101 2846 replica.cpp:537] Replica received write request for position 1 from (14399)@172.17.0.8:37843
I0206 01:37:29.442999 2846 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 42.830963ms
I0206 01:37:29.443084 2846 replica.cpp:712] Persisted action at 1
I0206 01:37:29.444593 2857 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0206 01:37:29.491114 2857 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 46.373782ms
I0206 01:37:29.491322 2857 replica.cpp:712] Persisted action at 1
I0206 01:37:29.491787 2857 replica.cpp:697] Replica learned APPEND action at position 1
I0206 01:37:29.493533 2847 registrar.cpp:484] Successfully updated the 'registry' in 96.251904ms
I0206 01:37:29.493690 2847 registrar.cpp:370] Successfully recovered registrar
I0206 01:37:29.493767 2852 log.cpp:702] Attempting to truncate the log to 1
I0206 01:37:29.493958 2847 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0206 01:37:29.494138 2852 master.cpp:1522] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0206 01:37:29.494397 2850 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0206 01:37:29.496155 2850 replica.cpp:537] Replica received write request for position 2 from (14400)@172.17.0.8:37843
I0206 01:37:29.531819 2850 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 35.583922ms
I0206 01:37:29.531920 2850 replica.cpp:712] Persisted action at 2
I0206 01:37:29.533265 2846 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0206 01:37:29.556874 2846 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 23.561286ms
I0206 01:37:29.556985 2846 leveldb.cpp:399] Deleting ~1 keys from leveldb took 51812ns
I0206 01:37:29.557010 2846 replica.cpp:712] Persisted action at 2
I0206 01:37:29.557040 2846 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0206 01:37:29.567399 2824 scheduler.cpp:154] Version: 0.28.0
I0206 01:37:29.570505 2844 scheduler.cpp:236] New master detected at master@172.17.0.8:37843
I0206 01:37:29.573139 2856 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.8:37843
I0206 01:37:29.577590 2852 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 01:37:29.578420 2852 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.8:47821
I0206 01:37:29.579581 2852 master.cpp:1974] Received subscription request for HTTP framework 'default'
I0206 01:37:29.579772 2852 master.cpp:1751] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0206 01:37:29.580469 2852 master.cpp:2065] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0206 01:37:29.583930 2854 hierarchical.cpp:265] Added framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000
I0206 01:37:29.584069 2854 hierarchical.cpp:1403] No resources available to allocate!
I0206 01:37:29.584120 2854 hierarchical.cpp:1498] No inverse offers to send out!
I0206 01:37:29.584152 2854 hierarchical.cpp:1096] Performed allocation for 0 slaves in 165356ns
I0206 01:37:29.584631 2854 master.hpp:1658] Sending heartbeat to 675a84d4-4d67-4255-b221-c63e4669bf79-0000
I0206 01:37:29.586541 2855 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.8:37843
I0206 01:37:29.588764 2855 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.8:37843
I0206 01:37:29.593571 2850 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.8:37843
I0206 01:37:29.594358 2850 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0206 01:37:29.596145 2850 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0206 01:37:29.596845 2850 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.8:47822
I0206 01:37:29.597326 2850 master.cpp:2719] Processing REQUEST call for framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000 (default)
I0206 01:37:29.597625 2850 hierarchical.cpp:589] Received resource request from framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000
I0206 01:37:29.599362 2824 master.cpp:1027] Master terminating
I0206 01:37:29.599967 2849 hierarchical.cpp:326] Removed framework 675a84d4-4d67-4255-b221-c63e4669bf79-0000
E0206 01:37:29.601402 2856 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (574 ms)
[----------] 22 tests from ContentType/SchedulerTest (15447 ms total)
[----------] Global test environment tear-down
2016-02-06 01:37:30,165:2824(0x7f25627fc700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:35224] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
[==========] 975 tests from 128 test cases ran. (759070 ms total)
[ PASSED ] 974 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ContainerLoggerTest.DefaultToSandbox
1 FAILED TEST
YOU HAVE 9 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.28.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.28.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1454720011-2393
Untagged: mesos-1454720011-2393:latest
Deleted: e42c99fcaa72eb17cc1611376204fd545950000d1790a9268348eecfbe30fde8
Deleted: 185e0e3627ee9ba9e7f301d825100654545b1372782cd9d8f8e13a6d5a268194
Deleted: 1cfb55e4d4cc6825a688ab1754693915bc6a298f02a78709e1802cb846f14d9b
Deleted: 9c1de076fef7cc3437e97beb23056d01b7ccc2e760d45794fd4663740a9b6336
Deleted: fdc9e659e296cb00bbe5a898b7d579a4f22dc92511bb663a369d7c27b7ed9609
Deleted: 128bc0580c75f17df712dc6444eb8fe4b4ca7a2c4e8eb26cb3a8f283c267c68b
Deleted: f82740bbdbbfc84ccbd5ab8f8bea8a6d836107d5abe6f8bd5aee9ebc38d1f51c
Deleted: fa86e52e13d0b16978d9d4054daa7c93d69f6d2011038e6d3bde292e986ad49a
Deleted: 00fecc2a5e8492452e7fc63e4c0a4a17a4a3b3460cd810c2e8e07e76d420cf8e
Deleted: 02681e291122d4d87e6e37d61a92e9bfdc9cf8fa00f7aa5e40fb2e10ec10ba94
Deleted: 181b660e5c6ab86421c2fd12552119d635981cfd09b25b22d9e5540346b4127c
Deleted: c0afc00571a7808ede99b86b6be19c862ca541fc62bf9903a352b12849efed42
Deleted: d69ed4589d9496dc861c5d922e0fe8a09f2ddafefa36576d5a80d4a2002062ed
Deleted: 28508a2d7ecb6046efd260b46698d0100a7d7fe059ff208f91dc6db22a633f73
Deleted: bd17c85774c72a7e2d71b965cefca5569bef2c360923f5ed2eacca24e95baac4
Deleted: 8dc0ccb1195d7dfd955154b09a7368e0886ac2c9667b8e6cd020dccfb698592c
Build step 'Execute shell' marked build as failure