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/01 07:37:23 UTC
Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1599
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1599/changes>
Changes:
[mpark] Avoid construction of temporary strings in `NumberWriter` for doubles.
[mpark] Avoid unnecessary string copies in `json` for protobuf messages.
------------------------------------------
[...truncated 166162 lines...]
[ OK ] ContentType/SchedulerTest.Message/1 (753 ms)
[ RUN ] ContentType/SchedulerTest.Request/0
I0201 06:37:14.787791 964 leveldb.cpp:174] Opened db in 106.362571ms
I0201 06:37:14.839335 964 leveldb.cpp:181] Compacted db in 51.444335ms
I0201 06:37:14.839449 964 leveldb.cpp:196] Created db iterator in 33823ns
I0201 06:37:14.839474 964 leveldb.cpp:202] Seeked to beginning of db in 4453ns
I0201 06:37:14.839490 964 leveldb.cpp:271] Iterated through 0 keys in the db in 466ns
I0201 06:37:14.839558 964 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0201 06:37:14.840559 985 recover.cpp:447] Starting replica recovery
I0201 06:37:14.840919 985 recover.cpp:473] Replica is in EMPTY status
I0201 06:37:14.842473 984 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14168)@172.17.0.3:40620
I0201 06:37:14.843113 998 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0201 06:37:14.844046 987 recover.cpp:564] Updating replica status to STARTING
I0201 06:37:14.848984 994 master.cpp:374] Master 46e5d4cb-69d0-4886-834a-81be19299acd (55c8beaba995) started on 172.17.0.3:40620
I0201 06:37:14.849032 994 master.cpp:376] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/IL0MQh/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/IL0MQh/master" --zk_session_timeout="10secs"
I0201 06:37:14.849753 994 master.cpp:423] Master allowing unauthenticated frameworks to register
I0201 06:37:14.849774 994 master.cpp:426] Master only allowing authenticated slaves to register
I0201 06:37:14.849828 994 credentials.hpp:35] Loading credentials for authentication from '/tmp/IL0MQh/credentials'
I0201 06:37:14.871907 994 master.cpp:466] Using default 'crammd5' authenticator
I0201 06:37:14.872226 994 master.cpp:535] Using default 'basic' HTTP authenticator
I0201 06:37:14.872396 994 master.cpp:569] Authorization enabled
I0201 06:37:14.873556 998 whitelist_watcher.cpp:77] No whitelist given
I0201 06:37:14.873747 988 hierarchical.cpp:144] Initialized hierarchical allocator process
I0201 06:37:14.877233 994 master.cpp:1710] The newly elected leader is master@172.17.0.3:40620 with id 46e5d4cb-69d0-4886-834a-81be19299acd
I0201 06:37:14.877334 994 master.cpp:1723] Elected as the leading master!
I0201 06:37:14.877357 994 master.cpp:1468] Recovering from registrar
I0201 06:37:14.877656 994 registrar.cpp:307] Recovering registrar
I0201 06:37:14.881592 987 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 36.153219ms
I0201 06:37:14.881671 987 replica.cpp:320] Persisted replica status to STARTING
I0201 06:37:14.882040 987 recover.cpp:473] Replica is in STARTING status
I0201 06:37:14.885202 987 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14171)@172.17.0.3:40620
I0201 06:37:14.885702 987 recover.cpp:193] Received a recover response from a replica in STARTING status
I0201 06:37:14.886384 987 recover.cpp:564] Updating replica status to VOTING
I0201 06:37:14.921021 986 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 34.475955ms
I0201 06:37:14.921118 986 replica.cpp:320] Persisted replica status to VOTING
I0201 06:37:14.921421 986 recover.cpp:578] Successfully joined the Paxos group
I0201 06:37:14.921639 986 recover.cpp:462] Recover process terminated
I0201 06:37:14.922479 986 log.cpp:659] Attempting to start the writer
I0201 06:37:14.924413 986 replica.cpp:493] Replica received implicit promise request from (14172)@172.17.0.3:40620 with proposal 1
I0201 06:37:14.962885 986 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 38.28937ms
I0201 06:37:14.962988 986 replica.cpp:342] Persisted promised to 1
I0201 06:37:14.964496 986 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0201 06:37:14.966482 990 replica.cpp:388] Replica received explicit promise request from (14173)@172.17.0.3:40620 for position 0 with proposal 2
I0201 06:37:15.004726 990 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 38.103353ms
I0201 06:37:15.004825 990 replica.cpp:712] Persisted action at 0
I0201 06:37:15.006717 990 replica.cpp:537] Replica received write request for position 0 from (14174)@172.17.0.3:40620
I0201 06:37:15.006811 990 leveldb.cpp:436] Reading position from leveldb took 52465ns
I0201 06:37:15.322005 990 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 315.11997ms
I0201 06:37:15.322109 990 replica.cpp:712] Persisted action at 0
I0201 06:37:15.323254 990 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0201 06:37:15.388959 990 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 65.64352ms
I0201 06:37:15.389062 990 replica.cpp:712] Persisted action at 0
I0201 06:37:15.389104 990 replica.cpp:697] Replica learned NOP action at position 0
I0201 06:37:15.390446 990 log.cpp:675] Writer started with ending position 0
I0201 06:37:15.392088 993 leveldb.cpp:436] Reading position from leveldb took 65786ns
I0201 06:37:15.393354 998 registrar.cpp:340] Successfully fetched the registry (0B) in 515.65312ms
I0201 06:37:15.393581 998 registrar.cpp:439] Applied 1 operations in 43886ns; attempting to update the 'registry'
I0201 06:37:15.394855 984 log.cpp:683] Attempting to append 170 bytes to the log
I0201 06:37:15.395200 984 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0201 06:37:15.396369 984 replica.cpp:537] Replica received write request for position 1 from (14175)@172.17.0.3:40620
I0201 06:37:15.437990 984 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 41.557187ms
I0201 06:37:15.438086 984 replica.cpp:712] Persisted action at 1
I0201 06:37:15.439455 984 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0201 06:37:15.471884 984 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.369215ms
I0201 06:37:15.471971 984 replica.cpp:712] Persisted action at 1
I0201 06:37:15.472005 984 replica.cpp:697] Replica learned APPEND action at position 1
I0201 06:37:15.484326 984 registrar.cpp:484] Successfully updated the 'registry' in 90.621952ms
I0201 06:37:15.484686 984 registrar.cpp:370] Successfully recovered registrar
I0201 06:37:15.484355 995 log.cpp:702] Attempting to truncate the log to 1
I0201 06:37:15.485556 995 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0201 06:37:15.486306 984 master.cpp:1520] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0201 06:37:15.486379 995 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0201 06:37:15.487155 991 replica.cpp:537] Replica received write request for position 2 from (14176)@172.17.0.3:40620
I0201 06:37:15.531323 991 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 44.088138ms
I0201 06:37:15.531431 991 replica.cpp:712] Persisted action at 2
I0201 06:37:15.537574 995 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0201 06:37:15.589211 995 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 51.548509ms
I0201 06:37:15.589373 995 leveldb.cpp:399] Deleting ~1 keys from leveldb took 75169ns
I0201 06:37:15.589400 995 replica.cpp:712] Persisted action at 2
I0201 06:37:15.589432 995 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0201 06:37:15.594434 964 scheduler.cpp:154] Version: 0.28.0
I0201 06:37:15.595213 987 scheduler.cpp:236] New master detected at master@172.17.0.3:40620
I0201 06:37:15.598340 987 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.3:40620
I0201 06:37:15.601701 994 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0201 06:37:15.602259 994 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:57564
I0201 06:37:15.602460 994 master.cpp:1972] Received subscription request for HTTP framework 'default'
I0201 06:37:15.602524 994 master.cpp:1749] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0201 06:37:15.603145 994 master.cpp:2063] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0201 06:37:15.604838 985 hierarchical.cpp:265] Added framework 46e5d4cb-69d0-4886-834a-81be19299acd-0000
I0201 06:37:15.604920 985 hierarchical.cpp:1403] No resources available to allocate!
I0201 06:37:15.604954 985 hierarchical.cpp:1498] No inverse offers to send out!
I0201 06:37:15.604977 985 hierarchical.cpp:1096] Performed allocation for 0 slaves in 111626ns
I0201 06:37:15.606153 983 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.3:40620
I0201 06:37:15.607700 983 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.3:40620
I0201 06:37:15.610669 985 master.hpp:1658] Sending heartbeat to 46e5d4cb-69d0-4886-834a-81be19299acd-0000
I0201 06:37:15.611796 997 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.3:40620
I0201 06:37:15.612253 991 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0201 06:37:15.612501 994 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0201 06:37:15.613044 994 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:57565
I0201 06:37:15.613170 994 master.cpp:2717] Processing REQUEST call for framework 46e5d4cb-69d0-4886-834a-81be19299acd-0000 (default)
I0201 06:37:15.613312 996 hierarchical.cpp:589] Received resource request from framework 46e5d4cb-69d0-4886-834a-81be19299acd-0000
I0201 06:37:15.613697 992 master.cpp:1025] Master terminating
I0201 06:37:15.614269 992 hierarchical.cpp:326] Removed framework 46e5d4cb-69d0-4886-834a-81be19299acd-0000
E0201 06:37:15.615905 992 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/0 (942 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0201 06:37:15.741824 964 leveldb.cpp:174] Opened db in 118.520222ms
I0201 06:37:15.783871 964 leveldb.cpp:181] Compacted db in 41.963596ms
I0201 06:37:15.783978 964 leveldb.cpp:196] Created db iterator in 28902ns
I0201 06:37:15.783996 964 leveldb.cpp:202] Seeked to beginning of db in 3614ns
I0201 06:37:15.784008 964 leveldb.cpp:271] Iterated through 0 keys in the db in 230ns
I0201 06:37:15.784059 964 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0201 06:37:15.784807 985 recover.cpp:447] Starting replica recovery
I0201 06:37:15.785375 985 recover.cpp:473] Replica is in EMPTY status
I0201 06:37:15.787657 985 master.cpp:374] Master 72297952-1798-4ac2-890a-d84fbd92fbdf (55c8beaba995) started on 172.17.0.3:40620
I0201 06:37:15.787683 985 master.cpp:376] Flags at startup: --acls="" --allocation_interval="1secs" --allocator="HierarchicalDRF" --authenticate="false" --authenticate_http="true" --authenticate_slaves="true" --authenticators="crammd5" --authorizers="local" --credentials="/tmp/rjTEfx/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/rjTEfx/master" --zk_session_timeout="10secs"
I0201 06:37:15.788018 985 master.cpp:423] Master allowing unauthenticated frameworks to register
I0201 06:37:15.788031 985 master.cpp:426] Master only allowing authenticated slaves to register
I0201 06:37:15.788041 985 credentials.hpp:35] Loading credentials for authentication from '/tmp/rjTEfx/credentials'
I0201 06:37:15.788416 985 master.cpp:466] Using default 'crammd5' authenticator
I0201 06:37:15.788558 985 master.cpp:535] Using default 'basic' HTTP authenticator
I0201 06:37:15.788684 985 master.cpp:569] Authorization enabled
I0201 06:37:15.790716 985 hierarchical.cpp:144] Initialized hierarchical allocator process
I0201 06:37:15.790791 985 whitelist_watcher.cpp:77] No whitelist given
I0201 06:37:15.791846 991 master.cpp:1710] The newly elected leader is master@172.17.0.3:40620 with id 72297952-1798-4ac2-890a-d84fbd92fbdf
I0201 06:37:15.791882 991 master.cpp:1723] Elected as the leading master!
I0201 06:37:15.791901 991 master.cpp:1468] Recovering from registrar
I0201 06:37:15.792292 987 registrar.cpp:307] Recovering registrar
I0201 06:37:15.794986 991 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (14185)@172.17.0.3:40620
I0201 06:37:15.795300 984 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0201 06:37:15.795876 984 recover.cpp:564] Updating replica status to STARTING
I0201 06:37:15.862304 984 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 66.11133ms
I0201 06:37:15.862388 984 replica.cpp:320] Persisted replica status to STARTING
I0201 06:37:15.862726 993 recover.cpp:473] Replica is in STARTING status
I0201 06:37:15.864308 992 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (14186)@172.17.0.3:40620
I0201 06:37:15.864737 993 recover.cpp:193] Received a recover response from a replica in STARTING status
I0201 06:37:15.865125 992 recover.cpp:564] Updating replica status to VOTING
I0201 06:37:15.920783 993 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 55.410666ms
I0201 06:37:15.920874 993 replica.cpp:320] Persisted replica status to VOTING
I0201 06:37:15.921145 993 recover.cpp:578] Successfully joined the Paxos group
I0201 06:37:15.921362 993 recover.cpp:462] Recover process terminated
I0201 06:37:15.922117 993 log.cpp:659] Attempting to start the writer
I0201 06:37:15.924016 993 replica.cpp:493] Replica received implicit promise request from (14187)@172.17.0.3:40620 with proposal 1
I0201 06:37:15.966851 993 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 42.76483ms
I0201 06:37:15.966938 993 replica.cpp:342] Persisted promised to 1
I0201 06:37:15.968137 993 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0201 06:37:15.969920 988 replica.cpp:388] Replica received explicit promise request from (14188)@172.17.0.3:40620 for position 0 with proposal 2
I0201 06:37:16.021311 988 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 51.322877ms
I0201 06:37:16.021399 988 replica.cpp:712] Persisted action at 0
I0201 06:37:16.022984 988 replica.cpp:537] Replica received write request for position 0 from (14189)@172.17.0.3:40620
I0201 06:37:16.023058 988 leveldb.cpp:436] Reading position from leveldb took 37417ns
I0201 06:37:16.080313 988 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 57.193881ms
I0201 06:37:16.080404 988 replica.cpp:712] Persisted action at 0
I0201 06:37:16.081360 988 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0201 06:37:16.117033 988 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 35.620249ms
I0201 06:37:16.117116 988 replica.cpp:712] Persisted action at 0
I0201 06:37:16.117146 988 replica.cpp:697] Replica learned NOP action at position 0
I0201 06:37:16.118199 988 log.cpp:675] Writer started with ending position 0
I0201 06:37:16.119715 984 leveldb.cpp:436] Reading position from leveldb took 59122ns
I0201 06:37:16.120667 992 registrar.cpp:340] Successfully fetched the registry (0B) in 328.32512ms
I0201 06:37:16.120808 992 registrar.cpp:439] Applied 1 operations in 34483ns; attempting to update the 'registry'
I0201 06:37:16.121909 995 log.cpp:683] Attempting to append 170 bytes to the log
I0201 06:37:16.122098 994 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0201 06:37:16.122797 995 replica.cpp:537] Replica received write request for position 1 from (14190)@172.17.0.3:40620
I0201 06:37:16.150552 995 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 27.692318ms
I0201 06:37:16.150643 995 replica.cpp:712] Persisted action at 1
I0201 06:37:16.151942 984 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0201 06:37:16.168324 984 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 16.320876ms
I0201 06:37:16.168416 984 replica.cpp:712] Persisted action at 1
I0201 06:37:16.168455 984 replica.cpp:697] Replica learned APPEND action at position 1
I0201 06:37:16.170631 995 registrar.cpp:484] Successfully updated the 'registry' in 49.74592ms
I0201 06:37:16.170809 995 registrar.cpp:370] Successfully recovered registrar
I0201 06:37:16.171080 995 log.cpp:702] Attempting to truncate the log to 1
I0201 06:37:16.171600 991 master.cpp:1520] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0201 06:37:16.171958 991 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0201 06:37:16.171778 995 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0201 06:37:16.173411 983 replica.cpp:537] Replica received write request for position 2 from (14191)@172.17.0.3:40620
I0201 06:37:16.192720 983 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 19.220731ms
I0201 06:37:16.192816 983 replica.cpp:712] Persisted action at 2
I0201 06:37:16.194210 983 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0201 06:37:16.210325 983 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 15.798514ms
I0201 06:37:16.210471 983 leveldb.cpp:399] Deleting ~1 keys from leveldb took 66336ns
I0201 06:37:16.210500 983 replica.cpp:712] Persisted action at 2
I0201 06:37:16.210536 983 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0201 06:37:16.217247 964 scheduler.cpp:154] Version: 0.28.0
I0201 06:37:16.218829 983 scheduler.cpp:236] New master detected at master@172.17.0.3:40620
I0201 06:37:16.221400 983 scheduler.cpp:298] Sending SUBSCRIBE call to master@172.17.0.3:40620
I0201 06:37:16.225144 995 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0201 06:37:16.225771 995 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:57566
I0201 06:37:16.226301 995 master.cpp:1972] Received subscription request for HTTP framework 'default'
I0201 06:37:16.226382 995 master.cpp:1749] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0201 06:37:16.227147 995 master.cpp:2063] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0201 06:37:16.229533 992 hierarchical.cpp:265] Added framework 72297952-1798-4ac2-890a-d84fbd92fbdf-0000
I0201 06:37:16.229650 992 hierarchical.cpp:1403] No resources available to allocate!
I0201 06:37:16.229696 992 hierarchical.cpp:1498] No inverse offers to send out!
I0201 06:37:16.229724 992 hierarchical.cpp:1096] Performed allocation for 0 slaves in 147076ns
I0201 06:37:16.230444 995 master.hpp:1658] Sending heartbeat to 72297952-1798-4ac2-890a-d84fbd92fbdf-0000
I0201 06:37:16.233240 998 scheduler.cpp:457] Enqueuing event SUBSCRIBED received from master@172.17.0.3:40620
I0201 06:37:16.234122 998 scheduler.cpp:457] Enqueuing event HEARTBEAT received from master@172.17.0.3:40620
I0201 06:37:16.234851 998 master_maintenance_tests.cpp:177] Ignoring HEARTBEAT event
I0201 06:37:16.236366 998 scheduler.cpp:298] Sending REQUEST call to master@172.17.0.3:40620
I0201 06:37:16.240417 988 process.cpp:3141] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0201 06:37:16.240981 988 http.cpp:503] HTTP POST for /master/api/v1/scheduler from 172.17.0.3:57567
I0201 06:37:16.241443 988 master.cpp:2717] Processing REQUEST call for framework 72297952-1798-4ac2-890a-d84fbd92fbdf-0000 (default)
I0201 06:37:16.241770 988 hierarchical.cpp:589] Received resource request from framework 72297952-1798-4ac2-890a-d84fbd92fbdf-0000
I0201 06:37:16.244737 964 master.cpp:1025] Master terminating
I0201 06:37:16.245245 985 hierarchical.cpp:326] Removed framework 72297952-1798-4ac2-890a-d84fbd92fbdf-0000
E0201 06:37:16.250202 993 scheduler.cpp:431] End-Of-File received from master. The master closed the event stream
[ OK ] ContentType/SchedulerTest.Request/1 (638 ms)
[----------] 22 tests from ContentType/SchedulerTest (18609 ms total)
[----------] Global test environment tear-down
2016-02-01 06:37:16,285:964(0x7f9a777fe700):ZOO_ERROR@handle_socket_error_msg@1697: Socket [127.0.0.1:53290] zk retcode=-4, errno=111(Connection refused): server refused to accept the client
[==========] 963 tests from 125 test cases ran. (856907 ms total)
[ PASSED ] 962 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] SlaveTest.CancelSlaveShutdown
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-1454305278-5523
Untagged: mesos-1454305278-5523:latest
Deleted: c0ef1cf569663af93a41f2d277c907649ee46670e09031c2bf76953be4c4189b
Deleted: 74dd832ea96e078fecc611cceaa26cecebf3d911c1334458348423bad0bfe793
Deleted: 74e7185277b394e82c9d547799006cf2b60a7afc8fd6680414ae9eef715c347d
Deleted: ee1d1507e8d086a619708598ec96e254459989968ea41c54d571a4a3107c5ce7
Deleted: abef8bb2044f551b1577be9ce6974d7a59779c2d8b799e0c8ad20b10ba66971f
Deleted: b8c7fb6cef14c6640f8d6b23e8b664563dc080e12fc98be578c0867d70faae0a
Deleted: 485225b2077cd54d5320fec390a3dc486b7ba8659abf93f647255407e4a6303e
Deleted: f17fd4e51351a04ca53e70c38761491ae765144d94670a2af239c03dbe62cd75
Deleted: cfb99beeae5aabfa135ed35575008d9aecaaf77cfc5f4567c41f9dcc43c00d7b
Deleted: ac469a03975bfab5ac2a3c5de96d5442a02dd874e2d24be1c2169009cad2852e
Deleted: 836a0fb038da5d5c9d9406c5878fff08dabf67da0e8e6b52cc0aaa70205a0149
Deleted: cc60b591a95e94bc53aa70e2fd60f0449f9fac3730b31ed7a4f24791045b6558
Deleted: 2aa9a368f0ebafb1c4c4d9ad13991950f5583b7782dd7cf9e1d0a020bdeba0b1
Deleted: 297c28c5322b8a89add396bf6dfe9929fecae6d8c176b992ffdd54be4b68e297
Deleted: 68f52751581d33c4212ee0b2801492d7f95e7a6dfd4eed63e7153bb03f8b1816
Deleted: 087093b4f2ab7407dd8e18e17c14a25d7f235d55b7de0ae9b1a5a39cae4bde44
Build step 'Execute shell' marked build as failure
Re: Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1599
Posted by Joseph Wu <jo...@mesosphere.io>.
There doesn't seem to be a JIRA tracking this, so I filed this one:
https://issues.apache.org/jira/browse/MESOS-4574
On Sun, Jan 31, 2016 at 10:37 PM, Apache Jenkins Server <
jenkins@builds.apache.org> wrote:
> See <
> https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1599/changes
> >
>
> Changes:
>
> [mpark] Avoid construction of temporary strings in `NumberWriter` for
> doubles.
>
> [mpark] Avoid unnecessary string copies in `json` for protobuf messages.
>
> ------------------------------------------
> [...truncated 166162 lines...]
> [ OK ] ContentType/SchedulerTest.Message/1 (753 ms)
> [ RUN ] ContentType/SchedulerTest.Request/0
> I0201 06:37:14.787791 964 leveldb.cpp:174] Opened db in 106.362571ms
> I0201 06:37:14.839335 964 leveldb.cpp:181] Compacted db in 51.444335ms
> I0201 06:37:14.839449 964 leveldb.cpp:196] Created db iterator in 33823ns
> I0201 06:37:14.839474 964 leveldb.cpp:202] Seeked to beginning of db in
> 4453ns
> I0201 06:37:14.839490 964 leveldb.cpp:271] Iterated through 0 keys in
> the db in 466ns
> I0201 06:37:14.839558 964 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0201 06:37:14.840559 985 recover.cpp:447] Starting replica recovery
> I0201 06:37:14.840919 985 recover.cpp:473] Replica is in EMPTY status
> I0201 06:37:14.842473 984 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14168)@172.17.0.3:40620
> I0201 06:37:14.843113 998 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0201 06:37:14.844046 987 recover.cpp:564] Updating replica status to
> STARTING
> I0201 06:37:14.848984 994 master.cpp:374] Master
> 46e5d4cb-69d0-4886-834a-81be19299acd (55c8beaba995) started on
> 172.17.0.3:40620
> I0201 06:37:14.849032 994 master.cpp:376] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/IL0MQh/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/IL0MQh/master" --zk_session_timeout="10secs"
> I0201 06:37:14.849753 994 master.cpp:423] Master allowing
> unauthenticated frameworks to register
> I0201 06:37:14.849774 994 master.cpp:426] Master only allowing
> authenticated slaves to register
> I0201 06:37:14.849828 994 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/IL0MQh/credentials'
> I0201 06:37:14.871907 994 master.cpp:466] Using default 'crammd5'
> authenticator
> I0201 06:37:14.872226 994 master.cpp:535] Using default 'basic' HTTP
> authenticator
> I0201 06:37:14.872396 994 master.cpp:569] Authorization enabled
> I0201 06:37:14.873556 998 whitelist_watcher.cpp:77] No whitelist given
> I0201 06:37:14.873747 988 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0201 06:37:14.877233 994 master.cpp:1710] The newly elected leader is
> master@172.17.0.3:40620 with id 46e5d4cb-69d0-4886-834a-81be19299acd
> I0201 06:37:14.877334 994 master.cpp:1723] Elected as the leading master!
> I0201 06:37:14.877357 994 master.cpp:1468] Recovering from registrar
> I0201 06:37:14.877656 994 registrar.cpp:307] Recovering registrar
> I0201 06:37:14.881592 987 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 36.153219ms
> I0201 06:37:14.881671 987 replica.cpp:320] Persisted replica status to
> STARTING
> I0201 06:37:14.882040 987 recover.cpp:473] Replica is in STARTING status
> I0201 06:37:14.885202 987 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14171)@172.17.0.3:40620
> I0201 06:37:14.885702 987 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0201 06:37:14.886384 987 recover.cpp:564] Updating replica status to
> VOTING
> I0201 06:37:14.921021 986 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 34.475955ms
> I0201 06:37:14.921118 986 replica.cpp:320] Persisted replica status to
> VOTING
> I0201 06:37:14.921421 986 recover.cpp:578] Successfully joined the Paxos
> group
> I0201 06:37:14.921639 986 recover.cpp:462] Recover process terminated
> I0201 06:37:14.922479 986 log.cpp:659] Attempting to start the writer
> I0201 06:37:14.924413 986 replica.cpp:493] Replica received implicit
> promise request from (14172)@172.17.0.3:40620 with proposal 1
> I0201 06:37:14.962885 986 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 38.28937ms
> I0201 06:37:14.962988 986 replica.cpp:342] Persisted promised to 1
> I0201 06:37:14.964496 986 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0201 06:37:14.966482 990 replica.cpp:388] Replica received explicit
> promise request from (14173)@172.17.0.3:40620 for position 0 with
> proposal 2
> I0201 06:37:15.004726 990 leveldb.cpp:341] Persisting action (8 bytes) to
> leveldb took 38.103353ms
> I0201 06:37:15.004825 990 replica.cpp:712] Persisted action at 0
> I0201 06:37:15.006717 990 replica.cpp:537] Replica received write
> request for position 0 from (14174)@172.17.0.3:40620
> I0201 06:37:15.006811 990 leveldb.cpp:436] Reading position from leveldb
> took 52465ns
> I0201 06:37:15.322005 990 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 315.11997ms
> I0201 06:37:15.322109 990 replica.cpp:712] Persisted action at 0
> I0201 06:37:15.323254 990 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0201 06:37:15.388959 990 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 65.64352ms
> I0201 06:37:15.389062 990 replica.cpp:712] Persisted action at 0
> I0201 06:37:15.389104 990 replica.cpp:697] Replica learned NOP action at
> position 0
> I0201 06:37:15.390446 990 log.cpp:675] Writer started with ending
> position 0
> I0201 06:37:15.392088 993 leveldb.cpp:436] Reading position from leveldb
> took 65786ns
> I0201 06:37:15.393354 998 registrar.cpp:340] Successfully fetched the
> registry (0B) in 515.65312ms
> I0201 06:37:15.393581 998 registrar.cpp:439] Applied 1 operations in
> 43886ns; attempting to update the 'registry'
> I0201 06:37:15.394855 984 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0201 06:37:15.395200 984 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0201 06:37:15.396369 984 replica.cpp:537] Replica received write
> request for position 1 from (14175)@172.17.0.3:40620
> I0201 06:37:15.437990 984 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 41.557187ms
> I0201 06:37:15.438086 984 replica.cpp:712] Persisted action at 1
> I0201 06:37:15.439455 984 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0201 06:37:15.471884 984 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 32.369215ms
> I0201 06:37:15.471971 984 replica.cpp:712] Persisted action at 1
> I0201 06:37:15.472005 984 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0201 06:37:15.484326 984 registrar.cpp:484] Successfully updated the
> 'registry' in 90.621952ms
> I0201 06:37:15.484686 984 registrar.cpp:370] Successfully recovered
> registrar
> I0201 06:37:15.484355 995 log.cpp:702] Attempting to truncate the log to
> 1
> I0201 06:37:15.485556 995 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0201 06:37:15.486306 984 master.cpp:1520] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0201 06:37:15.486379 995 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0201 06:37:15.487155 991 replica.cpp:537] Replica received write
> request for position 2 from (14176)@172.17.0.3:40620
> I0201 06:37:15.531323 991 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 44.088138ms
> I0201 06:37:15.531431 991 replica.cpp:712] Persisted action at 2
> I0201 06:37:15.537574 995 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0201 06:37:15.589211 995 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 51.548509ms
> I0201 06:37:15.589373 995 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 75169ns
> I0201 06:37:15.589400 995 replica.cpp:712] Persisted action at 2
> I0201 06:37:15.589432 995 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0201 06:37:15.594434 964 scheduler.cpp:154] Version: 0.28.0
> I0201 06:37:15.595213 987 scheduler.cpp:236] New master detected at
> master@172.17.0.3:40620
> I0201 06:37:15.598340 987 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.3:40620
> I0201 06:37:15.601701 994 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0201 06:37:15.602259 994 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:57564
> I0201 06:37:15.602460 994 master.cpp:1972] Received subscription request
> for HTTP framework 'default'
> I0201 06:37:15.602524 994 master.cpp:1749] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0201 06:37:15.603145 994 master.cpp:2063] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0201 06:37:15.604838 985 hierarchical.cpp:265] Added framework
> 46e5d4cb-69d0-4886-834a-81be19299acd-0000
> I0201 06:37:15.604920 985 hierarchical.cpp:1403] No resources available
> to allocate!
> I0201 06:37:15.604954 985 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0201 06:37:15.604977 985 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 111626ns
> I0201 06:37:15.606153 983 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.3:40620
> I0201 06:37:15.607700 983 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.3:40620
> I0201 06:37:15.610669 985 master.hpp:1658] Sending heartbeat to
> 46e5d4cb-69d0-4886-834a-81be19299acd-0000
> I0201 06:37:15.611796 997 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.3:40620
> I0201 06:37:15.612253 991 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0201 06:37:15.612501 994 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0201 06:37:15.613044 994 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:57565
> I0201 06:37:15.613170 994 master.cpp:2717] Processing REQUEST call for
> framework 46e5d4cb-69d0-4886-834a-81be19299acd-0000 (default)
> I0201 06:37:15.613312 996 hierarchical.cpp:589] Received resource
> request from framework 46e5d4cb-69d0-4886-834a-81be19299acd-0000
> I0201 06:37:15.613697 992 master.cpp:1025] Master terminating
> I0201 06:37:15.614269 992 hierarchical.cpp:326] Removed framework
> 46e5d4cb-69d0-4886-834a-81be19299acd-0000
> E0201 06:37:15.615905 992 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/0 (942 ms)
> [ RUN ] ContentType/SchedulerTest.Request/1
> I0201 06:37:15.741824 964 leveldb.cpp:174] Opened db in 118.520222ms
> I0201 06:37:15.783871 964 leveldb.cpp:181] Compacted db in 41.963596ms
> I0201 06:37:15.783978 964 leveldb.cpp:196] Created db iterator in 28902ns
> I0201 06:37:15.783996 964 leveldb.cpp:202] Seeked to beginning of db in
> 3614ns
> I0201 06:37:15.784008 964 leveldb.cpp:271] Iterated through 0 keys in
> the db in 230ns
> I0201 06:37:15.784059 964 replica.cpp:779] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0201 06:37:15.784807 985 recover.cpp:447] Starting replica recovery
> I0201 06:37:15.785375 985 recover.cpp:473] Replica is in EMPTY status
> I0201 06:37:15.787657 985 master.cpp:374] Master
> 72297952-1798-4ac2-890a-d84fbd92fbdf (55c8beaba995) started on
> 172.17.0.3:40620
> I0201 06:37:15.787683 985 master.cpp:376] Flags at startup: --acls=""
> --allocation_interval="1secs" --allocator="HierarchicalDRF"
> --authenticate="false" --authenticate_http="true"
> --authenticate_slaves="true" --authenticators="crammd5"
> --authorizers="local" --credentials="/tmp/rjTEfx/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/rjTEfx/master" --zk_session_timeout="10secs"
> I0201 06:37:15.788018 985 master.cpp:423] Master allowing
> unauthenticated frameworks to register
> I0201 06:37:15.788031 985 master.cpp:426] Master only allowing
> authenticated slaves to register
> I0201 06:37:15.788041 985 credentials.hpp:35] Loading credentials for
> authentication from '/tmp/rjTEfx/credentials'
> I0201 06:37:15.788416 985 master.cpp:466] Using default 'crammd5'
> authenticator
> I0201 06:37:15.788558 985 master.cpp:535] Using default 'basic' HTTP
> authenticator
> I0201 06:37:15.788684 985 master.cpp:569] Authorization enabled
> I0201 06:37:15.790716 985 hierarchical.cpp:144] Initialized hierarchical
> allocator process
> I0201 06:37:15.790791 985 whitelist_watcher.cpp:77] No whitelist given
> I0201 06:37:15.791846 991 master.cpp:1710] The newly elected leader is
> master@172.17.0.3:40620 with id 72297952-1798-4ac2-890a-d84fbd92fbdf
> I0201 06:37:15.791882 991 master.cpp:1723] Elected as the leading master!
> I0201 06:37:15.791901 991 master.cpp:1468] Recovering from registrar
> I0201 06:37:15.792292 987 registrar.cpp:307] Recovering registrar
> I0201 06:37:15.794986 991 replica.cpp:673] Replica in EMPTY status
> received a broadcasted recover request from (14185)@172.17.0.3:40620
> I0201 06:37:15.795300 984 recover.cpp:193] Received a recover response
> from a replica in EMPTY status
> I0201 06:37:15.795876 984 recover.cpp:564] Updating replica status to
> STARTING
> I0201 06:37:15.862304 984 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 66.11133ms
> I0201 06:37:15.862388 984 replica.cpp:320] Persisted replica status to
> STARTING
> I0201 06:37:15.862726 993 recover.cpp:473] Replica is in STARTING status
> I0201 06:37:15.864308 992 replica.cpp:673] Replica in STARTING status
> received a broadcasted recover request from (14186)@172.17.0.3:40620
> I0201 06:37:15.864737 993 recover.cpp:193] Received a recover response
> from a replica in STARTING status
> I0201 06:37:15.865125 992 recover.cpp:564] Updating replica status to
> VOTING
> I0201 06:37:15.920783 993 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 55.410666ms
> I0201 06:37:15.920874 993 replica.cpp:320] Persisted replica status to
> VOTING
> I0201 06:37:15.921145 993 recover.cpp:578] Successfully joined the Paxos
> group
> I0201 06:37:15.921362 993 recover.cpp:462] Recover process terminated
> I0201 06:37:15.922117 993 log.cpp:659] Attempting to start the writer
> I0201 06:37:15.924016 993 replica.cpp:493] Replica received implicit
> promise request from (14187)@172.17.0.3:40620 with proposal 1
> I0201 06:37:15.966851 993 leveldb.cpp:304] Persisting metadata (8 bytes)
> to leveldb took 42.76483ms
> I0201 06:37:15.966938 993 replica.cpp:342] Persisted promised to 1
> I0201 06:37:15.968137 993 coordinator.cpp:238] Coordinator attempting to
> fill missing positions
> I0201 06:37:15.969920 988 replica.cpp:388] Replica received explicit
> promise request from (14188)@172.17.0.3:40620 for position 0 with
> proposal 2
> I0201 06:37:16.021311 988 leveldb.cpp:341] Persisting action (8 bytes)
> to leveldb took 51.322877ms
> I0201 06:37:16.021399 988 replica.cpp:712] Persisted action at 0
> I0201 06:37:16.022984 988 replica.cpp:537] Replica received write
> request for position 0 from (14189)@172.17.0.3:40620
> I0201 06:37:16.023058 988 leveldb.cpp:436] Reading position from leveldb
> took 37417ns
> I0201 06:37:16.080313 988 leveldb.cpp:341] Persisting action (14 bytes)
> to leveldb took 57.193881ms
> I0201 06:37:16.080404 988 replica.cpp:712] Persisted action at 0
> I0201 06:37:16.081360 988 replica.cpp:691] Replica received learned
> notice for position 0 from @0.0.0.0:0
> I0201 06:37:16.117033 988 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 35.620249ms
> I0201 06:37:16.117116 988 replica.cpp:712] Persisted action at 0
> I0201 06:37:16.117146 988 replica.cpp:697] Replica learned NOP action at
> position 0
> I0201 06:37:16.118199 988 log.cpp:675] Writer started with ending
> position 0
> I0201 06:37:16.119715 984 leveldb.cpp:436] Reading position from leveldb
> took 59122ns
> I0201 06:37:16.120667 992 registrar.cpp:340] Successfully fetched the
> registry (0B) in 328.32512ms
> I0201 06:37:16.120808 992 registrar.cpp:439] Applied 1 operations in
> 34483ns; attempting to update the 'registry'
> I0201 06:37:16.121909 995 log.cpp:683] Attempting to append 170 bytes to
> the log
> I0201 06:37:16.122098 994 coordinator.cpp:348] Coordinator attempting to
> write APPEND action at position 1
> I0201 06:37:16.122797 995 replica.cpp:537] Replica received write
> request for position 1 from (14190)@172.17.0.3:40620
> I0201 06:37:16.150552 995 leveldb.cpp:341] Persisting action (189 bytes)
> to leveldb took 27.692318ms
> I0201 06:37:16.150643 995 replica.cpp:712] Persisted action at 1
> I0201 06:37:16.151942 984 replica.cpp:691] Replica received learned
> notice for position 1 from @0.0.0.0:0
> I0201 06:37:16.168324 984 leveldb.cpp:341] Persisting action (191 bytes)
> to leveldb took 16.320876ms
> I0201 06:37:16.168416 984 replica.cpp:712] Persisted action at 1
> I0201 06:37:16.168455 984 replica.cpp:697] Replica learned APPEND action
> at position 1
> I0201 06:37:16.170631 995 registrar.cpp:484] Successfully updated the
> 'registry' in 49.74592ms
> I0201 06:37:16.170809 995 registrar.cpp:370] Successfully recovered
> registrar
> I0201 06:37:16.171080 995 log.cpp:702] Attempting to truncate the log to
> 1
> I0201 06:37:16.171600 991 master.cpp:1520] Recovered 0 slaves from the
> Registry (131B) ; allowing 10mins for slaves to re-register
> I0201 06:37:16.171958 991 hierarchical.cpp:171] Skipping recovery of
> hierarchical allocator: nothing to recover
> I0201 06:37:16.171778 995 coordinator.cpp:348] Coordinator attempting to
> write TRUNCATE action at position 2
> I0201 06:37:16.173411 983 replica.cpp:537] Replica received write
> request for position 2 from (14191)@172.17.0.3:40620
> I0201 06:37:16.192720 983 leveldb.cpp:341] Persisting action (16 bytes)
> to leveldb took 19.220731ms
> I0201 06:37:16.192816 983 replica.cpp:712] Persisted action at 2
> I0201 06:37:16.194210 983 replica.cpp:691] Replica received learned
> notice for position 2 from @0.0.0.0:0
> I0201 06:37:16.210325 983 leveldb.cpp:341] Persisting action (18 bytes)
> to leveldb took 15.798514ms
> I0201 06:37:16.210471 983 leveldb.cpp:399] Deleting ~1 keys from leveldb
> took 66336ns
> I0201 06:37:16.210500 983 replica.cpp:712] Persisted action at 2
> I0201 06:37:16.210536 983 replica.cpp:697] Replica learned TRUNCATE
> action at position 2
> I0201 06:37:16.217247 964 scheduler.cpp:154] Version: 0.28.0
> I0201 06:37:16.218829 983 scheduler.cpp:236] New master detected at
> master@172.17.0.3:40620
> I0201 06:37:16.221400 983 scheduler.cpp:298] Sending SUBSCRIBE call to
> master@172.17.0.3:40620
> I0201 06:37:16.225144 995 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0201 06:37:16.225771 995 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:57566
> I0201 06:37:16.226301 995 master.cpp:1972] Received subscription request
> for HTTP framework 'default'
> I0201 06:37:16.226382 995 master.cpp:1749] Authorizing framework
> principal 'test-principal' to receive offers for role '*'
> I0201 06:37:16.227147 995 master.cpp:2063] Subscribing framework
> 'default' with checkpointing disabled and capabilities [ ]
> I0201 06:37:16.229533 992 hierarchical.cpp:265] Added framework
> 72297952-1798-4ac2-890a-d84fbd92fbdf-0000
> I0201 06:37:16.229650 992 hierarchical.cpp:1403] No resources available
> to allocate!
> I0201 06:37:16.229696 992 hierarchical.cpp:1498] No inverse offers to
> send out!
> I0201 06:37:16.229724 992 hierarchical.cpp:1096] Performed allocation
> for 0 slaves in 147076ns
> I0201 06:37:16.230444 995 master.hpp:1658] Sending heartbeat to
> 72297952-1798-4ac2-890a-d84fbd92fbdf-0000
> I0201 06:37:16.233240 998 scheduler.cpp:457] Enqueuing event SUBSCRIBED
> received from master@172.17.0.3:40620
> I0201 06:37:16.234122 998 scheduler.cpp:457] Enqueuing event HEARTBEAT
> received from master@172.17.0.3:40620
> I0201 06:37:16.234851 998 master_maintenance_tests.cpp:177] Ignoring
> HEARTBEAT event
> I0201 06:37:16.236366 998 scheduler.cpp:298] Sending REQUEST call to
> master@172.17.0.3:40620
> I0201 06:37:16.240417 988 process.cpp:3141] Handling HTTP event for
> process 'master' with path: '/master/api/v1/scheduler'
> I0201 06:37:16.240981 988 http.cpp:503] HTTP POST for
> /master/api/v1/scheduler from 172.17.0.3:57567
> I0201 06:37:16.241443 988 master.cpp:2717] Processing REQUEST call for
> framework 72297952-1798-4ac2-890a-d84fbd92fbdf-0000 (default)
> I0201 06:37:16.241770 988 hierarchical.cpp:589] Received resource
> request from framework 72297952-1798-4ac2-890a-d84fbd92fbdf-0000
> I0201 06:37:16.244737 964 master.cpp:1025] Master terminating
> I0201 06:37:16.245245 985 hierarchical.cpp:326] Removed framework
> 72297952-1798-4ac2-890a-d84fbd92fbdf-0000
> E0201 06:37:16.250202 993 scheduler.cpp:431] End-Of-File received from
> master. The master closed the event stream
> [ OK ] ContentType/SchedulerTest.Request/1 (638 ms)
> [----------] 22 tests from ContentType/SchedulerTest (18609 ms total)
>
> [----------] Global test environment tear-down
> 2016-02-01
> 06:37:16,285:964(0x7f9a777fe700):ZOO_ERROR@handle_socket_error_msg@1697:
> Socket [127.0.0.1:53290] zk retcode=-4, errno=111(Connection refused):
> server refused to accept the client
> [==========] 963 tests from 125 test cases ran. (856907 ms total)
> [ PASSED ] 962 tests.
> [ FAILED ] 1 test, listed below:
> [ FAILED ] SlaveTest.CancelSlaveShutdown
>
> 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-1454305278-5523
> Untagged: mesos-1454305278-5523:latest
> Deleted: c0ef1cf569663af93a41f2d277c907649ee46670e09031c2bf76953be4c4189b
> Deleted: 74dd832ea96e078fecc611cceaa26cecebf3d911c1334458348423bad0bfe793
> Deleted: 74e7185277b394e82c9d547799006cf2b60a7afc8fd6680414ae9eef715c347d
> Deleted: ee1d1507e8d086a619708598ec96e254459989968ea41c54d571a4a3107c5ce7
> Deleted: abef8bb2044f551b1577be9ce6974d7a59779c2d8b799e0c8ad20b10ba66971f
> Deleted: b8c7fb6cef14c6640f8d6b23e8b664563dc080e12fc98be578c0867d70faae0a
> Deleted: 485225b2077cd54d5320fec390a3dc486b7ba8659abf93f647255407e4a6303e
> Deleted: f17fd4e51351a04ca53e70c38761491ae765144d94670a2af239c03dbe62cd75
> Deleted: cfb99beeae5aabfa135ed35575008d9aecaaf77cfc5f4567c41f9dcc43c00d7b
> Deleted: ac469a03975bfab5ac2a3c5de96d5442a02dd874e2d24be1c2169009cad2852e
> Deleted: 836a0fb038da5d5c9d9406c5878fff08dabf67da0e8e6b52cc0aaa70205a0149
> Deleted: cc60b591a95e94bc53aa70e2fd60f0449f9fac3730b31ed7a4f24791045b6558
> Deleted: 2aa9a368f0ebafb1c4c4d9ad13991950f5583b7782dd7cf9e1d0a020bdeba0b1
> Deleted: 297c28c5322b8a89add396bf6dfe9929fecae6d8c176b992ffdd54be4b68e297
> Deleted: 68f52751581d33c4212ee0b2801492d7f95e7a6dfd4eed63e7153bb03f8b1816
> Deleted: 087093b4f2ab7407dd8e18e17c14a25d7f235d55b7de0ae9b1a5a39cae4bde44
> Build step 'Execute shell' marked build as failure
>
Jenkins build is back to normal : Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,centos:7,docker||Hadoop #1600
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=centos%3A7,label_exp=docker%7C%7CHadoop/1600/changes>