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/03/17 05:28:28 UTC
Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1824
See <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1824/>
------------------------------------------
Started by upstream project "Mesos" build number 1824
originally caused by:
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
Started by an SCM change
[EnvInject] - Loading node environment variables.
Building remotely on H9 (Mapreduce Falcon Hadoop Pig Zookeeper Tez Hdfs yahoo-not-h2) in workspace <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/ws/>
Cloning the remote Git repository
Using shallow clone
Cloning repository https://git-wip-us.apache.org/repos/asf/mesos.git
> git init <https://builds.apache.org/job/Mesos/COMPILER=gcc,CONFIGURATION=--verbose,ENVIRONMENT=GLOG_v=1%20MESOS_VERBOSE=1,OS=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/ws/> # timeout=10
Fetching upstream changes from https://git-wip-us.apache.org/repos/asf/mesos.git
> git --version # timeout=10
> git -c core.askpass=true fetch --tags --progress https://git-wip-us.apache.org/repos/asf/mesos.git +refs/heads/*:refs/remotes/origin/* --depth=1 # timeout=60
> git config remote.origin.url https://git-wip-us.apache.org/repos/asf/mesos.git # timeout=10
> git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10
> git config remote.origin.url https://git-wip-us.apache.org/repos/asf/mesos.git # timeout=10
Fetching upstream changes from https://git-wip-us.apache.org/repos/asf/mesos.git
> git -c core.askpass=true fetch --tags --progress https://git-wip-us.apache.org/repos/asf/mesos.git +refs/heads/*:refs/remotes/origin/* # timeout=60
Checking out Revision 9029e4347cd3619709f8f65fa3ee5e9b7c000bd5 (origin/master)
> git config core.sparsecheckout # timeout=10
> git checkout -f 9029e4347cd3619709f8f65fa3ee5e9b7c000bd5
FATAL: Could not checkout 9029e4347cd3619709f8f65fa3ee5e9b7c000bd5
hudson.plugins.git.GitException: Could not checkout 9029e4347cd3619709f8f65fa3ee5e9b7c000bd5
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl$9.execute(CliGitAPIImpl.java:1964)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler$1.call(RemoteGitImpl.java:152)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler$1.call(RemoteGitImpl.java:145)
at hudson.remoting.UserRequest.perform(UserRequest.java:120)
at hudson.remoting.UserRequest.perform(UserRequest.java:48)
at hudson.remoting.Request$2.run(Request.java:326)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:68)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
at ......remote call to H9(Native Method)
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1416)
at hudson.remoting.UserResponse.retrieve(UserRequest.java:220)
at hudson.remoting.Channel.call(Channel.java:781)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler.execute(RemoteGitImpl.java:145)
at sun.reflect.GeneratedMethodAccessor407.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler.invoke(RemoteGitImpl.java:131)
at com.sun.proxy.$Proxy65.execute(Unknown Source)
at hudson.plugins.git.GitSCM.checkout(GitSCM.java:1081)
at hudson.scm.SCM.checkout(SCM.java:485)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1276)
at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:607)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:86)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:529)
at hudson.model.Run.execute(Run.java:1738)
at hudson.matrix.MatrixRun.run(MatrixRun.java:146)
at hudson.model.ResourceController.execute(ResourceController.java:98)
at hudson.model.Executor.run(Executor.java:410)
Caused by: hudson.plugins.git.GitException: Command "git checkout -f 9029e4347cd3619709f8f65fa3ee5e9b7c000bd5" returned status code 128:
stdout:
stderr: fatal: reference is not a tree: 9029e4347cd3619709f8f65fa3ee5e9b7c000bd5
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.launchCommandIn(CliGitAPIImpl.java:1693)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl.access$500(CliGitAPIImpl.java:62)
at org.jenkinsci.plugins.gitclient.CliGitAPIImpl$9.execute(CliGitAPIImpl.java:1956)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler$1.call(RemoteGitImpl.java:152)
at org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler$1.call(RemoteGitImpl.java:145)
at hudson.remoting.UserRequest.perform(UserRequest.java:120)
at hudson.remoting.UserRequest.perform(UserRequest.java:48)
at hudson.remoting.Request$2.run(Request.java:326)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:68)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
Jenkins build is back to normal : Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1826
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=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1826/changes>
Build failed in Jenkins: Mesos » gcc,--verbose,GLOG_v=1 MESOS_VERBOSE=1,ubuntu:14.04,(docker||Hadoop)&&(!ubuntu-us1) #1825
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=ubuntu%3A14.04,label_exp=(docker%7C%7CHadoop)&&(!ubuntu-us1)/1825/changes>
Changes:
[yujie.jay] Fix the mis-leading URI fetcher error message (MESOS-4954).
------------------------------------------
[...truncated 174603 lines...]
I0317 07:46:14.664978 1263 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/o6aSKw/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.29.0/_inst/share/mesos/webui" --work_dir="/tmp/o6aSKw/master" --zk_session_timeout="10secs"
I0317 07:46:14.665285 1263 master.cpp:425] Master allowing unauthenticated frameworks to register
I0317 07:46:14.665295 1263 master.cpp:428] Master only allowing authenticated slaves to register
I0317 07:46:14.665302 1263 credentials.hpp:35] Loading credentials for authentication from '/tmp/o6aSKw/credentials'
I0317 07:46:14.665606 1263 master.cpp:468] Using default 'crammd5' authenticator
I0317 07:46:14.665757 1263 master.cpp:537] Using default 'basic' HTTP authenticator
I0317 07:46:14.665966 1263 master.cpp:571] Authorization enabled
I0317 07:46:14.666133 1256 hierarchical.cpp:144] Initialized hierarchical allocator process
I0317 07:46:14.666149 1265 whitelist_watcher.cpp:77] No whitelist given
I0317 07:46:14.667985 1259 master.cpp:1806] The newly elected leader is master@172.17.0.2:34878 with id 13a47b0d-6589-436c-a4b8-360feb8304a3
I0317 07:46:14.668017 1259 master.cpp:1819] Elected as the leading master!
I0317 07:46:14.668041 1259 master.cpp:1508] Recovering from registrar
I0317 07:46:14.668203 1255 registrar.cpp:307] Recovering registrar
I0317 07:46:14.690656 1252 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 26.525102ms
I0317 07:46:14.690706 1252 replica.cpp:320] Persisted replica status to STARTING
I0317 07:46:14.691036 1259 recover.cpp:473] Replica is in STARTING status
I0317 07:46:14.691994 1262 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15646)@172.17.0.2:34878
I0317 07:46:14.692454 1263 recover.cpp:193] Received a recover response from a replica in STARTING status
I0317 07:46:14.693012 1266 recover.cpp:564] Updating replica status to VOTING
I0317 07:46:14.720782 1255 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 27.434787ms
I0317 07:46:14.720821 1255 replica.cpp:320] Persisted replica status to VOTING
I0317 07:46:14.720942 1255 recover.cpp:578] Successfully joined the Paxos group
I0317 07:46:14.721168 1255 recover.cpp:462] Recover process terminated
I0317 07:46:14.721696 1266 log.cpp:659] Attempting to start the writer
I0317 07:46:14.722900 1266 replica.cpp:493] Replica received implicit promise request from (15647)@172.17.0.2:34878 with proposal 1
I0317 07:46:14.749478 1266 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 26.540787ms
I0317 07:46:14.749521 1266 replica.cpp:342] Persisted promised to 1
I0317 07:46:14.750365 1261 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0317 07:46:14.751543 1259 replica.cpp:388] Replica received explicit promise request from (15648)@172.17.0.2:34878 for position 0 with proposal 2
I0317 07:46:14.774901 1259 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 23.295729ms
I0317 07:46:14.774937 1259 replica.cpp:712] Persisted action at 0
I0317 07:46:14.776065 1262 replica.cpp:537] Replica received write request for position 0 from (15649)@172.17.0.2:34878
I0317 07:46:14.776127 1262 leveldb.cpp:436] Reading position from leveldb took 31850ns
I0317 07:46:14.800122 1262 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 23.900015ms
I0317 07:46:14.800163 1262 replica.cpp:712] Persisted action at 0
I0317 07:46:14.800858 1260 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0317 07:46:14.827430 1260 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 26.537719ms
I0317 07:46:14.827467 1260 replica.cpp:712] Persisted action at 0
I0317 07:46:14.827491 1260 replica.cpp:697] Replica learned NOP action at position 0
I0317 07:46:14.828327 1261 log.cpp:675] Writer started with ending position 0
I0317 07:46:14.829427 1259 leveldb.cpp:436] Reading position from leveldb took 41317ns
I0317 07:46:14.830487 1267 registrar.cpp:340] Successfully fetched the registry (0B) in 162.228992ms
I0317 07:46:14.830651 1267 registrar.cpp:439] Applied 1 operations in 43771ns; attempting to update the 'registry'
I0317 07:46:14.831460 1262 log.cpp:683] Attempting to append 170 bytes to the log
I0317 07:46:14.831576 1260 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0317 07:46:14.832293 1266 replica.cpp:537] Replica received write request for position 1 from (15650)@172.17.0.2:34878
I0317 07:46:14.861171 1266 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 28.839316ms
I0317 07:46:14.861201 1266 replica.cpp:712] Persisted action at 1
I0317 07:46:14.861857 1260 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0317 07:46:14.894861 1260 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 32.973414ms
I0317 07:46:14.894891 1260 replica.cpp:712] Persisted action at 1
I0317 07:46:14.894909 1260 replica.cpp:697] Replica learned APPEND action at position 1
I0317 07:46:14.895973 1260 registrar.cpp:484] Successfully updated the 'registry' in 65.256192ms
I0317 07:46:14.896105 1260 registrar.cpp:370] Successfully recovered registrar
I0317 07:46:14.896270 1264 log.cpp:702] Attempting to truncate the log to 1
I0317 07:46:14.896493 1258 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0317 07:46:14.896615 1254 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0317 07:46:14.896636 1262 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0317 07:46:14.897389 1266 replica.cpp:537] Replica received write request for position 2 from (15651)@172.17.0.2:34878
I0317 07:46:14.928779 1266 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 31.337075ms
I0317 07:46:14.928867 1266 replica.cpp:712] Persisted action at 2
I0317 07:46:14.929877 1259 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0317 07:46:14.962370 1259 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.442836ms
I0317 07:46:14.962522 1259 leveldb.cpp:399] Deleting ~1 keys from leveldb took 71228ns
I0317 07:46:14.962549 1259 replica.cpp:712] Persisted action at 2
I0317 07:46:14.962630 1259 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0317 07:46:14.967949 1233 scheduler.cpp:172] Version: 0.29.0
I0317 07:46:14.968637 1260 scheduler.cpp:437] New master detected at master@172.17.0.2:34878
I0317 07:46:14.970193 1260 scheduler.cpp:337] Connected with the master at http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:14.971382 1266 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:14.972108 1262 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0317 07:46:14.972599 1261 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44717
I0317 07:46:14.972822 1261 master.cpp:2068] Received subscription request for HTTP framework 'default'
I0317 07:46:14.972883 1261 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0317 07:46:14.973119 1261 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0317 07:46:14.973551 1263 hierarchical.cpp:265] Added framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000
I0317 07:46:14.973572 1266 master.hpp:1715] Sending heartbeat to 13a47b0d-6589-436c-a4b8-360feb8304a3-0000
I0317 07:46:14.973644 1263 hierarchical.cpp:1453] No resources available to allocate!
I0317 07:46:14.973839 1263 hierarchical.cpp:1548] No inverse offers to send out!
I0317 07:46:14.973958 1263 hierarchical.cpp:1130] Performed allocation for 0 slaves in 382880ns
I0317 07:46:14.974308 1264 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:14.974700 1264 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:14.975561 1255 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:14.976229 1259 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0317 07:46:14.976542 1261 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44718
I0317 07:46:14.976654 1261 master.cpp:2765] Processing REQUEST call for framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000 (default)
I0317 07:46:14.976871 1259 hierarchical.cpp:589] Received resource request from framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000
I0317 07:46:14.978835 1257 master.cpp:1251] Framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000 (default) disconnected
I0317 07:46:14.978860 1257 master.cpp:2622] Disconnecting framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000 (default)
I0317 07:46:14.978886 1257 master.cpp:2646] Deactivating framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000 (default)
I0317 07:46:14.979017 1257 master.cpp:1275] Giving framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000 (default) 0ns to failover
I0317 07:46:14.979058 1254 hierarchical.cpp:375] Deactivated framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000
I0317 07:46:14.980334 1264 master.cpp:5340] Framework failover timeout, removing framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000 (default)
I0317 07:46:14.980361 1264 master.cpp:6073] Removing framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000 (default)
I0317 07:46:14.980602 1253 hierarchical.cpp:326] Removed framework 13a47b0d-6589-436c-a4b8-360feb8304a3-0000
I0317 07:46:14.989182 1264 master.cpp:1065] Master terminating
[ OK ] ContentType/SchedulerTest.Request/0 (461 ms)
[ RUN ] ContentType/SchedulerTest.Request/1
I0317 07:46:14.995754 1233 cluster.cpp:139] Creating default 'local' authorizer
I0317 07:46:15.077077 1233 leveldb.cpp:174] Opened db in 81.096003ms
I0317 07:46:15.102360 1233 leveldb.cpp:181] Compacted db in 25.202375ms
I0317 07:46:15.102440 1233 leveldb.cpp:196] Created db iterator in 24434ns
I0317 07:46:15.102454 1233 leveldb.cpp:202] Seeked to beginning of db in 3038ns
I0317 07:46:15.102463 1233 leveldb.cpp:271] Iterated through 0 keys in the db in 337ns
I0317 07:46:15.102510 1233 replica.cpp:779] Replica recovered with log positions 0 -> 0 with 1 holes and 0 unlearned
I0317 07:46:15.103381 1255 recover.cpp:447] Starting replica recovery
I0317 07:46:15.103723 1255 recover.cpp:473] Replica is in EMPTY status
I0317 07:46:15.105164 1260 replica.cpp:673] Replica in EMPTY status received a broadcasted recover request from (15659)@172.17.0.2:34878
I0317 07:46:15.105375 1261 recover.cpp:193] Received a recover response from a replica in EMPTY status
I0317 07:46:15.106015 1257 recover.cpp:564] Updating replica status to STARTING
I0317 07:46:15.106547 1266 master.cpp:376] Master 05800080-af72-48f9-9b1d-22be3a942d0d (8a7aa3f4fc19) started on 172.17.0.2:34878
I0317 07:46:15.106575 1266 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/jDzqFR/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.29.0/_inst/share/mesos/webui" --work_dir="/tmp/jDzqFR/master" --zk_session_timeout="10secs"
I0317 07:46:15.107023 1266 master.cpp:425] Master allowing unauthenticated frameworks to register
I0317 07:46:15.107045 1266 master.cpp:428] Master only allowing authenticated slaves to register
I0317 07:46:15.107062 1266 credentials.hpp:35] Loading credentials for authentication from '/tmp/jDzqFR/credentials'
I0317 07:46:15.107470 1266 master.cpp:468] Using default 'crammd5' authenticator
I0317 07:46:15.107656 1266 master.cpp:537] Using default 'basic' HTTP authenticator
I0317 07:46:15.107928 1266 master.cpp:571] Authorization enabled
I0317 07:46:15.108130 1260 whitelist_watcher.cpp:77] No whitelist given
I0317 07:46:15.108175 1263 hierarchical.cpp:144] Initialized hierarchical allocator process
I0317 07:46:15.110498 1267 master.cpp:1806] The newly elected leader is master@172.17.0.2:34878 with id 05800080-af72-48f9-9b1d-22be3a942d0d
I0317 07:46:15.110544 1267 master.cpp:1819] Elected as the leading master!
I0317 07:46:15.110574 1267 master.cpp:1508] Recovering from registrar
I0317 07:46:15.110770 1260 registrar.cpp:307] Recovering registrar
I0317 07:46:15.136133 1264 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 29.891592ms
I0317 07:46:15.136210 1264 replica.cpp:320] Persisted replica status to STARTING
I0317 07:46:15.136512 1259 recover.cpp:473] Replica is in STARTING status
I0317 07:46:15.137754 1263 replica.cpp:673] Replica in STARTING status received a broadcasted recover request from (15661)@172.17.0.2:34878
I0317 07:46:15.138042 1263 recover.cpp:193] Received a recover response from a replica in STARTING status
I0317 07:46:15.138648 1262 recover.cpp:564] Updating replica status to VOTING
I0317 07:46:15.169631 1267 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.717029ms
I0317 07:46:15.169708 1267 replica.cpp:320] Persisted replica status to VOTING
I0317 07:46:15.169945 1267 recover.cpp:578] Successfully joined the Paxos group
I0317 07:46:15.170195 1267 recover.cpp:462] Recover process terminated
I0317 07:46:15.170703 1260 log.cpp:659] Attempting to start the writer
I0317 07:46:15.172328 1256 replica.cpp:493] Replica received implicit promise request from (15662)@172.17.0.2:34878 with proposal 1
I0317 07:46:15.203047 1256 leveldb.cpp:304] Persisting metadata (8 bytes) to leveldb took 30.663587ms
I0317 07:46:15.203119 1256 replica.cpp:342] Persisted promised to 1
I0317 07:46:15.204193 1259 coordinator.cpp:238] Coordinator attempting to fill missing positions
I0317 07:46:15.205664 1257 replica.cpp:388] Replica received explicit promise request from (15663)@172.17.0.2:34878 for position 0 with proposal 2
I0317 07:46:15.236644 1257 leveldb.cpp:341] Persisting action (8 bytes) to leveldb took 30.906894ms
I0317 07:46:15.236774 1257 replica.cpp:712] Persisted action at 0
I0317 07:46:15.238229 1264 replica.cpp:537] Replica received write request for position 0 from (15664)@172.17.0.2:34878
I0317 07:46:15.238312 1264 leveldb.cpp:436] Reading position from leveldb took 36093ns
I0317 07:46:15.270004 1264 leveldb.cpp:341] Persisting action (14 bytes) to leveldb took 31.636595ms
I0317 07:46:15.270057 1264 replica.cpp:712] Persisted action at 0
I0317 07:46:15.270941 1265 replica.cpp:691] Replica received learned notice for position 0 from @0.0.0.0:0
I0317 07:46:15.303462 1265 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 32.479309ms
I0317 07:46:15.303508 1265 replica.cpp:712] Persisted action at 0
I0317 07:46:15.303539 1265 replica.cpp:697] Replica learned NOP action at position 0
I0317 07:46:15.304550 1252 log.cpp:675] Writer started with ending position 0
I0317 07:46:15.305912 1261 leveldb.cpp:436] Reading position from leveldb took 47570ns
I0317 07:46:15.307518 1264 registrar.cpp:340] Successfully fetched the registry (0B) in 196.690944ms
I0317 07:46:15.307665 1264 registrar.cpp:439] Applied 1 operations in 33471ns; attempting to update the 'registry'
I0317 07:46:15.308539 1252 log.cpp:683] Attempting to append 170 bytes to the log
I0317 07:46:15.308671 1256 coordinator.cpp:348] Coordinator attempting to write APPEND action at position 1
I0317 07:46:15.309710 1261 replica.cpp:537] Replica received write request for position 1 from (15665)@172.17.0.2:34878
I0317 07:46:15.338450 1261 leveldb.cpp:341] Persisting action (189 bytes) to leveldb took 28.656532ms
I0317 07:46:15.338496 1261 replica.cpp:712] Persisted action at 1
I0317 07:46:15.339452 1255 replica.cpp:691] Replica received learned notice for position 1 from @0.0.0.0:0
I0317 07:46:15.370332 1255 leveldb.cpp:341] Persisting action (191 bytes) to leveldb took 30.837549ms
I0317 07:46:15.370373 1255 replica.cpp:712] Persisted action at 1
I0317 07:46:15.370395 1255 replica.cpp:697] Replica learned APPEND action at position 1
I0317 07:46:15.371531 1261 registrar.cpp:484] Successfully updated the 'registry' in 63.79904ms
I0317 07:46:15.371745 1261 registrar.cpp:370] Successfully recovered registrar
I0317 07:46:15.371891 1264 log.cpp:702] Attempting to truncate the log to 1
I0317 07:46:15.372141 1258 coordinator.cpp:348] Coordinator attempting to write TRUNCATE action at position 2
I0317 07:46:15.372539 1266 master.cpp:1616] Recovered 0 slaves from the Registry (131B) ; allowing 10mins for slaves to re-register
I0317 07:46:15.372611 1257 hierarchical.cpp:171] Skipping recovery of hierarchical allocator: nothing to recover
I0317 07:46:15.373289 1255 replica.cpp:537] Replica received write request for position 2 from (15666)@172.17.0.2:34878
I0317 07:46:15.403764 1255 leveldb.cpp:341] Persisting action (16 bytes) to leveldb took 30.429974ms
I0317 07:46:15.403807 1255 replica.cpp:712] Persisted action at 2
I0317 07:46:15.404501 1266 replica.cpp:691] Replica received learned notice for position 2 from @0.0.0.0:0
I0317 07:46:15.437110 1266 leveldb.cpp:341] Persisting action (18 bytes) to leveldb took 32.564278ms
I0317 07:46:15.437197 1266 leveldb.cpp:399] Deleting ~1 keys from leveldb took 43023ns
I0317 07:46:15.437222 1266 replica.cpp:712] Persisted action at 2
I0317 07:46:15.437244 1266 replica.cpp:697] Replica learned TRUNCATE action at position 2
I0317 07:46:15.444066 1233 scheduler.cpp:172] Version: 0.29.0
I0317 07:46:15.444690 1252 scheduler.cpp:437] New master detected at master@172.17.0.2:34878
I0317 07:46:15.446573 1259 scheduler.cpp:337] Connected with the master at http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:15.447994 1265 scheduler.cpp:230] Sending SUBSCRIBE call to http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:15.449111 1258 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0317 07:46:15.449638 1253 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44719
I0317 07:46:15.450179 1253 master.cpp:2068] Received subscription request for HTTP framework 'default'
I0317 07:46:15.450268 1253 master.cpp:1845] Authorizing framework principal 'test-principal' to receive offers for role '*'
I0317 07:46:15.450609 1253 master.cpp:2159] Subscribing framework 'default' with checkpointing disabled and capabilities [ ]
I0317 07:46:15.451135 1255 hierarchical.cpp:265] Added framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000
I0317 07:46:15.451236 1255 hierarchical.cpp:1453] No resources available to allocate!
I0317 07:46:15.451287 1255 hierarchical.cpp:1548] No inverse offers to send out!
I0317 07:46:15.451295 1253 master.hpp:1715] Sending heartbeat to 05800080-af72-48f9-9b1d-22be3a942d0d-0000
I0317 07:46:15.451325 1255 hierarchical.cpp:1130] Performed allocation for 0 slaves in 156216ns
I0317 07:46:15.452260 1252 scheduler.cpp:612] Enqueuing event SUBSCRIBED received from http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:15.452847 1252 scheduler.cpp:612] Enqueuing event HEARTBEAT received from http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:15.453605 1252 scheduler.cpp:230] Sending REQUEST call to http://172.17.0.2:34878/master/api/v1/scheduler
I0317 07:46:15.454689 1254 process.cpp:3136] Handling HTTP event for process 'master' with path: '/master/api/v1/scheduler'
I0317 07:46:15.455160 1262 http.cpp:312] HTTP POST for /master/api/v1/scheduler from 172.17.0.2:44720
I0317 07:46:15.455428 1262 master.cpp:2765] Processing REQUEST call for framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000 (default)
I0317 07:46:15.455628 1266 hierarchical.cpp:589] Received resource request from framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000
I0317 07:46:15.457552 1263 master.cpp:1251] Framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000 (default) disconnected
I0317 07:46:15.457651 1263 master.cpp:2622] Disconnecting framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000 (default)
I0317 07:46:15.457702 1263 master.cpp:2646] Deactivating framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000 (default)
I0317 07:46:15.457854 1263 master.cpp:1275] Giving framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000 (default) 0ns to failover
I0317 07:46:15.457890 1262 hierarchical.cpp:375] Deactivated framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000
I0317 07:46:15.459321 1252 master.cpp:5340] Framework failover timeout, removing framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000 (default)
I0317 07:46:15.459362 1252 master.cpp:6073] Removing framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000 (default)
I0317 07:46:15.459658 1259 hierarchical.cpp:326] Removed framework 05800080-af72-48f9-9b1d-22be3a942d0d-0000
I0317 07:46:15.467943 1267 master.cpp:1065] Master terminating
[ OK ] ContentType/SchedulerTest.Request/1 (480 ms)
[----------] 26 tests from ContentType/SchedulerTest (19813 ms total)
[----------] Global test environment tear-down
E0317 07:46:15.588250 1233 environment.cpp:752] Failed to remove '/tmp/LogZooKeeperTest_WriteRead_lL8ZRN': No such file or directory
E0317 07:46:15.588307 1233 environment.cpp:752] Failed to remove '/tmp/LogZooKeeperTest_LostZooKeeper_mXZf1A': No such file or directory
[==========] 1026 tests from 130 test cases ran. (738769 ms total)
[ PASSED ] 1025 tests.
[ FAILED ] 1 test, listed below:
[ FAILED ] ContainerLoggerTest.LOGROTATE_RotateInSandbox
1 FAILED TEST
YOU HAVE 6 DISABLED TESTS
make[4]: *** [check-local] Error 1
make[4]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[3]: *** [check-am] Error 2
make[3]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[2]: *** [check] Error 2
make[2]: Leaving directory `/mesos/mesos-0.29.0/_build/src'
make[1]: *** [check-recursive] Error 1
make[1]: Leaving directory `/mesos/mesos-0.29.0/_build'
make: *** [distcheck] Error 1
+ docker rmi mesos-1458198020-25818
Untagged: mesos-1458198020-25818:latest
Deleted: 0b796e32aabe2ea2a37d053409c64b1fbb3f4ef5cc2c6fd4fc53318f88477fc9
Deleted: 9de5c4c5a3fd39f08090d34174d3dc98747524b36dba09628527267f5cb4973a
Deleted: 8686a71796055961b82af4cf681120592af0c48f6a751c83b0cb5c5fab581412
Deleted: 15b17c60a338cbf431ed7e4bb49a73d9173faca0adcc22773af2d51aa2d049c3
Deleted: bc259a0d01312240b2168daff41cb00db2999473fba2062d0c3f2537fbb23428
Deleted: fe5a6a4637a31a011c4706faadfc836d97a46be8289cbe066f0221c614badd4b
Deleted: 48e29f6cedf3ce336e7b3bd79c3f3932794138c09b76ded6c571eaa83ed93105
Deleted: aba05722730212ca37a9e7b9f0412c07af28e0d33456ff7eaac3ec0d46afe2a3
Deleted: 37fe01d8cb22d6738da3f3f0c635fa521e4e0bca5039dfd12121b6fbdaf07a20
Deleted: 1e2e132f1f687228d710718b8148d132cc3279d397385ad5819b0f8b53cc793d
Deleted: 942e09e8aedbcecdcdca5796efdb570cc06e5ef48e61c89f8a67970b4709faa7
Deleted: ac2b674990e4b11f21d87d68346f4a5c7ac3c2004bac2a03c2603a9f5a071d74
Deleted: 2198e39e1586eeb1e0d94f3c0206b5e04ec8e298df09939b46fdf707a0c8f1bb
Deleted: 5a6065054f265130a370af1ece6ccb4099aa0a5e7b1cb083bc9ca4ee2f99f0f3
Deleted: d919ea4412cf165220b8c19fdeaa975120b2834a5724568e8a8ff29dd3540f0a
Deleted: 0e1bdf5ad20fe68e73a225f35a955a0d8170e50f0a98b8fe3510d6c762ee1cd8
Build step 'Execute shell' marked build as failure